Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.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.001395/0.001395, allocations: 79.98 kB / 19.18 MB, free: 2.516 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.001503/0.001503, allocations: 166.2 kB / 22.46 MB, free: 5.379 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.442/1.442, allocations: 177.2 MB / 202.8 MB, free: 9.168 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 master/package.mo): time 0.002507/0.002507, allocations: 241.5 kB / 259.4 MB, free: 468 kB / 238.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo): time 1.095/1.095, allocations: 138.4 MB / 454.3 MB, free: 2.004 MB / 366.1 MB
"
[Timeout remaining time 179]
Using package BuildingSystems with version 2.0.0-beta (/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo)
Using package NcDataReader2 with version 2.5.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 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(BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor")
translateModel(BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.122e-06/1.122e-06, allocations: 0 / 0.6468 GB, free: 5.031 MB / 0.5606 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.175e-05/2.287e-05, allocations: 6.281 kB / 0.6468 GB, free: 5.023 MB / 0.5606 GB
Notification: Performance of NFInst.instantiate(BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor): time 0.569/0.5691, allocations: 265.7 MB / 0.9063 GB, free: 6.32 MB / 0.7325 GB
Notification: Performance of NFInst.instExpressions: time 0.02418/0.5933, allocations: 14.31 MB / 0.9203 GB, free: 7.977 MB / 0.7481 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002769/0.596, allocations: 95.31 kB / 0.9204 GB, free: 7.883 MB / 0.7481 GB
Notification: Performance of NFTyping.typeComponents: time 0.004515/0.6005, allocations: 1.666 MB / 0.922 GB, free: 6.207 MB / 0.7481 GB
Notification: Performance of NFTyping.typeBindings: time 0.01544/0.616, allocations: 6.322 MB / 0.9282 GB, free: 15.86 MB / 0.7637 GB
Notification: Performance of NFTyping.typeClassSections: time 0.007753/0.6237, allocations: 2.951 MB / 0.9311 GB, free: 12.91 MB / 0.7637 GB
Notification: Performance of NFFlatten.flatten: time 0.009784/0.6335, allocations: 7.339 MB / 0.9382 GB, free: 5.543 MB / 0.7637 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.003982/0.6375, allocations: 2.002 MB / 0.9402 GB, free: 3.488 MB / 0.7637 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.4365/1.074, allocations: 3.608 MB / 0.9437 GB, free: 21.41 MB / 0.7637 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.006492/1.08, allocations: 2.478 MB / 0.9461 GB, free: 21.41 MB / 0.7637 GB
Notification: Performance of NFPackage.collectConstants: time 0.001333/1.082, allocations: 340.5 kB / 0.9465 GB, free: 21.41 MB / 0.7637 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01222/1.094, allocations: 4.823 MB / 0.9512 GB, free: 21.41 MB / 0.7637 GB
Notification: Performance of NFScalarize.scalarize: time 0.002628/1.097, allocations: 1.623 MB / 0.9528 GB, free: 21.41 MB / 0.7637 GB
Notification: Performance of NFVerifyModel.verify: time 0.003463/1.1, allocations: 1.401 MB / 0.9541 GB, free: 21.41 MB / 0.7637 GB
Notification: Performance of NFConvertDAE.convert: time 0.01623/1.116, allocations: 6.979 MB / 0.9609 GB, free: 21.18 MB / 0.7637 GB
Notification: Performance of FrontEnd - DAE generated: time 8.647e-06/1.116, allocations: 0 / 0.9609 GB, free: 21.18 MB / 0.7637 GB
Notification: Performance of FrontEnd: time 1.803e-06/1.116, allocations: 0.625 kB / 0.9609 GB, free: 21.18 MB / 0.7637 GB
Notification: Performance of Transformations before backend: time 0.0001698/1.117, allocations: 0.9375 kB / 0.9609 GB, free: 21.18 MB / 0.7637 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 726
 * Number of variables: 726
Notification: Performance of Generate backend data structure: time 0.01593/1.132, allocations: 4.15 MB / 0.965 GB, free: 19.55 MB / 0.7637 GB
Notification: Performance of prepare preOptimizeDAE: time 4.513e-05/1.133, allocations: 12.09 kB / 0.965 GB, free: 19.55 MB / 0.7637 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.006229/1.139, allocations: 0.8665 MB / 0.9659 GB, free: 19.36 MB / 0.7637 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004613/1.143, allocations: 1.789 MB / 0.9676 GB, free: 18.89 MB / 0.7637 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001921/1.144, allocations: 212.2 kB / 0.9678 GB, free: 18.88 MB / 0.7637 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0005619/1.144, allocations: 282.5 kB / 0.9681 GB, free: 18.88 MB / 0.7637 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.007352/1.151, allocations: 3.931 MB / 0.9719 GB, free: 18.21 MB / 0.7637 GB
Notification: Performance of preOpt findStateOrder (simulation): time 7.391e-05/1.152, allocations: 7.375 kB / 0.9719 GB, free: 18.21 MB / 0.7637 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003157/1.152, allocations: 115 kB / 0.972 GB, free: 18.21 MB / 0.7637 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001882/1.152, allocations: 151.6 kB / 0.9722 GB, free: 18.21 MB / 0.7637 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.007112/1.159, allocations: 3.704 MB / 0.9758 GB, free: 18.18 MB / 0.7637 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.01692/1.176, allocations: 8.157 MB / 0.9838 GB, free: 16.07 MB / 0.7637 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.00505/1.181, allocations: 2.954 MB / 0.9866 GB, free: 16 MB / 0.7637 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002714/1.184, allocations: 1.627 MB / 0.9882 GB, free: 15.96 MB / 0.7637 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001035/1.185, allocations: 241.5 kB / 0.9885 GB, free: 15.95 MB / 0.7637 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.831e-05/1.185, allocations: 64.09 kB / 0.9885 GB, free: 15.91 MB / 0.7637 GB
Notification: Performance of pre-optimization done (n=285): time 5.53e-06/1.185, allocations: 0.7188 kB / 0.9885 GB, free: 15.91 MB / 0.7637 GB
Notification: Performance of matching and sorting (n=285): time 0.006473/1.191, allocations: 4.031 MB / 0.9925 GB, free: 15.66 MB / 0.7637 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.282e-05/1.191, allocations: 87.05 kB / 0.9925 GB, free: 15.59 MB / 0.7637 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001654/1.193, allocations: 1.202 MB / 0.9937 GB, free: 15.43 MB / 0.7637 GB
Notification: Performance of collectPreVariables (initialization): time 0.0002207/1.193, allocations: 80.86 kB / 0.9938 GB, free: 15.4 MB / 0.7637 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0005614/1.194, allocations: 1.037 MB / 0.9948 GB, free: 14.95 MB / 0.7637 GB
Notification: Performance of collectInitialBindings (initialization): time 0.000759/1.195, allocations: 1.002 MB / 0.9958 GB, free: 14.27 MB / 0.7637 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0006508/1.195, allocations: 410.1 kB / 0.9962 GB, free: 14.24 MB / 0.7637 GB
Notification: Performance of setup shared object (initialization): time 4.201e-05/1.195, allocations: 303.1 kB / 0.9965 GB, free: 13.95 MB / 0.7637 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001618/1.197, allocations: 1.141 MB / 0.9976 GB, free: 13.94 MB / 0.7637 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001778/1.199, allocations: 1.432 MB / 0.999 GB, free: 13.73 MB / 0.7637 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0037/1.202, allocations: 2.717 MB / 1.002 GB, free: 13.54 MB / 0.7637 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.546e-06/1.202, allocations: 0 / 1.002 GB, free: 13.54 MB / 0.7637 GB
Notification: Performance of matching and sorting (n=312) (initialization): time 0.004864/1.207, allocations: 3.054 MB / 1.005 GB, free: 13.31 MB / 0.7637 GB
Notification: Performance of prepare postOptimizeDAE: time 2.711e-05/1.207, allocations: 8.5 kB / 1.005 GB, free: 13.31 MB / 0.7637 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.381e-05/1.207, allocations: 2.844 kB / 1.005 GB, free: 13.31 MB / 0.7637 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.392e-05/1.207, allocations: 14.25 kB / 1.005 GB, free: 13.31 MB / 0.7637 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002229/1.21, allocations: 0.5117 MB / 1.005 GB, free: 13.31 MB / 0.7637 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.684e-05/1.21, allocations: 18.3 kB / 1.005 GB, free: 13.31 MB / 0.7637 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002333/1.212, allocations: 185.1 kB / 1.005 GB, free: 13.21 MB / 0.7637 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001889/1.212, allocations: 85.02 kB / 1.005 GB, free: 13.21 MB / 0.7637 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 14
 * 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 (312):
 * Single equations (assignments): 312
 * 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.001415/1.214, allocations: 0.6889 MB / 1.006 GB, free: 13.09 MB / 0.7637 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0004625/1.214, allocations: 227.1 kB / 1.006 GB, free: 12.98 MB / 0.7637 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.004961/1.219, allocations: 3.194 MB / 1.009 GB, free: 12.8 MB / 0.7637 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.725e-05/1.219, allocations: 26.47 kB / 1.009 GB, free: 12.8 MB / 0.7637 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 8.606e-06/1.219, allocations: 0 / 1.009 GB, free: 12.8 MB / 0.7637 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.00356/1.223, allocations: 2.652 MB / 1.012 GB, free: 12.64 MB / 0.7637 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01006/1.233, allocations: 5.774 MB / 1.018 GB, free: 12.07 MB / 0.7637 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 9.699e-06/1.233, allocations: 4.156 kB / 1.018 GB, free: 12.07 MB / 0.7637 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002487/1.235, allocations: 0.5423 MB / 1.018 GB, free: 12.07 MB / 0.7637 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.722e-05/1.235, allocations: 11.41 kB / 1.018 GB, free: 12.07 MB / 0.7637 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.000175/1.235, allocations: 68.41 kB / 1.018 GB, free: 12.07 MB / 0.7637 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 1.566e-05/1.235, allocations: 15.89 kB / 1.018 GB, free: 12.07 MB / 0.7637 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.306e-06/1.235, allocations: 3.875 kB / 1.018 GB, free: 12.07 MB / 0.7637 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.006586/1.242, allocations: 4.815 MB / 1.023 GB, free: 11.55 MB / 0.7637 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001674/1.244, allocations: 0.5344 MB / 1.024 GB, free: 11.39 MB / 0.7637 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003266/1.244, allocations: 55.44 kB / 1.024 GB, free: 11.39 MB / 0.7637 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001553/1.245, allocations: 60.78 kB / 1.024 GB, free: 11.39 MB / 0.7637 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.000357/1.246, allocations: 192 kB / 1.024 GB, free: 11.38 MB / 0.7637 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001745/1.246, allocations: 83.89 kB / 1.024 GB, free: 11.38 MB / 0.7637 GB
Notification: Performance of sorting global known variables: time 0.001021/1.247, allocations: 0.8221 MB / 1.025 GB, free: 11.27 MB / 0.7637 GB
Notification: Performance of sort global known variables: time 1e-07/1.247, allocations: 0.875 kB / 1.025 GB, free: 11.27 MB / 0.7637 GB
Notification: Performance of remove unused functions: time 0.00243/1.249, allocations: 1.024 MB / 1.026 GB, free: 11.13 MB / 0.7637 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 11
 * Number of states: 18 (volEas.dynBal.medium.Xi[1],volEas.dynBal.U,volEas.dynBal.m,volEas.dynBal.mC[1],oriOutBot.mExc,oriOutTop.mExc,oriWesTop.mExc,dooOpeClo.mExcAB,dooOpeClo.mExcBA,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 (285):
 * Single equations (assignments): 285
 * 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.0007391/1.25, allocations: 405 kB / 1.026 GB, free: 11.02 MB / 0.7637 GB
Notification: Performance of simCode: created initialization part: time 0.003903/1.254, allocations: 2.706 MB / 1.029 GB, free: 10.94 MB / 0.7637 GB
Notification: Performance of simCode: created event and clocks part: time 5.801e-06/1.254, allocations: 3.531 kB / 1.029 GB, free: 10.94 MB / 0.7637 GB
Notification: Performance of simCode: created simulation system equations: time 0.001799/1.256, allocations: 1.439 MB / 1.03 GB, free: 10.93 MB / 0.7637 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.005199/1.261, allocations: 1.18 MB / 1.031 GB, free: 10.8 MB / 0.7637 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.00834/1.269, allocations: 5.97 MB / 1.037 GB, free: 8.715 MB / 0.7637 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001205/1.271, allocations: 1.432 MB / 1.039 GB, free: 8.488 MB / 0.7637 GB
Notification: Performance of simCode: alias equations: time 0.002848/1.273, allocations: 0.8606 MB / 1.039 GB, free: 8.344 MB / 0.7637 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0007729/1.274, allocations: 201 kB / 1.04 GB, free: 8.336 MB / 0.7637 GB
Notification: Performance of SimCode: time 1.102e-06/1.274, allocations: 0 / 1.04 GB, free: 8.336 MB / 0.7637 GB
Notification: Performance of Templates: time 0.07768/1.352, allocations: 47.13 MB / 1.086 GB, free: 15.25 MB / 0.8106 GB
"
[Timeout remaining time 659]
make -j1 -f BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.makefile [Timeout 660]
(rm -f BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.pipe ; mkfifo BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.pipe ; head -c 1048576 < BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.pipe >> ../files/BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.sim & ./BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor  -abortSlowSimulation -alarm=480 -lv LOG_STATS > BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 8.850161418318748]
Failed to read output from testmodel.py, exit status != 0:
1.3606968931853771 1.374084873 0.25440951100000003
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fbb910e91e0>