Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_10_Buildings.Examples.VAVCO2.VAVSystemCTControl.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.1-maint.10.0.x/package.mo", uses=false)
Using package Buildings with version 10.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.1-maint.10.0.x/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(Buildings.Examples.VAVCO2.VAVSystemCTControl,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|roo.roo45.vavACH.y|roo.roo46.vavACH.y|roo.roo47.vavACH.y|roo.roo48.vavACH.y|roo.roo49.vavACH.y|roo.roo50.vavACH.y|fan32.m_flow|fan56.m_flow|fan32.dp|fan56.dp|roo.roo45.volFraCO2.V|roo.roo46.volFraCO2.V|roo.roo47.volFraCO2.V|roo.roo48.volFraCO2.V|roo.roo49.volFraCO2.V|roo.roo50.volFraCO2.V|roo.roo45.con.CO2Set|roo.roo45.vol.C.1.|roo.roo46.vol.C.1.|roo.roo47.vol.C.1.|roo.roo48.vol.C.1.|roo.roo49.vol.C.1.|roo.roo50.vol.C.1.|roo.roo45.ple.C.1.|roo.roo46.ple.C.1.|roo.roo47.ple.C.1.|roo.roo48.ple.C.1.|roo.roo49.ple.C.1.|roo.roo50.ple.C.1.",fileNamePrefix="Buildings_10_Buildings.Examples.VAVCO2.VAVSystemCTControl")
translateModel(Buildings.Examples.VAVCO2.VAVSystemCTControl,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|roo.roo45.vavACH.y|roo.roo46.vavACH.y|roo.roo47.vavACH.y|roo.roo48.vavACH.y|roo.roo49.vavACH.y|roo.roo50.vavACH.y|fan32.m_flow|fan56.m_flow|fan32.dp|fan56.dp|roo.roo45.volFraCO2.V|roo.roo46.volFraCO2.V|roo.roo47.volFraCO2.V|roo.roo48.volFraCO2.V|roo.roo49.volFraCO2.V|roo.roo50.volFraCO2.V|roo.roo45.con.CO2Set|roo.roo45.vol.C.1.|roo.roo46.vol.C.1.|roo.roo47.vol.C.1.|roo.roo48.vol.C.1.|roo.roo49.vol.C.1.|roo.roo50.vol.C.1.|roo.roo45.ple.C.1.|roo.roo46.ple.C.1.|roo.roo47.ple.C.1.|roo.roo48.ple.C.1.|roo.roo49.ple.C.1.|roo.roo50.ple.C.1.",fileNamePrefix="Buildings_10_Buildings.Examples.VAVCO2.VAVSystemCTControl")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001205/0.001205, allocations: 117 kB / 16.42 MB, free: 6.512 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001287/0.001287, allocations: 186.5 kB / 17.35 MB, free: 5.754 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.418/1.418, allocations: 222.9 MB / 241 MB, free: 15.17 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.1-maint.10.0.x/package.mo): time 2.588/2.588, allocations: 437 MB / 0.7112 GB, free: 4.391 MB / 0.5762 GB
Notification: Performance of FrontEnd - loaded program: time 0.001656/0.001656, allocations: 60.12 kB / 1.019 GB, free: 9.438 MB / 0.795 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.042/1.043, allocations: 151.6 MB / 1.167 GB, free: 3.863 MB / 0.8887 GB
Notification: Performance of NFInst.instantiate(Buildings.Examples.VAVCO2.VAVSystemCTControl): time 3.717/4.761, allocations: 1.243 GB / 2.41 GB, free: 54.21 MB / 1.607 GB
Notification: Performance of NFInst.instExpressions: time 0.2084/4.969, allocations: 123.8 MB / 2.531 GB, free: 29.8 MB / 1.607 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.04688/5.016, allocations: 0.7618 MB / 2.532 GB, free: 29.8 MB / 1.607 GB
Notification: Performance of NFTyping.typeComponents: time 0.03565/5.052, allocations: 11.54 MB / 2.543 GB, free: 24.51 MB / 1.607 GB
Notification: Performance of NFTyping.typeBindings: time 0.0883/5.14, allocations: 27.41 MB / 2.57 GB, free: 2.898 MB / 1.607 GB
Notification: Performance of NFTyping.typeClassSections: time 0.05009/5.19, allocations: 16.44 MB / 2.586 GB, free: 4.094 MB / 1.623 GB
Notification: Performance of NFFlatten.flatten: time 0.1017/5.292, allocations: 58.2 MB / 2.643 GB, free: 11.79 MB / 1.686 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.05403/5.346, allocations: 22.54 MB / 2.665 GB, free: 5.551 MB / 1.701 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.08022/5.426, allocations: 25.62 MB / 2.69 GB, free: 13.66 MB / 1.732 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.03928/5.466, allocations: 16.44 MB / 2.706 GB, free: 14.38 MB / 1.748 GB
Notification: Performance of NFPackage.collectConstants: time 0.01511/5.481, allocations: 2.98 MB / 2.709 GB, free: 11.4 MB / 1.748 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0558/5.537, allocations: 17.94 MB / 2.726 GB, free: 11.49 MB / 1.764 GB
Notification: Performance of NFScalarize.scalarize: time 0.02205/5.559, allocations: 9.173 MB / 2.735 GB, free: 2.449 MB / 1.764 GB
Notification: Performance of NFVerifyModel.verify: time 0.04288/5.602, allocations: 13.15 MB / 2.748 GB, free: 5.395 MB / 1.779 GB
Notification: Performance of NFConvertDAE.convert: time 0.09859/5.7, allocations: 43.58 MB / 2.791 GB, free: 11.1 MB / 1.826 GB
Notification: Performance of FrontEnd - DAE generated: time 9.227e-06/5.7, allocations: 0 / 2.791 GB, free: 11.1 MB / 1.826 GB
Notification: Performance of FrontEnd: time 2.845e-06/5.7, allocations: 0 / 2.791 GB, free: 11.1 MB / 1.826 GB
Notification: Performance of Transformations before backend: time 0.001583/5.702, allocations: 7.938 kB / 2.791 GB, free: 11.09 MB / 1.826 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 3094
 * Number of variables: 3094
Notification: Performance of Generate backend data structure: time 0.1041/5.806, allocations: 24 MB / 2.814 GB, free: 3.059 MB / 1.842 GB
Notification: Performance of prepare preOptimizeDAE: time 5.933e-05/5.806, allocations: 8.922 kB / 2.814 GB, free: 3.051 MB / 1.842 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.03167/5.838, allocations: 4.488 MB / 2.818 GB, free: 14.57 MB / 1.857 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0586/5.897, allocations: 18.73 MB / 2.837 GB, free: 12.1 MB / 1.873 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001831/5.899, allocations: 0.9585 MB / 2.838 GB, free: 11.14 MB / 1.873 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.009188/5.908, allocations: 1.279 MB / 2.839 GB, free: 9.867 MB / 1.873 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.06518/5.973, allocations: 17.52 MB / 2.856 GB, free: 8.125 MB / 1.889 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0009155/5.974, allocations: 15.88 kB / 2.856 GB, free: 8.109 MB / 1.889 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.007267/5.981, allocations: 0.4884 MB / 2.856 GB, free: 7.625 MB / 1.889 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0006452/5.982, allocations: 483.7 kB / 2.857 GB, free: 7.152 MB / 1.889 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.05065/6.033, allocations: 16.24 MB / 2.873 GB, free: 6.898 MB / 1.904 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 1.952/7.984, allocations: 84.9 MB / 2.956 GB, free: 0.8964 GB / 1.92 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.02262/8.007, allocations: 10.59 MB / 2.966 GB, free: 0.8911 GB / 1.92 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0144/8.021, allocations: 6.995 MB / 2.973 GB, free: 0.8875 GB / 1.92 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001444/8.023, allocations: 333.5 kB / 2.973 GB, free: 0.8874 GB / 1.92 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.02067/8.043, allocations: 7.217 MB / 2.98 GB, free: 0.8832 GB / 1.92 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.01817/8.062, allocations: 1.874 MB / 2.982 GB, free: 0.8827 GB / 1.92 GB
Notification: Performance of pre-optimization done (n=880): time 7.687e-05/8.062, allocations: 0 / 2.982 GB, free: 0.8827 GB / 1.92 GB
Notification: Performance of matching and sorting (n=880): time 0.1326/8.194, allocations: 31.26 MB / 3.013 GB, free: 0.8666 GB / 1.92 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001686/8.195, allocations: 170.5 kB / 3.013 GB, free: 0.8665 GB / 1.92 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.04298/8.238, allocations: 14.8 MB / 3.027 GB, free: 0.8524 GB / 1.92 GB
Notification: Performance of collectPreVariables (initialization): time 0.004094/8.242, allocations: 233.7 kB / 3.027 GB, free: 0.8522 GB / 1.92 GB
Notification: Performance of collectInitialEqns (initialization): time 0.01322/8.255, allocations: 8.106 MB / 3.035 GB, free: 0.8448 GB / 1.92 GB
Notification: Performance of collectInitialBindings (initialization): time 0.008987/8.264, allocations: 3.198 MB / 3.038 GB, free: 0.8422 GB / 1.92 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.01571/8.28, allocations: 2.17 MB / 3.041 GB, free: 0.84 GB / 1.92 GB
Notification: Performance of setup shared object (initialization): time 8.511e-05/8.28, allocations: 308 kB / 3.041 GB, free: 0.8397 GB / 1.92 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0139/8.294, allocations: 6.123 MB / 3.047 GB, free: 0.8338 GB / 1.92 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01618/8.31, allocations: 8.949 MB / 3.056 GB, free: 0.824 GB / 1.92 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.03073/8.341, allocations: 14.44 MB / 3.07 GB, free: 0.8095 GB / 1.92 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.000136/8.341, allocations: 15.94 kB / 3.07 GB, free: 0.8094 GB / 1.92 GB
Notification: Performance of matching and sorting (n=1266) (initialization): time 0.07474/8.416, allocations: 21.16 MB / 3.09 GB, free: 0.7888 GB / 1.92 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001031/8.416, allocations: 20 kB / 3.09 GB, free: 0.7888 GB / 1.92 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001062/8.416, allocations: 36 kB / 3.09 GB, free: 0.7888 GB / 1.92 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02528/8.441, allocations: 5.429 MB / 3.096 GB, free: 0.7836 GB / 1.92 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01243/8.454, allocations: 2.045 MB / 3.098 GB, free: 0.7816 GB / 1.92 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0455/8.499, allocations: 33.77 MB / 3.131 GB, free: 0.7476 GB / 1.92 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.02199/8.522, allocations: 0.666 MB / 3.131 GB, free: 0.7469 GB / 1.92 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003704/8.525, allocations: 0.5112 MB / 3.132 GB, free: 0.7464 GB / 1.92 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.02584/8.551, allocations: 9.886 MB / 3.142 GB, free: 0.7367 GB / 1.92 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.01613/8.567, allocations: 8.644 MB / 3.15 GB, free: 0.7272 GB / 1.92 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.02915/8.597, allocations: 13.84 MB / 3.163 GB, free: 0.7132 GB / 1.92 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0001067/8.597, allocations: 8 kB / 3.164 GB, free: 0.7132 GB / 1.92 GB
Notification: Performance of matching and sorting (n=1266) (initialization_lambda0): time 0.06848/8.665, allocations: 20.07 MB / 3.183 GB, free: 0.6934 GB / 1.92 GB
Notification: Performance of prepare postOptimizeDAE: time 8.986e-05/8.665, allocations: 24 kB / 3.183 GB, free: 0.6934 GB / 1.92 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.093e-05/8.665, allocations: 36 kB / 3.183 GB, free: 0.6934 GB / 1.92 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02274/8.688, allocations: 4.826 MB / 3.188 GB, free: 0.6886 GB / 1.92 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01124/8.7, allocations: 1.939 MB / 3.19 GB, free: 0.6868 GB / 1.92 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.05348/8.753, allocations: 38.34 MB / 3.227 GB, free: 0.6481 GB / 1.92 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.02538/8.779, allocations: 0.8105 MB / 3.228 GB, free: 0.6473 GB / 1.92 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.004174/8.783, allocations: 487.6 kB / 3.228 GB, free: 0.6468 GB / 1.92 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 168
 * Number of states: 0 ()
 * Number of discrete variables: 34 ($PRE.roo.occ.office1.nextTimeEvent,$whenCondition7,$PRE.roo.occ.office2.nextTimeEvent,$whenCondition6,$PRE.roo.occ.cla1.nextTimeEvent,$whenCondition5,$PRE.roo.occ.cla2.nextTimeEvent,$whenCondition4,$PRE.roo.occ.smaRoo1.nextTimeEvent,$whenCondition3,$PRE.roo.occ.smaRoo2.nextTimeEvent,$whenCondition2,$whenCondition1,onSig.pulseStart,$PRE.onSig.pulseStart,onSig.y,roo.occ.smaRoo2.nextTimeEventScaled,$PRE.roo.occ.smaRoo2.nextTimeEventScaled,roo.occ.smaRoo2.nextTimeEvent,roo.occ.smaRoo1.nextTimeEventScaled,$PRE.roo.occ.smaRoo1.nextTimeEventScaled,roo.occ.smaRoo1.nextTimeEvent,roo.occ.cla2.nextTimeEventScaled,$PRE.roo.occ.cla2.nextTimeEventScaled,roo.occ.cla2.nextTimeEvent,roo.occ.cla1.nextTimeEventScaled,$PRE.roo.occ.cla1.nextTimeEventScaled,roo.occ.cla1.nextTimeEvent,roo.occ.office2.nextTimeEventScaled,$PRE.roo.occ.office2.nextTimeEventScaled,roo.occ.office2.nextTimeEvent,roo.occ.office1.nextTimeEventScaled,$PRE.roo.occ.office1.nextTimeEventScaled,roo.occ.office1.nextTimeEvent)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (1024):
 * Single equations (assignments): 1003
 * Array equations: 2
 * Algorithm blocks: 0
 * Record equations: 2
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 8
 * Torn equation systems: 9
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 0 systems
 * Non-linear Jacobian (size): 8 systems
   {1, 1, 1, 1, 1, 1, 1, 1}
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 6 systems
   {(4,9,87.5%), (4,9,87.5%), (4,9,81.2%), (4,9,81.2%), (4,9,100.0%), (4,9,87.5%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 3 systems
   {(2,10), (15,43), (9,26)}
Notification: Performance of prepare postOptimizeDAE: time 0.006389/8.789, allocations: 0.7472 MB / 3.229 GB, free: 0.6461 GB / 1.92 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.007963/8.797, allocations: 1.587 MB / 3.231 GB, free: 0.6445 GB / 1.92 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.04891/8.846, allocations: 16.36 MB / 3.247 GB, free: 0.6285 GB / 1.92 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.03737/8.884, allocations: 13.47 MB / 3.26 GB, free: 0.6153 GB / 1.92 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0001324/8.884, allocations: 4 kB / 3.26 GB, free: 0.6153 GB / 1.92 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.01504/8.899, allocations: 3.259 MB / 3.263 GB, free: 0.6121 GB / 1.92 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.008534/8.907, allocations: 208 kB / 3.263 GB, free: 0.6119 GB / 1.92 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.01823/8.926, allocations: 463.5 kB / 3.264 GB, free: 0.6115 GB / 1.92 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.00382/8.93, allocations: 0.6857 MB / 3.264 GB, free: 0.6108 GB / 1.92 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.04251/8.972, allocations: 15.77 MB / 3.28 GB, free: 0.5955 GB / 1.92 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.04802/9.02, allocations: 20.5 MB / 3.3 GB, free: 0.5755 GB / 1.92 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.01316/9.033, allocations: 5.997 MB / 3.306 GB, free: 0.5696 GB / 1.92 GB
Notification: Performance of sorting global known variables: time 0.02818/9.062, allocations: 10.16 MB / 3.316 GB, free: 0.5597 GB / 1.92 GB
Notification: Performance of Backend: time 1.172e-06/9.062, allocations: 0 / 3.316 GB, free: 0.5597 GB / 1.92 GB
Notification: Performance of simCode: created initialization part: time 0.08695/9.149, allocations: 25.09 MB / 3.34 GB, free: 0.5358 GB / 1.92 GB
Notification: Performance of SimCode: time 0.1147/9.264, allocations: 40.35 MB / 3.379 GB, free: 0.4965 GB / 1.92 GB
Notification: Performance of Templates: time 0.5869/9.851, allocations: 401.5 MB / 3.772 GB, free: 109.3 MB / 1.92 GB
make -j1 -f Buildings_10_Buildings.Examples.VAVCO2.VAVSystemCTControl.makefile
(rm -f Buildings_10_Buildings.Examples.VAVCO2.VAVSystemCTControl.pipe ; mkfifo Buildings_10_Buildings.Examples.VAVCO2.VAVSystemCTControl.pipe ; head -c 1048576 < Buildings_10_Buildings.Examples.VAVCO2.VAVSystemCTControl.pipe >> ../files/Buildings_10_Buildings.Examples.VAVCO2.VAVSystemCTControl.sim & ./Buildings_10_Buildings.Examples.VAVCO2.VAVSystemCTControl  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Buildings_10_Buildings.Examples.VAVCO2.VAVSystemCTControl.pipe 2>&1)