Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ScalableTestSuite_NB_resizable_ScalableTestSuite.Elementary.SimpleODE.Models.CascadedFirstOrder.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.001766/0.001766, allocations: 116.1 kB / 21.1 MB, free: 0.7695 MB / 14.72 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.001827/0.001827, allocations: 221.8 kB / 24.39 MB, free: 4.168 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 0.5676/0.5676, allocations: 233.3 MB / 260.9 MB, free: 15.53 MB / 222.1 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestSuite 2.2.0-master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestSuite 2.2.0-master/package.mo): time 0.6783/0.6783, allocations: 120.7 MB / 438 MB, free: 1.816 MB / 334.1 MB
"
[Timeout remaining time 179]
Using package ScalableTestSuite with version 2.2.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestSuite 2.2.0-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)
Running command: translateModel(ScalableTestSuite.Elementary.SimpleODE.Models.CascadedFirstOrder,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="der.x.10..|der.x.1..|der.x.2..|der.x.3..|der.x.4..|der.x.5..|der.x.6..|der.x.7..|der.x.8..|der.x.9..|time|time|x.10.|x.1.|x.2.|x.3.|x.4.|x.5.|x.6.|x.7.|x.8.|x.9.",fileNamePrefix="ScalableTestSuite_NB_resizable_ScalableTestSuite.Elementary.SimpleODE.Models.CascadedFirstOrder")
translateModel(ScalableTestSuite.Elementary.SimpleODE.Models.CascadedFirstOrder,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="der.x.10..|der.x.1..|der.x.2..|der.x.3..|der.x.4..|der.x.5..|der.x.6..|der.x.7..|der.x.8..|der.x.9..|time|time|x.10.|x.1.|x.2.|x.3.|x.4.|x.5.|x.6.|x.7.|x.8.|x.9.",fileNamePrefix="ScalableTestSuite_NB_resizable_ScalableTestSuite.Elementary.SimpleODE.Models.CascadedFirstOrder") [Timeout 400]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.524e-05/1.524e-05, allocations: 10.16 kB / 0.6104 GB, free: 43.73 MB / 478.3 MB
Notification: Performance of NFInst.instantiate(ScalableTestSuite.Elementary.SimpleODE.Models.CascadedFirstOrder): time 0.001481/0.001496, allocations: 1.197 MB / 0.6116 GB, free: 42.78 MB / 478.3 MB
Notification: Performance of NFInst.instExpressions: time 0.0001183/0.001614, allocations: 25.42 kB / 0.6116 GB, free: 42.76 MB / 478.3 MB
Notification: Performance of NFInst.updateImplicitVariability: time 2.418e-05/0.001638, allocations: 0.9375 kB / 0.6116 GB, free: 42.76 MB / 478.3 MB
Notification: Performance of NFTyping.typeComponents: time 4.143e-05/0.00168, allocations: 0 / 0.6116 GB, free: 42.76 MB / 478.3 MB
Notification: Performance of NFTyping.typeBindings: time 3.469e-05/0.001715, allocations: 8.844 kB / 0.6116 GB, free: 42.75 MB / 478.3 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0001168/0.001831, allocations: 10.77 kB / 0.6116 GB, free: 42.75 MB / 478.3 MB
Notification: Performance of NFFlatten.flatten: time 6.027e-05/0.001892, allocations: 19.28 kB / 0.6117 GB, free: 42.73 MB / 478.3 MB
Notification: Performance of NFFlatten.resolveArrayConnections: time 3.594e-05/0.001928, allocations: 1.812 kB / 0.6117 GB, free: 42.73 MB / 478.3 MB
Notification: Performance of NFEvalConstants.evaluate: time 1.869e-05/0.001946, allocations: 7.688 kB / 0.6117 GB, free: 42.73 MB / 478.3 MB
Notification: Performance of NFSimplifyModel.simplify: time 3.694e-05/0.001983, allocations: 15.55 kB / 0.6117 GB, free: 42.72 MB / 478.3 MB
Notification: Performance of NFPackage.collectConstants: time 1.223e-05/0.001995, allocations: 0.9062 kB / 0.6117 GB, free: 42.72 MB / 478.3 MB
Notification: Performance of NFFlatten.collectFunctions: time 7.654e-06/0.002003, allocations: 0.9375 kB / 0.6117 GB, free: 42.72 MB / 478.3 MB
Notification: Performance of combineBinaries: time 4.677e-05/0.00205, allocations: 37.84 kB / 0.6117 GB, free: 42.69 MB / 478.3 MB
Notification: Performance of replaceArrayConstructors: time 1.259e-05/0.002062, allocations: 27.11 kB / 0.6117 GB, free: 42.67 MB / 478.3 MB
Notification: Performance of NFVerifyModel.verify: time 1.995e-05/0.002082, allocations: 8.875 kB / 0.6118 GB, free: 42.66 MB / 478.3 MB
Notification: Performance of FrontEnd: time 5.621e-06/0.002088, allocations: 3.938 kB / 0.6118 GB, free: 42.66 MB / 478.3 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 11 (3)
 * Number of variables: 11 (2)
Notification: Performance of Bindings: time 0.0004583/0.002546, allocations: 348.7 kB / 0.6121 GB, free: 42.29 MB / 478.3 MB
Notification: Performance of FunctionAlias: time 2.534e-05/0.002572, allocations: 28.62 kB / 0.6121 GB, free: 42.25 MB / 478.3 MB
Notification: Performance of Early Inline: time 6.506e-05/0.002637, allocations: 104.8 kB / 0.6122 GB, free: 42.15 MB / 478.3 MB
Notification: Performance of Simplify 1: time 3.147e-05/0.002668, allocations: 61.95 kB / 0.6123 GB, free: 42.05 MB / 478.3 MB
Notification: Performance of Alias: time 0.0001773/0.002845, allocations: 128.6 kB / 0.6124 GB, free: 41.89 MB / 478.3 MB
Notification: Performance of Simplify 2: time 2.865e-05/0.002874, allocations: 65.89 kB / 0.6125 GB, free: 41.79 MB / 478.3 MB
Notification: Performance of Remove Stream: time 1.461e-05/0.002889, allocations: 41.66 kB / 0.6125 GB, free: 41.73 MB / 478.3 MB
Notification: Performance of Detect States: time 7.384e-05/0.002963, allocations: 78.94 kB / 0.6126 GB, free: 41.61 MB / 478.3 MB
Notification: Performance of Events: time 8.686e-06/0.002971, allocations: 9.141 kB / 0.6126 GB, free: 41.6 MB / 478.3 MB
Notification: Performance of Partitioning: time 3.395e-05/0.003005, allocations: 22.42 kB / 0.6126 GB, free: 41.57 MB / 478.3 MB
Notification: Performance of Causalize: time 7.402e-05/0.003079, allocations: 48.36 kB / 0.6127 GB, free: 41.54 MB / 478.3 MB
Notification: Performance of After Index Reduction Inline: time 3.734e-05/0.003117, allocations: 70.61 kB / 0.6127 GB, free: 41.5 MB / 478.3 MB
Notification: Performance of Simplify: time 0.0001081/0.003225, allocations: 126.8 kB / 0.6128 GB, free: 41.32 MB / 478.3 MB
Error: Internal error NBVariable.VariablePointers.getVarSafe failed for N
Notification: Performance of Inline: time 7.683e-05/0.003302, allocations: 120.4 kB / 0.613 GB, free: 41.24 MB / 478.3 MB
Notification: Performance of Partitioning: time 5.46e-06/0.003307, allocations: 9.375 kB / 0.613 GB, free: 41.22 MB / 478.3 MB
Notification: Performance of Cleanup: time 1.112e-05/0.003318, allocations: 15.8 kB / 0.613 GB, free: 41.21 MB / 478.3 MB
Notification: Performance of Causalize: time 0.0001531/0.003471, allocations: 130.2 kB / 0.6131 GB, free: 41.14 MB / 478.3 MB
Notification: Performance of Tearing: time 6.572e-06/0.003478, allocations: 1.562 kB / 0.6131 GB, free: 41.14 MB / 478.3 MB
Notification: Performance of Initialization: time 2.71e-07/0.003478, allocations: 0 / 0.6131 GB, free: 41.14 MB / 478.3 MB
Notification: Performance of Remove Dummies: time 2.335e-06/0.00348, allocations: 4 kB / 0.6131 GB, free: 41.14 MB / 478.3 MB
Notification: Performance of Tearing: time 1.603e-06/0.003482, allocations: 5.375 kB / 0.6131 GB, free: 41.14 MB / 478.3 MB
Notification: Performance of Categorize: time 4.278e-06/0.003486, allocations: 0 / 0.6131 GB, free: 41.14 MB / 478.3 MB
Notification: Performance of Solve: time 6.384e-05/0.00355, allocations: 27.91 kB / 0.6131 GB, free: 41.12 MB / 478.3 MB
Notification: Performance of Jacobian: time 0.0001132/0.003663, allocations: 201.1 kB / 0.6133 GB, free: 40.92 MB / 478.3 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: ............................. 10 (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: ........... 1 (scalar:1, 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: ........... 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: ......... 2 (resizable: 2, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 0 (linear: 0, nonlinear:0)
Notification: Performance of backend: time 2.344e-05/0.003687, allocations: 20.92 kB / 0.6134 GB, free: 40.91 MB / 478.3 MB
Notification: Performance of SimCode: time 0.0003763/0.004063, allocations: 427.7 kB / 0.6138 GB, free: 40.6 MB / 478.3 MB
Notification: Performance of Templates: time 0.002757/0.00682, allocations: 447.8 kB / 0.6142 GB, free: 40.34 MB / 478.3 MB
"
[Timeout remaining time 400]
make -j16 -f ScalableTestSuite_NB_resizable_ScalableTestSuite.Elementary.SimpleODE.Models.CascadedFirstOrder.makefile [Timeout 400]
(rm -f ScalableTestSuite_NB_resizable_ScalableTestSuite.Elementary.SimpleODE.Models.CascadedFirstOrder.pipe ; mkfifo ScalableTestSuite_NB_resizable_ScalableTestSuite.Elementary.SimpleODE.Models.CascadedFirstOrder.pipe ; head -c 1048576 < ScalableTestSuite_NB_resizable_ScalableTestSuite.Elementary.SimpleODE.Models.CascadedFirstOrder.pipe >> ../files/ScalableTestSuite_NB_resizable_ScalableTestSuite.Elementary.SimpleODE.Models.CascadedFirstOrder.sim & ./ScalableTestSuite_NB_resizable_ScalableTestSuite.Elementary.SimpleODE.Models.CascadedFirstOrder  -abortSlowSimulation -alarm=300  -emit_protected -lv LOG_STATS > ScalableTestSuite_NB_resizable_ScalableTestSuite.Elementary.SimpleODE.Models.CascadedFirstOrder.pipe 2>&1) [Timeout 300]
diffSimulationResults("ScalableTestSuite_NB_resizable_ScalableTestSuite.Elementary.SimpleODE.Models.CascadedFirstOrder_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/ReferenceFiles/ScalableTestSuite/ScalableTestSuite.Elementary.SimpleODE.Models.CascadedFirstOrder_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ScalableTestSuite_NB_resizable_ScalableTestSuite.Elementary.SimpleODE.Models.CascadedFirstOrder.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 400]
""
[Timeout remaining time 400]
Reference file matches
[Calling sys.exit(0), Time elapsed: 2.675445707514882]