Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_latest_IBPSA.Airflow.Multizone.Validation.ThreeRoomsContam.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.001407/0.001407, allocations: 84.22 kB / 20.94 MB, free: 3.602 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.001564/0.001564, allocations: 173 kB / 24.25 MB, free: 300 kB / 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.9798/0.9798, allocations: 177.1 MB / 204.6 MB, free: 5.578 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA master/package.mo): time 0.616/0.616, allocations: 114.9 MB / 376 MB, free: 7.609 MB / 346.7 MB
"
[Timeout remaining time 179]
Using package IBPSA with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA master/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(IBPSA.Airflow.Multizone.Validation.ThreeRoomsContam,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Airflow.Multizone.Validation.ThreeRoomsContam")
translateModel(IBPSA.Airflow.Multizone.Validation.ThreeRoomsContam,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Airflow.Multizone.Validation.ThreeRoomsContam") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.623e-06/1.623e-06, allocations: 0 / 481.5 MB, free: 11.9 MB / 410.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.66e-05/1.822e-05, allocations: 3.078 kB / 481.5 MB, free: 11.89 MB / 410.7 MB
Notification: Performance of NFInst.instantiate(IBPSA.Airflow.Multizone.Validation.ThreeRoomsContam): time 0.4233/0.4234, allocations: 284.7 MB / 0.7482 GB, free: 11 MB / 0.5573 GB
Notification: Performance of NFInst.instExpressions: time 0.01793/0.4413, allocations: 17.9 MB / 0.7657 GB, free: 3.031 MB / 0.5573 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002741/0.444, allocations: 93.66 kB / 0.7658 GB, free: 3.031 MB / 0.5573 GB
Notification: Performance of NFTyping.typeComponents: time 0.00341/0.4474, allocations: 1.637 MB / 0.7674 GB, free: 2.082 MB / 0.5573 GB
Notification: Performance of NFTyping.typeBindings: time 0.01218/0.4596, allocations: 6.376 MB / 0.7736 GB, free: 13.62 MB / 0.573 GB
Notification: Performance of NFTyping.typeClassSections: time 0.006223/0.4658, allocations: 2.927 MB / 0.7765 GB, free: 11.54 MB / 0.573 GB
Notification: Performance of NFFlatten.flatten: time 0.007226/0.4731, allocations: 6.839 MB / 0.7831 GB, free: 7.566 MB / 0.573 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002786/0.4759, allocations: 1.902 MB / 0.785 GB, free: 6.215 MB / 0.573 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.007244/0.4831, allocations: 3.694 MB / 0.7886 GB, free: 4.043 MB / 0.573 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.004106/0.4872, allocations: 2.304 MB / 0.7909 GB, free: 2.824 MB / 0.573 GB
Notification: Performance of NFPackage.collectConstants: time 0.0007432/0.4879, allocations: 304.3 kB / 0.7911 GB, free: 2.824 MB / 0.573 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.009382/0.4973, allocations: 4.987 MB / 0.796 GB, free: 156 kB / 0.573 GB
Notification: Performance of NFScalarize.scalarize: time 0.001842/0.4992, allocations: 1.434 MB / 0.7974 GB, free: 14.75 MB / 0.5886 GB
Notification: Performance of NFVerifyModel.verify: time 0.00256/0.5017, allocations: 1.263 MB / 0.7986 GB, free: 13.49 MB / 0.5886 GB
Notification: Performance of NFConvertDAE.convert: time 0.009801/0.5115, allocations: 5.178 MB / 0.8037 GB, free: 8.391 MB / 0.5886 GB
Notification: Performance of FrontEnd - DAE generated: time 5.811e-06/0.5115, allocations: 0 / 0.8037 GB, free: 8.391 MB / 0.5886 GB
Notification: Performance of FrontEnd: time 1.593e-06/0.5115, allocations: 0 / 0.8037 GB, free: 8.391 MB / 0.5886 GB
Notification: Performance of Transformations before backend: time 0.0001067/0.5116, allocations: 0 / 0.8037 GB, free: 8.391 MB / 0.5886 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 627
 * Number of variables: 627
Notification: Performance of Generate backend data structure: time 0.006128/0.5178, allocations: 3.838 MB / 0.8075 GB, free: 4.508 MB / 0.5886 GB
Notification: Performance of prepare preOptimizeDAE: time 4.501e-05/0.5178, allocations: 8.031 kB / 0.8075 GB, free: 4.5 MB / 0.5886 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.005371/0.5232, allocations: 1.576 MB / 0.809 GB, free: 2.922 MB / 0.5886 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002274/0.5255, allocations: 1.641 MB / 0.8106 GB, free: 1.289 MB / 0.5886 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000106/0.5256, allocations: 187.3 kB / 0.8108 GB, free: 1.105 MB / 0.5886 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002818/0.5259, allocations: 247.9 kB / 0.811 GB, free: 0.8633 MB / 0.5886 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.00358/0.5294, allocations: 2.441 MB / 0.8134 GB, free: 14.22 MB / 0.6042 GB
Notification: Performance of preOpt findStateOrder (simulation): time 5.665e-05/0.5295, allocations: 6.625 kB / 0.8134 GB, free: 14.22 MB / 0.6042 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000192/0.5297, allocations: 95.98 kB / 0.8135 GB, free: 14.12 MB / 0.6042 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001259/0.5298, allocations: 136.7 kB / 0.8136 GB, free: 13.99 MB / 0.6042 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002441/0.5322, allocations: 1.311 MB / 0.8149 GB, free: 12.67 MB / 0.6042 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.008517/0.5408, allocations: 7.655 MB / 0.8224 GB, free: 5.035 MB / 0.6042 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.002051/0.5428, allocations: 1.347 MB / 0.8237 GB, free: 3.699 MB / 0.6042 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0009944/0.5438, allocations: 0.6971 MB / 0.8244 GB, free: 3.004 MB / 0.6042 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001795/0.544, allocations: 61.97 kB / 0.8244 GB, free: 2.945 MB / 0.6042 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.974e-05/0.544, allocations: 63.39 kB / 0.8245 GB, free: 2.871 MB / 0.6042 GB
Notification: Performance of pre-optimization done (n=197): time 3.837e-06/0.544, allocations: 4 kB / 0.8245 GB, free: 2.867 MB / 0.6042 GB
Notification: Performance of matching and sorting (n=197): time 0.003131/0.5472, allocations: 1.758 MB / 0.8262 GB, free: 1.109 MB / 0.6042 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.254e-05/0.5472, allocations: 73.41 kB / 0.8263 GB, free: 1.027 MB / 0.6042 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0009185/0.5481, allocations: 1.039 MB / 0.8273 GB, free: 15.95 MB / 0.6198 GB
Notification: Performance of collectPreVariables (initialization): time 0.000111/0.5482, allocations: 61.7 kB / 0.8274 GB, free: 15.88 MB / 0.6198 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0004905/0.5487, allocations: 1.046 MB / 0.8284 GB, free: 14.83 MB / 0.6198 GB
Notification: Performance of collectInitialBindings (initialization): time 0.000447/0.5492, allocations: 0.7202 MB / 0.8291 GB, free: 14.11 MB / 0.6198 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0003699/0.5495, allocations: 309.2 kB / 0.8294 GB, free: 13.79 MB / 0.6198 GB
Notification: Performance of setup shared object (initialization): time 9.508e-05/0.5496, allocations: 480.7 kB / 0.8298 GB, free: 13.32 MB / 0.6198 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0006513/0.5503, allocations: 374.4 kB / 0.8302 GB, free: 12.96 MB / 0.6198 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0008011/0.5511, allocations: 0.6458 MB / 0.8308 GB, free: 12.25 MB / 0.6198 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001469/0.5526, allocations: 1.03 MB / 0.8318 GB, free: 11.19 MB / 0.6198 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.232e-06/0.5526, allocations: 4 kB / 0.8318 GB, free: 11.18 MB / 0.6198 GB
Notification: Performance of matching and sorting (n=219) (initialization): time 0.002292/0.5549, allocations: 1.401 MB / 0.8332 GB, free: 9.793 MB / 0.6198 GB
Notification: Performance of prepare postOptimizeDAE: time 2.264e-05/0.5549, allocations: 11.94 kB / 0.8332 GB, free: 9.781 MB / 0.6198 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 8.856e-06/0.5549, allocations: 4.344 kB / 0.8332 GB, free: 9.777 MB / 0.6198 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.065e-05/0.5549, allocations: 12 kB / 0.8332 GB, free: 9.766 MB / 0.6198 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001164/0.5561, allocations: 412.1 kB / 0.8336 GB, free: 9.383 MB / 0.6198 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.548e-05/0.5561, allocations: 16.28 kB / 0.8336 GB, free: 9.375 MB / 0.6198 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0009494/0.557, allocations: 167.1 kB / 0.8338 GB, free: 9.211 MB / 0.6198 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001046/0.5571, allocations: 63.98 kB / 0.8339 GB, free: 9.148 MB / 0.6198 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 12
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (219):
 * Single equations (assignments): 219
 * Array equations: 0
 * Algorithm blocks: 0
 * 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.000475/0.5576, allocations: 244.9 kB / 0.8341 GB, free: 8.918 MB / 0.6198 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003175/0.5579, allocations: 254.8 kB / 0.8343 GB, free: 8.668 MB / 0.6198 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002044/0.56, allocations: 1.499 MB / 0.8358 GB, free: 7.156 MB / 0.6198 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.196e-05/0.56, allocations: 20 kB / 0.8358 GB, free: 7.137 MB / 0.6198 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 5.059e-06/0.56, allocations: 0 / 0.8358 GB, free: 7.137 MB / 0.6198 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001544/0.5615, allocations: 1.123 MB / 0.8369 GB, free: 6.008 MB / 0.6198 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.004849/0.5664, allocations: 3.566 MB / 0.8404 GB, free: 2.352 MB / 0.6198 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.941e-06/0.5664, allocations: 4 kB / 0.8404 GB, free: 2.348 MB / 0.6198 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001309/0.5677, allocations: 464 kB / 0.8408 GB, free: 1.914 MB / 0.6198 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.161e-05/0.5677, allocations: 12.03 kB / 0.8409 GB, free: 1.902 MB / 0.6198 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.000103/0.5678, allocations: 55.97 kB / 0.8409 GB, free: 1.848 MB / 0.6198 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 8.776e-06/0.5678, allocations: 8 kB / 0.8409 GB, free: 1.84 MB / 0.6198 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.779e-06/0.5678, allocations: 10.47 kB / 0.8409 GB, free: 1.832 MB / 0.6198 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002874/0.5707, allocations: 2.193 MB / 0.8431 GB, free: 15.57 MB / 0.6355 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0006686/0.5714, allocations: 438.1 kB / 0.8435 GB, free: 15.14 MB / 0.6355 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001988/0.5716, allocations: 43.98 kB / 0.8435 GB, free: 15.1 MB / 0.6355 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0008388/0.5724, allocations: 83.88 kB / 0.8436 GB, free: 15.02 MB / 0.6355 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002224/0.5726, allocations: 144.1 kB / 0.8437 GB, free: 14.88 MB / 0.6355 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 9.604e-05/0.5727, allocations: 75.95 kB / 0.8438 GB, free: 14.8 MB / 0.6355 GB
Notification: Performance of sorting global known variables: time 0.0006056/0.5733, allocations: 0.6458 MB / 0.8445 GB, free: 14.16 MB / 0.6355 GB
Notification: Performance of sort global known variables: time 1.51e-07/0.5733, allocations: 0 / 0.8445 GB, free: 14.16 MB / 0.6355 GB
Notification: Performance of remove unused functions: time 0.001466/0.5748, allocations: 0.8927 MB / 0.8453 GB, free: 13.26 MB / 0.6355 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 11
 * Number of states: 16 (volEas.dynBal.medium.Xi[1],volEas.dynBal.U,volEas.dynBal.m,volEas.dynBal.mC[1],oriOutBot.mExc,oriOutTop.mExc,oriWesTop.mExc,volWes.dynBal.medium.Xi[1],volWes.dynBal.U,volWes.dynBal.m,volWes.dynBal.mC[1],oriEasTop.mExc,volTop.dynBal.medium.Xi[1],volTop.dynBal.U,volTop.dynBal.m,volTop.dynBal.mC[1])
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (201):
 * Single equations (assignments): 201
 * Array equations: 0
 * Algorithm blocks: 0
 * 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 Backend phase and start with SimCode phase: time 0.0004787/0.5753, allocations: 335.5 kB / 0.8456 GB, free: 12.95 MB / 0.6355 GB
Notification: Performance of simCode: created initialization part: time 0.001754/0.577, allocations: 1.176 MB / 0.8468 GB, free: 11.8 MB / 0.6355 GB
Notification: Performance of simCode: created event and clocks part: time 3.176e-06/0.577, allocations: 0 / 0.8468 GB, free: 11.8 MB / 0.6355 GB
Notification: Performance of simCode: created simulation system equations: time 0.001039/0.5781, allocations: 0.7057 MB / 0.8475 GB, free: 11.14 MB / 0.6355 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002616/0.5807, allocations: 1.137 MB / 0.8486 GB, free: 10.05 MB / 0.6355 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.00567/0.5864, allocations: 4.64 MB / 0.8531 GB, free: 5.336 MB / 0.6355 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0004052/0.5868, allocations: 0.5688 MB / 0.8537 GB, free: 4.734 MB / 0.6355 GB
Notification: Performance of simCode: alias equations: time 0.001377/0.5881, allocations: 0.503 MB / 0.8542 GB, free: 4.227 MB / 0.6355 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0007561/0.5889, allocations: 176.4 kB / 0.8543 GB, free: 4.051 MB / 0.6355 GB
Notification: Performance of SimCode: time 1.453e-06/0.5889, allocations: 0 / 0.8543 GB, free: 4.051 MB / 0.6355 GB
Notification: Performance of Templates: time 0.04893/0.6378, allocations: 45.16 MB / 0.8984 GB, free: 7.91 MB / 0.6823 GB
"
[Timeout remaining time 659]
make -j1 -f IBPSA_latest_IBPSA.Airflow.Multizone.Validation.ThreeRoomsContam.makefile [Timeout 660]
(rm -f IBPSA_latest_IBPSA.Airflow.Multizone.Validation.ThreeRoomsContam.pipe ; mkfifo IBPSA_latest_IBPSA.Airflow.Multizone.Validation.ThreeRoomsContam.pipe ; head -c 1048576 < IBPSA_latest_IBPSA.Airflow.Multizone.Validation.ThreeRoomsContam.pipe >> ../files/IBPSA_latest_IBPSA.Airflow.Multizone.Validation.ThreeRoomsContam.sim & ./IBPSA_latest_IBPSA.Airflow.Multizone.Validation.ThreeRoomsContam -abortSlowSimulation -alarm=240  -lv LOG_STATS > IBPSA_latest_IBPSA.Airflow.Multizone.Validation.ThreeRoomsContam.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 5.132649101084098]