Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_latest_Buildings.Airflow.Multizone.Examples.NaturalVentilation.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 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.0.0-master/package.mo", uses=false)
Using package Buildings with version 11.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.0.0-master/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+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(Buildings.Airflow.Multizone.Examples.NaturalVentilation,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|volA.T|volOut.T|oriOutBot.v|oriOutTop.v",fileNamePrefix="Buildings_latest_Buildings.Airflow.Multizone.Examples.NaturalVentilation")
translateModel(Buildings.Airflow.Multizone.Examples.NaturalVentilation,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|volA.T|volOut.T|oriOutBot.v|oriOutTop.v",fileNamePrefix="Buildings_latest_Buildings.Airflow.Multizone.Examples.NaturalVentilation")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001294/0.001294, allocations: 112.1 kB / 16.42 MB, free: 6.453 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.001264/0.001264, allocations: 187.9 kB / 17.35 MB, free: 5.738 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.392/1.392, allocations: 222.9 MB / 241.1 MB, free: 15.16 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.0.0-master/package.mo): time 2.567/2.567, allocations: 461.7 MB / 0.7353 GB, free: 10.95 MB / 0.6075 GB
Notification: Performance of FrontEnd - loaded program: time 0.001553/0.001554, allocations: 71.89 kB / 1.055 GB, free: 7.73 MB / 0.8262 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.024/1.025, allocations: 157.4 MB / 1.208 GB, free: 13.67 MB / 0.9356 GB
Notification: Performance of NFInst.instantiate(Buildings.Airflow.Multizone.Examples.NaturalVentilation): time 0.2275/1.253, allocations: 202 MB / 1.405 GB, free: 2.941 MB / 1.123 GB
Notification: Performance of NFInst.instExpressions: time 0.0112/1.264, allocations: 7.396 MB / 1.413 GB, free: 11.53 MB / 1.139 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001707/1.266, allocations: 35.81 kB / 1.413 GB, free: 11.5 MB / 1.139 GB
Notification: Performance of NFTyping.typeComponents: time 0.001907/1.268, allocations: 0.6874 MB / 1.413 GB, free: 10.8 MB / 1.139 GB
Notification: Performance of NFTyping.typeBindings: time 0.006597/1.275, allocations: 2.411 MB / 1.416 GB, free: 8.383 MB / 1.139 GB
Notification: Performance of NFTyping.typeClassSections: time 0.003524/1.278, allocations: 1.241 MB / 1.417 GB, free: 7.141 MB / 1.139 GB
Notification: Performance of NFFlatten.flatten: time 0.004218/1.282, allocations: 2.914 MB / 1.42 GB, free: 4.215 MB / 1.139 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001374/1.284, allocations: 0.943 MB / 1.421 GB, free: 3.25 MB / 1.139 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.001867/1.286, allocations: 1.02 MB / 1.422 GB, free: 2.227 MB / 1.139 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001838/1.288, allocations: 0.9652 MB / 1.423 GB, free: 1.27 MB / 1.139 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001817/1.288, allocations: 112 kB / 1.423 GB, free: 1.16 MB / 1.139 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.004544/1.292, allocations: 2.128 MB / 1.425 GB, free: 15.03 MB / 1.154 GB
Notification: Performance of NFScalarize.scalarize: time 0.0005269/1.293, allocations: 450.8 kB / 1.425 GB, free: 14.59 MB / 1.154 GB
Notification: Performance of NFVerifyModel.verify: time 0.0008573/1.294, allocations: 0.6728 MB / 1.426 GB, free: 13.91 MB / 1.154 GB
Notification: Performance of NFConvertDAE.convert: time 0.004793/1.298, allocations: 2.396 MB / 1.428 GB, free: 11.51 MB / 1.154 GB
Notification: Performance of FrontEnd - DAE generated: time 8.155e-06/1.298, allocations: 4 kB / 1.428 GB, free: 11.5 MB / 1.154 GB
Notification: Performance of FrontEnd: time 2.615e-06/1.299, allocations: 0 / 1.428 GB, free: 11.5 MB / 1.154 GB
Notification: Performance of Transformations before backend: time 2.192e-05/1.299, allocations: 0 / 1.428 GB, free: 11.5 MB / 1.154 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 214
 * Number of variables: 214
Notification: Performance of Generate backend data structure: time 0.003871/1.302, allocations: 1.62 MB / 1.43 GB, free: 9.828 MB / 1.154 GB
Notification: Performance of prepare preOptimizeDAE: time 6.871e-05/1.302, allocations: 16.03 kB / 1.43 GB, free: 9.812 MB / 1.154 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002531/1.305, allocations: 374.1 kB / 1.43 GB, free: 9.445 MB / 1.154 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001506/1.307, allocations: 0.8513 MB / 1.431 GB, free: 8.551 MB / 1.154 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 5.253e-05/1.307, allocations: 65.98 kB / 1.431 GB, free: 8.488 MB / 1.154 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001408/1.307, allocations: 84 kB / 1.431 GB, free: 8.406 MB / 1.154 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001867/1.309, allocations: 1.046 MB / 1.432 GB, free: 7.324 MB / 1.154 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.011e-05/1.309, allocations: 7.844 kB / 1.432 GB, free: 7.316 MB / 1.154 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 9.151e-05/1.309, allocations: 40 kB / 1.432 GB, free: 7.277 MB / 1.154 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 6.878e-05/1.309, allocations: 45.92 kB / 1.432 GB, free: 7.234 MB / 1.154 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001506/1.31, allocations: 0.8783 MB / 1.433 GB, free: 6.355 MB / 1.154 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.005394/1.316, allocations: 4.296 MB / 1.437 GB, free: 1.707 MB / 1.154 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.001134/1.317, allocations: 0.6935 MB / 1.438 GB, free: 1.004 MB / 1.154 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0007525/1.318, allocations: 459 kB / 1.438 GB, free: 0.5547 MB / 1.154 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0006934/1.318, allocations: 0.6771 MB / 1.439 GB, free: 15.73 MB / 1.17 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.755e-05/1.318, allocations: 39.12 kB / 1.439 GB, free: 15.68 MB / 1.17 GB
Notification: Performance of pre-optimization done (n=70): time 3.086e-06/1.318, allocations: 0 / 1.439 GB, free: 15.68 MB / 1.17 GB
Notification: Performance of matching and sorting (n=70): time 0.001909/1.32, allocations: 0.9977 MB / 1.44 GB, free: 14.68 MB / 1.17 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.506e-05/1.32, allocations: 59.97 kB / 1.44 GB, free: 14.61 MB / 1.17 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0007754/1.321, allocations: 0.6373 MB / 1.441 GB, free: 13.96 MB / 1.17 GB
Notification: Performance of collectPreVariables (initialization): time 6.61e-05/1.321, allocations: 41.7 kB / 1.441 GB, free: 13.92 MB / 1.17 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0003128/1.322, allocations: 428.1 kB / 1.441 GB, free: 13.49 MB / 1.17 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0002376/1.322, allocations: 252.3 kB / 1.441 GB, free: 13.24 MB / 1.17 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001901/1.322, allocations: 122.2 kB / 1.442 GB, free: 13.12 MB / 1.17 GB
Notification: Performance of setup shared object (initialization): time 0.0001785/1.322, allocations: 386.7 kB / 1.442 GB, free: 12.73 MB / 1.17 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0004405/1.323, allocations: 277.2 kB / 1.442 GB, free: 12.46 MB / 1.17 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0004983/1.323, allocations: 361 kB / 1.443 GB, free: 12.09 MB / 1.17 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0009444/1.324, allocations: 0.6626 MB / 1.443 GB, free: 11.4 MB / 1.17 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.807e-06/1.324, allocations: 4 kB / 1.443 GB, free: 11.4 MB / 1.17 GB
Notification: Performance of matching and sorting (n=82) (initialization): time 0.001236/1.325, allocations: 0.7892 MB / 1.444 GB, free: 10.6 MB / 1.17 GB
Notification: Performance of prepare postOptimizeDAE: time 2.028e-05/1.325, allocations: 8.188 kB / 1.444 GB, free: 10.59 MB / 1.17 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 8.116e-06/1.325, allocations: 4 kB / 1.444 GB, free: 10.59 MB / 1.17 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.07e-05/1.325, allocations: 4 kB / 1.444 GB, free: 10.59 MB / 1.17 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0006169/1.326, allocations: 139.8 kB / 1.444 GB, free: 10.45 MB / 1.17 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.628e-05/1.326, allocations: 11.94 kB / 1.444 GB, free: 10.44 MB / 1.17 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0007173/1.327, allocations: 55.42 kB / 1.444 GB, free: 10.38 MB / 1.17 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 4.753e-05/1.327, allocations: 23.94 kB / 1.444 GB, free: 10.36 MB / 1.17 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 7
 * 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 (82):
 * Single equations (assignments): 82
 * 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.0004486/1.327, allocations: 289.6 kB / 1.445 GB, free: 10.07 MB / 1.17 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001393/1.327, allocations: 86.97 kB / 1.445 GB, free: 9.98 MB / 1.17 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001395/1.329, allocations: 0.7881 MB / 1.445 GB, free: 9.176 MB / 1.17 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001042/1.33, allocations: 0.823 MB / 1.446 GB, free: 8.301 MB / 1.17 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.05e-06/1.33, allocations: 3.938 kB / 1.446 GB, free: 8.297 MB / 1.17 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0003884/1.33, allocations: 244 kB / 1.446 GB, free: 8.039 MB / 1.17 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001117/1.33, allocations: 15.98 kB / 1.446 GB, free: 8.023 MB / 1.17 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0005872/1.331, allocations: 27.91 kB / 1.446 GB, free: 7.996 MB / 1.17 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.000108/1.331, allocations: 40.5 kB / 1.446 GB, free: 7.957 MB / 1.17 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.001742/1.333, allocations: 0.9126 MB / 1.447 GB, free: 7.035 MB / 1.17 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.00175/1.335, allocations: 1.321 MB / 1.449 GB, free: 5.699 MB / 1.17 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0005096/1.335, allocations: 364.3 kB / 1.449 GB, free: 5.348 MB / 1.17 GB
Notification: Performance of sorting global known variables: time 0.0005525/1.336, allocations: 455 kB / 1.449 GB, free: 4.91 MB / 1.17 GB
Notification: Performance of Backend: time 4.41e-07/1.336, allocations: 0 / 1.449 GB, free: 4.91 MB / 1.17 GB
Notification: Performance of simCode: created initialization part: time 0.002309/1.338, allocations: 0.921 MB / 1.45 GB, free: 3.984 MB / 1.17 GB
Notification: Performance of SimCode: time 0.004212/1.342, allocations: 2.72 MB / 1.453 GB, free: 1.223 MB / 1.17 GB
Notification: Performance of Templates: time 0.03691/1.379, allocations: 23.12 MB / 1.476 GB, free: 10.29 MB / 1.201 GB
make -j1 -f Buildings_latest_Buildings.Airflow.Multizone.Examples.NaturalVentilation.makefile
(rm -f Buildings_latest_Buildings.Airflow.Multizone.Examples.NaturalVentilation.pipe ; mkfifo Buildings_latest_Buildings.Airflow.Multizone.Examples.NaturalVentilation.pipe ; head -c 1048576 < Buildings_latest_Buildings.Airflow.Multizone.Examples.NaturalVentilation.pipe >> ../files/Buildings_latest_Buildings.Airflow.Multizone.Examples.NaturalVentilation.sim & ./Buildings_latest_Buildings.Airflow.Multizone.Examples.NaturalVentilation  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Buildings_latest_Buildings.Airflow.Multizone.Examples.NaturalVentilation.pipe 2>&1)
diffSimulationResults("Buildings_latest_Buildings.Airflow.Multizone.Examples.NaturalVentilation_res.mat","/mnt/ReferenceFiles/Buildings/csv/master/Buildings_Airflow_Multizone_Examples_NaturalVentilation.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_latest_Buildings.Airflow.Multizone.Examples.NaturalVentilation.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001)
""

Variables in the reference:time,volA.T,volOut.T,oriOutBot.v,oriOutTop.v

Variables in the result:colOut.densitySelection,colOut.h,colRooTop.densitySelection,colRooTop.h,oriOutBot.A,oriOutBot.C,oriOutBot.CD,oriOutBot._dp_start,oriOutBot._m_flow_start,oriOutBot.a,oriOutBot.allowFlowReversal,oriOutBot.b,oriOutBot.c,oriOutBot.d,oriOutBot.dp_turbulent,oriOutBot.dynVis,oriOutBot.dynVis_default,oriOutBot.forceErrorControlOnFlow,oriOutBot.m,oriOutBot.m_flow_nominal,oriOutBot.m_flow_small,oriOutBot.rho,oriOutBot.rho_default,oriOutBot.sta.T,oriOutBot.sta.X[1],oriOutBot.sta.X[2],oriOutBot.sta.p,oriOutBot.sta_default.T,oriOutBot.sta_default.X[1],oriOutBot.sta_default.X[2],oriOutBot.sta_default.p,oriOutBot.useDefaultProperties,oriOutBot.v,oriOutTop.A,oriOutTop.C,oriOutTop.CD,oriOutTop._dp_start,oriOutTop._m_flow_start,oriOutTop.a,oriOutTop.allowFlowReversal,oriOutTop.b,oriOutTop.c,oriOutTop.d,oriOutTop.dp_turbulent,oriOutTop.dynVis,oriOutTop.dynVis_default,oriOutTop.forceErrorControlOnFlow,oriOutTop.m,oriOutTop.m_flow_nominal,oriOutTop.m_flow_small,oriOutTop.rho,oriOutTop.rho_default,oriOutTop.sta.T,oriOutTop.sta.X[1],oriOutTop.sta.X[2],oriOutTop.sta.p,oriOutTop.sta_default.T,oriOutTop.sta_default.X[1],oriOutTop.sta_default.X[2],oriOutTop.sta_default.p,oriOutTop.useDefaultProperties,oriOutTop.v,preHeaFlo.T_ref,preHeaFlo.alpha,q_flow.height,q_flow.offset,q_flow.startTime,time,volA.T,volA.T_start,volA.V,volA.X_start[1],volA.X_start[2],volA.allowFlowReversal,volA.dynBal.CSen,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.initialize_p,volA.dynBal.mSenFac,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.mSenFac,volA.m_flow_nominal,volA.m_flow_small,volA.massDynamics,volA.nPorts,volA.p_start,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,volOut.T,volOut.T_start,volOut.V,volOut.X_start[1],volOut.X_start[2],volOut.allowFlowReversal,volOut.dynBal.CSen,volOut.dynBal.T_start,volOut.dynBal.X_start[1],volOut.dynBal.X_start[2],volOut.dynBal.computeCSen,volOut.dynBal.cp_default,volOut.dynBal.energyDynamics,volOut.dynBal.fluidVolume,volOut.dynBal.hStart,volOut.dynBal.initialize_p,volOut.dynBal.mSenFac,volOut.dynBal.massDynamics,volOut.dynBal.medium.preferredMediumStates,volOut.dynBal.medium.standardOrderComponents,volOut.dynBal.nPorts,volOut.dynBal.p_start,volOut.dynBal.rho_default,volOut.dynBal.rho_start,volOut.dynBal.s[1],volOut.dynBal.state_default.T,volOut.dynBal.state_default.X[1],volOut.dynBal.state_default.X[2],volOut.dynBal.state_default.p,volOut.dynBal.substanceDynamics,volOut.dynBal.traceDynamics,volOut.dynBal.use_C_flow,volOut.dynBal.use_mWat_flow,volOut.dynBal.wrongEnergyMassBalanceConfiguration,volOut.energyDynamics,volOut.mSenFac,volOut.m_flow_nominal,volOut.m_flow_small,volOut.massDynamics,volOut.nPorts,volOut.p_start,volOut.rho_default,volOut.rho_start,volOut.state_default.T,volOut.state_default.X[1],volOut.state_default.X[2],volOut.state_default.p,volOut.state_start.T,volOut.state_start.X[1],volOut.state_start.X[2],volOut.state_start.p,volOut.substanceDynamics,volOut.traceDynamics,volOut.useSteadyStateTwoPort,volOut.use_C_flow,volOut.wrongEnergyMassBalanceConfiguration