Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRIII.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/package.mo): time 0.08532/0.08532, allocations: 12.36 MB / 32.03 MB, free: 6.41 MB / 26.18 MB
"
[Timeout remaining time 180]
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.001331/0.001331, allocations: 112.9 kB / 38.07 MB, free: 1.086 MB / 26.18 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.439/1.439, allocations: 207.7 MB / 251.7 MB, free: 7.23 MB / 190.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.001688/0.001688, allocations: 220.4 kB / 307 MB, free: 244 kB / 238.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/package.mo): time 0.3238/0.3238, allocations: 79.74 MB / 441.8 MB, free: 12.22 MB / 318.1 MB
"
[Timeout remaining time 179]
Using package OpenIPSL with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/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 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+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)
Using package Modelica_Synchronous with version 0.93.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/package.mo)
Running command: translateModel(OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRIII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRIII")
translateModel(OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRIII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRIII") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.531e-05/2.531e-05, allocations: 2.281 kB / 0.5888 GB, free: 6.969 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRIII): time 0.01368/0.0137, allocations: 10.96 MB / 0.5995 GB, free: 11.95 MB / 414.1 MB
Notification: Performance of NFInst.instExpressions: time 0.008276/0.02198, allocations: 3.146 MB / 0.6026 GB, free: 8.793 MB / 414.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002522/0.0245, allocations: 24 kB / 0.6026 GB, free: 8.77 MB / 414.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.00134/0.02584, allocations: 0.515 MB / 0.6031 GB, free: 8.25 MB / 414.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.005168/0.03101, allocations: 1.446 MB / 0.6045 GB, free: 6.805 MB / 414.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003695/0.03471, allocations: 1.449 MB / 0.6059 GB, free: 5.387 MB / 414.1 MB
Notification: Performance of NFFlatten.flatten: time 0.006015/0.04072, allocations: 4.077 MB / 0.6099 GB, free: 1.293 MB / 414.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001054/0.04177, allocations: 418.1 kB / 0.6103 GB, free: 0.8594 MB / 414.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002591/0.04437, allocations: 1.575 MB / 0.6118 GB, free: 15.27 MB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002245/0.04661, allocations: 1.667 MB / 0.6135 GB, free: 13.6 MB / 430.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0003948/0.047, allocations: 216 kB / 0.6137 GB, free: 13.39 MB / 430.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0009624/0.04797, allocations: 387.8 kB / 0.614 GB, free: 13.01 MB / 430.1 MB
Notification: Performance of combineBinaries: time 0.006328/0.0543, allocations: 6.902 MB / 0.6208 GB, free: 6.039 MB / 430.1 MB
Notification: Performance of replaceArrayConstructors: time 0.004394/0.05869, allocations: 4.391 MB / 0.6251 GB, free: 1.59 MB / 430.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0007867/0.05948, allocations: 203.9 kB / 0.6253 GB, free: 1.391 MB / 430.1 MB
Notification: Performance of FrontEnd: time 0.0005252/0.06, allocations: 67.88 kB / 0.6253 GB, free: 1.324 MB / 430.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 486 (450)
 * Number of variables: 486 (486)
Notification: Performance of [SIM] Bindings: time 0.01856/0.07856, allocations: 15.66 MB / 0.6406 GB, free: 1.254 MB / 446.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.2684/0.347, allocations: 2.021 MB / 0.6426 GB, free: 32.09 MB / 446.1 MB
Notification: Performance of [SIM] Early Inline: time 0.0239/0.3709, allocations: 19.69 MB / 0.6618 GB, free: 26.76 MB / 446.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.004565/0.3755, allocations: 2.411 MB / 0.6642 GB, free: 25.89 MB / 446.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.01374/0.3892, allocations: 11.57 MB / 0.6755 GB, free: 17.77 MB / 446.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.003142/0.3923, allocations: 2.225 MB / 0.6776 GB, free: 16.66 MB / 446.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.001109/0.3935, allocations: 0.964 MB / 0.6786 GB, free: 15.86 MB / 446.1 MB
Notification: Performance of [SIM] Detect States: time 0.001749/0.3952, allocations: 2.603 MB / 0.6811 GB, free: 13.62 MB / 446.1 MB
Notification: Performance of [SIM] Events: time 0.001513/0.3967, allocations: 1.29 MB / 0.6824 GB, free: 12.91 MB / 446.1 MB
Notification: Performance of [SIM] Partitioning: time 0.003856/0.4006, allocations: 4.309 MB / 0.6866 GB, free: 10.13 MB / 446.1 MB
Notification: Performance of [SIM] Causalize: time 0.009295/0.4099, allocations: 7.755 MB / 0.6942 GB, free: 7.352 MB / 446.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.005877/0.4157, allocations: 8.965 MB / 0.7029 GB, free: 0.5 MB / 446.1 MB
Notification: Performance of [INI] Simplify: time 0.01114/0.4269, allocations: 6.211 MB / 0.709 GB, free: 10.59 MB / 462.1 MB
Notification: Performance of [INI] Inline: time 0.023/0.4499, allocations: 18.03 MB / 0.7266 GB, free: 8.641 MB / 478.1 MB
Notification: Performance of [INI] Partitioning: time 0.0009474/0.4508, allocations: 223.6 kB / 0.7268 GB, free: 8.379 MB / 478.1 MB
Notification: Performance of [INI] Cleanup: time 0.005527/0.4564, allocations: 3.532 MB / 0.7303 GB, free: 4.84 MB / 478.1 MB
Notification: Performance of [INI] Causalize: time 0.08478/0.5411, allocations: 44.63 MB / 0.7738 GB, free: 8.109 MB / 0.5137 GB
Notification: Performance of [INI] Tearing: time 0.01319/0.5543, allocations: 9.72 MB / 0.7833 GB, free: 15 MB / 0.5294 GB
Notification: Performance of [SIM] Initialization: time 3.417e-06/0.5543, allocations: 0 / 0.7833 GB, free: 15 MB / 0.5294 GB
Notification: Performance of [SIM] DAE-Mode: time 0.008868/0.5632, allocations: 5.82 MB / 0.789 GB, free: 9.387 MB / 0.5294 GB
Notification: Performance of [SIM] Remove Dummies: time 3.738e-05/0.5632, allocations: 6 kB / 0.789 GB, free: 9.379 MB / 0.5294 GB
Notification: Performance of [SIM] Tearing: time 0.004031/0.5673, allocations: 2.845 MB / 0.7918 GB, free: 6.613 MB / 0.5294 GB
Notification: Performance of [SIM] Categorize: time 6.536e-05/0.5673, allocations: 21.75 kB / 0.7918 GB, free: 6.598 MB / 0.5294 GB
Notification: Performance of [SIM] Solve: time 0.02922/0.5966, allocations: 16.25 MB / 0.8077 GB, free: 6.41 MB / 0.545 GB
Notification: Performance of [SIM] Jacobian: time 0.04778/0.6443, allocations: 28.35 MB / 0.8354 GB, free: 10.51 MB / 0.5762 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: ............................. 14 (14) {order3_2.delta, order3_2.w, order2.delta, order2.w, order3_1.delta, order3_1.w, Exc1.vm, Exc1.vr, Exc1.vf1, Exc2.vm, Exc2.vr, Exc2.vf1, order3_2.e1q, order3_1.e1q}
 * 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: ........... 182 (scalar:182, 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: ........... 419 (scalar:419, 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: ........... 419 (scalar:419, 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.0003695/0.6447, allocations: 170.8 kB / 0.8355 GB, free: 10.34 MB / 0.5762 GB
Notification: Performance of SimCode: time 0.02336/0.6681, allocations: 14.57 MB / 0.8498 GB, free: 11.59 MB / 0.5919 GB
Notification: Performance of Templates: time 0.3922/1.06, allocations: 52.67 MB / 0.9012 GB, free: 149.2 MB / 0.6075 GB
"
[Timeout remaining time 659]
make -j1 -f OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRIII.makefile [Timeout 660]
(rm -f OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRIII.pipe ; mkfifo OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRIII.pipe ; head -c 1048576 < OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRIII.pipe >> ../files/OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRIII.sim & ./OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRIII  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRIII.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 356.6767692749854]
Failed to read output from testmodel.py, exit status != 0:
1.0686989750247449 1.078583232 1.016118486
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fd888186fb0>