Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.0.0_Modelica.Blocks.Examples.Interaction1.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.001862/0.001862, allocations: 104 kB / 21.25 MB, free: 0.5664 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.002182/0.002182, allocations: 190.7 kB / 24.53 MB, free: 3.852 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.611/1.611, allocations: 225.7 MB / 253.4 MB, free: 12.79 MB / 206.1 MB
"
[Timeout remaining time 178]
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(Modelica.Blocks.Examples.Interaction1,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5011,variableFilter="time|booleanTable.y|integerTable.y|start.on|start.reset.1.|stop.on|stop.reset.1.",fileNamePrefix="Modelica_4.0.0_Modelica.Blocks.Examples.Interaction1")
translateModel(Modelica.Blocks.Examples.Interaction1,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5011,variableFilter="time|booleanTable.y|integerTable.y|start.on|start.reset.1.|stop.on|stop.reset.1.",fileNamePrefix="Modelica_4.0.0_Modelica.Blocks.Examples.Interaction1") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.154e-05/2.154e-05, allocations: 5.516 kB / 371.7 MB, free: 9.168 MB / 302.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Blocks.Examples.Interaction1): time 0.004036/0.004058, allocations: 2.386 MB / 374.1 MB, free: 6.766 MB / 302.1 MB
Notification: Performance of NFInst.instExpressions: time 0.003204/0.007262, allocations: 1.726 MB / 375.8 MB, free: 5.035 MB / 302.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0001767/0.007439, allocations: 7.938 kB / 375.8 MB, free: 5.027 MB / 302.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001286/0.008724, allocations: 0.5414 MB / 376.4 MB, free: 4.484 MB / 302.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0008297/0.009554, allocations: 444 kB / 376.8 MB, free: 4.051 MB / 302.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001707/0.01126, allocations: 0.678 MB / 377.5 MB, free: 3.383 MB / 302.1 MB
Notification: Performance of NFFlatten.flatten: time 0.001086/0.01235, allocations: 0.9665 MB / 378.4 MB, free: 2.414 MB / 302.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0002387/0.01259, allocations: 73.05 kB / 378.5 MB, free: 2.336 MB / 302.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0005336/0.01312, allocations: 442.6 kB / 379 MB, free: 1.902 MB / 302.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.000446/0.01357, allocations: 366.6 kB / 379.3 MB, free: 1.543 MB / 302.1 MB
Notification: Performance of NFPackage.collectConstants: time 8.154e-05/0.01365, allocations: 40 kB / 379.3 MB, free: 1.504 MB / 302.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0004191/0.01407, allocations: 235.7 kB / 379.6 MB, free: 1.273 MB / 302.1 MB
Notification: Performance of combineBinaries: time 0.001061/0.01513, allocations: 1.091 MB / 380.7 MB, free: 176 kB / 302.1 MB
Notification: Performance of replaceArrayConstructors: time 0.2425/0.2577, allocations: 0.6393 MB / 381.3 MB, free: 15.23 MB / 302.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0002049/0.2579, allocations: 30.88 kB / 381.3 MB, free: 15.23 MB / 302.1 MB
Notification: Performance of FrontEnd: time 0.0001646/0.258, allocations: 22.41 kB / 381.4 MB, free: 15.23 MB / 302.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 50 (44)
 * Number of variables: 50 (50)
Notification: Performance of [SIM] Bindings: time 0.002739/0.2608, allocations: 2.285 MB / 383.6 MB, free: 14.44 MB / 302.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.0003789/0.2611, allocations: 229.5 kB / 383.9 MB, free: 14.38 MB / 302.1 MB
Notification: Performance of [SIM] Early Inline: time 0.001031/0.2622, allocations: 0.7713 MB / 384.6 MB, free: 14.31 MB / 302.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.0003778/0.2626, allocations: 203 kB / 384.8 MB, free: 14.2 MB / 302.1 MB
Notification: Performance of [SIM] Alias: time 0.001591/0.2641, allocations: 1.095 MB / 385.9 MB, free: 13.84 MB / 302.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.0002725/0.2644, allocations: 186.8 kB / 386.1 MB, free: 13.73 MB / 302.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0001607/0.2646, allocations: 136.5 kB / 386.3 MB, free: 13.68 MB / 302.1 MB
Notification: Performance of [SIM] Detect States: time 0.000497/0.2651, allocations: 360.3 kB / 386.6 MB, free: 13.54 MB / 302.1 MB
Notification: Performance of [SIM] Events: time 0.0002435/0.2653, allocations: 155.1 kB / 386.8 MB, free: 13.48 MB / 302.1 MB
Notification: Performance of [SIM] Partitioning: time 0.0005642/0.2659, allocations: 447 kB / 387.2 MB, free: 13.23 MB / 302.1 MB
Notification: Performance of [SIM] Causalize: time 0.001198/0.2671, allocations: 0.7337 MB / 387.9 MB, free: 13.03 MB / 302.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.0007537/0.2678, allocations: 0.7239 MB / 388.6 MB, free: 12.65 MB / 302.1 MB
Notification: Performance of [INI] Simplify: time 0.006486/0.2743, allocations: 1.417 MB / 390.1 MB, free: 12.01 MB / 302.1 MB
Notification: Performance of [INI] Inline: time 0.001364/0.2757, allocations: 1.297 MB / 391.4 MB, free: 11.32 MB / 302.1 MB
Notification: Performance of [INI] Partitioning: time 8.087e-05/0.2758, allocations: 44.61 kB / 391.4 MB, free: 11.28 MB / 302.1 MB
Notification: Performance of [INI] Cleanup: time 0.0002789/0.276, allocations: 285.5 kB / 391.7 MB, free: 11.12 MB / 302.1 MB
Warning: NBResolveSingularities.balanceInitialization reports an overdetermined initialization!
Checking for consistency is not yet supported, following equations had to be removed:
	[SCAL] (1) $PRE.stop.table.y = $START.stop.table.y ($RES_SRT_77);
	[SCAL] (1) $PRE.start.table.y = $START.start.table.y ($RES_SRT_75);
Notification: Performance of [INI] Causalize: time 0.00527/0.2813, allocations: 3.533 MB / 395.2 MB, free: 10.38 MB / 302.1 MB
Notification: Performance of [INI] Tearing: time 3.418e-05/0.2813, allocations: 47.84 kB / 395.3 MB, free: 10.34 MB / 302.1 MB
Notification: Performance of [SIM] Initialization: time 1.423e-06/0.2813, allocations: 1.281 kB / 395.3 MB, free: 10.34 MB / 302.1 MB
Notification: Performance of [SIM] DAE-Mode: time 0.0002381/0.2816, allocations: 145.7 kB / 395.4 MB, free: 10.24 MB / 302.1 MB
Notification: Performance of [SIM] Remove Dummies: time 9.588e-06/0.2816, allocations: 3.938 kB / 395.4 MB, free: 10.24 MB / 302.1 MB
Notification: Performance of [SIM] Tearing: time 1.805e-05/0.2816, allocations: 37.81 kB / 395.4 MB, free: 10.21 MB / 302.1 MB
Notification: Performance of [SIM] Categorize: time 2.287e-05/0.2816, allocations: 5.406 kB / 395.5 MB, free: 10.21 MB / 302.1 MB
Notification: Performance of [SIM] Solve: time 0.001003/0.2826, allocations: 0.7257 MB / 396.2 MB, free: 9.969 MB / 302.1 MB
Notification: Performance of [SIM] Jacobian: time 0.0003227/0.283, allocations: 0.7137 MB / 396.9 MB, free: 9.199 MB / 302.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: ......... 5
 * 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: .................... 8 (8) {stop.table.combiTimeTable.nextTimeEvent, stop.table.combiTimeTable.nextTimeEventScaled, start.table.combiTimeTable.nextTimeEvent, start.table.combiTimeTable.nextTimeEventScaled, booleanTable.combiTimeTable.nextTimeEvent, booleanTable.combiTimeTable.nextTimeEventScaled, integerTable.combiTimeTable.nextTimeEvent, integerTable.combiTimeTable.nextTimeEventScaled}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 34 (34) {integerValue.numberPort, timeTable.a, timeTable.b, timeTable.last, timeTable.nextEvent, timeTable.nextEventScaled, booleanValue.activePort, start.reset, start.on, start.table.y, start.pre_reset, stop.reset, stop.on, stop.table.y, stop.pre_reset, $SEV_12, $SEV_11, $SEV_10, $SEV_9, $SEV_8, $SEV_7, $SEV_6, $SEV_5, $SEV_4, $SEV_3, $SEV_2, $SEV_1, $SEV_0, $TEV_5, $TEV_4, $TEV_3, $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: ........... 34 (scalar:34, array:0, record:0)
 * Number of multi strong components: ............ 11 (algorithm:3, when:8, 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: ........... 87 (scalar:77, array:10, record:0)
 * Number of multi strong components: ............ 11 (algorithm:11, 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 7.763e-05/0.283, allocations: 59.23 kB / 397 MB, free: 9.16 MB / 302.1 MB
Notification: Performance of SimCode: time 0.005356/0.2884, allocations: 2.817 MB / 399.8 MB, free: 7.922 MB / 302.1 MB
Notification: Performance of Templates: time 0.01264/0.301, allocations: 6.345 MB / 406.1 MB, free: 5.133 MB / 302.1 MB
"
[Timeout remaining time 660]
make -j1 -f Modelica_4.0.0_Modelica.Blocks.Examples.Interaction1.makefile [Timeout 660]
(rm -f Modelica_4.0.0_Modelica.Blocks.Examples.Interaction1.pipe ; mkfifo Modelica_4.0.0_Modelica.Blocks.Examples.Interaction1.pipe ; head -c 1048576 < Modelica_4.0.0_Modelica.Blocks.Examples.Interaction1.pipe >> ../files/Modelica_4.0.0_Modelica.Blocks.Examples.Interaction1.sim & ./Modelica_4.0.0_Modelica.Blocks.Examples.Interaction1  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Modelica_4.0.0_Modelica.Blocks.Examples.Interaction1.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 5.158075957966503]
Failed to read output from testmodel.py, exit status != 0:
['time', 'booleanTable.y', 'integerTable.y', 'start.on', 'start.reset[1]', 'stop.on', 'stop.reset[1]']
0.3089407719671726 0.32243702599999996 0.061226294
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f5be37d2fb0>