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.000752/0.0007519, allocations: 89.89 kB / 15.36 MB, free: 0.7383 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.005054/0.005054, allocations: 170.3 kB / 16.26 MB, free: 6.613 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.545/1.545, allocations: 205.4 MB / 222.5 MB, free: 5.797 MB / 174.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Annex60 1.0.0/package.mo): time 0.4014/0.4014, allocations: 55 MB / 325.7 MB, free: 0.6172 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.0005548/0.0005548, allocations: 12 kB / 397.2 MB, free: 11.4 MB / 334.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3632/0.3638, allocations: 60.72 MB / 458 MB, free: 38.46 MB / 382.1 MB
Notification: Performance of NFInst.instantiate(Annex60.Airflow.Multizone.Examples.ClosedDoors): time 0.533/0.8968, allocations: 212.8 MB / 0.655 GB, free: 1.824 MB / 0.4981 GB
Notification: Performance of NFInst.instExpressions: time 0.02548/0.9223, allocations: 21.43 MB / 0.676 GB, free: 1.574 MB / 0.4981 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002652/0.925, allocations: 50.75 kB / 0.676 GB, free: 1.574 MB / 0.4981 GB
Notification: Performance of NFTyping.typeComponents: time 0.003325/0.9284, allocations: 1.086 MB / 0.6771 GB, free: 1.543 MB / 0.4981 GB
Notification: Performance of NFTyping.typeBindings: time 0.01149/0.9399, allocations: 3.775 MB / 0.6808 GB, free: 1.344 MB / 0.4981 GB
Notification: Performance of NFTyping.typeClassSections: time 0.00459/0.9445, allocations: 1.394 MB / 0.6821 GB, free: 1.301 MB / 0.4981 GB
Notification: Performance of NFFlatten.flatten: time 0.01414/0.9587, allocations: 9.634 MB / 0.6915 GB, free: 1.273 MB / 0.4981 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.005146/0.9638, allocations: 1.976 MB / 0.6935 GB, free: 17 MB / 0.5137 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.007218/0.9711, allocations: 2.063 MB / 0.6955 GB, free: 16.99 MB / 0.5137 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.007285/0.9784, allocations: 2.483 MB / 0.6979 GB, free: 16.97 MB / 0.5137 GB
Notification: Performance of NFPackage.collectConstants: time 0.001494/0.9799, allocations: 158.8 kB / 0.6981 GB, free: 16.97 MB / 0.5137 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01008/0.99, allocations: 3.546 MB / 0.7015 GB, free: 16.29 MB / 0.5137 GB
Notification: Performance of NFScalarize.scalarize: time 0.002469/0.9925, allocations: 1.14 MB / 0.7026 GB, free: 15.91 MB / 0.5137 GB
Notification: Performance of NFVerifyModel.verify: time 0.004402/0.997, allocations: 1.563 MB / 0.7042 GB, free: 15.73 MB / 0.5137 GB
Notification: Performance of NFConvertDAE.convert: time 0.01056/1.008, allocations: 5.432 MB / 0.7095 GB, free: 13.38 MB / 0.5137 GB
Notification: Performance of FrontEnd - DAE generated: time 4.558e-06/1.008, allocations: 0.5625 kB / 0.7095 GB, free: 13.38 MB / 0.5137 GB
Notification: Performance of FrontEnd: time 2.745e-06/1.008, allocations: 1.062 kB / 0.7095 GB, free: 13.38 MB / 0.5137 GB
Notification: Performance of Transformations before backend: time 0.0001926/1.008, allocations: 0 / 0.7095 GB, free: 13.38 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.01207/1.02, allocations: 3.288 MB / 0.7127 GB, free: 11.52 MB / 0.5137 GB
Notification: Performance of prepare preOptimizeDAE: time 5.389e-05/1.02, allocations: 6.812 kB / 0.7127 GB, free: 11.52 MB / 0.5137 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.007447/1.027, allocations: 0.7728 MB / 0.7134 GB, free: 11.2 MB / 0.5137 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.005643/1.033, allocations: 1.579 MB / 0.715 GB, free: 10.48 MB / 0.5137 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003621/1.033, allocations: 192.3 kB / 0.7152 GB, free: 10.47 MB / 0.5137 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001673/1.035, allocations: 254 kB / 0.7154 GB, free: 10.47 MB / 0.5137 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01128/1.046, allocations: 3.165 MB / 0.7185 GB, free: 9.68 MB / 0.5137 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001504/1.047, allocations: 9 kB / 0.7185 GB, free: 9.676 MB / 0.5137 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0008818/1.048, allocations: 104.5 kB / 0.7186 GB, free: 9.676 MB / 0.5137 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001915/1.048, allocations: 116.3 kB / 0.7187 GB, free: 9.66 MB / 0.5137 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01218/1.06, allocations: 4.277 MB / 0.7229 GB, free: 8.047 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.02289/1.083, allocations: 10.52 MB / 0.7332 GB, free: 15.36 MB / 0.5294 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.008967/1.092, allocations: 4.42 MB / 0.7375 GB, free: 10.95 MB / 0.5294 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004195/1.096, allocations: 2.421 MB / 0.7399 GB, free: 8.523 MB / 0.5294 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001096/1.097, allocations: 0.7791 MB / 0.7406 GB, free: 7.609 MB / 0.5294 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 6.322e-05/1.097, allocations: 73.44 kB / 0.7407 GB, free: 7.527 MB / 0.5294 GB
Notification: Performance of pre-optimization done (n=366): time 6.653e-06/1.097, allocations: 0 / 0.7407 GB, free: 7.527 MB / 0.5294 GB
Notification: Performance of matching and sorting (n=366): time 0.01083/1.108, allocations: 5.711 MB / 0.7463 GB, free: 1.73 MB / 0.5294 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.901e-05/1.108, allocations: 90.28 kB / 0.7463 GB, free: 1.629 MB / 0.5294 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001764/1.11, allocations: 0.9154 MB / 0.7472 GB, free: 0.6953 MB / 0.5294 GB
Notification: Performance of collectPreVariables (initialization): time 0.0003437/1.11, allocations: 89.7 kB / 0.7473 GB, free: 0.6016 MB / 0.5294 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0005967/1.111, allocations: 0.7181 MB / 0.748 GB, free: 15.87 MB / 0.545 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001374/1.112, allocations: 1.226 MB / 0.7492 GB, free: 14.66 MB / 0.545 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0004905/1.113, allocations: 252.2 kB / 0.7495 GB, free: 14.4 MB / 0.545 GB
Notification: Performance of setup shared object (initialization): time 0.0001097/1.113, allocations: 301.1 kB / 0.7498 GB, free: 14.11 MB / 0.545 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002711/1.116, allocations: 1.517 MB / 0.7512 GB, free: 12.59 MB / 0.545 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00281/1.119, allocations: 1.815 MB / 0.753 GB, free: 10.72 MB / 0.545 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.00572/1.124, allocations: 3.613 MB / 0.7565 GB, free: 7.062 MB / 0.545 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.137e-06/1.124, allocations: 0 / 0.7565 GB, free: 7.062 MB / 0.545 GB
Notification: Performance of matching and sorting (n=395) (initialization): time 0.006958/1.131, allocations: 3.943 MB / 0.7604 GB, free: 3.105 MB / 0.545 GB
Notification: Performance of prepare postOptimizeDAE: time 2.649e-05/1.131, allocations: 4 kB / 0.7604 GB, free: 3.102 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.674e-05/1.131, allocations: 5 kB / 0.7604 GB, free: 3.098 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (initialization): time 4.106e-05/1.131, allocations: 16 kB / 0.7604 GB, free: 3.082 MB / 0.545 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00313/1.135, allocations: 0.6389 MB / 0.761 GB, free: 2.48 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 7.771e-05/1.135, allocations: 18.53 kB / 0.7611 GB, free: 2.465 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00324/1.138, allocations: 345.3 kB / 0.7614 GB, free: 2.125 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003021/1.138, allocations: 104 kB / 0.7615 GB, free: 2.023 MB / 0.545 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 (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.001658/1.14, allocations: 0.6638 MB / 0.7621 GB, free: 1.387 MB / 0.545 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0006371/1.141, allocations: 209.5 kB / 0.7623 GB, free: 1.18 MB / 0.545 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.008325/1.149, allocations: 4.594 MB / 0.7668 GB, free: 12.57 MB / 0.5606 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.897e-05/1.149, allocations: 36 kB / 0.7668 GB, free: 12.53 MB / 0.5606 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.904e-05/1.149, allocations: 4 kB / 0.7669 GB, free: 12.53 MB / 0.5606 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.006144/1.155, allocations: 3.896 MB / 0.7707 GB, free: 8.574 MB / 0.5606 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01392/1.169, allocations: 8.038 MB / 0.7785 GB, free: 0.5508 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.222e-05/1.169, allocations: 3.938 kB / 0.7785 GB, free: 0.5469 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.00328/1.172, allocations: 0.6635 MB / 0.7792 GB, free: 15.92 MB / 0.5762 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0005604/1.173, allocations: 141 kB / 0.7793 GB, free: 15.77 MB / 0.5762 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0003563/1.173, allocations: 87.97 kB / 0.7794 GB, free: 15.69 MB / 0.5762 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.00414/1.177, allocations: 4.552 MB / 0.7838 GB, free: 10.77 MB / 0.5762 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.405e-06/1.178, allocations: 0 / 0.7838 GB, free: 10.77 MB / 0.5762 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01373/1.191, allocations: 6.896 MB / 0.7906 GB, free: 3.816 MB / 0.5762 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.002356/1.194, allocations: 0.7278 MB / 0.7913 GB, free: 3.066 MB / 0.5762 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0006835/1.194, allocations: 68 kB / 0.7913 GB, free: 3 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003089/1.197, allocations: 203.7 kB / 0.7915 GB, free: 2.801 MB / 0.5762 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0006238/1.198, allocations: 233.2 kB / 0.7917 GB, free: 2.574 MB / 0.5762 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0004623/1.199, allocations: 112 kB / 0.7919 GB, free: 2.465 MB / 0.5762 GB
Notification: Performance of sorting global known variables: time 0.001622/1.2, allocations: 0.6358 MB / 0.7925 GB, free: 1.82 MB / 0.5762 GB
Notification: Performance of sort global known variables: time 6.71e-07/1.2, allocations: 0 / 0.7925 GB, free: 1.82 MB / 0.5762 GB
Notification: Performance of remove unused functions: time 0.003666/1.204, allocations: 1.041 MB / 0.7935 GB, free: 0.7773 MB / 0.5762 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 (not torn): 0
 * Torn equation systems: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 3 systems
   {(1,2,100.0%), (1,2,100.0%), (1,2,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.0008705/1.205, allocations: 328.9 kB / 0.7938 GB, free: 0.4922 MB / 0.5762 GB
Notification: Performance of simCode: created initialization part: time 0.006891/1.212, allocations: 3.901 MB / 0.7976 GB, free: 12.62 MB / 0.5919 GB
Notification: Performance of simCode: created event and clocks part: time 1.051e-05/1.212, allocations: 7.938 kB / 0.7976 GB, free: 12.61 MB / 0.5919 GB
Notification: Performance of simCode: created simulation system equations: time 0.003999/1.216, allocations: 2.526 MB / 0.8001 GB, free: 10.12 MB / 0.5919 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.005843/1.222, allocations: 1.002 MB / 0.8011 GB, free: 9.145 MB / 0.5919 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01197/1.234, allocations: 7.684 MB / 0.8086 GB, free: 1.434 MB / 0.5919 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.002262/1.236, allocations: 1.832 MB / 0.8104 GB, free: 15.56 MB / 0.6075 GB
Notification: Performance of simCode: alias equations: time 0.00489/1.241, allocations: 1.104 MB / 0.8114 GB, free: 14.45 MB / 0.6075 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001484/1.242, allocations: 239.4 kB / 0.8117 GB, free: 14.22 MB / 0.6075 GB
Notification: Performance of SimCode: time 8.72e-07/1.242, allocations: 4 kB / 0.8117 GB, free: 14.21 MB / 0.6075 GB
Notification: Performance of Templates: time 0.07873/1.321, allocations: 45.51 MB / 0.8561 GB, free: 1.199 MB / 0.6387 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: