Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_12_Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_VariableSpeed.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.001846/0.001846, allocations: 96.2 kB / 19.7 MB, free: 2.129 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.002318/0.002318, allocations: 205.9 kB / 23 MB, free: 4.934 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.515/1.515, allocations: 230.6 MB / 256.8 MB, free: 7.793 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo): time 3.225/3.225, allocations: 0.49 GB / 0.7959 GB, free: 35.49 MB / 0.6231 GB
"
[Timeout remaining time 176]
Using package Buildings with version 12.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/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(Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_VariableSpeed,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|heaPum.sta_a2.T|heaPum.sta_b2.T|heaPum.com.port_a.T|heaPum.sta_a1.T|heaPum.sta_b1.T|heaPum.com.port_b.T|heaPum.QCon_flow|heaPum.QEva_flow|heaPum.P|heaPum.com.COP|heaPum.com.y|heaPum.com.isOn",fileNamePrefix="Buildings_12_Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_VariableSpeed")
translateModel(Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_VariableSpeed,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|heaPum.sta_a2.T|heaPum.sta_b2.T|heaPum.com.port_a.T|heaPum.sta_a1.T|heaPum.sta_b1.T|heaPum.com.port_b.T|heaPum.QCon_flow|heaPum.QEva_flow|heaPum.P|heaPum.com.COP|heaPum.com.y|heaPum.com.isOn",fileNamePrefix="Buildings_12_Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_VariableSpeed") [Timeout 300]
"Notification: Buildings 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.002092/0.002092, allocations: 65.91 kB / 1.155 GB, free: 25.2 MB / 0.9044 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3261/0.3282, allocations: 176.1 MB / 1.327 GB, free: 1 MB / 1.014 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_VariableSpeed): time 0.07562/0.4038, allocations: 64.5 MB / 1.39 GB, free: 292 kB / 1.076 GB
Notification: Performance of NFInst.instExpressions: time 0.02386/0.4277, allocations: 13.5 MB / 1.403 GB, free: 2.754 MB / 1.092 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003003/0.4307, allocations: 103.1 kB / 1.403 GB, free: 2.652 MB / 1.092 GB
Notification: Performance of NFTyping.typeComponents: time 0.00305/0.4337, allocations: 0.9044 MB / 1.404 GB, free: 1.742 MB / 1.092 GB
Notification: Performance of NFTyping.typeBindings: time 0.008351/0.4421, allocations: 2.724 MB / 1.407 GB, free: 15.01 MB / 1.107 GB
Notification: Performance of NFTyping.typeClassSections: time 0.0057/0.4478, allocations: 2.029 MB / 1.409 GB, free: 13 MB / 1.107 GB
Notification: Performance of NFFlatten.flatten: time 0.006987/0.4548, allocations: 4.813 MB / 1.414 GB, free: 8.172 MB / 1.107 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002087/0.4569, allocations: 1.295 MB / 1.415 GB, free: 6.832 MB / 1.107 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.004599/0.4615, allocations: 2.038 MB / 1.417 GB, free: 4.793 MB / 1.107 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.002075/0.4635, allocations: 1.326 MB / 1.418 GB, free: 3.461 MB / 1.107 GB
Notification: Performance of NFPackage.collectConstants: time 0.0003275/0.4639, allocations: 196 kB / 1.418 GB, free: 3.27 MB / 1.107 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.003959/0.4678, allocations: 1.795 MB / 1.42 GB, free: 1.473 MB / 1.107 GB
Notification: Performance of NFScalarize.scalarize: time 0.0005087/0.4683, allocations: 486.2 kB / 1.421 GB, free: 0.9961 MB / 1.107 GB
Notification: Performance of NFVerifyModel.verify: time 0.001055/0.4694, allocations: 0.8403 MB / 1.421 GB, free: 156 kB / 1.107 GB
Notification: Performance of NFConvertDAE.convert: time 0.005369/0.4747, allocations: 3.59 MB / 1.425 GB, free: 12.55 MB / 1.123 GB
Notification: Performance of FrontEnd - DAE generated: time 5.38e-06/0.4748, allocations: 0 / 1.425 GB, free: 12.55 MB / 1.123 GB
Notification: Performance of FrontEnd: time 1.833e-06/0.4748, allocations: 0 / 1.425 GB, free: 12.55 MB / 1.123 GB
Notification: Performance of Transformations before backend: time 3.307e-05/0.4748, allocations: 0 / 1.425 GB, free: 12.55 MB / 1.123 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 296
 * Number of variables: 296
Notification: Performance of Generate backend data structure: time 0.004783/0.4796, allocations: 2.351 MB / 1.427 GB, free: 10.14 MB / 1.123 GB
Notification: Performance of prepare preOptimizeDAE: time 4.493e-05/0.4796, allocations: 11.97 kB / 1.427 GB, free: 10.13 MB / 1.123 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.005889/0.4855, allocations: 0.6631 MB / 1.428 GB, free: 9.465 MB / 1.123 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003067/0.4886, allocations: 1.561 MB / 1.429 GB, free: 7.859 MB / 1.123 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001704/0.4887, allocations: 157.7 kB / 1.429 GB, free: 7.691 MB / 1.123 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002416/0.489, allocations: 116 kB / 1.43 GB, free: 7.578 MB / 1.123 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.004467/0.4935, allocations: 2.054 MB / 1.432 GB, free: 5.336 MB / 1.123 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.717e-05/0.4935, allocations: 3.938 kB / 1.432 GB, free: 5.332 MB / 1.123 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001563/0.4936, allocations: 55.98 kB / 1.432 GB, free: 5.277 MB / 1.123 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.515e-05/0.4937, allocations: 32 kB / 1.432 GB, free: 5.246 MB / 1.123 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.005018/0.4987, allocations: 1.378 MB / 1.433 GB, free: 3.859 MB / 1.123 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.01099/0.5097, allocations: 5.563 MB / 1.438 GB, free: 14.17 MB / 1.139 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.002491/0.5122, allocations: 0.726 MB / 1.439 GB, free: 13.43 MB / 1.139 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001789/0.514, allocations: 462.5 kB / 1.44 GB, free: 12.98 MB / 1.139 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0008919/0.5149, allocations: 144.5 kB / 1.44 GB, free: 12.84 MB / 1.139 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002465/0.5173, allocations: 1.012 MB / 1.441 GB, free: 11.7 MB / 1.139 GB
Notification: Performance of pre-optimization done (n=70): time 3.777e-06/0.5173, allocations: 3.938 kB / 1.441 GB, free: 11.69 MB / 1.139 GB
Notification: Performance of matching and sorting (n=70): time 0.01029/0.5276, allocations: 1.844 MB / 1.443 GB, free: 9.84 MB / 1.139 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001914/0.5278, allocations: 328.9 kB / 1.443 GB, free: 9.445 MB / 1.139 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00207/0.5299, allocations: 1.188 MB / 1.444 GB, free: 8.246 MB / 1.139 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001445/0.53, allocations: 49.7 kB / 1.444 GB, free: 8.191 MB / 1.139 GB
Notification: Performance of collectInitialEqns (initialization): time 0.000726/0.5308, allocations: 0.7309 MB / 1.445 GB, free: 7.453 MB / 1.139 GB
Notification: Performance of collectInitialBindings (initialization): time 0.000305/0.5311, allocations: 257.8 kB / 1.445 GB, free: 7.199 MB / 1.139 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0004664/0.5315, allocations: 246.4 kB / 1.445 GB, free: 6.953 MB / 1.139 GB
Notification: Performance of setup shared object (initialization): time 0.0001692/0.5317, allocations: 345.9 kB / 1.446 GB, free: 6.609 MB / 1.139 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002179/0.5339, allocations: 0.5374 MB / 1.446 GB, free: 6.062 MB / 1.139 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002717/0.5366, allocations: 1.248 MB / 1.447 GB, free: 4.594 MB / 1.139 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.00406/0.5407, allocations: 1.174 MB / 1.448 GB, free: 3.332 MB / 1.139 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.516e-06/0.5407, allocations: 4 kB / 1.448 GB, free: 3.328 MB / 1.139 GB
Notification: Performance of matching and sorting (n=117) (initialization): time 0.007723/0.5484, allocations: 1.677 MB / 1.45 GB, free: 1.621 MB / 1.139 GB
Notification: Performance of prepare postOptimizeDAE: time 5.237e-05/0.5484, allocations: 35.27 kB / 1.45 GB, free: 1.582 MB / 1.139 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.576e-05/0.5485, allocations: 8 kB / 1.45 GB, free: 1.574 MB / 1.139 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001605/0.5501, allocations: 387 kB / 1.451 GB, free: 1.199 MB / 1.139 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001747/0.5518, allocations: 279.5 kB / 1.451 GB, free: 0.9258 MB / 1.139 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001481/0.5533, allocations: 0.9125 MB / 1.452 GB, free: 15.98 MB / 1.154 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00255/0.5558, allocations: 203.2 kB / 1.452 GB, free: 15.79 MB / 1.154 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001416/0.556, allocations: 55.89 kB / 1.452 GB, free: 15.73 MB / 1.154 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.00282/0.5588, allocations: 0.847 MB / 1.453 GB, free: 14.88 MB / 1.154 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.002612/0.5614, allocations: 1.244 MB / 1.454 GB, free: 13.41 MB / 1.154 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.003967/0.5654, allocations: 1.17 MB / 1.455 GB, free: 12.15 MB / 1.154 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 6.893e-06/0.5654, allocations: 4 kB / 1.455 GB, free: 12.15 MB / 1.154 GB
Notification: Performance of matching and sorting (n=117) (initialization_lambda0): time 0.007385/0.5728, allocations: 1.666 MB / 1.457 GB, free: 10.46 MB / 1.154 GB
Notification: Performance of prepare postOptimizeDAE: time 4.505e-05/0.5728, allocations: 37.69 kB / 1.457 GB, free: 10.42 MB / 1.154 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.364e-05/0.5728, allocations: 12.44 kB / 1.457 GB, free: 10.41 MB / 1.154 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001552/0.5744, allocations: 396.8 kB / 1.457 GB, free: 10.02 MB / 1.154 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001788/0.5762, allocations: 275.5 kB / 1.457 GB, free: 9.754 MB / 1.154 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001461/0.5776, allocations: 0.9152 MB / 1.458 GB, free: 8.812 MB / 1.154 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002445/0.5801, allocations: 199.4 kB / 1.459 GB, free: 8.617 MB / 1.154 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001242/0.5802, allocations: 51.95 kB / 1.459 GB, free: 8.566 MB / 1.154 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 27
 * Number of states: 0 ()
 * Number of discrete variables: 7 (N.count,$PRE.N.count,N.T_start,$PRE.N.T_start,heaPum.com.isOn,$PRE.heaPum.com.isOn,$whenCondition1)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (98):
 * Single equations (assignments): 96
 * Array equations: 0
 * Algorithm blocks: 1
 * 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
   {(5,14)}
Notification: Performance of prepare postOptimizeDAE: time 0.0004158/0.5806, allocations: 247.9 kB / 1.459 GB, free: 8.316 MB / 1.154 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003311/0.5809, allocations: 139.1 kB / 1.459 GB, free: 8.18 MB / 1.154 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.004565/0.5855, allocations: 1.233 MB / 1.46 GB, free: 6.934 MB / 1.154 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.003826/0.5893, allocations: 1.04 MB / 1.461 GB, free: 5.859 MB / 1.154 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.989e-06/0.5893, allocations: 0 / 1.461 GB, free: 5.859 MB / 1.154 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007545/0.5901, allocations: 266.8 kB / 1.461 GB, free: 5.598 MB / 1.154 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002262/0.5903, allocations: 16 kB / 1.461 GB, free: 5.582 MB / 1.154 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001672/0.592, allocations: 107.9 kB / 1.462 GB, free: 5.477 MB / 1.154 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001379/0.5921, allocations: 52.39 kB / 1.462 GB, free: 5.426 MB / 1.154 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.005642/0.5978, allocations: 1.609 MB / 1.463 GB, free: 3.809 MB / 1.154 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.006173/0.6039, allocations: 2.067 MB / 1.465 GB, free: 1.723 MB / 1.154 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001801/0.6057, allocations: 455.6 kB / 1.466 GB, free: 1.281 MB / 1.154 GB
Notification: Performance of sorting global known variables: time 0.001553/0.6073, allocations: 0.8272 MB / 1.466 GB, free: 460 kB / 1.154 GB
Notification: Performance of Backend: time 3.01e-07/0.6073, allocations: 0 / 1.466 GB, free: 460 kB / 1.154 GB
Notification: Performance of simCode: created initialization part: time 0.0101/0.6174, allocations: 2.429 MB / 1.469 GB, free: 13.98 MB / 1.17 GB
Notification: Performance of SimCode: time 0.006153/0.6236, allocations: 3.388 MB / 1.472 GB, free: 10.55 MB / 1.17 GB
Notification: Performance of Templates: time 0.07668/0.7002, allocations: 47.94 MB / 1.519 GB, free: 11.18 MB / 1.217 GB
"
[Timeout remaining time 299]
make -j1 -f Buildings_12_Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_VariableSpeed.makefile [Timeout 300]
(rm -f Buildings_12_Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_VariableSpeed.pipe ; mkfifo Buildings_12_Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_VariableSpeed.pipe ; head -c 1048576 < Buildings_12_Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_VariableSpeed.pipe >> ../files/Buildings_12_Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_VariableSpeed.sim & ./Buildings_12_Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_VariableSpeed  -abortSlowSimulation -alarm=400  -emit_protected -lv LOG_STATS > Buildings_12_Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_VariableSpeed.pipe 2>&1) [Timeout 400]
[Calling os._exit(0), Time elapsed: 10.743328541997471]
Failed to read output from testmodel.py, exit status != 0:
0.7090716980019351 0.7241954300000001 0.24614412900000002
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fa34f752f80>