Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr Annex60_1.0.0_Annex60.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse.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 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Annex60 1.0.0/package.mo", uses=false)
Using package Annex60 with version 1.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Annex60 1.0.0/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 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(Annex60.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse,tolerance=1e-06,outputFormat="empty",numberOfIntervals=60000,variableFilter="",fileNamePrefix="Annex60_1.0.0_Annex60.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse")
translateModel(Annex60.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse,tolerance=1e-06,outputFormat="empty",numberOfIntervals=60000,variableFilter="",fileNamePrefix="Annex60_1.0.0_Annex60.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001303/0.001303, allocations: 103 kB / 15.06 MB, free: 1.098 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.00188/0.00188, allocations: 173.3 kB / 15.95 MB, free: 212 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.433/1.433, allocations: 205.4 MB / 222.1 MB, free: 5.656 MB / 174.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Annex60 1.0.0/package.mo): time 0.4066/0.4066, allocations: 54.97 MB / 325.3 MB, free: 0.6953 MB / 254.1 MB
Notification: Modelica requested package Complex of version 3.2.3. Complex 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Modelica requested package ModelicaServices of version 3.2.3. ModelicaServices 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Annex60 requested package Modelica of version 3.2.2. Modelica 3.2.3 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.0008545/0.0008548, allocations: 12 kB / 396.6 MB, free: 11.77 MB / 334.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4091/0.4099, allocations: 60.72 MB / 457.3 MB, free: 38.14 MB / 382.1 MB
Notification: Performance of NFInst.instantiate(Annex60.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse): time 0.1939/0.6039, allocations: 162.8 MB / 0.6056 GB, free: 4.438 MB / 478.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01456/0.6185, allocations: 8.79 MB / 0.6142 GB, free: 11.64 MB / 494.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002/0.6206, allocations: 31.81 kB / 0.6142 GB, free: 11.61 MB / 494.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.002039/0.6226, allocations: 0.5792 MB / 0.6148 GB, free: 11.03 MB / 494.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.007105/0.6298, allocations: 1.73 MB / 0.6165 GB, free: 9.301 MB / 494.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004485/0.6343, allocations: 1.268 MB / 0.6177 GB, free: 8.051 MB / 494.1 MB
Notification: Performance of NFFlatten.flatten: time 0.004832/0.6391, allocations: 2.473 MB / 0.6201 GB, free: 5.578 MB / 494.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002535/0.6417, allocations: 1.563 MB / 0.6217 GB, free: 4 MB / 494.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003055/0.6447, allocations: 1.34 MB / 0.623 GB, free: 2.656 MB / 494.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001613/0.6464, allocations: 0.9806 MB / 0.6239 GB, free: 1.672 MB / 494.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.000224/0.6466, allocations: 84 kB / 0.624 GB, free: 1.59 MB / 494.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002757/0.6494, allocations: 0.8855 MB / 0.6249 GB, free: 0.7031 MB / 494.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.00062/0.65, allocations: 0.5803 MB / 0.6254 GB, free: 124 kB / 494.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0006273/0.6506, allocations: 456.6 kB / 0.6259 GB, free: 15.66 MB / 0.4981 GB
Notification: Performance of NFConvertDAE.convert: time 0.00357/0.6542, allocations: 2.976 MB / 0.6288 GB, free: 12.69 MB / 0.4981 GB
Notification: Performance of FrontEnd - DAE generated: time 3.447e-06/0.6542, allocations: 4 kB / 0.6288 GB, free: 12.69 MB / 0.4981 GB
Notification: Performance of FrontEnd: time 1.773e-06/0.6542, allocations: 0 / 0.6288 GB, free: 12.69 MB / 0.4981 GB
Notification: Performance of Transformations before backend: time 5.866e-05/0.6543, allocations: 4 kB / 0.6288 GB, free: 12.68 MB / 0.4981 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 495
 * Number of variables: 495
Notification: Performance of Generate backend data structure: time 0.005795/0.6601, allocations: 2.348 MB / 0.6311 GB, free: 10.28 MB / 0.4981 GB
Notification: Performance of prepare preOptimizeDAE: time 4.299e-05/0.6602, allocations: 12.03 kB / 0.6311 GB, free: 10.27 MB / 0.4981 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003411/0.6636, allocations: 0.5515 MB / 0.6316 GB, free: 9.711 MB / 0.4981 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002678/0.6663, allocations: 1.269 MB / 0.6329 GB, free: 8.387 MB / 0.4981 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001357/0.6664, allocations: 147.8 kB / 0.633 GB, free: 8.242 MB / 0.4981 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002995/0.6667, allocations: 192 kB / 0.6332 GB, free: 8.055 MB / 0.4981 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.004467/0.6712, allocations: 2.364 MB / 0.6355 GB, free: 5.656 MB / 0.4981 GB
Notification: Performance of preOpt findStateOrder (simulation): time 4.112e-05/0.6713, allocations: 1.188 kB / 0.6355 GB, free: 5.656 MB / 0.4981 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002052/0.6715, allocations: 83.98 kB / 0.6356 GB, free: 5.574 MB / 0.4981 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 8.377e-05/0.6716, allocations: 83.73 kB / 0.6357 GB, free: 5.492 MB / 0.4981 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.005018/0.6766, allocations: 2.841 MB / 0.6384 GB, free: 2.648 MB / 0.4981 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.2713/0.9479, allocations: 7.138 MB / 0.6454 GB, free: 154.7 MB / 0.4981 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.004061/0.952, allocations: 2.298 MB / 0.6477 GB, free: 154.6 MB / 0.4981 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001151/0.9532, allocations: 1.18 MB / 0.6488 GB, free: 153.4 MB / 0.4981 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002029/0.9552, allocations: 1.321 MB / 0.6501 GB, free: 153.2 MB / 0.4981 GB
Notification: Performance of pre-optimization done (n=198): time 3.837e-06/0.9552, allocations: 3.469 kB / 0.6501 GB, free: 153.2 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=198): time 0.006352/0.9616, allocations: 3.231 MB / 0.6533 GB, free: 152.1 MB / 0.4981 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001146/0.9617, allocations: 392.9 kB / 0.6536 GB, free: 151.7 MB / 0.4981 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001533/0.9633, allocations: 1.057 MB / 0.6547 GB, free: 151.3 MB / 0.4981 GB
Notification: Performance of collectPreVariables (initialization): time 0.0002003/0.9635, allocations: 64.89 kB / 0.6547 GB, free: 151.2 MB / 0.4981 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0005541/0.964, allocations: 0.6825 MB / 0.6554 GB, free: 150.7 MB / 0.4981 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0004915/0.9645, allocations: 0.6573 MB / 0.656 GB, free: 150.2 MB / 0.4981 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.000102/0.9646, allocations: 37.84 kB / 0.6561 GB, free: 150.2 MB / 0.4981 GB
Notification: Performance of setup shared object (initialization): time 0.0001077/0.9648, allocations: 372.2 kB / 0.6564 GB, free: 149.8 MB / 0.4981 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001396/0.9662, allocations: 0.8116 MB / 0.6572 GB, free: 149.5 MB / 0.4981 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001545/0.9677, allocations: 1.056 MB / 0.6582 GB, free: 149 MB / 0.4981 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001596/0.9693, allocations: 0.9682 MB / 0.6592 GB, free: 148.6 MB / 0.4981 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.011e-06/0.9693, allocations: 4.094 kB / 0.6592 GB, free: 148.6 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=221) (initialization): time 0.004782/0.9741, allocations: 2.26 MB / 0.6614 GB, free: 147.7 MB / 0.4981 GB
Notification: Performance of prepare postOptimizeDAE: time 3.019e-05/0.9742, allocations: 6.875 kB / 0.6614 GB, free: 147.7 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.353e-05/0.9742, allocations: 2.625 kB / 0.6614 GB, free: 147.7 MB / 0.4981 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0005937/0.9748, allocations: 88.41 kB / 0.6615 GB, free: 147.7 MB / 0.4981 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001715/0.9765, allocations: 393.6 kB / 0.6619 GB, free: 147.6 MB / 0.4981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001717/0.9783, allocations: 1.525 MB / 0.6634 GB, free: 146.2 MB / 0.4981 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001895/0.9802, allocations: 234.8 kB / 0.6636 GB, free: 146.1 MB / 0.4981 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001522/0.9803, allocations: 70.08 kB / 0.6637 GB, free: 146.1 MB / 0.4981 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 11
 * Number of states: 0 ()
 * Number of discrete variables: 6 ($whenCondition2,$whenCondition1,$PRE.weaDat.conTim1.tStart,weaDat.conTim1.tStart,$PRE.weaDat.conTim.tStart,weaDat.conTim.tStart)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (219):
 * Single equations (assignments): 218
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 0
 * Non-linear torn systems: 1 {1 2}
Notification: Performance of prepare postOptimizeDAE: time 0.001134/0.9815, allocations: 0.5025 MB / 0.6641 GB, free: 145.8 MB / 0.4981 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003256/0.9818, allocations: 169.4 kB / 0.6643 GB, free: 145.7 MB / 0.4981 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.005245/0.9871, allocations: 2.629 MB / 0.6669 GB, free: 144.6 MB / 0.4981 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.12e-05/0.9871, allocations: 22.28 kB / 0.6669 GB, free: 144.6 MB / 0.4981 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 9.197e-06/0.9871, allocations: 1.062 kB / 0.6669 GB, free: 144.6 MB / 0.4981 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.004152/0.9913, allocations: 2.392 MB / 0.6692 GB, free: 143.4 MB / 0.4981 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.008708/1, allocations: 5.053 MB / 0.6742 GB, free: 141.1 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.273e-05/1, allocations: 5.312 kB / 0.6742 GB, free: 141.1 MB / 0.4981 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001819/1.002, allocations: 388.3 kB / 0.6745 GB, free: 141 MB / 0.4981 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0006808/1.003, allocations: 142.5 kB / 0.6747 GB, free: 141 MB / 0.4981 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001432/1.003, allocations: 52.03 kB / 0.6747 GB, free: 140.9 MB / 0.4981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.002425/1.005, allocations: 3.025 MB / 0.6777 GB, free: 138.1 MB / 0.4981 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 7.113e-06/1.005, allocations: 4.781 kB / 0.6777 GB, free: 138.1 MB / 0.4981 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.006921/1.012, allocations: 4.105 MB / 0.6817 GB, free: 136 MB / 0.4981 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001386/1.014, allocations: 0.5538 MB / 0.6822 GB, free: 135.6 MB / 0.4981 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002762/1.014, allocations: 44.67 kB / 0.6823 GB, free: 135.6 MB / 0.4981 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001496/1.015, allocations: 149.6 kB / 0.6824 GB, free: 135.6 MB / 0.4981 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002844/1.016, allocations: 147 kB / 0.6826 GB, free: 135.6 MB / 0.4981 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001522/1.016, allocations: 77.2 kB / 0.6826 GB, free: 135.5 MB / 0.4981 GB
Notification: Performance of sorting global known variables: time 0.001198/1.017, allocations: 0.7289 MB / 0.6833 GB, free: 135.2 MB / 0.4981 GB
Notification: Performance of sort global known variables: time 1.062e-06/1.017, allocations: 0.6562 kB / 0.6833 GB, free: 135.2 MB / 0.4981 GB
Notification: Performance of remove unused functions: time 0.002178/1.019, allocations: 0.7187 MB / 0.684 GB, free: 135.1 MB / 0.4981 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 18
 * Number of states: 5 (simpleZone.volRoom.dynBal.U,simpleZone.volRoom.dynBal.m,simpleZone.volRoom.dynBal.mXi[1],simpleZone.door.mExcAB,simpleZone.door.mExcBA)
 * Number of discrete variables: 4 ($whenCondition2,$whenCondition1,weaDat.conTim1.tStart,weaDat.conTim.tStart)
 * Number of discrete states: 2 (weaDat.conTim.tStart,weaDat.conTim1.tStart)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (213):
 * Single equations (assignments): 209
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 2
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 1 {(1,100.0%) 2}
 * Non-linear torn systems: 1 {1 3}
Notification: Performance of Backend phase and start with SimCode phase: time 0.001694/1.021, allocations: 0.7252 MB / 0.6848 GB, free: 134.9 MB / 0.4981 GB
Notification: Performance of simCode: created initialization part: time 0.00514/1.026, allocations: 2.198 MB / 0.6869 GB, free: 134.1 MB / 0.4981 GB
Notification: Performance of simCode: created event and clocks part: time 8.375e-06/1.026, allocations: 1.625 kB / 0.6869 GB, free: 134.1 MB / 0.4981 GB
Notification: Performance of simCode: created simulation system equations: time 0.002572/1.029, allocations: 1.397 MB / 0.6883 GB, free: 133.4 MB / 0.4981 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003672/1.032, allocations: 0.7072 MB / 0.689 GB, free: 133.2 MB / 0.4981 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.009969/1.042, allocations: 5.207 MB / 0.694 GB, free: 130.3 MB / 0.4981 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001618/1.044, allocations: 1.533 MB / 0.6955 GB, free: 129.5 MB / 0.4981 GB
Notification: Performance of simCode: alias equations: time 0.0032/1.047, allocations: 0.6492 MB / 0.6962 GB, free: 129.4 MB / 0.4981 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.00063/1.048, allocations: 180 kB / 0.6963 GB, free: 129.3 MB / 0.4981 GB
Notification: Performance of SimCode: time 9.82e-07/1.048, allocations: 0 / 0.6963 GB, free: 129.3 MB / 0.4981 GB
Notification: Performance of Templates: time 0.05407/1.102, allocations: 33.42 MB / 0.729 GB, free: 102.2 MB / 0.4981 GB
make -j1 -f Annex60_1.0.0_Annex60.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse.makefile
Environment - environmentSimulation:
(rm -f Annex60_1.0.0_Annex60.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse.pipe ; mkfifo Annex60_1.0.0_Annex60.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse.pipe ; head -c 1048576 < Annex60_1.0.0_Annex60.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse.pipe >> ../files/Annex60_1.0.0_Annex60.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse.sim & ./Annex60_1.0.0_Annex60.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse  -abortSlowSimulation -alarm=480 -lv LOG_STATS > Annex60_1.0.0_Annex60.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse.pipe 2>&1)
Environment - environmentSimulation: