Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_latest_Buildings.DHC.Networks.Controls.Examples.AgentPump1PipeExample.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.001475/0.001475, 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.001443/0.001443, 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.305/1.305, 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.816/2.816, allocations: 496.1 MB / 0.7826 GB, free: 33.5 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.Networks.Controls.Examples.AgentPump1PipeExample,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|AgentPump.TSouIn|AgentPump.TSouOut|AgentPump.TSou|AgentPump.TSupDis|AgentPump.TRetDis|AgentPump.y",fileNamePrefix="Buildings_latest_Buildings.DHC.Networks.Controls.Examples.AgentPump1PipeExample")
translateModel(Buildings.DHC.Networks.Controls.Examples.AgentPump1PipeExample,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|AgentPump.TSouIn|AgentPump.TSouOut|AgentPump.TSou|AgentPump.TSupDis|AgentPump.TRetDis|AgentPump.y",fileNamePrefix="Buildings_latest_Buildings.DHC.Networks.Controls.Examples.AgentPump1PipeExample") [Timeout 300]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.671e-05/1.671e-05, allocations: 7.891 kB / 1.136 GB, free: 33.88 MB / 0.9044 GB
Notification: Performance of NFInst.instantiate(Buildings.DHC.Networks.Controls.Examples.AgentPump1PipeExample): time 0.003721/0.003738, allocations: 3.74 MB / 1.14 GB, free: 31.2 MB / 0.9044 GB
Notification: Performance of NFInst.instExpressions: time 0.002136/0.005874, allocations: 1.751 MB / 1.142 GB, free: 29.95 MB / 0.9044 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0002332/0.006107, allocations: 14.66 kB / 1.142 GB, free: 29.94 MB / 0.9044 GB
Notification: Performance of NFTyping.typeComponents: time 0.0004887/0.006596, allocations: 273 kB / 1.142 GB, free: 29.84 MB / 0.9044 GB
Notification: Performance of NFTyping.typeBindings: time 0.0004117/0.007008, allocations: 263.5 kB / 1.142 GB, free: 29.7 MB / 0.9044 GB
Notification: Performance of NFTyping.typeClassSections: time 0.0006265/0.007634, allocations: 465.2 kB / 1.143 GB, free: 29.42 MB / 0.9044 GB
Notification: Performance of NFFlatten.flatten: time 0.0007287/0.008363, allocations: 1.098 MB / 1.144 GB, free: 28.89 MB / 0.9044 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0003099/0.008673, allocations: 229.7 kB / 1.144 GB, free: 28.74 MB / 0.9044 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.0003274/0.009, allocations: 423 kB / 1.145 GB, free: 28.54 MB / 0.9044 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0002386/0.009239, allocations: 363.5 kB / 1.145 GB, free: 28.35 MB / 0.9044 GB
Notification: Performance of NFPackage.collectConstants: time 9.702e-05/0.009336, allocations: 77 kB / 1.145 GB, free: 28.35 MB / 0.9044 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0001938/0.00953, allocations: 118.8 kB / 1.145 GB, free: 28.34 MB / 0.9044 GB
Notification: Performance of combineBinaries: time 0.0006016/0.01013, allocations: 1.237 MB / 1.146 GB, free: 27.45 MB / 0.9044 GB
Notification: Performance of replaceArrayConstructors: time 0.0003211/0.01045, allocations: 0.7589 MB / 1.147 GB, free: 26.9 MB / 0.9044 GB
Notification: Performance of NFVerifyModel.verify: time 0.0001012/0.01055, allocations: 115.2 kB / 1.147 GB, free: 26.82 MB / 0.9044 GB
Notification: Performance of FrontEnd: time 9.459e-05/0.01065, allocations: 41.78 kB / 1.147 GB, free: 26.8 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: 110 (110)
 * Number of variables: 110 (110)
Notification: Performance of Bindings: time 0.001668/0.01232, allocations: 2.472 MB / 1.15 GB, free: 24.98 MB / 0.9044 GB
Notification: Performance of FunctionAlias: time 0.0001707/0.01249, allocations: 239 kB / 1.15 GB, free: 24.82 MB / 0.9044 GB
Notification: Performance of Early Inline: time 0.0007087/0.0132, allocations: 0.9141 MB / 1.151 GB, free: 24.22 MB / 0.9044 GB
Notification: Performance of Simplify 1: time 0.0001803/0.01338, allocations: 182 kB / 1.151 GB, free: 24.11 MB / 0.9044 GB
Notification: Performance of Alias: time 0.002619/0.01599, allocations: 2.46 MB / 1.153 GB, free: 22.56 MB / 0.9044 GB
Notification: Performance of Simplify 2: time 0.000101/0.0161, allocations: 123.7 kB / 1.153 GB, free: 22.47 MB / 0.9044 GB
Notification: Performance of Remove Stream: time 8.271e-05/0.01618, allocations: 126.1 kB / 1.153 GB, free: 22.36 MB / 0.9044 GB
Notification: Performance of Detect States: time 0.0004479/0.01663, allocations: 295.5 kB / 1.154 GB, free: 22.13 MB / 0.9044 GB
Notification: Performance of Events: time 0.0001729/0.0168, allocations: 158.8 kB / 1.154 GB, free: 22.04 MB / 0.9044 GB
Notification: Performance of Partitioning: time 0.0002829/0.01708, allocations: 319.6 kB / 1.154 GB, free: 21.87 MB / 0.9044 GB
Notification: Performance of Causalize: time 0.001193/0.01828, allocations: 1.032 MB / 1.155 GB, free: 21.53 MB / 0.9044 GB
Notification: Performance of After Index Reduction Inline: time 0.0004132/0.01869, allocations: 0.5383 MB / 1.156 GB, free: 21.21 MB / 0.9044 GB
Notification: Performance of Simplify: time 0.004373/0.02306, allocations: 1.373 MB / 1.157 GB, free: 20.41 MB / 0.9044 GB
Notification: Performance of Inline: time 0.0009915/0.02405, allocations: 1.07 MB / 1.158 GB, free: 19.77 MB / 0.9044 GB
Notification: Performance of Partitioning: time 9.287e-05/0.02415, allocations: 62.88 kB / 1.158 GB, free: 19.72 MB / 0.9044 GB
Notification: Performance of Cleanup: time 0.0003415/0.02449, allocations: 458.9 kB / 1.159 GB, free: 19.43 MB / 0.9044 GB
Notification: Performance of Causalize: time 0.00757/0.03206, allocations: 6.506 MB / 1.165 GB, free: 17.29 MB / 0.9044 GB
Notification: Performance of Tearing: time 3.585e-05/0.03209, allocations: 0 / 1.165 GB, free: 17.29 MB / 0.9044 GB
Notification: Performance of Initialization: time 1.533e-06/0.03209, allocations: 1.281 kB / 1.165 GB, free: 17.29 MB / 0.9044 GB
Notification: Performance of Remove Dummies: time 9.338e-06/0.0321, allocations: 448 / 1.165 GB, free: 17.29 MB / 0.9044 GB
Notification: Performance of Tearing: time 3.156e-06/0.03211, allocations: 1.219 kB / 1.165 GB, free: 17.29 MB / 0.9044 GB
Notification: Performance of Categorize: time 1.366e-05/0.03212, allocations: 3.188 kB / 1.165 GB, free: 17.29 MB / 0.9044 GB
Notification: Performance of Solve: time 0.0007424/0.03286, allocations: 344.8 kB / 1.165 GB, free: 17.16 MB / 0.9044 GB
Notification: Performance of Jacobian: time 3.657e-06/0.03287, allocations: 0 / 1.165 GB, free: 17.16 MB / 0.9044 GB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 0
 * Number of ODE event partitions: ............... 0
 * 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: ............................. 0 (0) {}
 * Number of discrete states: .................... 6 (6) {TretDis.nextTimeEvent, TretDis.nextTimeEventScaled, TSouOut.nextTimeEvent, TSouOut.nextTimeEventScaled, TSouIn.nextTimeEvent, TSouIn.nextTimeEventScaled}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 16 (16) {AgentPump.swi.u2, AgentPump.hys.y, AgentPump.hys1.y, $SEV_9, $SEV_8, $SEV_7, $SEV_6, $SEV_5, $SEV_4, $SEV_3, $SEV_2, $SEV_1, $SEV_0, $TEV_2, $TEV_1, $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: ........... 46 (scalar:46, array:0, record:0)
 * Number of multi strong components: ............ 6 (algorithm:0, when:6, 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: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 160 (scalar:157, array:0, record:3)
 * Number of multi strong components: ............ 6 (algorithm:6, 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: [Initialization (lambda=0)] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 160 (scalar:157, array:0, record:3)
 * Number of multi strong components: ............ 6 (algorithm:6, 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.291e-05/0.03293, allocations: 98.11 kB / 1.165 GB, free: 17.07 MB / 0.9044 GB
Notification: Performance of SimCode: time 0.004311/0.03724, allocations: 3.408 MB / 1.169 GB, free: 15.24 MB / 0.9044 GB
Notification: Performance of Templates: time 0.01207/0.04931, allocations: 6.337 MB / 1.175 GB, free: 14.7 MB / 0.9044 GB
"
[Timeout remaining time 300]
make -j1 -f Buildings_latest_Buildings.DHC.Networks.Controls.Examples.AgentPump1PipeExample.makefile [Timeout 300]
(rm -f Buildings_latest_Buildings.DHC.Networks.Controls.Examples.AgentPump1PipeExample.pipe ; mkfifo Buildings_latest_Buildings.DHC.Networks.Controls.Examples.AgentPump1PipeExample.pipe ; head -c 1048576 < Buildings_latest_Buildings.DHC.Networks.Controls.Examples.AgentPump1PipeExample.pipe >> ../files/Buildings_latest_Buildings.DHC.Networks.Controls.Examples.AgentPump1PipeExample.sim & ./Buildings_latest_Buildings.DHC.Networks.Controls.Examples.AgentPump1PipeExample  -abortSlowSimulation -alarm=400  -emit_protected -lv LOG_STATS > Buildings_latest_Buildings.DHC.Networks.Controls.Examples.AgentPump1PipeExample.pipe 2>&1) [Timeout 400]
diffSimulationResults("Buildings_latest_Buildings.DHC.Networks.Controls.Examples.AgentPump1PipeExample_res.mat","/mnt/ReferenceFiles/Buildings/csv/master/Buildings_DHC_Networks_Controls_Examples_AgentPump1PipeExample.csv","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_latest_Buildings.DHC.Networks.Controls.Examples.AgentPump1PipeExample.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 300]
""
[Timeout remaining time 300]
""

Variables in the reference:time,AgentPump.TSouIn,AgentPump.TSouOut,AgentPump.TSou,AgentPump.TSupDis,AgentPump.TRetDis,AgentPump.y

Variables in the result:AgentPump.NetDemBool.h,AgentPump.NetDemBool.have_hysteresis,AgentPump.NetDemBool.lesHys.h,AgentPump.NetDemBool.lesHys.pre_y_start,AgentPump.NetDemBool.lesHys.u2,AgentPump.NetDemBool.pre_y_start,AgentPump.NetDemBool.u2,AgentPump.TRetDis,AgentPump.TSou,AgentPump.TSouIn,AgentPump.TSouOut,AgentPump.TSupDis,AgentPump.Td,AgentPump.Ti,AgentPump.Tsou_negshift.p,AgentPump.Tsou_negshift.u,AgentPump.Tsou_negshift.y,AgentPump.Tsou_posshift.p,AgentPump.Tsou_posshift.u,AgentPump.Tsou_posshift.y,AgentPump.Zero.k,AgentPump.Zero.y,AgentPump.conPIDCoo.Izero.k,AgentPump.conPIDCoo.Izero.y,AgentPump.conPIDCoo.Nd,AgentPump.conPIDCoo.Ni,AgentPump.conPIDCoo.P.k,AgentPump.conPIDCoo.Td,AgentPump.conPIDCoo.Ti,AgentPump.conPIDCoo.addPD.u2,AgentPump.conPIDCoo.addPID.u2,AgentPump.conPIDCoo.assMesYMinMax.u,AgentPump.conPIDCoo.cheYMinMax.k,AgentPump.conPIDCoo.cheYMinMax.y,AgentPump.conPIDCoo.controlError.u1,AgentPump.conPIDCoo.controllerType,AgentPump.conPIDCoo.k,AgentPump.conPIDCoo.lim.uMax,AgentPump.conPIDCoo.lim.uMin,AgentPump.conPIDCoo.r,AgentPump.conPIDCoo.revAct,AgentPump.conPIDCoo.reverseActing,AgentPump.conPIDCoo.uMea_revAct.k,AgentPump.conPIDCoo.uS_revAct.k,AgentPump.conPIDCoo.uS_revAct.u,AgentPump.conPIDCoo.u_s,AgentPump.conPIDCoo.xi_start,AgentPump.conPIDCoo.yMax,AgentPump.conPIDCoo.yMin,AgentPump.conPIDCoo.yd_start,AgentPump.conPIDHea.Izero.k,AgentPump.conPIDHea.Izero.y,AgentPump.conPIDHea.Nd,AgentPump.conPIDHea.Ni,AgentPump.conPIDHea.P.k,AgentPump.conPIDHea.Td,AgentPump.conPIDHea.Ti,AgentPump.conPIDHea.addPD.u2,AgentPump.conPIDHea.addPID.u2,AgentPump.conPIDHea.assMesYMinMax.u,AgentPump.conPIDHea.cheYMinMax.k,AgentPump.conPIDHea.cheYMinMax.y,AgentPump.conPIDHea.controlError.u1,AgentPump.conPIDHea.controllerType,AgentPump.conPIDHea.k,AgentPump.conPIDHea.lim.uMax,AgentPump.conPIDHea.lim.uMin,AgentPump.conPIDHea.r,AgentPump.conPIDHea.revAct,AgentPump.conPIDHea.reverseActing,AgentPump.conPIDHea.uMea_revAct.k,AgentPump.conPIDHea.uS_revAct.k,AgentPump.conPIDHea.uS_revAct.u,AgentPump.conPIDHea.u_s,AgentPump.conPIDHea.xi_start,AgentPump.conPIDHea.yMax,AgentPump.conPIDHea.yMin,AgentPump.conPIDHea.yd_start,AgentPump.controllerType,AgentPump.dTSouSup.u1,AgentPump.dTSouSupCoo.p,AgentPump.dTSouSupHea.p,AgentPump.dToff,AgentPump.gai1.k,AgentPump.h,AgentPump.hys.pre_y_start,AgentPump.hys.uHigh,AgentPump.hys.uLow,AgentPump.hys1.pre_y_start,AgentPump.hys1.uHigh,AgentPump.hys1.uLow,AgentPump.k,AgentPump.swi1.u3,AgentPump.swi2.u3,AgentPump.uHighCoo,AgentPump.uHighHea,AgentPump.uLowCoo,AgentPump.uLowHea,AgentPump.y,AgentPump.yPumMax,AgentPump.yPumMin,TSou,TSouIn.columns[1],TSouIn.extrapolation,TSouIn.nout,TSouIn.offset[1],TSouIn.p_offset[1],TSouIn.shiftTime,TSouIn.smoothness,TSouIn.startTime,TSouIn.t_max,TSouIn.t_maxScaled,TSouIn.t_min,TSouIn.t_minScaled,TSouIn.tableOnFile,TSouIn.table[1,1],TSouIn.table[1,2],TSouIn.table[2,1],TSouIn.table[2,2],TSouIn.table[3,1],TSouIn.table[3,2],TSouIn.table[4,1],TSouIn.table[4,2],TSouIn.timeEvents,TSouIn.timeScale,TSouIn.timeScaled,TSouIn.verboseExtrapolation,TSouIn.verboseRead,TSouOut.columns[1],TSouOut.extrapolation,TSouOut.nout,TSouOut.offset[1],TSouOut.p_offset[1],TSouOut.shiftTime,TSouOut.smoothness,TSouOut.startTime,TSouOut.t_max,TSouOut.t_maxScaled,TSouOut.t_min,TSouOut.t_minScaled,TSouOut.tableOnFile,TSouOut.table[1,1],TSouOut.table[1,2],TSouOut.table[2,1],TSouOut.table[2,2],TSouOut.table[3,1],TSouOut.table[3,2],TSouOut.table[4,1],TSouOut.table[4,2],TSouOut.timeEvents,TSouOut.timeScale,TSouOut.timeScaled,TSouOut.verboseExtrapolation,TSouOut.verboseRead,TretDis.columns[1],TretDis.extrapolation,TretDis.nout,TretDis.offset[1],TretDis.p_offset[1],TretDis.shiftTime,TretDis.smoothness,TretDis.startTime,TretDis.t_max,TretDis.t_maxScaled,TretDis.t_min,TretDis.t_minScaled,TretDis.tableOnFile,TretDis.table[1,1],TretDis.table[1,2],TretDis.table[2,1],TretDis.table[2,2],TretDis.table[3,1],TretDis.table[3,2],TretDis.table[4,1],TretDis.table[4,2],TretDis.timeEvents,TretDis.timeScale,TretDis.timeScaled,TretDis.verboseExtrapolation,TretDis.verboseRead,TsupDis.k,TsupDis.y,con2.k,con2.y,time
[Calling sys.exit(0), Time elapsed: 7.750576093792915]