Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_12_Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser.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.002138/0.002138, allocations: 104.3 kB / 19.71 MB, free: 2.145 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.002272/0.002272, allocations: 211.9 kB / 23.01 MB, free: 4.965 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.246/1.246, allocations: 230.6 MB / 256.8 MB, free: 7.801 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.662/2.662, allocations: 0.49 GB / 0.7958 GB, free: 35.52 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.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|ideUse.yVal_actual|mPre_flow.y|ideUse.senMasFlo.m_flow",fileNamePrefix="Buildings_12_Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser")
translateModel(Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|ideUse.yVal_actual|mPre_flow.y|ideUse.senMasFlo.m_flow",fileNamePrefix="Buildings_12_Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser") [Timeout 300]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.06e-05/1.06e-05, allocations: 2.281 kB / 1.155 GB, free: 25.27 MB / 0.9044 GB
Notification: Performance of NFInst.instantiate(Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser): time 0.01511/0.01512, allocations: 19.9 MB / 1.174 GB, free: 11.9 MB / 0.9044 GB
Notification: Performance of NFInst.instExpressions: time 0.006806/0.02193, allocations: 7.194 MB / 1.181 GB, free: 7.051 MB / 0.9044 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0004175/0.02235, allocations: 34.66 kB / 1.181 GB, free: 7.031 MB / 0.9044 GB
Notification: Performance of NFTyping.typeComponents: time 0.0006652/0.02301, allocations: 337.2 kB / 1.182 GB, free: 6.828 MB / 0.9044 GB
Notification: Performance of NFTyping.typeBindings: time 0.001373/0.02439, allocations: 0.841 MB / 1.183 GB, free: 6.367 MB / 0.9044 GB
Notification: Performance of NFTyping.typeClassSections: time 0.001483/0.02587, allocations: 0.8368 MB / 1.183 GB, free: 5.926 MB / 0.9044 GB
Notification: Performance of NFFlatten.flatten: time 0.001419/0.02729, allocations: 1.877 MB / 1.185 GB, free: 5.031 MB / 0.9044 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0006704/0.02796, allocations: 0.6357 MB / 1.186 GB, free: 4.703 MB / 0.9044 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.001026/0.02898, allocations: 0.9081 MB / 1.187 GB, free: 4.285 MB / 0.9044 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0004536/0.02944, allocations: 0.4897 MB / 1.187 GB, free: 4.051 MB / 0.9044 GB
Notification: Performance of NFPackage.collectConstants: time 9.057e-05/0.02953, allocations: 67.09 kB / 1.187 GB, free: 4.051 MB / 0.9044 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0009861/0.03051, allocations: 0.7841 MB / 1.188 GB, free: 3.836 MB / 0.9044 GB
Notification: Performance of combineBinaries: time 0.0007518/0.03127, allocations: 1.44 MB / 1.19 GB, free: 2.863 MB / 0.9044 GB
Notification: Performance of replaceArrayConstructors: time 0.0003425/0.03161, allocations: 0.9253 MB / 1.19 GB, free: 2.223 MB / 0.9044 GB
Notification: Performance of NFVerifyModel.verify: time 9.161e-05/0.0317, allocations: 57 kB / 1.19 GB, free: 2.223 MB / 0.9044 GB
Notification: Performance of FrontEnd: time 8.797e-05/0.03179, allocations: 29.45 kB / 1.19 GB, free: 2.211 MB / 0.9044 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 109 (105)
 * Number of variables: 109 (109)
Notification: Performance of [SIM] Bindings: time 0.0021/0.03389, allocations: 3.199 MB / 1.194 GB, free: 15.99 MB / 0.92 GB
Notification: Performance of [SIM] FunctionAlias: time 0.0004306/0.03432, allocations: 373.7 kB / 1.194 GB, free: 15.74 MB / 0.92 GB
Notification: Performance of [SIM] Early Inline: time 0.001581/0.0359, allocations: 1.575 MB / 1.196 GB, free: 14.72 MB / 0.92 GB
Notification: Performance of [SIM] Simplify 1: time 0.0004365/0.03634, allocations: 298.4 kB / 1.196 GB, free: 14.53 MB / 0.92 GB
Notification: Performance of [SIM] Alias: time 0.003274/0.03961, allocations: 2.568 MB / 1.198 GB, free: 12.98 MB / 0.92 GB
Notification: Performance of [SIM] Simplify 2: time 0.000286/0.0399, allocations: 202.8 kB / 1.199 GB, free: 12.84 MB / 0.92 GB
Notification: Performance of [SIM] Remove Stream: time 0.0001776/0.04007, allocations: 141.1 kB / 1.199 GB, free: 12.72 MB / 0.92 GB
Notification: Performance of [SIM] Detect States: time 0.0003645/0.04044, allocations: 305.8 kB / 1.199 GB, free: 12.46 MB / 0.92 GB
Notification: Performance of [SIM] Events: time 0.0001021/0.04054, allocations: 61.42 kB / 1.199 GB, free: 12.43 MB / 0.92 GB
Notification: Performance of [SIM] Partitioning: time 0.0004069/0.04095, allocations: 352.3 kB / 1.199 GB, free: 12.23 MB / 0.92 GB
Notification: Performance of [SIM] Causalize: time 0.0007481/0.04169, allocations: 0.575 MB / 1.2 GB, free: 12.05 MB / 0.92 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.0006661/0.04236, allocations: 0.6865 MB / 1.201 GB, free: 11.66 MB / 0.92 GB
Notification: Performance of [INI] Simplify: time 0.001383/0.04374, allocations: 0.9726 MB / 1.202 GB, free: 11.11 MB / 0.92 GB
Notification: Performance of [INI] Inline: time 0.001345/0.04509, allocations: 1.414 MB / 1.203 GB, free: 10.27 MB / 0.92 GB
Notification: Performance of [INI] Partitioning: time 7.75e-05/0.04517, allocations: 48.67 kB / 1.203 GB, free: 10.23 MB / 0.92 GB
Notification: Performance of [INI] Cleanup: time 0.0003517/0.04552, allocations: 357.7 kB / 1.203 GB, free: 10.01 MB / 0.92 GB
Notification: Performance of [INI] Causalize: time 0.004244/0.04976, allocations: 3.666 MB / 1.207 GB, free: 9.219 MB / 0.92 GB
Notification: Performance of [INI] Tearing: time 7.205e-05/0.04983, allocations: 110.9 kB / 1.207 GB, free: 9.137 MB / 0.92 GB
Notification: Performance of [SIM] Initialization: time 6.61e-07/0.04983, allocations: 3.938 kB / 1.207 GB, free: 9.133 MB / 0.92 GB
Notification: Performance of [SIM] Remove Dummies: time 4.799e-06/0.04984, allocations: 1.094 kB / 1.207 GB, free: 9.133 MB / 0.92 GB
Notification: Performance of [SIM] Tearing: time 1.342e-05/0.04985, allocations: 21.56 kB / 1.207 GB, free: 9.117 MB / 0.92 GB
Notification: Performance of [SIM] Categorize: time 8.887e-06/0.04986, allocations: 3.016 kB / 1.207 GB, free: 9.117 MB / 0.92 GB
Notification: Performance of [SIM] Solve: time 0.001401/0.05126, allocations: 1.124 MB / 1.208 GB, free: 8.523 MB / 0.92 GB
Notification: Performance of [SIM] Jacobian: time 0.0005752/0.05184, allocations: 0.5961 MB / 1.209 GB, free: 7.992 MB / 0.92 GB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 2
 * Number of ODE event partitions: ............... 1
 * Number of algebraic event partitions: ......... 1
 * Number of clocked partitions: ................. 0
 * Number of initial partitions: ................. 1
 * Number of initial(lambda=0) partitions: ....... 1
Notification: Variable statistics after passing the back-end:
 * Number of states: ............................. 2 (2) {ideUse.yVal_actual, ideUse.conPI.I.y}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 8 (8) {mPre_flow.a, mPre_flow.b, mPre_flow.last, mPre_flow.nextEvent, mPre_flow.nextEventScaled, $SEV_2, $SEV_1, $SEV_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: ........... 32 (scalar:32, array:0, record:0)
 * Number of multi strong components: ............ 1 (algorithm:1, when:0, if:0, tuple:0)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 1 (linear: 0, nonlinear:1)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 100 (scalar:98, array:2, record:0)
 * Number of multi strong components: ............ 1 (algorithm:1, when:0, if:0, tuple:0)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 1 (linear: 0, nonlinear:1)
Notification: [Initialization (lambda=0)] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 101 (scalar:99, array:2, record:0)
 * Number of multi strong components: ............ 1 (algorithm:1, when:0, if:0, tuple:0)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 0 (linear: 0, nonlinear:0)
Notification: Performance of backend: time 6.61e-05/0.0519, allocations: 64.78 kB / 1.209 GB, free: 7.934 MB / 0.92 GB
Notification: Performance of SimCode: time 0.004162/0.05607, allocations: 3.163 MB / 1.212 GB, free: 6.578 MB / 0.92 GB
Notification: Performance of Templates: time 0.01439/0.07046, allocations: 8.656 MB / 1.22 GB, free: 5.078 MB / 0.92 GB
"
[Timeout remaining time 300]
make -j1 -f Buildings_12_Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser.makefile [Timeout 300]
(rm -f Buildings_12_Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser.pipe ; mkfifo Buildings_12_Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser.pipe ; head -c 1048576 < Buildings_12_Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser.pipe >> ../files/Buildings_12_Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser.sim & ./Buildings_12_Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser  -abortSlowSimulation -alarm=400  -emit_protected -lv LOG_STATS > Buildings_12_Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser.pipe 2>&1) [Timeout 400]
diffSimulationResults("Buildings_12_Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser_res.mat","/mnt/ReferenceFiles/Buildings/csv/maint_12.x/Buildings_DHC_Plants_Cooling_BaseClasses_Validation_IdealUser.csv","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_12_Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 300]
""
[Timeout remaining time 300]
""

Variables in the reference:time,ideUse.yVal_actual,mPre_flow.y,ideUse.senMasFlo.m_flow

Variables in the result:QCooLoa_flow_nominal,T_CHWR_nominal,T_CHWS_nominal,allowFlowReversal,dp_nominal,ideUse.TRet.k,ideUse.TRet.y,ideUse.T_CHWR_nominal,ideUse.conPI.I.initType,ideUse.conPI.I.k,ideUse.conPI.I.reset,ideUse.conPI.I.trigger_internal,ideUse.conPI.I.y_reset,ideUse.conPI.I.y_reset_internal,ideUse.conPI.I.y_start,ideUse.conPI.Nd,ideUse.conPI.Ni,ideUse.conPI.P.k,ideUse.conPI.Td,ideUse.conPI.Ti,ideUse.conPI.addI.k1,ideUse.conPI.addI.k2,ideUse.conPI.addI.k3,ideUse.conPI.addP.k1,ideUse.conPI.addP.k2,ideUse.conPI.addPID.k1,ideUse.conPI.addPID.k2,ideUse.conPI.addPID.k3,ideUse.conPI.addPID.u2,ideUse.conPI.addSat.k1,ideUse.conPI.addSat.k2,ideUse.conPI.controllerType,ideUse.conPI.gainPID.k,ideUse.conPI.gainTrack.k,ideUse.conPI.initType,ideUse.conPI.k,ideUse.conPI.limiter.limitsAtInit,ideUse.conPI.limiter.strict,ideUse.conPI.limiter.uMax,ideUse.conPI.limiter.uMin,ideUse.conPI.reset,ideUse.conPI.revAct,ideUse.conPI.reverseActing,ideUse.conPI.strict,ideUse.conPI.wd,ideUse.conPI.wp,ideUse.conPI.xd_start,ideUse.conPI.xi_start,ideUse.conPI.yMax,ideUse.conPI.yMin,ideUse.conPI.y_reset,ideUse.conPI.y_reset_internal,ideUse.conPI.y_start,ideUse.dp_nominal,ideUse.m_flow_nominal,ideUse.port_a.h_outflow,ideUse.port_b.h_outflow,ideUse.proSou.T_in,ideUse.proSou.T_in_internal,ideUse.proSou.allowFlowReversal,ideUse.proSou.h_T_a,ideUse.proSou.h_T_b,ideUse.proSou.h_internal_a,ideUse.proSou.h_internal_b,ideUse.proSou.port_a.h_outflow,ideUse.proSou.port_b.h_outflow,ideUse.proSou.use_C_in,ideUse.proSou.use_T_in,ideUse.proSou.use_Xi_in,ideUse.senMasFlo.allowFlowReversal,ideUse.senMasFlo.m_flow,ideUse.senMasFlo.m_flow_nominal,ideUse.senMasFlo.m_flow_small,ideUse.senMasFlo.port_b.h_outflow,ideUse.senRelPre.port_a.h_outflow,ideUse.senRelPre.port_a.m_flow,ideUse.senRelPre.port_b.h_outflow,ideUse.senRelPre.port_b.m_flow,ideUse.val.Av,ideUse.val.Cv,ideUse.val.CvData,ideUse.val.Kv,ideUse.val.Kv_SI,ideUse.val.R,ideUse.val._dp_start,ideUse.val._m_flow_start,ideUse.val.actPos.Falling,ideUse.val.actPos.Rising,ideUse.val.actPos.Td,ideUse.val.actPos.initType,ideUse.val.actPos.strict,ideUse.val.actPos.y_start,ideUse.val.allowFlowReversal,ideUse.val.casePreInd,ideUse.val.delta0,ideUse.val.deltaM,ideUse.val.dpFixed_nominal,ideUse.val.dpValve_nominal,ideUse.val.dp_nominal,ideUse.val.dp_nominal_pos,ideUse.val.eta_default,ideUse.val.from_dp,ideUse.val.init,ideUse.val.kFixed,ideUse.val.l,ideUse.val.linearized,ideUse.val.m_flow,ideUse.val.m_flow_nominal,ideUse.val.m_flow_nominal_pos,ideUse.val.m_flow_small,ideUse.val.m_flow_turbulent,ideUse.val.port_a.h_outflow,ideUse.val.rhoStd,ideUse.val.sta_default.T,ideUse.val.sta_default.p,ideUse.val.strokeTime,ideUse.val.use_strokeTime,ideUse.val.y_start,ideUse.yVal_actual,mPre_flow.offset,mPre_flow.shiftTime,mPre_flow.startTime,mPre_flow.table[1,1],mPre_flow.table[1,2],mPre_flow.table[2,1],mPre_flow.table[2,2],mPre_flow.table[3,1],mPre_flow.table[3,2],mPre_flow.table[4,1],mPre_flow.table[4,2],mPre_flow.table[5,1],mPre_flow.table[5,2],mPre_flow.table[6,1],mPre_flow.table[6,2],mPre_flow.timeScale,mPre_flow.timeScaled,mPre_flow.y,m_flow_nominal,p_Pressurisation,sin.T,sin.T_in_internal,sin.X[1],sin.X_in_internal[1],sin.flowDirection,sin.h_internal,sin.nPorts,sin.p,sin.p_in_internal,sin.use_C_in,sin.use_T_in,sin.use_X_in,sin.use_Xi_in,sin.use_p_in,sin.verifyInputs,sou.T,sou.T_in_internal,sou.X[1],sou.X_in_internal[1],sou.flowDirection,sou.h_internal,sou.nPorts,sou.p,sou.p_in_internal,sou.use_C_in,sou.use_T_in,sou.use_X_in,sou.use_Xi_in,sou.use_p_in,sou.verifyInputs,time
[Calling sys.exit(0), Time elapsed: 7.512101663742214]