Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_dev_IDEAS.Airflow.Multizone.Examples.ChimneyShaftWithVolume.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/IDEAS 3.0.0-master/package.mo", uses=false)
Using package IDEAS with version 3.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/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(IDEAS.Airflow.Multizone.Examples.ChimneyShaftWithVolume,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Airflow.Multizone.Examples.ChimneyShaftWithVolume")
translateModel(IDEAS.Airflow.Multizone.Examples.ChimneyShaftWithVolume,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Airflow.Multizone.Examples.ChimneyShaftWithVolume")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001165/0.001165, allocations: 115.3 kB / 15.34 MB, free: 0.5156 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.001768/0.001769, allocations: 189.5 kB / 16.25 MB, free: 6.633 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.352/1.352, allocations: 222.9 MB / 239.9 MB, free: 4.531 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo): time 0.9524/0.9524, allocations: 135.2 MB / 425.2 MB, free: 12.01 MB / 350.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001304/0.001304, allocations: 67.23 kB / 0.5049 GB, free: 5.621 MB / 414.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1858/0.1871, allocations: 80.94 MB / 0.5839 GB, free: 4.852 MB / 494.1 MB
Notification: Performance of NFInst.instantiate(IDEAS.Airflow.Multizone.Examples.ChimneyShaftWithVolume): time 0.5863/0.7735, allocations: 233 MB / 0.8114 GB, free: 4.129 MB / 0.6231 GB
Notification: Performance of NFInst.instExpressions: time 0.01765/0.7912, allocations: 10.33 MB / 0.8215 GB, free: 9.781 MB / 0.6387 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003616/0.7949, allocations: 51.69 kB / 0.8216 GB, free: 9.73 MB / 0.6387 GB
Notification: Performance of NFTyping.typeComponents: time 0.003777/0.7987, allocations: 1.065 MB / 0.8226 GB, free: 8.66 MB / 0.6387 GB
Notification: Performance of NFTyping.typeBindings: time 0.01164/0.8104, allocations: 3.436 MB / 0.826 GB, free: 5.211 MB / 0.6387 GB
Notification: Performance of NFTyping.typeClassSections: time 0.006345/0.8168, allocations: 1.897 MB / 0.8278 GB, free: 3.324 MB / 0.6387 GB
Notification: Performance of NFFlatten.flatten: time 0.007086/0.8239, allocations: 4.451 MB / 0.8322 GB, free: 14.86 MB / 0.6544 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002993/0.8269, allocations: 1.551 MB / 0.8337 GB, free: 13.27 MB / 0.6544 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.003955/0.8309, allocations: 1.623 MB / 0.8353 GB, free: 11.64 MB / 0.6544 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.003874/0.8348, allocations: 1.59 MB / 0.8368 GB, free: 10.06 MB / 0.6544 GB
Notification: Performance of NFPackage.collectConstants: time 0.0007096/0.8355, allocations: 184 kB / 0.837 GB, free: 9.883 MB / 0.6544 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.007756/0.8433, allocations: 3.374 MB / 0.8403 GB, free: 6.5 MB / 0.6544 GB
Notification: Performance of NFScalarize.scalarize: time 0.001394/0.8447, allocations: 0.7012 MB / 0.841 GB, free: 5.797 MB / 0.6544 GB
Notification: Performance of NFVerifyModel.verify: time 0.002504/0.8472, allocations: 1.069 MB / 0.842 GB, free: 4.723 MB / 0.6544 GB
Notification: Performance of NFConvertDAE.convert: time 0.009061/0.8563, allocations: 4.02 MB / 0.8459 GB, free: 0.6914 MB / 0.6544 GB
Notification: Performance of FrontEnd - DAE generated: time 8.175e-06/0.8563, allocations: 3.938 kB / 0.8459 GB, free: 0.6875 MB / 0.6544 GB
Notification: Performance of FrontEnd: time 2.515e-06/0.8563, allocations: 0 / 0.8459 GB, free: 0.6875 MB / 0.6544 GB
Notification: Performance of Transformations before backend: time 4.004e-05/0.8564, allocations: 4 kB / 0.8459 GB, free: 0.6836 MB / 0.6544 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 344
 * Number of variables: 344
Notification: Performance of Generate backend data structure: time 0.006034/0.8624, allocations: 2.482 MB / 0.8484 GB, free: 14.15 MB / 0.67 GB
Notification: Performance of prepare preOptimizeDAE: time 5.338e-05/0.8625, allocations: 8.031 kB / 0.8484 GB, free: 14.14 MB / 0.67 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003425/0.8659, allocations: 0.5284 MB / 0.8489 GB, free: 13.61 MB / 0.67 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002214/0.8682, allocations: 1.228 MB / 0.8501 GB, free: 12.32 MB / 0.67 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 8.735e-05/0.8683, allocations: 102.7 kB / 0.8502 GB, free: 12.22 MB / 0.67 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002197/0.8685, allocations: 143.9 kB / 0.8503 GB, free: 12.08 MB / 0.67 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002968/0.8715, allocations: 1.662 MB / 0.852 GB, free: 10.35 MB / 0.67 GB
Notification: Performance of preOpt findStateOrder (simulation): time 4.168e-05/0.8715, allocations: 7.938 kB / 0.852 GB, free: 10.34 MB / 0.67 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001366/0.8717, allocations: 56 kB / 0.852 GB, free: 10.29 MB / 0.67 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 8.186e-05/0.8718, allocations: 70.58 kB / 0.8521 GB, free: 10.22 MB / 0.67 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002517/0.8743, allocations: 1.443 MB / 0.8535 GB, free: 8.77 MB / 0.67 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.009092/0.8834, allocations: 6.945 MB / 0.8603 GB, free: 1.363 MB / 0.67 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.001757/0.8852, allocations: 1.18 MB / 0.8614 GB, free: 152 kB / 0.67 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.355/1.24, allocations: 0.6059 MB / 0.862 GB, free: 221.7 MB / 0.67 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0007588/1.241, allocations: 0.8673 MB / 0.8629 GB, free: 220.8 MB / 0.67 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0009772/1.242, allocations: 0.6015 MB / 0.8634 GB, free: 220.7 MB / 0.67 GB
Notification: Performance of pre-optimization done (n=110): time 4.318e-06/1.242, allocations: 0 / 0.8634 GB, free: 220.7 MB / 0.67 GB
Notification: Performance of matching and sorting (n=110): time 0.00292/1.245, allocations: 1.574 MB / 0.865 GB, free: 220.6 MB / 0.67 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.863e-05/1.245, allocations: 91.45 kB / 0.8651 GB, free: 220.6 MB / 0.67 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001291/1.246, allocations: 1.196 MB / 0.8662 GB, free: 220.1 MB / 0.67 GB
Notification: Performance of collectPreVariables (initialization): time 8.673e-05/1.246, allocations: 51.11 kB / 0.8663 GB, free: 220 MB / 0.67 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0004622/1.247, allocations: 0.6679 MB / 0.8669 GB, free: 219.7 MB / 0.67 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0002862/1.247, allocations: 384.8 kB / 0.8673 GB, free: 219.4 MB / 0.67 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.000252/1.247, allocations: 179.2 kB / 0.8675 GB, free: 219.3 MB / 0.67 GB
Notification: Performance of setup shared object (initialization): time 7.294e-05/1.247, allocations: 347.2 kB / 0.8678 GB, free: 219 MB / 0.67 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0006098/1.248, allocations: 423.8 kB / 0.8682 GB, free: 218.8 MB / 0.67 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0006799/1.249, allocations: 0.5689 MB / 0.8688 GB, free: 218.5 MB / 0.67 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001325/1.25, allocations: 1.011 MB / 0.8698 GB, free: 218 MB / 0.67 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.586e-06/1.25, allocations: 2.938 kB / 0.8698 GB, free: 218 MB / 0.67 GB
Notification: Performance of matching and sorting (n=126) (initialization): time 0.001697/1.252, allocations: 1.214 MB / 0.8709 GB, free: 217.5 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 2.292e-05/1.252, allocations: 17.92 kB / 0.871 GB, free: 217.5 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.003e-05/1.252, allocations: 3.406 kB / 0.871 GB, free: 217.5 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.913e-05/1.252, allocations: 6.531 kB / 0.871 GB, free: 217.5 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0009105/1.253, allocations: 216.9 kB / 0.8712 GB, free: 217.5 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.278e-05/1.253, allocations: 6.438 kB / 0.8712 GB, free: 217.5 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001309/1.254, allocations: 170.1 kB / 0.8713 GB, free: 217.5 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 7.492e-05/1.254, allocations: 40.66 kB / 0.8714 GB, free: 217.4 MB / 0.67 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.0009946/1.255, allocations: 0.734 MB / 0.8721 GB, free: 217.1 MB / 0.67 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.0006414/1.256, allocations: 0.5521 MB / 0.8726 GB, free: 216.8 MB / 0.67 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.001218/1.257, allocations: 0.9938 MB / 0.8736 GB, free: 216.3 MB / 0.67 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 2.334e-06/1.257, allocations: 0 / 0.8736 GB, free: 216.3 MB / 0.67 GB
Notification: Performance of matching and sorting (n=126) (initialization_lambda0): time 0.001626/1.259, allocations: 1.191 MB / 0.8748 GB, free: 215.8 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 1.23e-05/1.259, allocations: 12.22 kB / 0.8748 GB, free: 215.8 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.559e-06/1.259, allocations: 0 / 0.8748 GB, free: 215.8 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 9.658e-06/1.259, allocations: 8.531 kB / 0.8748 GB, free: 215.8 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0008113/1.26, allocations: 212.5 kB / 0.875 GB, free: 215.8 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.072e-05/1.26, allocations: 9.125 kB / 0.875 GB, free: 215.8 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001242/1.261, allocations: 138.6 kB / 0.8751 GB, free: 215.8 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 7.053e-05/1.261, allocations: 40.52 kB / 0.8752 GB, free: 215.8 MB / 0.67 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 11
 * Number of states: 0 ()
 * Number of discrete variables: 5 ($PRE.mRoo_flow.nextTimeEvent,$whenCondition1,mRoo_flow.nextTimeEventScaled,$PRE.mRoo_flow.nextTimeEventScaled,mRoo_flow.nextTimeEvent)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (126):
 * Single equations (assignments): 126
 * 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.0002817/1.261, allocations: 249 kB / 0.8754 GB, free: 215.5 MB / 0.67 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001911/1.261, allocations: 148.8 kB / 0.8756 GB, free: 215.5 MB / 0.67 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001943/1.263, allocations: 1.239 MB / 0.8768 GB, free: 214.9 MB / 0.67 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 8.526e-06/1.263, allocations: 9.656 kB / 0.8768 GB, free: 214.9 MB / 0.67 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 8.035e-06/1.263, allocations: 3.562 kB / 0.8768 GB, free: 214.9 MB / 0.67 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001397/1.265, allocations: 1.007 MB / 0.8778 GB, free: 214.5 MB / 0.67 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.004213/1.269, allocations: 3.153 MB / 0.8808 GB, free: 213 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.674e-06/1.269, allocations: 10.38 kB / 0.8809 GB, free: 213 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001023/1.27, allocations: 231.3 kB / 0.8811 GB, free: 213 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (simulation): time 9.328e-06/1.27, allocations: 5.969 kB / 0.8811 GB, free: 213 MB / 0.67 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 7.238e-05/1.27, allocations: 30.12 kB / 0.8811 GB, free: 212.9 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 1.008e-05/1.27, allocations: 10.5 kB / 0.8811 GB, free: 212.9 MB / 0.67 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.587e-06/1.27, allocations: 3 kB / 0.8811 GB, free: 212.9 MB / 0.67 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002448/1.273, allocations: 1.996 MB / 0.8831 GB, free: 212 MB / 0.67 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.000569/1.273, allocations: 362.4 kB / 0.8834 GB, free: 211.7 MB / 0.67 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001801/1.273, allocations: 26.69 kB / 0.8834 GB, free: 211.7 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001054/1.275, allocations: 115.7 kB / 0.8836 GB, free: 211.7 MB / 0.67 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001686/1.275, allocations: 75.25 kB / 0.8836 GB, free: 211.7 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 7.461e-05/1.275, allocations: 43.12 kB / 0.8837 GB, free: 211.7 MB / 0.67 GB
Notification: Performance of sorting global known variables: time 0.0009457/1.276, allocations: 0.825 MB / 0.8845 GB, free: 211.3 MB / 0.67 GB
Notification: Performance of sort global known variables: time 1.2e-07/1.276, allocations: 0 / 0.8845 GB, free: 211.3 MB / 0.67 GB
Notification: Performance of remove unused functions: time 0.001754/1.278, allocations: 0.8376 MB / 0.8853 GB, free: 211.2 MB / 0.67 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 11
 * Number of states: 9 (roo.dynBal.U,roo.dynBal.m,roo.dynBal.mXi[1],oriChiTop.mExc,oriBot.mExc,oriChiBot.mExc,sha.vol.dynBal.U,sha.vol.dynBal.m,sha.vol.dynBal.mXi[1])
 * Number of discrete variables: 3 ($whenCondition1,mRoo_flow.nextTimeEvent,mRoo_flow.nextTimeEventScaled)
 * Number of discrete states: 2 (mRoo_flow.nextTimeEvent,mRoo_flow.nextTimeEventScaled)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (110):
 * Single equations (assignments): 108
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 2
 * 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.001327/1.279, allocations: 0.5824 MB / 0.8859 GB, free: 211 MB / 0.67 GB
Notification: Performance of simCode: created initialization part: time 0.003305/1.282, allocations: 1.855 MB / 0.8877 GB, free: 210.5 MB / 0.67 GB
Notification: Performance of simCode: created event and clocks part: time 4.058e-06/1.282, allocations: 3.969 kB / 0.8877 GB, free: 210.5 MB / 0.67 GB
Notification: Performance of simCode: created simulation system equations: time 0.0007633/1.283, allocations: 0.5702 MB / 0.8882 GB, free: 210.3 MB / 0.67 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003461/1.286, allocations: 0.8726 MB / 0.8891 GB, free: 210 MB / 0.67 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.005561/1.292, allocations: 3.958 MB / 0.8929 GB, free: 207.7 MB / 0.67 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0009833/1.293, allocations: 1.363 MB / 0.8943 GB, free: 206.9 MB / 0.67 GB
Notification: Performance of simCode: alias equations: time 0.002079/1.295, allocations: 455.4 kB / 0.8947 GB, free: 206.8 MB / 0.67 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0004054/1.296, allocations: 116.9 kB / 0.8948 GB, free: 206.8 MB / 0.67 GB
Notification: Performance of SimCode: time 8.32e-07/1.296, allocations: 0 / 0.8948 GB, free: 206.8 MB / 0.67 GB
Notification: Performance of Templates: time 0.07222/1.368, allocations: 29.84 MB / 0.924 GB, free: 200.3 MB / 0.67 GB
make -j1 -f IDEAS_dev_IDEAS.Airflow.Multizone.Examples.ChimneyShaftWithVolume.makefile
(rm -f IDEAS_dev_IDEAS.Airflow.Multizone.Examples.ChimneyShaftWithVolume.pipe ; mkfifo IDEAS_dev_IDEAS.Airflow.Multizone.Examples.ChimneyShaftWithVolume.pipe ; head -c 1048576 < IDEAS_dev_IDEAS.Airflow.Multizone.Examples.ChimneyShaftWithVolume.pipe >> ../files/IDEAS_dev_IDEAS.Airflow.Multizone.Examples.ChimneyShaftWithVolume.sim & ./IDEAS_dev_IDEAS.Airflow.Multizone.Examples.ChimneyShaftWithVolume  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IDEAS_dev_IDEAS.Airflow.Multizone.Examples.ChimneyShaftWithVolume.pipe 2>&1)