Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Tests.Banks.PSSE.CSVGN1.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.00144/0.00144, 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.51/1.51, 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.001772/0.001772, allocations: 211.3 kB / 310.1 MB, free: 3.027 MB / 238.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL master/package.mo): time 0.651/0.651, allocations: 107.2 MB / 473.7 MB, free: 29.75 MB / 350.1 MB
"
[Timeout remaining time 179]
Using package OpenIPSL with version 3.1.0-dev (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL master/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.Tests.Banks.PSSE.CSVGN1,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Tests.Banks.PSSE.CSVGN1")
translateModel(OpenIPSL.Tests.Banks.PSSE.CSVGN1,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Tests.Banks.PSSE.CSVGN1") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.18e-05/2.18e-05, allocations: 5.094 kB / 0.6343 GB, free: 29.39 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Banks.PSSE.CSVGN1): time 0.008106/0.008128, allocations: 7.586 MB / 0.6417 GB, free: 24.26 MB / 446.1 MB
Notification: Performance of NFInst.instExpressions: time 0.007364/0.01549, allocations: 2.628 MB / 0.6443 GB, free: 22.43 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002013/0.01751, allocations: 15.94 kB / 0.6443 GB, free: 22.43 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001448/0.01895, allocations: 343.3 kB / 0.6446 GB, free: 22.25 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.003985/0.02294, allocations: 1.102 MB / 0.6457 GB, free: 21.54 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.002571/0.02551, allocations: 0.8944 MB / 0.6466 GB, free: 21.07 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.004762/0.03027, allocations: 2.926 MB / 0.6494 GB, free: 19.65 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0008545/0.03112, allocations: 285.4 kB / 0.6497 GB, free: 19.48 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002253/0.03338, allocations: 1.075 MB / 0.6508 GB, free: 18.96 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001928/0.03531, allocations: 1.126 MB / 0.6519 GB, free: 18.3 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0004489/0.03575, allocations: 182.5 kB / 0.652 GB, free: 18.3 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001114/0.03687, allocations: 373.8 kB / 0.6524 GB, free: 18.22 MB / 446.1 MB
Notification: Performance of combineBinaries: time 0.003203/0.04007, allocations: 4.618 MB / 0.6569 GB, free: 14.69 MB / 446.1 MB
Notification: Performance of replaceArrayConstructors: time 0.00212/0.04219, allocations: 2.653 MB / 0.6595 GB, free: 12.78 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0008707/0.04306, allocations: 118.6 kB / 0.6596 GB, free: 12.78 MB / 446.1 MB
Notification: Performance of FrontEnd: time 0.0005645/0.04363, allocations: 51.12 kB / 0.6596 GB, free: 12.77 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: 262 (246)
 * Number of variables: 262 (262)
Notification: Performance of [SIM] Bindings: time 0.008124/0.05175, allocations: 9.667 MB / 0.6691 GB, free: 3.305 MB / 446.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.00123/0.05298, allocations: 1.142 MB / 0.6702 GB, free: 2.086 MB / 446.1 MB
Notification: Performance of [SIM] Early Inline: time 0.009191/0.06217, allocations: 8.539 MB / 0.6785 GB, free: 9.48 MB / 462.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.002473/0.06464, allocations: 1.141 MB / 0.6797 GB, free: 8.234 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.01044/0.07508, allocations: 6.456 MB / 0.686 GB, free: 1.168 MB / 462.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.001782/0.07686, allocations: 1.006 MB / 0.6869 GB, free: 80 kB / 462.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.000649/0.07751, allocations: 0.492 MB / 0.6874 GB, free: 15.55 MB / 478.1 MB
Notification: Performance of [SIM] Detect States: time 0.00152/0.07903, allocations: 1.301 MB / 0.6887 GB, free: 14.18 MB / 478.1 MB
Notification: Performance of [SIM] Events: time 0.001011/0.08004, allocations: 0.5779 MB / 0.6893 GB, free: 13.6 MB / 478.1 MB
Notification: Performance of [SIM] Partitioning: time 0.002664/0.08271, allocations: 2.131 MB / 0.6913 GB, free: 11.43 MB / 478.1 MB
Notification: Performance of [SIM] Causalize: time 0.006429/0.08913, allocations: 3.928 MB / 0.6952 GB, free: 7.434 MB / 478.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.00514/0.09427, allocations: 4.398 MB / 0.6995 GB, free: 3.031 MB / 478.1 MB
Notification: Performance of [INI] Simplify: time 0.009109/0.1034, allocations: 4.268 MB / 0.7036 GB, free: 14.55 MB / 494.1 MB
Notification: Performance of [INI] Inline: time 0.01319/0.1166, allocations: 10.04 MB / 0.7134 GB, free: 4.492 MB / 494.1 MB
Notification: Performance of [INI] Partitioning: time 0.0005864/0.1172, allocations: 128.8 kB / 0.7136 GB, free: 4.336 MB / 494.1 MB
Notification: Performance of [INI] Cleanup: time 0.003197/0.1204, allocations: 1.922 MB / 0.7154 GB, free: 2.402 MB / 494.1 MB
Notification: Performance of [INI] Causalize: time 0.03064/0.151, allocations: 16.33 MB / 0.7314 GB, free: 1.785 MB / 0.4981 GB
Notification: Performance of [INI] Tearing: time 0.0072/0.1582, allocations: 5.025 MB / 0.7363 GB, free: 12.73 MB / 0.5137 GB
Notification: Performance of [SIM] Initialization: time 2.966e-06/0.1582, allocations: 0 / 0.7363 GB, free: 12.73 MB / 0.5137 GB
Notification: Performance of [SIM] DAE-Mode: time 0.003473/0.1617, allocations: 2.398 MB / 0.7386 GB, free: 10.31 MB / 0.5137 GB
Notification: Performance of [SIM] Remove Dummies: time 1.241e-05/0.1617, allocations: 1 kB / 0.7386 GB, free: 10.31 MB / 0.5137 GB
Notification: Performance of [SIM] Tearing: time 0.002319/0.164, allocations: 1.502 MB / 0.7401 GB, free: 8.809 MB / 0.5137 GB
Notification: Performance of [SIM] Categorize: time 2.349e-05/0.164, allocations: 11.94 kB / 0.7401 GB, free: 8.797 MB / 0.5137 GB
Notification: Performance of [SIM] Solve: time 0.01394/0.178, allocations: 8.368 MB / 0.7483 GB, free: 424 kB / 0.5137 GB
Notification: Performance of [SIM] Jacobian: time 0.02877/0.2067, allocations: 18.87 MB / 0.7667 GB, free: 13.86 MB / 0.545 GB
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: ............................. 11 (11) {gENCLS.delta, gENCLS.omega, gENCLS.eq, gENSAL.SPEED, gENSAL.ANGLE, cSVGN1.LagLim.state, cSVGN1.leadLag.TF.x_scaled, gENSAL.Epq, gENSAL.PSIkd, gENSAL.PSIppq, cSVGN1.leadLagLim.integrator.y}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 30 (30) {$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: ........... 98 (scalar:97, 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: ........... 289 (scalar:288, 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 (lambda=0)] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 291 (scalar:290, 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: Performance of backend: time 0.0002383/0.207, allocations: 136.6 kB / 0.7669 GB, free: 13.73 MB / 0.545 GB
Notification: Performance of SimCode: time 0.0157/0.2227, allocations: 10.35 MB / 0.777 GB, free: 3.125 MB / 0.545 GB
Notification: Performance of Templates: time 0.3668/0.5894, allocations: 39.02 MB / 0.8151 GB, free: 121.5 MB / 0.5762 GB
"
[Timeout remaining time 659]
make -j1 -f OpenIPSL_dev_OpenIPSL.Tests.Banks.PSSE.CSVGN1.makefile [Timeout 660]
(rm -f OpenIPSL_dev_OpenIPSL.Tests.Banks.PSSE.CSVGN1.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Tests.Banks.PSSE.CSVGN1.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Tests.Banks.PSSE.CSVGN1.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Tests.Banks.PSSE.CSVGN1.sim & ./OpenIPSL_dev_OpenIPSL.Tests.Banks.PSSE.CSVGN1  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_dev_OpenIPSL.Tests.Banks.PSSE.CSVGN1.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 8.111723143025301]
Failed to read output from testmodel.py, exit status != 0:
0.5983166009536944 0.6126405269999999 0.566095865
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f332fa3efb0>