Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_12_Buildings.DHC.Plants.Cooling.Subsystems.Examples.CoolingTowersWithBypass.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.001908/0.001908, allocations: 107.8 kB / 19.71 MB, free: 2.145 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.002401/0.002401, allocations: 220 kB / 23.01 MB, free: 4.969 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.492/1.492, allocations: 230.6 MB / 256.8 MB, free: 7.824 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.359/3.359, allocations: 0.49 GB / 0.7959 GB, free: 35.51 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.DHC.Plants.Cooling.Subsystems.Examples.CoolingTowersWithBypass,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|tow.TWetBul|TEnt.T|tow.TLvg|tow.cooTowSpeCon.u_s|tow.cooTowSpeCon.u_m|TVol.T|TSwi.y|tow.bypValCon.u_s|tow.bypValCon.u_m|tow.bypValCon.y",fileNamePrefix="Buildings_12_Buildings.DHC.Plants.Cooling.Subsystems.Examples.CoolingTowersWithBypass")
translateModel(Buildings.DHC.Plants.Cooling.Subsystems.Examples.CoolingTowersWithBypass,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|tow.TWetBul|TEnt.T|tow.TLvg|tow.cooTowSpeCon.u_s|tow.cooTowSpeCon.u_m|TVol.T|TSwi.y|tow.bypValCon.u_s|tow.bypValCon.u_m|tow.bypValCon.y",fileNamePrefix="Buildings_12_Buildings.DHC.Plants.Cooling.Subsystems.Examples.CoolingTowersWithBypass") [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.002051/0.002051, allocations: 68.11 kB / 1.155 GB, free: 25.27 MB / 0.9044 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4173/0.4193, allocations: 176.1 MB / 1.327 GB, free: 0.9844 MB / 1.014 GB
Notification: Performance of NFInst.instantiate(Buildings.DHC.Plants.Cooling.Subsystems.Examples.CoolingTowersWithBypass): time 0.3248/0.7441, allocations: 245.4 MB / 1.567 GB, free: 10.69 MB / 1.264 GB
Notification: Performance of NFInst.instExpressions: time 0.05964/0.8037, allocations: 33.93 MB / 1.6 GB, free: 8.676 MB / 1.295 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.008092/0.8118, allocations: 218.2 kB / 1.6 GB, free: 8.461 MB / 1.295 GB
Notification: Performance of NFTyping.typeComponents: time 0.009935/0.8217, allocations: 2.851 MB / 1.603 GB, free: 5.598 MB / 1.295 GB
Notification: Performance of NFTyping.typeBindings: time 1.055/1.877, allocations: 6.268 MB / 1.609 GB, free: 26.09 MB / 1.295 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01351/1.89, allocations: 4.615 MB / 1.614 GB, free: 26.09 MB / 1.295 GB
Notification: Performance of NFFlatten.flatten: time 0.02918/1.919, allocations: 16.19 MB / 1.629 GB, free: 26.08 MB / 1.295 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.01178/1.931, allocations: 4.718 MB / 1.634 GB, free: 25.73 MB / 1.295 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.01754/1.949, allocations: 5.758 MB / 1.64 GB, free: 25.72 MB / 1.295 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01139/1.96, allocations: 4.15 MB / 1.644 GB, free: 25.53 MB / 1.295 GB
Notification: Performance of NFPackage.collectConstants: time 0.004366/1.964, allocations: 0.7102 MB / 1.644 GB, free: 25.53 MB / 1.295 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01496/1.979, allocations: 4.193 MB / 1.648 GB, free: 25.53 MB / 1.295 GB
Notification: Performance of NFScalarize.scalarize: time 0.005036/1.984, allocations: 1.887 MB / 1.65 GB, free: 25.25 MB / 1.295 GB
Notification: Performance of NFVerifyModel.verify: time 0.0099/1.994, allocations: 2.839 MB / 1.653 GB, free: 24.55 MB / 1.295 GB
Notification: Performance of NFConvertDAE.convert: time 0.02866/2.023, allocations: 12.47 MB / 1.665 GB, free: 23.15 MB / 1.295 GB
Notification: Performance of FrontEnd - DAE generated: time 7.094e-06/2.023, allocations: 3.75 kB / 1.665 GB, free: 23.15 MB / 1.295 GB
Notification: Performance of FrontEnd: time 2.485e-06/2.023, allocations: 0 / 1.665 GB, free: 23.15 MB / 1.295 GB
Notification: Performance of Transformations before backend: time 0.0005063/2.023, allocations: 0.9375 kB / 1.665 GB, free: 23.15 MB / 1.295 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 892
 * Number of variables: 892
Notification: Performance of Generate backend data structure: time 0.03216/2.056, allocations: 8.145 MB / 1.673 GB, free: 19.91 MB / 1.295 GB
Notification: Performance of prepare preOptimizeDAE: time 5.833e-05/2.056, allocations: 7.781 kB / 1.673 GB, free: 19.91 MB / 1.295 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.008752/2.064, allocations: 1.417 MB / 1.675 GB, free: 19.54 MB / 1.295 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.02085/2.085, allocations: 6.374 MB / 1.681 GB, free: 18.47 MB / 1.295 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006356/2.086, allocations: 281.2 kB / 1.681 GB, free: 18.46 MB / 1.295 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002997/2.089, allocations: 371.2 kB / 1.681 GB, free: 18.46 MB / 1.295 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01984/2.109, allocations: 5.592 MB / 1.687 GB, free: 17.26 MB / 1.295 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002844/2.109, allocations: 9.938 kB / 1.687 GB, free: 17.26 MB / 1.295 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.002018/2.111, allocations: 148.5 kB / 1.687 GB, free: 17.26 MB / 1.295 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001617/2.111, allocations: 93.52 kB / 1.687 GB, free: 17.26 MB / 1.295 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01484/2.126, allocations: 5.14 MB / 1.692 GB, free: 17.23 MB / 1.295 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.04502/2.171, allocations: 15.73 MB / 1.707 GB, free: 13.55 MB / 1.295 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.008855/2.18, allocations: 3.598 MB / 1.711 GB, free: 13.48 MB / 1.295 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004395/2.184, allocations: 1.854 MB / 1.713 GB, free: 12.86 MB / 1.295 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.02029/2.205, allocations: 5.95 MB / 1.719 GB, free: 10.14 MB / 1.295 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.005242/2.21, allocations: 2.676 MB / 1.721 GB, free: 8.801 MB / 1.295 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.003605/2.213, allocations: 413.6 kB / 1.722 GB, free: 8.625 MB / 1.295 GB
Notification: Performance of pre-optimization done (n=264): time 2.018e-05/2.213, allocations: 0 / 1.722 GB, free: 8.625 MB / 1.295 GB
Notification: Performance of matching and sorting (n=264): time 0.02808/2.242, allocations: 9.298 MB / 1.731 GB, free: 4.16 MB / 1.295 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001084/2.242, allocations: 266.6 kB / 1.731 GB, free: 3.871 MB / 1.295 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01171/2.253, allocations: 5.134 MB / 1.736 GB, free: 1.172 MB / 1.295 GB
Notification: Performance of collectPreVariables (initialization): time 0.001195/2.255, allocations: 93.39 kB / 1.736 GB, free: 1.098 MB / 1.295 GB
Notification: Performance of collectInitialEqns (initialization): time 0.00379/2.258, allocations: 2.417 MB / 1.738 GB, free: 15.13 MB / 1.311 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002017/2.26, allocations: 0.9358 MB / 1.739 GB, free: 14.32 MB / 1.311 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.003986/2.264, allocations: 0.8253 MB / 1.74 GB, free: 13.86 MB / 1.311 GB
Notification: Performance of setup shared object (initialization): time 0.0001533/2.265, allocations: 301.1 kB / 1.74 GB, free: 13.57 MB / 1.311 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005323/2.27, allocations: 2.115 MB / 1.743 GB, free: 12.46 MB / 1.311 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006824/2.277, allocations: 4.047 MB / 1.746 GB, free: 9.25 MB / 1.311 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01003/2.287, allocations: 4.655 MB / 1.751 GB, free: 6.512 MB / 1.311 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.401e-05/2.287, allocations: 5.906 kB / 1.751 GB, free: 6.512 MB / 1.311 GB
Notification: Performance of matching and sorting (n=474) (initialization): time 0.01993/2.307, allocations: 6.76 MB / 1.758 GB, free: 2.645 MB / 1.311 GB
Notification: Performance of prepare postOptimizeDAE: time 4.897e-05/2.307, allocations: 12 kB / 1.758 GB, free: 2.633 MB / 1.311 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.897e-05/2.307, allocations: 16 kB / 1.758 GB, free: 2.617 MB / 1.311 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.335/4.641, allocations: 339.4 MB / 2.089 GB, free: 14.7 MB / 1.654 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004242/4.646, allocations: 0.7485 MB / 2.09 GB, free: 14 MB / 1.654 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007911/4.653, allocations: 5.619 MB / 2.095 GB, free: 8.168 MB / 1.654 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006254/4.66, allocations: 353.4 kB / 2.096 GB, free: 7.824 MB / 1.654 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001172/4.661, allocations: 171.9 kB / 2.096 GB, free: 7.656 MB / 1.654 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.008897/4.67, allocations: 3.438 MB / 2.099 GB, free: 4.215 MB / 1.654 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.006471/4.676, allocations: 3.98 MB / 2.103 GB, free: 15.75 MB / 1.67 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.009574/4.686, allocations: 4.527 MB / 2.107 GB, free: 11.04 MB / 1.67 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 2.233e-05/4.686, allocations: 4 kB / 2.107 GB, free: 11.04 MB / 1.67 GB
Notification: Performance of matching and sorting (n=474) (initialization_lambda0): time 0.01878/4.705, allocations: 6.608 MB / 2.114 GB, free: 4.492 MB / 1.67 GB
Notification: Performance of prepare postOptimizeDAE: time 4.751e-05/4.705, allocations: 12 kB / 2.114 GB, free: 4.48 MB / 1.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.659e-05/4.705, allocations: 20 kB / 2.114 GB, free: 4.461 MB / 1.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.073/7.778, allocations: 339.3 MB / 2.445 GB, free: 0.6432 GB / 1.826 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003876/7.781, allocations: 0.7334 MB / 2.446 GB, free: 0.6426 GB / 1.826 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.006497/7.788, allocations: 6.822 MB / 2.453 GB, free: 0.6365 GB / 1.826 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.005817/7.794, allocations: 379.1 kB / 2.453 GB, free: 0.6362 GB / 1.826 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0009471/7.795, allocations: 166 kB / 2.453 GB, free: 0.6361 GB / 1.826 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 71
 * Number of states: 0 ()
 * Number of discrete variables: 23 (tow.cooTowSpeCon.con1.y,weaDat.conTim.canRepeatWeatherFile,weaDat.conTimMin.canRepeatWeatherFile,hys.y,$PRE.hys.y,weaDat.decAng.tNext,$PRE.weaDat.decAng.tNext,weaDat.eqnTim.tNext,$PRE.weaDat.eqnTim.tNext,weaDat.conTim.tNext,$PRE.weaDat.conTim.tNext,weaDat.conTimMin.tNext,$PRE.weaDat.conTimMin.tNext,weaDat.locTim.tNext,$PRE.weaDat.locTim.tNext,tow.hys.y,$PRE.tow.hys.y,$whenCondition1,$whenCondition2,$whenCondition3,$whenCondition4,$whenCondition5,$whenCondition6)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (418):
 * Single equations (assignments): 410
 * Array equations: 1
 * Algorithm blocks: 0
 * Record equations: 1
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 6
 * 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): 6 systems
   {(1,6), (1,6), (1,1), (1,1), (3,5), (1,2)}
Notification: Performance of prepare postOptimizeDAE: time 0.001653/7.796, allocations: 399.4 kB / 2.454 GB, free: 0.6358 GB / 1.826 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002054/7.798, allocations: 0.4976 MB / 2.454 GB, free: 0.6356 GB / 1.826 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01386/7.812, allocations: 5.449 MB / 2.459 GB, free: 0.6318 GB / 1.826 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.008336/7.821, allocations: 4.333 MB / 2.464 GB, free: 0.6285 GB / 1.826 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.171e-05/7.821, allocations: 0 / 2.464 GB, free: 0.6285 GB / 1.826 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.003774/7.824, allocations: 1.005 MB / 2.465 GB, free: 0.6278 GB / 1.826 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001811/7.826, allocations: 60 kB / 2.465 GB, free: 0.6277 GB / 1.826 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.004111/7.83, allocations: 290.1 kB / 2.465 GB, free: 0.6275 GB / 1.826 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0005439/7.831, allocations: 218 kB / 2.465 GB, free: 0.6273 GB / 1.826 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.0131/7.844, allocations: 5.75 MB / 2.471 GB, free: 0.6217 GB / 1.826 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.01155/7.856, allocations: 7.051 MB / 2.478 GB, free: 0.6149 GB / 1.826 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.00293/7.858, allocations: 1.897 MB / 2.48 GB, free: 0.613 GB / 1.826 GB
Notification: Performance of sorting global known variables: time 0.006721/7.865, allocations: 3.504 MB / 2.483 GB, free: 0.6096 GB / 1.826 GB
Notification: Performance of Backend: time 2.81e-07/7.865, allocations: 0 / 2.483 GB, free: 0.6096 GB / 1.826 GB
Notification: Performance of simCode: created initialization part: time 0.0228/7.888, allocations: 7.79 MB / 2.491 GB, free: 0.6026 GB / 1.826 GB
Notification: Performance of SimCode: time 0.0403/7.928, allocations: 12.09 MB / 2.502 GB, free: 0.5909 GB / 1.826 GB
Notification: Performance of Templates: time 0.1823/8.111, allocations: 143.9 MB / 2.643 GB, free: 462.6 MB / 1.826 GB
"
[Timeout remaining time 292]
make -j1 -f Buildings_12_Buildings.DHC.Plants.Cooling.Subsystems.Examples.CoolingTowersWithBypass.makefile [Timeout 300]
(rm -f Buildings_12_Buildings.DHC.Plants.Cooling.Subsystems.Examples.CoolingTowersWithBypass.pipe ; mkfifo Buildings_12_Buildings.DHC.Plants.Cooling.Subsystems.Examples.CoolingTowersWithBypass.pipe ; head -c 1048576 < Buildings_12_Buildings.DHC.Plants.Cooling.Subsystems.Examples.CoolingTowersWithBypass.pipe >> ../files/Buildings_12_Buildings.DHC.Plants.Cooling.Subsystems.Examples.CoolingTowersWithBypass.sim & ./Buildings_12_Buildings.DHC.Plants.Cooling.Subsystems.Examples.CoolingTowersWithBypass  -abortSlowSimulation -alarm=400  -emit_protected -lv LOG_STATS > Buildings_12_Buildings.DHC.Plants.Cooling.Subsystems.Examples.CoolingTowersWithBypass.pipe 2>&1) [Timeout 400]
[Calling os._exit(0), Time elapsed: 21.184637592006766]
Failed to read output from testmodel.py, exit status != 0:
8.121058035001624 8.129356156 6.103744761
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f46d02def80>