Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII.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.001329/0.001329, allocations: 104.3 kB / 19.77 MB, free: 2.051 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.446/1.446, allocations: 230.7 MB / 253.5 MB, free: 14.25 MB / 206.1 MB
"
[Timeout remaining time 178]
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.001558/0.001558, allocations: 213.1 kB / 310.1 MB, free: 3.062 MB / 238.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/package.mo): time 0.4701/0.4701, allocations: 79.75 MB / 446.3 MB, free: 40.38 MB / 350.1 MB
"
[Timeout remaining time 179]
Using package OpenIPSL with version 3.0.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/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 Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 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(OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII")
translateModel(OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.722e-05/1.722e-05, allocations: 3.641 kB / 0.5942 GB, free: 38.41 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII): time 0.009324/0.009341, allocations: 11.13 MB / 0.6051 GB, free: 31.86 MB / 446.1 MB
Notification: Performance of NFInst.instExpressions: time 0.006137/0.01548, allocations: 3.273 MB / 0.6083 GB, free: 29.57 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0008742/0.01635, allocations: 31.19 kB / 0.6083 GB, free: 29.57 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0008685/0.01722, allocations: 0.5088 MB / 0.6088 GB, free: 29.29 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.002899/0.02012, allocations: 1.482 MB / 0.6102 GB, free: 28.29 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0027/0.02282, allocations: 1.457 MB / 0.6117 GB, free: 27.57 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.003088/0.02591, allocations: 4.186 MB / 0.6157 GB, free: 25.49 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0006714/0.02658, allocations: 412.5 kB / 0.6161 GB, free: 25.27 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001344/0.02792, allocations: 1.621 MB / 0.6177 GB, free: 24.47 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001174/0.0291, allocations: 1.696 MB / 0.6194 GB, free: 23.71 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0002872/0.02938, allocations: 224.8 kB / 0.6196 GB, free: 23.71 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0006783/0.03006, allocations: 425.1 kB / 0.62 GB, free: 23.65 MB / 446.1 MB
Notification: Performance of combineBinaries: time 0.003185/0.03325, allocations: 7.063 MB / 0.6269 GB, free: 18.9 MB / 446.1 MB
Notification: Performance of replaceArrayConstructors: time 0.001655/0.0349, allocations: 4.43 MB / 0.6312 GB, free: 15.76 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0003289/0.03523, allocations: 198.8 kB / 0.6314 GB, free: 15.76 MB / 446.1 MB
Notification: Performance of FrontEnd: time 0.0002993/0.03553, allocations: 74.28 kB / 0.6315 GB, free: 15.75 MB / 446.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 489 (453)
 * Number of variables: 489 (489)
Notification: Performance of [SIM] Bindings: time 0.01024/0.04577, allocations: 15.95 MB / 0.6471 GB, free: 5.211 MB / 446.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.002109/0.04788, allocations: 1.998 MB / 0.649 GB, free: 4.031 MB / 446.1 MB
Notification: Performance of [SIM] Early Inline: time 0.01948/0.06736, allocations: 19.88 MB / 0.6684 GB, free: 9.883 MB / 462.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.004466/0.07183, allocations: 2.566 MB / 0.6709 GB, free: 8.93 MB / 462.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.01559/0.08742, allocations: 11.69 MB / 0.6823 GB, free: 14.47 MB / 478.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.003272/0.09069, allocations: 2.37 MB / 0.6847 GB, free: 12.03 MB / 478.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.00116/0.09185, allocations: 0.9806 MB / 0.6856 GB, free: 10.99 MB / 478.1 MB
Notification: Performance of [SIM] Detect States: time 0.002492/0.09435, allocations: 2.589 MB / 0.6881 GB, free: 8.316 MB / 478.1 MB
Notification: Performance of [SIM] Events: time 0.001798/0.09614, allocations: 1.36 MB / 0.6895 GB, free: 6.941 MB / 478.1 MB
Notification: Performance of [SIM] Partitioning: time 0.004928/0.1011, allocations: 4.389 MB / 0.6938 GB, free: 2.496 MB / 478.1 MB
Notification: Performance of [SIM] Causalize: time 0.01146/0.1125, allocations: 7.812 MB / 0.7014 GB, free: 10.58 MB / 494.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.009114/0.1216, allocations: 9.125 MB / 0.7103 GB, free: 1.445 MB / 494.1 MB
Notification: Performance of [INI] Simplify: time 0.01056/0.1322, allocations: 6.481 MB / 0.7166 GB, free: 10.71 MB / 0.4981 GB
Notification: Performance of [INI] Inline: time 0.01905/0.1512, allocations: 18.42 MB / 0.7346 GB, free: 8.238 MB / 0.5137 GB
Notification: Performance of [INI] Partitioning: time 0.0006893/0.1519, allocations: 212.8 kB / 0.7348 GB, free: 7.984 MB / 0.5137 GB
Notification: Performance of [INI] Cleanup: time 0.004051/0.156, allocations: 3.612 MB / 0.7384 GB, free: 4.34 MB / 0.5137 GB
Notification: Performance of [INI] Causalize: time 0.07259/0.2286, allocations: 45.04 MB / 0.7823 GB, free: 6.508 MB / 0.5606 GB
Notification: Performance of [INI] Tearing: time 0.01211/0.2407, allocations: 9.913 MB / 0.792 GB, free: 13.05 MB / 0.5762 GB
Notification: Performance of [SIM] Initialization: time 2.735e-06/0.2407, allocations: 0 / 0.792 GB, free: 13.05 MB / 0.5762 GB
Notification: Performance of [SIM] DAE-Mode: time 0.008121/0.2488, allocations: 6.016 MB / 0.7979 GB, free: 7.145 MB / 0.5762 GB
Notification: Performance of [SIM] Remove Dummies: time 1.766e-05/0.2488, allocations: 5.938 kB / 0.7979 GB, free: 7.141 MB / 0.5762 GB
Notification: Performance of [SIM] Tearing: time 0.003614/0.2524, allocations: 2.842 MB / 0.8007 GB, free: 4.293 MB / 0.5762 GB
Notification: Performance of [SIM] Categorize: time 3.548e-05/0.2525, allocations: 19.94 kB / 0.8007 GB, free: 4.273 MB / 0.5762 GB
Notification: Performance of [SIM] Solve: time 0.02496/0.2774, allocations: 17.64 MB / 0.8179 GB, free: 2.598 MB / 0.5919 GB
Notification: Performance of [SIM] Jacobian: time 0.2846/0.562, allocations: 40.52 MB / 0.8575 GB, free: 132.3 MB / 0.5919 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: ......... 2
 * Number of clocked partitions: ................. 0
 * Number of initial partitions: ................. 1
 * Number of initial(lambda=0) partitions: ....... 1
Notification: Variable statistics after passing the back-end:
 * Number of states: ............................. 17 (17) {order3_2.delta, order3_2.w, order2.delta, order2.w, Syn2.e1q, Syn2.e2q, Syn2.delta, Syn2.w, Exc1.vm, Exc1.vr, Exc1.vf1, Exc2.vm, Exc2.vr, Exc2.vf1, order3_2.e1q, Syn2.e1d, Syn2.e2d}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 54 (54) {$SEV_6, $SEV_5, $SEV_4, $SEV_3, $SEV_2, $SEV_1, $SEV_0, $TEV_46, $TEV_45, $TEV_44, $TEV_43, $TEV_42, $TEV_41, $TEV_40, $TEV_39, $TEV_38, $TEV_37, $TEV_36, $TEV_35, $TEV_34, $TEV_33, $TEV_32, $TEV_31, $TEV_30, $TEV_29, $TEV_28, $TEV_27, $TEV_26, $TEV_25, $TEV_24, $TEV_23, $TEV_22, $TEV_21, $TEV_20, $TEV_19, $TEV_18, $TEV_17, $TEV_16, $TEV_15, $TEV_14, $TEV_13, $TEV_12, $TEV_11, $TEV_10, $TEV_9, $TEV_8, $TEV_7, $TEV_6, $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: ........... 185 (scalar:185, 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: ........... 426 (scalar:426, 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 (lambda=0)] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 426 (scalar:426, 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 0.0002161/0.5622, allocations: 177.5 kB / 0.8577 GB, free: 132.1 MB / 0.5919 GB
Notification: Performance of SimCode: time 0.01698/0.5792, allocations: 17.48 MB / 0.8747 GB, free: 127.6 MB / 0.5919 GB
Notification: Performance of Templates: time 0.0786/0.6578, allocations: 69.17 MB / 0.9423 GB, free: 81.73 MB / 0.5919 GB
"
[Timeout remaining time 659]
make -j1 -f OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII.makefile [Timeout 660]
(rm -f OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII.pipe ; mkfifo OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII.pipe ; head -c 1048576 < OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII.pipe >> ../files/OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII.sim & ./OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 440.6891814909759]
Failed to read output from testmodel.py, exit status != 0:
0.6644270530086942 0.676895 0.639167051
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f8ed3826fb0>