Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ScalableTestSuite_ScalableTestSuite.Electrical.BreakerCircuits.ScaledExperiments.BreakerNetworkDelayed_N_40_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.001436/0.001436, allocations: 103.6 kB / 21.25 MB, free: 0.5664 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.001616/0.001616, allocations: 212.5 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.444/1.444, allocations: 230.6 MB / 258.3 MB, free: 7.746 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.146/1.146, allocations: 120.6 MB / 435.3 MB, free: 0.582 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_40_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.40..G|B.40..i|B.40..n.i|B.40..n.v|B.40..open|B.40..p.i|B.40..p.v|B.40..timeClose|B.40..triggered|B.40..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|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.40..G_actual|G.40..LossPower|G.40..i|G.40..n.i|G.40..n.v|G.40..p.i|G.40..p.v|G.40..v|G.41..i|G.41..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.6..i|G.6..v|ground.p.v|time|time",fileNamePrefix="ScalableTestSuite_ScalableTestSuite.Electrical.BreakerCircuits.ScaledExperiments.BreakerNetworkDelayed_N_40_M_10") translateModel(ScalableTestSuite.Electrical.BreakerCircuits.ScaledExperiments.BreakerNetworkDelayed_N_40_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.40..G|B.40..i|B.40..n.i|B.40..n.v|B.40..open|B.40..p.i|B.40..p.v|B.40..timeClose|B.40..triggered|B.40..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|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.40..G_actual|G.40..LossPower|G.40..i|G.40..n.i|G.40..n.v|G.40..p.i|G.40..p.v|G.40..v|G.41..i|G.41..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.6..i|G.6..v|ground.p.v|time|time",fileNamePrefix="ScalableTestSuite_ScalableTestSuite.Electrical.BreakerCircuits.ScaledExperiments.BreakerNetworkDelayed_N_40_M_10") [Timeout 300] "Notification: Performance of FrontEnd - Absyn->SCode: time 2.382e-05/2.382e-05, allocations: 4.531 kB / 0.608 GB, free: 10.23 MB / 446.3 MB Notification: Performance of NFInst.instantiate(ScalableTestSuite.Electrical.BreakerCircuits.ScaledExperiments.BreakerNetworkDelayed_N_40_M_10): time 0.002847/0.002871, allocations: 2.04 MB / 0.61 GB, free: 8.172 MB / 446.3 MB Notification: Performance of NFInst.instExpressions: time 0.0009445/0.003816, allocations: 0.565 MB / 0.6106 GB, free: 7.602 MB / 446.3 MB Notification: Performance of NFInst.updateImplicitVariability: time 9.994e-05/0.003915, allocations: 7.938 kB / 0.6106 GB, free: 7.594 MB / 446.3 MB Notification: Performance of NFTyping.typeComponents: time 0.0001456/0.004061, allocations: 19.78 kB / 0.6106 GB, free: 7.574 MB / 446.3 MB Notification: Performance of NFTyping.typeBindings: time 0.0002465/0.004308, allocations: 47.62 kB / 0.6106 GB, free: 7.527 MB / 446.3 MB Notification: Performance of NFTyping.typeClassSections: time 0.0002468/0.004554, allocations: 71.83 kB / 0.6107 GB, free: 7.457 MB / 446.3 MB Notification: Performance of NFFlatten.flatten: time 0.0006226/0.005177, allocations: 0.7903 MB / 0.6115 GB, free: 6.664 MB / 446.3 MB Notification: Performance of NFFlatten.resolveConnections: time 0.000777/0.005954, allocations: 0.7053 MB / 0.6122 GB, free: 5.938 MB / 446.3 MB Notification: Performance of NFEvalConstants.evaluate: time 0.0002618/0.006216, allocations: 355.5 kB / 0.6125 GB, free: 5.59 MB / 446.3 MB Notification: Performance of NFSimplifyModel.simplify: time 0.000291/0.006507, allocations: 367.4 kB / 0.6129 GB, free: 5.23 MB / 446.3 MB Notification: Performance of NFPackage.collectConstants: time 0.0001056/0.006612, allocations: 92 kB / 0.6129 GB, free: 5.141 MB / 446.3 MB Notification: Performance of NFFlatten.collectFunctions: time 0.0001307/0.006743, allocations: 92 kB / 0.613 GB, free: 5.051 MB / 446.3 MB Notification: Performance of combineBinaries: time 0.0004408/0.007184, allocations: 0.7154 MB / 0.6137 GB, free: 4.332 MB / 446.3 MB Notification: Performance of replaceArrayConstructors: time 0.0001167/0.007301, allocations: 269.4 kB / 0.614 GB, free: 4.066 MB / 446.3 MB Notification: Performance of NFVerifyModel.verify: time 7.588e-05/0.007376, allocations: 47.94 kB / 0.614 GB, free: 4.02 MB / 446.3 MB Notification: Performance of FrontEnd: time 3.539e-05/0.007412, allocations: 4 kB / 0.614 GB, free: 4.016 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: 780 (187) * Number of variables: 780 (30) Notification: Performance of [SIM] Bindings: time 0.001832/0.009244, allocations: 2.584 MB / 0.6166 GB, free: 1.289 MB / 446.3 MB Notification: Performance of [SIM] FunctionAlias: time 0.000287/0.009531, allocations: 464.8 kB / 0.617 GB, free: 0.8203 MB / 446.3 MB Notification: Performance of [SIM] Early Inline: time 0.002219/0.01175, allocations: 2.487 MB / 0.6194 GB, free: 14.29 MB / 462.3 MB Notification: Performance of [SIM] Simplify 1: time 0.0006223/0.01237, allocations: 420.2 kB / 0.6198 GB, free: 13.82 MB / 462.3 MB Notification: Performance of [SIM] Alias: time 0.00221/0.01458, allocations: 1.618 MB / 0.6214 GB, free: 12.12 MB / 462.3 MB Notification: Performance of [SIM] Simplify 2: time 0.0005873/0.01517, allocations: 462.3 kB / 0.6219 GB, free: 11.61 MB / 462.3 MB Notification: Performance of [SIM] Remove Stream: time 0.0003669/0.01554, allocations: 300.2 kB / 0.6221 GB, free: 11.28 MB / 462.3 MB Notification: Performance of [SIM] Detect States: time 0.0006991/0.01624, allocations: 0.598 MB / 0.6227 GB, free: 10.63 MB / 462.3 MB Notification: Performance of [SIM] Events: time 0.0002214/0.01646, allocations: 121 kB / 0.6228 GB, free: 10.51 MB / 462.3 MB Notification: Performance of [SIM] Partitioning: time 0.001146/0.0176, allocations: 1.07 MB / 0.6239 GB, free: 9.426 MB / 462.3 MB Notification: Performance of [SIM] Causalize: time 0.0343/0.05191, allocations: 32.86 MB / 0.656 GB, free: 8.469 MB / 494.3 MB Notification: Performance of [SIM] After Index Reduction Inline: time 0.002499/0.05441, allocations: 2.455 MB / 0.6584 GB, free: 6.012 MB / 494.3 MB Notification: Performance of [INI] Simplify: time 0.001612/0.05602, allocations: 0.9073 MB / 0.6593 GB, free: 5.016 MB / 494.3 MB Notification: Performance of [INI] Inline: time 0.003575/0.05959, allocations: 3.677 MB / 0.6628 GB, free: 1.328 MB / 494.3 MB Notification: Performance of [INI] Partitioning: time 9.595e-05/0.05969, allocations: 45.39 kB / 0.6629 GB, free: 1.273 MB / 494.3 MB Notification: Performance of [INI] Cleanup: time 0.0006982/0.06039, allocations: 0.5348 MB / 0.6634 GB, free: 0.7383 MB / 494.3 MB Notification: Performance of [INI] Causalize: time 0.04238/0.1028, allocations: 35.7 MB / 0.6983 GB, free: 12.84 MB / 0.5295 GB Notification: Performance of [INI] Tearing: time 0.0009252/0.1037, allocations: 0.5912 MB / 0.6989 GB, free: 12.25 MB / 0.5295 GB Notification: Performance of [SIM] Initialization: time 1.823e-06/0.1037, allocations: 0 / 0.6989 GB, free: 12.25 MB / 0.5295 GB Notification: Performance of [SIM] DAE-Mode: time 0.001352/0.105, allocations: 0.7576 MB / 0.6996 GB, free: 11.47 MB / 0.5295 GB Notification: Performance of [SIM] Remove Dummies: time 8.255e-06/0.105, allocations: 0.5 kB / 0.6996 GB, free: 11.47 MB / 0.5295 GB Notification: Performance of [SIM] Tearing: time 0.0007556/0.1058, allocations: 0.6772 MB / 0.7003 GB, free: 10.79 MB / 0.5295 GB Notification: Performance of [SIM] Categorize: time 1.663e-05/0.1058, allocations: 4 kB / 0.7003 GB, free: 10.79 MB / 0.5295 GB Notification: Performance of [SIM] Solve: time 0.003064/0.1089, allocations: 1.877 MB / 0.7021 GB, free: 8.902 MB / 0.5295 GB Error: Internal error NBJacobian.SparsityColoring.PartialD2ColoringAlgC.getIndices failed because cref G[41].n.v is neither a seed nor a partial candidate! " [Timeout remaining time 300] [Calling sys.exit(0), Time elapsed: 4.1842241220292635] Failed to read output from testmodel.py, exit status != 0: 0.11957551498198882 0.13407138600000001 0.123635536 Calling exit ...