Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ScalableTestSuite_ScalableTestSuite.Electrical.BreakerCircuits.ScaledExperiments.BreakerNetworkDelayed_N_640_M_10.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.001493/0.001493, allocations: 103.6 kB / 21.25 MB, free: 420 kB / 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.001763/0.001763, allocations: 204.2 kB / 24.54 MB, free: 3.727 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.487/1.487, allocations: 230.6 MB / 258.3 MB, free: 7.781 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.157/1.157, allocations: 120.6 MB / 435.3 MB, free: 0.6484 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.Electrical.BreakerCircuits.ScaledExperiments.BreakerNetworkDelayed_N_640_M_10,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="B.1..G|B.1..i|B.1..n.i|B.1..n.v|B.1..open|B.1..p.i|B.1..p.v|B.1..timeClose|B.1..triggered|B.1..v|B.2..G|B.2..i|B.2..n.i|B.2..n.v|B.2..open|B.2..p.i|B.2..p.v|B.2..timeClose|B.2..triggered|B.2..v|B.3..G|B.3..i|B.3..n.i|B.3..n.v|B.3..open|B.3..p.i|B.3..p.v|B.3..timeClose|B.3..triggered|B.3..v|B.4..G|B.4..i|B.4..n.i|B.4..n.v|B.4..open|B.4..p.i|B.4..p.v|B.4..timeClose|B.4..triggered|B.4..v|B.5..G|B.5..i|B.5..n.i|B.5..n.v|B.5..open|B.5..p.i|B.5..p.v|B.5..timeClose|B.5..triggered|B.5..v|B.640..G|B.640..i|B.640..n.i|B.640..n.v|B.640..open|B.640..p.i|B.640..p.v|B.640..timeClose|B.640..triggered|B.640..v|G.1..G_actual|G.1..LossPower|G.1..i|G.1..n.i|G.1..n.v|G.1..p.i|G.1..p.v|G.1..v|G.2..G_actual|G.2..LossPower|G.2..i|G.2..n.i|G.2..n.v|G.2..p.i|G.2..p.v|G.2..v|G.3..G_actual|G.3..LossPower|G.3..i|G.3..n.i|G.3..n.v|G.3..p.i|G.3..p.v|G.3..v|G.4..G_actual|G.4..LossPower|G.4..i|G.4..n.i|G.4..n.v|G.4..p.i|G.4..p.v|G.4..v|G.5..G_actual|G.5..LossPower|G.5..i|G.5..n.i|G.5..n.v|G.5..p.i|G.5..p.v|G.5..v|G.640..G_actual|G.640..LossPower|G.640..i|G.640..n.i|G.640..n.v|G.640..p.i|G.640..p.v|G.640..v|G.641..i|G.641..v|G.6..i|G.6..v|ground.p.v|time|time",fileNamePrefix="ScalableTestSuite_ScalableTestSuite.Electrical.BreakerCircuits.ScaledExperiments.BreakerNetworkDelayed_N_640_M_10")
translateModel(ScalableTestSuite.Electrical.BreakerCircuits.ScaledExperiments.BreakerNetworkDelayed_N_640_M_10,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="B.1..G|B.1..i|B.1..n.i|B.1..n.v|B.1..open|B.1..p.i|B.1..p.v|B.1..timeClose|B.1..triggered|B.1..v|B.2..G|B.2..i|B.2..n.i|B.2..n.v|B.2..open|B.2..p.i|B.2..p.v|B.2..timeClose|B.2..triggered|B.2..v|B.3..G|B.3..i|B.3..n.i|B.3..n.v|B.3..open|B.3..p.i|B.3..p.v|B.3..timeClose|B.3..triggered|B.3..v|B.4..G|B.4..i|B.4..n.i|B.4..n.v|B.4..open|B.4..p.i|B.4..p.v|B.4..timeClose|B.4..triggered|B.4..v|B.5..G|B.5..i|B.5..n.i|B.5..n.v|B.5..open|B.5..p.i|B.5..p.v|B.5..timeClose|B.5..triggered|B.5..v|B.640..G|B.640..i|B.640..n.i|B.640..n.v|B.640..open|B.640..p.i|B.640..p.v|B.640..timeClose|B.640..triggered|B.640..v|G.1..G_actual|G.1..LossPower|G.1..i|G.1..n.i|G.1..n.v|G.1..p.i|G.1..p.v|G.1..v|G.2..G_actual|G.2..LossPower|G.2..i|G.2..n.i|G.2..n.v|G.2..p.i|G.2..p.v|G.2..v|G.3..G_actual|G.3..LossPower|G.3..i|G.3..n.i|G.3..n.v|G.3..p.i|G.3..p.v|G.3..v|G.4..G_actual|G.4..LossPower|G.4..i|G.4..n.i|G.4..n.v|G.4..p.i|G.4..p.v|G.4..v|G.5..G_actual|G.5..LossPower|G.5..i|G.5..n.i|G.5..n.v|G.5..p.i|G.5..p.v|G.5..v|G.640..G_actual|G.640..LossPower|G.640..i|G.640..n.i|G.640..n.v|G.640..p.i|G.640..p.v|G.640..v|G.641..i|G.641..v|G.6..i|G.6..v|ground.p.v|time|time",fileNamePrefix="ScalableTestSuite_ScalableTestSuite.Electrical.BreakerCircuits.ScaledExperiments.BreakerNetworkDelayed_N_640_M_10") [Timeout 300]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.319e-05/2.319e-05, allocations: 4.25 kB / 0.608 GB, free: 10.25 MB / 446.3 MB
Notification: Performance of NFInst.instantiate(ScalableTestSuite.Electrical.BreakerCircuits.ScaledExperiments.BreakerNetworkDelayed_N_640_M_10): time 0.002864/0.002887, allocations: 2.042 MB / 0.61 GB, free: 8.195 MB / 446.3 MB
Notification: Performance of NFInst.instExpressions: time 0.0009205/0.003807, allocations: 0.5727 MB / 0.6106 GB, free: 7.617 MB / 446.3 MB
Notification: Performance of NFInst.updateImplicitVariability: time 9.502e-05/0.003903, allocations: 4 kB / 0.6106 GB, free: 7.613 MB / 446.3 MB
Notification: Performance of NFTyping.typeComponents: time 0.0001477/0.00405, allocations: 23.78 kB / 0.6106 GB, free: 7.59 MB / 446.3 MB
Notification: Performance of NFTyping.typeBindings: time 0.0002509/0.004301, allocations: 51.61 kB / 0.6106 GB, free: 7.539 MB / 446.3 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0002552/0.004556, allocations: 75.83 kB / 0.6107 GB, free: 7.465 MB / 446.3 MB
Notification: Performance of NFFlatten.flatten: time 0.003127/0.007683, allocations: 5.977 MB / 0.6166 GB, free: 1.477 MB / 446.3 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.02233/0.03001, allocations: 10.93 MB / 0.6272 GB, free: 6.453 MB / 462.3 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.004453/0.03446, allocations: 3.229 MB / 0.6304 GB, free: 3.223 MB / 462.3 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.006127/0.04059, allocations: 3.403 MB / 0.6337 GB, free: 15.82 MB / 478.3 MB
Notification: Performance of NFPackage.collectConstants: time 0.002784/0.04337, allocations: 1.191 MB / 0.6349 GB, free: 14.62 MB / 478.3 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003286/0.04666, allocations: 1.188 MB / 0.636 GB, free: 13.44 MB / 478.3 MB
Notification: Performance of combineBinaries: time 0.009391/0.05605, allocations: 5.543 MB / 0.6414 GB, free: 7.887 MB / 478.3 MB
Notification: Performance of replaceArrayConstructors: time 0.0007623/0.05681, allocations: 341.4 kB / 0.6418 GB, free: 7.551 MB / 478.3 MB
Notification: Performance of NFVerifyModel.verify: time 0.002667/0.05948, allocations: 496 kB / 0.6422 GB, free: 7.066 MB / 478.3 MB
Notification: Performance of FrontEnd: time 8.327e-05/0.05956, allocations: 4 kB / 0.6422 GB, free: 7.062 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: 12180 (2587)
 * Number of variables: 12180 (30)
Notification: Performance of [SIM] Bindings: time 0.03346/0.09302, allocations: 21.29 MB / 0.663 GB, free: 1.457 MB / 494.3 MB
Notification: Performance of [SIM] FunctionAlias: time 0.0121/0.1051, allocations: 5.589 MB / 0.6685 GB, free: 11.84 MB / 0.4983 GB
Notification: Performance of [SIM] Early Inline: time 0.05132/0.1564, allocations: 32.02 MB / 0.6998 GB, free: 11.66 MB / 0.5295 GB
Notification: Performance of [SIM] Simplify 1: time 0.009588/0.166, allocations: 4.14 MB / 0.7038 GB, free: 7.363 MB / 0.5295 GB
Notification: Performance of [SIM] Alias: time 0.04151/0.2075, allocations: 18.62 MB / 0.722 GB, free: 4.543 MB / 0.5452 GB
Notification: Performance of [SIM] Simplify 2: time 0.01124/0.2188, allocations: 4.99 MB / 0.7269 GB, free: 15.36 MB / 0.5608 GB
Notification: Performance of [SIM] Remove Stream: time 0.007366/0.2261, allocations: 3.348 MB / 0.7301 GB, free: 11.87 MB / 0.5608 GB
Notification: Performance of [SIM] Detect States: time 0.01251/0.2386, allocations: 6.431 MB / 0.7364 GB, free: 5.379 MB / 0.5608 GB
Notification: Performance of [SIM] Events: time 0.001564/0.2402, allocations: 0.6933 MB / 0.7371 GB, free: 4.68 MB / 0.5608 GB
Notification: Performance of [SIM] Partitioning: time 0.02746/0.2677, allocations: 13.52 MB / 0.7503 GB, free: 7.078 MB / 0.5764 GB
Notification: Performance of [SIM] Causalize: time 23.62/23.89, allocations: 10.02 GB / 10.77 GB, free: 0.6087 GB / 1.893 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.04444/23.94, allocations: 30.88 MB / 10.8 GB, free: 0.5911 GB / 1.893 GB
Notification: Performance of [INI] Simplify: time 0.02425/23.96, allocations: 9.384 MB / 10.8 GB, free: 0.5854 GB / 1.893 GB
Notification: Performance of [INI] Inline: time 0.07173/24.03, allocations: 46.66 MB / 10.85 GB, free: 0.5537 GB / 1.893 GB
Notification: Performance of [INI] Partitioning: time 0.002897/24.03, allocations: 429.5 kB / 10.85 GB, free: 0.5535 GB / 1.893 GB
Notification: Performance of [INI] Cleanup: time 0.01708/24.05, allocations: 6.803 MB / 10.86 GB, free: 0.5481 GB / 1.893 GB
Notification: Performance of [INI] Causalize: time 22.57/46.62, allocations: 10.58 GB / 21.44 GB, free: 281.9 MB / 1.958 GB
Notification: Performance of [INI] Tearing: time 0.01297/46.64, allocations: 8.583 MB / 21.45 GB, free: 276.3 MB / 1.958 GB
Notification: Performance of [SIM] Initialization: time 3.086e-06/46.64, allocations: 0 / 21.45 GB, free: 276.3 MB / 1.958 GB
Notification: Performance of [SIM] DAE-Mode: time 0.02129/46.66, allocations: 10.31 MB / 21.46 GB, free: 267.9 MB / 1.958 GB
Notification: Performance of [SIM] Remove Dummies: time 2.133e-05/46.66, allocations: 8.172 kB / 21.46 GB, free: 267.9 MB / 1.958 GB
Notification: Performance of [SIM] Tearing: time 0.01275/46.67, allocations: 9.849 MB / 21.47 GB, free: 258 MB / 1.958 GB
Notification: Performance of [SIM] Categorize: time 2.24e-05/46.67, allocations: 0 / 21.47 GB, free: 258 MB / 1.958 GB
Notification: Performance of [SIM] Solve: time 0.04372/46.71, allocations: 24.33 MB / 21.49 GB, free: 233.7 MB / 1.958 GB
Error: Internal error NBJacobian.SparsityColoring.PartialD2ColoringAlgC.getIndices failed because cref G[641].n.v is neither a seed nor a partial candidate!
"
[Timeout remaining time 253]
[Calling sys.exit(0), Time elapsed: 50.87406354700215]
Failed to read output from testmodel.py, exit status != 0:
46.76923315401655 46.779661201 46.717603588
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f334a9eadd0>