Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr Annex60_1.0.0_Annex60.Airflow.Multizone.Examples.ClosedDoors.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 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Annex60 1.0.0/package.mo", uses=false)
Using package Annex60 with version 1.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Annex60 1.0.0/package.mo)
Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+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(Annex60.Airflow.Multizone.Examples.ClosedDoors,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Annex60_1.0.0_Annex60.Airflow.Multizone.Examples.ClosedDoors")
translateModel(Annex60.Airflow.Multizone.Examples.ClosedDoors,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Annex60_1.0.0_Annex60.Airflow.Multizone.Examples.ClosedDoors")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0008614/0.0008613, allocations: 91.61 kB / 15.06 MB, free: 1.098 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.0009021/0.0009021, allocations: 172.7 kB / 15.95 MB, free: 208 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.603/1.603, allocations: 205.4 MB / 222.1 MB, free: 5.66 MB / 174.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Annex60 1.0.0/package.mo): time 0.4273/0.4273, allocations: 55.02 MB / 325.4 MB, free: 0.6289 MB / 254.1 MB
Notification: Modelica requested package Complex of version 3.2.3. Complex 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Modelica requested package ModelicaServices of version 3.2.3. ModelicaServices 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Annex60 requested package Modelica of version 3.2.2. Modelica 3.2.3 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.0005761/0.0005763, allocations: 12 kB / 396.6 MB, free: 11.71 MB / 334.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4269/0.4275, allocations: 60.72 MB / 457.4 MB, free: 38.29 MB / 382.1 MB
Notification: Performance of NFInst.instantiate(Annex60.Airflow.Multizone.Examples.ClosedDoors): time 0.2413/0.6688, allocations: 198.7 MB / 0.6406 GB, free: 500 kB / 0.4981 GB
Notification: Performance of NFInst.instExpressions: time 0.4179/1.087, allocations: 19.9 MB / 0.6601 GB, free: 5.625 MB / 0.4981 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003208/1.09, allocations: 50.28 kB / 0.6601 GB, free: 5.625 MB / 0.4981 GB
Notification: Performance of NFTyping.typeComponents: time 0.002956/1.093, allocations: 0.8077 MB / 0.6609 GB, free: 5.609 MB / 0.4981 GB
Notification: Performance of NFTyping.typeBindings: time 0.01015/1.103, allocations: 2.963 MB / 0.6638 GB, free: 5.48 MB / 0.4981 GB
Notification: Performance of NFTyping.typeClassSections: time 0.004089/1.107, allocations: 1.083 MB / 0.6649 GB, free: 5.449 MB / 0.4981 GB
Notification: Performance of NFFlatten.flatten: time 0.01316/1.12, allocations: 8.152 MB / 0.6728 GB, free: 5.422 MB / 0.4981 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.005375/1.126, allocations: 1.767 MB / 0.6745 GB, free: 21.23 MB / 0.5137 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.006725/1.133, allocations: 2.108 MB / 0.6766 GB, free: 21.23 MB / 0.5137 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.006208/1.139, allocations: 2.416 MB / 0.679 GB, free: 21.22 MB / 0.5137 GB
Notification: Performance of NFPackage.collectConstants: time 0.0008156/1.14, allocations: 161.7 kB / 0.6791 GB, free: 21.21 MB / 0.5137 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.007144/1.147, allocations: 1.592 MB / 0.6807 GB, free: 21.18 MB / 0.5137 GB
Notification: Performance of NFScalarize.scalarize: time 0.002123/1.149, allocations: 1.142 MB / 0.6818 GB, free: 21.18 MB / 0.5137 GB
Notification: Performance of NFVerifyModel.verify: time 0.002195/1.151, allocations: 0.5862 MB / 0.6824 GB, free: 21.15 MB / 0.5137 GB
Notification: Performance of NFConvertDAE.convert: time 0.01024/1.161, allocations: 3.728 MB / 0.686 GB, free: 20.52 MB / 0.5137 GB
Notification: Performance of FrontEnd - DAE generated: time 7.955e-06/1.162, allocations: 0 / 0.686 GB, free: 20.52 MB / 0.5137 GB
Notification: Performance of FrontEnd: time 4.529e-06/1.162, allocations: 0.625 kB / 0.686 GB, free: 20.52 MB / 0.5137 GB
Notification: Performance of Transformations before backend: time 0.0001969/1.162, allocations: 0 / 0.686 GB, free: 20.52 MB / 0.5137 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 652
 * Number of variables: 652
Notification: Performance of Generate backend data structure: time 0.01369/1.175, allocations: 3.314 MB / 0.6892 GB, free: 18.67 MB / 0.5137 GB
Notification: Performance of prepare preOptimizeDAE: time 4.91e-05/1.176, allocations: 12.23 kB / 0.6892 GB, free: 18.66 MB / 0.5137 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.006036/1.182, allocations: 0.7631 MB / 0.69 GB, free: 18.35 MB / 0.5137 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.005285/1.187, allocations: 1.542 MB / 0.6915 GB, free: 17.68 MB / 0.5137 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002656/1.187, allocations: 188.9 kB / 0.6917 GB, free: 17.67 MB / 0.5137 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.00162/1.189, allocations: 252.2 kB / 0.6919 GB, free: 17.67 MB / 0.5137 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01193/1.201, allocations: 3.157 MB / 0.695 GB, free: 16.88 MB / 0.5137 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002077/1.201, allocations: 3.906 kB / 0.695 GB, free: 16.87 MB / 0.5137 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001432/1.203, allocations: 102.6 kB / 0.6951 GB, free: 16.87 MB / 0.5137 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001998/1.203, allocations: 120 kB / 0.6952 GB, free: 16.86 MB / 0.5137 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01287/1.216, allocations: 4.25 MB / 0.6994 GB, free: 16.11 MB / 0.5137 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.02622/1.242, allocations: 10.53 MB / 0.7097 GB, free: 10.44 MB / 0.5137 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0112/1.253, allocations: 4.396 MB / 0.7139 GB, free: 9.773 MB / 0.5137 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.005589/1.259, allocations: 2.413 MB / 0.7163 GB, free: 9.453 MB / 0.5137 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001487/1.26, allocations: 0.7782 MB / 0.7171 GB, free: 8.66 MB / 0.5137 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 9.687e-05/1.26, allocations: 84.94 kB / 0.7171 GB, free: 8.613 MB / 0.5137 GB
Notification: Performance of pre-optimization done (n=366): time 6.672e-06/1.26, allocations: 1.969 kB / 0.7171 GB, free: 8.613 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=366): time 0.01637/1.277, allocations: 5.669 MB / 0.7227 GB, free: 4.754 MB / 0.5137 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 9.078e-05/1.277, allocations: 90.28 kB / 0.7228 GB, free: 4.652 MB / 0.5137 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001902/1.279, allocations: 0.9102 MB / 0.7237 GB, free: 3.953 MB / 0.5137 GB
Notification: Performance of collectPreVariables (initialization): time 0.001009/1.28, allocations: 90.45 kB / 0.7237 GB, free: 3.906 MB / 0.5137 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0007032/1.281, allocations: 0.709 MB / 0.7244 GB, free: 3.422 MB / 0.5137 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002283/1.283, allocations: 1.23 MB / 0.7256 GB, free: 2.363 MB / 0.5137 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0007215/1.284, allocations: 60 kB / 0.7257 GB, free: 2.352 MB / 0.5137 GB
Notification: Performance of setup shared object (initialization): time 0.0004738/1.284, allocations: 425.9 kB / 0.7261 GB, free: 1.977 MB / 0.5137 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.003597/1.288, allocations: 1.512 MB / 0.7276 GB, free: 1.008 MB / 0.5137 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.003822/1.292, allocations: 1.797 MB / 0.7293 GB, free: 15.76 MB / 0.5294 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.003988/1.296, allocations: 1.784 MB / 0.7311 GB, free: 14.7 MB / 0.5294 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.653e-05/1.296, allocations: 0 / 0.7311 GB, free: 14.7 MB / 0.5294 GB
Notification: Performance of matching and sorting (n=395) (initialization): time 0.009794/1.306, allocations: 3.917 MB / 0.7349 GB, free: 12.08 MB / 0.5294 GB
Notification: Performance of prepare postOptimizeDAE: time 3.22e-05/1.306, allocations: 4 kB / 0.7349 GB, free: 12.08 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.081e-05/1.306, allocations: 4 kB / 0.7349 GB, free: 12.07 MB / 0.5294 GB
Notification: Performance of postOpt tearingSystem (initialization): time 4.466e-05/1.306, allocations: 16 kB / 0.7349 GB, free: 12.06 MB / 0.5294 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00354/1.309, allocations: 0.633 MB / 0.7355 GB, free: 11.48 MB / 0.5294 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 7.262e-05/1.309, allocations: 25.08 kB / 0.7356 GB, free: 11.46 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003844/1.313, allocations: 341.3 kB / 0.7359 GB, free: 11.18 MB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001037/1.314, allocations: 110.9 kB / 0.736 GB, free: 11.09 MB / 0.5294 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 12
 * 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 (395):
 * Single equations (assignments): 395
 * 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.004427/1.319, allocations: 0.658 MB / 0.7366 GB, free: 10.7 MB / 0.5294 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001928/1.321, allocations: 213.4 kB / 0.7368 GB, free: 10.49 MB / 0.5294 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01331/1.334, allocations: 4.583 MB / 0.7413 GB, free: 7.578 MB / 0.5294 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.628e-05/1.334, allocations: 36 kB / 0.7413 GB, free: 7.543 MB / 0.5294 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.863e-05/1.334, allocations: 0 / 0.7413 GB, free: 7.543 MB / 0.5294 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.008905/1.343, allocations: 3.877 MB / 0.7451 GB, free: 4.93 MB / 0.5294 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.02274/1.366, allocations: 8.011 MB / 0.753 GB, free: 15.58 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.917e-05/1.366, allocations: 0.875 kB / 0.753 GB, free: 15.58 MB / 0.545 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.005453/1.371, allocations: 0.6655 MB / 0.7536 GB, free: 14.98 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0007272/1.372, allocations: 137.9 kB / 0.7537 GB, free: 14.86 MB / 0.545 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0004947/1.373, allocations: 80.2 kB / 0.7538 GB, free: 14.83 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.006039/1.379, allocations: 4.543 MB / 0.7583 GB, free: 10.29 MB / 0.545 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.104e-06/1.379, allocations: 0 / 0.7583 GB, free: 10.29 MB / 0.545 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01864/1.397, allocations: 6.861 MB / 0.765 GB, free: 5.82 MB / 0.545 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.003403/1.401, allocations: 0.7259 MB / 0.7657 GB, free: 5.242 MB / 0.545 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0009405/1.402, allocations: 68.62 kB / 0.7657 GB, free: 5.227 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00446/1.406, allocations: 212.8 kB / 0.7659 GB, free: 5.066 MB / 0.545 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0007681/1.407, allocations: 226.1 kB / 0.7661 GB, free: 4.875 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0004253/1.408, allocations: 114.2 kB / 0.7663 GB, free: 4.789 MB / 0.545 GB
Notification: Performance of sorting global known variables: time 0.001552/1.409, allocations: 0.6177 MB / 0.7669 GB, free: 4.32 MB / 0.545 GB
Notification: Performance of sort global known variables: time 3.2e-07/1.409, allocations: 0 / 0.7669 GB, free: 4.32 MB / 0.545 GB
Notification: Performance of remove unused functions: time 0.005908/1.415, allocations: 1.046 MB / 0.7679 GB, free: 3.379 MB / 0.545 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 1
 * Number of states: 15 (dooAB.mExcAB,dooAB.mExcBA,volA.dynBal.U,volA.dynBal.m,volA.dynBal.mXi[1],volB.dynBal.U,volB.dynBal.m,volB.dynBal.mXi[1],volC.dynBal.U,volC.dynBal.m,volC.dynBal.mXi[1],dooAC.mExcAB,dooAC.mExcBA,dooBC.mExcAB,dooBC.mExcBA)
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (390):
 * Single equations (assignments): 387
 * 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: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 3 {(1,100.0%) 2,(1,100.0%) 2,(1,100.0%) 2}
 * Non-linear torn systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.001222/1.416, allocations: 341.5 kB / 0.7682 GB, free: 3.137 MB / 0.545 GB
Notification: Performance of simCode: created initialization part: time 0.01117/1.428, allocations: 3.875 MB / 0.772 GB, free: 0.5117 MB / 0.545 GB
Notification: Performance of simCode: created event and clocks part: time 1.209e-05/1.428, allocations: 128 / 0.772 GB, free: 0.5117 MB / 0.545 GB
Notification: Performance of simCode: created simulation system equations: time 0.006514/1.434, allocations: 2.529 MB / 0.7745 GB, free: 14.72 MB / 0.5606 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.008749/1.443, allocations: 1.019 MB / 0.7755 GB, free: 13.95 MB / 0.5606 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01881/1.462, allocations: 7.478 MB / 0.7828 GB, free: 7.379 MB / 0.5606 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.002402/1.464, allocations: 1.833 MB / 0.7845 GB, free: 5.602 MB / 0.5606 GB
Notification: Performance of simCode: alias equations: time 0.007934/1.472, allocations: 1.105 MB / 0.7856 GB, free: 4.492 MB / 0.5606 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001855/1.474, allocations: 234.1 kB / 0.7858 GB, free: 4.277 MB / 0.5606 GB
Notification: Performance of SimCode: time 2.925e-06/1.474, allocations: 8 kB / 0.7859 GB, free: 4.27 MB / 0.5606 GB
Notification: Performance of Templates: time 0.09004/1.564, allocations: 44.06 MB / 0.8289 GB, free: 8.77 MB / 0.6075 GB
make -j1 -f Annex60_1.0.0_Annex60.Airflow.Multizone.Examples.ClosedDoors.makefile
Environment - environmentSimulation:
(rm -f Annex60_1.0.0_Annex60.Airflow.Multizone.Examples.ClosedDoors.pipe ; mkfifo Annex60_1.0.0_Annex60.Airflow.Multizone.Examples.ClosedDoors.pipe ; head -c 1048576 < Annex60_1.0.0_Annex60.Airflow.Multizone.Examples.ClosedDoors.pipe >> ../files/Annex60_1.0.0_Annex60.Airflow.Multizone.Examples.ClosedDoors.sim & ./Annex60_1.0.0_Annex60.Airflow.Multizone.Examples.ClosedDoors  -abortSlowSimulation -alarm=480 -lv LOG_STATS > Annex60_1.0.0_Annex60.Airflow.Multizone.Examples.ClosedDoors.pipe 2>&1)
Environment - environmentSimulation: