Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_IDEAS.Fluid.Movers.Examples.PumpsSeries.conf.json loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.001711/0.001711, allocations: 102.1 kB / 19.7 MB, free: 2.254 MB / 14.72 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.001983/0.001983, allocations: 218.3 kB / 23.01 MB, free: 4.605 MB / 14.72 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 1.485/1.485, allocations: 230.6 MB / 256.8 MB, free: 7.996 MB / 206.1 MB " [Timeout remaining time 178] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0/package.mo): time 0.8538/0.8538, allocations: 134 MB / 447.2 MB, free: 10.18 MB / 350.1 MB " [Timeout remaining time 179] Using package IDEAS with version 3.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0/package.mo) Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo) Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo) Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo) Running command: translateModel(IDEAS.Fluid.Movers.Examples.PumpsSeries,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_IDEAS.Fluid.Movers.Examples.PumpsSeries") translateModel(IDEAS.Fluid.Movers.Examples.PumpsSeries,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_IDEAS.Fluid.Movers.Examples.PumpsSeries") [Timeout 660] "Notification: IDEAS requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed. Notification: Performance of FrontEnd - loaded program: time 0.001978/0.001978, allocations: 67.31 kB / 0.6198 GB, free: 6.715 MB / 446.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.1776/0.1795, allocations: 85.89 MB / 0.7036 GB, free: 0.8984 MB / 0.5137 GB Notification: Performance of NFInst.instantiate(IDEAS.Fluid.Movers.Examples.PumpsSeries): time 0.5043/0.6839, allocations: 51.8 MB / 0.7542 GB, free: 5.211 MB / 0.5606 GB Notification: Performance of NFInst.instExpressions: time 0.02506/0.7089, allocations: 16.25 MB / 0.7701 GB, free: 4.996 MB / 0.5606 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.004236/0.7132, allocations: 103.8 kB / 0.7702 GB, free: 4.996 MB / 0.5606 GB Notification: Performance of NFTyping.typeComponents: time 0.00403/0.7172, allocations: 1.106 MB / 0.7713 GB, free: 4.977 MB / 0.5606 GB Notification: Performance of NFTyping.typeBindings: time 0.01006/0.7273, allocations: 2.589 MB / 0.7738 GB, free: 4.695 MB / 0.5606 GB Notification: Performance of NFTyping.typeClassSections: time 0.007482/0.7347, allocations: 2.071 MB / 0.7758 GB, free: 4.535 MB / 0.5606 GB Notification: Performance of NFFlatten.flatten: time 0.008885/0.7436, allocations: 4.452 MB / 0.7802 GB, free: 2.637 MB / 0.5606 GB Notification: Performance of NFFlatten.resolveConnections: time 0.003456/0.7471, allocations: 1.36 MB / 0.7815 GB, free: 1.977 MB / 0.5606 GB Notification: Performance of NFEvalConstants.evaluate: time 0.006146/0.7532, allocations: 1.894 MB / 0.7834 GB, free: 1.195 MB / 0.5606 GB Notification: Performance of NFSimplifyModel.simplify: time 0.003608/0.7568, allocations: 1.426 MB / 0.7847 GB, free: 0.6562 MB / 0.5606 GB Notification: Performance of NFPackage.collectConstants: time 0.0009744/0.7578, allocations: 191.8 kB / 0.7849 GB, free: 0.6523 MB / 0.5606 GB Notification: Performance of NFFlatten.collectFunctions: time 0.005654/0.7635, allocations: 1.694 MB / 0.7866 GB, free: 180 kB / 0.5606 GB Notification: Performance of NFScalarize.scalarize: time 0.001854/0.7653, allocations: 0.6601 MB / 0.7872 GB, free: 0 / 0.5606 GB Notification: Performance of NFVerifyModel.verify: time 0.003308/0.7686, allocations: 1.035 MB / 0.7882 GB, free: 15.34 MB / 0.5762 GB Notification: Performance of NFConvertDAE.convert: time 0.00947/0.7781, allocations: 3.497 MB / 0.7917 GB, free: 14.14 MB / 0.5762 GB Notification: Performance of FrontEnd - DAE generated: time 4.929e-06/0.7781, allocations: 0 / 0.7917 GB, free: 14.14 MB / 0.5762 GB Notification: Performance of FrontEnd: time 1.703e-06/0.7781, allocations: 0 / 0.7917 GB, free: 14.14 MB / 0.5762 GB Notification: Performance of Transformations before backend: time 0.0001477/0.7782, allocations: 0 / 0.7917 GB, free: 14.14 MB / 0.5762 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 249 * Number of variables: 249 Notification: Performance of Generate backend data structure: time 0.007895/0.7861, allocations: 2.394 MB / 0.794 GB, free: 12.45 MB / 0.5762 GB Notification: Performance of prepare preOptimizeDAE: time 5.035e-05/0.7862, allocations: 12.98 kB / 0.794 GB, free: 12.44 MB / 0.5762 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003591/0.7898, allocations: 0.5701 MB / 0.7946 GB, free: 12.17 MB / 0.5762 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.005885/0.7957, allocations: 1.766 MB / 0.7963 GB, free: 10.94 MB / 0.5762 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001179/0.7958, allocations: 84.86 kB / 0.7964 GB, free: 10.9 MB / 0.5762 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002826/0.7961, allocations: 108.8 kB / 0.7965 GB, free: 10.87 MB / 0.5762 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.002789/0.7989, allocations: 1.238 MB / 0.7977 GB, free: 10.09 MB / 0.5762 GB Notification: Performance of preOpt findStateOrder (simulation): time 3.417e-05/0.7989, allocations: 5.531 kB / 0.7977 GB, free: 10.09 MB / 0.5762 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001201/0.799, allocations: 41 kB / 0.7977 GB, free: 10.06 MB / 0.5762 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 5.253e-05/0.7991, allocations: 36.25 kB / 0.7978 GB, free: 10.05 MB / 0.5762 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002409/0.8015, allocations: 0.9532 MB / 0.7987 GB, free: 9.59 MB / 0.5762 GB Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts. Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.009236/0.8107, allocations: 5.757 MB / 0.8043 GB, free: 4.605 MB / 0.5762 GB Notification: Performance of preOpt comSubExp (simulation): time 0.001937/0.8126, allocations: 0.6884 MB / 0.805 GB, free: 4.242 MB / 0.5762 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.000764/0.8134, allocations: 325.3 kB / 0.8053 GB, free: 4.082 MB / 0.5762 GB Notification: Performance of preOpt evalFunc (simulation): time 0.003202/0.8166, allocations: 1.383 MB / 0.8066 GB, free: 3.039 MB / 0.5762 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.838e-05/0.8167, allocations: 45.67 kB / 0.8067 GB, free: 2.988 MB / 0.5762 GB Notification: Performance of pre-optimization done (n=57): time 2.044e-06/0.8167, allocations: 0 / 0.8067 GB, free: 2.988 MB / 0.5762 GB Notification: Performance of matching and sorting (n=57): time 0.003389/0.82, allocations: 1.139 MB / 0.8078 GB, free: 2.418 MB / 0.5762 GB Notification: Performance of inlineWhenForInitialization (initialization): time 5.24e-05/0.8201, allocations: 60.64 kB / 0.8079 GB, free: 2.352 MB / 0.5762 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002204/0.8223, allocations: 1.507 MB / 0.8093 GB, free: 1.547 MB / 0.5762 GB Notification: Performance of collectPreVariables (initialization): time 8.893e-05/0.8224, allocations: 43.36 kB / 0.8094 GB, free: 1.504 MB / 0.5762 GB Notification: Performance of collectInitialEqns (initialization): time 0.0007392/0.8231, allocations: 0.7942 MB / 0.8101 GB, free: 0.8047 MB / 0.5762 GB Notification: Performance of collectInitialBindings (initialization): time 0.0002485/0.8234, allocations: 217.1 kB / 0.8104 GB, free: 0.6211 MB / 0.5762 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.000332/0.8237, allocations: 211.6 kB / 0.8106 GB, free: 0.5 MB / 0.5762 GB Notification: Performance of setup shared object (initialization): time 0.0001747/0.8239, allocations: 337.3 kB / 0.8109 GB, free: 176 kB / 0.5762 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.0009223/0.8248, allocations: 432.6 kB / 0.8113 GB, free: 15.96 MB / 0.5919 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.001215/0.826, allocations: 0.7185 MB / 0.812 GB, free: 15.37 MB / 0.5919 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.002065/0.8281, allocations: 1.106 MB / 0.8131 GB, free: 14.61 MB / 0.5919 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.652e-06/0.8281, allocations: 2.969 kB / 0.8131 GB, free: 14.61 MB / 0.5919 GB Notification: Performance of matching and sorting (n=107) (initialization): time 0.002921/0.831, allocations: 1.378 MB / 0.8144 GB, free: 13.91 MB / 0.5919 GB Notification: Performance of prepare postOptimizeDAE: time 2.51e-05/0.831, allocations: 12.27 kB / 0.8144 GB, free: 13.91 MB / 0.5919 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.306e-05/0.8311, allocations: 8.281 kB / 0.8144 GB, free: 13.91 MB / 0.5919 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.0005125/0.8316, allocations: 143 kB / 0.8146 GB, free: 13.85 MB / 0.5919 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0009137/0.8325, allocations: 232.1 kB / 0.8148 GB, free: 13.79 MB / 0.5919 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0006647/0.8331, allocations: 0.6637 MB / 0.8154 GB, free: 13.19 MB / 0.5919 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001276/0.8344, allocations: 114.7 kB / 0.8156 GB, free: 13.12 MB / 0.5919 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.000111/0.8345, allocations: 59.91 kB / 0.8156 GB, free: 13.1 MB / 0.5919 GB Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.001323/0.8359, allocations: 0.6797 MB / 0.8163 GB, free: 12.7 MB / 0.5919 GB Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.001052/0.8369, allocations: 0.657 MB / 0.8169 GB, free: 12.04 MB / 0.5919 GB Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.001727/0.8386, allocations: 0.9778 MB / 0.8179 GB, free: 10.95 MB / 0.5919 GB Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 8.406e-06/0.8386, allocations: 4 kB / 0.8179 GB, free: 10.95 MB / 0.5919 GB Notification: Performance of matching and sorting (n=107) (initialization_lambda0): time 0.002946/0.8416, allocations: 1.278 MB / 0.8191 GB, free: 9.66 MB / 0.5919 GB Notification: Performance of prepare postOptimizeDAE: time 1.843e-05/0.8416, allocations: 8 kB / 0.8191 GB, free: 9.652 MB / 0.5919 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.618e-05/0.8416, allocations: 8 kB / 0.8191 GB, free: 9.645 MB / 0.5919 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.0009403/0.8426, allocations: 209.1 kB / 0.8193 GB, free: 9.438 MB / 0.5919 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0008415/0.8434, allocations: 219.6 kB / 0.8195 GB, free: 9.223 MB / 0.5919 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.006157/0.8496, allocations: 3.395 MB / 0.8229 GB, free: 5.645 MB / 0.5919 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001444/0.851, allocations: 91.48 kB / 0.8229 GB, free: 5.555 MB / 0.5919 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.00014/0.8512, allocations: 55.97 kB / 0.823 GB, free: 5.5 MB / 0.5919 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 37 * Number of states: 0 () * Number of discrete variables: 0 () * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (100): * Single equations (assignments): 97 * Array equations: 2 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 1 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 0 systems * Non-linear torn systems (#iteration vars, #inner vars): 1 system {(1,5)} Notification: Performance of prepare postOptimizeDAE: time 0.0004199/0.8516, allocations: 236.8 kB / 0.8232 GB, free: 5.262 MB / 0.5919 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003437/0.8519, allocations: 166.9 kB / 0.8234 GB, free: 5.098 MB / 0.5919 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002999/0.8549, allocations: 1.201 MB / 0.8246 GB, free: 3.887 MB / 0.5919 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.517e-05/0.8549, allocations: 8 kB / 0.8246 GB, free: 3.879 MB / 0.5919 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 2.935e-06/0.8549, allocations: 0 / 0.8246 GB, free: 3.879 MB / 0.5919 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.11e-05/0.855, allocations: 7.984 kB / 0.8246 GB, free: 3.871 MB / 0.5919 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.004942/0.8599, allocations: 2.364 MB / 0.8269 GB, free: 1.461 MB / 0.5919 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.001e-06/0.8599, allocations: 0 / 0.8269 GB, free: 1.461 MB / 0.5919 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0006919/0.8606, allocations: 132 kB / 0.827 GB, free: 1.332 MB / 0.5919 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.0003521/0.8609, allocations: 100.8 kB / 0.8271 GB, free: 1.23 MB / 0.5919 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 6.628e-05/0.861, allocations: 31.92 kB / 0.8271 GB, free: 1.199 MB / 0.5919 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.0005489/0.8616, allocations: 0.6376 MB / 0.8278 GB, free: 0.5391 MB / 0.5919 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.147e-06/0.8616, allocations: 4.031 kB / 0.8278 GB, free: 0.5352 MB / 0.5919 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.003604/0.8652, allocations: 1.613 MB / 0.8293 GB, free: 14.89 MB / 0.6075 GB Notification: Performance of postOpt removeConstants (simulation): time 0.0008691/0.866, allocations: 262.6 kB / 0.8296 GB, free: 14.63 MB / 0.6075 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002365/0.8663, allocations: 12 kB / 0.8296 GB, free: 14.62 MB / 0.6075 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0008005/0.8671, allocations: 51.91 kB / 0.8296 GB, free: 14.57 MB / 0.6075 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 9.465e-05/0.8672, allocations: 44.58 kB / 0.8297 GB, free: 14.53 MB / 0.6075 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 7.789e-05/0.8672, allocations: 35.91 kB / 0.8297 GB, free: 14.49 MB / 0.6075 GB Notification: Performance of sorting global known variables: time 0.00172/0.869, allocations: 1.032 MB / 0.8307 GB, free: 13.47 MB / 0.6075 GB Notification: Performance of sort global known variables: time 1e-07/0.869, allocations: 4 kB / 0.8307 GB, free: 13.46 MB / 0.6075 GB Notification: Performance of remove unused functions: time 0.002949/0.8719, allocations: 0.8963 MB / 0.8316 GB, free: 12.57 MB / 0.6075 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 5 * Number of states: 4 (floMac1.filter.s[1],floMac1.filter.s[2],floMac2.filter.s[1],floMac2.filter.s[2]) * Number of discrete variables: 0 () * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for simulation (64): * Single equations (assignments): 57 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 6 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 1 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 0 systems * Non-linear torn systems (#iteration vars, #inner vars): 1 system {(1,7)} Notification: Performance of Backend phase and start with SimCode phase: time 0.001364/0.8733, allocations: 0.5268 MB / 0.8321 GB, free: 12.07 MB / 0.6075 GB Notification: Performance of simCode: created initialization part: time 0.00388/0.8772, allocations: 1.596 MB / 0.8337 GB, free: 10.46 MB / 0.6075 GB Notification: Performance of simCode: created event and clocks part: time 4.589e-06/0.8772, allocations: 0 / 0.8337 GB, free: 10.46 MB / 0.6075 GB Notification: Performance of simCode: created simulation system equations: time 0.001049/0.8782, allocations: 411.9 kB / 0.8341 GB, free: 10.06 MB / 0.6075 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003498/0.8817, allocations: 0.7589 MB / 0.8348 GB, free: 9.344 MB / 0.6075 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.00552/0.8872, allocations: 2.927 MB / 0.8377 GB, free: 6.367 MB / 0.6075 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001121/0.8883, allocations: 1.36 MB / 0.839 GB, free: 4.98 MB / 0.6075 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0005067/0.8889, allocations: 139.4 kB / 0.8391 GB, free: 4.844 MB / 0.6075 GB Notification: Performance of SimCode: time 1.492e-06/0.8889, allocations: 0 / 0.8391 GB, free: 4.844 MB / 0.6075 GB Notification: Performance of Templates: time 0.06121/0.9501, allocations: 38.23 MB / 0.8765 GB, free: 14.78 MB / 0.6544 GB " [Timeout remaining time 659] make -j1 -f IDEAS_IDEAS.Fluid.Movers.Examples.PumpsSeries.makefile [Timeout 660] (rm -f IDEAS_IDEAS.Fluid.Movers.Examples.PumpsSeries.pipe ; mkfifo IDEAS_IDEAS.Fluid.Movers.Examples.PumpsSeries.pipe ; head -c 1048576 < IDEAS_IDEAS.Fluid.Movers.Examples.PumpsSeries.pipe >> ../files/IDEAS_IDEAS.Fluid.Movers.Examples.PumpsSeries.sim & ./IDEAS_IDEAS.Fluid.Movers.Examples.PumpsSeries -abortSlowSimulation -alarm=480 -lv LOG_STATS > IDEAS_IDEAS.Fluid.Movers.Examples.PumpsSeries.pipe 2>&1) [Timeout 480] [Calling sys.exit(0), Time elapsed: 15.930130303138867] Failed to read output from testmodel.py, exit status != 0: 0.958830005954951 0.974644294 0.193109785 Calling exit ...