Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_12_Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_Dynamic.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.001944/0.001944, allocations: 101.1 kB / 19.71 MB, free: 2.152 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.00238/0.00238, allocations: 209.6 kB / 23 MB, free: 4.965 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.516/1.516, allocations: 230.6 MB / 256.8 MB, free: 7.797 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.259/3.259, allocations: 0.49 GB / 0.7959 GB, free: 35.46 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_Dynamic,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|heaPum.QCon_flow|heaPum1.QCon_flow|appCap.y|heaPum.com.y",fileNamePrefix="Buildings_12_Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_Dynamic")
translateModel(Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_Dynamic,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|heaPum.QCon_flow|heaPum1.QCon_flow|appCap.y|heaPum.com.y",fileNamePrefix="Buildings_12_Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_Dynamic") [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.002091/0.002091, allocations: 68.53 kB / 1.155 GB, free: 25.19 MB / 0.9044 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3508/0.3529, allocations: 176.1 MB / 1.327 GB, free: 1.004 MB / 1.014 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_Dynamic): time 0.1433/0.4962, allocations: 120.2 MB / 1.445 GB, free: 8.359 MB / 1.139 GB
Notification: Performance of NFInst.instExpressions: time 0.03922/0.5354, allocations: 23.37 MB / 1.467 GB, free: 0.9336 MB / 1.154 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004912/0.5403, allocations: 190.4 kB / 1.468 GB, free: 0.7461 MB / 1.154 GB
Notification: Performance of NFTyping.typeComponents: time 0.005422/0.5457, allocations: 1.704 MB / 1.469 GB, free: 15.03 MB / 1.17 GB
Notification: Performance of NFTyping.typeBindings: time 0.01464/0.5604, allocations: 4.876 MB / 1.474 GB, free: 10.14 MB / 1.17 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01038/0.5707, allocations: 3.567 MB / 1.477 GB, free: 6.598 MB / 1.17 GB
Notification: Performance of NFFlatten.flatten: time 0.01458/0.5853, allocations: 9.198 MB / 1.486 GB, free: 13.37 MB / 1.186 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.004767/0.5901, allocations: 2.532 MB / 1.489 GB, free: 10.78 MB / 1.186 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.009154/0.5992, allocations: 3.863 MB / 1.493 GB, free: 6.914 MB / 1.186 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.004942/0.6042, allocations: 2.576 MB / 1.495 GB, free: 4.328 MB / 1.186 GB
Notification: Performance of NFPackage.collectConstants: time 0.000939/0.6051, allocations: 395.9 kB / 1.496 GB, free: 3.941 MB / 1.186 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.006707/0.6118, allocations: 2.581 MB / 1.498 GB, free: 1.359 MB / 1.186 GB
Notification: Performance of NFScalarize.scalarize: time 0.001262/0.6131, allocations: 0.9186 MB / 1.499 GB, free: 448 kB / 1.186 GB
Notification: Performance of NFVerifyModel.verify: time 0.002825/0.6159, allocations: 1.611 MB / 1.501 GB, free: 14.82 MB / 1.201 GB
Notification: Performance of NFConvertDAE.convert: time 0.01136/0.6273, allocations: 6.486 MB / 1.507 GB, free: 8.312 MB / 1.201 GB
Notification: Performance of FrontEnd - DAE generated: time 8.917e-06/0.6273, allocations: 4 kB / 1.507 GB, free: 8.309 MB / 1.201 GB
Notification: Performance of FrontEnd: time 1.783e-06/0.6273, allocations: 0 / 1.507 GB, free: 8.309 MB / 1.201 GB
Notification: Performance of Transformations before backend: time 0.0001236/0.6274, allocations: 0 / 1.507 GB, free: 8.309 MB / 1.201 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 583
 * Number of variables: 583
Notification: Performance of Generate backend data structure: time 0.00912/0.6365, allocations: 4.137 MB / 1.511 GB, free: 4.078 MB / 1.201 GB
Notification: Performance of prepare preOptimizeDAE: time 5.068e-05/0.6366, allocations: 8.031 kB / 1.511 GB, free: 4.07 MB / 1.201 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01145/0.648, allocations: 1.309 MB / 1.512 GB, free: 2.758 MB / 1.201 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.007215/0.6553, allocations: 2.958 MB / 1.515 GB, free: 15.71 MB / 1.217 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003135/0.6556, allocations: 307.4 kB / 1.515 GB, free: 15.38 MB / 1.217 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0005319/0.6561, allocations: 240 kB / 1.516 GB, free: 15.15 MB / 1.217 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.009084/0.6652, allocations: 4.037 MB / 1.52 GB, free: 10.71 MB / 1.217 GB
Notification: Performance of preOpt findStateOrder (simulation): time 5.744e-05/0.6652, allocations: 11.88 kB / 1.52 GB, free: 10.7 MB / 1.217 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003022/0.6655, allocations: 96 kB / 1.52 GB, free: 10.61 MB / 1.217 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 8.659e-05/0.6656, allocations: 79.92 kB / 1.52 GB, free: 10.53 MB / 1.217 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.009381/0.675, allocations: 2.632 MB / 1.522 GB, free: 7.883 MB / 1.217 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.02148/0.6965, allocations: 10.9 MB / 1.533 GB, free: 12.73 MB / 1.232 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.004661/0.7012, allocations: 1.335 MB / 1.534 GB, free: 11.38 MB / 1.232 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.003272/0.7044, allocations: 0.8192 MB / 1.535 GB, free: 10.56 MB / 1.232 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.00131/0.7057, allocations: 209.1 kB / 1.535 GB, free: 10.35 MB / 1.232 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.00463/0.7104, allocations: 1.857 MB / 1.537 GB, free: 8.254 MB / 1.232 GB
Notification: Performance of pre-optimization done (n=128): time 4.469e-06/0.7104, allocations: 0 / 1.537 GB, free: 8.254 MB / 1.232 GB
Notification: Performance of matching and sorting (n=134): time 0.03201/0.7424, allocations: 9.271 MB / 1.546 GB, free: 14.91 MB / 1.248 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0003429/0.7427, allocations: 0.5805 MB / 1.547 GB, free: 14.2 MB / 1.248 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00393/0.7467, allocations: 2.225 MB / 1.549 GB, free: 11.95 MB / 1.248 GB
Notification: Performance of collectPreVariables (initialization): time 0.0003278/0.747, allocations: 61.7 kB / 1.549 GB, free: 11.88 MB / 1.248 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001371/0.7483, allocations: 1.37 MB / 1.55 GB, free: 10.5 MB / 1.248 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0006062/0.749, allocations: 491.8 kB / 1.551 GB, free: 10.01 MB / 1.248 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.000833/0.7498, allocations: 467.1 kB / 1.551 GB, free: 9.551 MB / 1.248 GB
Notification: Performance of setup shared object (initialization): time 0.0001334/0.7499, allocations: 301.1 kB / 1.551 GB, free: 9.254 MB / 1.248 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004029/0.754, allocations: 0.9784 MB / 1.552 GB, free: 8.266 MB / 1.248 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.005064/0.759, allocations: 2.26 MB / 1.555 GB, free: 5.59 MB / 1.248 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.007848/0.7669, allocations: 2.183 MB / 1.557 GB, free: 3.238 MB / 1.248 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.04e-05/0.7669, allocations: 4 kB / 1.557 GB, free: 3.234 MB / 1.248 GB
Notification: Performance of matching and sorting (n=225) (initialization): time 0.01204/0.7789, allocations: 3.085 MB / 1.56 GB, free: 128 kB / 1.248 GB
Notification: Performance of prepare postOptimizeDAE: time 6.331e-05/0.779, allocations: 37.89 kB / 1.56 GB, free: 84 kB / 1.248 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.685e-05/0.779, allocations: 16.19 kB / 1.56 GB, free: 68 kB / 1.248 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.003728/0.7827, allocations: 0.9741 MB / 1.561 GB, free: 15.09 MB / 1.264 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003198/0.7859, allocations: 491.2 kB / 1.561 GB, free: 14.61 MB / 1.264 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.003732/0.7897, allocations: 2.221 MB / 1.563 GB, free: 12.34 MB / 1.264 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004525/0.7942, allocations: 267.6 kB / 1.564 GB, free: 12.07 MB / 1.264 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002783/0.7945, allocations: 107.9 kB / 1.564 GB, free: 11.97 MB / 1.264 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.005046/0.7995, allocations: 1.478 MB / 1.565 GB, free: 10.48 MB / 1.264 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.004942/0.8044, allocations: 2.249 MB / 1.567 GB, free: 7.82 MB / 1.264 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.007327/0.8118, allocations: 2.148 MB / 1.57 GB, free: 5.504 MB / 1.264 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 9.257e-06/0.8118, allocations: 4 kB / 1.57 GB, free: 5.5 MB / 1.264 GB
Notification: Performance of matching and sorting (n=225) (initialization_lambda0): time 0.01191/0.8237, allocations: 3.054 MB / 1.572 GB, free: 2.418 MB / 1.264 GB
Notification: Performance of prepare postOptimizeDAE: time 5.393e-05/0.8237, allocations: 37.7 kB / 1.573 GB, free: 2.375 MB / 1.264 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.895e-05/0.8238, allocations: 12 kB / 1.573 GB, free: 2.363 MB / 1.264 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.003562/0.8273, allocations: 0.958 MB / 1.573 GB, free: 1.406 MB / 1.264 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003184/0.8305, allocations: 483.2 kB / 1.574 GB, free: 0.9336 MB / 1.264 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.003727/0.8342, allocations: 2.23 MB / 1.576 GB, free: 14.66 MB / 1.279 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004608/0.8388, allocations: 284 kB / 1.576 GB, free: 14.38 MB / 1.279 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002659/0.8391, allocations: 107.8 kB / 1.576 GB, free: 14.27 MB / 1.279 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 53
 * Number of states: 0 ()
 * Number of discrete variables: 10 (realToInteger.y,N.count,$PRE.N.count,N.T_start,$PRE.N.T_start,heaPum1.com.isOn,$PRE.heaPum1.com.isOn,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 (170):
 * Single equations (assignments): 167
 * Array equations: 0
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 2
 * 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): 2 systems
   {(7,20), (7,22)}
Notification: Performance of prepare postOptimizeDAE: time 0.0006884/0.8398, allocations: 311.8 kB / 1.577 GB, free: 13.96 MB / 1.279 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0007198/0.8405, allocations: 250.5 kB / 1.577 GB, free: 13.71 MB / 1.279 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01057/0.8511, allocations: 2.426 MB / 1.579 GB, free: 11.27 MB / 1.279 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.009467/0.8606, allocations: 2.057 MB / 1.581 GB, free: 9.156 MB / 1.279 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.627e-05/0.8606, allocations: 4 kB / 1.581 GB, free: 9.152 MB / 1.279 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001684/0.8623, allocations: 482.7 kB / 1.582 GB, free: 8.68 MB / 1.279 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0006402/0.8629, allocations: 28 kB / 1.582 GB, free: 8.652 MB / 1.279 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003705/0.8666, allocations: 203.6 kB / 1.582 GB, free: 8.453 MB / 1.279 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002984/0.8669, allocations: 104.8 kB / 1.582 GB, free: 8.348 MB / 1.279 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01077/0.8777, allocations: 2.958 MB / 1.585 GB, free: 5.375 MB / 1.279 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.01134/0.889, allocations: 3.631 MB / 1.589 GB, free: 1.727 MB / 1.279 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.003248/0.8923, allocations: 0.8235 MB / 1.589 GB, free: 0.9023 MB / 1.279 GB
Notification: Performance of sorting global known variables: time 0.003012/0.8953, allocations: 1.515 MB / 1.591 GB, free: 15.37 MB / 1.295 GB
Notification: Performance of Backend: time 1.012e-06/0.8953, allocations: 0 / 1.591 GB, free: 15.37 MB / 1.295 GB
Notification: Performance of simCode: created initialization part: time 0.01889/0.9142, allocations: 4.255 MB / 1.595 GB, free: 11.09 MB / 1.295 GB
Notification: Performance of SimCode: time 0.01239/0.9266, allocations: 6.041 MB / 1.601 GB, free: 5.02 MB / 1.295 GB
Notification: Performance of Templates: time 0.7448/1.671, allocations: 92.57 MB / 1.691 GB, free: 211.2 MB / 1.295 GB
"
[Timeout remaining time 298]
make -j1 -f Buildings_12_Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_Dynamic.makefile [Timeout 300]
(rm -f Buildings_12_Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_Dynamic.pipe ; mkfifo Buildings_12_Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_Dynamic.pipe ; head -c 1048576 < Buildings_12_Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_Dynamic.pipe >> ../files/Buildings_12_Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_Dynamic.sim & ./Buildings_12_Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_Dynamic  -abortSlowSimulation -alarm=400  -emit_protected -lv LOG_STATS > Buildings_12_Buildings.Fluid.HeatPumps.Validation.ReciprocatingWaterToWater_Dynamic.pipe 2>&1) [Timeout 400]
[Calling os._exit(0), Time elapsed: 12.896712034002121]
Failed to read output from testmodel.py, exit status != 0:
1.6794940879990463 1.688613103 1.058859234
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f4720eaef80>