Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ScalableTestSuite_ScalableTestSuite.Thermal.HeatExchanger.ScaledExperiments.CounterCurrentHeatExchangerEquations_N_1280.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.001649/0.001649, allocations: 103.6 kB / 21.25 MB, free: 0.5586 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.001816/0.001816, allocations: 211.6 kB / 24.55 MB, free: 3.824 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 1.489/1.489, allocations: 230.6 MB / 258.3 MB, free: 7.734 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestSuite master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestSuite master/package.mo): time 1.083/1.083, allocations: 120.6 MB / 435.3 MB, free: 0.6172 MB / 334.1 MB
"
[Timeout remaining time 179]
Using package ScalableTestSuite with version 2.2.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestSuite 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_1280,tolerance=1e-08,outputFormat="mat",numberOfIntervals=2500,variableFilter="QA.1.|QA.2.|QA.3.|QB.1.|QB.2.|QB.3.|TA.1280.|TA.1.|TA.2.|TA.3.|TB.1280.|TB.1.|TB.2.|TB.3.|TW.1.|TW.2.|TW.3.|der.TA.1280..|der.TA.2..|der.TA.3..|der.TB.1..|der.TB.2..|der.TB.3..|der.TW.1..|der.TW.2..|der.TW.3..|time|time",fileNamePrefix="ScalableTestSuite_ScalableTestSuite.Thermal.HeatExchanger.ScaledExperiments.CounterCurrentHeatExchangerEquations_N_1280")
translateModel(ScalableTestSuite.Thermal.HeatExchanger.ScaledExperiments.CounterCurrentHeatExchangerEquations_N_1280,tolerance=1e-08,outputFormat="mat",numberOfIntervals=2500,variableFilter="QA.1.|QA.2.|QA.3.|QB.1.|QB.2.|QB.3.|TA.1280.|TA.1.|TA.2.|TA.3.|TB.1280.|TB.1.|TB.2.|TB.3.|TW.1.|TW.2.|TW.3.|der.TA.1280..|der.TA.2..|der.TA.3..|der.TB.1..|der.TB.2..|der.TB.3..|der.TW.1..|der.TW.2..|der.TW.3..|time|time",fileNamePrefix="ScalableTestSuite_ScalableTestSuite.Thermal.HeatExchanger.ScaledExperiments.CounterCurrentHeatExchangerEquations_N_1280") [Timeout 300]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.227e-05/2.227e-05, allocations: 8.75 kB / 0.6095 GB, free: 8.68 MB / 446.3 MB
Notification: Performance of NFInst.instantiate(ScalableTestSuite.Thermal.HeatExchanger.ScaledExperiments.CounterCurrentHeatExchangerEquations_N_1280): time 0.001953/0.001975, allocations: 1.438 MB / 0.6109 GB, free: 7.234 MB / 446.3 MB
Notification: Performance of NFInst.instExpressions: time 0.0002788/0.002254, allocations: 99.64 kB / 0.611 GB, free: 7.137 MB / 446.3 MB
Notification: Performance of NFInst.updateImplicitVariability: time 4.544e-05/0.002299, allocations: 7.938 kB / 0.611 GB, free: 7.129 MB / 446.3 MB
Notification: Performance of NFTyping.typeComponents: time 0.0001114/0.002411, allocations: 11.92 kB / 0.6111 GB, free: 7.117 MB / 446.3 MB
Notification: Performance of NFTyping.typeBindings: time 0.0001199/0.002531, allocations: 63.7 kB / 0.6111 GB, free: 7.055 MB / 446.3 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0002009/0.002731, allocations: 31.94 kB / 0.6111 GB, free: 7.023 MB / 446.3 MB
Notification: Performance of NFFlatten.flatten: time 0.0001719/0.002903, allocations: 167.5 kB / 0.6113 GB, free: 6.859 MB / 446.3 MB
Notification: Performance of NFFlatten.resolveConnections: time 5.727e-05/0.002961, allocations: 7.859 kB / 0.6113 GB, free: 6.852 MB / 446.3 MB
Notification: Performance of NFEvalConstants.evaluate: time 8.231e-05/0.003043, allocations: 91.72 kB / 0.6114 GB, free: 6.762 MB / 446.3 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.000106/0.003149, allocations: 79.72 kB / 0.6115 GB, free: 6.684 MB / 446.3 MB
Notification: Performance of NFPackage.collectConstants: time 2.666e-05/0.003176, allocations: 4 kB / 0.6115 GB, free: 6.68 MB / 446.3 MB
Notification: Performance of NFFlatten.collectFunctions: time 2.676e-05/0.003202, allocations: 8 kB / 0.6115 GB, free: 6.672 MB / 446.3 MB
Notification: Performance of combineBinaries: time 0.0001493/0.003352, allocations: 206.3 kB / 0.6117 GB, free: 6.469 MB / 446.3 MB
Notification: Performance of replaceArrayConstructors: time 5.069e-05/0.003402, allocations: 114.8 kB / 0.6118 GB, free: 6.355 MB / 446.3 MB
Notification: Performance of NFVerifyModel.verify: time 2.335e-05/0.003426, allocations: 8 kB / 0.6118 GB, free: 6.348 MB / 446.3 MB
Notification: Performance of FrontEnd: time 1.16e-05/0.003437, allocations: 7.984 kB / 0.6118 GB, free: 6.34 MB / 446.3 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 8958 (12)
 * Number of variables: 8958 (10)
Notification: Performance of [SIM] Bindings: time 0.0008386/0.004276, allocations: 0.7766 MB / 0.6126 GB, free: 5.438 MB / 446.3 MB
Notification: Performance of [SIM] FunctionAlias: time 0.0001133/0.004389, allocations: 83.95 kB / 0.6126 GB, free: 5.344 MB / 446.3 MB
Notification: Performance of [SIM] Early Inline: time 0.0002211/0.00461, allocations: 375.9 kB / 0.613 GB, free: 4.949 MB / 446.3 MB
Notification: Performance of [SIM] Simplify 1: time 9.939e-05/0.00471, allocations: 137.9 kB / 0.6131 GB, free: 4.77 MB / 446.3 MB
Notification: Performance of [SIM] Alias: time 0.0004266/0.005136, allocations: 310.2 kB / 0.6134 GB, free: 4.402 MB / 446.3 MB
Notification: Performance of [SIM] Simplify 2: time 9.293e-05/0.005229, allocations: 126 kB / 0.6136 GB, free: 4.23 MB / 446.3 MB
Notification: Performance of [SIM] Remove Stream: time 4.068e-05/0.00527, allocations: 60.34 kB / 0.6136 GB, free: 4.145 MB / 446.3 MB
Notification: Performance of [SIM] Detect States: time 0.0001677/0.005437, allocations: 168.3 kB / 0.6138 GB, free: 3.934 MB / 446.3 MB
Notification: Performance of [SIM] Events: time 6.114e-05/0.005499, allocations: 23.98 kB / 0.6138 GB, free: 3.91 MB / 446.3 MB
Notification: Performance of [SIM] Partitioning: time 0.0001278/0.005626, allocations: 132 kB / 0.6139 GB, free: 3.773 MB / 446.3 MB
Notification: Performance of [SIM] Causalize: time 0.07366/0.07928, allocations: 57.04 MB / 0.6696 GB, free: 10.74 MB / 0.4983 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.0004103/0.07969, allocations: 339.4 kB / 0.6699 GB, free: 10.41 MB / 0.4983 GB
Notification: Performance of [INI] Simplify: time 0.0004891/0.08018, allocations: 206.2 kB / 0.6701 GB, free: 10.14 MB / 0.4983 GB
Notification: Performance of [INI] Inline: time 0.0004975/0.08068, allocations: 0.4915 MB / 0.6706 GB, free: 9.645 MB / 0.4983 GB
Notification: Performance of [INI] Partitioning: time 4.35e-05/0.08072, allocations: 20.09 kB / 0.6706 GB, free: 9.617 MB / 0.4983 GB
Notification: Performance of [INI] Cleanup: time 7.532e-05/0.0808, allocations: 67.86 kB / 0.6707 GB, free: 9.551 MB / 0.4983 GB
Notification: Performance of [INI] Causalize: time 0.6865/0.7673, allocations: 90.38 MB / 0.759 GB, free: 129.3 MB / 0.5767 GB
Notification: Performance of [INI] Tearing: time 2.356e-05/0.7673, allocations: 5.906 kB / 0.759 GB, free: 129.3 MB / 0.5767 GB
Notification: Performance of [SIM] Initialization: time 1.864e-06/0.7673, allocations: 0 / 0.759 GB, free: 129.3 MB / 0.5767 GB
Notification: Performance of [SIM] DAE-Mode: time 0.0001302/0.7674, allocations: 29.56 kB / 0.759 GB, free: 129.3 MB / 0.5767 GB
Notification: Performance of [SIM] Remove Dummies: time 5.56e-06/0.7674, allocations: 0.9062 kB / 0.759 GB, free: 129.3 MB / 0.5767 GB
Notification: Performance of [SIM] Tearing: time 1.233e-05/0.7675, allocations: 14.59 kB / 0.759 GB, free: 129.3 MB / 0.5767 GB
Notification: Performance of [SIM] Categorize: time 1.331e-05/0.7675, allocations: 0.9375 kB / 0.759 GB, free: 129.3 MB / 0.5767 GB
Notification: Performance of [SIM] Solve: time 0.0008825/0.7684, allocations: 244.3 kB / 0.7592 GB, free: 129.3 MB / 0.5767 GB
Error: Internal error NBJacobian.SparsityColoring.PartialD2ColoringAlgC.getIndices failed because cref QB[1] is neither a seed nor a partial candidate!
"
[Timeout remaining time 299]
[Calling sys.exit(0), Time elapsed: 4.786215886997525]
Failed to read output from testmodel.py, exit status != 0:
0.8831162980059162 0.892838065 0.887331429
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f09a3e52dd0>