Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_OpenIPSL.Tests.Machines.PSAT.Order2test2.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.00143/0.00143, 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.512/1.512, 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.001628/0.001628, allocations: 215.8 kB / 310.1 MB, free: 3.062 MB / 238.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/package.mo): time 0.5024/0.5024, allocations: 79.75 MB / 446.3 MB, free: 40.43 MB / 350.1 MB
"
[Timeout remaining time 179]
Using package OpenIPSL with version 3.0.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/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.Order2test2,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Tests.Machines.PSAT.Order2test2")
translateModel(OpenIPSL.Tests.Machines.PSAT.Order2test2,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Tests.Machines.PSAT.Order2test2") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.676e-05/1.676e-05, allocations: 6.219 kB / 0.5941 GB, free: 38.5 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Machines.PSAT.Order2test2): time 0.005199/0.005216, allocations: 5.029 MB / 0.599 GB, free: 36.05 MB / 446.1 MB
Notification: Performance of NFInst.instExpressions: time 0.002829/0.008045, allocations: 1.685 MB / 0.6007 GB, free: 34.91 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0003137/0.008359, allocations: 12.75 kB / 0.6007 GB, free: 34.91 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0004267/0.008786, allocations: 201.5 kB / 0.6009 GB, free: 34.8 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.001439/0.01022, allocations: 0.725 MB / 0.6016 GB, free: 34.33 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001034/0.01126, allocations: 0.5507 MB / 0.6021 GB, free: 34.06 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.001217/0.01248, allocations: 1.479 MB / 0.6036 GB, free: 33.34 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0003706/0.01285, allocations: 171 kB / 0.6037 GB, free: 33.25 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0006049/0.01345, allocations: 0.5779 MB / 0.6043 GB, free: 32.96 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0005342/0.01399, allocations: 0.5852 MB / 0.6049 GB, free: 32.7 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001168/0.0141, allocations: 73.06 kB / 0.605 GB, free: 32.7 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0004323/0.01453, allocations: 237.9 kB / 0.6052 GB, free: 32.65 MB / 446.1 MB
Notification: Performance of combineBinaries: time 0.00137/0.0159, allocations: 2.467 MB / 0.6076 GB, free: 30.98 MB / 446.1 MB
Notification: Performance of replaceArrayConstructors: time 0.0006015/0.01651, allocations: 1.583 MB / 0.6091 GB, free: 29.86 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0001177/0.01662, allocations: 70.28 kB / 0.6092 GB, free: 29.86 MB / 446.1 MB
Notification: Performance of FrontEnd: time 0.0001045/0.01673, allocations: 33.47 kB / 0.6092 GB, free: 29.86 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: 166 (154)
 * Number of variables: 166 (166)
Notification: Performance of [SIM] Bindings: time 0.003555/0.02028, allocations: 5.621 MB / 0.6147 GB, free: 26.05 MB / 446.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.000636/0.02092, allocations: 0.6555 MB / 0.6154 GB, free: 25.66 MB / 446.1 MB
Notification: Performance of [SIM] Early Inline: time 0.004679/0.0256, allocations: 6.644 MB / 0.6219 GB, free: 22.27 MB / 446.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.0009199/0.02652, allocations: 0.8105 MB / 0.6226 GB, free: 21.96 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.003762/0.03028, allocations: 3.956 MB / 0.6265 GB, free: 19.58 MB / 446.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.0007624/0.03104, allocations: 0.7367 MB / 0.6272 GB, free: 19.31 MB / 446.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0002416/0.03128, allocations: 339.2 kB / 0.6275 GB, free: 19.1 MB / 446.1 MB
Notification: Performance of [SIM] Detect States: time 0.0005646/0.03185, allocations: 0.8718 MB / 0.6284 GB, free: 18.52 MB / 446.1 MB
Notification: Performance of [SIM] Events: time 0.0004674/0.03232, allocations: 487.8 kB / 0.6289 GB, free: 18.25 MB / 446.1 MB
Notification: Performance of [SIM] Partitioning: time 0.001132/0.03345, allocations: 1.503 MB / 0.6303 GB, free: 17.6 MB / 446.1 MB
Notification: Performance of [SIM] Causalize: time 0.003103/0.03655, allocations: 2.806 MB / 0.6331 GB, free: 16.86 MB / 446.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.001838/0.03839, allocations: 3.061 MB / 0.6361 GB, free: 15.19 MB / 446.1 MB
Notification: Performance of [INI] Simplify: time 0.002816/0.04121, allocations: 2.079 MB / 0.6381 GB, free: 14.21 MB / 446.1 MB
Notification: Performance of [INI] Inline: time 0.004285/0.04549, allocations: 6.153 MB / 0.6441 GB, free: 10.86 MB / 446.1 MB
Notification: Performance of [INI] Partitioning: time 0.0001752/0.04567, allocations: 68.33 kB / 0.6442 GB, free: 10.82 MB / 446.1 MB
Notification: Performance of [INI] Cleanup: time 0.0008637/0.04653, allocations: 0.8417 MB / 0.645 GB, free: 10.35 MB / 446.1 MB
Notification: Performance of [INI] Causalize: time 0.01084/0.05737, allocations: 7.74 MB / 0.6525 GB, free: 8.375 MB / 446.1 MB
Notification: Performance of [INI] Tearing: time 0.00164/0.05901, allocations: 1.56 MB / 0.6541 GB, free: 7.848 MB / 446.1 MB
Notification: Performance of [SIM] Initialization: time 1.562e-06/0.05901, allocations: 0 / 0.6541 GB, free: 7.848 MB / 446.1 MB
Notification: Performance of [SIM] DAE-Mode: time 0.002252/0.06126, allocations: 1.865 MB / 0.6559 GB, free: 7.211 MB / 446.1 MB
Notification: Performance of [SIM] Remove Dummies: time 7.403e-06/0.06127, allocations: 8.594 kB / 0.6559 GB, free: 7.203 MB / 446.1 MB
Notification: Performance of [SIM] Tearing: time 0.0009544/0.06222, allocations: 0.9484 MB / 0.6568 GB, free: 6.859 MB / 446.1 MB
Notification: Performance of [SIM] Categorize: time 1.888e-05/0.06224, allocations: 5.875 kB / 0.6568 GB, free: 6.859 MB / 446.1 MB
Notification: Performance of [SIM] Solve: time 0.005578/0.06782, allocations: 5.19 MB / 0.6619 GB, free: 4.18 MB / 446.1 MB
Notification: Performance of [SIM] Jacobian: time 0.0113/0.07912, allocations: 9.91 MB / 0.6716 GB, free: 10.4 MB / 462.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: ............................. 2 (2) {order21.delta, order21.w}
 * 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: ........... 67 (scalar:67, 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: ........... 149 (scalar:149, 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 9.628e-05/0.07922, allocations: 47.08 kB / 0.6716 GB, free: 10.36 MB / 462.1 MB
Notification: Performance of SimCode: time 0.006674/0.08589, allocations: 5.218 MB / 0.6767 GB, free: 5.027 MB / 462.1 MB
Notification: Performance of Templates: time 0.03024/0.1161, allocations: 18.22 MB / 0.6945 GB, free: 2.844 MB / 478.1 MB
"
[Timeout remaining time 660]
make -j1 -f OpenIPSL_OpenIPSL.Tests.Machines.PSAT.Order2test2.makefile [Timeout 660]
(rm -f OpenIPSL_OpenIPSL.Tests.Machines.PSAT.Order2test2.pipe ; mkfifo OpenIPSL_OpenIPSL.Tests.Machines.PSAT.Order2test2.pipe ; head -c 1048576 < OpenIPSL_OpenIPSL.Tests.Machines.PSAT.Order2test2.pipe >> ../files/OpenIPSL_OpenIPSL.Tests.Machines.PSAT.Order2test2.sim & ./OpenIPSL_OpenIPSL.Tests.Machines.PSAT.Order2test2  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_OpenIPSL.Tests.Machines.PSAT.Order2test2.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 6.0036572180106305]
Failed to read output from testmodel.py, exit status != 0:
0.12368752801558003 0.139174415 0.11941655500000001
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f56a11c2fb0>