Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr LargeTestSuite_NB_LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_12800.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.5325/0.5325, allocations: 120.7 MB / 141.9 MB, free: 12.02 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.001834/0.001834, allocations: 111.5 kB / 177.6 MB, free: 8.223 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.001329/0.001329, allocations: 215.2 kB / 213.4 MB, free: 26.74 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.6067/0.6067, allocations: 233.4 MB / 482.4 MB, free: 21.46 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.007937/0.007937, allocations: 1.598 MB / 0.5595 GB, free: 4.895 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_12800,tolerance=1e-05,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="LargeTestSuite_NB_LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_12800")
translateModel(LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_12800,tolerance=1e-05,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="LargeTestSuite_NB_LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_12800") [Timeout 400]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.274e-05/1.274e-05, allocations: 5.672 kB / 0.7462 GB, free: 27.64 MB / 478.5 MB
Notification: Performance of NFInst.instantiate(LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_12800): time 0.001342/0.001354, allocations: 1.229 MB / 0.7474 GB, free: 26.41 MB / 478.5 MB
Notification: Performance of NFInst.instExpressions: time 0.0001767/0.001531, allocations: 55.94 kB / 0.7475 GB, free: 26.36 MB / 478.5 MB
Notification: Performance of NFInst.updateImplicitVariability: time 3.17e-05/0.001563, allocations: 4 kB / 0.7475 GB, free: 26.36 MB / 478.5 MB
Notification: Performance of NFTyping.typeComponents: time 5.343e-05/0.001616, allocations: 3.938 kB / 0.7475 GB, free: 26.35 MB / 478.5 MB
Notification: Performance of NFTyping.typeBindings: time 0.000125/0.001741, allocations: 11.94 kB / 0.7475 GB, free: 26.34 MB / 478.5 MB
Notification: Performance of NFTyping.typeClassSections: time 5.946e-05/0.001801, allocations: 11.97 kB / 0.7475 GB, free: 26.33 MB / 478.5 MB
Notification: Performance of NFFlatten.flatten: time 6.38e-05/0.001865, allocations: 23.92 kB / 0.7476 GB, free: 26.3 MB / 478.5 MB
Notification: Performance of NFFlatten.resolveArrayConnections: time 3.895e-05/0.001904, allocations: 4 kB / 0.7476 GB, free: 26.3 MB / 478.5 MB
Notification: Performance of NFEvalConstants.evaluate: time 2.516e-05/0.001929, allocations: 7.984 kB / 0.7476 GB, free: 26.29 MB / 478.5 MB
Notification: Performance of NFSimplifyModel.simplify: time 3.285e-05/0.001962, allocations: 11.94 kB / 0.7476 GB, free: 26.28 MB / 478.5 MB
Notification: Performance of NFPackage.collectConstants: time 1.052e-05/0.001972, allocations: 0 / 0.7476 GB, free: 26.28 MB / 478.5 MB
Notification: Performance of NFFlatten.collectFunctions: time 3.57e-05/0.002008, allocations: 7.984 kB / 0.7476 GB, free: 26.27 MB / 478.5 MB
Notification: Performance of combineBinaries: time 4.032e-05/0.002048, allocations: 35.72 kB / 0.7476 GB, free: 26.24 MB / 478.5 MB
Notification: Performance of replaceArrayConstructors: time 9.868e-06/0.002058, allocations: 19.75 kB / 0.7476 GB, free: 26.22 MB / 478.5 MB
Notification: Performance of NFVerifyModel.verify: time 1.284e-05/0.002071, allocations: 0 / 0.7476 GB, free: 26.22 MB / 478.5 MB
Notification: Performance of FrontEnd: time 4.779e-06/0.002076, 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: 12801 (3)
 * Number of variables: 12801 (2)
Notification: Performance of [SIM] Bindings: time 0.00032/0.002396, allocations: 269 kB / 0.7479 GB, free: 25.83 MB / 478.5 MB
Notification: Performance of [SIM] FunctionAlias: time 2.995e-05/0.002425, allocations: 39.88 kB / 0.7479 GB, free: 25.78 MB / 478.5 MB
Notification: Performance of [SIM] Early Inline: time 7.834e-05/0.002504, allocations: 112.3 kB / 0.748 GB, free: 25.64 MB / 478.5 MB
Notification: Performance of [SIM] Simplify 1: time 3.594e-05/0.00254, allocations: 76.73 kB / 0.7481 GB, free: 25.52 MB / 478.5 MB
Notification: Performance of [SIM] Alias: time 4.1e-05/0.002581, allocations: 96.56 kB / 0.7482 GB, free: 25.38 MB / 478.5 MB
Notification: Performance of [SIM] Simplify 2: time 2.806e-05/0.002609, allocations: 64.73 kB / 0.7483 GB, free: 25.27 MB / 478.5 MB
Notification: Performance of [SIM] Remove Stream: time 1.515e-05/0.002624, allocations: 40.42 kB / 0.7483 GB, free: 25.2 MB / 478.5 MB
Notification: Performance of [SIM] Detect States: time 8.096e-05/0.002705, allocations: 84.83 kB / 0.7484 GB, free: 25.07 MB / 478.5 MB
Notification: Performance of [SIM] Events: time 1.245e-05/0.002717, allocations: 15.94 kB / 0.7484 GB, free: 25.05 MB / 478.5 MB
Notification: Performance of [SIM] Partitioning: time 5.549e-05/0.002773, allocations: 36.09 kB / 0.7484 GB, free: 25.01 MB / 478.5 MB
Notification: Performance of [SIM] Causalize: time 0.01805/0.02083, allocations: 31.95 MB / 0.7796 GB, free: 9.711 MB / 494.5 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 6.359e-05/0.02089, allocations: 55.89 kB / 0.7797 GB, free: 9.656 MB / 494.5 MB
Notification: Performance of [INI] Simplify: time 0.000229/0.02112, allocations: 137.7 kB / 0.7798 GB, free: 9.453 MB / 494.5 MB
Notification: Performance of [INI] Inline: time 9.607e-05/0.02122, allocations: 111.9 kB / 0.7799 GB, free: 9.34 MB / 494.5 MB
Notification: Performance of [INI] Partitioning: time 1.111e-05/0.02123, allocations: 12.06 kB / 0.7799 GB, free: 9.32 MB / 494.5 MB
Notification: Performance of [INI] Cleanup: time 1.942e-05/0.02125, allocations: 19.98 kB / 0.78 GB, free: 9.301 MB / 494.5 MB
Notification: Performance of [INI] Causalize: time 0.3303/0.3516, allocations: 146.8 MB / 0.9233 GB, free: 45.96 MB / 0.5454 GB
Notification: Performance of [INI] Tearing: time 1.521e-05/0.3516, allocations: 6.562 kB / 0.9233 GB, free: 45.96 MB / 0.5454 GB
Notification: Performance of [SIM] Initialization: time 1.172e-06/0.3516, allocations: 0 / 0.9233 GB, free: 45.96 MB / 0.5454 GB
Notification: Performance of [SIM] Remove Dummies: time 5.13e-06/0.3516, allocations: 0 / 0.9233 GB, free: 45.96 MB / 0.5454 GB
Notification: Performance of [SIM] Tearing: time 8.41e-07/0.3516, allocations: 1.406 kB / 0.9233 GB, free: 45.96 MB / 0.5454 GB
Notification: Performance of [SIM] Categorize: time 8.285e-06/0.3516, allocations: 8.422 kB / 0.9233 GB, free: 45.95 MB / 0.5454 GB
Notification: Performance of [SIM] Solve: time 0.0002306/0.3519, allocations: 58.02 kB / 0.9233 GB, free: 45.91 MB / 0.5454 GB
Notification: Performance of [SIM] Jacobian: time 0.1696/0.5215, allocations: 126 MB / 1.046 GB, free: 2.34 MB / 0.6235 GB
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: ............................. 12800 (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 4.325e-05/0.5215, allocations: 22.22 kB / 1.046 GB, free: 2.336 MB / 0.6235 GB
Notification: Performance of SimCode: time 0.4279/0.9494, allocations: 291.2 MB / 1.331 GB, free: 19.04 MB / 0.686 GB
Notification: Performance of Templates: time 0.4265/1.376, allocations: 196.6 MB / 1.523 GB, free: 14.22 MB / 0.7173 GB
"
[Timeout remaining time 399]
make -j16 -f LargeTestSuite_NB_LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_12800.makefile [Timeout 400]
(rm -f LargeTestSuite_NB_LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_12800.pipe ; mkfifo LargeTestSuite_NB_LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_12800.pipe ; head -c 1048576 < LargeTestSuite_NB_LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_12800.pipe >> ../files/LargeTestSuite_NB_LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_12800.sim & ./LargeTestSuite_NB_LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_12800  -s=ida -abortSlowSimulation -alarm=400 -lv LOG_STATS > LargeTestSuite_NB_LargeTestSuite.Elementary.SimpleODE.CascadedFirstOrder_N_12800.pipe 2>&1) [Timeout 400]
[Calling sys.exit(0), Time elapsed: 6.33035269472748]
Failed to read output from testmodel.py, exit status != 0:
1.382383999414742 1.397376218 1.392616742
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f4a8c20b040>