Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PowerSysPro_PowerSysPro.Examples.BreakerTests.BreakersWithLines.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.001312/0.001312, allocations: 100.4 kB / 20.55 MB, free: 1.113 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.001473/0.001473, allocations: 214.1 kB / 23.86 MB, free: 4.309 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.578/1.578, allocations: 230.6 MB / 257.6 MB, free: 7.777 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSysPro 2.1.3/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSysPro 2.1.3/package.mo): time 0.03801/0.03801, allocations: 6.134 MB / 320.2 MB, free: 12.73 MB / 254.1 MB
"
[Timeout remaining time 180]
Using package PowerSysPro with version 2.1.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSysPro 2.1.3/package.mo)
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(PowerSysPro.Examples.BreakerTests.BreakersWithLines,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="PowerSysPro_PowerSysPro.Examples.BreakerTests.BreakersWithLines")
translateModel(PowerSysPro.Examples.BreakerTests.BreakersWithLines,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="PowerSysPro_PowerSysPro.Examples.BreakerTests.BreakersWithLines") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.357e-05/2.357e-05, allocations: 2.281 kB / 445.8 MB, free: 4.59 MB / 302.1 MB
Notification: Performance of NFInst.instantiate(PowerSysPro.Examples.BreakerTests.BreakersWithLines): time 0.003489/0.003513, allocations: 2.723 MB / 448.5 MB, free: 1.855 MB / 302.1 MB
Notification: Performance of NFInst.instExpressions: time 0.001335/0.004847, allocations: 0.7938 MB / 449.3 MB, free: 1.059 MB / 302.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0001043/0.004951, allocations: 12 kB / 449.3 MB, free: 1.047 MB / 302.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0003825/0.005334, allocations: 143.2 kB / 449.4 MB, free: 0.9062 MB / 302.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0003099/0.005644, allocations: 143 kB / 449.6 MB, free: 0.7656 MB / 302.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001059/0.006703, allocations: 0.6071 MB / 450.2 MB, free: 160 kB / 302.1 MB
Notification: Performance of NFFlatten.flatten: time 0.0004452/0.007148, allocations: 406.3 kB / 450.6 MB, free: 15.76 MB / 318.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0002326/0.007381, allocations: 80.53 kB / 450.7 MB, free: 15.68 MB / 318.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0001822/0.007563, allocations: 147.4 kB / 450.8 MB, free: 15.53 MB / 318.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.000291/0.007854, allocations: 199 kB / 451 MB, free: 15.34 MB / 318.1 MB
Notification: Performance of NFPackage.collectConstants: time 5.407e-05/0.007908, allocations: 28 kB / 451 MB, free: 15.31 MB / 318.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0003246/0.008232, allocations: 175.9 kB / 451.2 MB, free: 15.14 MB / 318.1 MB
Notification: Performance of combineBinaries: time 0.0006995/0.008932, allocations: 0.7348 MB / 451.9 MB, free: 14.39 MB / 318.1 MB
Notification: Performance of replaceArrayConstructors: time 0.0003687/0.009301, allocations: 489.6 kB / 452.4 MB, free: 13.91 MB / 318.1 MB
Notification: Performance of NFVerifyModel.verify: time 4.504e-05/0.009346, allocations: 24 kB / 452.4 MB, free: 13.89 MB / 318.1 MB
Notification: Performance of FrontEnd: time 2.747e-05/0.009373, allocations: 4 kB / 452.4 MB, free: 13.88 MB / 318.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 76 (65)
 * Number of variables: 76 (76)
Notification: Performance of [SIM] Bindings: time 0.002377/0.01175, allocations: 2.155 MB / 454.6 MB, free: 11.59 MB / 318.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.0002556/0.01201, allocations: 240.8 kB / 454.8 MB, free: 11.34 MB / 318.1 MB
Notification: Performance of [SIM] Early Inline: time 0.002566/0.01457, allocations: 2.332 MB / 457.2 MB, free: 8.98 MB / 318.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.0004007/0.01497, allocations: 264.2 kB / 457.4 MB, free: 8.672 MB / 318.1 MB
Warning: NBAlias.setStartFixed: Alias set with conflicting unfixed start values detected. Use -d=dumprepl for more information.
Notification: Performance of [SIM] Alias: time 0.001366/0.01634, allocations: 0.9895 MB / 458.4 MB, free: 7.625 MB / 318.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.0003744/0.01671, allocations: 272.1 kB / 458.7 MB, free: 7.316 MB / 318.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0001882/0.0169, allocations: 164.1 kB / 458.8 MB, free: 7.129 MB / 318.1 MB
Notification: Performance of [SIM] Detect States: time 0.0004226/0.01732, allocations: 396.3 kB / 459.2 MB, free: 6.684 MB / 318.1 MB
Notification: Performance of [SIM] Events: time 0.0001628/0.01749, allocations: 75.97 kB / 459.3 MB, free: 6.605 MB / 318.1 MB
Notification: Performance of [SIM] Partitioning: time 0.0007808/0.01827, allocations: 0.6307 MB / 459.9 MB, free: 5.973 MB / 318.1 MB
Notification: Performance of [SIM] Causalize: time 0.001967/0.02023, allocations: 1.38 MB / 461.3 MB, free: 4.57 MB / 318.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.001177/0.02141, allocations: 1.218 MB / 462.5 MB, free: 3.352 MB / 318.1 MB
Notification: Performance of [INI] Simplify: time 0.001015/0.02243, allocations: 0.563 MB / 463.1 MB, free: 2.719 MB / 318.1 MB
Notification: Performance of [INI] Inline: time 0.002237/0.02466, allocations: 2.236 MB / 465.3 MB, free: 488 kB / 318.1 MB
Notification: Performance of [INI] Partitioning: time 6.792e-05/0.02473, allocations: 40 kB / 465.4 MB, free: 440 kB / 318.1 MB
Notification: Performance of [INI] Cleanup: time 0.000281/0.02501, allocations: 307.7 kB / 465.7 MB, free: 132 kB / 318.1 MB
Notification: Performance of [INI] Causalize: time 0.002569/0.02758, allocations: 1.703 MB / 467.4 MB, free: 14.41 MB / 334.1 MB
Notification: Performance of [INI] Tearing: time 0.0004015/0.02798, allocations: 293.4 kB / 467.7 MB, free: 14.13 MB / 334.1 MB
Notification: Performance of [SIM] Initialization: time 6.71e-07/0.02798, allocations: 4 kB / 467.7 MB, free: 14.12 MB / 334.1 MB
Notification: Performance of [SIM] DAE-Mode: time 0.0008623/0.02885, allocations: 0.5685 MB / 468.2 MB, free: 13.55 MB / 334.1 MB
Notification: Performance of [SIM] Remove Dummies: time 6.692e-06/0.02885, allocations: 0.5 kB / 468.2 MB, free: 13.55 MB / 334.1 MB
Notification: Performance of [SIM] Tearing: time 0.0003563/0.02921, allocations: 321.4 kB / 468.5 MB, free: 13.25 MB / 334.1 MB
Notification: Performance of [SIM] Categorize: time 1.035e-05/0.02922, allocations: 0 / 468.5 MB, free: 13.25 MB / 334.1 MB
Notification: Performance of [SIM] Solve: time 0.002819/0.03204, allocations: 2.117 MB / 470.7 MB, free: 11.13 MB / 334.1 MB
Notification: Performance of [SIM] Jacobian: time 0.003473/0.03551, allocations: 2.977 MB / 473.6 MB, free: 8.023 MB / 334.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: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 4 (4) {n.u, $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: ........... 55 (scalar:55, 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: ... 2 (linear: 2, nonlinear:0)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 63 (scalar:63, 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: ... 2 (linear: 2, nonlinear:0)
Notification: Performance of backend: time 8.849e-05/0.0356, allocations: 47.69 kB / 473.7 MB, free: 7.98 MB / 334.1 MB
Notification: Performance of SimCode: time 0.002994/0.03859, allocations: 2.139 MB / 475.8 MB, free: 5.805 MB / 334.1 MB
Notification: Performance of Templates: time 0.01564/0.05423, allocations: 8.851 MB / 484.7 MB, free: 13.01 MB / 350.1 MB
"
[Timeout remaining time 660]
make -j1 -f PowerSysPro_PowerSysPro.Examples.BreakerTests.BreakersWithLines.makefile [Timeout 660]
(rm -f PowerSysPro_PowerSysPro.Examples.BreakerTests.BreakersWithLines.pipe ; mkfifo PowerSysPro_PowerSysPro.Examples.BreakerTests.BreakersWithLines.pipe ; head -c 1048576 < PowerSysPro_PowerSysPro.Examples.BreakerTests.BreakersWithLines.pipe >> ../files/PowerSysPro_PowerSysPro.Examples.BreakerTests.BreakersWithLines.sim & ./PowerSysPro_PowerSysPro.Examples.BreakerTests.BreakersWithLines  -abortSlowSimulation -alarm=480 -lv LOG_STATS > PowerSysPro_PowerSysPro.Examples.BreakerTests.BreakersWithLines.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 5.032608235022053]
Failed to read output from testmodel.py, exit status != 0:
0.06230181100545451 0.077862581 0.06556806600000001
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f06970a2dd0>