Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Data.PowerPlant.Examples.Anderson.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.001461/0.001461, 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.504/1.504, 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.001728/0.001728, allocations: 214 kB / 310.1 MB, free: 3.062 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.6437/0.6437, allocations: 107.2 MB / 473.7 MB, free: 29.81 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.Data.PowerPlant.Examples.Anderson,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Data.PowerPlant.Examples.Anderson")
translateModel(OpenIPSL.Data.PowerPlant.Examples.Anderson,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Data.PowerPlant.Examples.Anderson") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.109e-05/2.109e-05, allocations: 2.281 kB / 0.6343 GB, free: 29.48 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Data.PowerPlant.Examples.Anderson): time 0.01136/0.01138, allocations: 10.73 MB / 0.6448 GB, free: 22.25 MB / 446.1 MB
Notification: Performance of NFInst.instExpressions: time 0.0138/0.02518, allocations: 9.765 MB / 0.6543 GB, free: 15.45 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00339/0.02857, allocations: 37.66 kB / 0.6544 GB, free: 15.43 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.004568/0.03314, allocations: 1.119 MB / 0.6555 GB, free: 14.89 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.005987/0.03913, allocations: 1.503 MB / 0.6569 GB, free: 13.7 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003933/0.04306, allocations: 1.511 MB / 0.6584 GB, free: 12.5 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.00666/0.04972, allocations: 5.124 MB / 0.6634 GB, free: 9.203 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001465/0.05118, allocations: 425.6 kB / 0.6638 GB, free: 8.91 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002664/0.05385, allocations: 1.769 MB / 0.6656 GB, free: 7.406 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002027/0.05588, allocations: 1.574 MB / 0.6671 GB, free: 5.824 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0005735/0.05645, allocations: 363.9 kB / 0.6674 GB, free: 5.469 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002329/0.05878, allocations: 0.8904 MB / 0.6683 GB, free: 4.578 MB / 446.1 MB
Notification: Performance of combineBinaries: time 0.004646/0.06342, allocations: 6.859 MB / 0.675 GB, free: 13.65 MB / 462.1 MB
Notification: Performance of replaceArrayConstructors: time 0.003953/0.06738, allocations: 3.999 MB / 0.6789 GB, free: 9.602 MB / 462.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0008634/0.06824, allocations: 168 kB / 0.6791 GB, free: 9.438 MB / 462.1 MB
Notification: Performance of FrontEnd: time 0.0006634/0.0689, allocations: 83.56 kB / 0.6791 GB, free: 9.355 MB / 462.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 355 (341)
 * Number of variables: 355 (355)
Notification: Performance of [SIM] Bindings: time 0.01897/0.08788, allocations: 16.59 MB / 0.6953 GB, free: 8.328 MB / 478.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.003201/0.09108, allocations: 1.687 MB / 0.697 GB, free: 6.598 MB / 478.1 MB
Notification: Performance of [SIM] Early Inline: time 0.0136/0.1047, allocations: 10.89 MB / 0.7076 GB, free: 11.64 MB / 494.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.0034/0.1081, allocations: 1.555 MB / 0.7092 GB, free: 9.984 MB / 494.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.01481/0.1229, allocations: 9.254 MB / 0.7182 GB, free: 15.86 MB / 0.4981 GB
Notification: Performance of [SIM] Simplify 2: time 0.002615/0.1255, allocations: 1.307 MB / 0.7195 GB, free: 14.48 MB / 0.4981 GB
Notification: Performance of [SIM] Remove Stream: time 0.0009549/0.1265, allocations: 0.63 MB / 0.7201 GB, free: 13.81 MB / 0.4981 GB
Notification: Performance of [SIM] Detect States: time 0.002094/0.1286, allocations: 1.779 MB / 0.7218 GB, free: 11.96 MB / 0.4981 GB
Notification: Performance of [SIM] Events: time 0.001442/0.13, allocations: 0.8228 MB / 0.7226 GB, free: 11.11 MB / 0.4981 GB
Notification: Performance of [SIM] Partitioning: time 0.0036/0.1336, allocations: 2.682 MB / 0.7252 GB, free: 8.375 MB / 0.4981 GB
Notification: Performance of [SIM] Causalize: time 0.007904/0.1415, allocations: 4.604 MB / 0.7297 GB, free: 3.695 MB / 0.4981 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.006953/0.1485, allocations: 5.769 MB / 0.7354 GB, free: 13.92 MB / 0.5137 GB
Notification: Performance of [INI] Simplify: time 0.01335/0.1618, allocations: 6.643 MB / 0.7419 GB, free: 7.008 MB / 0.5137 GB
Notification: Performance of [INI] Inline: time 0.0178/0.1796, allocations: 13.27 MB / 0.7548 GB, free: 9.703 MB / 0.5294 GB
Notification: Performance of [INI] Partitioning: time 0.0009348/0.1805, allocations: 203 kB / 0.755 GB, free: 9.453 MB / 0.5294 GB
Notification: Performance of [INI] Cleanup: time 0.004878/0.1854, allocations: 2.71 MB / 0.7577 GB, free: 6.715 MB / 0.5294 GB
Notification: Performance of [INI] Causalize: time 0.05104/0.2365, allocations: 25.22 MB / 0.7823 GB, free: 12.93 MB / 0.5606 GB
Notification: Performance of [INI] Tearing: time 0.008932/0.2454, allocations: 5.793 MB / 0.7879 GB, free: 7.059 MB / 0.5606 GB
Notification: Performance of [SIM] Initialization: time 8.005e-06/0.2454, allocations: 4 kB / 0.788 GB, free: 7.055 MB / 0.5606 GB
Notification: Performance of [SIM] DAE-Mode: time 0.004617/0.25, allocations: 2.941 MB / 0.7908 GB, free: 4.121 MB / 0.5606 GB
Notification: Performance of [SIM] Remove Dummies: time 1.724e-05/0.25, allocations: 1.312 kB / 0.7908 GB, free: 4.121 MB / 0.5606 GB
Notification: Performance of [SIM] Tearing: time 0.002321/0.2524, allocations: 1.464 MB / 0.7923 GB, free: 2.66 MB / 0.5606 GB
Notification: Performance of [SIM] Categorize: time 3.129e-05/0.2524, allocations: 11.94 kB / 0.7923 GB, free: 2.648 MB / 0.5606 GB
Notification: Performance of [SIM] Solve: time 0.02207/0.2745, allocations: 12.92 MB / 0.8049 GB, free: 5.832 MB / 0.5762 GB
Notification: Performance of [SIM] Jacobian: time 0.318/0.5924, allocations: 12.96 MB / 0.8175 GB, free: 112.3 MB / 0.5762 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: ......... 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: ............................. 25 (25) {gENCLS.delta, gENCLS.omega, gENCLS.eq, generator1.gENROE.w, generator1.gENROE.ANGLE, generator1.eSDC1A.imDerivativeLag.x, generator1.eSDC1A.imLeadLag.TF.x_scaled, generator1.eSDC1A.simpleLagLim.state, generator1.gENROE.EFD, generator1.eSDC1A.TransducerDelay.state, generator1.iEE2ST.imDerivativeLag.x, generator1.iEE2ST.imSimpleLag.state, generator1.iEE2ST.imSimpleLag1.state, generator1.iEE2ST.imLeadLag.TF.x_scaled, generator1.iEE2ST.imLeadLag1.TF.x_scaled, generator1.iEE2ST.imLeadLag2.TF.x_scaled, generator1.iEESGO.imSimpleLag.state, generator1.iEESGO.imLeadLag.TF.x_scaled, generator1.iEESGO.imSimpleLag1.state, generator1.iEESGO.imSimpleLag2.state, generator1.iEESGO.imSimpleLag3.state, generator1.gENROE.Epd, generator1.gENROE.Epq, generator1.gENROE.PSIkd, generator1.gENROE.PSIkq}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 39 (39) {$SEV_18, $SEV_17, $SEV_16, $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: ........... 159 (scalar:154, array:5, 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: ........... 510 (scalar:505, array:5, record:0)
 * Number of multi strong components: ............ 1 (algorithm:1, when:0, if:0, tuple:1)
 * 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: ........... 510 (scalar:505, array:5, record:0)
 * Number of multi strong components: ............ 1 (algorithm:1, when:0, if:0, tuple:1)
 * 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.0003044/0.5927, allocations: 214.2 kB / 0.8177 GB, free: 112.2 MB / 0.5762 GB
Notification: Performance of SimCode: time 0.0225/0.6152, allocations: 14.93 MB / 0.8323 GB, free: 108.3 MB / 0.5762 GB
Notification: Performance of Templates: time 0.06497/0.6802, allocations: 43.3 MB / 0.8746 GB, free: 87.06 MB / 0.5762 GB
"
[Timeout remaining time 659]
make -j1 -f OpenIPSL_dev_OpenIPSL.Data.PowerPlant.Examples.Anderson.makefile [Timeout 660]
(rm -f OpenIPSL_dev_OpenIPSL.Data.PowerPlant.Examples.Anderson.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Data.PowerPlant.Examples.Anderson.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Data.PowerPlant.Examples.Anderson.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Data.PowerPlant.Examples.Anderson.sim & ./OpenIPSL_dev_OpenIPSL.Data.PowerPlant.Examples.Anderson  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_dev_OpenIPSL.Data.PowerPlant.Examples.Anderson.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 8.066395224013831]
Failed to read output from testmodel.py, exit status != 0:
0.6888997179921716 0.699907975 0.628418515
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f963df32fb0>