Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_12_Buildings.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.001373/0.001373, allocations: 100.4 kB / 19.72 MB, free: 2.121 MB / 14.72 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.001493/0.001493, allocations: 210.1 kB / 23.01 MB, free: 4.93 MB / 14.72 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.298/1.298, allocations: 230.6 MB / 256.8 MB, free: 7.812 MB / 206.1 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo): time 2.799/2.799, allocations: 0.49 GB / 0.7958 GB, free: 35.48 MB / 0.6231 GB
"
[Timeout remaining time 177]
Using package Buildings with version 12.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/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(Buildings.Airflow.Multizone.Examples.ClosedDoors,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|dooAB.vAB|dooAB.vBA|dooAC.vAB|dooAC.vBA|dooBC.vAB|dooBC.vBA",fileNamePrefix="Buildings_12_Buildings.Airflow.Multizone.Examples.ClosedDoors")
translateModel(Buildings.Airflow.Multizone.Examples.ClosedDoors,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|dooAB.vAB|dooAB.vBA|dooAC.vAB|dooAC.vBA|dooBC.vAB|dooBC.vBA",fileNamePrefix="Buildings_12_Buildings.Airflow.Multizone.Examples.ClosedDoors") [Timeout 300]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.724e-05/1.724e-05, allocations: 4.391 kB / 1.155 GB, free: 25.13 MB / 0.9044 GB
Notification: Performance of NFInst.instantiate(Buildings.Airflow.Multizone.Examples.ClosedDoors): time 0.2121/0.2121, allocations: 193.5 MB / 1.344 GB, free: 15.2 MB / 1.045 GB
Notification: Performance of NFInst.instExpressions: time 0.02523/0.2373, allocations: 18.91 MB / 1.362 GB, free: 12.24 MB / 1.061 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002186/0.2395, allocations: 75.44 kB / 1.362 GB, free: 12.17 MB / 1.061 GB
Notification: Performance of NFTyping.typeComponents: time 0.002899/0.2424, allocations: 1.189 MB / 1.364 GB, free: 10.97 MB / 1.061 GB
Notification: Performance of NFTyping.typeBindings: time 0.01023/0.2526, allocations: 4.261 MB / 1.368 GB, free: 6.695 MB / 1.061 GB
Notification: Performance of NFTyping.typeClassSections: time 0.003974/0.2566, allocations: 1.593 MB / 1.369 GB, free: 5.105 MB / 1.061 GB
Notification: Performance of NFFlatten.flatten: time 0.007904/0.2645, allocations: 6.211 MB / 1.375 GB, free: 14.88 MB / 1.076 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002431/0.267, allocations: 1.585 MB / 1.377 GB, free: 13.26 MB / 1.076 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.004305/0.2713, allocations: 2.457 MB / 1.379 GB, free: 10.79 MB / 1.076 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.003652/0.2749, allocations: 1.948 MB / 1.381 GB, free: 8.848 MB / 1.076 GB
Notification: Performance of NFPackage.collectConstants: time 0.0003464/0.2753, allocations: 220 kB / 1.381 GB, free: 8.633 MB / 1.076 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.005698/0.281, allocations: 2.747 MB / 1.384 GB, free: 5.879 MB / 1.076 GB
Notification: Performance of combineBinaries: time 0.004782/0.2857, allocations: 5.045 MB / 1.389 GB, free: 0.793 MB / 1.076 GB
Notification: Performance of replaceArrayConstructors: time 0.002565/0.2883, allocations: 3.017 MB / 1.392 GB, free: 13.74 MB / 1.092 GB
Notification: Performance of NFVerifyModel.verify: time 0.0005134/0.2888, allocations: 220 kB / 1.392 GB, free: 13.53 MB / 1.092 GB
Notification: Performance of FrontEnd: time 0.0003431/0.2892, allocations: 59.73 kB / 1.392 GB, free: 13.47 MB / 1.092 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 760 (406)
 * Number of variables: 760 (382)
Notification: Performance of [SIM] Bindings: time 0.0127/0.3019, allocations: 12.93 MB / 1.405 GB, free: 212 kB / 1.092 GB
Notification: Performance of [SIM] FunctionAlias: time 0.001919/0.3038, allocations: 1.693 MB / 1.407 GB, free: 14.53 MB / 1.107 GB
Notification: Performance of [SIM] Early Inline: time 0.009178/0.313, allocations: 8.938 MB / 1.415 GB, free: 5.531 MB / 1.107 GB
Notification: Performance of [SIM] Simplify 1: time 0.002099/0.3151, allocations: 1.309 MB / 1.417 GB, free: 4.125 MB / 1.107 GB
Warning: NBAlias.setStartFixed: Alias set with conflicting unfixed start values detected. Use -d=dumprepl for more information.
Notification: Performance of [SIM] Alias: time 0.01043/0.3255, allocations: 7.658 MB / 1.424 GB, free: 11.77 MB / 1.123 GB
Notification: Performance of [SIM] Simplify 2: time 0.001815/0.3273, allocations: 1.192 MB / 1.425 GB, free: 10.47 MB / 1.123 GB
Notification: Performance of [SIM] Remove Stream: time 0.0007581/0.3281, allocations: 0.6011 MB / 1.426 GB, free: 9.809 MB / 1.123 GB
Notification: Performance of [SIM] Detect States: time 0.00161/0.3297, allocations: 1.544 MB / 1.427 GB, free: 8.168 MB / 1.123 GB
Notification: Performance of [SIM] Events: time 0.0005828/0.3303, allocations: 433.8 kB / 1.428 GB, free: 7.73 MB / 1.123 GB
Notification: Performance of [SIM] Partitioning: time 0.002818/0.3331, allocations: 2.549 MB / 1.43 GB, free: 5.137 MB / 1.123 GB
Notification: Performance of [SIM] Causalize: time 0.01203/0.3451, allocations: 8.883 MB / 1.439 GB, free: 12.15 MB / 1.139 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.005072/0.3502, allocations: 5.335 MB / 1.444 GB, free: 6.812 MB / 1.139 GB
Notification: Performance of [INI] Simplify: time 0.005539/0.3557, allocations: 3.259 MB / 1.447 GB, free: 3.359 MB / 1.139 GB
Notification: Performance of [INI] Inline: time 0.009563/0.3653, allocations: 10.2 MB / 1.457 GB, free: 9.137 MB / 1.154 GB
Notification: Performance of [INI] Partitioning: time 0.0002715/0.3655, allocations: 132.8 kB / 1.457 GB, free: 8.98 MB / 1.154 GB
Notification: Performance of [INI] Cleanup: time 0.001281/0.3668, allocations: 1.28 MB / 1.459 GB, free: 7.699 MB / 1.154 GB
Notification: Performance of [INI] Causalize: time 0.01444/0.3813, allocations: 11.34 MB / 1.47 GB, free: 12.21 MB / 1.17 GB
Notification: Performance of [INI] Tearing: time 0.0001345/0.3814, allocations: 208 kB / 1.47 GB, free: 12.01 MB / 1.17 GB
Notification: Performance of [SIM] Initialization: time 1.392e-06/0.3814, allocations: 0.5625 kB / 1.47 GB, free: 12.01 MB / 1.17 GB
Notification: Performance of [SIM] Remove Dummies: time 3.246e-05/0.3814, allocations: 2.656 kB / 1.47 GB, free: 12.01 MB / 1.17 GB
Notification: Performance of [SIM] Tearing: time 0.0002537/0.3817, allocations: 255.9 kB / 1.47 GB, free: 11.76 MB / 1.17 GB
Notification: Performance of [SIM] Categorize: time 2.363e-05/0.3817, allocations: 16 kB / 1.47 GB, free: 11.75 MB / 1.17 GB
Notification: Performance of [SIM] Solve: time 0.008145/0.3899, allocations: 6.318 MB / 1.476 GB, free: 5.418 MB / 1.17 GB
Notification: Performance of [SIM] Jacobian: time 0.007368/0.3972, allocations: 5.895 MB / 1.482 GB, free: 15.3 MB / 1.186 GB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 0
 * Number of ODE event partitions: ............... 1
 * Number of algebraic event partitions: ......... 0
 * Number of clocked partitions: ................. 0
 * Number of initial partitions: ................. 1
 * Number of initial(lambda=0) partitions: ....... 0
Notification: Variable statistics after passing the back-end:
 * Number of states: ............................. 15 (15) {dooAB.mExcAB, dooAB.mExcBA, volA.U, volA.m, volA.Xi, volB.U, volB.m, volB.Xi, volC.U, volC.m, volC.Xi, dooAC.mExcAB, dooAC.mExcBA, dooBC.mExcAB, dooBC.mExcBA}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 16 (13) {$SEV_11, $SEV_10, $SEV_9, $SEV_8, $SEV_7, $SEV_6, $SEV_5, $SEV_4, $SEV_3, $SEV_2, $SEV_1, $SEV_0, $TEV_0}
 * Number of clocks: ............................. 0 (0) {}
 * Number of top-level inputs: ................... 0 (0) {}
Notification: [Simulation] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 274 (scalar:262, array:12, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:12)
 * Number of for-loop strong components: ......... 48 (resizable: 48, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 3 (linear: 3, nonlinear:0)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 356 (scalar:338, array:18, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:15)
 * Number of for-loop strong components: ......... 48 (resizable: 48, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 0 (linear: 0, nonlinear:0)
Notification: Performance of backend: time 0.0001391/0.3974, allocations: 125.1 kB / 1.482 GB, free: 15.19 MB / 1.186 GB
Notification: Performance of SimCode: time 0.02421/0.4216, allocations: 19.26 MB / 1.501 GB, free: 11.71 MB / 1.201 GB
Notification: Performance of Templates: time 0.05844/0.48, allocations: 38.91 MB / 1.539 GB, free: 4.953 MB / 1.232 GB
"
[Timeout remaining time 300]
make -j1 -f Buildings_12_Buildings.Airflow.Multizone.Examples.ClosedDoors.makefile [Timeout 300]
(rm -f Buildings_12_Buildings.Airflow.Multizone.Examples.ClosedDoors.pipe ; mkfifo Buildings_12_Buildings.Airflow.Multizone.Examples.ClosedDoors.pipe ; head -c 1048576 < Buildings_12_Buildings.Airflow.Multizone.Examples.ClosedDoors.pipe >> ../files/Buildings_12_Buildings.Airflow.Multizone.Examples.ClosedDoors.sim & ./Buildings_12_Buildings.Airflow.Multizone.Examples.ClosedDoors  -abortSlowSimulation -alarm=400  -emit_protected -lv LOG_STATS > Buildings_12_Buildings.Airflow.Multizone.Examples.ClosedDoors.pipe 2>&1) [Timeout 400]
diffSimulationResults("Buildings_12_Buildings.Airflow.Multizone.Examples.ClosedDoors_res.mat","/mnt/ReferenceFiles/Buildings/csv/maint_12.x/Buildings_Airflow_Multizone_Examples_ClosedDoors.csv","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_12_Buildings.Airflow.Multizone.Examples.ClosedDoors.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 300]
""
[Timeout remaining time 300]
""

Variables in the reference:time,dooAB.vAB,dooAB.vBA,dooAC.vAB,dooAC.vBA,dooBC.vAB,dooBC.vBA

Variables in the result:dooAB.AClo,dooAB.AOpe,dooAB.CDClo,dooAB.CDCloRat,dooAB.CDOpe,dooAB.LClo,dooAB.allowFlowReversal1,dooAB.allowFlowReversal2,dooAB.dh,dooAB.dpCloRat,dooAB.dp_turbulent,dooAB.forceErrorControlOnFlow,dooAB.fraOpe,dooAB.hA,dooAB.hB,dooAB.hOpe,dooAB.m1_flow_nominal,dooAB.m1_flow_small,dooAB.m2_flow_nominal,dooAB.m2_flow_small,dooAB.mClo,dooAB.mOpe,dooAB.nCom,dooAB.rho_default,dooAB.sta_default.T,dooAB.sta_default.X[1],dooAB.sta_default.X[2],dooAB.sta_default.p,dooAB.vAB,dooAB.vBA,dooAB.vZer,dooAB.wOpe,dooAB.y,dooAC.AClo,dooAC.AOpe,dooAC.CDClo,dooAC.CDCloRat,dooAC.CDOpe,dooAC.LClo,dooAC.allowFlowReversal1,dooAC.allowFlowReversal2,dooAC.dh,dooAC.dpCloRat,dooAC.dp_turbulent,dooAC.forceErrorControlOnFlow,dooAC.fraOpe,dooAC.hA,dooAC.hB,dooAC.hOpe,dooAC.m1_flow_nominal,dooAC.m1_flow_small,dooAC.m2_flow_nominal,dooAC.m2_flow_small,dooAC.mClo,dooAC.mOpe,dooAC.nCom,dooAC.rho_default,dooAC.sta_default.T,dooAC.sta_default.X[1],dooAC.sta_default.X[2],dooAC.sta_default.p,dooAC.vAB,dooAC.vBA,dooAC.vZer,dooAC.wOpe,dooAC.y,dooBC.AClo,dooBC.AOpe,dooBC.CDClo,dooBC.CDCloRat,dooBC.CDOpe,dooBC.LClo,dooBC.allowFlowReversal1,dooBC.allowFlowReversal2,dooBC.dh,dooBC.dpCloRat,dooBC.dp_turbulent,dooBC.forceErrorControlOnFlow,dooBC.fraOpe,dooBC.hA,dooBC.hB,dooBC.hOpe,dooBC.m1_flow_nominal,dooBC.m1_flow_small,dooBC.m2_flow_nominal,dooBC.m2_flow_small,dooBC.mClo,dooBC.mOpe,dooBC.nCom,dooBC.rho_default,dooBC.sta_default.T,dooBC.sta_default.X[1],dooBC.sta_default.X[2],dooBC.sta_default.p,dooBC.vAB,dooBC.vBA,dooBC.vZer,dooBC.wOpe,dooBC.y,gai.k,preHeaFlo.T_ref,preHeaFlo.alpha,sinBou.amplitude,sinBou.continuous,sinBou.f,sinBou.offset,sinBou.phase,sinBou.startTime,time,volA.T_start,volA.V,volA.X_start[1],volA.X_start[2],volA.allowFlowReversal,volA.dynBal.CSen,volA.dynBal.Q_flow,volA.dynBal.T_start,volA.dynBal.X_start[1],volA.dynBal.X_start[2],volA.dynBal.computeCSen,volA.dynBal.cp_default,volA.dynBal.energyDynamics,volA.dynBal.fluidVolume,volA.dynBal.hStart,volA.dynBal.mSenFac,volA.dynBal.mWat_flow_internal,volA.dynBal.massDynamics,volA.dynBal.medium.preferredMediumStates,volA.dynBal.medium.standardOrderComponents,volA.dynBal.nPorts,volA.dynBal.p_start,volA.dynBal.rho_default,volA.dynBal.rho_start,volA.dynBal.s[1],volA.dynBal.state_default.T,volA.dynBal.state_default.X[1],volA.dynBal.state_default.X[2],volA.dynBal.state_default.p,volA.dynBal.substanceDynamics,volA.dynBal.traceDynamics,volA.dynBal.use_C_flow,volA.dynBal.use_mWat_flow,volA.dynBal.wrongEnergyMassBalanceConfiguration,volA.energyDynamics,volA.heaFloSen.Q_flow,volA.heaFloSen.port_a.Q_flow,volA.heaFloSen.port_b.Q_flow,volA.heatPort.Q_flow,volA.mSenFac,volA.m_flow_nominal,volA.m_flow_small,volA.massDynamics,volA.nPorts,volA.p_start,volA.preTem.port.Q_flow,volA.rho_default,volA.rho_start,volA.state_default.T,volA.state_default.X[1],volA.state_default.X[2],volA.state_default.p,volA.state_start.T,volA.state_start.X[1],volA.state_start.X[2],volA.state_start.p,volA.substanceDynamics,volA.traceDynamics,volA.useSteadyStateTwoPort,volA.use_C_flow,volA.wrongEnergyMassBalanceConfiguration,volB.T_start,volB.V,volB.X_start[1],volB.X_start[2],volB.allowFlowReversal,volB.dynBal.CSen,volB.dynBal.T_start,volB.dynBal.X_start[1],volB.dynBal.X_start[2],volB.dynBal.computeCSen,volB.dynBal.cp_default,volB.dynBal.energyDynamics,volB.dynBal.fluidVolume,volB.dynBal.hStart,volB.dynBal.mSenFac,volB.dynBal.mWat_flow_internal,volB.dynBal.massDynamics,volB.dynBal.medium.preferredMediumStates,volB.dynBal.medium.standardOrderComponents,volB.dynBal.nPorts,volB.dynBal.p_start,volB.dynBal.rho_default,volB.dynBal.rho_start,volB.dynBal.s[1],volB.dynBal.state_default.T,volB.dynBal.state_default.X[1],volB.dynBal.state_default.X[2],volB.dynBal.state_default.p,volB.dynBal.substanceDynamics,volB.dynBal.traceDynamics,volB.dynBal.use_C_flow,volB.dynBal.use_mWat_flow,volB.dynBal.wrongEnergyMassBalanceConfiguration,volB.energyDynamics,volB.mSenFac,volB.m_flow_nominal,volB.m_flow_small,volB.massDynamics,volB.nPorts,volB.p_start,volB.rho_default,volB.rho_start,volB.state_default.T,volB.state_default.X[1],volB.state_default.X[2],volB.state_default.p,volB.state_start.T,volB.state_start.X[1],volB.state_start.X[2],volB.state_start.p,volB.substanceDynamics,volB.traceDynamics,volB.useSteadyStateTwoPort,volB.use_C_flow,volB.wrongEnergyMassBalanceConfiguration,volC.T_start,volC.V,volC.X_start[1],volC.X_start[2],volC.allowFlowReversal,volC.dynBal.CSen,volC.dynBal.Q_flow,volC.dynBal.T_start,volC.dynBal.X_start[1],volC.dynBal.X_start[2],volC.dynBal.computeCSen,volC.dynBal.cp_default,volC.dynBal.energyDynamics,volC.dynBal.fluidVolume,volC.dynBal.hStart,volC.dynBal.mSenFac,volC.dynBal.mWat_flow_internal,volC.dynBal.massDynamics,volC.dynBal.medium.preferredMediumStates,volC.dynBal.medium.standardOrderComponents,volC.dynBal.nPorts,volC.dynBal.p_start,volC.dynBal.rho_default,volC.dynBal.rho_start,volC.dynBal.s[1],volC.dynBal.state_default.T,volC.dynBal.state_default.X[1],volC.dynBal.state_default.X[2],volC.dynBal.state_default.p,volC.dynBal.substanceDynamics,volC.dynBal.traceDynamics,volC.dynBal.use_C_flow,volC.dynBal.use_mWat_flow,volC.dynBal.wrongEnergyMassBalanceConfiguration,volC.energyDynamics,volC.heaFloSen.Q_flow,volC.heaFloSen.port_a.Q_flow,volC.heaFloSen.port_b.Q_flow,volC.heatPort.Q_flow,volC.mSenFac,volC.m_flow_nominal,volC.m_flow_small,volC.massDynamics,volC.nPorts,volC.p_start,volC.preTem.port.Q_flow,volC.rho_default,volC.rho_start,volC.state_default.T,volC.state_default.X[1],volC.state_default.X[2],volC.state_default.p,volC.state_start.T,volC.state_start.X[1],volC.state_start.X[2],volC.state_start.p,volC.substanceDynamics,volC.traceDynamics,volC.useSteadyStateTwoPort,volC.use_C_flow,volC.wrongEnergyMassBalanceConfiguration,yDoor.k,yDoor.y
[Calling sys.exit(0), Time elapsed: 142.75163366505876]