Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_2.0.0_OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/package.mo): time 0.08351/0.08351, allocations: 12.37 MB / 32.03 MB, free: 6.406 MB / 26.18 MB
"
[Timeout remaining time 180]
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.001256/0.001256, allocations: 113 kB / 38.07 MB, free: 1.082 MB / 26.18 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.388/1.388, allocations: 207.7 MB / 251.7 MB, free: 7.23 MB / 190.1 MB
"
[Timeout remaining time 179]
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.001539/0.001539, allocations: 228.2 kB / 307 MB, free: 252 kB / 238.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/package.mo): time 0.317/0.317, allocations: 79.73 MB / 441.8 MB, free: 12.24 MB / 318.1 MB
"
[Timeout remaining time 179]
Using package OpenIPSL with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/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 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+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)
Using package Modelica_Synchronous with version 0.93.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0/package.mo)
Running command: translateModel(OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation")
translateModel(OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.338e-05/1.338e-05, allocations: 2.281 kB / 0.5887 GB, free: 7.039 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation): time 0.009484/0.009497, allocations: 5.699 MB / 0.5943 GB, free: 1.301 MB / 398.1 MB
Notification: Performance of NFInst.instExpressions: time 0.004894/0.01439, allocations: 1.76 MB / 0.596 GB, free: 15.54 MB / 414.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0005106/0.0149, allocations: 15.94 kB / 0.596 GB, free: 15.52 MB / 414.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0006909/0.01559, allocations: 226 kB / 0.5963 GB, free: 15.3 MB / 414.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.002438/0.01803, allocations: 0.7532 MB / 0.597 GB, free: 14.54 MB / 414.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001916/0.01995, allocations: 0.6039 MB / 0.5976 GB, free: 13.95 MB / 414.1 MB
Notification: Performance of NFFlatten.flatten: time 0.002536/0.02248, allocations: 1.702 MB / 0.5992 GB, free: 12.23 MB / 414.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0006243/0.02311, allocations: 189.2 kB / 0.5994 GB, free: 12.04 MB / 414.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001253/0.02436, allocations: 0.6613 MB / 0.6001 GB, free: 11.38 MB / 414.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001069/0.02543, allocations: 0.6722 MB / 0.6007 GB, free: 10.7 MB / 414.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0002083/0.02564, allocations: 88 kB / 0.6008 GB, free: 10.61 MB / 414.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0006772/0.02631, allocations: 231.9 kB / 0.601 GB, free: 10.39 MB / 414.1 MB
Notification: Performance of combineBinaries: time 0.003096/0.02941, allocations: 2.884 MB / 0.6038 GB, free: 7.473 MB / 414.1 MB
Notification: Performance of replaceArrayConstructors: time 0.001842/0.03125, allocations: 1.85 MB / 0.6057 GB, free: 5.598 MB / 414.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0002413/0.03149, allocations: 87.94 kB / 0.6057 GB, free: 5.512 MB / 414.1 MB
Notification: Performance of FrontEnd: time 0.0002154/0.03171, allocations: 35.88 kB / 0.6058 GB, free: 5.477 MB / 414.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 195 (183)
 * Number of variables: 195 (195)
Notification: Performance of [SIM] Bindings: time 0.009019/0.04073, allocations: 6.504 MB / 0.6121 GB, free: 14.71 MB / 430.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.001372/0.0421, allocations: 0.7929 MB / 0.6129 GB, free: 13.9 MB / 430.1 MB
Notification: Performance of [SIM] Early Inline: time 0.01068/0.05278, allocations: 7.14 MB / 0.6199 GB, free: 6.719 MB / 430.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.002063/0.05484, allocations: 0.9152 MB / 0.6208 GB, free: 5.734 MB / 430.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.007089/0.06193, allocations: 4.713 MB / 0.6254 GB, free: 0.5703 MB / 430.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.001132/0.06307, allocations: 0.8101 MB / 0.6262 GB, free: 15.7 MB / 446.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0004124/0.06348, allocations: 399.9 kB / 0.6265 GB, free: 15.27 MB / 446.1 MB
Notification: Performance of [SIM] Detect States: time 0.0009486/0.06443, allocations: 0.9796 MB / 0.6275 GB, free: 14.23 MB / 446.1 MB
Notification: Performance of [SIM] Events: time 0.0006982/0.06512, allocations: 0.5442 MB / 0.628 GB, free: 13.68 MB / 446.1 MB
Notification: Performance of [SIM] Partitioning: time 0.001835/0.06696, allocations: 1.701 MB / 0.6297 GB, free: 11.96 MB / 446.1 MB
Notification: Performance of [SIM] Causalize: time 0.004277/0.07124, allocations: 3.145 MB / 0.6328 GB, free: 8.777 MB / 446.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.00318/0.07442, allocations: 3.477 MB / 0.6362 GB, free: 5.293 MB / 446.1 MB
Notification: Performance of [INI] Simplify: time 0.00364/0.07806, allocations: 2.247 MB / 0.6383 GB, free: 2.934 MB / 446.1 MB
Notification: Performance of [INI] Inline: time 0.2052/0.2832, allocations: 6.799 MB / 0.645 GB, free: 44.73 MB / 446.1 MB
Notification: Performance of [INI] Partitioning: time 0.000333/0.2836, allocations: 74.73 kB / 0.6451 GB, free: 44.7 MB / 446.1 MB
Notification: Performance of [INI] Cleanup: time 0.0009216/0.2845, allocations: 0.9443 MB / 0.646 GB, free: 44.7 MB / 446.1 MB
Notification: Performance of [INI] Causalize: time 0.01097/0.2954, allocations: 8.351 MB / 0.6541 GB, free: 43.64 MB / 446.1 MB
Notification: Performance of [INI] Tearing: time 0.001535/0.297, allocations: 1.591 MB / 0.6557 GB, free: 43.34 MB / 446.1 MB
Notification: Performance of [SIM] Initialization: time 1.312e-06/0.297, allocations: 1.219 kB / 0.6557 GB, free: 43.34 MB / 446.1 MB
Notification: Performance of [SIM] DAE-Mode: time 0.002228/0.2992, allocations: 2.035 MB / 0.6577 GB, free: 42.66 MB / 446.1 MB
Notification: Performance of [SIM] Remove Dummies: time 7.514e-06/0.2992, allocations: 2.375 kB / 0.6577 GB, free: 42.66 MB / 446.1 MB
Notification: Performance of [SIM] Tearing: time 0.0009011/0.3001, allocations: 0.9759 MB / 0.6586 GB, free: 42.32 MB / 446.1 MB
Notification: Performance of [SIM] Categorize: time 1.551e-05/0.3001, allocations: 7.469 kB / 0.6586 GB, free: 42.32 MB / 446.1 MB
Notification: Performance of [SIM] Solve: time 0.00617/0.3063, allocations: 5.846 MB / 0.6644 GB, free: 40.38 MB / 446.1 MB
Notification: Performance of [SIM] Jacobian: time 0.009132/0.3154, allocations: 10.21 MB / 0.6743 GB, free: 35.92 MB / 446.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) {order3_Inputs_Outputs1.delta, order3_Inputs_Outputs1.w, order3_Inputs_Outputs1.e1q}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 29 (29) {$TEV_28, $TEV_27, $TEV_26, $TEV_25, $TEV_24, $TEV_23, $TEV_22, $TEV_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: ........... 94 (scalar:94, 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: ........... 176 (scalar:176, 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 7.457e-05/0.3155, allocations: 59.7 kB / 0.6744 GB, free: 35.87 MB / 446.1 MB
Notification: Performance of SimCode: time 0.004334/0.3198, allocations: 5.871 MB / 0.6801 GB, free: 32.66 MB / 446.1 MB
Notification: Performance of Templates: time 0.02166/0.3415, allocations: 20.07 MB / 0.6997 GB, free: 12.83 MB / 446.1 MB
"
[Timeout remaining time 660]
make -j1 -f OpenIPSL_2.0.0_OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation.makefile [Timeout 660]
(rm -f OpenIPSL_2.0.0_OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation.pipe ; mkfifo OpenIPSL_2.0.0_OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation.pipe ; head -c 1048576 < OpenIPSL_2.0.0_OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation.pipe >> ../files/OpenIPSL_2.0.0_OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation.sim & ./OpenIPSL_2.0.0_OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_2.0.0_OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 6.916655250999611]
Failed to read output from testmodel.py, exit status != 0:
0.3485697970027104 0.357264556 0.323002406
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f8194ab6fb0>