Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ScalableTestSuite_NB_resizable_ScalableTestSuite.Thermal.HeatExchanger.ScaledExperiments.CounterCurrentHeatExchangerEquations_N_640.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.001818/0.001818, 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.00184/0.00184, allocations: 216.6 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.5295/0.5295, allocations: 233.3 MB / 260.8 MB, free: 14.84 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.6452/0.6452, 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.Thermal.HeatExchanger.ScaledExperiments.CounterCurrentHeatExchangerEquations_N_640,tolerance=1e-08,outputFormat="mat",numberOfIntervals=2500,variableFilter="QA.1.|QA.2.|QA.3.|QB.1.|QB.2.|QB.3.|TA.1.|TA.2.|TA.3.|TA.640.|TB.1.|TB.2.|TB.3.|TB.640.|TW.1.|TW.2.|TW.3.|der.TA.2..|der.TA.3..|der.TA.640..|der.TB.1..|der.TB.2..|der.TB.3..|der.TW.1..|der.TW.2..|der.TW.3..|time|time",fileNamePrefix="ScalableTestSuite_NB_resizable_ScalableTestSuite.Thermal.HeatExchanger.ScaledExperiments.CounterCurrentHeatExchangerEquations_N_640")
translateModel(ScalableTestSuite.Thermal.HeatExchanger.ScaledExperiments.CounterCurrentHeatExchangerEquations_N_640,tolerance=1e-08,outputFormat="mat",numberOfIntervals=2500,variableFilter="QA.1.|QA.2.|QA.3.|QB.1.|QB.2.|QB.3.|TA.1.|TA.2.|TA.3.|TA.640.|TB.1.|TB.2.|TB.3.|TB.640.|TW.1.|TW.2.|TW.3.|der.TA.2..|der.TA.3..|der.TA.640..|der.TB.1..|der.TB.2..|der.TB.3..|der.TW.1..|der.TW.2..|der.TW.3..|time|time",fileNamePrefix="ScalableTestSuite_NB_resizable_ScalableTestSuite.Thermal.HeatExchanger.ScaledExperiments.CounterCurrentHeatExchangerEquations_N_640") [Timeout 400]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.653e-05/1.653e-05, allocations: 2.281 kB / 0.612 GB, free: 42.54 MB / 478.2 MB
Notification: Performance of NFInst.instantiate(ScalableTestSuite.Thermal.HeatExchanger.ScaledExperiments.CounterCurrentHeatExchangerEquations_N_640): time 0.001582/0.001598, allocations: 1.469 MB / 0.6134 GB, free: 41.38 MB / 478.2 MB
Notification: Performance of NFInst.instExpressions: time 0.0002282/0.001826, allocations: 93.84 kB / 0.6135 GB, free: 41.3 MB / 478.2 MB
Notification: Performance of NFInst.updateImplicitVariability: time 3.733e-05/0.001864, allocations: 0.9375 kB / 0.6135 GB, free: 41.3 MB / 478.2 MB
Notification: Performance of NFTyping.typeComponents: time 8.045e-05/0.001944, allocations: 21 kB / 0.6136 GB, free: 41.28 MB / 478.2 MB
Notification: Performance of NFTyping.typeBindings: time 0.0001265/0.002071, allocations: 75.03 kB / 0.6136 GB, free: 41.22 MB / 478.2 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0001525/0.002223, allocations: 31.41 kB / 0.6137 GB, free: 41.2 MB / 478.2 MB
Notification: Performance of NFFlatten.flatten: time 0.0001802/0.002403, allocations: 208.5 kB / 0.6139 GB, free: 41.09 MB / 478.2 MB
Notification: Performance of NFFlatten.resolveArrayConnections: time 4.287e-05/0.002446, allocations: 13.22 kB / 0.6139 GB, free: 41.08 MB / 478.2 MB
Notification: Performance of NFEvalConstants.evaluate: time 6.44e-05/0.002511, allocations: 74.97 kB / 0.6139 GB, free: 41.05 MB / 478.2 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.00011/0.002621, allocations: 109.5 kB / 0.614 GB, free: 40.98 MB / 478.2 MB
Notification: Performance of NFPackage.collectConstants: time 2.302e-05/0.002644, allocations: 6.312 kB / 0.6141 GB, free: 40.98 MB / 478.2 MB
Notification: Performance of NFFlatten.collectFunctions: time 2.513e-05/0.002669, allocations: 16.78 kB / 0.6141 GB, free: 40.97 MB / 478.2 MB
Notification: Performance of combineBinaries: time 0.0001805/0.002849, allocations: 289.1 kB / 0.6143 GB, free: 40.77 MB / 478.2 MB
Notification: Performance of replaceArrayConstructors: time 0.0001097/0.002959, allocations: 180.2 kB / 0.6145 GB, free: 40.66 MB / 478.2 MB
Notification: Performance of NFVerifyModel.verify: time 2.569e-05/0.002985, allocations: 18.25 kB / 0.6145 GB, free: 40.65 MB / 478.2 MB
Notification: Performance of FrontEnd: time 8.916e-06/0.002994, allocations: 2.5 kB / 0.6145 GB, free: 40.65 MB / 478.2 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 4478 (12)
 * Number of variables: 4478 (10)
Notification: Performance of Bindings: time 0.001287/0.00428, allocations: 1.518 MB / 0.616 GB, free: 39.55 MB / 478.2 MB
Notification: Performance of FunctionAlias: time 8.897e-05/0.004369, allocations: 88.08 kB / 0.6161 GB, free: 39.47 MB / 478.2 MB
Notification: Performance of Early Inline: time 0.0002977/0.004667, allocations: 0.5179 MB / 0.6166 GB, free: 39.11 MB / 478.2 MB
Notification: Performance of Simplify 1: time 8.782e-05/0.004755, allocations: 118.9 kB / 0.6167 GB, free: 38.98 MB / 478.2 MB
Notification: Performance of Alias: time 0.0003457/0.0051, allocations: 332.9 kB / 0.617 GB, free: 38.66 MB / 478.2 MB
Notification: Performance of Simplify 2: time 7.791e-05/0.005178, allocations: 112.6 kB / 0.6171 GB, free: 38.54 MB / 478.2 MB
Notification: Performance of Remove Stream: time 3.682e-05/0.005215, allocations: 66.42 kB / 0.6172 GB, free: 38.46 MB / 478.2 MB
Notification: Performance of Detect States: time 0.0001465/0.005362, allocations: 190.6 kB / 0.6174 GB, free: 38.25 MB / 478.2 MB
Notification: Performance of Events: time 5.274e-05/0.005414, allocations: 34.25 kB / 0.6174 GB, free: 38.23 MB / 478.2 MB
Notification: Performance of Partitioning: time 0.000106/0.00552, allocations: 123.2 kB / 0.6175 GB, free: 38.14 MB / 478.2 MB
Notification: Performance of Causalize: time 0.0004767/0.005997, allocations: 459.4 kB / 0.618 GB, free: 37.93 MB / 478.2 MB
Notification: Performance of After Index Reduction Inline: time 0.0002757/0.006273, allocations: 495.3 kB / 0.6185 GB, free: 37.62 MB / 478.2 MB
Notification: Performance of Simplify: time 0.0002042/0.006477, allocations: 213.5 kB / 0.6187 GB, free: 37.4 MB / 478.2 MB
Notification: Performance of Inline: time 0.0004021/0.006879, allocations: 0.726 MB / 0.6194 GB, free: 36.93 MB / 478.2 MB
Notification: Performance of Partitioning: time 1.427e-05/0.006893, allocations: 12.62 kB / 0.6194 GB, free: 36.91 MB / 478.2 MB
Notification: Performance of Cleanup: time 6.003e-05/0.006953, allocations: 77.77 kB / 0.6194 GB, free: 36.86 MB / 478.2 MB
Notification: Performance of Causalize: time 0.0006549/0.007608, allocations: 0.6458 MB / 0.6201 GB, free: 36.58 MB / 478.2 MB
Notification: Performance of Tearing: time 8.927e-06/0.007617, allocations: 2.094 kB / 0.6201 GB, free: 36.58 MB / 478.2 MB
Notification: Performance of Initialization: time 7.62e-07/0.007618, allocations: 0 / 0.6201 GB, free: 36.58 MB / 478.2 MB
Notification: Performance of Remove Dummies: time 3.577e-06/0.007622, allocations: 1.125 kB / 0.6201 GB, free: 36.58 MB / 478.2 MB
Notification: Performance of Tearing: time 1.954e-06/0.007624, allocations: 9.031 kB / 0.6201 GB, free: 36.57 MB / 478.2 MB
Notification: Performance of Categorize: time 4.92e-06/0.007629, allocations: 1.125 kB / 0.6201 GB, free: 36.57 MB / 478.2 MB
Notification: Performance of Solve: time 0.0001999/0.007828, allocations: 155 kB / 0.6202 GB, free: 36.48 MB / 478.2 MB
"
[Timeout remaining time 400]
[Calling sys.exit(0), Time elapsed: 2.334858927875757]
Failed to read output from testmodel.py, exit status != 0:
0.013705512508749962 0.021871945 0.017063569
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fd0c4509720>