Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_master_IBPSA.Airflow.Multizone.Examples.OneOpenDoor.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.OneOpenDoor,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_master_IBPSA.Airflow.Multizone.Examples.OneOpenDoor")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.003901/0.003908, allocations: 358.2 kB / 16.59 MB, free: 5.566 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.746/1.746, allocations: 165.8 MB / 183.1 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 4.152/4.152, allocations: 410.8 MB / 0.6001 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.001861/0.00186, allocations: 192.9 kB / 0.6668 GB, free: 6.898 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004881/0.0004885, allocations: 8 kB / 0.7429 GB, free: 8.938 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4404/0.4409, allocations: 67.44 MB / 0.8087 GB, free: 28.78 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(IBPSA.Airflow.Multizone.Examples.OneOpenDoor): time 0.6801/1.121, allocations: 151.6 MB / 0.9568 GB, free: 2.043 MB / 474.7 MB
Notification: Performance of NFInst.instExpressions: time 0.01719/1.138, allocations: 7.79 MB / 0.9644 GB, free: 1.945 MB / 474.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002502/1.141, allocations: 28.88 kB / 0.9644 GB, free: 1.945 MB / 474.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.003087/1.144, allocations: 0.4921 MB / 0.9649 GB, free: 1.945 MB / 474.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.01146/1.156, allocations: 1.621 MB / 0.9665 GB, free: 1.922 MB / 474.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.007693/1.163, allocations: 0.9842 MB / 0.9675 GB, free: 1.895 MB / 474.7 MB
Notification: Performance of NFFlatten.flatten: time 0.01036/1.174, allocations: 4.563 MB / 0.9719 GB, free: 1.875 MB / 474.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.003265/1.177, allocations: 0.9907 MB / 0.9729 GB, free: 1.773 MB / 474.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.004426/1.182, allocations: 1.204 MB / 0.9741 GB, free: 1.77 MB / 474.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.004371/1.186, allocations: 1.456 MB / 0.9755 GB, free: 1.762 MB / 474.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.00065/1.187, allocations: 89.56 kB / 0.9756 GB, free: 1.762 MB / 474.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003975/1.191, allocations: 0.8248 MB / 0.9764 GB, free: 1.754 MB / 474.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.001438/1.192, allocations: 0.5564 MB / 0.9769 GB, free: 1.754 MB / 474.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.001196/1.193, allocations: 368.3 kB / 0.9773 GB, free: 1.719 MB / 474.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.004219/1.198, allocations: 1.98 MB / 0.9792 GB, free: 1.031 MB / 474.7 MB
Notification: Performance of FrontEnd - DAE generated: time 7.594e-06/1.198, allocations: 0 / 0.9792 GB, free: 1.031 MB / 474.7 MB
Notification: Performance of FrontEnd: time 3.026e-06/1.198, allocations: 0 / 0.9792 GB, free: 1.031 MB / 474.7 MB
Notification: Performance of Transformations before backend: time 0.0001047/1.198, allocations: 0 / 0.9792 GB, free: 1.031 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: 385
 * Number of variables: 385
Notification: Performance of Generate backend data structure: time 0.007815/1.206, allocations: 2.018 MB / 0.9812 GB, free: 15.81 MB / 490.7 MB
Notification: Performance of prepare preOptimizeDAE: time 6.217e-05/1.206, allocations: 8.781 kB / 0.9812 GB, free: 15.81 MB / 490.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001346/1.207, allocations: 198.9 kB / 0.9814 GB, free: 15.71 MB / 490.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003857/1.211, allocations: 1.127 MB / 0.9825 GB, free: 15.18 MB / 490.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001263/1.211, allocations: 112.7 kB / 0.9826 GB, free: 15.18 MB / 490.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004529/1.212, allocations: 148.7 kB / 0.9827 GB, free: 15.18 MB / 490.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.006274/1.218, allocations: 1.921 MB / 0.9846 GB, free: 14.66 MB / 490.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 7.507e-05/1.218, allocations: 0 / 0.9846 GB, free: 14.66 MB / 490.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002299/1.218, allocations: 64.22 kB / 0.9847 GB, free: 14.66 MB / 490.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.691e-05/1.218, allocations: 34.41 kB / 0.9847 GB, free: 14.66 MB / 490.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.007783/1.226, allocations: 2.482 MB / 0.9871 GB, free: 14.14 MB / 490.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.0162/1.243, allocations: 6.098 MB / 0.9931 GB, free: 10.7 MB / 490.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.006886/1.249, allocations: 2.799 MB / 0.9958 GB, free: 10.29 MB / 490.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004094/1.254, allocations: 1.414 MB / 0.9972 GB, free: 10.09 MB / 490.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.002695/1.256, allocations: 1.753 MB / 0.9989 GB, free: 8.141 MB / 490.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 8.153e-05/1.257, allocations: 53.91 kB / 0.9989 GB, free: 8.098 MB / 490.7 MB
Notification: Performance of pre-optimization done (n=237): time 6.102e-06/1.257, allocations: 0 / 0.9989 GB, free: 8.098 MB / 490.7 MB
Notification: Performance of matching and sorting (n=237): time 0.01193/1.268, allocations: 3.443 MB / 1.002 GB, free: 6.016 MB / 490.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.032e-05/1.269, allocations: 75.27 kB / 1.002 GB, free: 5.934 MB / 490.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001656/1.27, allocations: 0.7513 MB / 1.003 GB, free: 5.359 MB / 490.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0004534/1.271, allocations: 65.33 kB / 1.003 GB, free: 5.32 MB / 490.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0004791/1.271, allocations: 485.8 kB / 1.004 GB, free: 4.992 MB / 490.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.001301/1.273, allocations: 0.7837 MB / 1.004 GB, free: 4.316 MB / 490.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001654/1.273, allocations: 40.62 kB / 1.004 GB, free: 4.309 MB / 490.7 MB
Notification: Performance of setup shared object (initialization): time 0.0002494/1.273, allocations: 388.8 kB / 1.005 GB, free: 3.957 MB / 490.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002997/1.276, allocations: 0.9012 MB / 1.006 GB, free: 3.348 MB / 490.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.003361/1.279, allocations: 1.117 MB / 1.007 GB, free: 2.5 MB / 490.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.003434/1.283, allocations: 1.082 MB / 1.008 GB, free: 1.828 MB / 490.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.809e-05/1.283, allocations: 0 / 1.008 GB, free: 1.828 MB / 490.7 MB
Notification: Performance of matching and sorting (n=261) (initialization): time 0.008043/1.291, allocations: 2.43 MB / 1.01 GB, free: 152 kB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 3.695e-05/1.291, allocations: 8 kB / 1.01 GB, free: 144 kB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.335e-05/1.291, allocations: 8.453 kB / 1.01 GB, free: 136 kB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 3.679e-05/1.291, allocations: 12 kB / 1.01 GB, free: 124 kB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003913/1.295, allocations: 446.6 kB / 1.011 GB, free: 15.73 MB / 0.4948 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 5.799e-05/1.295, allocations: 14.53 kB / 1.011 GB, free: 15.71 MB / 0.4948 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002635/1.298, allocations: 211.6 kB / 1.011 GB, free: 15.54 MB / 0.4948 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0004509/1.298, allocations: 72.11 kB / 1.011 GB, free: 15.48 MB / 0.4948 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 16
 * 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 (261):
 * Single equations (assignments): 261
 * 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.001339/1.3, allocations: 440.5 kB / 1.011 GB, free: 15.21 MB / 0.4948 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005507/1.3, allocations: 142.3 kB / 1.012 GB, free: 15.07 MB / 0.4948 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.008832/1.309, allocations: 2.735 MB / 1.014 GB, free: 13.23 MB / 0.4948 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.007092/1.316, allocations: 2.39 MB / 1.017 GB, free: 11.5 MB / 0.4948 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.574e-05/1.316, allocations: 4 kB / 1.017 GB, free: 11.49 MB / 0.4948 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001853/1.318, allocations: 488.7 kB / 1.017 GB, free: 11.09 MB / 0.4948 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0005006/1.319, allocations: 38.88 kB / 1.017 GB, free: 11.08 MB / 0.4948 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002409/1.321, allocations: 110.5 kB / 1.017 GB, free: 11 MB / 0.4948 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0005487/1.322, allocations: 132.7 kB / 1.017 GB, free: 10.89 MB / 0.4948 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.009318/1.331, allocations: 2.651 MB / 1.02 GB, free: 9.059 MB / 0.4948 GB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.01155/1.343, allocations: 4.006 MB / 1.024 GB, free: 6.309 MB / 0.4948 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.003383/1.346, allocations: 1.201 MB / 1.025 GB, free: 5.539 MB / 0.4948 GB
Notification: Performance of sorting global known variables: time 0.001184/1.347, allocations: 0.5296 MB / 1.025 GB, free: 5.148 MB / 0.4948 GB
Notification: Performance of Backend: time 8.42e-07/1.348, allocations: 0 / 1.025 GB, free: 5.148 MB / 0.4948 GB
Notification: Performance of simCode: created initialization part: time 0.006331/1.354, allocations: 1.731 MB / 1.027 GB, free: 3.914 MB / 0.4948 GB
Notification: Performance of SimCode: time 0.0108/1.365, allocations: 4.273 MB / 1.031 GB, free: 15.84 MB / 0.5105 GB
Notification: Performance of Templates: time 0.08221/1.447, allocations: 27.69 MB / 1.058 GB, free: 4.391 MB / 0.5261 GB