Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_13_Buildings.Fluid.HeatExchangers.Examples.WaterHeater_u.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.0009344/0.0009344, allocations: 80.81 kB / 21.55 MB, free: 2.941 MB / 18.57 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.0009742/0.0009742, allocations: 168.8 kB / 24.97 MB, free: 9.18 MB / 18.57 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 0.9684/0.9684, allocations: 177.1 MB / 205.4 MB, free: 5.488 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.x/package.mo): time 1.957/1.957, allocations: 389.6 MB / 0.6362 GB, free: 12.28 MB / 0.6042 GB
"
[Timeout remaining time 178]
Using package Buildings with version 13.0.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.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.HeatExchangers.Examples.WaterHeater_u,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.Fluid.HeatExchangers.Examples.WaterHeater_u")
translateModel(Buildings.Fluid.HeatExchangers.Examples.WaterHeater_u,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.Fluid.HeatExchangers.Examples.WaterHeater_u") [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 1.352e-06/1.352e-06, allocations: 0.6562 kB / 0.776 GB, free: 5.324 MB / 0.7292 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.636e-05/1.771e-05, allocations: 5.688 kB / 0.776 GB, free: 5.32 MB / 0.7292 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.HeatExchangers.Examples.WaterHeater_u): time 0.01593/0.01595, allocations: 20.4 MB / 0.7959 GB, free: 0.8906 MB / 0.7448 GB
Notification: Performance of NFInst.instExpressions: time 0.01506/0.03101, allocations: 10.4 MB / 0.8061 GB, free: 6.543 MB / 0.7605 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002059/0.03307, allocations: 130.9 kB / 0.8062 GB, free: 6.414 MB / 0.7605 GB
Notification: Performance of NFTyping.typeComponents: time 0.003135/0.0362, allocations: 1.632 MB / 0.8078 GB, free: 4.781 MB / 0.7605 GB
Notification: Performance of NFTyping.typeBindings: time 0.00375/0.03995, allocations: 2.34 MB / 0.8101 GB, free: 2.449 MB / 0.7605 GB
Notification: Performance of NFTyping.typeClassSections: time 0.003432/0.04338, allocations: 2.303 MB / 0.8123 GB, free: 156 kB / 0.7605 GB
Notification: Performance of NFFlatten.flatten: time 0.007928/0.05131, allocations: 8.826 MB / 0.821 GB, free: 7.344 MB / 0.7761 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002183/0.0535, allocations: 1.57 MB / 0.8225 GB, free: 5.742 MB / 0.7761 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.003173/0.05667, allocations: 2.973 MB / 0.8254 GB, free: 2.793 MB / 0.7761 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001172/0.05784, allocations: 1.285 MB / 0.8267 GB, free: 1.504 MB / 0.7761 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001861/0.05803, allocations: 184 kB / 0.8268 GB, free: 1.324 MB / 0.7761 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.001511/0.05954, allocations: 1.123 MB / 0.8279 GB, free: 204 kB / 0.7761 GB
Notification: Performance of NFScalarize.scalarize: time 0.0005083/0.06005, allocations: 0.7278 MB / 0.8286 GB, free: 15.47 MB / 0.7917 GB
Notification: Performance of NFVerifyModel.verify: time 0.0007048/0.06075, allocations: 0.8416 MB / 0.8295 GB, free: 14.62 MB / 0.7917 GB
Notification: Performance of NFConvertDAE.convert: time 0.002312/0.06306, allocations: 2.472 MB / 0.8319 GB, free: 12.14 MB / 0.7917 GB
Notification: Performance of FrontEnd - DAE generated: time 2.505e-06/0.06307, allocations: 4 kB / 0.8319 GB, free: 12.14 MB / 0.7917 GB
Notification: Performance of FrontEnd: time 7.91e-07/0.06307, allocations: 0 / 0.8319 GB, free: 12.14 MB / 0.7917 GB
Notification: Performance of Transformations before backend: time 1.643e-05/0.06308, allocations: 4 kB / 0.8319 GB, free: 12.14 MB / 0.7917 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 311
 * Number of variables: 311
Notification: Performance of Generate backend data structure: time 0.002739/0.06582, allocations: 2.87 MB / 0.8347 GB, free: 9.203 MB / 0.7917 GB
Notification: Performance of prepare preOptimizeDAE: time 3.415e-05/0.06586, allocations: 12.58 kB / 0.8347 GB, free: 9.191 MB / 0.7917 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0008135/0.06667, allocations: 443.4 kB / 0.8351 GB, free: 8.758 MB / 0.7917 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002084/0.06875, allocations: 1.907 MB / 0.837 GB, free: 6.754 MB / 0.7917 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 5.911e-05/0.06881, allocations: 102.5 kB / 0.8371 GB, free: 6.652 MB / 0.7917 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001252/0.06894, allocations: 136 kB / 0.8372 GB, free: 6.52 MB / 0.7917 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001315/0.07025, allocations: 1.22 MB / 0.8384 GB, free: 5.152 MB / 0.7917 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.426e-05/0.07028, allocations: 11.92 kB / 0.8384 GB, free: 5.141 MB / 0.7917 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 6.852e-05/0.07035, allocations: 48 kB / 0.8385 GB, free: 5.094 MB / 0.7917 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.793e-05/0.07037, allocations: 32 kB / 0.8385 GB, free: 5.062 MB / 0.7917 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0004993/0.07087, allocations: 380 kB / 0.8389 GB, free: 4.672 MB / 0.7917 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.003791/0.07467, allocations: 4.319 MB / 0.8431 GB, free: 212 kB / 0.7917 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0006368/0.0753, allocations: 486.7 kB / 0.8435 GB, free: 15.72 MB / 0.8073 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0002144/0.07552, allocations: 172.3 kB / 0.8437 GB, free: 15.55 MB / 0.8073 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001579/0.0771, allocations: 1.123 MB / 0.8448 GB, free: 14.41 MB / 0.8073 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0007046/0.0778, allocations: 494 kB / 0.8453 GB, free: 13.86 MB / 0.8073 GB
Notification: Performance of pre-optimization done (n=60): time 2.134e-06/0.0778, allocations: 0 / 0.8453 GB, free: 13.86 MB / 0.8073 GB
Notification: Performance of matching and sorting (n=60): time 0.0007147/0.07852, allocations: 477.7 kB / 0.8457 GB, free: 13.39 MB / 0.8073 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.198e-05/0.07856, allocations: 115.3 kB / 0.8458 GB, free: 13.25 MB / 0.8073 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001153/0.07971, allocations: 1.63 MB / 0.8474 GB, free: 11.59 MB / 0.8073 GB
Notification: Performance of collectPreVariables (initialization): time 5.147e-05/0.07976, allocations: 49.64 kB / 0.8475 GB, free: 11.54 MB / 0.8073 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0003694/0.08013, allocations: 0.7238 MB / 0.8482 GB, free: 10.8 MB / 0.8073 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001248/0.08026, allocations: 226.1 kB / 0.8484 GB, free: 10.57 MB / 0.8073 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001815/0.08044, allocations: 194.4 kB / 0.8486 GB, free: 10.38 MB / 0.8073 GB
Notification: Performance of setup shared object (initialization): time 8.998e-05/0.08053, allocations: 341.9 kB / 0.8489 GB, free: 10.04 MB / 0.8073 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0002275/0.08076, allocations: 161.3 kB / 0.8491 GB, free: 9.871 MB / 0.8073 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0004396/0.0812, allocations: 0.742 MB / 0.8498 GB, free: 8.906 MB / 0.8073 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0005783/0.08177, allocations: 0.6767 MB / 0.8504 GB, free: 8.078 MB / 0.8073 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.09e-06/0.08178, allocations: 0 / 0.8504 GB, free: 8.078 MB / 0.8073 GB
Notification: Performance of matching and sorting (n=118) (initialization): time 0.0007408/0.08252, allocations: 0.7113 MB / 0.8511 GB, free: 7.344 MB / 0.8073 GB
Notification: Performance of prepare postOptimizeDAE: time 3.751e-05/0.08256, allocations: 42.61 kB / 0.8512 GB, free: 7.297 MB / 0.8073 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.23e-05/0.08257, allocations: 8 kB / 0.8512 GB, free: 7.289 MB / 0.8073 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.933e-05/0.08259, allocations: 20 kB / 0.8512 GB, free: 7.27 MB / 0.8073 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0004645/0.08305, allocations: 239.6 kB / 0.8514 GB, free: 7.035 MB / 0.8073 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.399e-05/0.08307, allocations: 23.92 kB / 0.8515 GB, free: 7.012 MB / 0.8073 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0004515/0.08352, allocations: 131.3 kB / 0.8516 GB, free: 6.883 MB / 0.8073 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003496/0.08387, allocations: 39.98 kB / 0.8516 GB, free: 6.844 MB / 0.8073 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.0003777/0.08425, allocations: 253.5 kB / 0.8519 GB, free: 6.582 MB / 0.8073 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.0004312/0.08468, allocations: 0.7459 MB / 0.8526 GB, free: 5.613 MB / 0.8073 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.0005633/0.08524, allocations: 0.6779 MB / 0.8533 GB, free: 4.777 MB / 0.8073 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 4.097e-06/0.08524, allocations: 4 kB / 0.8533 GB, free: 4.773 MB / 0.8073 GB
Notification: Performance of matching and sorting (n=118) (initialization_lambda0): time 0.00072/0.08596, allocations: 0.7156 MB / 0.854 GB, free: 4.035 MB / 0.8073 GB
Notification: Performance of prepare postOptimizeDAE: time 2.299e-05/0.08599, allocations: 37.7 kB / 0.854 GB, free: 3.992 MB / 0.8073 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 8.106e-06/0.086, allocations: 11.98 kB / 0.854 GB, free: 3.98 MB / 0.8073 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.166e-05/0.08601, allocations: 16 kB / 0.854 GB, free: 3.965 MB / 0.8073 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.000437/0.08644, allocations: 244 kB / 0.8542 GB, free: 3.727 MB / 0.8073 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.395e-05/0.08646, allocations: 23.92 kB / 0.8543 GB, free: 3.703 MB / 0.8073 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0004445/0.0869, allocations: 107.5 kB / 0.8544 GB, free: 3.598 MB / 0.8073 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.138e-05/0.08695, allocations: 43.98 kB / 0.8544 GB, free: 3.555 MB / 0.8073 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 47
 * Number of states: 0 ()
 * Number of discrete variables: 6 (TSet.count,$PRE.TSet.count,TSet.T_start,$PRE.TSet.T_start,$whenCondition1,conPI.I.trigger_internal)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (116):
 * Single equations (assignments): 114
 * Array equations: 1
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.0001445/0.0871, allocations: 247.9 kB / 0.8547 GB, free: 3.305 MB / 0.8073 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001809/0.08728, allocations: 139.8 kB / 0.8548 GB, free: 3.168 MB / 0.8073 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.000796/0.08808, allocations: 0.5689 MB / 0.8553 GB, free: 2.59 MB / 0.8073 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0002839/0.08836, allocations: 279.9 kB / 0.8556 GB, free: 2.301 MB / 0.8073 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.378e-06/0.08836, allocations: 3.938 kB / 0.8556 GB, free: 2.297 MB / 0.8073 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0003119/0.08868, allocations: 246.4 kB / 0.8558 GB, free: 2.055 MB / 0.8073 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001147/0.08879, allocations: 16 kB / 0.8559 GB, free: 2.039 MB / 0.8073 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0003606/0.08915, allocations: 47.97 kB / 0.8559 GB, free: 1.992 MB / 0.8073 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 8.019e-05/0.08923, allocations: 52.48 kB / 0.856 GB, free: 1.941 MB / 0.8073 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.0006584/0.08989, allocations: 0.6487 MB / 0.8566 GB, free: 1.281 MB / 0.8073 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.0006847/0.09057, allocations: 0.8889 MB / 0.8575 GB, free: 380 kB / 0.8073 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0001654/0.09074, allocations: 167.2 kB / 0.8576 GB, free: 212 kB / 0.8073 GB
Notification: Performance of sorting global known variables: time 0.000885/0.09162, allocations: 0.7787 MB / 0.8584 GB, free: 15.41 MB / 0.823 GB
Notification: Performance of Backend: time 4.4e-07/0.09163, allocations: 0 / 0.8584 GB, free: 15.41 MB / 0.823 GB
Notification: Performance of simCode: created initialization part: time 0.001764/0.09339, allocations: 1.154 MB / 0.8595 GB, free: 14.21 MB / 0.823 GB
Notification: Performance of SimCode: time 0.005717/0.09911, allocations: 5.113 MB / 0.8645 GB, free: 9.023 MB / 0.823 GB
Notification: Performance of Templates: time 0.05358/0.1527, allocations: 48.81 MB / 0.9122 GB, free: 9.285 MB / 0.8698 GB
"
[Timeout remaining time 300]
make -j1 -f Buildings_13_Buildings.Fluid.HeatExchangers.Examples.WaterHeater_u.makefile [Timeout 300]
(rm -f Buildings_13_Buildings.Fluid.HeatExchangers.Examples.WaterHeater_u.pipe ; mkfifo Buildings_13_Buildings.Fluid.HeatExchangers.Examples.WaterHeater_u.pipe ; head -c 1048576 < Buildings_13_Buildings.Fluid.HeatExchangers.Examples.WaterHeater_u.pipe >> ../files/Buildings_13_Buildings.Fluid.HeatExchangers.Examples.WaterHeater_u.sim & ./Buildings_13_Buildings.Fluid.HeatExchangers.Examples.WaterHeater_u -abortSlowSimulation -alarm=240  -lv LOG_STATS > Buildings_13_Buildings.Fluid.HeatExchangers.Examples.WaterHeater_u.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 5.773652006871998]