Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.1.0_Modelica.Electrical.Spice3.Examples.InvertersExtendedModel.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.001554/0.001554, allocations: 99.59 kB / 21.2 MB, free: 0.6406 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.001704/0.001704, allocations: 217.1 kB / 24.5 MB, free: 3.926 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.485/1.485, allocations: 230.6 MB / 258.3 MB, free: 7.785 MB / 206.1 MB
"
[Timeout remaining time 178]
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(Modelica.Electrical.Spice3.Examples.InvertersExtendedModel,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|mp1.S.v|mp2.S.v|v.p.v",fileNamePrefix="Modelica_4.1.0_Modelica.Electrical.Spice3.Examples.InvertersExtendedModel")
translateModel(Modelica.Electrical.Spice3.Examples.InvertersExtendedModel,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|mp1.S.v|mp2.S.v|v.p.v",fileNamePrefix="Modelica_4.1.0_Modelica.Electrical.Spice3.Examples.InvertersExtendedModel") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.043e-05/2.043e-05, allocations: 5.234 kB / 378.9 MB, free: 2.496 MB / 302.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Electrical.Spice3.Examples.InvertersExtendedModel): time 0.1832/0.1832, allocations: 12.89 MB / 391.8 MB, free: 7.32 MB / 302.1 MB
Notification: Performance of NFInst.instExpressions: time 0.02336/0.2065, allocations: 17.97 MB / 409.8 MB, free: 11.06 MB / 318.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004423/0.211, allocations: 137.1 kB / 409.9 MB, free: 10.96 MB / 318.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.004366/0.2153, allocations: 1.154 MB / 411.1 MB, free: 10.13 MB / 318.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.01039/0.2257, allocations: 2.405 MB / 413.5 MB, free: 8.223 MB / 318.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.005769/0.2315, allocations: 1.418 MB / 414.9 MB, free: 7.16 MB / 318.1 MB
Notification: Performance of NFFlatten.flatten: time 0.01231/0.2438, allocations: 7.418 MB / 422.3 MB, free: 1.809 MB / 318.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0003613/0.2442, allocations: 133.6 kB / 422.5 MB, free: 1.68 MB / 318.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003638/0.2478, allocations: 1.998 MB / 424.4 MB, free: 15.68 MB / 334.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001797/0.2496, allocations: 1.352 MB / 425.8 MB, free: 14.32 MB / 334.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001953/0.2498, allocations: 76 kB / 425.9 MB, free: 14.24 MB / 334.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.007174/0.257, allocations: 2.107 MB / 428 MB, free: 12.13 MB / 334.1 MB
Notification: Performance of combineBinaries: time 0.006251/0.2632, allocations: 6.341 MB / 434.3 MB, free: 5.715 MB / 334.1 MB
Notification: Performance of replaceArrayConstructors: time 0.004975/0.2682, allocations: 4.749 MB / 439.1 MB, free: 0.9023 MB / 334.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.001025/0.2692, allocations: 272 kB / 439.3 MB, free: 0.6367 MB / 334.1 MB
Notification: Performance of FrontEnd: time 0.0001649/0.2694, allocations: 19.94 kB / 439.4 MB, free: 0.6172 MB / 334.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 154 (122)
 * Number of variables: 154 (154)
Notification: Performance of [SIM] Bindings: time 0.02588/0.2953, allocations: 23.59 MB / 462.9 MB, free: 8.527 MB / 366.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.002777/0.298, allocations: 1.559 MB / 464.5 MB, free: 6.934 MB / 366.1 MB
Notification: Performance of [SIM] Early Inline: time 0.005764/0.3038, allocations: 5.419 MB / 469.9 MB, free: 1.461 MB / 366.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.001724/0.3055, allocations: 0.8788 MB / 470.8 MB, free: 0.4961 MB / 366.1 MB
Notification: Performance of [SIM] Alias: time 0.005941/0.3115, allocations: 3.622 MB / 474.4 MB, free: 12.64 MB / 382.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.001228/0.3127, allocations: 0.7678 MB / 475.2 MB, free: 11.8 MB / 382.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0004715/0.3132, allocations: 372 kB / 475.6 MB, free: 11.41 MB / 382.1 MB
Notification: Performance of [SIM] Detect States: time 0.001504/0.3147, allocations: 1.129 MB / 476.7 MB, free: 10.22 MB / 382.1 MB
Notification: Performance of [SIM] Events: time 0.0007324/0.3154, allocations: 0.5905 MB / 477.3 MB, free: 9.625 MB / 382.1 MB
Notification: Performance of [SIM] Partitioning: time 0.002898/0.3183, allocations: 2.746 MB / 480 MB, free: 6.855 MB / 382.1 MB
Notification: Performance of [SIM] Causalize: time 0.01142/0.3297, allocations: 9.086 MB / 489.1 MB, free: 13.62 MB / 398.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.003704/0.3334, allocations: 3.552 MB / 492.7 MB, free: 10.07 MB / 398.1 MB
Notification: Performance of [INI] Simplify: time 0.006492/0.3399, allocations: 3.448 MB / 496.1 MB, free: 6.398 MB / 398.1 MB
Notification: Performance of [INI] Inline: time 0.03941/0.3793, allocations: 28.85 MB / 0.5127 GB, free: 9.477 MB / 430.1 MB
Notification: Performance of [INI] Partitioning: time 0.00101/0.3803, allocations: 212.7 kB / 0.5129 GB, free: 9.223 MB / 430.1 MB
Notification: Performance of [INI] Cleanup: time 0.00798/0.3883, allocations: 2.803 MB / 0.5156 GB, free: 6.418 MB / 430.1 MB
Notification: Performance of [INI] Causalize: time 0.9639/1.352, allocations: 321.7 MB / 0.8298 GB, free: 44.74 MB / 0.5137 GB
Notification: Performance of [INI] Tearing: time 0.000379/1.353, allocations: 481.5 kB / 0.8302 GB, free: 44.74 MB / 0.5137 GB
Notification: Performance of [SIM] Initialization: time 2.024e-06/1.353, allocations: 0 / 0.8302 GB, free: 44.74 MB / 0.5137 GB
Notification: Performance of [SIM] Remove Dummies: time 2.202e-05/1.353, allocations: 4.75 kB / 0.8302 GB, free: 44.74 MB / 0.5137 GB
Notification: Performance of [SIM] Tearing: time 0.0001859/1.353, allocations: 235.1 kB / 0.8305 GB, free: 44.74 MB / 0.5137 GB
Notification: Performance of [SIM] Categorize: time 1.898e-05/1.353, allocations: 7.594 kB / 0.8305 GB, free: 44.74 MB / 0.5137 GB
Notification: Performance of [SIM] Solve: time 0.0295/1.382, allocations: 24.3 MB / 0.8542 GB, free: 31.38 MB / 0.5137 GB
Notification: Performance of [SIM] Jacobian: time 0.002792/1.385, allocations: 2.489 MB / 0.8566 GB, free: 29.41 MB / 0.5137 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: ....... 0
Notification: Variable statistics after passing the back-end:
 * Number of states: ............................. 6 (6) {mp1.Dinternal, mn1.Dinternal, mp2.Dinternal, mp2.Sinternal, mn2.Dinternal, c1.vinternal}
 * Number of discrete states: .................... 6 (6) {v.counter, v.counter2, v.T0, vin.counter, vin.counter2, vin.T0}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 12 (12) {$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: ........... 96 (scalar:96, array:0, record:0)
 * Number of multi strong components: ............ 6 (algorithm:0, when:6, if:0, tuple:4)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 3 (linear: 3, nonlinear:0)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 610 (scalar:610, array:0, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:4)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 3 (linear: 3, nonlinear:0)
Notification: Performance of backend: time 0.0002236/1.385, allocations: 125.6 kB / 0.8568 GB, free: 29.39 MB / 0.5137 GB
Notification: Performance of SimCode: time 0.01973/1.405, allocations: 19.04 MB / 0.8753 GB, free: 15.13 MB / 0.5137 GB
Notification: Performance of Templates: time 0.5921/1.997, allocations: 232 MB / 1.102 GB, free: 30.24 MB / 0.545 GB
"
[Timeout remaining time 658]
make -j1 -f Modelica_4.1.0_Modelica.Electrical.Spice3.Examples.InvertersExtendedModel.makefile [Timeout 660]
(rm -f Modelica_4.1.0_Modelica.Electrical.Spice3.Examples.InvertersExtendedModel.pipe ; mkfifo Modelica_4.1.0_Modelica.Electrical.Spice3.Examples.InvertersExtendedModel.pipe ; head -c 1048576 < Modelica_4.1.0_Modelica.Electrical.Spice3.Examples.InvertersExtendedModel.pipe >> ../files/Modelica_4.1.0_Modelica.Electrical.Spice3.Examples.InvertersExtendedModel.sim & ./Modelica_4.1.0_Modelica.Electrical.Spice3.Examples.InvertersExtendedModel  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Modelica_4.1.0_Modelica.Electrical.Spice3.Examples.InvertersExtendedModel.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 17.696590239182115]
Failed to read output from testmodel.py, exit status != 0:
['time', 'mp1.S.v', 'mp2.S.v', 'v.p.v']
2.0047003221698105 2.014076198 1.7423111930000001
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f9b3561ef20>