Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_master_IBPSA.Airflow.Multizone.Examples.ClosedDoors.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.ClosedDoors,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_master_IBPSA.Airflow.Multizone.Examples.ClosedDoors")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.003646/0.003646, allocations: 359.9 kB / 16.59 MB, free: 5.562 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.441/1.441, allocations: 165.8 MB / 183.1 MB, free: 5.914 MB / 90.72 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 4.702/4.702, allocations: 410.8 MB / 0.6001 GB, free: 2.191 MB / 410.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo): time 0.0018/0.0018, allocations: 180.6 kB / 0.6668 GB, free: 14.06 MB / 490.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004945/0.0004949, allocations: 11.98 kB / 0.7429 GB, free: 64 kB / 0.5417 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.5218/0.5223, allocations: 67.45 MB / 0.8087 GB, free: 245.5 MB / 0.5886 GB
Notification: Performance of NFInst.instantiate(IBPSA.Airflow.Multizone.Examples.ClosedDoors): time 0.2632/0.7856, allocations: 194.6 MB / 0.9988 GB, free: 62.66 MB / 0.5886 GB
Notification: Performance of NFInst.instExpressions: time 0.03259/0.8182, allocations: 19.64 MB / 1.018 GB, free: 43.02 MB / 0.5886 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002962/0.8213, allocations: 47.69 kB / 1.018 GB, free: 42.97 MB / 0.5886 GB
Notification: Performance of NFTyping.typeComponents: time 0.004457/0.8258, allocations: 0.9901 MB / 1.019 GB, free: 41.98 MB / 0.5886 GB
Notification: Performance of NFTyping.typeBindings: time 0.01384/0.8397, allocations: 3.692 MB / 1.023 GB, free: 38.27 MB / 0.5886 GB
Notification: Performance of NFTyping.typeClassSections: time 0.006278/0.846, allocations: 1.453 MB / 1.024 GB, free: 36.85 MB / 0.5886 GB
Notification: Performance of NFFlatten.flatten: time 0.01099/0.857, allocations: 7.967 MB / 1.032 GB, free: 28.86 MB / 0.5886 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.003575/0.8606, allocations: 1.728 MB / 1.033 GB, free: 27.13 MB / 0.5886 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.005288/0.866, allocations: 2.02 MB / 1.035 GB, free: 25.11 MB / 0.5886 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.005353/0.8714, allocations: 2.394 MB / 1.038 GB, free: 22.71 MB / 0.5886 GB
Notification: Performance of NFPackage.collectConstants: time 0.0008254/0.8723, allocations: 160 kB / 1.038 GB, free: 22.55 MB / 0.5886 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.006917/0.8792, allocations: 1.488 MB / 1.039 GB, free: 21.06 MB / 0.5886 GB
Notification: Performance of NFScalarize.scalarize: time 0.002542/0.8818, allocations: 1.262 MB / 1.041 GB, free: 19.79 MB / 0.5886 GB
Notification: Performance of NFVerifyModel.verify: time 0.001647/0.8835, allocations: 0.6329 MB / 1.041 GB, free: 19.15 MB / 0.5886 GB
Notification: Performance of NFConvertDAE.convert: time 0.006981/0.8905, allocations: 3.85 MB / 1.045 GB, free: 15.29 MB / 0.5886 GB
Notification: Performance of FrontEnd - DAE generated: time 7.935e-06/0.8905, allocations: 0 / 1.045 GB, free: 15.29 MB / 0.5886 GB
Notification: Performance of FrontEnd: time 2.836e-06/0.8906, allocations: 2.906 kB / 1.045 GB, free: 15.29 MB / 0.5886 GB
Notification: Performance of Transformations before backend: time 0.0001133/0.8907, allocations: 0 / 1.045 GB, free: 15.29 MB / 0.5886 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 697
 * Number of variables: 697
Notification: Performance of Generate backend data structure: time 0.0136/0.9043, allocations: 3.43 MB / 1.048 GB, free: 11.8 MB / 0.5886 GB
Notification: Performance of prepare preOptimizeDAE: time 5.024e-05/0.9044, allocations: 8.031 kB / 1.048 GB, free: 11.79 MB / 0.5886 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.007658/0.912, allocations: 0.8392 MB / 1.049 GB, free: 10.95 MB / 0.5886 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.005361/0.9175, allocations: 1.812 MB / 1.051 GB, free: 9.082 MB / 0.5886 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001733/0.9177, allocations: 209.3 kB / 1.051 GB, free: 8.875 MB / 0.5886 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0006628/0.9184, allocations: 271.9 kB / 1.051 GB, free: 8.609 MB / 0.5886 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01042/0.9288, allocations: 3.251 MB / 1.055 GB, free: 5.316 MB / 0.5886 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001193/0.929, allocations: 4 kB / 1.055 GB, free: 5.312 MB / 0.5886 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000432/0.9294, allocations: 116 kB / 1.055 GB, free: 5.199 MB / 0.5886 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001805/0.9296, allocations: 121.4 kB / 1.055 GB, free: 5.078 MB / 0.5886 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.3879/1.318, allocations: 4.592 MB / 1.059 GB, free: 233.4 MB / 0.5886 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.02822/1.346, allocations: 10.85 MB / 1.07 GB, free: 227.4 MB / 0.5886 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01112/1.357, allocations: 4.837 MB / 1.075 GB, free: 225.1 MB / 0.5886 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.007326/1.364, allocations: 2.511 MB / 1.077 GB, free: 224.2 MB / 0.5886 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001799/1.366, allocations: 0.8714 MB / 1.078 GB, free: 223.3 MB / 0.5886 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0001015/1.366, allocations: 78.95 kB / 1.078 GB, free: 223.2 MB / 0.5886 GB
Notification: Performance of pre-optimization done (n=399): time 7.243e-06/1.366, allocations: 0 / 1.078 GB, free: 223.2 MB / 0.5886 GB
Notification: Performance of matching and sorting (n=399): time 0.02036/1.387, allocations: 5.993 MB / 1.084 GB, free: 220.3 MB / 0.5886 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.288e-05/1.387, allocations: 92.86 kB / 1.084 GB, free: 220.2 MB / 0.5886 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00284/1.39, allocations: 1.211 MB / 1.085 GB, free: 219.6 MB / 0.5886 GB
Notification: Performance of collectPreVariables (initialization): time 0.0008069/1.391, allocations: 95.42 kB / 1.085 GB, free: 219.5 MB / 0.5886 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0007159/1.391, allocations: 0.803 MB / 1.086 GB, free: 218.8 MB / 0.5886 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002022/1.393, allocations: 1.332 MB / 1.087 GB, free: 217.6 MB / 0.5886 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.000316/1.394, allocations: 69.75 kB / 1.087 GB, free: 217.6 MB / 0.5886 GB
Notification: Performance of setup shared object (initialization): time 0.0003001/1.394, allocations: 442.9 kB / 1.088 GB, free: 217.2 MB / 0.5886 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005055/1.399, allocations: 1.589 MB / 1.089 GB, free: 216.4 MB / 0.5886 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.005793/1.405, allocations: 1.916 MB / 1.091 GB, free: 215.3 MB / 0.5886 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.006301/1.411, allocations: 1.886 MB / 1.093 GB, free: 214.3 MB / 0.5886 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.463e-05/1.411, allocations: 1.094 kB / 1.093 GB, free: 214.3 MB / 0.5886 GB
Notification: Performance of matching and sorting (n=430) (initialization): time 0.01398/1.425, allocations: 4.162 MB / 1.097 GB, free: 212.3 MB / 0.5886 GB
Notification: Performance of prepare postOptimizeDAE: time 4.063e-05/1.426, allocations: 9.516 kB / 1.097 GB, free: 212.3 MB / 0.5886 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.737e-05/1.426, allocations: 7.219 kB / 1.097 GB, free: 212.3 MB / 0.5886 GB
Notification: Performance of postOpt tearingSystem (initialization): time 4.954e-05/1.426, allocations: 17.97 kB / 1.097 GB, free: 212.3 MB / 0.5886 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.006299/1.432, allocations: 0.6836 MB / 1.098 GB, free: 211.7 MB / 0.5886 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 7.758e-05/1.432, allocations: 17.17 kB / 1.098 GB, free: 211.7 MB / 0.5886 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.005449/1.438, allocations: 363.3 kB / 1.098 GB, free: 211.4 MB / 0.5886 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0007582/1.438, allocations: 112 kB / 1.098 GB, free: 211.3 MB / 0.5886 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 14
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (430):
 * Single equations (assignments): 430
 * 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: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.002236/1.441, allocations: 0.7454 MB / 1.099 GB, free: 210.5 MB / 0.5886 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0008634/1.441, allocations: 233.2 kB / 1.099 GB, free: 210.3 MB / 0.5886 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01279/1.454, allocations: 4.607 MB / 1.104 GB, free: 205.7 MB / 0.5886 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.01041/1.465, allocations: 3.936 MB / 1.108 GB, free: 201.7 MB / 0.5886 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.714e-05/1.465, allocations: 4 kB / 1.108 GB, free: 201.7 MB / 0.5886 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.002596/1.467, allocations: 0.7548 MB / 1.108 GB, free: 200.9 MB / 0.5886 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0007737/1.468, allocations: 68 kB / 1.108 GB, free: 200.8 MB / 0.5886 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.004528/1.473, allocations: 228.9 kB / 1.109 GB, free: 200.6 MB / 0.5886 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.000798/1.474, allocations: 228.5 kB / 1.109 GB, free: 200.4 MB / 0.5886 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01447/1.488, allocations: 4.563 MB / 1.113 GB, free: 195.9 MB / 0.5886 GB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.01735/1.505, allocations: 7.104 MB / 1.12 GB, free: 188.8 MB / 0.5886 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.00482/1.51, allocations: 2.133 MB / 1.122 GB, free: 186.6 MB / 0.5886 GB
Notification: Performance of sorting global known variables: time 0.001778/1.512, allocations: 0.8366 MB / 1.123 GB, free: 185.8 MB / 0.5886 GB
Notification: Performance of Backend: time 6.51e-07/1.512, allocations: 3.938 kB / 1.123 GB, free: 185.8 MB / 0.5886 GB
Notification: Performance of simCode: created initialization part: time 0.01008/1.522, allocations: 3.037 MB / 1.126 GB, free: 182.9 MB / 0.5886 GB
Notification: Performance of SimCode: time 0.01667/1.539, allocations: 7.213 MB / 1.133 GB, free: 176 MB / 0.5886 GB
Notification: Performance of Templates: time 0.125/1.664, allocations: 52.95 MB / 1.185 GB, free: 123.9 MB / 0.5886 GB