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.001053/0.001053, allocations: 111.3 kB / 15.23 MB, free: 0.6602 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.001058/0.001058, allocations: 192.6 kB / 16.14 MB, free: 6.691 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.364/1.364, allocations: 222.9 MB / 239.8 MB, free: 4.602 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo): time 0.9297/0.9297, allocations: 135.1 MB / 425.1 MB, free: 11.88 MB / 350.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001261/0.001262, allocations: 63.91 kB / 0.5047 GB, free: 5.801 MB / 414.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1745/0.1757, allocations: 80.92 MB / 0.5837 GB, free: 5.047 MB / 494.1 MB
Notification: Performance of NFInst.instantiate(IDEAS.Airflow.Multizone.Examples.ChimneyShaftWithVolume): time 0.5603/0.7361, allocations: 233.6 MB / 0.8119 GB, free: 3.688 MB / 0.6231 GB
Notification: Performance of NFInst.instExpressions: time 0.01797/0.7541, allocations: 10.5 MB / 0.8221 GB, free: 9.172 MB / 0.6387 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003706/0.7579, allocations: 59.62 kB / 0.8222 GB, free: 9.113 MB / 0.6387 GB
Notification: Performance of NFTyping.typeComponents: time 0.003812/0.7617, allocations: 1.026 MB / 0.8232 GB, free: 8.082 MB / 0.6387 GB
Notification: Performance of NFTyping.typeBindings: time 0.01137/0.7731, allocations: 3.428 MB / 0.8265 GB, free: 4.641 MB / 0.6387 GB
Notification: Performance of NFTyping.typeClassSections: time 0.006759/0.7799, allocations: 1.886 MB / 0.8284 GB, free: 2.766 MB / 0.6387 GB
Notification: Performance of NFFlatten.flatten: time 0.008795/0.7887, allocations: 6.277 MB / 0.8345 GB, free: 12.47 MB / 0.6544 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002584/0.7914, allocations: 1.351 MB / 0.8358 GB, free: 11.09 MB / 0.6544 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.003584/0.795, allocations: 1.377 MB / 0.8372 GB, free: 9.707 MB / 0.6544 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.003945/0.7989, allocations: 1.541 MB / 0.8387 GB, free: 8.164 MB / 0.6544 GB
Notification: Performance of NFPackage.collectConstants: time 0.0004304/0.7994, allocations: 96 kB / 0.8388 GB, free: 8.07 MB / 0.6544 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.008748/0.8081, allocations: 3.744 MB / 0.8424 GB, free: 4.316 MB / 0.6544 GB
Notification: Performance of NFScalarize.scalarize: time 0.001123/0.8093, allocations: 0.6152 MB / 0.843 GB, free: 3.699 MB / 0.6544 GB
Notification: Performance of NFVerifyModel.verify: time 0.001791/0.8111, allocations: 0.9677 MB / 0.844 GB, free: 2.727 MB / 0.6544 GB
Notification: Performance of NFConvertDAE.convert: time 0.008119/0.8192, allocations: 3.95 MB / 0.8478 GB, free: 14.77 MB / 0.67 GB
Notification: Performance of FrontEnd - DAE generated: time 7.804e-06/0.8193, allocations: 0 / 0.8478 GB, free: 14.77 MB / 0.67 GB
Notification: Performance of FrontEnd: time 1.643e-06/0.8193, allocations: 0 / 0.8478 GB, free: 14.77 MB / 0.67 GB
Notification: Performance of Transformations before backend: time 4.237e-05/0.8193, allocations: 4 kB / 0.8478 GB, free: 14.76 MB / 0.67 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.006075/0.8254, allocations: 2.474 MB / 0.8502 GB, free: 12.23 MB / 0.67 GB
Notification: Performance of prepare preOptimizeDAE: time 5.073e-05/0.8255, allocations: 15.97 kB / 0.8503 GB, free: 12.22 MB / 0.67 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003497/0.829, allocations: 0.5244 MB / 0.8508 GB, free: 11.69 MB / 0.67 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002088/0.8311, allocations: 1.228 MB / 0.852 GB, free: 10.41 MB / 0.67 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 8.784e-05/0.8312, allocations: 106.7 kB / 0.8521 GB, free: 10.3 MB / 0.67 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002029/0.8314, allocations: 135.9 kB / 0.8522 GB, free: 10.17 MB / 0.67 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002872/0.8343, allocations: 1.662 MB / 0.8538 GB, free: 8.438 MB / 0.67 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.538e-05/0.8344, allocations: 4 kB / 0.8538 GB, free: 8.434 MB / 0.67 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001372/0.8345, allocations: 63.97 kB / 0.8539 GB, free: 8.371 MB / 0.67 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 9.093e-05/0.8346, allocations: 74.52 kB / 0.854 GB, free: 8.297 MB / 0.67 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002522/0.8371, allocations: 1.435 MB / 0.8554 GB, free: 6.855 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.3496/1.187, allocations: 6.951 MB / 0.8621 GB, free: 220.8 MB / 0.67 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.001877/1.189, allocations: 1.179 MB / 0.8633 GB, free: 220.6 MB / 0.67 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0008183/1.19, allocations: 0.6077 MB / 0.8639 GB, free: 220.6 MB / 0.67 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0007453/1.19, allocations: 0.8645 MB / 0.8647 GB, free: 219.7 MB / 0.67 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.001002/1.191, allocations: 0.6087 MB / 0.8653 GB, free: 219.6 MB / 0.67 GB
Notification: Performance of pre-optimization done (n=110): time 4.218e-06/1.191, allocations: 1.062 kB / 0.8653 GB, free: 219.6 MB / 0.67 GB
Notification: Performance of matching and sorting (n=110): time 0.003029/1.194, allocations: 1.571 MB / 0.8669 GB, free: 219.2 MB / 0.67 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.504e-05/1.194, allocations: 90.61 kB / 0.867 GB, free: 219.1 MB / 0.67 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001343/1.196, allocations: 1.195 MB / 0.8681 GB, free: 218.6 MB / 0.67 GB
Notification: Performance of collectPreVariables (initialization): time 8.334e-05/1.196, allocations: 48.36 kB / 0.8682 GB, free: 218.6 MB / 0.67 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0004557/1.196, allocations: 0.6753 MB / 0.8688 GB, free: 218.2 MB / 0.67 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0002869/1.197, allocations: 387.3 kB / 0.8692 GB, free: 217.9 MB / 0.67 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002745/1.197, allocations: 184 kB / 0.8694 GB, free: 217.8 MB / 0.67 GB
Notification: Performance of setup shared object (initialization): time 9.168e-05/1.197, allocations: 343.5 kB / 0.8697 GB, free: 217.5 MB / 0.67 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.000648/1.198, allocations: 430.5 kB / 0.8701 GB, free: 217.3 MB / 0.67 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0007502/1.198, allocations: 0.5555 MB / 0.8707 GB, free: 217 MB / 0.67 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001408/1.2, allocations: 1.02 MB / 0.8716 GB, free: 216.5 MB / 0.67 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.921e-06/1.2, allocations: 3.125 kB / 0.8716 GB, free: 216.5 MB / 0.67 GB
Notification: Performance of matching and sorting (n=126) (initialization): time 0.001924/1.202, allocations: 1.213 MB / 0.8728 GB, free: 216 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 2.139e-05/1.202, allocations: 6.938 kB / 0.8728 GB, free: 216 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.718e-06/1.202, allocations: 2.938 kB / 0.8728 GB, free: 216 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.372e-05/1.202, allocations: 5.875 kB / 0.8728 GB, free: 216 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0009617/1.203, allocations: 216 kB / 0.8731 GB, free: 216 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.504e-05/1.203, allocations: 16.67 kB / 0.8731 GB, free: 216 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001421/1.204, allocations: 169.1 kB / 0.8732 GB, free: 216 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 8.152e-05/1.204, allocations: 38.72 kB / 0.8733 GB, free: 216 MB / 0.67 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.001179/1.206, allocations: 0.7327 MB / 0.874 GB, free: 215.6 MB / 0.67 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.0007202/1.206, allocations: 0.5542 MB / 0.8745 GB, free: 215.3 MB / 0.67 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.001305/1.208, allocations: 0.9908 MB / 0.8755 GB, free: 214.8 MB / 0.67 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 2.946e-06/1.208, allocations: 3.75 kB / 0.8755 GB, free: 214.8 MB / 0.67 GB
Notification: Performance of matching and sorting (n=126) (initialization_lambda0): time 0.001807/1.209, allocations: 1.193 MB / 0.8767 GB, free: 214.3 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 1.37e-05/1.209, allocations: 5.219 kB / 0.8767 GB, free: 214.3 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.651e-06/1.209, allocations: 2.906 kB / 0.8767 GB, free: 214.3 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.036e-05/1.209, allocations: 8.766 kB / 0.8767 GB, free: 214.3 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0008759/1.21, allocations: 214.8 kB / 0.8769 GB, free: 214.3 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.136e-05/1.21, allocations: 10.2 kB / 0.8769 GB, free: 214.3 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001322/1.212, allocations: 146.2 kB / 0.877 GB, free: 214.3 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 7.19e-05/1.212, allocations: 40.05 kB / 0.8771 GB, free: 214.3 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.000287/1.212, allocations: 242.2 kB / 0.8773 GB, free: 214 MB / 0.67 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002013/1.212, allocations: 151 kB / 0.8774 GB, free: 214 MB / 0.67 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002022/1.214, allocations: 1.239 MB / 0.8787 GB, free: 213.4 MB / 0.67 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 9.408e-06/1.214, allocations: 8.688 kB / 0.8787 GB, free: 213.4 MB / 0.67 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 7.935e-06/1.214, allocations: 3.938 kB / 0.8787 GB, free: 213.4 MB / 0.67 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001403/1.216, allocations: 1.007 MB / 0.8797 GB, free: 213 MB / 0.67 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.004595/1.22, allocations: 3.159 MB / 0.8827 GB, free: 211.5 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.873e-06/1.22, allocations: 2.719 kB / 0.8827 GB, free: 211.5 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.00103/1.221, allocations: 233.4 kB / 0.883 GB, free: 211.5 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.039e-05/1.221, allocations: 6.438 kB / 0.883 GB, free: 211.5 MB / 0.67 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 7.514e-05/1.222, allocations: 32.72 kB / 0.883 GB, free: 211.4 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 9.337e-06/1.222, allocations: 9.094 kB / 0.883 GB, free: 211.4 MB / 0.67 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.036e-06/1.222, allocations: 3.281 kB / 0.883 GB, free: 211.4 MB / 0.67 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002605/1.224, allocations: 1.994 MB / 0.885 GB, free: 210.5 MB / 0.67 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0006393/1.225, allocations: 356.8 kB / 0.8853 GB, free: 210.2 MB / 0.67 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001875/1.225, allocations: 24.69 kB / 0.8853 GB, free: 210.2 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001041/1.226, allocations: 116.8 kB / 0.8854 GB, free: 210.2 MB / 0.67 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001545/1.226, allocations: 83.55 kB / 0.8855 GB, free: 210.2 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 7.44e-05/1.226, allocations: 37.14 kB / 0.8855 GB, free: 210.2 MB / 0.67 GB
Notification: Performance of sorting global known variables: time 0.0009526/1.227, allocations: 0.8297 MB / 0.8864 GB, free: 209.8 MB / 0.67 GB
Notification: Performance of sort global known variables: time 1.51e-07/1.227, allocations: 0 / 0.8864 GB, free: 209.8 MB / 0.67 GB
Notification: Performance of remove unused functions: time 0.001796/1.229, allocations: 0.8314 MB / 0.8872 GB, free: 209.7 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.001351/1.23, allocations: 0.5839 MB / 0.8877 GB, free: 209.6 MB / 0.67 GB
Notification: Performance of simCode: created initialization part: time 0.002656/1.233, allocations: 1.611 MB / 0.8893 GB, free: 209 MB / 0.67 GB
Notification: Performance of simCode: created event and clocks part: time 5.681e-06/1.233, allocations: 6.312 kB / 0.8893 GB, free: 209 MB / 0.67 GB
Notification: Performance of simCode: created simulation system equations: time 0.0009966/1.234, allocations: 0.5724 MB / 0.8899 GB, free: 208.8 MB / 0.67 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004008/1.238, allocations: 0.8201 MB / 0.8907 GB, free: 208.6 MB / 0.67 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.005867/1.244, allocations: 3.956 MB / 0.8945 GB, free: 206.3 MB / 0.67 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0009067/1.245, allocations: 1.365 MB / 0.8959 GB, free: 205.5 MB / 0.67 GB
Notification: Performance of simCode: alias equations: time 0.002089/1.247, allocations: 454.5 kB / 0.8963 GB, free: 205.4 MB / 0.67 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0004112/1.247, allocations: 115.3 kB / 0.8964 GB, free: 205.4 MB / 0.67 GB
Notification: Performance of SimCode: time 6.61e-07/1.247, allocations: 0 / 0.8964 GB, free: 205.4 MB / 0.67 GB
Notification: Performance of Templates: time 0.05835/1.306, allocations: 28.77 MB / 0.9245 GB, free: 199.2 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)