Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr Buildings_maint.7.0.x_Buildings.Examples.VAVReheat.Controls.Examples.OperationModes.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 7.0.3-maint.7.0.x/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo", uses=false)
Using package ModelicaServices with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo)
Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo)
Using package Complex with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo)
Using package Buildings with version 7.0.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 7.0.3-maint.7.0.x/package.mo)
Running command: translateModel(Buildings.Examples.VAVReheat.Controls.Examples.OperationModes,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Buildings_maint.7.0.x_Buildings.Examples.VAVReheat.Controls.Examples.OperationModes")
translateModel(Buildings.Examples.VAVReheat.Controls.Examples.OperationModes,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Buildings_maint.7.0.x_Buildings.Examples.VAVReheat.Controls.Examples.OperationModes")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 7.0.3-maint.7.0.x/package.mo): time 1.318/1.318, allocations: 251.5 MB / 267.4 MB, free: 11.07 MB / 218.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.001103/0.001103, allocations: 161.5 kB / 326.5 MB, free: 2.715 MB / 250.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.745/1.745, allocations: 205 MB / 0.5767 GB, free: 21.02 MB / 426.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo): time 0.000823/0.0008229, allocations: 89.23 kB / 0.6804 GB, free: 14.73 MB / 0.5105 GB
Notification: Performance of FrontEnd - loaded program: time 0.0003059/0.000306, allocations: 8.438 kB / 0.7938 GB, free: 136.6 MB / 0.6042 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2221/0.2224, allocations: 105.4 MB / 0.8967 GB, free: 68.22 MB / 0.6042 GB
Notification: Performance of NFInst.instantiate(Buildings.Examples.VAVReheat.Controls.Examples.OperationModes): time 0.005618/0.2281, allocations: 4.433 MB / 0.9011 GB, free: 63.76 MB / 0.6042 GB
Notification: Performance of NFInst.instExpressions: time 0.004794/0.2329, allocations: 2.132 MB / 0.9032 GB, free: 61.62 MB / 0.6042 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0009031/0.2339, allocations: 35.81 kB / 0.9032 GB, free: 61.58 MB / 0.6042 GB
Notification: Performance of NFTyping.typeComponents: time 0.0004321/0.2343, allocations: 126.9 kB / 0.9033 GB, free: 61.46 MB / 0.6042 GB
Notification: Performance of NFTyping.typeBindings: time 0.0005741/0.2349, allocations: 154.9 kB / 0.9035 GB, free: 61.3 MB / 0.6042 GB
Notification: Performance of NFTyping.typeClassSections: time 0.001456/0.2364, allocations: 0.7341 MB / 0.9042 GB, free: 60.57 MB / 0.6042 GB
Notification: Performance of NFFlatten.flatten: time 0.001251/0.2376, allocations: 1.801 MB / 0.9059 GB, free: 58.77 MB / 0.6042 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001343/0.239, allocations: 1.237 MB / 0.9071 GB, free: 57.49 MB / 0.6042 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.0004441/0.2394, allocations: 497.8 kB / 0.9076 GB, free: 57 MB / 0.6042 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0004356/0.2399, allocations: 0.5515 MB / 0.9082 GB, free: 56.45 MB / 0.6042 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001126/0.24, allocations: 60 kB / 0.9082 GB, free: 56.39 MB / 0.6042 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0002034/0.2402, allocations: 99.97 kB / 0.9083 GB, free: 56.29 MB / 0.6042 GB
Notification: Performance of NFScalarize.scalarize: time 0.0001723/0.2404, allocations: 250.8 kB / 0.9086 GB, free: 56.04 MB / 0.6042 GB
Notification: Performance of NFVerifyModel.verify: time 0.0003679/0.2408, allocations: 0.5979 MB / 0.9091 GB, free: 55.44 MB / 0.6042 GB
Notification: Performance of NFConvertDAE.convert: time 0.001026/0.2418, allocations: 1.841 MB / 0.9109 GB, free: 53.59 MB / 0.6042 GB
Notification: Performance of FrontEnd - DAE generated: time 4.799e-06/0.2418, allocations: 0 / 0.9109 GB, free: 53.59 MB / 0.6042 GB
Notification: Performance of FrontEnd: time 2.164e-06/0.2418, allocations: 0 / 0.9109 GB, free: 53.59 MB / 0.6042 GB
Notification: Performance of Transformations before backend: time 1.779e-05/0.2419, allocations: 4 kB / 0.9109 GB, free: 53.59 MB / 0.6042 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 377
 * Number of variables: 377
Notification: Performance of Generate backend data structure: time 0.002095/0.244, allocations: 1.44 MB / 0.9123 GB, free: 52.07 MB / 0.6042 GB
Notification: Performance of prepare preOptimizeDAE: time 4.232e-05/0.244, allocations: 12.03 kB / 0.9124 GB, free: 52.05 MB / 0.6042 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0002254/0.2442, allocations: 167.1 kB / 0.9125 GB, free: 51.89 MB / 0.6042 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0008704/0.2451, allocations: 0.7727 MB / 0.9133 GB, free: 51.08 MB / 0.6042 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 9.171e-05/0.2452, allocations: 127.3 kB / 0.9134 GB, free: 50.95 MB / 0.6042 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001631/0.2454, allocations: 172.4 kB / 0.9136 GB, free: 50.79 MB / 0.6042 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002012/0.2474, allocations: 1.667 MB / 0.9152 GB, free: 49.01 MB / 0.6042 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.476e-05/0.2475, allocations: 3.938 kB / 0.9152 GB, free: 49 MB / 0.6042 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001005/0.2476, allocations: 60 kB / 0.9152 GB, free: 48.95 MB / 0.6042 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.193e-05/0.2476, allocations: 44 kB / 0.9153 GB, free: 48.9 MB / 0.6042 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001712/0.2493, allocations: 1.22 MB / 0.9165 GB, free: 47.68 MB / 0.6042 GB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.005207/0.2545, allocations: 5.865 MB / 0.9222 GB, free: 41.65 MB / 0.6042 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.001539/0.2561, allocations: 1.121 MB / 0.9233 GB, free: 40.5 MB / 0.6042 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0006296/0.2567, allocations: 0.509 MB / 0.9238 GB, free: 39.98 MB / 0.6042 GB
Notification: Performance of preOpt evalFunc (simulation): time 8.025e-05/0.2568, allocations: 17 kB / 0.9238 GB, free: 39.96 MB / 0.6042 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0008302/0.2577, allocations: 0.6343 MB / 0.9244 GB, free: 39.31 MB / 0.6042 GB
Notification: Performance of pre-optimization done (n=123): time 3.927e-06/0.2577, allocations: 0 / 0.9244 GB, free: 39.31 MB / 0.6042 GB
Notification: Performance of matching and sorting (n=123): time 0.002059/0.2597, allocations: 1.374 MB / 0.9258 GB, free: 37.93 MB / 0.6042 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.727e-05/0.2598, allocations: 106 kB / 0.9259 GB, free: 37.8 MB / 0.6042 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0005879/0.2604, allocations: 0.6633 MB / 0.9265 GB, free: 37.13 MB / 0.6042 GB
Notification: Performance of collectPreVariables (initialization): time 9.307e-05/0.2605, allocations: 81.69 kB / 0.9266 GB, free: 37.04 MB / 0.6042 GB
Notification: Performance of collectInitialEqns (initialization): time 0.000277/0.2608, allocations: 0.5389 MB / 0.9271 GB, free: 36.49 MB / 0.6042 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001749/0.261, allocations: 327.8 kB / 0.9274 GB, free: 36.17 MB / 0.6042 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001735/0.2611, allocations: 217.6 kB / 0.9276 GB, free: 35.95 MB / 0.6042 GB
Notification: Performance of setup shared object (initialization): time 7.396e-05/0.2612, allocations: 353.9 kB / 0.928 GB, free: 35.6 MB / 0.6042 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0006409/0.2619, allocations: 459.8 kB / 0.9284 GB, free: 35.15 MB / 0.6042 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0007553/0.2626, allocations: 0.7938 MB / 0.9292 GB, free: 34.23 MB / 0.6042 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001192/0.2638, allocations: 1.085 MB / 0.9303 GB, free: 33.02 MB / 0.6042 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.801e-06/0.2638, allocations: 4 kB / 0.9303 GB, free: 33.01 MB / 0.6042 GB
Notification: Performance of matching and sorting (n=195) (initialization): time 0.001862/0.2657, allocations: 1.493 MB / 0.9317 GB, free: 31.5 MB / 0.6042 GB
Notification: Performance of prepare postOptimizeDAE: time 5.208e-05/0.2658, allocations: 50.58 kB / 0.9318 GB, free: 31.45 MB / 0.6042 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.37e-05/0.2658, allocations: 12 kB / 0.9318 GB, free: 31.44 MB / 0.6042 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.524e-05/0.2658, allocations: 16 kB / 0.9318 GB, free: 31.42 MB / 0.6042 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0005537/0.2664, allocations: 183.6 kB / 0.932 GB, free: 31.24 MB / 0.6042 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.016e-05/0.2664, allocations: 31.86 kB / 0.932 GB, free: 31.21 MB / 0.6042 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0007607/0.2672, allocations: 218.5 kB / 0.9322 GB, free: 31 MB / 0.6042 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 9.288e-05/0.2673, allocations: 71.98 kB / 0.9323 GB, free: 30.93 MB / 0.6042 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 42
 * Number of states: 0 ()
 * Number of discrete variables: 125 ($PRE.operationModes.start.enableFire,$PRE.operationModes.t2.enableFire,$PRE.operationModes.t1.enableFire,$PRE.operationModes.t6.enableFire,$PRE.operationModes.t5.enableFire,$PRE.operationModes.t3.enableFire,$PRE.operationModes.t4.enableFire,$PRE.operationModes.t7.enableFire,$PRE.operationModes.t9.enableFire,$PRE.operationModes.t8.enableFire,operationModes.stateGraphRoot.resume,operationModes.stateGraphRoot.suspend,operationModes.start.condition,operationModes.preCooSta.and3.u[2],occSch.tNonOcc,$PRE.occSch.tNonOcc,occSch.tOcc,$PRE.occSch.tOcc,occSch.iPerSto,$PRE.occSch.iPerSto,occSch.iPerSta,$PRE.occSch.iPerSta,occSch.nexStoInd,$PRE.occSch.nexStoInd,occSch.nexStaInd,$PRE.occSch.nexStaInd,occSch.occupied,$PRE.occSch.occupied,modSel1.y,modSel.y,operationModes.yFan,operationModes.sum.u[5],operationModes.sum.u[4],operationModes.sum.u[3],operationModes.sum.u[2],operationModes.sum.u[1],operationModes.t8.enableFire,operationModes.not1.y,operationModes.t9.enableFire,operationModes.t9.condition,operationModes.preCooSta.and3.u[3],operationModes.preCooSta.and3.u[1],operationModes.booleanExpression.y,operationModes.t7.enableFire,operationModes.t7.condition,operationModes.morPreCoo.active,operationModes.morPreCoo.oldActive,$PRE.operationModes.morPreCoo.oldActive,operationModes.morPreCoo.newActive,$PRE.operationModes.morPreCoo.newActive,operationModes.morPreCoo.localActive,$PRE.operationModes.morPreCoo.localActive,operationModes.t4.enableFire,operationModes.t4.condition,operationModes.t3.enableFire,operationModes.t3.condition,operationModes.occ.active,operationModes.occ.oldActive,$PRE.operationModes.occ.oldActive,operationModes.occ.newActive,$PRE.operationModes.occ.newActive,operationModes.occ.inPort[3].occupied,operationModes.occ.inPort[2].occupied,operationModes.occ.localActive,$PRE.operationModes.occ.localActive,operationModes.t5.enableFire,operationModes.t5.condition,operationModes.t6.t_start,$PRE.operationModes.t6.t_start,operationModes.t6.fire,operationModes.t6.enableFire,operationModes.morWarUp.active,operationModes.morWarUp.oldActive,$PRE.operationModes.morWarUp.oldActive,operationModes.morWarUp.newActive,$PRE.operationModes.morWarUp.newActive,operationModes.morWarUp.inPort[2].occupied,operationModes.morWarUp.localActive,$PRE.operationModes.morWarUp.localActive,operationModes.stateGraphRoot.activeSteps,operationModes.t1.t_start,$PRE.operationModes.t1.t_start,operationModes.t1.fire,operationModes.t1.enableFire,operationModes.t1.condition,operationModes.t2.t_start,$PRE.operationModes.t2.t_start,operationModes.t2.fire,operationModes.t2.enableFire,operationModes.t2.condition,operationModes.unOccNigSetBac.active,operationModes.unOccNigSetBac.oldActive,$PRE.operationModes.unOccNigSetBac.oldActive,operationModes.unOccNigSetBac.newActive,$PRE.operationModes.unOccNigSetBac.newActive,operationModes.unOccNigSetBac.outPort[2].available,operationModes.unOccNigSetBac.localActive,$PRE.operationModes.unOccNigSetBac.localActive,operationModes.unOccOff.active,operationModes.unOccOff.oldActive,$PRE.operationModes.unOccOff.oldActive,operationModes.unOccOff.newActive,$PRE.operationModes.unOccOff.newActive,operationModes.unOccOff.outPort[4].available,operationModes.unOccOff.outPort[3].available,operationModes.unOccOff.outPort[2].available,operationModes.unOccOff.inPort[3].occupied,operationModes.unOccOff.inPort[2].occupied,operationModes.unOccOff.localActive,$PRE.operationModes.unOccOff.localActive,operationModes.start.enableFire,operationModes.initialStep.oldActive,$PRE.operationModes.initialStep.oldActive,operationModes.initialStep.newActive,$PRE.operationModes.initialStep.newActive,operationModes.initialStep.localActive,$PRE.operationModes.initialStep.localActive,operationModes.initialStep.active,controlBus.controlMode,$whenCondition1,$whenCondition2,$whenCondition3,$whenCondition5,$whenCondition4,PID.I.trigger_internal)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (189):
 * Single equations (assignments): 188
 * Array equations: 0
 * Algorithm blocks: 1
 * 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.0005541/0.2678, allocations: 424.5 kB / 0.9327 GB, free: 30.5 MB / 0.6042 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001175/0.268, allocations: 94.86 kB / 0.9328 GB, free: 30.41 MB / 0.6042 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001605/0.2696, allocations: 1.159 MB / 0.9339 GB, free: 29.23 MB / 0.6042 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.223e-05/0.2696, allocations: 3.984 kB / 0.9339 GB, free: 29.23 MB / 0.6042 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.188e-06/0.2696, allocations: 7.938 kB / 0.9339 GB, free: 29.22 MB / 0.6042 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0002694/0.2699, allocations: 267.6 kB / 0.9342 GB, free: 28.94 MB / 0.6042 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.000124/0.27, allocations: 35.98 kB / 0.9342 GB, free: 28.9 MB / 0.6042 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0004489/0.2705, allocations: 91.86 kB / 0.9343 GB, free: 28.81 MB / 0.6042 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002483/0.2707, allocations: 141.1 kB / 0.9344 GB, free: 28.68 MB / 0.6042 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.001851/0.2726, allocations: 1.328 MB / 0.9357 GB, free: 27.34 MB / 0.6042 GB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.002211/0.2748, allocations: 1.937 MB / 0.9376 GB, free: 25.39 MB / 0.6042 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0006653/0.2755, allocations: 0.553 MB / 0.9382 GB, free: 24.83 MB / 0.6042 GB
Notification: Performance of sorting global known variables: time 0.000375/0.2759, allocations: 387.1 kB / 0.9385 GB, free: 24.46 MB / 0.6042 GB
Notification: Performance of Backend: time 1.182e-06/0.2759, allocations: 0 / 0.9385 GB, free: 24.46 MB / 0.6042 GB
Notification: Performance of simCode: created initialization part: time 0.001778/0.2777, allocations: 1.062 MB / 0.9396 GB, free: 23.38 MB / 0.6042 GB
Notification: Performance of SimCode: time 0.003041/0.2807, allocations: 2.843 MB / 0.9423 GB, free: 20.48 MB / 0.6042 GB
Notification: Performance of Templates: time 0.02052/0.3013, allocations: 13.58 MB / 0.9556 GB, free: 7.027 MB / 0.6042 GB
make -j1 -f Buildings_maint.7.0.x_Buildings.Examples.VAVReheat.Controls.Examples.OperationModes.makefile
(rm -f Buildings_maint.7.0.x_Buildings.Examples.VAVReheat.Controls.Examples.OperationModes.pipe ; mkfifo Buildings_maint.7.0.x_Buildings.Examples.VAVReheat.Controls.Examples.OperationModes.pipe ; head -c 1048576 < Buildings_maint.7.0.x_Buildings.Examples.VAVReheat.Controls.Examples.OperationModes.pipe >> ../files/Buildings_maint.7.0.x_Buildings.Examples.VAVReheat.Controls.Examples.OperationModes.sim & ./Buildings_maint.7.0.x_Buildings.Examples.VAVReheat.Controls.Examples.OperationModes -abortSlowSimulation -alarm=480 -lv LOG_STATS > Buildings_maint.7.0.x_Buildings.Examples.VAVReheat.Controls.Examples.OperationModes.pipe 2>&1)