Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ScalableTestSuite_ScalableTestSuite.Electrical.DistributionSystemDC.ScaledExperiments.DistributionSystemModelicaActiveLoads_N_28_M_28.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.001438/0.001438, allocations: 95.66 kB / 21.25 MB, free: 428 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.001582/0.001582, allocations: 207.2 kB / 24.55 MB, free: 3.73 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.526/1.526, allocations: 230.6 MB / 258.3 MB, free: 7.738 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.354/1.354, allocations: 120.6 MB / 435.3 MB, free: 0.6133 MB / 334.1 MB " [Timeout remaining time 178] 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.DistributionSystemDC.ScaledExperiments.DistributionSystemModelicaActiveLoads_N_28_M_28,tolerance=1e-06,outputFormat="mat",numberOfIntervals=3000,variableFilter="ground.1,1..p.i|ground.1,1..p.v|ground.1,2..p.i|ground.1,2..p.v|ground.2,1..p.i|ground.2,1..p.v|ground.2,2..p.i|ground.2,2..p.v|ground.28,28..p.i|ground.28,28..p.v|ground.3,1..p.i|ground.3,1..p.v|ground.3,2..p.i|ground.3,2..p.v|ground.4,1..p.i|ground.4,1..p.v|ground.4,2..p.i|ground.4,2..p.v|load.1,1..LossPower|load.1,1..R_actual|load.1,1..i|load.1,1..n.i|load.1,1..n.v|load.1,1..p.i|load.1,1..p.v|load.1,1..v|load.1,2..LossPower|load.1,2..R_actual|load.1,2..i|load.1,2..n.i|load.1,2..n.v|load.1,2..p.i|load.1,2..p.v|load.1,2..v|load.2,1..LossPower|load.2,1..R_actual|load.2,1..i|load.2,1..n.i|load.2,1..n.v|load.2,1..p.i|load.2,1..p.v|load.2,1..v|load.2,2..LossPower|load.2,2..R_actual|load.2,2..i|load.2,2..n.i|load.2,2..n.v|load.2,2..p.i|load.2,2..p.v|load.2,2..v|load.28,27..v|load.28,28..LossPower|load.28,28..R_actual|load.28,28..i|load.28,28..n.i|load.28,28..n.v|load.28,28..p.i|load.28,28..p.v|load.28,28..v|load.3,1..LossPower|load.3,1..R_actual|load.3,1..i|load.3,1..n.i|load.3,1..n.v|load.3,1..p.i|load.3,1..p.v|load.3,1..v|load.3,2..LossPower|load.3,2..R_actual|load.3,2..i|load.3,2..n.i|load.3,2..n.v|load.3,2..p.i|load.3,2..p.v|load.3,2..v|load.4,1..LossPower|load.4,1..R_actual|load.4,1..i|load.4,1..n.i|load.4,1..n.v|load.4,1..p.i|load.4,1..p.v|load.4,1..v|load.4,2..LossPower|load.4,2..R_actual|load.4,2..i|load.4,2..n.i|load.4,2..n.v|load.4,2..p.i|load.4,2..p.v|load.4,2..v|primary.1..n.v|primary.2..n.v|primary.3..n.v|primary.4..n.v|secondary.1,1..LossPower|secondary.1,1..R_actual|secondary.1,1..i|secondary.1,1..n.i|secondary.1,1..n.v|secondary.1,1..p.i|secondary.1,1..p.v|secondary.1,1..v|secondary.1,2..LossPower|secondary.1,2..R_actual|secondary.1,2..i|secondary.1,2..n.i|secondary.1,2..n.v|secondary.1,2..p.i|secondary.1,2..p.v|secondary.1,2..v|secondary.2,1..LossPower|secondary.2,1..R_actual|secondary.2,1..i|secondary.2,1..n.i|secondary.2,1..n.v|secondary.2,1..p.i|secondary.2,1..p.v|secondary.2,1..v|secondary.2,2..LossPower|secondary.2,2..R_actual|secondary.2,2..i|secondary.2,2..n.i|secondary.2,2..n.v|secondary.2,2..p.i|secondary.2,2..p.v|secondary.2,2..v|secondary.28,28..LossPower|secondary.28,28..R_actual|secondary.28,28..i|secondary.28,28..n.i|secondary.28,28..n.v|secondary.28,28..p.i|secondary.28,28..p.v|secondary.28,28..v|secondary.3,1..LossPower|secondary.3,1..R_actual|secondary.3,1..i|secondary.3,1..n.i|secondary.3,1..n.v|secondary.3,1..p.i|secondary.3,1..p.v|secondary.3,1..v|secondary.3,2..LossPower|secondary.3,2..R_actual|secondary.3,2..i|secondary.3,2..n.i|secondary.3,2..n.v|secondary.3,2..p.i|secondary.3,2..p.v|secondary.3,2..v|secondary.4,1..LossPower|secondary.4,1..R_actual|secondary.4,1..i|secondary.4,1..n.i|secondary.4,1..n.v|secondary.4,1..p.i|secondary.4,1..p.v|secondary.4,1..v|secondary.4,2..LossPower|secondary.4,2..R_actual|secondary.4,2..i|secondary.4,2..n.i|secondary.4,2..n.v|secondary.4,2..p.i|secondary.4,2..p.v|secondary.4,2..v|time|time",fileNamePrefix="ScalableTestSuite_ScalableTestSuite.Electrical.DistributionSystemDC.ScaledExperiments.DistributionSystemModelicaActiveLoads_N_28_M_28") translateModel(ScalableTestSuite.Electrical.DistributionSystemDC.ScaledExperiments.DistributionSystemModelicaActiveLoads_N_28_M_28,tolerance=1e-06,outputFormat="mat",numberOfIntervals=3000,variableFilter="ground.1,1..p.i|ground.1,1..p.v|ground.1,2..p.i|ground.1,2..p.v|ground.2,1..p.i|ground.2,1..p.v|ground.2,2..p.i|ground.2,2..p.v|ground.28,28..p.i|ground.28,28..p.v|ground.3,1..p.i|ground.3,1..p.v|ground.3,2..p.i|ground.3,2..p.v|ground.4,1..p.i|ground.4,1..p.v|ground.4,2..p.i|ground.4,2..p.v|load.1,1..LossPower|load.1,1..R_actual|load.1,1..i|load.1,1..n.i|load.1,1..n.v|load.1,1..p.i|load.1,1..p.v|load.1,1..v|load.1,2..LossPower|load.1,2..R_actual|load.1,2..i|load.1,2..n.i|load.1,2..n.v|load.1,2..p.i|load.1,2..p.v|load.1,2..v|load.2,1..LossPower|load.2,1..R_actual|load.2,1..i|load.2,1..n.i|load.2,1..n.v|load.2,1..p.i|load.2,1..p.v|load.2,1..v|load.2,2..LossPower|load.2,2..R_actual|load.2,2..i|load.2,2..n.i|load.2,2..n.v|load.2,2..p.i|load.2,2..p.v|load.2,2..v|load.28,27..v|load.28,28..LossPower|load.28,28..R_actual|load.28,28..i|load.28,28..n.i|load.28,28..n.v|load.28,28..p.i|load.28,28..p.v|load.28,28..v|load.3,1..LossPower|load.3,1..R_actual|load.3,1..i|load.3,1..n.i|load.3,1..n.v|load.3,1..p.i|load.3,1..p.v|load.3,1..v|load.3,2..LossPower|load.3,2..R_actual|load.3,2..i|load.3,2..n.i|load.3,2..n.v|load.3,2..p.i|load.3,2..p.v|load.3,2..v|load.4,1..LossPower|load.4,1..R_actual|load.4,1..i|load.4,1..n.i|load.4,1..n.v|load.4,1..p.i|load.4,1..p.v|load.4,1..v|load.4,2..LossPower|load.4,2..R_actual|load.4,2..i|load.4,2..n.i|load.4,2..n.v|load.4,2..p.i|load.4,2..p.v|load.4,2..v|primary.1..n.v|primary.2..n.v|primary.3..n.v|primary.4..n.v|secondary.1,1..LossPower|secondary.1,1..R_actual|secondary.1,1..i|secondary.1,1..n.i|secondary.1,1..n.v|secondary.1,1..p.i|secondary.1,1..p.v|secondary.1,1..v|secondary.1,2..LossPower|secondary.1,2..R_actual|secondary.1,2..i|secondary.1,2..n.i|secondary.1,2..n.v|secondary.1,2..p.i|secondary.1,2..p.v|secondary.1,2..v|secondary.2,1..LossPower|secondary.2,1..R_actual|secondary.2,1..i|secondary.2,1..n.i|secondary.2,1..n.v|secondary.2,1..p.i|secondary.2,1..p.v|secondary.2,1..v|secondary.2,2..LossPower|secondary.2,2..R_actual|secondary.2,2..i|secondary.2,2..n.i|secondary.2,2..n.v|secondary.2,2..p.i|secondary.2,2..p.v|secondary.2,2..v|secondary.28,28..LossPower|secondary.28,28..R_actual|secondary.28,28..i|secondary.28,28..n.i|secondary.28,28..n.v|secondary.28,28..p.i|secondary.28,28..p.v|secondary.28,28..v|secondary.3,1..LossPower|secondary.3,1..R_actual|secondary.3,1..i|secondary.3,1..n.i|secondary.3,1..n.v|secondary.3,1..p.i|secondary.3,1..p.v|secondary.3,1..v|secondary.3,2..LossPower|secondary.3,2..R_actual|secondary.3,2..i|secondary.3,2..n.i|secondary.3,2..n.v|secondary.3,2..p.i|secondary.3,2..p.v|secondary.3,2..v|secondary.4,1..LossPower|secondary.4,1..R_actual|secondary.4,1..i|secondary.4,1..n.i|secondary.4,1..n.v|secondary.4,1..p.i|secondary.4,1..p.v|secondary.4,1..v|secondary.4,2..LossPower|secondary.4,2..R_actual|secondary.4,2..i|secondary.4,2..n.i|secondary.4,2..n.v|secondary.4,2..p.i|secondary.4,2..p.v|secondary.4,2..v|time|time",fileNamePrefix="ScalableTestSuite_ScalableTestSuite.Electrical.DistributionSystemDC.ScaledExperiments.DistributionSystemModelicaActiveLoads_N_28_M_28") [Timeout 300] "Notification: Performance of FrontEnd - Absyn->SCode: time 2.422e-05/2.422e-05, allocations: 2.281 kB / 0.608 GB, free: 10.2 MB / 446.3 MB Notification: Performance of NFInst.instantiate(ScalableTestSuite.Electrical.DistributionSystemDC.ScaledExperiments.DistributionSystemModelicaActiveLoads_N_28_M_28): time 0.003631/0.003655, allocations: 2.58 MB / 0.6106 GB, free: 7.605 MB / 446.3 MB Notification: Performance of NFInst.instExpressions: time 0.001098/0.004753, allocations: 0.6584 MB / 0.6112 GB, free: 6.941 MB / 446.3 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.0001319/0.004885, allocations: 7.938 kB / 0.6112 GB, free: 6.934 MB / 446.3 MB Notification: Performance of NFTyping.typeComponents: time 0.0001728/0.005058, allocations: 47.5 kB / 0.6113 GB, free: 6.887 MB / 446.3 MB Notification: Performance of NFTyping.typeBindings: time 0.0001634/0.005221, allocations: 87.27 kB / 0.6113 GB, free: 6.801 MB / 446.3 MB Notification: Performance of NFTyping.typeClassSections: time 0.0002662/0.005487, allocations: 83.88 kB / 0.6114 GB, free: 6.719 MB / 446.3 MB Notification: Performance of NFFlatten.flatten: time 0.006359/0.01185, allocations: 9.343 MB / 0.6205 GB, free: 13.36 MB / 462.3 MB Notification: Performance of NFFlatten.resolveConnections: time 0.03863/0.05048, allocations: 15.82 MB / 0.636 GB, free: 13.45 MB / 478.3 MB Notification: Performance of NFEvalConstants.evaluate: time 0.01045/0.06092, allocations: 5.634 MB / 0.6415 GB, free: 7.816 MB / 478.3 MB Notification: Performance of NFSimplifyModel.simplify: time 0.01214/0.07306, allocations: 5.792 MB / 0.6472 GB, free: 2.02 MB / 478.3 MB Notification: Performance of NFPackage.collectConstants: time 0.005852/0.07892, allocations: 1.727 MB / 0.6488 GB, free: 296 kB / 478.3 MB Notification: Performance of NFFlatten.collectFunctions: time 0.007344/0.08626, allocations: 1.731 MB / 0.6505 GB, free: 14.55 MB / 494.3 MB Notification: Performance of combineBinaries: time 0.01935/0.1056, allocations: 8.94 MB / 0.6593 GB, free: 5.605 MB / 494.3 MB Notification: Performance of replaceArrayConstructors: time 0.001565/0.1072, allocations: 0.6943 MB / 0.6599 GB, free: 4.906 MB / 494.3 MB Notification: Performance of NFVerifyModel.verify: time 0.005549/0.1127, allocations: 0.793 MB / 0.6607 GB, free: 4.113 MB / 494.3 MB Notification: Performance of FrontEnd: time 0.0001426/0.1129, allocations: 11.98 kB / 0.6607 GB, free: 4.102 MB / 494.3 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 16361 (4238) * Number of variables: 16361 (53) Notification: Performance of [SIM] Bindings: time 0.06115/0.174, allocations: 36.54 MB / 0.6964 GB, free: 15.19 MB / 0.5295 GB Notification: Performance of [SIM] FunctionAlias: time 0.02018/0.1942, allocations: 9.677 MB / 0.7059 GB, free: 5.488 MB / 0.5295 GB Notification: Performance of [SIM] Early Inline: time 0.7999/0.9941, allocations: 53.91 MB / 0.7585 GB, free: 105.1 MB / 0.5767 GB Notification: Performance of [SIM] Simplify 1: time 0.02081/1.015, allocations: 7.108 MB / 0.7654 GB, free: 103.9 MB / 0.5767 GB Notification: Performance of [SIM] Alias: time 0.09365/1.109, allocations: 29.8 MB / 0.7945 GB, free: 96.59 MB / 0.5767 GB Notification: Performance of [SIM] Simplify 2: time 0.01891/1.128, allocations: 7.107 MB / 0.8015 GB, free: 93.25 MB / 0.5767 GB Notification: Performance of [SIM] Remove Stream: time 0.01285/1.14, allocations: 5.56 MB / 0.8069 GB, free: 89.81 MB / 0.5767 GB Notification: Performance of [SIM] Detect States: time 0.01818/1.159, allocations: 10.35 MB / 0.817 GB, free: 81.47 MB / 0.5767 GB Notification: Performance of [SIM] Events: time 0.002919/1.161, allocations: 1.589 MB / 0.8186 GB, free: 80.43 MB / 0.5767 GB Notification: Performance of [SIM] Partitioning: time 0.04551/1.207, allocations: 23.48 MB / 0.8415 GB, free: 59.3 MB / 0.5767 GB Notification: Performance of [SIM] Causalize: time 0.2541/1.461, allocations: 139 MB / 0.9772 GB, free: 1.484 MB / 0.6548 GB Notification: Performance of [SIM] After Index Reduction Inline: time 0.5456/2.007, allocations: 51.95 MB / 1.028 GB, free: 202.2 MB / 0.7017 GB Notification: Performance of [INI] Simplify: time 0.04381/2.05, allocations: 14.25 MB / 1.042 GB, free: 199.4 MB / 0.7017 GB Notification: Performance of [INI] Inline: time 0.1092/2.16, allocations: 78.59 MB / 1.119 GB, free: 162.6 MB / 0.7017 GB Notification: Performance of [INI] Partitioning: time 0.004065/2.164, allocations: 0.7251 MB / 1.119 GB, free: 161.9 MB / 0.7017 GB Notification: Performance of [INI] Cleanup: time 0.02548/2.189, allocations: 11.06 MB / 1.13 GB, free: 150.8 MB / 0.7017 GB Notification: Performance of [INI] Causalize: time 0.2566/2.446, allocations: 162.8 MB / 1.289 GB, free: 5.41 MB / 0.7173 GB Notification: Performance of [INI] Tearing: time 0.4485/2.894, allocations: 11.11 MB / 1.3 GB, free: 190 MB / 0.7173 GB Notification: Performance of [SIM] Initialization: time 3.677e-06/2.894, allocations: 0 / 1.3 GB, free: 190 MB / 0.7173 GB Notification: Performance of [SIM] DAE-Mode: time 0.009957/2.904, allocations: 2.345 MB / 1.302 GB, free: 189.3 MB / 0.7173 GB Notification: Performance of [SIM] Remove Dummies: time 2.661e-05/2.904, allocations: 14.75 kB / 1.302 GB, free: 189.3 MB / 0.7173 GB Notification: Performance of [SIM] Tearing: time 0.03625/2.94, allocations: 22.2 MB / 1.324 GB, free: 185.4 MB / 0.7173 GB Notification: Performance of [SIM] Categorize: time 2.638e-05/2.941, allocations: 1.406 kB / 1.324 GB, free: 185.4 MB / 0.7173 GB Notification: Performance of [SIM] Solve: time 0.0513/2.992, allocations: 16.9 MB / 1.34 GB, free: 181.3 MB / 0.7173 GB Error: Internal error NBJacobian.SparsityColoring.PartialD2ColoringAlgC.getIndices failed because cref activeLoad[1].firstOrder1.u is neither a seed nor a partial candidate! " [Timeout remaining time 296] [Calling sys.exit(0), Time elapsed: 8.384358244016767] Failed to read output from testmodel.py, exit status != 0: 3.928562169021461 3.940783141 3.824671904 Calling exit ...