Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_latest_Buildings.Controls.OBC.CDL.Reals.Validation.PIDScale.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.00154/0.00154, allocations: 104.3 kB / 19.76 MB, free: 1.906 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.001656/0.001656, allocations: 210.1 kB / 23.06 MB, free: 4.984 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.397/1.397, allocations: 230.7 MB / 256.9 MB, free: 7.781 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo): time 3.095/3.095, allocations: 0.5108 GB / 0.8167 GB, free: 17.81 MB / 0.6231 GB
"
[Timeout remaining time 177]
Using package Buildings with version 13.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/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.Controls.OBC.CDL.Reals.Validation.PIDScale,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|pidSca.y|pidNoSca.y|pla1.y|pla2.y|pidSca.u_s",fileNamePrefix="Buildings_latest_Buildings.Controls.OBC.CDL.Reals.Validation.PIDScale")
translateModel(Buildings.Controls.OBC.CDL.Reals.Validation.PIDScale,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|pidSca.y|pidNoSca.y|pla1.y|pla2.y|pidSca.u_s",fileNamePrefix="Buildings_latest_Buildings.Controls.OBC.CDL.Reals.Validation.PIDScale") [Timeout 300]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.794e-05/1.794e-05, allocations: 2.281 kB / 1.187 GB, free: 13.43 MB / 0.9044 GB
Notification: Performance of NFInst.instantiate(Buildings.Controls.OBC.CDL.Reals.Validation.PIDScale): time 0.002933/0.002951, allocations: 1.829 MB / 1.189 GB, free: 12.23 MB / 0.9044 GB
Notification: Performance of NFInst.instExpressions: time 0.002543/0.005494, allocations: 1.617 MB / 1.191 GB, free: 11.04 MB / 0.9044 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0002007/0.005695, allocations: 11.84 kB / 1.191 GB, free: 11.04 MB / 0.9044 GB
Notification: Performance of NFTyping.typeComponents: time 0.0002428/0.005938, allocations: 84.58 kB / 1.191 GB, free: 10.98 MB / 0.9044 GB
Notification: Performance of NFTyping.typeBindings: time 0.0001978/0.006135, allocations: 89.48 kB / 1.191 GB, free: 10.91 MB / 0.9044 GB
Notification: Performance of NFTyping.typeClassSections: time 0.0007594/0.006895, allocations: 324.4 kB / 1.191 GB, free: 10.72 MB / 0.9044 GB
Notification: Performance of NFFlatten.flatten: time 0.0007265/0.007621, allocations: 0.754 MB / 1.192 GB, free: 10.32 MB / 0.9044 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0003801/0.008001, allocations: 214 kB / 1.192 GB, free: 10.2 MB / 0.9044 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.0002506/0.008252, allocations: 272.2 kB / 1.192 GB, free: 10.05 MB / 0.9044 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0003069/0.008559, allocations: 248.7 kB / 1.192 GB, free: 9.918 MB / 0.9044 GB
Notification: Performance of NFPackage.collectConstants: time 0.000111/0.00867, allocations: 77.25 kB / 1.193 GB, free: 9.918 MB / 0.9044 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0001624/0.008832, allocations: 84.61 kB / 1.193 GB, free: 9.918 MB / 0.9044 GB
Notification: Performance of combineBinaries: time 0.0007982/0.00963, allocations: 1.159 MB / 1.194 GB, free: 9.078 MB / 0.9044 GB
Notification: Performance of replaceArrayConstructors: time 0.0003679/0.009998, allocations: 0.7141 MB / 1.194 GB, free: 8.566 MB / 0.9044 GB
Notification: Performance of NFVerifyModel.verify: time 9.433e-05/0.01009, allocations: 41.69 kB / 1.194 GB, free: 8.562 MB / 0.9044 GB
Notification: Performance of FrontEnd: time 8.385e-05/0.01018, allocations: 38.38 kB / 1.195 GB, free: 8.539 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: 124 (124)
 * Number of variables: 124 (124)
Notification: Performance of [SIM] Bindings: time 0.002273/0.01245, allocations: 2.753 MB / 1.197 GB, free: 6.551 MB / 0.9044 GB
Notification: Performance of [SIM] FunctionAlias: time 0.0002824/0.01273, allocations: 301.2 kB / 1.197 GB, free: 6.352 MB / 0.9044 GB
Notification: Performance of [SIM] Early Inline: time 0.001603/0.01433, allocations: 1.717 MB / 1.199 GB, free: 5.254 MB / 0.9044 GB
Notification: Performance of [SIM] Simplify 1: time 0.0005642/0.0149, allocations: 369.8 kB / 1.2 GB, free: 5.07 MB / 0.9044 GB
Notification: Performance of [SIM] Alias: time 0.003784/0.01868, allocations: 2.932 MB / 1.202 GB, free: 3.277 MB / 0.9044 GB
Notification: Performance of [SIM] Simplify 2: time 0.0003853/0.01907, allocations: 286.4 kB / 1.203 GB, free: 3.121 MB / 0.9044 GB
Notification: Performance of [SIM] Remove Stream: time 0.0002184/0.01929, allocations: 199.3 kB / 1.203 GB, free: 2.988 MB / 0.9044 GB
Notification: Performance of [SIM] Detect States: time 0.0004639/0.01975, allocations: 402.6 kB / 1.203 GB, free: 2.664 MB / 0.9044 GB
Notification: Performance of [SIM] Events: time 8.709e-05/0.01984, allocations: 44.77 kB / 1.203 GB, free: 2.641 MB / 0.9044 GB
Notification: Performance of [SIM] Partitioning: time 0.0003808/0.02022, allocations: 314 kB / 1.204 GB, free: 2.484 MB / 0.9044 GB
Notification: Performance of [SIM] Causalize: time 0.0009139/0.02113, allocations: 0.6115 MB / 1.204 GB, free: 2.34 MB / 0.9044 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.0007326/0.02187, allocations: 0.7867 MB / 1.205 GB, free: 1.887 MB / 0.9044 GB
Notification: Performance of [INI] Simplify: time 0.001451/0.02332, allocations: 1.018 MB / 1.206 GB, free: 1.328 MB / 0.9044 GB
Notification: Performance of [INI] Inline: time 0.001316/0.02463, allocations: 1.517 MB / 1.207 GB, free: 416 kB / 0.9044 GB
Notification: Performance of [INI] Partitioning: time 8.311e-05/0.02471, allocations: 42.88 kB / 1.207 GB, free: 384 kB / 0.9044 GB
Notification: Performance of [INI] Cleanup: time 0.0003053/0.02502, allocations: 378.6 kB / 1.208 GB, free: 140 kB / 0.9044 GB
Notification: Performance of [INI] Causalize: time 0.005156/0.03018, allocations: 3.422 MB / 1.211 GB, free: 15.43 MB / 0.92 GB
Notification: Performance of [INI] Tearing: time 9.969e-05/0.03028, allocations: 112.3 kB / 1.211 GB, free: 15.34 MB / 0.92 GB
Notification: Performance of [SIM] Initialization: time 1.272e-06/0.03028, allocations: 3.938 kB / 1.211 GB, free: 15.34 MB / 0.92 GB
Notification: Performance of [SIM] DAE-Mode: time 0.0004513/0.03073, allocations: 259.8 kB / 1.212 GB, free: 15.25 MB / 0.92 GB
Notification: Performance of [SIM] Remove Dummies: time 1.347e-05/0.03074, allocations: 9.125 kB / 1.212 GB, free: 15.24 MB / 0.92 GB
Notification: Performance of [SIM] Tearing: time 2.802e-05/0.03077, allocations: 39.69 kB / 1.212 GB, free: 15.21 MB / 0.92 GB
Notification: Performance of [SIM] Categorize: time 1.192e-05/0.03078, allocations: 1.406 kB / 1.212 GB, free: 15.21 MB / 0.92 GB
Notification: Performance of [SIM] Solve: time 0.00175/0.03253, allocations: 1.144 MB / 1.213 GB, free: 14.78 MB / 0.92 GB
Notification: Performance of [SIM] Jacobian: time 0.0008414/0.03337, allocations: 0.7031 MB / 1.213 GB, free: 14.26 MB / 0.92 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: ....... 1
Notification: Variable statistics after passing the back-end:
 * Number of states: ............................. 6 (6) {pidSca.addPID.u2, pidSca.D.x, pla1.y, pidNoSca.addPID.u2, pidNoSca.D.x, pla2.y}
 * Number of discrete states: .................... 1 (1) {pulse.booPul.y}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 2 (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: ........... 41 (scalar:41, array:0, record:0)
 * Number of multi strong components: ............ 1 (algorithm:0, when:1, 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: ........... 107 (scalar:107, 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: ... 0 (linear: 0, nonlinear:0)
Notification: [Initialization (lambda=0)] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 107 (scalar:107, 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: ... 0 (linear: 0, nonlinear:0)
Notification: Performance of backend: time 8.664e-05/0.03346, allocations: 58.44 kB / 1.213 GB, free: 14.21 MB / 0.92 GB
Notification: Performance of SimCode: time 0.00301/0.03647, allocations: 2.275 MB / 1.216 GB, free: 12.66 MB / 0.92 GB
Notification: Performance of Templates: time 0.01127/0.04774, allocations: 5.5 MB / 1.221 GB, free: 9.887 MB / 0.92 GB
"
[Timeout remaining time 300]
make -j1 -f Buildings_latest_Buildings.Controls.OBC.CDL.Reals.Validation.PIDScale.makefile [Timeout 300]
(rm -f Buildings_latest_Buildings.Controls.OBC.CDL.Reals.Validation.PIDScale.pipe ; mkfifo Buildings_latest_Buildings.Controls.OBC.CDL.Reals.Validation.PIDScale.pipe ; head -c 1048576 < Buildings_latest_Buildings.Controls.OBC.CDL.Reals.Validation.PIDScale.pipe >> ../files/Buildings_latest_Buildings.Controls.OBC.CDL.Reals.Validation.PIDScale.sim & ./Buildings_latest_Buildings.Controls.OBC.CDL.Reals.Validation.PIDScale  -abortSlowSimulation -alarm=800  -emit_protected -lv LOG_STATS > Buildings_latest_Buildings.Controls.OBC.CDL.Reals.Validation.PIDScale.pipe 2>&1) [Timeout 800]
[Calling os._exit(0), Time elapsed: 8.239387527981307]
Failed to read output from testmodel.py, exit status != 0:
0.05523671698756516 0.06921806 0.056164042000000004
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f5056d8efb0>