Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_3.2.3_Modelica.Electrical.QuasiStationary.SinglePhase.Examples.Transformer.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.0015/0.0015, allocations: 105.5 kB / 22 MB, free: 5.219 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.001907/0.001907, allocations: 209.5 kB / 25.3 MB, free: 2.898 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.785/1.785, allocations: 207.7 MB / 236.2 MB, free: 9.781 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.Electrical.QuasiStationary.SinglePhase.Examples.Transformer,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|load.pin_p.reference.gamma|voltageSource.pin_p.reference.gamma|idealTransformer.v1.re|idealTransformer.v1.im|idealTransformer.i1.re|idealTransformer.i1.im|idealTransformer.v2.re|idealTransformer.v2.im|idealTransformer.i2.re|idealTransformer.i2.im",fileNamePrefix="Modelica_3.2.3_Modelica.Electrical.QuasiStationary.SinglePhase.Examples.Transformer")
translateModel(Modelica.Electrical.QuasiStationary.SinglePhase.Examples.Transformer,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|load.pin_p.reference.gamma|voltageSource.pin_p.reference.gamma|idealTransformer.v1.re|idealTransformer.v1.im|idealTransformer.i1.re|idealTransformer.i1.im|idealTransformer.v2.re|idealTransformer.v2.im|idealTransformer.i2.re|idealTransformer.i2.im",fileNamePrefix="Modelica_3.2.3_Modelica.Electrical.QuasiStationary.SinglePhase.Examples.Transformer") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.951e-05/2.951e-05, allocations: 2.281 kB / 348.5 MB, free: 9.484 MB / 286.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Electrical.QuasiStationary.SinglePhase.Examples.Transformer): time 0.008777/0.008807, allocations: 5.215 MB / 353.7 MB, free: 4.234 MB / 286.1 MB
Notification: Performance of NFInst.instExpressions: time 0.004303/0.01311, allocations: 1.815 MB / 355.5 MB, free: 2.406 MB / 286.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.000826/0.01394, allocations: 20 kB / 355.6 MB, free: 2.387 MB / 286.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.004144/0.01808, allocations: 1.501 MB / 357.1 MB, free: 0.8789 MB / 286.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.002343/0.02042, allocations: 0.6606 MB / 357.7 MB, free: 220 kB / 286.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0009677/0.02139, allocations: 402.9 kB / 358.1 MB, free: 15.82 MB / 302.1 MB
Notification: Performance of NFFlatten.flatten: time 0.001334/0.02272, allocations: 1.117 MB / 359.2 MB, free: 14.7 MB / 302.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001222/0.02395, allocations: 0.7051 MB / 359.9 MB, free: 13.98 MB / 302.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0005212/0.02447, allocations: 422.1 kB / 360.4 MB, free: 13.57 MB / 302.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0006294/0.0251, allocations: 0.513 MB / 360.9 MB, free: 13.05 MB / 302.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001271/0.02522, allocations: 87.94 kB / 360.9 MB, free: 12.97 MB / 302.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0005625/0.02579, allocations: 231.9 kB / 361.2 MB, free: 12.74 MB / 302.1 MB
Notification: Performance of combineBinaries: time 0.001821/0.02761, allocations: 2.184 MB / 363.4 MB, free: 10.54 MB / 302.1 MB
Notification: Performance of replaceArrayConstructors: time 0.001241/0.02885, allocations: 1.446 MB / 364.8 MB, free: 9.07 MB / 302.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0001468/0.02899, allocations: 72 kB / 364.9 MB, free: 9 MB / 302.1 MB
Notification: Performance of FrontEnd: time 0.000123/0.02912, allocations: 23.92 kB / 364.9 MB, free: 8.977 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: 276 (224)
 * Number of variables: 276 (276)
Notification: Performance of [SIM] Bindings: time 0.006451/0.03557, allocations: 6.285 MB / 371.2 MB, free: 2.457 MB / 302.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.001067/0.03664, allocations: 0.9393 MB / 372.1 MB, free: 1.48 MB / 302.1 MB
Notification: Performance of [SIM] Early Inline: time 0.006662/0.0433, allocations: 5.918 MB / 378 MB, free: 11.45 MB / 318.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.001069/0.04437, allocations: 0.5782 MB / 378.6 MB, free: 10.77 MB / 318.1 MB
Notification: Performance of [SIM] Alias: time 0.003729/0.0481, allocations: 2.858 MB / 381.5 MB, free: 7.664 MB / 318.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.000936/0.04903, allocations: 0.5632 MB / 382 MB, free: 7 MB / 318.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.00053/0.04956, allocations: 408.2 kB / 382.4 MB, free: 6.539 MB / 318.1 MB
Notification: Performance of [SIM] Detect States: time 0.001207/0.05077, allocations: 1.004 MB / 383.4 MB, free: 5.434 MB / 318.1 MB
Notification: Performance of [SIM] Events: time 0.0003709/0.05114, allocations: 208.5 kB / 383.6 MB, free: 5.211 MB / 318.1 MB
Notification: Performance of [SIM] Partitioning: time 0.002159/0.0533, allocations: 1.742 MB / 385.4 MB, free: 3.422 MB / 318.1 MB
Notification: Performance of [SIM] Causalize: time 0.2049/0.2582, allocations: 6.154 MB / 391.5 MB, free: 32.68 MB / 318.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.003253/0.2615, allocations: 3.344 MB / 394.9 MB, free: 32.68 MB / 318.1 MB
Notification: Performance of [INI] Simplify: time 0.002651/0.2641, allocations: 1.333 MB / 396.2 MB, free: 32.11 MB / 318.1 MB
Notification: Performance of [INI] Inline: time 0.005527/0.2696, allocations: 5.876 MB / 402.1 MB, free: 28.46 MB / 318.1 MB
Notification: Performance of [INI] Partitioning: time 0.0003487/0.27, allocations: 101 kB / 402.2 MB, free: 28.38 MB / 318.1 MB
Notification: Performance of [INI] Cleanup: time 0.0009166/0.2709, allocations: 0.8589 MB / 403.1 MB, free: 27.83 MB / 318.1 MB
Notification: Performance of [INI] Causalize: time 0.009335/0.2802, allocations: 5.658 MB / 408.7 MB, free: 26.62 MB / 318.1 MB
Notification: Performance of [INI] Tearing: time 7.361e-05/0.2803, allocations: 155.6 kB / 408.9 MB, free: 26.53 MB / 318.1 MB
Notification: Performance of [SIM] Initialization: time 1.944e-06/0.2803, allocations: 0 / 408.9 MB, free: 26.53 MB / 318.1 MB
Notification: Performance of [SIM] DAE-Mode: time 0.002238/0.2825, allocations: 1.539 MB / 410.4 MB, free: 26.07 MB / 318.1 MB
Notification: Performance of [SIM] Remove Dummies: time 1.784e-05/0.2826, allocations: 2.5 kB / 410.4 MB, free: 26.07 MB / 318.1 MB
Notification: Performance of [SIM] Tearing: time 0.0001411/0.2827, allocations: 288 kB / 410.7 MB, free: 25.86 MB / 318.1 MB
Notification: Performance of [SIM] Categorize: time 6.838e-05/0.2828, allocations: 15.27 kB / 410.7 MB, free: 25.86 MB / 318.1 MB
Notification: Performance of [SIM] Solve: time 0.006926/0.2897, allocations: 5.725 MB / 416.4 MB, free: 23.29 MB / 318.1 MB
Notification: Performance of [SIM] Jacobian: time 0.001488/0.2912, allocations: 1.53 MB / 418 MB, free: 22.14 MB / 318.1 MB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 2
 * Number of algebraic partitions: ............... 0
 * Number of ODE event partitions: ............... 0
 * Number of algebraic event partitions: ......... 2
 * 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) {voltageSource.pin_p.reference.gamma, load.pin_p.reference.gamma}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 3 (3) {$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: ........... 267 (scalar:267, array:0, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:14)
 * 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: ........... 278 (scalar:278, array:0, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:14)
 * 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.0001552/0.2913, allocations: 95.67 kB / 418 MB, free: 22.05 MB / 318.1 MB
Notification: Performance of SimCode: time 0.005207/0.2965, allocations: 6.375 MB / 424.4 MB, free: 15.52 MB / 318.1 MB
Notification: Performance of Templates: time 0.03032/0.3269, allocations: 20.82 MB / 445.2 MB, free: 10.73 MB / 334.1 MB
"
[Timeout remaining time 660]
make -j1 -f Modelica_3.2.3_Modelica.Electrical.QuasiStationary.SinglePhase.Examples.Transformer.makefile [Timeout 660]
(rm -f Modelica_3.2.3_Modelica.Electrical.QuasiStationary.SinglePhase.Examples.Transformer.pipe ; mkfifo Modelica_3.2.3_Modelica.Electrical.QuasiStationary.SinglePhase.Examples.Transformer.pipe ; head -c 1048576 < Modelica_3.2.3_Modelica.Electrical.QuasiStationary.SinglePhase.Examples.Transformer.pipe >> ../files/Modelica_3.2.3_Modelica.Electrical.QuasiStationary.SinglePhase.Examples.Transformer.sim & ./Modelica_3.2.3_Modelica.Electrical.QuasiStationary.SinglePhase.Examples.Transformer  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Modelica_3.2.3_Modelica.Electrical.QuasiStationary.SinglePhase.Examples.Transformer.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 5.760151242022403]
Failed to read output from testmodel.py, exit status != 0:
['time', 'load.pin_p.reference.gamma', 'voltageSource.pin_p.reference.gamma', 'idealTransformer.v1.re', 'idealTransformer.v1.im', 'idealTransformer.i1.re', 'idealTransformer.i1.im', 'idealTransformer.v2.re', 'idealTransformer.v2.im', 'idealTransformer.i2.re', 'idealTransformer.i2.im']
0.3358388869673945 0.352490264 0.32018514400000003
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f3efc42efb0>