Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr LargeTestSuite_NB_LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_1600.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestSuite 2.2.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestSuite 2.2.0/package.mo): time 0.5177/0.5177, allocations: 120.7 MB / 141.8 MB, free: 12.03 MB / 126.2 MB
"
[Timeout remaining time 179]
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.001811/0.001811, allocations: 116.8 kB / 177.5 MB, free: 8.227 MB / 158.2 MB
"
[Timeout remaining time 180]
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.001331/0.001331, allocations: 211.9 kB / 213.3 MB, free: 26.76 MB / 158.5 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 0.6404/0.6404, allocations: 233.4 MB / 482.4 MB, free: 23.32 MB / 334.5 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/LargeTestSuite master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/LargeTestSuite master/package.mo): time 0.004908/0.004908, allocations: 1.58 MB / 0.5595 GB, free: 5.824 MB / 398.5 MB
"
[Timeout remaining time 180]
Using package LargeTestSuite with version 2.2.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/LargeTestSuite master/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 Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 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)
Using package ScalableTestSuite with version 2.2.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestSuite 2.2.0/package.mo)
Running command: translateModel(LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_1600,tolerance=1e-05,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="LargeTestSuite_NB_LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_1600")
translateModel(LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_1600,tolerance=1e-05,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="LargeTestSuite_NB_LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_1600") [Timeout 400]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.089e-05/1.089e-05, allocations: 5.234 kB / 0.7462 GB, free: 27.64 MB / 478.5 MB
Notification: Performance of NFInst.instantiate(LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_1600): time 0.001244/0.001255, allocations: 1.217 MB / 0.7474 GB, free: 26.41 MB / 478.5 MB
Notification: Performance of NFInst.instExpressions: time 0.0001582/0.001413, allocations: 55.8 kB / 0.7474 GB, free: 26.36 MB / 478.5 MB
Notification: Performance of NFInst.updateImplicitVariability: time 2.481e-05/0.001438, allocations: 0 / 0.7474 GB, free: 26.36 MB / 478.5 MB
Notification: Performance of NFTyping.typeComponents: time 5.858e-05/0.001497, allocations: 0 / 0.7474 GB, free: 26.36 MB / 478.5 MB
Notification: Performance of NFTyping.typeBindings: time 0.0001213/0.001618, allocations: 15.91 kB / 0.7474 GB, free: 26.34 MB / 478.5 MB
Notification: Performance of NFTyping.typeClassSections: time 5.639e-05/0.001674, allocations: 7.984 kB / 0.7475 GB, free: 26.34 MB / 478.5 MB
Notification: Performance of NFFlatten.flatten: time 5.666e-05/0.001731, allocations: 19.88 kB / 0.7475 GB, free: 26.32 MB / 478.5 MB
Notification: Performance of NFFlatten.resolveArrayConnections: time 3.25e-05/0.001764, allocations: 7.984 kB / 0.7475 GB, free: 26.31 MB / 478.5 MB
Notification: Performance of NFEvalConstants.evaluate: time 2.066e-05/0.001784, allocations: 8 kB / 0.7475 GB, free: 26.3 MB / 478.5 MB
Notification: Performance of NFSimplifyModel.simplify: time 3.15e-05/0.001816, allocations: 11.98 kB / 0.7475 GB, free: 26.29 MB / 478.5 MB
Notification: Performance of NFPackage.collectConstants: time 9.939e-06/0.001826, allocations: 0 / 0.7475 GB, free: 26.29 MB / 478.5 MB
Notification: Performance of NFFlatten.collectFunctions: time 3.318e-05/0.001859, allocations: 19.92 kB / 0.7475 GB, free: 26.27 MB / 478.5 MB
Notification: Performance of combineBinaries: time 4.372e-05/0.001903, allocations: 31.67 kB / 0.7476 GB, free: 26.24 MB / 478.5 MB
Notification: Performance of replaceArrayConstructors: time 1.047e-05/0.001913, allocations: 23.73 kB / 0.7476 GB, free: 26.21 MB / 478.5 MB
Notification: Performance of NFVerifyModel.verify: time 1.547e-05/0.001928, allocations: 0 / 0.7476 GB, free: 26.21 MB / 478.5 MB
Notification: Performance of FrontEnd: time 5.531e-06/0.001934, allocations: 4 kB / 0.7476 GB, free: 26.21 MB / 478.5 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1601 (3)
 * Number of variables: 1601 (2)
Notification: Performance of [SIM] Bindings: time 0.000297/0.002231, allocations: 263.7 kB / 0.7478 GB, free: 25.84 MB / 478.5 MB
Notification: Performance of [SIM] FunctionAlias: time 3.005e-05/0.002261, allocations: 35.89 kB / 0.7479 GB, free: 25.79 MB / 478.5 MB
Notification: Performance of [SIM] Early Inline: time 8.208e-05/0.002343, allocations: 120.2 kB / 0.748 GB, free: 25.64 MB / 478.5 MB
Notification: Performance of [SIM] Simplify 1: time 3.473e-05/0.002378, allocations: 72.67 kB / 0.748 GB, free: 25.53 MB / 478.5 MB
Notification: Performance of [SIM] Alias: time 4.136e-05/0.002419, allocations: 88.64 kB / 0.7481 GB, free: 25.39 MB / 478.5 MB
Notification: Performance of [SIM] Simplify 2: time 3.011e-05/0.002449, allocations: 84.64 kB / 0.7482 GB, free: 25.26 MB / 478.5 MB
Notification: Performance of [SIM] Remove Stream: time 1.521e-05/0.002465, allocations: 40.44 kB / 0.7483 GB, free: 25.2 MB / 478.5 MB
Notification: Performance of [SIM] Detect States: time 7.377e-05/0.002538, allocations: 76.84 kB / 0.7483 GB, free: 25.07 MB / 478.5 MB
Notification: Performance of [SIM] Events: time 1.213e-05/0.00255, allocations: 11.98 kB / 0.7483 GB, free: 25.05 MB / 478.5 MB
Notification: Performance of [SIM] Partitioning: time 5.109e-05/0.002601, allocations: 36.09 kB / 0.7484 GB, free: 25.01 MB / 478.5 MB
Notification: Performance of [SIM] Causalize: time 0.002201/0.004803, allocations: 4.033 MB / 0.7523 GB, free: 20.93 MB / 478.5 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 3.399e-05/0.004837, allocations: 59.88 kB / 0.7524 GB, free: 20.88 MB / 478.5 MB
Notification: Performance of [INI] Simplify: time 0.0001265/0.004963, allocations: 148.8 kB / 0.7525 GB, free: 20.66 MB / 478.5 MB
Notification: Performance of [INI] Inline: time 6.004e-05/0.005023, allocations: 99.95 kB / 0.7526 GB, free: 20.56 MB / 478.5 MB
Notification: Performance of [INI] Partitioning: time 6.472e-06/0.00503, allocations: 16.06 kB / 0.7526 GB, free: 20.54 MB / 478.5 MB
Notification: Performance of [INI] Cleanup: time 1.44e-05/0.005044, allocations: 27.97 kB / 0.7526 GB, free: 20.51 MB / 478.5 MB
Notification: Performance of [INI] Causalize: time 0.01088/0.01593, allocations: 17.76 MB / 0.77 GB, free: 2.699 MB / 478.5 MB
Notification: Performance of [INI] Tearing: time 1.353e-05/0.01594, allocations: 4 kB / 0.77 GB, free: 2.695 MB / 478.5 MB
Notification: Performance of [SIM] Initialization: time 4.51e-07/0.01594, allocations: 0 / 0.77 GB, free: 2.695 MB / 478.5 MB
Notification: Performance of [SIM] Remove Dummies: time 2.514e-06/0.01594, allocations: 0 / 0.77 GB, free: 2.695 MB / 478.5 MB
Notification: Performance of [SIM] Tearing: time 8.22e-07/0.01594, allocations: 0 / 0.77 GB, free: 2.695 MB / 478.5 MB
Notification: Performance of [SIM] Categorize: time 4.438e-06/0.01595, allocations: 0 / 0.77 GB, free: 2.695 MB / 478.5 MB
Notification: Performance of [SIM] Solve: time 0.0001236/0.01607, allocations: 67.95 kB / 0.7701 GB, free: 2.629 MB / 478.5 MB
Notification: Performance of [SIM] Jacobian: time 0.02111/0.03718, allocations: 16 MB / 0.7857 GB, free: 2.496 MB / 494.5 MB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 1
 * Number of algebraic partitions: ............... 0
 * Number of ODE event partitions: ............... 0
 * 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: ............................. 1600 (1) {x}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 0 (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: ........... 2 (scalar:2, array:0, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:0)
 * Number of for-loop strong components: ......... 1 (resizable: 1, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 0 (linear: 0, nonlinear:0)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 4 (scalar:3, array:1, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:0)
 * Number of for-loop strong components: ......... 1 (resizable: 1, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 0 (linear: 0, nonlinear:0)
Notification: Performance of backend: time 3.326e-05/0.03721, allocations: 24.52 kB / 0.7857 GB, free: 2.488 MB / 494.5 MB
Notification: Performance of SimCode: time 0.03027/0.06748, allocations: 36.5 MB / 0.8213 GB, free: 13.53 MB / 0.5298 GB
Notification: Performance of Templates: time 0.0372/0.1047, allocations: 25.01 MB / 0.8458 GB, free: 4.48 MB / 0.5454 GB
"
[Timeout remaining time 400]
make -j16 -f LargeTestSuite_NB_LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_1600.makefile [Timeout 400]
(rm -f LargeTestSuite_NB_LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_1600.pipe ; mkfifo LargeTestSuite_NB_LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_1600.pipe ; head -c 1048576 < LargeTestSuite_NB_LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_1600.pipe >> ../files/LargeTestSuite_NB_LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_1600.sim & ./LargeTestSuite_NB_LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_1600  -s=ida -abortSlowSimulation -alarm=400 -lv LOG_STATS > LargeTestSuite_NB_LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_1600.pipe 2>&1) [Timeout 400]
[Calling sys.exit(0), Time elapsed: 2.9191740439273417]
Failed to read output from testmodel.py, exit status != 0:
0.1106130201369524 0.32997905299999997 0.32600461799999997
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f163b396da0>