Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.0.0_Modelica.StateGraph.Examples.FirstExample.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.001557/0.001557, allocations: 96.03 kB / 21.25 MB, free: 428 kB / 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.002091/0.002091, allocations: 186.9 kB / 24.53 MB, free: 3.746 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.582/1.582, 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.StateGraph.Examples.FirstExample,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5012,variableFilter="time|transition1.enableFire|transition2.enableFire",fileNamePrefix="Modelica_4.0.0_Modelica.StateGraph.Examples.FirstExample")
translateModel(Modelica.StateGraph.Examples.FirstExample,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5012,variableFilter="time|transition1.enableFire|transition2.enableFire",fileNamePrefix="Modelica_4.0.0_Modelica.StateGraph.Examples.FirstExample") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.187e-05/2.187e-05, allocations: 2.281 kB / 371.7 MB, free: 9.188 MB / 302.1 MB
Notification: Performance of NFInst.instantiate(Modelica.StateGraph.Examples.FirstExample): time 0.002719/0.002741, allocations: 1.462 MB / 373.2 MB, free: 7.715 MB / 302.1 MB
Notification: Performance of NFInst.instExpressions: time 0.000705/0.003446, allocations: 271.2 kB / 373.4 MB, free: 7.449 MB / 302.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 7.935e-05/0.003526, allocations: 0 / 373.4 MB, free: 7.449 MB / 302.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0001402/0.003666, allocations: 31.67 kB / 373.4 MB, free: 7.418 MB / 302.1 MB
Notification: Performance of NFTyping.typeBindings: time 5.154e-05/0.003717, allocations: 23.8 kB / 373.5 MB, free: 7.395 MB / 302.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0004129/0.00413, allocations: 115.8 kB / 373.6 MB, free: 7.281 MB / 302.1 MB
Notification: Performance of NFFlatten.flatten: time 0.0003896/0.00452, allocations: 295.5 kB / 373.9 MB, free: 6.992 MB / 302.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0002929/0.004813, allocations: 146.7 kB / 374 MB, free: 6.84 MB / 302.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0001466/0.004959, allocations: 123.7 kB / 374.1 MB, free: 6.719 MB / 302.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0001607/0.00512, allocations: 99.58 kB / 374.2 MB, free: 6.621 MB / 302.1 MB
Notification: Performance of NFPackage.collectConstants: time 4.656e-05/0.005167, allocations: 24 kB / 374.3 MB, free: 6.598 MB / 302.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 7.989e-05/0.005247, allocations: 31.98 kB / 374.3 MB, free: 6.566 MB / 302.1 MB
Notification: Performance of combineBinaries: time 0.0003287/0.005575, allocations: 380.3 kB / 374.7 MB, free: 6.191 MB / 302.1 MB
Notification: Performance of replaceArrayConstructors: time 0.000153/0.005728, allocations: 232.8 kB / 374.9 MB, free: 5.961 MB / 302.1 MB
Notification: Performance of NFVerifyModel.verify: time 3.967e-05/0.005768, allocations: 16 kB / 374.9 MB, free: 5.945 MB / 302.1 MB
Notification: Performance of FrontEnd: time 4.825e-05/0.005816, allocations: 31.8 kB / 374.9 MB, free: 5.914 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: 55 (55)
 * Number of variables: 55 (55)
Notification: Performance of [SIM] Bindings: time 0.001403/0.007219, allocations: 0.959 MB / 375.9 MB, free: 4.828 MB / 302.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.0002219/0.007441, allocations: 163.8 kB / 376.1 MB, free: 4.656 MB / 302.1 MB
Notification: Performance of [SIM] Early Inline: time 0.0007109/0.008151, allocations: 0.6586 MB / 376.7 MB, free: 3.973 MB / 302.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.0002487/0.0084, allocations: 153.7 kB / 376.9 MB, free: 3.773 MB / 302.1 MB
Notification: Performance of [SIM] Alias: time 0.001575/0.009975, allocations: 0.9747 MB / 377.8 MB, free: 2.668 MB / 302.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.0001977/0.01017, allocations: 129.8 kB / 378 MB, free: 2.492 MB / 302.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0001103/0.01028, allocations: 79.02 kB / 378 MB, free: 2.391 MB / 302.1 MB
Notification: Performance of [SIM] Detect States: time 0.0003767/0.01066, allocations: 248.1 kB / 378.3 MB, free: 2.094 MB / 302.1 MB
Notification: Performance of [SIM] Events: time 0.0001237/0.01078, allocations: 65.88 kB / 378.3 MB, free: 2.031 MB / 302.1 MB
Notification: Performance of [SIM] Partitioning: time 0.0003156/0.0111, allocations: 239.6 kB / 378.6 MB, free: 1.793 MB / 302.1 MB
Notification: Performance of [SIM] Causalize: time 0.0009024/0.012, allocations: 0.6075 MB / 379.2 MB, free: 1.176 MB / 302.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.0006052/0.01261, allocations: 431.6 kB / 379.6 MB, free: 0.7539 MB / 302.1 MB
Notification: Performance of [INI] Simplify: time 0.0004476/0.01305, allocations: 276.4 kB / 379.9 MB, free: 424 kB / 302.1 MB
Notification: Performance of [INI] Inline: time 0.2301/0.2431, allocations: 0.557 MB / 380.4 MB, free: 17.8 MB / 302.1 MB
Notification: Performance of [INI] Partitioning: time 0.0001768/0.2433, allocations: 24.66 kB / 380.5 MB, free: 17.79 MB / 302.1 MB
Notification: Performance of [INI] Cleanup: time 0.000186/0.2435, allocations: 96.27 kB / 380.6 MB, free: 17.79 MB / 302.1 MB
Notification: Performance of [INI] Causalize: time 0.003235/0.2467, allocations: 1.293 MB / 381.8 MB, free: 17.73 MB / 302.1 MB
Notification: Performance of [INI] Tearing: time 3.093e-05/0.2468, allocations: 27.56 kB / 381.9 MB, free: 17.72 MB / 302.1 MB
Notification: Performance of [SIM] Initialization: time 1.593e-06/0.2468, allocations: 0.6875 kB / 381.9 MB, free: 17.72 MB / 302.1 MB
Notification: Performance of [SIM] DAE-Mode: time 0.0001347/0.2469, allocations: 58.83 kB / 381.9 MB, free: 17.7 MB / 302.1 MB
Notification: Performance of [SIM] Remove Dummies: time 9.338e-06/0.2469, allocations: 0.625 kB / 381.9 MB, free: 17.7 MB / 302.1 MB
Notification: Performance of [SIM] Tearing: time 2.279e-05/0.2469, allocations: 27.47 kB / 382 MB, free: 17.68 MB / 302.1 MB
Notification: Performance of [SIM] Categorize: time 2.048e-05/0.247, allocations: 8.594 kB / 382 MB, free: 17.68 MB / 302.1 MB
Notification: Performance of [SIM] Solve: time 0.0006481/0.2476, allocations: 312.2 kB / 382.3 MB, free: 17.67 MB / 302.1 MB
Notification: Performance of [SIM] Jacobian: time 0.0001932/0.2478, allocations: 193.9 kB / 382.5 MB, free: 17.48 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: ......... 1
 * 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) {transition2.t_start, transition1.t_start}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 35 (35) {initialStep.active, initialStep.localActive, initialStep.inPort.occupied, initialStep.inPort.set, initialStep.outPort.available, initialStep.outPort.reset, initialStep.newActive, transition1.enableFire, transition1.fire, transition1.inPort.available, transition1.outPort.occupied, step.active, step.localActive, step.inPort.occupied, step.inPort.set, step.outPort.available, step.outPort.reset, step.newActive, transition2.enableFire, transition2.fire, transition2.inPort.available, transition2.outPort.occupied, stateGraphRoot.activeSteps, $FUN_1, $FUN_2, $FUN_3, $FUN_4, $SEV_5, $SEV_4, $SEV_3, $SEV_2, $SEV_1, $SEV_0, $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: ........... 42 (scalar:42, array:0, record:0)
 * Number of multi strong components: ............ 2 (algorithm:0, 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: ........... 54 (scalar:54, array:0, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, 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.000175/0.248, allocations: 53.61 kB / 382.5 MB, free: 17.45 MB / 302.1 MB
Notification: Performance of SimCode: time 0.001377/0.2494, allocations: 0.6486 MB / 383.2 MB, free: 17.19 MB / 302.1 MB
Notification: Performance of Templates: time 0.006025/0.2554, allocations: 1.612 MB / 384.8 MB, free: 17.04 MB / 302.1 MB
"
[Timeout remaining time 660]
make -j1 -f Modelica_4.0.0_Modelica.StateGraph.Examples.FirstExample.makefile [Timeout 660]
(rm -f Modelica_4.0.0_Modelica.StateGraph.Examples.FirstExample.pipe ; mkfifo Modelica_4.0.0_Modelica.StateGraph.Examples.FirstExample.pipe ; head -c 1048576 < Modelica_4.0.0_Modelica.StateGraph.Examples.FirstExample.pipe >> ../files/Modelica_4.0.0_Modelica.StateGraph.Examples.FirstExample.sim & ./Modelica_4.0.0_Modelica.StateGraph.Examples.FirstExample  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Modelica_4.0.0_Modelica.StateGraph.Examples.FirstExample.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 4.895986378018279]
Failed to read output from testmodel.py, exit status != 0:
['time', 'transition1.enableFire', 'transition2.enableFire']
0.2644243370159529 0.278837597 0.27009814800000004
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fd946f22fb0>