Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_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/package.mo", uses=false)
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.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_IDEAS.Fluid.Examples.Performance.Example1v2")
translateModel(IDEAS.Fluid.Examples.Performance.Example1v2,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IDEAS_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.001093/0.001093, allocations: 114.5 kB / 16.42 MB, free: 6.496 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.001016/0.001016, allocations: 188.3 kB / 17.35 MB, free: 5.758 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.296/1.296, allocations: 222.9 MB / 241.1 MB, free: 15.17 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0/package.mo): time 0.7826/0.7826, allocations: 132.2 MB / 423.4 MB, free: 6.59 MB / 334.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001363/0.001363, allocations: 59.66 kB / 0.582 GB, free: 23.77 MB / 446.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1324/0.1338, allocations: 80.21 MB / 0.6603 GB, free: 10.27 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.07688/0.2107, allocations: 80.61 MB / 0.7391 GB, free: 9.375 MB / 0.5762 GB
Notification: Performance of NFInst.instExpressions: time 0.3307/0.5415, allocations: 19.24 MB / 0.7579 GB, free: 11.16 MB / 0.5762 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00312/0.5446, allocations: 107.1 kB / 0.758 GB, free: 11.16 MB / 0.5762 GB
Notification: Performance of NFTyping.typeComponents: time 0.002851/0.5475, allocations: 1.155 MB / 0.7591 GB, free: 11.13 MB / 0.5762 GB
Notification: Performance of NFTyping.typeBindings: time 0.008012/0.5555, allocations: 2.847 MB / 0.7619 GB, free: 11.01 MB / 0.5762 GB
Notification: Performance of NFTyping.typeClassSections: time 0.005995/0.5615, allocations: 2.397 MB / 0.7642 GB, free: 10.96 MB / 0.5762 GB
Notification: Performance of NFFlatten.flatten: time 0.02416/0.5857, allocations: 26.89 MB / 0.7905 GB, free: 7.207 MB / 0.5762 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.01806/0.6038, allocations: 17.08 MB / 0.8072 GB, free: 14.79 MB / 0.5919 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.02169/0.6255, allocations: 11.43 MB / 0.8183 GB, free: 4.5 MB / 0.5919 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01989/0.6454, allocations: 11.68 MB / 0.8297 GB, free: 9.699 MB / 0.6075 GB
Notification: Performance of NFPackage.collectConstants: time 0.004508/0.6499, allocations: 1.277 MB / 0.831 GB, free: 8.422 MB / 0.6075 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01049/0.6604, allocations: 2.577 MB / 0.8335 GB, free: 5.902 MB / 0.6075 GB
Notification: Performance of NFScalarize.scalarize: time 0.00672/0.6672, allocations: 3.634 MB / 0.837 GB, free: 2.449 MB / 0.6075 GB
Notification: Performance of NFVerifyModel.verify: time 0.01336/0.6805, allocations: 6.487 MB / 0.8434 GB, free: 12.14 MB / 0.6231 GB
Notification: Performance of NFConvertDAE.convert: time 0.02565/0.7062, allocations: 18.56 MB / 0.8615 GB, free: 10.22 MB / 0.6387 GB
Notification: Performance of FrontEnd - DAE generated: time 7.354e-06/0.7062, allocations: 3.984 kB / 0.8615 GB, free: 10.22 MB / 0.6387 GB
Notification: Performance of FrontEnd: time 1.543e-06/0.7063, allocations: 0 / 0.8615 GB, free: 10.22 MB / 0.6387 GB
Notification: Performance of Transformations before backend: time 0.0004302/0.7067, allocations: 4 kB / 0.8615 GB, free: 10.21 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.03145/0.7382, allocations: 12.5 MB / 0.8737 GB, free: 13.76 MB / 0.6544 GB
Notification: Performance of prepare preOptimizeDAE: time 4.03e-05/0.7382, allocations: 8.031 kB / 0.8737 GB, free: 13.75 MB / 0.6544 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.006168/0.7444, allocations: 1.31 MB / 0.875 GB, free: 12.44 MB / 0.6544 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0198/0.7642, allocations: 10.19 MB / 0.885 GB, free: 2.402 MB / 0.6544 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003375/0.7646, allocations: 447 kB / 0.8854 GB, free: 1.965 MB / 0.6544 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002183/0.7668, allocations: 0.585 MB / 0.886 GB, free: 1.383 MB / 0.6544 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02363/0.7904, allocations: 14.62 MB / 0.9002 GB, free: 2.508 MB / 0.67 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001447/0.7906, allocations: 23.83 kB / 0.9002 GB, free: 2.484 MB / 0.67 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001182/0.7918, allocations: 244 kB / 0.9005 GB, free: 2.246 MB / 0.67 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 9.923e-05/0.7919, allocations: 136 kB / 0.9006 GB, free: 2.113 MB / 0.67 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0281/0.82, allocations: 19.76 MB / 0.9199 GB, free: 14.34 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.08998/0.91, allocations: 60.11 MB / 0.9786 GB, free: 1.141 MB / 0.7481 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01422/0.9242, allocations: 9.165 MB / 0.9876 GB, free: 7.973 MB / 0.7637 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.01184/0.9361, allocations: 8.599 MB / 0.996 GB, free: 15.38 MB / 0.7794 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.04953/0.9857, allocations: 27.45 MB / 1.023 GB, free: 3.648 MB / 0.795 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.01184/0.9975, allocations: 7.645 MB / 1.03 GB, free: 11.96 MB / 0.8106 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.01518/1.013, allocations: 4.491 MB / 1.035 GB, free: 7.609 MB / 0.8106 GB
Notification: Performance of pre-optimization done (n=299): time 2.439e-05/1.013, allocations: 0 / 1.035 GB, free: 7.609 MB / 0.8106 GB
Notification: Performance of matching and sorting (n=299): time 0.05182/1.065, allocations: 23.36 MB / 1.057 GB, free: 140 kB / 0.8262 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.315e-05/1.065, allocations: 85.77 kB / 1.057 GB, free: 40 kB / 0.8262 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.5875/1.652, allocations: 8.245 MB / 1.066 GB, free: 309 MB / 0.8263 GB
Notification: Performance of collectPreVariables (initialization): time 0.001872/1.654, allocations: 97.2 kB / 1.066 GB, free: 308.9 MB / 0.8263 GB
Notification: Performance of collectInitialEqns (initialization): time 0.003595/1.658, allocations: 4.125 MB / 1.07 GB, free: 306.8 MB / 0.8263 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002566/1.66, allocations: 1.127 MB / 1.071 GB, free: 306.3 MB / 0.8263 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.003528/1.664, allocations: 1.677 MB / 1.072 GB, free: 305.9 MB / 0.8263 GB
Notification: Performance of setup shared object (initialization): time 3.971e-05/1.664, allocations: 303.3 kB / 1.073 GB, free: 305.6 MB / 0.8263 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.009972/1.674, allocations: 6.546 MB / 1.079 GB, free: 303.1 MB / 0.8263 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01079/1.685, allocations: 8.322 MB / 1.087 GB, free: 298.5 MB / 0.8263 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02046/1.705, allocations: 15.25 MB / 1.102 GB, free: 290.5 MB / 0.8263 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.25e-05/1.705, allocations: 16.28 kB / 1.102 GB, free: 290.5 MB / 0.8263 GB
Notification: Performance of matching and sorting (n=578) (initialization): time 0.03333/1.739, allocations: 17.59 MB / 1.119 GB, free: 278.3 MB / 0.8263 GB
Notification: Performance of prepare postOptimizeDAE: time 6.704e-05/1.739, allocations: 61.69 kB / 1.119 GB, free: 278.2 MB / 0.8263 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.332e-05/1.739, allocations: 48 kB / 1.119 GB, free: 278.1 MB / 0.8263 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.06369/1.802, allocations: 11.57 MB / 1.131 GB, free: 266.9 MB / 0.8263 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007867/1.81, allocations: 1.557 MB / 1.132 GB, free: 265.3 MB / 0.8263 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.00474/1.815, allocations: 3.788 MB / 1.136 GB, free: 261.4 MB / 0.8263 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.009214/1.824, allocations: 342.1 kB / 1.136 GB, free: 261.1 MB / 0.8263 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002245/1.827, allocations: 0.5421 MB / 1.137 GB, free: 260.6 MB / 0.8263 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.01348/1.84, allocations: 8.818 MB / 1.145 GB, free: 251.7 MB / 0.8263 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.00993/1.85, allocations: 8.279 MB / 1.153 GB, free: 242.6 MB / 0.8263 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.0195/1.87, allocations: 15.16 MB / 1.168 GB, free: 226.7 MB / 0.8263 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 4.15e-05/1.87, allocations: 12 kB / 1.168 GB, free: 226.7 MB / 0.8263 GB
Notification: Performance of matching and sorting (n=578) (initialization_lambda0): time 0.03241/1.902, allocations: 17.45 MB / 1.185 GB, free: 209.2 MB / 0.8263 GB
Notification: Performance of prepare postOptimizeDAE: time 7.165e-05/1.902, allocations: 57.7 kB / 1.185 GB, free: 209.1 MB / 0.8263 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 7.148e-05/1.902, allocations: 48 kB / 1.185 GB, free: 209.1 MB / 0.8263 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0637/1.966, allocations: 11.13 MB / 1.196 GB, free: 198 MB / 0.8263 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.008113/1.974, allocations: 1.543 MB / 1.198 GB, free: 196.4 MB / 0.8263 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.008559/1.983, allocations: 8.182 MB / 1.206 GB, free: 188 MB / 0.8263 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.009222/1.992, allocations: 346.1 kB / 1.206 GB, free: 187.6 MB / 0.8263 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002424/1.994, allocations: 0.5343 MB / 1.207 GB, free: 187.1 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.003282/1.998, allocations: 0.4887 MB / 1.207 GB, free: 186.6 MB / 0.8263 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.003692/2.001, allocations: 0.7996 MB / 1.208 GB, free: 185.8 MB / 0.8263 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0253/2.027, allocations: 14.16 MB / 1.222 GB, free: 171.7 MB / 0.8263 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.01559/2.042, allocations: 10.78 MB / 1.232 GB, free: 160.8 MB / 0.8263 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.745e-05/2.042, allocations: 4 kB / 1.232 GB, free: 160.8 MB / 0.8263 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.005002/2.047, allocations: 1.855 MB / 1.234 GB, free: 158.9 MB / 0.8263 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002686/2.05, allocations: 75.81 kB / 1.234 GB, free: 158.9 MB / 0.8263 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.005969/2.056, allocations: 198.2 kB / 1.234 GB, free: 158.7 MB / 0.8263 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.00127/2.057, allocations: 258.4 kB / 1.235 GB, free: 158.4 MB / 0.8263 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01953/2.077, allocations: 11.71 MB / 1.246 GB, free: 146.7 MB / 0.8263 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.02227/2.099, allocations: 19.36 MB / 1.265 GB, free: 127.3 MB / 0.8263 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.006031/2.105, allocations: 6.011 MB / 1.271 GB, free: 121.3 MB / 0.8263 GB
Notification: Performance of sorting global known variables: time 0.008427/2.114, allocations: 5.699 MB / 1.276 GB, free: 115.6 MB / 0.8263 GB
Notification: Performance of Backend: time 7.61e-07/2.114, allocations: 0 / 1.276 GB, free: 115.6 MB / 0.8263 GB
Notification: Performance of simCode: created initialization part: time 0.03286/2.147, allocations: 19.16 MB / 1.295 GB, free: 96.54 MB / 0.8263 GB
Notification: Performance of SimCode: time 0.03862/2.185, allocations: 21.16 MB / 1.316 GB, free: 75.54 MB / 0.8263 GB
Notification: Performance of Templates: time 0.6714/2.857, allocations: 271.8 MB / 1.581 GB, free: 128.6 MB / 0.8263 GB
make -j1 -f IDEAS_IDEAS.Fluid.Examples.Performance.Example1v2.makefile
(rm -f IDEAS_IDEAS.Fluid.Examples.Performance.Example1v2.pipe ; mkfifo IDEAS_IDEAS.Fluid.Examples.Performance.Example1v2.pipe ; head -c 1048576 < IDEAS_IDEAS.Fluid.Examples.Performance.Example1v2.pipe >> ../files/IDEAS_IDEAS.Fluid.Examples.Performance.Example1v2.sim & ./IDEAS_IDEAS.Fluid.Examples.Performance.Example1v2  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IDEAS_IDEAS.Fluid.Examples.Performance.Example1v2.pipe 2>&1)