Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_master_IBPSA.Airflow.Multizone.Examples.ChimneyShaftNoVolume.conf.json
Using package ModelicaServices with version 3.2.3 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo)
Using package Modelica with version 3.2.3 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo)
Using package IBPSA with version 3.0.0 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 3.0.0-master/package.mo)
Using package Complex with version 3.2.3 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo)
Running command: translateModel(IBPSA.Airflow.Multizone.Examples.ChimneyShaftNoVolume,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_master_IBPSA.Airflow.Multizone.Examples.ChimneyShaftNoVolume")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.002551/0.00255, allocations: 356.5 kB / 15.94 MB, free: 6.207 MB / 13.93 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 3.0.0-master/package.mo): time 1.455/1.455, allocations: 165.8 MB / 182.5 MB, free: 5.461 MB / 138.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 3.612/3.612, allocations: 410.8 MB / 0.5995 GB, free: 15.05 MB / 298.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo): time 0.001783/0.001782, allocations: 197.2 kB / 0.6662 GB, free: 6.891 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004642/0.0004645, allocations: 16 kB / 0.7423 GB, free: 8.934 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3295/0.33, allocations: 67.43 MB / 0.8081 GB, free: 28.77 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(IBPSA.Airflow.Multizone.Examples.ChimneyShaftNoVolume): time 0.5466/0.8767, allocations: 152.2 MB / 0.9567 GB, free: 2.023 MB / 474.7 MB
Notification: Performance of NFInst.instExpressions: time 0.01346/0.8901, allocations: 8.061 MB / 0.9646 GB, free: 1.91 MB / 474.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001929/0.8921, allocations: 34.09 kB / 0.9646 GB, free: 1.91 MB / 474.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.001872/0.894, allocations: 0.5519 MB / 0.9652 GB, free: 1.91 MB / 474.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.005378/0.8994, allocations: 1.519 MB / 0.9667 GB, free: 1.891 MB / 474.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004511/0.9039, allocations: 1.248 MB / 0.9679 GB, free: 1.863 MB / 474.7 MB
Notification: Performance of NFFlatten.flatten: time 0.005847/0.9098, allocations: 3.1 MB / 0.9709 GB, free: 1.859 MB / 474.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002252/0.9121, allocations: 0.7122 MB / 0.9716 GB, free: 1.777 MB / 474.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002541/0.9147, allocations: 0.8274 MB / 0.9724 GB, free: 1.777 MB / 474.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002462/0.9172, allocations: 0.9273 MB / 0.9733 GB, free: 1.766 MB / 474.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0003208/0.9175, allocations: 43.28 kB / 0.9734 GB, free: 1.766 MB / 474.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.004153/0.9217, allocations: 0.8268 MB / 0.9742 GB, free: 1.762 MB / 474.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0003898/0.9221, allocations: 211.9 kB / 0.9744 GB, free: 1.762 MB / 474.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0004896/0.9226, allocations: 244.3 kB / 0.9746 GB, free: 1.73 MB / 474.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.00202/0.9247, allocations: 1.224 MB / 0.9758 GB, free: 1.617 MB / 474.7 MB
Notification: Performance of FrontEnd - DAE generated: time 4.928e-06/0.9247, allocations: 0 / 0.9758 GB, free: 1.617 MB / 474.7 MB
Notification: Performance of FrontEnd: time 2.112e-06/0.9247, allocations: 0 / 0.9758 GB, free: 1.617 MB / 474.7 MB
Notification: Performance of Transformations before backend: time 2.395e-05/0.9248, allocations: 0 / 0.9758 GB, free: 1.617 MB / 474.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 195
 * Number of variables: 195
Notification: Performance of Generate backend data structure: time 0.002774/0.9275, allocations: 1.381 MB / 0.9771 GB, free: 0.8008 MB / 474.7 MB
Notification: Performance of prepare preOptimizeDAE: time 3.772e-05/0.9276, allocations: 8.625 kB / 0.9772 GB, free: 0.8008 MB / 474.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0005266/0.9281, allocations: 138.9 kB / 0.9773 GB, free: 0.7344 MB / 474.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001785/0.9299, allocations: 0.8202 MB / 0.9781 GB, free: 432 kB / 474.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 6.996e-05/0.93, allocations: 58.56 kB / 0.9781 GB, free: 428 kB / 474.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001319/0.9302, allocations: 81.11 kB / 0.9782 GB, free: 424 kB / 474.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.00178/0.932, allocations: 0.8678 MB / 0.9791 GB, free: 256 kB / 474.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 3.332e-05/0.932, allocations: 0 / 0.9791 GB, free: 256 kB / 474.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 9.032e-05/0.9321, allocations: 33.41 kB / 0.9791 GB, free: 256 kB / 474.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 1.962e-05/0.9322, allocations: 20.72 kB / 0.9791 GB, free: 256 kB / 474.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001526/0.9337, allocations: 0.6341 MB / 0.9797 GB, free: 200 kB / 474.7 MB
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.007821/0.9415, allocations: 4.017 MB / 0.9837 GB, free: 13.62 MB / 490.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.001487/0.9431, allocations: 0.581 MB / 0.9842 GB, free: 13.53 MB / 490.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0006665/0.9438, allocations: 301.5 kB / 0.9845 GB, free: 13.49 MB / 490.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.00127/0.945, allocations: 1.248 MB / 0.9857 GB, free: 12.05 MB / 490.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0007903/0.9459, allocations: 325.5 kB / 0.986 GB, free: 11.93 MB / 490.7 MB
Notification: Performance of pre-optimization done (n=63): time 4.346e-06/0.9459, allocations: 0 / 0.986 GB, free: 11.93 MB / 490.7 MB
Notification: Performance of matching and sorting (n=63): time 0.003292/0.9492, allocations: 1.156 MB / 0.9872 GB, free: 11.49 MB / 490.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.099e-05/0.9493, allocations: 83.89 kB / 0.9873 GB, free: 11.39 MB / 490.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001363/0.9507, allocations: 0.8575 MB / 0.9881 GB, free: 11.12 MB / 490.7 MB
Notification: Performance of collectPreVariables (initialization): time 9.491e-05/0.9508, allocations: 40.7 kB / 0.9881 GB, free: 11.09 MB / 490.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0004288/0.9512, allocations: 473.1 kB / 0.9886 GB, free: 10.88 MB / 490.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0002549/0.9515, allocations: 216.8 kB / 0.9888 GB, free: 10.72 MB / 490.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 3.827e-05/0.9515, allocations: 9.25 kB / 0.9888 GB, free: 10.72 MB / 490.7 MB
Notification: Performance of setup shared object (initialization): time 0.0001941/0.9517, allocations: 335.6 kB / 0.9891 GB, free: 10.4 MB / 490.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0005601/0.9523, allocations: 207 kB / 0.9893 GB, free: 10.37 MB / 490.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0006395/0.953, allocations: 316.9 kB / 0.9896 GB, free: 10.24 MB / 490.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0006073/0.9536, allocations: 242.6 kB / 0.9899 GB, free: 10.22 MB / 490.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.88e-06/0.9536, allocations: 5.016 kB / 0.9899 GB, free: 10.22 MB / 490.7 MB
Notification: Performance of matching and sorting (n=75) (initialization): time 0.001812/0.9554, allocations: 0.7129 MB / 0.9906 GB, free: 10.05 MB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 0.000127/0.9556, allocations: 154.5 kB / 0.9907 GB, free: 9.887 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.462e-06/0.9556, allocations: 3.125 kB / 0.9907 GB, free: 9.887 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0006907/0.9563, allocations: 106.7 kB / 0.9908 GB, free: 9.855 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0006015/0.9569, allocations: 119.8 kB / 0.9909 GB, free: 9.84 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0007853/0.9577, allocations: 0.658 MB / 0.9916 GB, free: 9.297 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0008865/0.9586, allocations: 100.8 kB / 0.9917 GB, free: 9.25 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 8.678e-05/0.9588, allocations: 31.52 kB / 0.9917 GB, free: 9.242 MB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 1.098e-05/0.9588, allocations: 3.156 kB / 0.9917 GB, free: 9.242 MB / 490.7 MB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.001403/0.9602, allocations: 0.5679 MB / 0.9922 GB, free: 9.172 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 8.557e-06/0.9602, allocations: 2.594 kB / 0.9922 GB, free: 9.172 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0006187/0.9608, allocations: 112.4 kB / 0.9924 GB, free: 9.141 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0005649/0.9614, allocations: 106.2 kB / 0.9925 GB, free: 9.129 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0007698/0.9622, allocations: 0.6669 MB / 0.9931 GB, free: 8.582 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0008875/0.9631, allocations: 91.41 kB / 0.9932 GB, free: 8.535 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 8.066e-05/0.9632, allocations: 30.48 kB / 0.9932 GB, free: 8.531 MB / 490.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 10
 * Number of states: 0 ()
 * Number of discrete variables: 5 ($whenCondition1,$PRE.mRoo_flow.nextTimeEvent,mRoo_flow.nextTimeEvent,$PRE.mRoo_flow.nextTimeEventScaled,mRoo_flow.nextTimeEventScaled)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (67):
 * Single equations (assignments): 66
 * 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 {2 7}
Notification: Performance of prepare postOptimizeDAE: time 0.0006301/0.9639, allocations: 325.8 kB / 0.9935 GB, free: 8.332 MB / 490.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001944/0.9641, allocations: 96.62 kB / 0.9936 GB, free: 8.281 MB / 490.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002334/0.9664, allocations: 0.9515 MB / 0.9946 GB, free: 8.027 MB / 490.7 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.5e-05/0.9665, allocations: 5.719 kB / 0.9946 GB, free: 8.027 MB / 490.7 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 7.442e-06/0.9665, allocations: 3.125 kB / 0.9946 GB, free: 8.027 MB / 490.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001722/0.9682, allocations: 0.8575 MB / 0.9954 GB, free: 7.672 MB / 490.7 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.003844/0.9721, allocations: 1.863 MB / 0.9972 GB, free: 6.953 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 9.896e-06/0.9721, allocations: 3.594 kB / 0.9972 GB, free: 6.953 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0005774/0.9727, allocations: 100.6 kB / 0.9973 GB, free: 6.941 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0008111/0.9735, allocations: 161.3 kB / 0.9975 GB, free: 6.891 MB / 490.7 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 6.585e-05/0.9736, allocations: 24.45 kB / 0.9975 GB, free: 6.883 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.001253/0.9749, allocations: 1.239 MB / 0.9987 GB, free: 5.812 MB / 490.7 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.996e-06/0.9749, allocations: 0 / 0.9987 GB, free: 5.812 MB / 490.7 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002821/0.9777, allocations: 1.285 MB / 1 GB, free: 5.449 MB / 490.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0005672/0.9783, allocations: 272.6 kB / 1 GB, free: 5.254 MB / 490.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001546/0.9785, allocations: 11.41 kB / 1 GB, free: 5.254 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.000719/0.9792, allocations: 65.67 kB / 1 GB, free: 5.23 MB / 490.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001286/0.9794, allocations: 57.2 kB / 1 GB, free: 5.207 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 7.794e-05/0.9795, allocations: 27.17 kB / 1 GB, free: 5.203 MB / 490.7 MB
Notification: Performance of sorting global known variables: time 0.0009912/0.9805, allocations: 0.6005 MB / 1.001 GB, free: 5.004 MB / 490.7 MB
Notification: Performance of sort global known variables: time 7.36e-07/0.9805, allocations: 0 / 1.001 GB, free: 5.004 MB / 490.7 MB
Notification: Performance of remove unused functions: time 0.001666/0.9822, allocations: 0.5536 MB / 1.002 GB, free: 4.906 MB / 490.7 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 3
 * Number of states: 5 (roo.dynBal.U,roo.dynBal.m,oriChiTop.mExc,oriBot.mExc,oriChiBot.mExc)
 * 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 (62):
 * Single equations (assignments): 54
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 4
 * 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: 0
 * Non-linear torn systems: 2 {2 9,1 2}
Notification: Performance of Backend phase and start with SimCode phase: time 0.002065/0.9842, allocations: 0.6897 MB / 1.002 GB, free: 4.621 MB / 490.7 MB
Notification: Performance of simCode: created initialization part: time 0.00251/0.9868, allocations: 0.9187 MB / 1.003 GB, free: 4.238 MB / 490.7 MB
Notification: Performance of simCode: created event and clocks part: time 5.606e-06/0.9868, allocations: 0 / 1.003 GB, free: 4.238 MB / 490.7 MB
Notification: Performance of simCode: created simulation system equations: time 0.001077/0.9879, allocations: 395.5 kB / 1.003 GB, free: 3.957 MB / 490.7 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002662/0.9906, allocations: 425.8 kB / 1.004 GB, free: 3.625 MB / 490.7 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.004858/0.9955, allocations: 2.531 MB / 1.006 GB, free: 1.246 MB / 490.7 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0009375/0.9964, allocations: 1.095 MB / 1.007 GB, free: 140 kB / 490.7 MB
Notification: Performance of simCode: alias equations: time 0.001275/0.9977, allocations: 211.1 kB / 1.008 GB, free: 15.93 MB / 0.4948 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0002792/0.998, allocations: 80.88 kB / 1.008 GB, free: 15.85 MB / 0.4948 GB
Notification: Performance of SimCode: time 1.693e-06/0.998, allocations: 0 / 1.008 GB, free: 15.85 MB / 0.4948 GB
Notification: Performance of Templates: time 0.04773/1.046, allocations: 19.38 MB / 1.027 GB, free: 12.75 MB / 0.5105 GB