Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_latest_Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001485/0.001485, allocations: 112.7 kB / 18.34 MB, free: 3.961 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001456/0.001456, allocations: 190 kB / 21.52 MB, free: 0.7969 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.3/1.3, allocations: 225.6 MB / 250.1 MB, free: 13.5 MB / 206.1 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.0.0-master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.0.0-master/package.mo): time 2.838/2.838, allocations: 496.1 MB / 0.7826 GB, free: 33.61 MB / 0.6231 GB
"
[Timeout remaining time 177]
Using package Buildings with version 12.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.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.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_latest_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_latest_Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser") [Timeout 300]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.667e-05/1.667e-05, allocations: 4.531 kB / 1.136 GB, free: 33.91 MB / 0.9044 GB
Notification: Performance of NFInst.instantiate(Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser): time 0.01587/0.01589, allocations: 21.75 MB / 1.158 GB, free: 18.5 MB / 0.9044 GB
Notification: Performance of NFInst.instExpressions: time 0.007726/0.02362, allocations: 7.801 MB / 1.165 GB, free: 12.98 MB / 0.9044 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0006739/0.02429, allocations: 26.69 kB / 1.165 GB, free: 12.97 MB / 0.9044 GB
Notification: Performance of NFTyping.typeComponents: time 0.000791/0.02508, allocations: 334.2 kB / 1.166 GB, free: 12.77 MB / 0.9044 GB
Notification: Performance of NFTyping.typeBindings: time 0.001655/0.02674, allocations: 0.8369 MB / 1.167 GB, free: 12.3 MB / 0.9044 GB
Notification: Performance of NFTyping.typeClassSections: time 0.001745/0.02848, allocations: 0.8358 MB / 1.167 GB, free: 11.86 MB / 0.9044 GB
Notification: Performance of NFFlatten.flatten: time 0.001739/0.03022, allocations: 1.833 MB / 1.169 GB, free: 11 MB / 0.9044 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.000724/0.03094, allocations: 0.6196 MB / 1.17 GB, free: 10.68 MB / 0.9044 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.001175/0.03212, allocations: 0.8832 MB / 1.171 GB, free: 10.27 MB / 0.9044 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0004785/0.0326, allocations: 0.4957 MB / 1.171 GB, free: 10.04 MB / 0.9044 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001068/0.0327, allocations: 67.28 kB / 1.171 GB, free: 10.04 MB / 0.9044 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.001249/0.03395, allocations: 0.8842 MB / 1.172 GB, free: 9.777 MB / 0.9044 GB
Notification: Performance of combineBinaries: time 0.0006779/0.03463, allocations: 1.301 MB / 1.173 GB, free: 8.902 MB / 0.9044 GB
Notification: Performance of replaceArrayConstructors: time 0.0003025/0.03493, allocations: 0.7781 MB / 1.174 GB, free: 8.363 MB / 0.9044 GB
Notification: Performance of NFVerifyModel.verify: time 0.0001147/0.03505, allocations: 131.7 kB / 1.174 GB, free: 8.289 MB / 0.9044 GB
Notification: Performance of FrontEnd: time 0.0001453/0.03519, allocations: 39.03 kB / 1.174 GB, free: 8.27 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 Bindings: time 0.001739/0.03693, allocations: 2.495 MB / 1.177 GB, free: 6.473 MB / 0.9044 GB
Notification: Performance of FunctionAlias: time 0.0002099/0.03714, allocations: 259 kB / 1.177 GB, free: 6.285 MB / 0.9044 GB
Notification: Performance of Early Inline: time 0.000936/0.03808, allocations: 1.142 MB / 1.178 GB, free: 5.52 MB / 0.9044 GB
Notification: Performance of Simplify 1: time 0.000226/0.0383, allocations: 215 kB / 1.178 GB, free: 5.387 MB / 0.9044 GB
Notification: Performance of Alias: time 0.002533/0.04084, allocations: 2.444 MB / 1.181 GB, free: 3.891 MB / 0.9044 GB
Notification: Performance of Simplify 2: time 0.0001385/0.04098, allocations: 139.2 kB / 1.181 GB, free: 3.793 MB / 0.9044 GB
Notification: Performance of Remove Stream: time 0.0001056/0.04108, allocations: 129.1 kB / 1.181 GB, free: 3.688 MB / 0.9044 GB
Notification: Performance of Detect States: time 0.0002812/0.04136, allocations: 262.9 kB / 1.181 GB, free: 3.477 MB / 0.9044 GB
Notification: Performance of Events: time 0.0001284/0.04149, allocations: 97.2 kB / 1.181 GB, free: 3.422 MB / 0.9044 GB
Notification: Performance of Partitioning: time 0.0002769/0.04177, allocations: 322.2 kB / 1.181 GB, free: 3.199 MB / 0.9044 GB
Notification: Performance of Causalize: time 0.0009002/0.04267, allocations: 0.8776 MB / 1.182 GB, free: 2.859 MB / 0.9044 GB
Notification: Performance of After Index Reduction Inline: time 0.000403/0.04307, allocations: 477.8 kB / 1.183 GB, free: 2.582 MB / 0.9044 GB
Notification: Performance of Simplify: time 0.000931/0.044, allocations: 0.8464 MB / 1.184 GB, free: 2.09 MB / 0.9044 GB
Notification: Performance of Inline: time 0.0008959/0.0449, allocations: 1.042 MB / 1.185 GB, free: 1.453 MB / 0.9044 GB
Notification: Performance of Partitioning: time 6.099e-05/0.04496, allocations: 45.61 kB / 1.185 GB, free: 1.422 MB / 0.9044 GB
Notification: Performance of Cleanup: time 0.0002763/0.04524, allocations: 381.6 kB / 1.185 GB, free: 1.191 MB / 0.9044 GB
Warning: NBResolveSingularities.balanceInitialization reports an overdetermined initialization!
Checking for consistency is not yet supported, following equations had to be removed:
	[SCAL] (1) mPre_flow.nextEventScaled = $START.mPre_flow.nextEventScaled ($RES_SRT_128);
Notification: Performance of Causalize: time 0.008382/0.05362, allocations: 7.11 MB / 1.192 GB, free: 14.87 MB / 0.92 GB
Notification: Performance of Tearing: time 2.521e-05/0.05364, allocations: 0 / 1.192 GB, free: 14.87 MB / 0.92 GB
Notification: Performance of Initialization: time 1.372e-06/0.05364, allocations: 1.375 kB / 1.192 GB, free: 14.87 MB / 0.92 GB
Notification: Performance of Remove Dummies: time 7.634e-06/0.05365, allocations: 288 / 1.192 GB, free: 14.87 MB / 0.92 GB
Notification: Performance of Tearing: time 2.565e-06/0.05365, allocations: 1.375 kB / 1.192 GB, free: 14.87 MB / 0.92 GB
Notification: Performance of Categorize: time 1.766e-05/0.05367, allocations: 7.453 kB / 1.192 GB, free: 14.87 MB / 0.92 GB
Notification: Performance of Solve: time 0.001323/0.055, allocations: 1.108 MB / 1.193 GB, free: 13.98 MB / 0.92 GB
Notification: Performance of Jacobian: time 0.0003972/0.05539, allocations: 397.2 kB / 1.193 GB, free: 13.67 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: ................. 12 (12) {mPre_flow.a, mPre_flow.b, mPre_flow.last, mPre_flow.nextEvent, mPre_flow.nextEventScaled, $SEV_6, $SEV_5, $SEV_4, $SEV_3, $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: ........... 36 (scalar:36, 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: ........... 107 (scalar:105, 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: ........... 108 (scalar:106, 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 0.0001286/0.05552, allocations: 59.95 kB / 1.194 GB, free: 13.62 MB / 0.92 GB
Notification: Performance of SimCode: time 0.004934/0.06046, allocations: 3.643 MB / 1.197 GB, free: 11.73 MB / 0.92 GB
Notification: Performance of Templates: time 0.01593/0.07638, allocations: 8.331 MB / 1.205 GB, free: 10.96 MB / 0.92 GB
"
[Timeout remaining time 300]
make -j1 -f Buildings_latest_Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser.makefile [Timeout 300]
(rm -f Buildings_latest_Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser.pipe ; mkfifo Buildings_latest_Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser.pipe ; head -c 1048576 < Buildings_latest_Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser.pipe >> ../files/Buildings_latest_Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser.sim & ./Buildings_latest_Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser  -abortSlowSimulation -alarm=400  -emit_protected -lv LOG_STATS > Buildings_latest_Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser.pipe 2>&1) [Timeout 400]
diffSimulationResults("Buildings_latest_Buildings.DHC.Plants.Cooling.BaseClasses.Validation.IdealUser_res.mat","/mnt/ReferenceFiles/Buildings/csv/master/Buildings_DHC_Plants_Cooling_BaseClasses_Validation_IdealUser.csv","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_latest_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.969053043983877]