Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_3.2.3_ModelicaTest.Blocks.OnDelay.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.001704/0.001704, allocations: 100.4 kB / 20.52 MB, free: 1.148 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.001874/0.001874, allocations: 214.8 kB / 23.82 MB, free: 4.344 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.591/1.591, allocations: 207.7 MB / 234.7 MB, free: 9.758 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo): time 0.2551/0.2551, allocations: 44.27 MB / 331.2 MB, free: 9.27 MB / 270.1 MB
"
[Timeout remaining time 179]
Using package ModelicaTest with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo)
Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+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(ModelicaTest.Blocks.OnDelay,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5020,variableFilter="time|booleanTable.y|onDelay1.delaySignal|onDelay1.t_next|onDelay1.u|onDelay2.delaySignal|onDelay2.t_next|onDelay2.u|onDelay3.delaySignal|onDelay3.t_next|onDelay3.u",fileNamePrefix="ModelicaTest_3.2.3_ModelicaTest.Blocks.OnDelay")
translateModel(ModelicaTest.Blocks.OnDelay,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5020,variableFilter="time|booleanTable.y|onDelay1.delaySignal|onDelay1.t_next|onDelay1.u|onDelay2.delaySignal|onDelay2.t_next|onDelay2.u|onDelay3.delaySignal|onDelay3.t_next|onDelay3.u",fileNamePrefix="ModelicaTest_3.2.3_ModelicaTest.Blocks.OnDelay") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.925e-05/1.925e-05, allocations: 3.688 kB / 468.8 MB, free: 3.867 MB / 334.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.Blocks.OnDelay): time 0.003561/0.00358, allocations: 1.85 MB / 470.7 MB, free: 2.008 MB / 334.1 MB
Notification: Performance of NFInst.instExpressions: time 0.001736/0.005316, allocations: 0.9209 MB / 471.6 MB, free: 1.078 MB / 334.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 9.158e-05/0.005408, allocations: 7.938 kB / 471.6 MB, free: 1.07 MB / 334.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0007257/0.006133, allocations: 191.4 kB / 471.8 MB, free: 0.8828 MB / 334.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0003758/0.006509, allocations: 139.1 kB / 471.9 MB, free: 0.75 MB / 334.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0004328/0.006942, allocations: 163.7 kB / 472.1 MB, free: 0.5898 MB / 334.1 MB
Notification: Performance of NFFlatten.flatten: time 0.0004024/0.007344, allocations: 311.2 kB / 472.4 MB, free: 292 kB / 334.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0001307/0.007475, allocations: 21.75 kB / 472.4 MB, free: 272 kB / 334.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0001882/0.007663, allocations: 135.6 kB / 472.5 MB, free: 136 kB / 334.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0001766/0.00784, allocations: 111.6 kB / 472.6 MB, free: 24 kB / 334.1 MB
Notification: Performance of NFPackage.collectConstants: time 4.336e-05/0.007883, allocations: 16 kB / 472.6 MB, free: 8 kB / 334.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0001656/0.008049, allocations: 71.89 kB / 472.7 MB, free: 15.94 MB / 350.1 MB
Notification: Performance of combineBinaries: time 0.0003908/0.00844, allocations: 388.6 kB / 473.1 MB, free: 15.55 MB / 350.1 MB
Notification: Performance of replaceArrayConstructors: time 0.0001677/0.008607, allocations: 213.3 kB / 473.3 MB, free: 15.34 MB / 350.1 MB
Notification: Performance of NFVerifyModel.verify: time 4.734e-05/0.008655, allocations: 16 kB / 473.3 MB, free: 15.33 MB / 350.1 MB
Notification: Performance of FrontEnd: time 2.457e-05/0.008679, allocations: 4 kB / 473.3 MB, free: 15.32 MB / 350.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 21 (18)
 * Number of variables: 21 (21)
Notification: Performance of [SIM] Bindings: time 0.001272/0.009951, allocations: 0.902 MB / 474.2 MB, free: 14.3 MB / 350.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.0001618/0.01011, allocations: 104.2 kB / 474.3 MB, free: 14.18 MB / 350.1 MB
Notification: Performance of [SIM] Early Inline: time 0.0004982/0.01061, allocations: 431.9 kB / 474.7 MB, free: 13.73 MB / 350.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.000224/0.01084, allocations: 152.6 kB / 474.9 MB, free: 13.54 MB / 350.1 MB
Notification: Performance of [SIM] Alias: time 0.0007626/0.0116, allocations: 490.5 kB / 475.4 MB, free: 12.98 MB / 350.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.0001707/0.01177, allocations: 130.7 kB / 475.5 MB, free: 12.81 MB / 350.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0001298/0.0119, allocations: 112.3 kB / 475.6 MB, free: 12.67 MB / 350.1 MB
Notification: Performance of [SIM] Detect States: time 0.0003499/0.01225, allocations: 241.9 kB / 475.8 MB, free: 12.39 MB / 350.1 MB
Notification: Performance of [SIM] Events: time 0.0001613/0.01241, allocations: 90.44 kB / 475.9 MB, free: 12.3 MB / 350.1 MB
Notification: Performance of [SIM] Partitioning: time 0.000354/0.01276, allocations: 268.1 kB / 476.2 MB, free: 12.02 MB / 350.1 MB
Notification: Performance of [SIM] Causalize: time 0.0006238/0.01339, allocations: 416.5 kB / 476.6 MB, free: 11.59 MB / 350.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.000466/0.01385, allocations: 443.6 kB / 477 MB, free: 11.16 MB / 350.1 MB
Notification: Performance of [INI] Simplify: time 0.002383/0.01624, allocations: 0.5324 MB / 477.6 MB, free: 10.58 MB / 350.1 MB
Notification: Performance of [INI] Inline: time 0.0006527/0.01689, allocations: 0.6258 MB / 478.2 MB, free: 9.949 MB / 350.1 MB
Notification: Performance of [INI] Partitioning: time 3.896e-05/0.01693, allocations: 26.67 kB / 478.2 MB, free: 9.918 MB / 350.1 MB
Notification: Performance of [INI] Cleanup: time 0.0001767/0.01711, allocations: 167.8 kB / 478.4 MB, free: 9.754 MB / 350.1 MB
Notification: Performance of [INI] Causalize: time 0.001821/0.01893, allocations: 1.253 MB / 479.6 MB, free: 8.492 MB / 350.1 MB
Notification: Performance of [INI] Tearing: time 2.651e-05/0.01895, allocations: 24 kB / 479.7 MB, free: 8.469 MB / 350.1 MB
Notification: Performance of [SIM] Initialization: time 3.91e-07/0.01895, allocations: 0 / 479.7 MB, free: 8.469 MB / 350.1 MB
Notification: Performance of [SIM] DAE-Mode: time 0.0001046/0.01906, allocations: 68.28 kB / 479.7 MB, free: 8.379 MB / 350.1 MB
Notification: Performance of [SIM] Remove Dummies: time 9.227e-06/0.01907, allocations: 7.938 kB / 479.7 MB, free: 8.371 MB / 350.1 MB
Notification: Performance of [SIM] Tearing: time 9.458e-06/0.01908, allocations: 12 kB / 479.8 MB, free: 8.359 MB / 350.1 MB
Notification: Performance of [SIM] Categorize: time 1.337e-05/0.01909, allocations: 4 kB / 479.8 MB, free: 8.355 MB / 350.1 MB
Notification: Performance of [SIM] Solve: time 0.0003834/0.01947, allocations: 241.8 kB / 480 MB, free: 8.117 MB / 350.1 MB
Notification: Performance of [SIM] Jacobian: time 0.0001989/0.01967, allocations: 182 kB / 480.2 MB, free: 7.906 MB / 350.1 MB
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: ......... 3
 * Number of clocked partitions: ................. 0
 * Number of initial partitions: ................. 1
 * Number of initial(lambda=0) partitions: ....... 0
Notification: Variable statistics after passing the back-end:
 * Number of states: ............................. 0 (0) {}
 * Number of discrete states: .................... 2 (2) {booleanTable.combiTimeTable.nextTimeEvent, booleanTable.combiTimeTable.nextTimeEventScaled}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 27 (27) {onDelay1.u, onDelay1.y, onDelay1.delaySignal, onDelay1.t_next, booleanStep3.y, onDelay3.u, onDelay3.y, onDelay3.delaySignal, onDelay3.t_next, booleanStep2.y, onDelay2.u, onDelay2.y, onDelay2.delaySignal, onDelay2.t_next, $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: ........... 21 (scalar:21, array:0, record:0)
 * Number of multi strong components: ............ 5 (algorithm:3, when:2, 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: ........... 38 (scalar:37, array:1, record:0)
 * Number of multi strong components: ............ 5 (algorithm:5, 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 5.432e-05/0.01973, allocations: 49.41 kB / 480.2 MB, free: 7.867 MB / 350.1 MB
Notification: Performance of SimCode: time 0.002845/0.02257, allocations: 1.11 MB / 481.3 MB, free: 6.805 MB / 350.1 MB
Notification: Performance of Templates: time 0.00645/0.02902, allocations: 2.431 MB / 483.8 MB, free: 4.375 MB / 350.1 MB
"
[Timeout remaining time 660]
make -j1 -f ModelicaTest_3.2.3_ModelicaTest.Blocks.OnDelay.makefile [Timeout 660]
(rm -f ModelicaTest_3.2.3_ModelicaTest.Blocks.OnDelay.pipe ; mkfifo ModelicaTest_3.2.3_ModelicaTest.Blocks.OnDelay.pipe ; head -c 1048576 < ModelicaTest_3.2.3_ModelicaTest.Blocks.OnDelay.pipe >> ../files/ModelicaTest_3.2.3_ModelicaTest.Blocks.OnDelay.sim & ./ModelicaTest_3.2.3_ModelicaTest.Blocks.OnDelay  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > ModelicaTest_3.2.3_ModelicaTest.Blocks.OnDelay.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 5.170418337977026]
Failed to read output from testmodel.py, exit status != 0:
['time', 'booleanTable.y', 'onDelay1.delaySignal', 'onDelay1.t_next', 'onDelay1.u', 'onDelay2.delaySignal', 'onDelay2.t_next', 'onDelay2.u', 'onDelay3.delaySignal', 'onDelay3.t_next', 'onDelay3.u']
0.03810182603774592 0.053470100000000007 0.04172225
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f3907a86fb0>