Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example1v2.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo", uses=false)
Using package IDEAS with version 3.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(IDEAS.Fluid.Examples.Performance.Example1v2,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example1v2")
translateModel(IDEAS.Fluid.Examples.Performance.Example1v2,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example1v2")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001257/0.001257, allocations: 114.5 kB / 16.42 MB, free: 6.039 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001011/0.001011, allocations: 186.2 kB / 17.34 MB, free: 5.625 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.227/1.227, allocations: 222.9 MB / 241.1 MB, free: 15.16 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo): time 0.7469/0.7469, allocations: 135.2 MB / 426.4 MB, free: 4.09 MB / 334.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.00115/0.00115, allocations: 70.97 kB / 0.5863 GB, free: 21.57 MB / 446.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1192/0.1203, allocations: 80.91 MB / 0.6653 GB, free: 5.777 MB / 0.4981 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/Blocks/Math.mo:543:5-544:48:writable] Warning: Parameter pump.gaiSpe.k has no value, and is fixed during initialization (fixed=true), using available start value (start=1) as default value.
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.Examples.Performance.Example1v2): time 0.06705/0.1874, allocations: 80.62 MB / 0.744 GB, free: 4.887 MB / 0.5762 GB
Notification: Performance of NFInst.instExpressions: time 0.3154/0.5028, allocations: 19.23 MB / 0.7628 GB, free: 11.66 MB / 0.5762 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003121/0.506, allocations: 112.9 kB / 0.7629 GB, free: 11.66 MB / 0.5762 GB
Notification: Performance of NFTyping.typeComponents: time 0.002645/0.5087, allocations: 1.153 MB / 0.7641 GB, free: 11.64 MB / 0.5762 GB
Notification: Performance of NFTyping.typeBindings: time 0.006994/0.5157, allocations: 2.853 MB / 0.7668 GB, free: 11.52 MB / 0.5762 GB
Notification: Performance of NFTyping.typeClassSections: time 0.005759/0.5214, allocations: 2.39 MB / 0.7692 GB, free: 11.46 MB / 0.5762 GB
Notification: Performance of NFFlatten.flatten: time 0.02092/0.5424, allocations: 26.9 MB / 0.7954 GB, free: 5.078 MB / 0.5762 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.01681/0.5592, allocations: 17.08 MB / 0.8121 GB, free: 11.56 MB / 0.5919 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.02078/0.58, allocations: 11.42 MB / 0.8233 GB, free: 0.7969 MB / 0.5919 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01864/0.5987, allocations: 11.69 MB / 0.8347 GB, free: 5.984 MB / 0.6075 GB
Notification: Performance of NFPackage.collectConstants: time 0.00421/0.6029, allocations: 1.277 MB / 0.8359 GB, free: 4.707 MB / 0.6075 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.00918/0.6121, allocations: 2.58 MB / 0.8385 GB, free: 2.184 MB / 0.6075 GB
Notification: Performance of NFScalarize.scalarize: time 0.005574/0.6177, allocations: 3.632 MB / 0.842 GB, free: 14.73 MB / 0.6231 GB
Notification: Performance of NFVerifyModel.verify: time 0.01188/0.6296, allocations: 6.486 MB / 0.8483 GB, free: 8.41 MB / 0.6231 GB
Notification: Performance of NFConvertDAE.convert: time 0.02242/0.652, allocations: 18.55 MB / 0.8665 GB, free: 6.5 MB / 0.6387 GB
Notification: Performance of FrontEnd - DAE generated: time 5.06e-06/0.652, allocations: 0 / 0.8665 GB, free: 6.5 MB / 0.6387 GB
Notification: Performance of FrontEnd: time 1.333e-06/0.6521, allocations: 0 / 0.8665 GB, free: 6.5 MB / 0.6387 GB
Notification: Performance of Transformations before backend: time 0.0004376/0.6525, allocations: 4 kB / 0.8665 GB, free: 6.496 MB / 0.6387 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1418
 * Number of variables: 1418
Notification: Performance of Generate backend data structure: time 0.03222/0.6847, allocations: 12.52 MB / 0.8787 GB, free: 10.03 MB / 0.6544 GB
Notification: Performance of prepare preOptimizeDAE: time 4.45e-05/0.6848, allocations: 9.109 kB / 0.8787 GB, free: 10.02 MB / 0.6544 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0073/0.6921, allocations: 1.317 MB / 0.88 GB, free: 8.695 MB / 0.6544 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.02054/0.7126, allocations: 10.19 MB / 0.8899 GB, free: 14.66 MB / 0.67 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003872/0.7131, allocations: 435.1 kB / 0.8903 GB, free: 14.23 MB / 0.67 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002932/0.716, allocations: 0.5858 MB / 0.8909 GB, free: 13.65 MB / 0.67 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02741/0.7434, allocations: 14.61 MB / 0.9052 GB, free: 14.77 MB / 0.6856 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001416/0.7436, allocations: 23.83 kB / 0.9052 GB, free: 14.75 MB / 0.6856 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001205/0.7448, allocations: 240 kB / 0.9054 GB, free: 14.51 MB / 0.6856 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 8.524e-05/0.7449, allocations: 136 kB / 0.9056 GB, free: 14.38 MB / 0.6856 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03068/0.7756, allocations: 19.76 MB / 0.9249 GB, free: 10.6 MB / 0.7012 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.09776/0.8734, allocations: 60.11 MB / 0.9836 GB, free: 13.42 MB / 0.7637 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01393/0.8873, allocations: 9.18 MB / 0.9925 GB, free: 4.234 MB / 0.7637 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.01197/0.8993, allocations: 8.598 MB / 1.001 GB, free: 11.64 MB / 0.7794 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.05035/0.9497, allocations: 27.43 MB / 1.028 GB, free: 15.92 MB / 0.8106 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0122/0.9619, allocations: 7.647 MB / 1.035 GB, free: 8.234 MB / 0.8106 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.01608/0.9781, allocations: 4.493 MB / 1.04 GB, free: 3.883 MB / 0.8106 GB
Notification: Performance of pre-optimization done (n=299): time 1.963e-05/0.9781, allocations: 0 / 1.04 GB, free: 3.883 MB / 0.8106 GB
Notification: Performance of matching and sorting (n=299): time 0.5698/1.548, allocations: 23.36 MB / 1.062 GB, free: 304.4 MB / 0.8263 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.176e-05/1.548, allocations: 84.5 kB / 1.062 GB, free: 304.3 MB / 0.8263 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01272/1.561, allocations: 8.249 MB / 1.071 GB, free: 301.9 MB / 0.8263 GB
Notification: Performance of collectPreVariables (initialization): time 0.001986/1.563, allocations: 100.8 kB / 1.071 GB, free: 301.8 MB / 0.8263 GB
Notification: Performance of collectInitialEqns (initialization): time 0.003488/1.566, allocations: 4.127 MB / 1.075 GB, free: 299.7 MB / 0.8263 GB
Notification: Performance of collectInitialBindings (initialization): time 0.00257/1.569, allocations: 1.126 MB / 1.076 GB, free: 299.2 MB / 0.8263 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002888/1.572, allocations: 1.672 MB / 1.077 GB, free: 298.7 MB / 0.8263 GB
Notification: Performance of setup shared object (initialization): time 3.55e-05/1.572, allocations: 301.1 kB / 1.078 GB, free: 298.4 MB / 0.8263 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.007935/1.58, allocations: 6.55 MB / 1.084 GB, free: 296 MB / 0.8263 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.008779/1.588, allocations: 8.324 MB / 1.092 GB, free: 291.3 MB / 0.8263 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0167/1.605, allocations: 15.24 MB / 1.107 GB, free: 282.5 MB / 0.8263 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.244e-05/1.605, allocations: 15.31 kB / 1.107 GB, free: 282.5 MB / 0.8263 GB
Notification: Performance of matching and sorting (n=578) (initialization): time 0.02628/1.632, allocations: 17.61 MB / 1.124 GB, free: 270.4 MB / 0.8263 GB
Notification: Performance of prepare postOptimizeDAE: time 5.765e-05/1.632, allocations: 61.67 kB / 1.124 GB, free: 270.3 MB / 0.8263 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.899e-05/1.632, allocations: 48 kB / 1.124 GB, free: 270.2 MB / 0.8263 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.06429/1.696, allocations: 11.57 MB / 1.136 GB, free: 258.9 MB / 0.8263 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007626/1.704, allocations: 1.553 MB / 1.137 GB, free: 257.4 MB / 0.8263 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.004458/1.708, allocations: 3.776 MB / 1.141 GB, free: 253.5 MB / 0.8263 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.008546/1.717, allocations: 344.8 kB / 1.141 GB, free: 253.2 MB / 0.8263 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001228/1.718, allocations: 0.546 MB / 1.142 GB, free: 252.7 MB / 0.8263 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.009621/1.728, allocations: 8.814 MB / 1.15 GB, free: 243.8 MB / 0.8263 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.007158/1.735, allocations: 8.28 MB / 1.158 GB, free: 234.7 MB / 0.8263 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.01465/1.749, allocations: 15.16 MB / 1.173 GB, free: 218.8 MB / 0.8263 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.74e-05/1.749, allocations: 12 kB / 1.173 GB, free: 218.8 MB / 0.8263 GB
Notification: Performance of matching and sorting (n=578) (initialization_lambda0): time 0.02513/1.775, allocations: 17.45 MB / 1.19 GB, free: 201.3 MB / 0.8263 GB
Notification: Performance of prepare postOptimizeDAE: time 5.137e-05/1.775, allocations: 57.69 kB / 1.19 GB, free: 201.2 MB / 0.8263 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.745e-05/1.775, allocations: 51.94 kB / 1.19 GB, free: 201.2 MB / 0.8263 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.06502/1.84, allocations: 11.13 MB / 1.201 GB, free: 190.1 MB / 0.8263 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007541/1.847, allocations: 1.542 MB / 1.203 GB, free: 188.5 MB / 0.8263 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007813/1.855, allocations: 8.175 MB / 1.211 GB, free: 180.1 MB / 0.8263 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.008929/1.864, allocations: 356.3 kB / 1.211 GB, free: 179.7 MB / 0.8263 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002002/1.866, allocations: 0.5344 MB / 1.212 GB, free: 179.2 MB / 0.8263 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 217
 * Number of states: 0 ()
 * Number of discrete variables: 8 (pulse.count,$PRE.pulse.count,pulse.T_start,$PRE.pulse.T_start,$whenCondition1,from_dp.y,nRes.y,allowFlowReversal.y)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (524):
 * Single equations (assignments): 519
 * Array equations: 1
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 3
 * 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): 3 systems
   {(2,5), (20,21), (2,5)}
Notification: Performance of prepare postOptimizeDAE: time 0.002209/1.868, allocations: 495.4 kB / 1.212 GB, free: 178.7 MB / 0.8263 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002732/1.871, allocations: 0.8035 MB / 1.213 GB, free: 177.9 MB / 0.8263 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02065/1.892, allocations: 14.17 MB / 1.227 GB, free: 163.7 MB / 0.8263 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.01277/1.905, allocations: 10.76 MB / 1.237 GB, free: 152.9 MB / 0.8263 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.185e-05/1.905, allocations: 4 kB / 1.237 GB, free: 152.9 MB / 0.8263 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.004525/1.909, allocations: 1.851 MB / 1.239 GB, free: 151 MB / 0.8263 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001897/1.911, allocations: 71.98 kB / 1.239 GB, free: 150.9 MB / 0.8263 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.005124/1.916, allocations: 198.3 kB / 1.239 GB, free: 150.7 MB / 0.8263 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0008372/1.917, allocations: 258.4 kB / 1.24 GB, free: 150.5 MB / 0.8263 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01723/1.934, allocations: 11.71 MB / 1.251 GB, free: 138.8 MB / 0.8263 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.02007/1.954, allocations: 19.36 MB / 1.27 GB, free: 119.4 MB / 0.8263 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.005289/1.96, allocations: 6.019 MB / 1.276 GB, free: 113.4 MB / 0.8263 GB
Notification: Performance of sorting global known variables: time 0.006272/1.966, allocations: 5.695 MB / 1.281 GB, free: 107.7 MB / 0.8263 GB
Notification: Performance of Backend: time 3.61e-07/1.966, allocations: 0 / 1.281 GB, free: 107.7 MB / 0.8263 GB
Notification: Performance of simCode: created initialization part: time 0.02738/1.993, allocations: 19.16 MB / 1.3 GB, free: 88.61 MB / 0.8263 GB
Notification: Performance of SimCode: time 0.03177/2.025, allocations: 21.14 MB / 1.321 GB, free: 67.58 MB / 0.8263 GB
Notification: Performance of Templates: time 0.5624/2.588, allocations: 271.8 MB / 1.586 GB, free: 118.1 MB / 0.8263 GB
make -j1 -f IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example1v2.makefile
(rm -f IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example1v2.pipe ; mkfifo IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example1v2.pipe ; head -c 1048576 < IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example1v2.pipe >> ../files/IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example1v2.sim & ./IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example1v2  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example1v2.pipe 2>&1)