Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_12_Buildings.Examples.ScalableBenchmarks.BuildingVAV.ThermalZones.Validation.ThermalZone.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.001384/0.001384, 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.00149/0.00149, allocations: 215.5 kB / 23 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.52/1.52, 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.327/3.327, allocations: 0.49 GB / 0.7959 GB, free: 35.5 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.Examples.ScalableBenchmarks.BuildingVAV.ThermalZones.Validation.ThermalZone,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|thermalZone.weaBus.TDryBul|thermalZone.weaBus.HDirNor|thermalZone.roo.heaPorAir.T|thermalZone.roo.air.QCon_flow|thermalZone.roo.air.QLat_flow",fileNamePrefix="Buildings_12_Buildings.Examples.ScalableBenchmarks.BuildingVAV.ThermalZones.Validation.ThermalZone")
translateModel(Buildings.Examples.ScalableBenchmarks.BuildingVAV.ThermalZones.Validation.ThermalZone,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|thermalZone.weaBus.TDryBul|thermalZone.weaBus.HDirNor|thermalZone.roo.heaPorAir.T|thermalZone.roo.air.QCon_flow|thermalZone.roo.air.QLat_flow",fileNamePrefix="Buildings_12_Buildings.Examples.ScalableBenchmarks.BuildingVAV.ThermalZones.Validation.ThermalZone") [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.002078/0.002078, allocations: 67.97 kB / 1.155 GB, free: 25.23 MB / 0.9044 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.382/0.384, allocations: 176.1 MB / 1.327 GB, free: 1.059 MB / 1.014 GB
Notification: Performance of NFInst.instantiate(Buildings.Examples.ScalableBenchmarks.BuildingVAV.ThermalZones.Validation.ThermalZone): time 0.276/0.66, allocations: 210.8 MB / 1.533 GB, free: 13.42 MB / 1.232 GB
Notification: Performance of NFInst.instExpressions: time 0.07728/0.7373, allocations: 45.45 MB / 1.577 GB, free: 15.84 MB / 1.279 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01273/0.75, allocations: 492.1 kB / 1.578 GB, free: 15.35 MB / 1.279 GB
Notification: Performance of NFTyping.typeComponents: time 0.02015/0.7702, allocations: 7.401 MB / 1.585 GB, free: 7.914 MB / 1.279 GB
Notification: Performance of NFTyping.typeBindings: time 0.02677/0.797, allocations: 7.283 MB / 1.592 GB, free: 0.6016 MB / 1.279 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01865/0.8156, allocations: 6.543 MB / 1.599 GB, free: 10.07 MB / 1.295 GB
Notification: Performance of NFFlatten.flatten: time 1.012/1.827, allocations: 33.9 MB / 1.632 GB, free: 18.88 MB / 1.295 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.02906/1.856, allocations: 11.69 MB / 1.643 GB, free: 18.21 MB / 1.295 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.03762/1.894, allocations: 13.4 MB / 1.656 GB, free: 17.43 MB / 1.295 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.02489/1.919, allocations: 11.97 MB / 1.668 GB, free: 15.59 MB / 1.295 GB
Notification: Performance of NFPackage.collectConstants: time 0.01096/1.93, allocations: 2.504 MB / 1.67 GB, free: 15.59 MB / 1.295 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.02312/1.953, allocations: 6.721 MB / 1.677 GB, free: 15.59 MB / 1.295 GB
Notification: Performance of NFScalarize.scalarize: time 0.01696/1.97, allocations: 8.299 MB / 1.685 GB, free: 14.82 MB / 1.295 GB
Notification: Performance of NFVerifyModel.verify: time 0.02883/1.999, allocations: 8.297 MB / 1.693 GB, free: 12.76 MB / 1.295 GB
Notification: Performance of NFConvertDAE.convert: time 0.06748/2.066, allocations: 40.82 MB / 1.733 GB, free: 13.43 MB / 1.311 GB
Notification: Performance of FrontEnd - DAE generated: time 9.368e-06/2.066, allocations: 4 kB / 1.733 GB, free: 13.43 MB / 1.311 GB
Notification: Performance of FrontEnd: time 2.766e-06/2.066, allocations: 0.9062 kB / 1.733 GB, free: 13.43 MB / 1.311 GB
Notification: Performance of Transformations before backend: time 0.001509/2.068, allocations: 0 / 1.733 GB, free: 13.43 MB / 1.311 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1569
 * Number of variables: 1569
Notification: Performance of Generate backend data structure: time 0.09219/2.16, allocations: 24.2 MB / 1.757 GB, free: 13.79 MB / 1.326 GB
Notification: Performance of prepare preOptimizeDAE: time 5.798e-05/2.16, allocations: 7.656 kB / 1.757 GB, free: 13.79 MB / 1.326 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.03336/2.193, allocations: 5.213 MB / 1.762 GB, free: 11.38 MB / 1.326 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.07096/2.264, allocations: 28.92 MB / 1.79 GB, free: 2.977 MB / 1.342 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001685/2.266, allocations: 1.685 MB / 1.792 GB, free: 1.031 MB / 1.342 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.004829/2.271, allocations: 0.7682 MB / 1.792 GB, free: 272 kB / 1.342 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.03425/2.305, allocations: 11.39 MB / 1.803 GB, free: 4.469 MB / 1.357 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002755/2.305, allocations: 10.28 kB / 1.803 GB, free: 4.465 MB / 1.357 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.002773/2.308, allocations: 259.7 kB / 1.804 GB, free: 4.215 MB / 1.357 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003057/2.308, allocations: 223.5 kB / 1.804 GB, free: 3.996 MB / 1.357 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02935/2.338, allocations: 11.12 MB / 1.815 GB, free: 8.949 MB / 1.373 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.1318/2.469, allocations: 51.07 MB / 1.865 GB, free: 6.176 MB / 1.42 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0187/2.488, allocations: 7.826 MB / 1.872 GB, free: 14.39 MB / 1.436 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.01098/2.499, allocations: 4.522 MB / 1.877 GB, free: 9.867 MB / 1.436 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001501/2.501, allocations: 194.7 kB / 1.877 GB, free: 9.68 MB / 1.436 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.01473/2.515, allocations: 5.157 MB / 1.882 GB, free: 4.367 MB / 1.436 GB
Notification: Performance of pre-optimization done (n=428): time 1.332e-05/2.515, allocations: 4 kB / 1.882 GB, free: 4.363 MB / 1.436 GB
Notification: Performance of matching and sorting (n=428): time 0.05691/2.572, allocations: 17.08 MB / 1.899 GB, free: 3.254 MB / 1.451 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0003923/2.573, allocations: 404.2 kB / 1.899 GB, free: 2.797 MB / 1.451 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.08653/2.659, allocations: 36.79 MB / 1.935 GB, free: 14.09 MB / 1.498 GB
Notification: Performance of collectPreVariables (initialization): time 0.003173/2.662, allocations: 177.7 kB / 1.935 GB, free: 13.91 MB / 1.498 GB
Notification: Performance of collectInitialEqns (initialization): time 0.01694/2.679, allocations: 7.466 MB / 1.942 GB, free: 6.469 MB / 1.498 GB
Notification: Performance of collectInitialBindings (initialization): time 0.003621/2.683, allocations: 1.551 MB / 1.944 GB, free: 4.941 MB / 1.498 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.01403/2.697, allocations: 4.639 MB / 1.948 GB, free: 308 kB / 1.498 GB
Notification: Performance of setup shared object (initialization): time 0.0001686/2.697, allocations: 309.2 kB / 1.949 GB, free: 15.99 MB / 1.514 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.05863/2.756, allocations: 31.08 MB / 1.979 GB, free: 0.8828 MB / 1.529 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.05865/2.814, allocations: 34.63 MB / 2.013 GB, free: 13.25 MB / 1.576 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.06496/2.879, allocations: 39.48 MB / 2.051 GB, free: 5.184 MB / 1.607 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001478/2.879, allocations: 16 kB / 2.051 GB, free: 5.168 MB / 1.607 GB
Notification: Performance of matching and sorting (n=1765) (initialization): time 0.1326/3.012, allocations: 70.65 MB / 2.12 GB, free: 14.85 MB / 1.686 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002918/3.012, allocations: 165.7 kB / 2.121 GB, free: 14.68 MB / 1.686 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.09067/3.103, allocations: 50.97 MB / 2.17 GB, free: 11.69 MB / 1.732 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0108/3.114, allocations: 3.093 MB / 2.173 GB, free: 8.586 MB / 1.732 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01317/3.127, allocations: 2.101 MB / 2.175 GB, free: 6.594 MB / 1.732 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0135/3.141, allocations: 9.308 MB / 2.185 GB, free: 13.01 MB / 1.748 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.02121/3.162, allocations: 1.709 MB / 2.186 GB, free: 11.3 MB / 1.748 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.004461/3.166, allocations: 378.8 kB / 2.187 GB, free: 10.93 MB / 1.748 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.06553/3.232, allocations: 39.37 MB / 2.225 GB, free: 3.535 MB / 1.779 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.0503/3.282, allocations: 34.61 MB / 2.259 GB, free: 15.91 MB / 1.826 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 1.14/4.422, allocations: 39.43 MB / 2.297 GB, free: 0.7156 GB / 1.826 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0001255/4.422, allocations: 20.19 kB / 2.297 GB, free: 0.7156 GB / 1.826 GB
Notification: Performance of matching and sorting (n=1765) (initialization_lambda0): time 0.107/4.529, allocations: 71.14 MB / 2.367 GB, free: 0.6813 GB / 1.826 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002773/4.529, allocations: 163 kB / 2.367 GB, free: 0.6812 GB / 1.826 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.07055/4.6, allocations: 51.17 MB / 2.417 GB, free: 0.6352 GB / 1.826 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.008129/4.608, allocations: 2.996 MB / 2.42 GB, free: 0.633 GB / 1.826 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01235/4.62, allocations: 2.09 MB / 2.422 GB, free: 0.6314 GB / 1.826 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02802/4.648, allocations: 22.46 MB / 2.444 GB, free: 0.612 GB / 1.826 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0198/4.668, allocations: 1.516 MB / 2.445 GB, free: 0.6113 GB / 1.826 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003862/4.672, allocations: 378.6 kB / 2.446 GB, free: 0.611 GB / 1.826 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 255
 * Number of states: 0 ()
 * Number of discrete variables: 40 ($PRE.thermalZone.intLoad.nextTimeEvent,$whenCondition6,weaDat.conTim.canRepeatWeatherFile,weaDat.conTimMin.canRepeatWeatherFile,thermalZone.roo.solRadExc.is_flo[10],thermalZone.roo.solRadExc.is_flo[9],thermalZone.roo.solRadExc.is_flo[8],thermalZone.roo.solRadExc.is_flo[7],thermalZone.roo.solRadExc.is_flo[6],thermalZone.roo.solRadExc.is_flo[5],thermalZone.roo.solRadExc.is_flo[4],thermalZone.roo.solRadExc.is_flo[3],thermalZone.roo.solRadExc.is_flo[2],thermalZone.roo.solRadExc.is_flo[1],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,thermalZone.intLoad.nextTimeEventScaled,$PRE.thermalZone.intLoad.nextTimeEventScaled,thermalZone.intLoad.nextTimeEvent,thermalZone.roo.bouConExtWin.HDifTil[1].incAng.decAng.tNext,$PRE.thermalZone.roo.bouConExtWin.HDifTil[1].incAng.decAng.tNext,thermalZone.roo.bouConExtWin.HDirTil[1].incAng.decAng.tNext,$PRE.thermalZone.roo.bouConExtWin.HDirTil[1].incAng.decAng.tNext,$whenCondition1,$whenCondition2,$whenCondition3,$whenCondition4,$whenCondition5,$whenCondition7,$whenCondition8,thermalZone.intECoo.local_reset,thermalZone.intEHea.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (985):
 * Single equations (assignments): 941
 * Array equations: 39
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 4
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 2 systems
   {(1,1,100.0%), (1,1,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 2 systems
   {(13,56), (1,2)}
Notification: Performance of prepare postOptimizeDAE: time 0.008222/4.68, allocations: 0.7981 MB / 2.446 GB, free: 0.6102 GB / 1.826 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.01142/4.691, allocations: 1.556 MB / 2.448 GB, free: 0.6091 GB / 1.826 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0323/4.724, allocations: 11.85 MB / 2.46 GB, free: 0.5981 GB / 1.826 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0188/4.743, allocations: 9.636 MB / 2.469 GB, free: 0.5887 GB / 1.826 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.714e-05/4.743, allocations: 0 / 2.469 GB, free: 0.5887 GB / 1.826 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.01521/4.758, allocations: 4.078 MB / 2.473 GB, free: 0.5847 GB / 1.826 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.007846/4.766, allocations: 116.6 kB / 2.473 GB, free: 0.5846 GB / 1.826 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0124/4.778, allocations: 0.5726 MB / 2.474 GB, free: 0.5841 GB / 1.826 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001689/4.78, allocations: 282.8 kB / 2.474 GB, free: 0.5838 GB / 1.826 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.02658/4.806, allocations: 11.96 MB / 2.486 GB, free: 0.5722 GB / 1.826 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.03069/4.837, allocations: 16.17 MB / 2.501 GB, free: 0.5564 GB / 1.826 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.007245/4.844, allocations: 4.36 MB / 2.506 GB, free: 0.5521 GB / 1.826 GB
Notification: Performance of sorting global known variables: time 0.04726/4.891, allocations: 24.85 MB / 2.53 GB, free: 0.5279 GB / 1.826 GB
Notification: Performance of Backend: time 9.52e-07/4.891, allocations: 3.094 kB / 2.53 GB, free: 0.5279 GB / 1.826 GB
Notification: Performance of simCode: created initialization part: time 0.1055/4.997, allocations: 69.1 MB / 2.597 GB, free: 472.4 MB / 1.826 GB
Notification: Performance of SimCode: time 0.112/5.109, allocations: 48.3 MB / 2.645 GB, free: 424.3 MB / 1.826 GB
Notification: Performance of Templates: time 0.5534/5.662, allocations: 417.2 MB / 3.052 GB, free: 8.371 MB / 1.826 GB
"
[Timeout remaining time 293]
make -j1 -f Buildings_12_Buildings.Examples.ScalableBenchmarks.BuildingVAV.ThermalZones.Validation.ThermalZone.makefile [Timeout 300]
(rm -f Buildings_12_Buildings.Examples.ScalableBenchmarks.BuildingVAV.ThermalZones.Validation.ThermalZone.pipe ; mkfifo Buildings_12_Buildings.Examples.ScalableBenchmarks.BuildingVAV.ThermalZones.Validation.ThermalZone.pipe ; head -c 1048576 < Buildings_12_Buildings.Examples.ScalableBenchmarks.BuildingVAV.ThermalZones.Validation.ThermalZone.pipe >> ../files/Buildings_12_Buildings.Examples.ScalableBenchmarks.BuildingVAV.ThermalZones.Validation.ThermalZone.sim & ./Buildings_12_Buildings.Examples.ScalableBenchmarks.BuildingVAV.ThermalZones.Validation.ThermalZone  -abortSlowSimulation -alarm=400  -emit_protected -lv LOG_STATS > Buildings_12_Buildings.Examples.ScalableBenchmarks.BuildingVAV.ThermalZones.Validation.ThermalZone.pipe 2>&1) [Timeout 400]
[Calling os._exit(0), Time elapsed: 25.446243965001486]
Failed to read output from testmodel.py, exit status != 0:
6.671075528000074 6.687850229 4.617404248
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fd9fec22f80>