Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSSE.ES.EXAC2.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.07859/0.07859, 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.001243/0.001243, allocations: 116.9 kB / 38.07 MB, free: 1.082 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.329/1.329, allocations: 207.7 MB / 251.7 MB, free: 7.23 MB / 190.1 MB
"
[Timeout remaining time 179]
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.00158/0.00158, allocations: 208.4 kB / 307 MB, free: 240 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.3143/0.3143, allocations: 79.73 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.Tests.Controls.PSSE.ES.EXAC2,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSSE.ES.EXAC2")
translateModel(OpenIPSL.Tests.Controls.PSSE.ES.EXAC2,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSSE.ES.EXAC2") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.531e-05/1.531e-05, allocations: 2.281 kB / 0.5887 GB, free: 7.016 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Controls.PSSE.ES.EXAC2): time 0.009736/0.009751, allocations: 8.12 MB / 0.5967 GB, free: 14.84 MB / 414.1 MB
Notification: Performance of NFInst.instExpressions: time 0.005548/0.0153, allocations: 2.862 MB / 0.5995 GB, free: 11.97 MB / 414.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0006293/0.01593, allocations: 19.94 kB / 0.5995 GB, free: 11.95 MB / 414.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0008403/0.01677, allocations: 360.8 kB / 0.5998 GB, free: 11.6 MB / 414.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.002887/0.01966, allocations: 1.301 MB / 0.6011 GB, free: 10.3 MB / 414.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001865/0.02152, allocations: 0.9588 MB / 0.602 GB, free: 9.348 MB / 414.1 MB
Notification: Performance of NFFlatten.flatten: time 0.003388/0.02491, allocations: 3.349 MB / 0.6053 GB, free: 5.992 MB / 414.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0006487/0.02556, allocations: 361.1 kB / 0.6057 GB, free: 5.605 MB / 414.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001375/0.02693, allocations: 1.175 MB / 0.6068 GB, free: 4.426 MB / 414.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001461/0.02839, allocations: 1.295 MB / 0.6081 GB, free: 3.125 MB / 414.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0003124/0.02871, allocations: 212 kB / 0.6083 GB, free: 2.918 MB / 414.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0007638/0.02947, allocations: 455.6 kB / 0.6087 GB, free: 2.473 MB / 414.1 MB
Notification: Performance of combineBinaries: time 0.004123/0.03359, allocations: 5.067 MB / 0.6137 GB, free: 13.36 MB / 430.1 MB
Notification: Performance of replaceArrayConstructors: time 0.002262/0.03586, allocations: 2.888 MB / 0.6165 GB, free: 10.43 MB / 430.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.000299/0.03615, allocations: 124 kB / 0.6166 GB, free: 10.31 MB / 430.1 MB
Notification: Performance of FrontEnd: time 0.0002849/0.03644, allocations: 63.69 kB / 0.6167 GB, free: 10.25 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: 295 (281)
 * Number of variables: 295 (295)
Notification: Performance of [SIM] Bindings: time 0.009817/0.04626, allocations: 10.59 MB / 0.627 GB, free: 15.31 MB / 446.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.001425/0.04768, allocations: 1.231 MB / 0.6282 GB, free: 14.05 MB / 446.1 MB
Notification: Performance of [SIM] Early Inline: time 0.009192/0.05687, allocations: 9.177 MB / 0.6372 GB, free: 4.801 MB / 446.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.0019/0.05877, allocations: 1.219 MB / 0.6383 GB, free: 3.473 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.1944/0.2532, allocations: 7.338 MB / 0.6455 GB, free: 37.81 MB / 446.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.001456/0.2547, allocations: 1.069 MB / 0.6466 GB, free: 37.59 MB / 446.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0004838/0.2551, allocations: 0.5109 MB / 0.6471 GB, free: 37.5 MB / 446.1 MB
Notification: Performance of [SIM] Detect States: time 0.001001/0.2561, allocations: 1.417 MB / 0.6484 GB, free: 37.32 MB / 446.1 MB
Notification: Performance of [SIM] Events: time 0.0006986/0.2568, allocations: 0.6567 MB / 0.6491 GB, free: 37.29 MB / 446.1 MB
Notification: Performance of [SIM] Partitioning: time 0.001953/0.2588, allocations: 2.271 MB / 0.6513 GB, free: 37.2 MB / 446.1 MB
Notification: Performance of [SIM] Causalize: time 0.005421/0.2642, allocations: 4.093 MB / 0.6553 GB, free: 36.86 MB / 446.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.003593/0.2678, allocations: 4.762 MB / 0.6599 GB, free: 34.46 MB / 446.1 MB
Notification: Performance of [INI] Simplify: time 0.0061/0.2739, allocations: 4.62 MB / 0.6645 GB, free: 32.65 MB / 446.1 MB
Notification: Performance of [INI] Inline: time 0.007326/0.2812, allocations: 10.91 MB / 0.6751 GB, free: 25.5 MB / 446.1 MB
Notification: Performance of [INI] Partitioning: time 0.0003312/0.2816, allocations: 129 kB / 0.6752 GB, free: 25.43 MB / 446.1 MB
Notification: Performance of [INI] Cleanup: time 0.001382/0.2829, allocations: 2.059 MB / 0.6773 GB, free: 23.71 MB / 446.1 MB
Notification: Performance of [INI] Causalize: time 0.01945/0.3024, allocations: 17.48 MB / 0.6943 GB, free: 14.64 MB / 446.1 MB
Notification: Performance of [INI] Tearing: time 0.005095/0.3075, allocations: 6.315 MB / 0.7005 GB, free: 8.348 MB / 446.1 MB
Notification: Performance of [SIM] Initialization: time 1.723e-06/0.3075, allocations: 0 / 0.7005 GB, free: 8.348 MB / 446.1 MB
Notification: Performance of [SIM] DAE-Mode: time 0.002182/0.3097, allocations: 2.499 MB / 0.7029 GB, free: 5.879 MB / 446.1 MB
Notification: Performance of [SIM] Remove Dummies: time 7.895e-06/0.3097, allocations: 1 kB / 0.7029 GB, free: 5.875 MB / 446.1 MB
Notification: Performance of [SIM] Tearing: time 0.001095/0.3108, allocations: 1.395 MB / 0.7043 GB, free: 4.496 MB / 446.1 MB
Notification: Performance of [SIM] Categorize: time 1.555e-05/0.3108, allocations: 8 kB / 0.7043 GB, free: 4.488 MB / 446.1 MB
Notification: Performance of [SIM] Solve: time 0.01015/0.3209, allocations: 9.432 MB / 0.7135 GB, free: 11.11 MB / 462.1 MB
Notification: Performance of [SIM] Jacobian: time 0.01509/0.336, allocations: 12.92 MB / 0.7261 GB, free: 14.57 MB / 478.1 MB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 1
 * Number of algebraic partitions: ............... 0
 * Number of ODE event partitions: ............... 1
 * Number of algebraic event partitions: ......... 1
 * 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) {gENCLS.delta, gENCLS.omega, gENCLS.eq, gENROE.SPEED, gENROE.ANGLE, eXAC2.imLimitedSimpleLag.state, eXAC2.imDerivativeLag.x, eXAC2.leadLag.TF.x_scaled, eXAC2.rectifierCommutationVoltageDrop.V_EX, eXAC2.TransducerDelay.state, gENROE.Epd, gENROE.Epq, gENROE.PSIkd, gENROE.PSIkq}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 36 (36) {$SEV_15, $SEV_14, $SEV_13, $SEV_12, $SEV_11, $SEV_10, $SEV_9, $SEV_8, $SEV_7, $SEV_6, $SEV_5, $SEV_4, $SEV_3, $SEV_2, $SEV_1, $SEV_0, $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: ........... 127 (scalar:126, array:1, 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: ........... 329 (scalar:328, array:1, 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: ........... 329 (scalar:328, array:1, 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.0001664/0.3362, allocations: 135.7 kB / 0.7263 GB, free: 14.44 MB / 478.1 MB
Notification: Performance of SimCode: time 0.0105/0.3467, allocations: 9.621 MB / 0.7356 GB, free: 4.652 MB / 478.1 MB
Notification: Performance of Templates: time 0.05069/0.3974, allocations: 33.41 MB / 0.7683 GB, free: 3.316 MB / 0.4981 GB
"
[Timeout remaining time 660]
make -j1 -f OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSSE.ES.EXAC2.makefile [Timeout 660]
(rm -f OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSSE.ES.EXAC2.pipe ; mkfifo OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSSE.ES.EXAC2.pipe ; head -c 1048576 < OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSSE.ES.EXAC2.pipe >> ../files/OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSSE.ES.EXAC2.sim & ./OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSSE.ES.EXAC2  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_2.0.0_OpenIPSL.Tests.Controls.PSSE.ES.EXAC2.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 6.020799455000088]
Failed to read output from testmodel.py, exit status != 0:
0.40471768501447514 0.417910682 0.37864847700000004
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f623db32fb0>