Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.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.001394/0.001394, allocations: 92.7 kB / 19.44 MB, free: 324 kB / 13.93 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.001538/0.001538, allocations: 161.3 kB / 22.75 MB, free: 1.656 MB / 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 1.486/1.486, allocations: 177.1 MB / 203.1 MB, free: 5.617 MB / 186.7 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 master/package.mo): time 0.002649/0.002649, allocations: 226.4 kB / 259.8 MB, free: 12.86 MB / 250.7 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo): time 1.111/1.111, allocations: 138.4 MB / 454.7 MB, free: 1.484 MB / 362.7 MB
"
[Timeout remaining time 179]
Using package BuildingSystems with version 2.0.0-beta (/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo)
Using package NcDataReader2 with version 2.5.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 master/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(BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor")
translateModel(BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.423e-06/1.423e-06, allocations: 0 / 0.5543 GB, free: 0.8438 MB / 474.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.64e-05/2.782e-05, allocations: 6.312 kB / 0.5543 GB, free: 0.8359 MB / 474.7 MB
Notification: Performance of NFInst.instantiate(BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor): time 0.5675/0.5675, allocations: 265.7 MB / 0.8138 GB, free: 0.5859 MB / 0.6198 GB
Notification: Performance of NFInst.instExpressions: time 0.0275/0.595, allocations: 14.32 MB / 0.8278 GB, free: 2.23 MB / 0.6355 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003165/0.5982, allocations: 99.25 kB / 0.8279 GB, free: 2.133 MB / 0.6355 GB
Notification: Performance of NFTyping.typeComponents: time 0.005267/0.6035, allocations: 1.666 MB / 0.8296 GB, free: 468 kB / 0.6355 GB
Notification: Performance of NFTyping.typeBindings: time 0.0188/0.6223, allocations: 6.33 MB / 0.8357 GB, free: 10.1 MB / 0.6511 GB
Notification: Performance of NFTyping.typeClassSections: time 0.009064/0.6313, allocations: 2.939 MB / 0.8386 GB, free: 7.148 MB / 0.6511 GB
Notification: Performance of NFFlatten.flatten: time 0.3272/0.9585, allocations: 7.339 MB / 0.8458 GB, free: 18.79 MB / 0.6511 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.003652/0.9622, allocations: 2.075 MB / 0.8478 GB, free: 18.65 MB / 0.6511 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.006869/0.969, allocations: 3.621 MB / 0.8513 GB, free: 18.65 MB / 0.6511 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.005221/0.9742, allocations: 2.49 MB / 0.8538 GB, free: 18.65 MB / 0.6511 GB
Notification: Performance of NFPackage.collectConstants: time 0.0008591/0.9751, allocations: 342.7 kB / 0.8541 GB, free: 18.65 MB / 0.6511 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01035/0.9855, allocations: 4.827 MB / 0.8588 GB, free: 18.65 MB / 0.6511 GB
Notification: Performance of NFScalarize.scalarize: time 0.003536/0.989, allocations: 1.626 MB / 0.8604 GB, free: 18.65 MB / 0.6511 GB
Notification: Performance of NFVerifyModel.verify: time 0.005986/0.995, allocations: 1.403 MB / 0.8618 GB, free: 18.65 MB / 0.6511 GB
Notification: Performance of NFConvertDAE.convert: time 0.0176/1.013, allocations: 6.979 MB / 0.8686 GB, free: 18.43 MB / 0.6511 GB
Notification: Performance of FrontEnd - DAE generated: time 4.929e-06/1.013, allocations: 0 / 0.8686 GB, free: 18.43 MB / 0.6511 GB
Notification: Performance of FrontEnd: time 1.443e-06/1.013, allocations: 1.031 kB / 0.8686 GB, free: 18.43 MB / 0.6511 GB
Notification: Performance of Transformations before backend: time 0.0002134/1.013, allocations: 3.344 kB / 0.8686 GB, free: 18.43 MB / 0.6511 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 726
 * Number of variables: 726
Notification: Performance of Generate backend data structure: time 0.01596/1.029, allocations: 4.15 MB / 0.8726 GB, free: 16.8 MB / 0.6511 GB
Notification: Performance of prepare preOptimizeDAE: time 5.227e-05/1.029, allocations: 9.781 kB / 0.8726 GB, free: 16.8 MB / 0.6511 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.008791/1.038, allocations: 1.202 MB / 0.8738 GB, free: 16.54 MB / 0.6511 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.006535/1.044, allocations: 1.86 MB / 0.8756 GB, free: 16.07 MB / 0.6511 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003631/1.045, allocations: 213.1 kB / 0.8758 GB, free: 16.06 MB / 0.6511 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001642/1.046, allocations: 298 kB / 0.8761 GB, free: 16.05 MB / 0.6511 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0116/1.058, allocations: 3.925 MB / 0.88 GB, free: 15.39 MB / 0.6511 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.000193/1.058, allocations: 6 kB / 0.88 GB, free: 15.39 MB / 0.6511 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001116/1.059, allocations: 119.6 kB / 0.8801 GB, free: 15.39 MB / 0.6511 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.000244/1.059, allocations: 146.4 kB / 0.8802 GB, free: 15.38 MB / 0.6511 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.009266/1.069, allocations: 3.704 MB / 0.8838 GB, free: 15.35 MB / 0.6511 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.01778/1.086, allocations: 8.253 MB / 0.8919 GB, free: 13.24 MB / 0.6511 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.00439/1.091, allocations: 2.958 MB / 0.8948 GB, free: 13.17 MB / 0.6511 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002447/1.093, allocations: 1.625 MB / 0.8964 GB, free: 13.14 MB / 0.6511 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001031/1.094, allocations: 263.9 kB / 0.8966 GB, free: 13.13 MB / 0.6511 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.739e-05/1.094, allocations: 62.62 kB / 0.8967 GB, free: 13.09 MB / 0.6511 GB
Notification: Performance of pre-optimization done (n=285): time 5.069e-06/1.094, allocations: 0 / 0.8967 GB, free: 13.09 MB / 0.6511 GB
Notification: Performance of matching and sorting (n=285): time 0.006669/1.101, allocations: 4.121 MB / 0.9007 GB, free: 12.84 MB / 0.6511 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.755e-05/1.101, allocations: 85.45 kB / 0.9008 GB, free: 12.77 MB / 0.6511 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001899/1.103, allocations: 1.204 MB / 0.902 GB, free: 12.6 MB / 0.6511 GB
Notification: Performance of collectPreVariables (initialization): time 0.0002176/1.103, allocations: 75.61 kB / 0.902 GB, free: 12.57 MB / 0.6511 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0005569/1.104, allocations: 1.036 MB / 0.903 GB, free: 12.11 MB / 0.6511 GB
Notification: Performance of collectInitialBindings (initialization): time 0.000751/1.104, allocations: 1.002 MB / 0.904 GB, free: 11.44 MB / 0.6511 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0006605/1.105, allocations: 413 kB / 0.9044 GB, free: 11.41 MB / 0.6511 GB
Notification: Performance of setup shared object (initialization): time 4.6e-05/1.105, allocations: 301.1 kB / 0.9047 GB, free: 11.12 MB / 0.6511 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001788/1.107, allocations: 1.139 MB / 0.9058 GB, free: 11.11 MB / 0.6511 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002088/1.109, allocations: 1.437 MB / 0.9072 GB, free: 10.9 MB / 0.6511 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.003901/1.113, allocations: 2.72 MB / 0.9099 GB, free: 10.71 MB / 0.6511 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.29e-05/1.113, allocations: 4.062 kB / 0.9099 GB, free: 10.71 MB / 0.6511 GB
Notification: Performance of matching and sorting (n=312) (initialization): time 0.005172/1.118, allocations: 3.147 MB / 0.913 GB, free: 10.48 MB / 0.6511 GB
Notification: Performance of prepare postOptimizeDAE: time 2.433e-05/1.118, allocations: 6.812 kB / 0.913 GB, free: 10.48 MB / 0.6511 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.745e-05/1.118, allocations: 4.062 kB / 0.913 GB, free: 10.48 MB / 0.6511 GB
Notification: Performance of postOpt tearingSystem (initialization): time 4.316e-05/1.118, allocations: 10.97 kB / 0.913 GB, free: 10.48 MB / 0.6511 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002157/1.12, allocations: 0.5117 MB / 0.9135 GB, free: 10.48 MB / 0.6511 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 4.666e-05/1.12, allocations: 12.75 kB / 0.9135 GB, free: 10.48 MB / 0.6511 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002232/1.123, allocations: 185.6 kB / 0.9137 GB, free: 10.38 MB / 0.6511 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002469/1.123, allocations: 84.36 kB / 0.9137 GB, free: 10.38 MB / 0.6511 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 (312):
 * Single equations (assignments): 312
 * 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.00152/1.124, allocations: 0.6833 MB / 0.9144 GB, free: 10.27 MB / 0.6511 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.000859/1.125, allocations: 312.5 kB / 0.9147 GB, free: 10.15 MB / 0.6511 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.005469/1.131, allocations: 3.198 MB / 0.9178 GB, free: 9.977 MB / 0.6511 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.791e-05/1.131, allocations: 28.5 kB / 0.9179 GB, free: 9.977 MB / 0.6511 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 2.024e-05/1.131, allocations: 0.7812 kB / 0.9179 GB, free: 9.977 MB / 0.6511 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.003993/1.135, allocations: 2.659 MB / 0.9205 GB, free: 9.816 MB / 0.6511 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01113/1.146, allocations: 5.77 MB / 0.9261 GB, free: 9.238 MB / 0.6511 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.173e-05/1.146, allocations: 4.469 kB / 0.9261 GB, free: 9.238 MB / 0.6511 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002491/1.148, allocations: 0.5385 MB / 0.9266 GB, free: 9.238 MB / 0.6511 GB
Notification: Performance of postOpt tearingSystem (simulation): time 3.425e-05/1.148, allocations: 11.12 kB / 0.9266 GB, free: 9.238 MB / 0.6511 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0002493/1.149, allocations: 71.75 kB / 0.9267 GB, free: 9.238 MB / 0.6511 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 1.884e-05/1.149, allocations: 13.09 kB / 0.9267 GB, free: 9.238 MB / 0.6511 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.817e-06/1.149, allocations: 7.125 kB / 0.9267 GB, free: 9.238 MB / 0.6511 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.007006/1.156, allocations: 4.9 MB / 0.9315 GB, free: 8.176 MB / 0.6511 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001518/1.157, allocations: 0.5372 MB / 0.932 GB, free: 7.891 MB / 0.6511 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0004967/1.158, allocations: 58.55 kB / 0.9321 GB, free: 7.852 MB / 0.6511 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001729/1.159, allocations: 65.81 kB / 0.9321 GB, free: 7.812 MB / 0.6511 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003506/1.16, allocations: 190 kB / 0.9323 GB, free: 7.781 MB / 0.6511 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001847/1.16, allocations: 84.59 kB / 0.9324 GB, free: 7.762 MB / 0.6511 GB
Notification: Performance of sorting global known variables: time 0.00125/1.161, allocations: 0.8255 MB / 0.9332 GB, free: 7.438 MB / 0.6511 GB
Notification: Performance of sort global known variables: time 8e-08/1.161, allocations: 0 / 0.9332 GB, free: 7.438 MB / 0.6511 GB
Notification: Performance of remove unused functions: time 0.00298/1.164, allocations: 1.03 MB / 0.9342 GB, free: 6.434 MB / 0.6511 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 11
 * Number of states: 18 (volEas.dynBal.medium.Xi[1],volEas.dynBal.U,volEas.dynBal.m,volEas.dynBal.mC[1],oriOutBot.mExc,oriOutTop.mExc,oriWesTop.mExc,dooOpeClo.mExcAB,dooOpeClo.mExcBA,volWes.dynBal.medium.Xi[1],volWes.dynBal.U,volWes.dynBal.m,volWes.dynBal.mC[1],oriEasTop.mExc,volTop.dynBal.medium.Xi[1],volTop.dynBal.U,volTop.dynBal.m,volTop.dynBal.mC[1])
 * 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 (285):
 * Single equations (assignments): 285
 * 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 Backend phase and start with SimCode phase: time 0.0008868/1.165, allocations: 399.4 kB / 0.9346 GB, free: 6.141 MB / 0.6511 GB
Notification: Performance of simCode: created initialization part: time 0.004192/1.169, allocations: 2.699 MB / 0.9372 GB, free: 3.789 MB / 0.6511 GB
Notification: Performance of simCode: created event and clocks part: time 8.906e-06/1.169, allocations: 0 / 0.9372 GB, free: 3.789 MB / 0.6511 GB
Notification: Performance of simCode: created simulation system equations: time 0.00202/1.171, allocations: 1.443 MB / 0.9386 GB, free: 2.578 MB / 0.6511 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.006554/1.178, allocations: 1.203 MB / 0.9398 GB, free: 1.746 MB / 0.6511 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.0128/1.191, allocations: 6.036 MB / 0.9457 GB, free: 11.98 MB / 0.6667 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.00262/1.193, allocations: 1.437 MB / 0.9471 GB, free: 10.5 MB / 0.6667 GB
Notification: Performance of simCode: alias equations: time 0.004377/1.198, allocations: 0.8575 MB / 0.948 GB, free: 9.645 MB / 0.6667 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.00129/1.199, allocations: 200.5 kB / 0.9482 GB, free: 9.445 MB / 0.6667 GB
Notification: Performance of SimCode: time 1.493e-06/1.199, allocations: 0 / 0.9482 GB, free: 9.445 MB / 0.6667 GB
Notification: Performance of Templates: time 0.08807/1.287, allocations: 47.31 MB / 0.9944 GB, free: 11.26 MB / 0.7136 GB
"
[Timeout remaining time 659]
make -j1 -f BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.makefile [Timeout 660]
(rm -f BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.pipe ; mkfifo BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.pipe ; head -c 1048576 < BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.pipe >> ../files/BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.sim & ./BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor  -abortSlowSimulation -alarm=480 -lv LOG_STATS > BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 8.63103644491639]