Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order3test2.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.001421/0.001421, 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.519/1.519, allocations: 230.6 MB / 253.5 MB, free: 14.27 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.001597/0.001597, allocations: 215.8 kB / 310.1 MB, free: 3.074 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.6691/0.6691, allocations: 107.2 MB / 473.7 MB, free: 29.98 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.Machines.PSAT.Order3test2,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order3test2")
translateModel(OpenIPSL.Tests.Machines.PSAT.Order3test2,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order3test2") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.122e-05/2.122e-05, allocations: 2.281 kB / 0.6343 GB, free: 29.48 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Machines.PSAT.Order3test2): time 0.005759/0.00578, allocations: 5.09 MB / 0.6393 GB, free: 26.03 MB / 446.1 MB
Notification: Performance of NFInst.instExpressions: time 0.004757/0.01054, allocations: 1.716 MB / 0.641 GB, free: 24.84 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001538/0.01208, allocations: 17.38 kB / 0.641 GB, free: 24.84 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0007491/0.01282, allocations: 206.5 kB / 0.6412 GB, free: 24.73 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.002479/0.0153, allocations: 0.715 MB / 0.6419 GB, free: 24.26 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001419/0.01672, allocations: 0.5547 MB / 0.6424 GB, free: 23.97 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.002009/0.01873, allocations: 1.506 MB / 0.6439 GB, free: 23.23 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0004466/0.01918, allocations: 159.4 kB / 0.644 GB, free: 23.14 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0008876/0.02007, allocations: 0.5871 MB / 0.6446 GB, free: 22.86 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0007278/0.02079, allocations: 0.5961 MB / 0.6452 GB, free: 22.58 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001575/0.02095, allocations: 75.56 kB / 0.6453 GB, free: 22.58 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0006671/0.02162, allocations: 240.1 kB / 0.6455 GB, free: 22.54 MB / 446.1 MB
Notification: Performance of combineBinaries: time 0.001694/0.02331, allocations: 2.504 MB / 0.6479 GB, free: 20.84 MB / 446.1 MB
Notification: Performance of replaceArrayConstructors: time 0.0008904/0.0242, allocations: 1.6 MB / 0.6495 GB, free: 19.71 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0002211/0.02442, allocations: 74.69 kB / 0.6496 GB, free: 19.71 MB / 446.1 MB
Notification: Performance of FrontEnd: time 0.0002025/0.02463, allocations: 23.28 kB / 0.6496 GB, free: 19.71 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: 167 (155)
 * Number of variables: 167 (167)
Notification: Performance of [SIM] Bindings: time 0.004982/0.02961, allocations: 5.685 MB / 0.6552 GB, free: 15.85 MB / 446.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.0008958/0.0305, allocations: 0.6785 MB / 0.6558 GB, free: 15.44 MB / 446.1 MB
Notification: Performance of [SIM] Early Inline: time 0.005705/0.03621, allocations: 6.679 MB / 0.6623 GB, free: 10.45 MB / 446.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.001158/0.03737, allocations: 0.8124 MB / 0.6631 GB, free: 9.57 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.003951/0.04132, allocations: 3.977 MB / 0.667 GB, free: 5.273 MB / 446.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.0007697/0.04209, allocations: 0.744 MB / 0.6677 GB, free: 4.48 MB / 446.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0002468/0.04233, allocations: 359.8 kB / 0.6681 GB, free: 4.102 MB / 446.1 MB
Notification: Performance of [SIM] Detect States: time 0.0005717/0.04291, allocations: 0.8772 MB / 0.6689 GB, free: 3.168 MB / 446.1 MB
Notification: Performance of [SIM] Events: time 0.0004685/0.04337, allocations: 487.9 kB / 0.6694 GB, free: 2.672 MB / 446.1 MB
Notification: Performance of [SIM] Partitioning: time 0.001204/0.04458, allocations: 1.519 MB / 0.6709 GB, free: 1.129 MB / 446.1 MB
Notification: Performance of [SIM] Causalize: time 0.004005/0.04858, allocations: 2.807 MB / 0.6736 GB, free: 14.29 MB / 462.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.003563/0.05215, allocations: 3.087 MB / 0.6766 GB, free: 11.21 MB / 462.1 MB
Notification: Performance of [INI] Simplify: time 0.004377/0.05652, allocations: 2.119 MB / 0.6787 GB, free: 8.969 MB / 462.1 MB
Notification: Performance of [INI] Inline: time 0.007499/0.06402, allocations: 6.209 MB / 0.6848 GB, free: 2.742 MB / 462.1 MB
Notification: Performance of [INI] Partitioning: time 0.000344/0.06437, allocations: 68.06 kB / 0.6848 GB, free: 2.664 MB / 462.1 MB
Notification: Performance of [INI] Cleanup: time 0.0012/0.06557, allocations: 0.8545 MB / 0.6857 GB, free: 1.809 MB / 462.1 MB
Notification: Performance of [INI] Causalize: time 0.01388/0.07944, allocations: 7.803 MB / 0.6933 GB, free: 9.887 MB / 478.1 MB
Notification: Performance of [INI] Tearing: time 0.002167/0.08161, allocations: 1.574 MB / 0.6948 GB, free: 8.367 MB / 478.1 MB
Notification: Performance of [SIM] Initialization: time 1.843e-06/0.08161, allocations: 0 / 0.6948 GB, free: 8.367 MB / 478.1 MB
Notification: Performance of [SIM] DAE-Mode: time 0.002966/0.08458, allocations: 1.888 MB / 0.6967 GB, free: 6.512 MB / 478.1 MB
Notification: Performance of [SIM] Remove Dummies: time 1.384e-05/0.08459, allocations: 12.66 kB / 0.6967 GB, free: 6.5 MB / 478.1 MB
Notification: Performance of [SIM] Tearing: time 0.001248/0.08584, allocations: 0.9449 MB / 0.6976 GB, free: 5.574 MB / 478.1 MB
Notification: Performance of [SIM] Categorize: time 2.178e-05/0.08586, allocations: 4 kB / 0.6976 GB, free: 5.57 MB / 478.1 MB
Notification: Performance of [SIM] Solve: time 0.008404/0.09427, allocations: 5.348 MB / 0.7028 GB, free: 236 kB / 478.1 MB
Notification: Performance of [SIM] Jacobian: time 0.01516/0.1094, allocations: 10 MB / 0.7126 GB, free: 6.348 MB / 494.1 MB
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: ......... 0
 * Number of clocked partitions: ................. 0
 * Number of initial partitions: ................. 1
 * Number of initial(lambda=0) partitions: ....... 0
Notification: Variable statistics after passing the back-end:
 * Number of states: ............................. 3 (3) {order31.delta, order31.w, order31.e1q}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 21 (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: ........... 68 (scalar:68, 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: ........... 150 (scalar:150, 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: Performance of backend: time 0.0001483/0.1096, allocations: 58.42 kB / 0.7127 GB, free: 6.293 MB / 494.1 MB
Notification: Performance of SimCode: time 0.008438/0.118, allocations: 5.328 MB / 0.7179 GB, free: 0.8359 MB / 494.1 MB
Notification: Performance of Templates: time 0.03291/0.1509, allocations: 18.45 MB / 0.7359 GB, free: 14.43 MB / 0.5137 GB
"
[Timeout remaining time 660]
make -j1 -f OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order3test2.makefile [Timeout 660]
(rm -f OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order3test2.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order3test2.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order3test2.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order3test2.sim & ./OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order3test2  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order3test2.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 7.8524664209689945]
Failed to read output from testmodel.py, exit status != 0:
0.15970377495978028 0.175308235 0.14730921
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f40a27f2fb0>