Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ScalableTestSuite_noopt_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.001309/0.001309, allocations: 96.38 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.001445/0.001445, allocations: 206.1 kB / 24.55 MB, free: 3.828 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.43/1.43, allocations: 230.6 MB / 258.3 MB, free: 7.77 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.178/1.178, allocations: 120.6 MB / 435.3 MB, free: 0.6641 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_noopt_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_noopt_ScalableTestSuite.Electrical.BreakerCircuits.ScaledExperiments.BreakerNetworkDelayed_N_40_M_10") [Timeout 300] "Notification: Performance of FrontEnd - Absyn->SCode: time 1.413e-05/1.413e-05, allocations: 2.281 kB / 0.608 GB, free: 10.27 MB / 446.3 MB Notification: Performance of NFInst.instantiate(ScalableTestSuite.Electrical.BreakerCircuits.ScaledExperiments.BreakerNetworkDelayed_N_40_M_10): time 0.003016/0.00303, allocations: 2.049 MB / 0.61 GB, free: 8.215 MB / 446.3 MB Notification: Performance of NFInst.instExpressions: time 0.001123/0.004153, allocations: 0.5611 MB / 0.6106 GB, free: 7.648 MB / 446.3 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.0001007/0.004254, allocations: 4 kB / 0.6106 GB, free: 7.645 MB / 446.3 MB Notification: Performance of NFTyping.typeComponents: time 0.0001592/0.004413, allocations: 27.72 kB / 0.6106 GB, free: 7.617 MB / 446.3 MB Notification: Performance of NFTyping.typeBindings: time 0.0002632/0.004676, allocations: 43.69 kB / 0.6106 GB, free: 7.574 MB / 446.3 MB Notification: Performance of NFTyping.typeClassSections: time 0.0002802/0.004956, allocations: 71.83 kB / 0.6107 GB, free: 7.504 MB / 446.3 MB Notification: Performance of NFFlatten.flatten: time 0.0007875/0.005744, allocations: 0.7864 MB / 0.6115 GB, free: 6.715 MB / 446.3 MB Notification: Performance of NFFlatten.resolveConnections: time 0.0007515/0.006495, allocations: 0.7014 MB / 0.6122 GB, free: 5.992 MB / 446.3 MB Notification: Performance of NFEvalConstants.evaluate: time 0.0002202/0.006715, allocations: 359.4 kB / 0.6125 GB, free: 5.641 MB / 446.3 MB Notification: Performance of NFSimplifyModel.simplify: time 0.0002793/0.006995, allocations: 363.4 kB / 0.6129 GB, free: 5.285 MB / 446.3 MB Notification: Performance of NFPackage.collectConstants: time 0.000102/0.007097, allocations: 92 kB / 0.6129 GB, free: 5.195 MB / 446.3 MB Notification: Performance of NFFlatten.collectFunctions: time 0.0001265/0.007223, allocations: 99.98 kB / 0.613 GB, free: 5.098 MB / 446.3 MB Notification: Performance of combineBinaries: time 0.0004293/0.007652, allocations: 0.7154 MB / 0.6137 GB, free: 4.379 MB / 446.3 MB Notification: Performance of replaceArrayConstructors: time 0.000115/0.007767, allocations: 265.4 kB / 0.614 GB, free: 4.117 MB / 446.3 MB Notification: Performance of NFVerifyModel.verify: time 6.948e-05/0.007837, allocations: 51.94 kB / 0.614 GB, free: 4.066 MB / 446.3 MB Notification: Performance of FrontEnd: time 2.26e-05/0.007859, allocations: 0 / 0.614 GB, free: 4.066 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.001855/0.009715, allocations: 2.588 MB / 0.6166 GB, free: 1.336 MB / 446.3 MB Notification: Performance of [SIM] FunctionAlias: time 0.0002492/0.009964, allocations: 464.8 kB / 0.617 GB, free: 0.8672 MB / 446.3 MB Notification: Performance of [SIM] Early Inline: time 0.002064/0.01203, allocations: 2.483 MB / 0.6194 GB, free: 14.34 MB / 462.3 MB Notification: Performance of [SIM] Simplify 1: time 0.0006436/0.01267, allocations: 420.2 kB / 0.6198 GB, free: 13.88 MB / 462.3 MB Notification: Performance of [SIM] Alias: time 0.002054/0.01473, allocations: 1.625 MB / 0.6214 GB, free: 12.17 MB / 462.3 MB Notification: Performance of [SIM] Simplify 2: time 0.0005841/0.01531, allocations: 458.3 kB / 0.6219 GB, free: 11.66 MB / 462.3 MB Notification: Performance of [SIM] Remove Stream: time 0.0003782/0.01569, allocations: 304.1 kB / 0.6221 GB, free: 11.32 MB / 462.3 MB Notification: Performance of [SIM] Detect States: time 0.0007061/0.01639, allocations: 0.5996 MB / 0.6227 GB, free: 10.67 MB / 462.3 MB Notification: Performance of [SIM] Events: time 0.0001955/0.01659, allocations: 117.1 kB / 0.6228 GB, free: 10.56 MB / 462.3 MB Notification: Performance of [SIM] Partitioning: time 0.001177/0.01777, allocations: 1.074 MB / 0.6239 GB, free: 9.469 MB / 462.3 MB Notification: Performance of [SIM] Causalize: time 0.03217/0.04994, allocations: 32.85 MB / 0.656 GB, free: 8.516 MB / 494.3 MB Notification: Performance of [SIM] After Index Reduction Inline: time 0.00257/0.05251, allocations: 2.459 MB / 0.6584 GB, free: 6.055 MB / 494.3 MB Notification: Performance of [INI] Simplify: time 0.001597/0.05411, allocations: 0.9112 MB / 0.6593 GB, free: 5.055 MB / 494.3 MB Notification: Performance of [INI] Inline: time 0.003521/0.05763, allocations: 3.669 MB / 0.6628 GB, free: 1.375 MB / 494.3 MB Notification: Performance of [INI] Partitioning: time 7.926e-05/0.05771, allocations: 41.41 kB / 0.6629 GB, free: 1.324 MB / 494.3 MB Notification: Performance of [INI] Cleanup: time 0.0005247/0.05823, allocations: 0.5426 MB / 0.6634 GB, free: 0.7812 MB / 494.3 MB Notification: Performance of [INI] Causalize: time 0.03817/0.0964, allocations: 35.7 MB / 0.6983 GB, free: 12.89 MB / 0.5295 GB Notification: Performance of [INI] Tearing: time 0.0008933/0.09729, allocations: 0.5912 MB / 0.6989 GB, free: 12.3 MB / 0.5295 GB Notification: Performance of [SIM] Initialization: time 2.184e-06/0.0973, allocations: 0 / 0.6989 GB, free: 12.3 MB / 0.5295 GB Notification: Performance of [SIM] DAE-Mode: time 0.001315/0.09861, allocations: 0.7538 MB / 0.6996 GB, free: 11.52 MB / 0.5295 GB Notification: Performance of [SIM] Remove Dummies: time 6.612e-06/0.09862, allocations: 0.5 kB / 0.6996 GB, free: 11.52 MB / 0.5295 GB Notification: Performance of [SIM] Tearing: time 0.0007527/0.09937, allocations: 0.6849 MB / 0.7003 GB, free: 10.84 MB / 0.5295 GB Notification: Performance of [SIM] Categorize: time 1.41e-05/0.09938, allocations: 4 kB / 0.7003 GB, free: 10.83 MB / 0.5295 GB Notification: Performance of [SIM] Solve: time 0.002972/0.1024, allocations: 1.869 MB / 0.7021 GB, free: 8.957 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.096300374018028] Failed to read output from testmodel.py, exit status != 0: 0.11251793702831492 0.12771696000000002 0.116696063 Calling exit ...