Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.ClosedDoors.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.001314/0.001314, allocations: 81.86 kB / 20.68 MB, free: 3.809 MB / 18.57 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.001657/0.001657, allocations: 177 kB / 24.09 MB, free: 412 kB / 18.57 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 0.9538/0.9538, allocations: 177.1 MB / 204.5 MB, free: 5.281 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.0/package.mo", uses=false) [Timeout 180]
"[/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.0/Building/BuildingEnvelope/HeatTransfer/package.mo:2:1-40:17:writable] Warning: Soil_case_nappe was referenced in the package.order file, but was not found in package.mo, Soil_case_nappe/package.mo or Soil_case_nappe.mo.
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.0/package.mo): time 0.5765/0.5765, allocations: 110.3 MB / 371.3 MB, free: 12.29 MB / 346.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.0/BoundaryConditions/Scenarios/CombiTimeTable.mo:44:3-56:20:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.0/BoundaryConditions/Scenarios/CombiTimeTable.mo:58:3-65:19:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.0/BoundaryConditions/Scenarios/CombiTimeTable.mo:67:3-72:20:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.0/BoundaryConditions/Scenarios/CombiTimeTable.mo:74:3-79:20:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 179]
Using package BuildSysPro with version 3.6.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.0/package.mo)
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo)
Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo)
Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo)
Running command: translateModel(BuildSysPro.IBPSA.Airflow.Multizone.Examples.ClosedDoors,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.ClosedDoors")
translateModel(BuildSysPro.IBPSA.Airflow.Multizone.Examples.ClosedDoors,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.ClosedDoors") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.312e-06/1.312e-06, allocations: 0 / 475.6 MB, free: 15.05 MB / 410.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.856e-05/1.987e-05, allocations: 2.859 kB / 475.6 MB, free: 15.04 MB / 410.7 MB
Notification: Performance of NFInst.instantiate(BuildSysPro.IBPSA.Airflow.Multizone.Examples.ClosedDoors): time 0.1287/0.1288, allocations: 153.7 MB / 0.6145 GB, free: 4.957 MB / 0.5261 GB
Notification: Performance of NFInst.instExpressions: time 0.01286/0.1416, allocations: 10.84 MB / 0.6251 GB, free: 10.1 MB / 0.5417 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001789/0.1434, allocations: 75.44 kB / 0.6252 GB, free: 10.02 MB / 0.5417 GB
Notification: Performance of NFTyping.typeComponents: time 0.00114/0.1446, allocations: 0.5004 MB / 0.6257 GB, free: 9.52 MB / 0.5417 GB
Notification: Performance of NFTyping.typeBindings: time 0.003986/0.1485, allocations: 2.191 MB / 0.6278 GB, free: 7.316 MB / 0.5417 GB
Notification: Performance of NFTyping.typeClassSections: time 0.002016/0.1506, allocations: 1.208 MB / 0.629 GB, free: 6.105 MB / 0.5417 GB
Notification: Performance of NFFlatten.flatten: time 0.005994/0.1566, allocations: 7.018 MB / 0.6358 GB, free: 15.06 MB / 0.5573 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002432/0.159, allocations: 1.893 MB / 0.6377 GB, free: 13.12 MB / 0.5573 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.005087/0.1641, allocations: 3.514 MB / 0.6411 GB, free: 9.664 MB / 0.5573 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.002783/0.1669, allocations: 2.353 MB / 0.6434 GB, free: 7.305 MB / 0.5573 GB
Notification: Performance of NFPackage.collectConstants: time 0.0006814/0.1675, allocations: 336 kB / 0.6437 GB, free: 6.977 MB / 0.5573 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.001465/0.169, allocations: 0.7021 MB / 0.6444 GB, free: 6.273 MB / 0.5573 GB
Notification: Performance of NFScalarize.scalarize: time 0.001023/0.17, allocations: 1.563 MB / 0.646 GB, free: 4.707 MB / 0.5573 GB
Notification: Performance of NFVerifyModel.verify: time 0.00168/0.1717, allocations: 1.372 MB / 0.6473 GB, free: 3.332 MB / 0.5573 GB
Notification: Performance of NFConvertDAE.convert: time 0.226/0.3977, allocations: 3.337 MB / 0.6506 GB, free: 26.07 MB / 0.5573 GB
Notification: Performance of FrontEnd - DAE generated: time 6.563e-06/0.3977, allocations: 1.5 kB / 0.6506 GB, free: 26.07 MB / 0.5573 GB
Notification: Performance of FrontEnd: time 1.212e-06/0.3977, allocations: 0 / 0.6506 GB, free: 26.07 MB / 0.5573 GB
Notification: Performance of Transformations before backend: time 0.0001932/0.3979, allocations: 1.062 kB / 0.6506 GB, free: 26.07 MB / 0.5573 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.006112/0.404, allocations: 3.9 MB / 0.6544 GB, free: 24.68 MB / 0.5573 GB
Notification: Performance of prepare preOptimizeDAE: time 3.549e-05/0.404, allocations: 13.03 kB / 0.6544 GB, free: 24.68 MB / 0.5573 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002502/0.4065, allocations: 1.02 MB / 0.6554 GB, free: 24.46 MB / 0.5573 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001776/0.4083, allocations: 1.547 MB / 0.6569 GB, free: 24.02 MB / 0.5573 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 8.2e-05/0.4084, allocations: 202.6 kB / 0.6571 GB, free: 24.02 MB / 0.5573 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002452/0.4086, allocations: 276.1 kB / 0.6573 GB, free: 24.01 MB / 0.5573 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001973/0.4106, allocations: 1.978 MB / 0.6593 GB, free: 23.39 MB / 0.5573 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.977e-05/0.4107, allocations: 5.312 kB / 0.6593 GB, free: 23.39 MB / 0.5573 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001685/0.4108, allocations: 111.3 kB / 0.6594 GB, free: 23.39 MB / 0.5573 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 7.475e-05/0.4109, allocations: 124.2 kB / 0.6595 GB, free: 23.39 MB / 0.5573 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001697/0.4126, allocations: 1.566 MB / 0.661 GB, free: 23.3 MB / 0.5573 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.006316/0.4189, allocations: 5.93 MB / 0.6668 GB, free: 22.54 MB / 0.5573 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.002471/0.4214, allocations: 3.302 MB / 0.67 GB, free: 22.27 MB / 0.5573 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001282/0.4227, allocations: 1.049 MB / 0.6711 GB, free: 22.23 MB / 0.5573 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0005543/0.4232, allocations: 261.3 kB / 0.6713 GB, free: 22.21 MB / 0.5573 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.58e-05/0.4233, allocations: 82.84 kB / 0.6714 GB, free: 22.16 MB / 0.5573 GB
Notification: Performance of pre-optimization done (n=399): time 5.13e-06/0.4233, allocations: 0 / 0.6714 GB, free: 22.16 MB / 0.5573 GB
Notification: Performance of matching and sorting (n=399): time 0.003794/0.4271, allocations: 2.774 MB / 0.6741 GB, free: 21.86 MB / 0.5573 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.656e-05/0.4271, allocations: 96.05 kB / 0.6742 GB, free: 21.79 MB / 0.5573 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0008513/0.428, allocations: 1.045 MB / 0.6752 GB, free: 21.58 MB / 0.5573 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001279/0.4281, allocations: 93.48 kB / 0.6753 GB, free: 21.55 MB / 0.5573 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0003642/0.4285, allocations: 0.8983 MB / 0.6762 GB, free: 21.15 MB / 0.5573 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0005946/0.4291, allocations: 1.425 MB / 0.6776 GB, free: 20.22 MB / 0.5573 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.000629/0.4297, allocations: 0.565 MB / 0.6781 GB, free: 20.18 MB / 0.5573 GB
Notification: Performance of setup shared object (initialization): time 3.87e-05/0.4297, allocations: 482.4 kB / 0.6786 GB, free: 19.71 MB / 0.5573 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0007863/0.4305, allocations: 494.4 kB / 0.6791 GB, free: 19.71 MB / 0.5573 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001568/0.4321, allocations: 0.8626 MB / 0.6799 GB, free: 19.5 MB / 0.5573 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.002615/0.4347, allocations: 1.742 MB / 0.6816 GB, free: 19.25 MB / 0.5573 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.525e-05/0.4347, allocations: 2.062 kB / 0.6816 GB, free: 19.25 MB / 0.5573 GB
Notification: Performance of matching and sorting (n=430) (initialization): time 0.002779/0.4375, allocations: 2.131 MB / 0.6837 GB, free: 19 MB / 0.5573 GB
Notification: Performance of prepare postOptimizeDAE: time 2.18e-05/0.4375, allocations: 7.781 kB / 0.6837 GB, free: 19 MB / 0.5573 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.311e-05/0.4375, allocations: 5.109 kB / 0.6837 GB, free: 19 MB / 0.5573 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.443e-05/0.4376, allocations: 17.75 kB / 0.6837 GB, free: 19 MB / 0.5573 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001556/0.4391, allocations: 0.6749 MB / 0.6844 GB, free: 19 MB / 0.5573 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 4.1e-05/0.4391, allocations: 25.06 kB / 0.6844 GB, free: 19 MB / 0.5573 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001371/0.4405, allocations: 233.4 kB / 0.6846 GB, free: 18.86 MB / 0.5573 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001404/0.4407, allocations: 85.89 kB / 0.6847 GB, free: 18.86 MB / 0.5573 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 (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.0005846/0.4412, allocations: 335.9 kB / 0.685 GB, free: 18.7 MB / 0.5573 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003737/0.4416, allocations: 372.5 kB / 0.6854 GB, free: 18.57 MB / 0.5573 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002694/0.4443, allocations: 2.504 MB / 0.6878 GB, free: 18.32 MB / 0.5573 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.652e-05/0.4443, allocations: 41.53 kB / 0.6879 GB, free: 18.32 MB / 0.5573 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 6.773e-06/0.4443, allocations: 3.281 kB / 0.6879 GB, free: 18.32 MB / 0.5573 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001658/0.446, allocations: 1.844 MB / 0.6897 GB, free: 18.03 MB / 0.5573 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.004765/0.4508, allocations: 4.392 MB / 0.694 GB, free: 17.48 MB / 0.5573 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.141e-06/0.4508, allocations: 0.9062 kB / 0.694 GB, free: 17.48 MB / 0.5573 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001432/0.4522, allocations: 0.6498 MB / 0.6946 GB, free: 17.48 MB / 0.5573 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0003036/0.4525, allocations: 138.3 kB / 0.6947 GB, free: 17.45 MB / 0.5573 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.000163/0.4527, allocations: 83.94 kB / 0.6948 GB, free: 17.45 MB / 0.5573 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.00219/0.4549, allocations: 5.99 MB / 0.7007 GB, free: 27.67 MB / 0.573 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.072e-06/0.4549, allocations: 3.969 kB / 0.7007 GB, free: 27.67 MB / 0.573 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.004542/0.4594, allocations: 3.152 MB / 0.7037 GB, free: 27.16 MB / 0.573 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001536/0.4609, allocations: 0.6187 MB / 0.7043 GB, free: 26.97 MB / 0.573 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0004867/0.4614, allocations: 66.56 kB / 0.7044 GB, free: 26.97 MB / 0.573 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001089/0.4625, allocations: 86.67 kB / 0.7045 GB, free: 26.97 MB / 0.573 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002729/0.4628, allocations: 230.8 kB / 0.7047 GB, free: 26.96 MB / 0.573 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001336/0.4629, allocations: 88.98 kB / 0.7048 GB, free: 26.96 MB / 0.573 GB
Notification: Performance of sorting global known variables: time 0.0004758/0.4634, allocations: 0.6155 MB / 0.7054 GB, free: 26.81 MB / 0.573 GB
Notification: Performance of sort global known variables: time 8.1e-08/0.4634, allocations: 0 / 0.7054 GB, free: 26.81 MB / 0.573 GB
Notification: Performance of remove unused functions: time 0.001689/0.4651, allocations: 1.063 MB / 0.7064 GB, free: 26.72 MB / 0.573 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.medium.Xi[1],volA.dynBal.U,volA.dynBal.m,volB.dynBal.medium.Xi[1],volB.dynBal.U,volB.dynBal.m,volC.dynBal.medium.Xi[1],volC.dynBal.U,volC.dynBal.m,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 (393):
 * Single equations (assignments): 390
 * 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.0004261/0.4655, allocations: 261.9 kB / 0.7067 GB, free: 26.63 MB / 0.573 GB
Notification: Performance of simCode: created initialization part: time 0.001947/0.4675, allocations: 1.836 MB / 0.7085 GB, free: 26.53 MB / 0.573 GB
Notification: Performance of simCode: created event and clocks part: time 3.657e-06/0.4675, allocations: 0 / 0.7085 GB, free: 26.53 MB / 0.573 GB
Notification: Performance of simCode: created simulation system equations: time 0.001351/0.4688, allocations: 1.642 MB / 0.7101 GB, free: 25.98 MB / 0.573 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002307/0.4711, allocations: 1.075 MB / 0.7111 GB, free: 25.85 MB / 0.573 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.005477/0.4766, allocations: 5.148 MB / 0.7162 GB, free: 23.89 MB / 0.573 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0004738/0.4771, allocations: 0.6642 MB / 0.7168 GB, free: 23.77 MB / 0.573 GB
Notification: Performance of simCode: alias equations: time 0.001226/0.4783, allocations: 466.4 kB / 0.7173 GB, free: 23.61 MB / 0.573 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001788/0.4801, allocations: 1.007 MB / 0.7182 GB, free: 23.54 MB / 0.573 GB
Notification: Performance of SimCode: time 1.363e-06/0.4801, allocations: 0 / 0.7182 GB, free: 23.54 MB / 0.573 GB
Notification: Performance of Templates: time 0.05265/0.5327, allocations: 51.17 MB / 0.7682 GB, free: 316 kB / 0.5886 GB
"
[Timeout remaining time 659]
make -j1 -f BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.ClosedDoors.makefile [Timeout 660]
(rm -f BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.ClosedDoors.pipe ; mkfifo BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.ClosedDoors.pipe ; head -c 1048576 < BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.ClosedDoors.pipe >> ../files/BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.ClosedDoors.sim & ./BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.ClosedDoors -abortSlowSimulation -alarm=240  -lv LOG_STATS > BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.ClosedDoors.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 6.0604436518624425]