Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_trunk_Modelica.Magnetic.FluxTubes.Examples.Hysteresis.SinglePhaseTransformerWithHysteresis1.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.001563/0.001563, allocations: 107.6 kB / 21.25 MB, free: 416 kB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.002136/0.002136, allocations: 206.7 kB / 24.55 MB, free: 3.727 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.532/1.532, allocations: 230.5 MB / 258.2 MB, free: 7.719 MB / 206.1 MB
"
[Timeout remaining time 178]
Using package Modelica with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo)
Using package Complex with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo)
Using package ModelicaServices with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo)
Running command: translateModel(Modelica.Magnetic.FluxTubes.Examples.Hysteresis.SinglePhaseTransformerWithHysteresis1,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|core.B|core.Hstat|core.MagRel|winding1.i|winding2.i",fileNamePrefix="Modelica_trunk_Modelica.Magnetic.FluxTubes.Examples.Hysteresis.SinglePhaseTransformerWithHysteresis1")
translateModel(Modelica.Magnetic.FluxTubes.Examples.Hysteresis.SinglePhaseTransformerWithHysteresis1,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|core.B|core.Hstat|core.MagRel|winding1.i|winding2.i",fileNamePrefix="Modelica_trunk_Modelica.Magnetic.FluxTubes.Examples.Hysteresis.SinglePhaseTransformerWithHysteresis1") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.305e-05/2.305e-05, allocations: 2.281 kB / 379 MB, free: 2.316 MB / 302.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Magnetic.FluxTubes.Examples.Hysteresis.SinglePhaseTransformerWithHysteresis1): time 0.2068/0.2068, allocations: 3.196 MB / 382.2 MB, free: 13.07 MB / 302.1 MB
Notification: Performance of NFInst.instExpressions: time 0.002809/0.2096, allocations: 1.617 MB / 383.8 MB, free: 12.57 MB / 302.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0002705/0.2099, allocations: 5.438 kB / 383.9 MB, free: 12.57 MB / 302.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.004174/0.214, allocations: 2.665 MB / 386.5 MB, free: 12.32 MB / 302.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.001126/0.2152, allocations: 224.4 kB / 386.7 MB, free: 12.21 MB / 302.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001234/0.2164, allocations: 329.4 kB / 387.1 MB, free: 12.14 MB / 302.1 MB
Notification: Performance of NFFlatten.flatten: time 0.004491/0.2209, allocations: 2.378 MB / 389.4 MB, free: 11.86 MB / 302.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0002945/0.2212, allocations: 90.95 kB / 389.5 MB, free: 11.81 MB / 302.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001543/0.2227, allocations: 0.7603 MB / 390.3 MB, free: 11.64 MB / 302.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001494/0.2242, allocations: 1.044 MB / 391.3 MB, free: 11.52 MB / 302.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001233/0.2243, allocations: 44.91 kB / 391.4 MB, free: 11.52 MB / 302.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002815/0.2272, allocations: 1.769 MB / 393.1 MB, free: 11.13 MB / 302.1 MB
Notification: Performance of combineBinaries: time 0.001404/0.2286, allocations: 1.485 MB / 394.6 MB, free: 10.2 MB / 302.1 MB
Notification: Performance of replaceArrayConstructors: time 0.0004206/0.229, allocations: 0.681 MB / 395.3 MB, free: 9.734 MB / 302.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0001254/0.2291, allocations: 41.69 kB / 395.4 MB, free: 9.734 MB / 302.1 MB
Notification: Performance of FrontEnd: time 0.0001423/0.2292, allocations: 19.73 kB / 395.4 MB, free: 9.727 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: 89 (89)
 * Number of variables: 89 (89)
Notification: Performance of [SIM] Bindings: time 0.002672/0.2319, allocations: 2.73 MB / 398.1 MB, free: 7.863 MB / 302.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.0003645/0.2323, allocations: 293.8 kB / 398.4 MB, free: 7.637 MB / 302.1 MB
Notification: Performance of [SIM] Early Inline: time 0.001055/0.2333, allocations: 1.241 MB / 399.6 MB, free: 6.664 MB / 302.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.0003904/0.2337, allocations: 298.2 kB / 399.9 MB, free: 6.457 MB / 302.1 MB
Notification: Performance of [SIM] Alias: time 0.002077/0.2358, allocations: 1.663 MB / 401.6 MB, free: 5.273 MB / 302.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.0002367/0.236, allocations: 221.4 kB / 401.8 MB, free: 5.105 MB / 302.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0001113/0.2362, allocations: 119.6 kB / 401.9 MB, free: 4.988 MB / 302.1 MB
Notification: Performance of [SIM] Detect States: time 0.0003427/0.2365, allocations: 318 kB / 402.2 MB, free: 4.684 MB / 302.1 MB
Notification: Performance of [SIM] Events: time 0.0001203/0.2366, allocations: 73.66 kB / 402.3 MB, free: 4.637 MB / 302.1 MB
Notification: Performance of [SIM] Partitioning: time 0.0004486/0.2371, allocations: 474.2 kB / 402.8 MB, free: 4.293 MB / 302.1 MB
Notification: Performance of [SIM] Causalize: time 0.002248/0.2393, allocations: 2.078 MB / 404.8 MB, free: 2.668 MB / 302.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.0005003/0.2398, allocations: 0.8001 MB / 405.6 MB, free: 1.867 MB / 302.1 MB
Notification: Performance of [INI] Simplify: time 0.003409/0.2432, allocations: 0.8296 MB / 406.5 MB, free: 1.027 MB / 302.1 MB
Notification: Performance of [INI] Inline: time 0.001113/0.2443, allocations: 1.506 MB / 408 MB, free: 15.52 MB / 318.1 MB
Notification: Performance of [INI] Partitioning: time 7.119e-05/0.2444, allocations: 40 kB / 408 MB, free: 15.47 MB / 318.1 MB
Notification: Performance of [INI] Cleanup: time 0.0002428/0.2446, allocations: 247.8 kB / 408.3 MB, free: 15.23 MB / 318.1 MB
Notification: Performance of [INI] Causalize: time 0.007414/0.2521, allocations: 5.965 MB / 414.2 MB, free: 9.18 MB / 318.1 MB
Notification: Performance of [INI] Tearing: time 4.624e-05/0.2521, allocations: 52 kB / 414.3 MB, free: 9.129 MB / 318.1 MB
Notification: Performance of [SIM] Initialization: time 1.492e-06/0.2521, allocations: 4 kB / 414.3 MB, free: 9.125 MB / 318.1 MB
Notification: Performance of [SIM] DAE-Mode: time 0.000637/0.2527, allocations: 407.7 kB / 414.7 MB, free: 8.676 MB / 318.1 MB
Notification: Performance of [SIM] Remove Dummies: time 7.584e-06/0.2528, allocations: 0.5 kB / 414.7 MB, free: 8.676 MB / 318.1 MB
Notification: Performance of [SIM] Tearing: time 0.000345/0.2531, allocations: 260.2 kB / 414.9 MB, free: 8.422 MB / 318.1 MB
Notification: Performance of [SIM] Categorize: time 1.85e-05/0.2531, allocations: 4 kB / 414.9 MB, free: 8.418 MB / 318.1 MB
Notification: Performance of [SIM] Solve: time 0.001944/0.2551, allocations: 1.42 MB / 416.4 MB, free: 6.988 MB / 318.1 MB
Notification: Performance of [SIM] Jacobian: time 0.002362/0.2574, allocations: 2.093 MB / 418.4 MB, free: 4.656 MB / 318.1 MB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 1
 * Number of algebraic partitions: ............... 3
 * Number of ODE event partitions: ............... 1
 * 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: ............................. 5 (5) {winding1.Phi_ind, winding2.Phi_ind, core.MagRel, core.B, core.tabfal.u}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 5 (5) {core.asc, $SEV_2, $SEV_1, $SEV_0, $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: ........... 56 (scalar:56, 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: ........... 100 (scalar:98, array:2, 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 7.668e-05/0.2575, allocations: 44.89 kB / 418.5 MB, free: 4.617 MB / 318.1 MB
Notification: Performance of SimCode: time 0.01349/0.271, allocations: 9.191 MB / 427.7 MB, free: 11.43 MB / 334.1 MB
Notification: Performance of Templates: time 0.01973/0.2907, allocations: 7.737 MB / 435.4 MB, free: 3.742 MB / 334.1 MB
"
[Timeout remaining time 660]
make -j1 -f Modelica_trunk_Modelica.Magnetic.FluxTubes.Examples.Hysteresis.SinglePhaseTransformerWithHysteresis1.makefile [Timeout 660]
(rm -f Modelica_trunk_Modelica.Magnetic.FluxTubes.Examples.Hysteresis.SinglePhaseTransformerWithHysteresis1.pipe ; mkfifo Modelica_trunk_Modelica.Magnetic.FluxTubes.Examples.Hysteresis.SinglePhaseTransformerWithHysteresis1.pipe ; head -c 1048576 < Modelica_trunk_Modelica.Magnetic.FluxTubes.Examples.Hysteresis.SinglePhaseTransformerWithHysteresis1.pipe >> ../files/Modelica_trunk_Modelica.Magnetic.FluxTubes.Examples.Hysteresis.SinglePhaseTransformerWithHysteresis1.sim & ./Modelica_trunk_Modelica.Magnetic.FluxTubes.Examples.Hysteresis.SinglePhaseTransformerWithHysteresis1  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Modelica_trunk_Modelica.Magnetic.FluxTubes.Examples.Hysteresis.SinglePhaseTransformerWithHysteresis1.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 4.9247820019954816]
Failed to read output from testmodel.py, exit status != 0:
['time', 'core.B', 'core.Hstat', 'core.MagRel', 'winding1.i', 'winding2.i']
0.2997165789711289 0.31465299999999996 0.082013686
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f4d1d40efb0>