Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR.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.001473/0.001473, 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.24 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.001885/0.001885, allocations: 210.4 kB / 310.1 MB, free: 3.078 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.6694/0.6694, allocations: 107.2 MB / 473.7 MB, free: 29.9 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.Order4test2_AVR,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR")
translateModel(OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.642e-05/2.642e-05, allocations: 2.281 kB / 0.6343 GB, free: 29.43 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR): time 0.007238/0.007264, allocations: 5.453 MB / 0.6396 GB, free: 25.73 MB / 446.1 MB
Notification: Performance of NFInst.instExpressions: time 0.005119/0.01238, allocations: 1.854 MB / 0.6415 GB, free: 24.46 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001602/0.01399, allocations: 10.22 kB / 0.6415 GB, free: 24.46 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001095/0.01508, allocations: 209.6 kB / 0.6417 GB, free: 24.35 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.003061/0.01814, allocations: 0.7431 MB / 0.6424 GB, free: 23.86 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001904/0.02004, allocations: 0.63 MB / 0.643 GB, free: 23.52 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.002857/0.0229, allocations: 1.664 MB / 0.6446 GB, free: 22.7 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0005484/0.02345, allocations: 170.2 kB / 0.6448 GB, free: 22.61 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001263/0.02471, allocations: 0.6521 MB / 0.6454 GB, free: 22.29 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001124/0.02584, allocations: 0.6277 MB / 0.646 GB, free: 22.01 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0002346/0.02607, allocations: 84.72 kB / 0.6461 GB, free: 22.01 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0008407/0.02691, allocations: 243.8 kB / 0.6464 GB, free: 21.96 MB / 446.1 MB
Notification: Performance of combineBinaries: time 0.002396/0.02931, allocations: 2.658 MB / 0.6489 GB, free: 20.17 MB / 446.1 MB
Notification: Performance of replaceArrayConstructors: time 0.00137/0.03068, allocations: 1.69 MB / 0.6506 GB, free: 18.97 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0003506/0.03103, allocations: 81.06 kB / 0.6507 GB, free: 18.97 MB / 446.1 MB
Notification: Performance of FrontEnd: time 0.0002888/0.03132, allocations: 28.62 kB / 0.6507 GB, free: 18.96 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: 179 (167)
 * Number of variables: 179 (179)
Notification: Performance of [SIM] Bindings: time 0.005751/0.03707, allocations: 6.011 MB / 0.6566 GB, free: 14.91 MB / 446.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.00125/0.03832, allocations: 0.739 MB / 0.6573 GB, free: 14.45 MB / 446.1 MB
Notification: Performance of [SIM] Early Inline: time 0.006904/0.04522, allocations: 6.929 MB / 0.6641 GB, free: 8.664 MB / 446.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.001408/0.04663, allocations: 0.9019 MB / 0.6649 GB, free: 7.707 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.004826/0.05146, allocations: 4.306 MB / 0.6691 GB, free: 3.027 MB / 446.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.0009622/0.05242, allocations: 0.8277 MB / 0.67 GB, free: 2.148 MB / 446.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0002796/0.0527, allocations: 375.9 kB / 0.6703 GB, free: 1.75 MB / 446.1 MB
Notification: Performance of [SIM] Detect States: time 0.0007261/0.05342, allocations: 0.9856 MB / 0.6713 GB, free: 0.6953 MB / 446.1 MB
Notification: Performance of [SIM] Events: time 0.0005303/0.05395, allocations: 474.7 kB / 0.6717 GB, free: 232 kB / 446.1 MB
Notification: Performance of [SIM] Partitioning: time 0.001933/0.05589, allocations: 1.597 MB / 0.6733 GB, free: 14.6 MB / 462.1 MB
Notification: Performance of [SIM] Causalize: time 0.005839/0.06173, allocations: 2.928 MB / 0.6761 GB, free: 11.63 MB / 462.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.004297/0.06602, allocations: 3.282 MB / 0.6794 GB, free: 8.344 MB / 462.1 MB
Notification: Performance of [INI] Simplify: time 0.005232/0.07126, allocations: 2.243 MB / 0.6815 GB, free: 6 MB / 462.1 MB
Notification: Performance of [INI] Inline: time 0.008848/0.0801, allocations: 6.504 MB / 0.6879 GB, free: 15.48 MB / 478.1 MB
Notification: Performance of [INI] Partitioning: time 0.0004014/0.0805, allocations: 72.06 kB / 0.688 GB, free: 15.4 MB / 478.1 MB
Notification: Performance of [INI] Cleanup: time 0.002038/0.08254, allocations: 1.28 MB / 0.6892 GB, free: 14.11 MB / 478.1 MB
Notification: Performance of [INI] Causalize: time 0.01891/0.1015, allocations: 10.07 MB / 0.699 GB, free: 3.891 MB / 478.1 MB
Notification: Performance of [INI] Tearing: time 0.004336/0.1058, allocations: 3.155 MB / 0.7021 GB, free: 0.8398 MB / 478.1 MB
Notification: Performance of [SIM] Initialization: time 4.869e-06/0.1058, allocations: 3.938 kB / 0.7021 GB, free: 0.8359 MB / 478.1 MB
Notification: Performance of [SIM] DAE-Mode: time 0.003077/0.1089, allocations: 2.012 MB / 0.7041 GB, free: 14.87 MB / 494.1 MB
Notification: Performance of [SIM] Remove Dummies: time 1.204e-05/0.1089, allocations: 0.6562 kB / 0.7041 GB, free: 14.87 MB / 494.1 MB
Notification: Performance of [SIM] Tearing: time 0.001276/0.1102, allocations: 0.961 MB / 0.705 GB, free: 13.93 MB / 494.1 MB
Notification: Performance of [SIM] Categorize: time 2.279e-05/0.1102, allocations: 4 kB / 0.705 GB, free: 13.92 MB / 494.1 MB
Notification: Performance of [SIM] Solve: time 0.009249/0.1194, allocations: 6.086 MB / 0.711 GB, free: 7.855 MB / 494.1 MB
Notification: Performance of [SIM] Jacobian: time 0.02209/0.1415, allocations: 14.15 MB / 0.7248 GB, free: 9.922 MB / 0.4981 GB
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: ......... 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: ............................. 7 (7) {AVRtypeIII1.vm, AVRtypeIII1.vr, AVRtypeIII1.vf1, order4_Inputs_Outputs.e1d, order4_Inputs_Outputs.delta, order4_Inputs_Outputs.w, order4_Inputs_Outputs.e1q}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 24 (24) {$SEV_2, $SEV_1, $SEV_0, $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: ........... 76 (scalar:76, 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: ........... 165 (scalar:165, 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 (lambda=0)] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 165 (scalar:165, 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.0002059/0.1417, allocations: 98.89 kB / 0.7249 GB, free: 9.832 MB / 0.4981 GB
Notification: Performance of SimCode: time 0.01111/0.1528, allocations: 6.634 MB / 0.7314 GB, free: 3.059 MB / 0.4981 GB
Notification: Performance of Templates: time 0.04405/0.1969, allocations: 25.04 MB / 0.7558 GB, free: 10.07 MB / 0.5294 GB
"
[Timeout remaining time 660]
make -j1 -f OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR.makefile [Timeout 660]
(rm -f OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR.sim & ./OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order4test2_AVR.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 6.887677059974521]
Failed to read output from testmodel.py, exit status != 0:
0.20749059598892927 0.22358361200000001 0.188850439
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f19a84c6fb0>