Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_3.2.3_Modelica.Media.Examples.Tests.MediaTestModels.Air.SimpleAir.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.001517/0.001517, allocations: 114.9 kB / 22 MB, free: 4.559 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.001446/0.001446, allocations: 217.5 kB / 25.3 MB, free: 2.902 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.595/1.595, allocations: 207.7 MB / 236.2 MB, free: 9.75 MB / 190.1 MB
"
[Timeout remaining time 178]
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(Modelica.Media.Examples.Tests.MediaTestModels.Air.SimpleAir,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|volume.medium.T|volume.medium.p",fileNamePrefix="Modelica_3.2.3_Modelica.Media.Examples.Tests.MediaTestModels.Air.SimpleAir")
translateModel(Modelica.Media.Examples.Tests.MediaTestModels.Air.SimpleAir,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|volume.medium.T|volume.medium.p",fileNamePrefix="Modelica_3.2.3_Modelica.Media.Examples.Tests.MediaTestModels.Air.SimpleAir") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.278e-05/2.278e-05, allocations: 2.281 kB / 348.6 MB, free: 9.328 MB / 286.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Media.Examples.Tests.MediaTestModels.Air.SimpleAir): time 0.4052/0.4052, allocations: 124.1 MB / 472.6 MB, free: 11.02 MB / 366.1 MB
Notification: Performance of NFInst.instExpressions: time 0.003123/0.4084, allocations: 1.631 MB / 474.3 MB, free: 9.383 MB / 366.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0002402/0.4086, allocations: 7.938 kB / 474.3 MB, free: 9.375 MB / 366.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0009299/0.4095, allocations: 174.8 kB / 474.4 MB, free: 9.203 MB / 366.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0008192/0.4104, allocations: 334.3 kB / 474.8 MB, free: 8.875 MB / 366.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001255/0.4116, allocations: 418.7 kB / 475.2 MB, free: 8.469 MB / 366.1 MB
Notification: Performance of NFFlatten.flatten: time 0.001087/0.4127, allocations: 0.9344 MB / 476.1 MB, free: 7.531 MB / 366.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0002487/0.4129, allocations: 77.56 kB / 476.2 MB, free: 7.461 MB / 366.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0008561/0.4138, allocations: 458.5 kB / 476.6 MB, free: 7.012 MB / 366.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0004862/0.4143, allocations: 314.8 kB / 476.9 MB, free: 6.703 MB / 366.1 MB
Notification: Performance of NFPackage.collectConstants: time 6.765e-05/0.4144, allocations: 32 kB / 477 MB, free: 6.672 MB / 366.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0004122/0.4148, allocations: 155.7 kB / 477.1 MB, free: 6.52 MB / 366.1 MB
Notification: Performance of combineBinaries: time 0.0007178/0.4155, allocations: 0.6668 MB / 477.8 MB, free: 5.848 MB / 366.1 MB
Notification: Performance of replaceArrayConstructors: time 0.0003563/0.4158, allocations: 398.6 kB / 478.2 MB, free: 5.457 MB / 366.1 MB
Notification: Performance of NFVerifyModel.verify: time 7.137e-05/0.4159, allocations: 40 kB / 478.2 MB, free: 5.418 MB / 366.1 MB
Notification: Performance of FrontEnd: time 4.999e-05/0.416, allocations: 8 kB / 478.2 MB, free: 5.41 MB / 366.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 60 (60)
 * Number of variables: 60 (60)
Notification: Performance of [SIM] Bindings: time 0.002205/0.4182, allocations: 1.717 MB / 479.9 MB, free: 3.562 MB / 366.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.0002428/0.4184, allocations: 173.1 kB / 480.1 MB, free: 3.379 MB / 366.1 MB
Notification: Performance of [SIM] Early Inline: time 0.0009596/0.4194, allocations: 0.8928 MB / 481 MB, free: 2.461 MB / 366.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.0003113/0.4197, allocations: 192.4 kB / 481.2 MB, free: 2.227 MB / 366.1 MB
Notification: Performance of [SIM] Alias: time 0.002578/0.4223, allocations: 1.564 MB / 482.8 MB, free: 0.5117 MB / 366.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.0002647/0.4225, allocations: 165.8 kB / 482.9 MB, free: 308 kB / 366.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0001407/0.4227, allocations: 105.6 kB / 483 MB, free: 176 kB / 366.1 MB
Notification: Performance of [SIM] Detect States: time 0.0003776/0.423, allocations: 220.6 kB / 483.2 MB, free: 15.9 MB / 382.1 MB
Notification: Performance of [SIM] Events: time 0.0002324/0.4233, allocations: 112.3 kB / 483.3 MB, free: 15.79 MB / 382.1 MB
Notification: Performance of [SIM] Partitioning: time 0.0004965/0.4238, allocations: 373.3 kB / 483.7 MB, free: 15.33 MB / 382.1 MB
Notification: Performance of [SIM] Causalize: time 0.0009071/0.4247, allocations: 0.6145 MB / 484.3 MB, free: 14.61 MB / 382.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.0006093/0.4253, allocations: 0.5661 MB / 484.9 MB, free: 14.05 MB / 382.1 MB
Notification: Performance of [INI] Simplify: time 0.0009307/0.4262, allocations: 0.4952 MB / 485.4 MB, free: 13.48 MB / 382.1 MB
Notification: Performance of [INI] Inline: time 0.001044/0.4273, allocations: 0.934 MB / 486.3 MB, free: 12.54 MB / 382.1 MB
Notification: Performance of [INI] Partitioning: time 4.749e-05/0.4273, allocations: 30.67 kB / 486.3 MB, free: 12.51 MB / 382.1 MB
Notification: Performance of [INI] Cleanup: time 0.0001458/0.4275, allocations: 127.8 kB / 486.5 MB, free: 12.38 MB / 382.1 MB
Notification: Performance of [INI] Causalize: time 0.001294/0.4288, allocations: 0.8707 MB / 487.3 MB, free: 11.51 MB / 382.1 MB
Notification: Performance of [INI] Tearing: time 9.115e-05/0.4288, allocations: 51.95 kB / 487.4 MB, free: 11.46 MB / 382.1 MB
Notification: Performance of [SIM] Initialization: time 4.41e-07/0.4288, allocations: 0 / 487.4 MB, free: 11.46 MB / 382.1 MB
Notification: Performance of [SIM] DAE-Mode: time 0.0004564/0.4293, allocations: 300 kB / 487.7 MB, free: 11.07 MB / 382.1 MB
Notification: Performance of [SIM] Remove Dummies: time 1.625e-05/0.4293, allocations: 7.938 kB / 487.7 MB, free: 11.06 MB / 382.1 MB
Notification: Performance of [SIM] Tearing: time 5.634e-05/0.4294, allocations: 55.92 kB / 487.7 MB, free: 11 MB / 382.1 MB
Notification: Performance of [SIM] Categorize: time 2.257e-05/0.4294, allocations: 3.938 kB / 487.8 MB, free: 11 MB / 382.1 MB
Notification: Performance of [SIM] Solve: time 0.001182/0.4306, allocations: 0.7797 MB / 488.5 MB, free: 10.22 MB / 382.1 MB
Notification: Performance of [SIM] Jacobian: time 0.002067/0.4326, allocations: 2.503 MB / 491 MB, free: 7.262 MB / 382.1 MB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 6
 * Number of ODE event partitions: ............... 1
 * Number of algebraic event partitions: ......... 6
 * 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: ............................. 2 (2) {volume.m, volume.U}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 8 (8) {$SEV_7, $SEV_6, $SEV_5, $SEV_4, $SEV_3, $SEV_2, $SEV_1, $SEV_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: ........... 32 (scalar:32, 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: ... 1 (linear: 1, nonlinear:0)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 56 (scalar:53, array:3, 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: ... 1 (linear: 1, nonlinear:0)
Notification: Performance of backend: time 6.436e-05/0.4327, allocations: 34.72 kB / 491.1 MB, free: 7.227 MB / 382.1 MB
Notification: Performance of SimCode: time 0.002957/0.4357, allocations: 1.63 MB / 492.7 MB, free: 5.539 MB / 382.1 MB
Notification: Performance of Templates: time 0.01106/0.4467, allocations: 4.869 MB / 497.6 MB, free: 0.6641 MB / 382.1 MB
"
[Timeout remaining time 660]
make -j1 -f Modelica_3.2.3_Modelica.Media.Examples.Tests.MediaTestModels.Air.SimpleAir.makefile [Timeout 660]
(rm -f Modelica_3.2.3_Modelica.Media.Examples.Tests.MediaTestModels.Air.SimpleAir.pipe ; mkfifo Modelica_3.2.3_Modelica.Media.Examples.Tests.MediaTestModels.Air.SimpleAir.pipe ; head -c 1048576 < Modelica_3.2.3_Modelica.Media.Examples.Tests.MediaTestModels.Air.SimpleAir.pipe >> ../files/Modelica_3.2.3_Modelica.Media.Examples.Tests.MediaTestModels.Air.SimpleAir.sim & ./Modelica_3.2.3_Modelica.Media.Examples.Tests.MediaTestModels.Air.SimpleAir  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Modelica_3.2.3_Modelica.Media.Examples.Tests.MediaTestModels.Air.SimpleAir.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 5.255197476013564]
Failed to read output from testmodel.py, exit status != 0:
['time', 'volume.medium.T', 'volume.medium.p']
0.45626070600701496 0.47109932299999996 0.051633395000000006
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fc0772cefb0>