Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ScalableTestSuite_noopt_ScalableTestSuite.Electrical.DistributionSystemDC.ScaledExperiments.DistributionSystemModelica_N_14_M_14.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.001717/0.001717, allocations: 92.42 kB / 21.25 MB, free: 432 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.001876/0.001876, allocations: 208.3 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.579/1.579, allocations: 230.7 MB / 258.3 MB, free: 7.785 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.297/1.297, allocations: 120.6 MB / 435.3 MB, free: 0.6367 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.DistributionSystemModelica_N_14_M_14,tolerance=1e-06,outputFormat="mat",numberOfIntervals=1000,variableFilter="ground.1,1..p.i|ground.1,1..p.v|ground.1,2..p.i|ground.1,2..p.v|ground.14,14..p.i|ground.14,14..p.v|ground.2,1..p.i|ground.2,1..p.v|ground.2,2..p.i|ground.2,2..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.14,13..v|load.14,14..LossPower|load.14,14..R_actual|load.14,14..i|load.14,14..n.i|load.14,14..n.v|load.14,14..p.i|load.14,14..p.v|load.14,14..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.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.14,14..LossPower|secondary.14,14..R_actual|secondary.14,14..i|secondary.14,14..n.i|secondary.14,14..n.v|secondary.14,14..p.i|secondary.14,14..p.v|secondary.14,14..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.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_noopt_ScalableTestSuite.Electrical.DistributionSystemDC.ScaledExperiments.DistributionSystemModelica_N_14_M_14") translateModel(ScalableTestSuite.Electrical.DistributionSystemDC.ScaledExperiments.DistributionSystemModelica_N_14_M_14,tolerance=1e-06,outputFormat="mat",numberOfIntervals=1000,variableFilter="ground.1,1..p.i|ground.1,1..p.v|ground.1,2..p.i|ground.1,2..p.v|ground.14,14..p.i|ground.14,14..p.v|ground.2,1..p.i|ground.2,1..p.v|ground.2,2..p.i|ground.2,2..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.14,13..v|load.14,14..LossPower|load.14,14..R_actual|load.14,14..i|load.14,14..n.i|load.14,14..n.v|load.14,14..p.i|load.14,14..p.v|load.14,14..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.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.14,14..LossPower|secondary.14,14..R_actual|secondary.14,14..i|secondary.14,14..n.i|secondary.14,14..n.v|secondary.14,14..p.i|secondary.14,14..p.v|secondary.14,14..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.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_noopt_ScalableTestSuite.Electrical.DistributionSystemDC.ScaledExperiments.DistributionSystemModelica_N_14_M_14") [Timeout 300] "Notification: Performance of FrontEnd - Absyn->SCode: time 2.329e-05/2.329e-05, allocations: 2.281 kB / 0.608 GB, free: 10.25 MB / 446.3 MB Notification: Performance of NFInst.instantiate(ScalableTestSuite.Electrical.DistributionSystemDC.ScaledExperiments.DistributionSystemModelica_N_14_M_14): time 0.003035/0.003059, allocations: 2.284 MB / 0.6103 GB, free: 7.953 MB / 446.3 MB Notification: Performance of NFInst.instExpressions: time 0.001001/0.004059, allocations: 0.5533 MB / 0.6108 GB, free: 7.395 MB / 446.3 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.000103/0.004162, allocations: 11.88 kB / 0.6108 GB, free: 7.383 MB / 446.3 MB Notification: Performance of NFTyping.typeComponents: time 0.0001642/0.004326, allocations: 39.64 kB / 0.6109 GB, free: 7.344 MB / 446.3 MB Notification: Performance of NFTyping.typeBindings: time 0.0001262/0.004453, allocations: 63.47 kB / 0.6109 GB, free: 7.281 MB / 446.3 MB Notification: Performance of NFTyping.typeClassSections: time 0.0001666/0.004619, allocations: 55.91 kB / 0.611 GB, free: 7.227 MB / 446.3 MB Notification: Performance of NFFlatten.flatten: time 0.001561/0.00618, allocations: 2.735 MB / 0.6136 GB, free: 4.488 MB / 446.3 MB Notification: Performance of NFFlatten.resolveConnections: time 0.004229/0.01041, allocations: 3.653 MB / 0.6172 GB, free: 0.7695 MB / 446.3 MB Notification: Performance of NFEvalConstants.evaluate: time 0.001612/0.01202, allocations: 1.515 MB / 0.6187 GB, free: 15.25 MB / 462.3 MB Notification: Performance of NFSimplifyModel.simplify: time 0.00283/0.01485, allocations: 1.58 MB / 0.6202 GB, free: 13.67 MB / 462.3 MB Notification: Performance of NFPackage.collectConstants: time 0.001106/0.01596, allocations: 436 kB / 0.6206 GB, free: 13.25 MB / 462.3 MB Notification: Performance of NFFlatten.collectFunctions: time 0.0008775/0.01683, allocations: 440 kB / 0.6211 GB, free: 12.82 MB / 462.3 MB Notification: Performance of combineBinaries: time 0.00356/0.02039, allocations: 2.545 MB / 0.6235 GB, free: 10.27 MB / 462.3 MB Notification: Performance of replaceArrayConstructors: time 0.000653/0.02105, allocations: 468.3 kB / 0.624 GB, free: 9.805 MB / 462.3 MB Notification: Performance of NFVerifyModel.verify: time 0.0007006/0.02175, allocations: 208 kB / 0.6242 GB, free: 9.602 MB / 462.3 MB Notification: Performance of FrontEnd: time 9.293e-05/0.02184, allocations: 8 kB / 0.6242 GB, free: 9.594 MB / 462.3 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 4055 (1039) * Number of variables: 4055 (38) Notification: Performance of [SIM] Bindings: time 0.01582/0.03766, allocations: 10.43 MB / 0.6344 GB, free: 14.89 MB / 478.3 MB Notification: Performance of [SIM] FunctionAlias: time 0.004476/0.04214, allocations: 2.442 MB / 0.6368 GB, free: 12.43 MB / 478.3 MB Notification: Performance of [SIM] Early Inline: time 0.02251/0.06465, allocations: 13.54 MB / 0.65 GB, free: 14.76 MB / 494.3 MB Notification: Performance of [SIM] Simplify 1: time 0.004288/0.06893, allocations: 1.838 MB / 0.6518 GB, free: 12.78 MB / 494.3 MB Notification: Performance of [SIM] Alias: time 0.01273/0.08166, allocations: 7.494 MB / 0.6591 GB, free: 5.121 MB / 494.3 MB Notification: Performance of [SIM] Simplify 2: time 0.003951/0.08562, allocations: 1.833 MB / 0.6609 GB, free: 3.148 MB / 494.3 MB Notification: Performance of [SIM] Remove Stream: time 0.002855/0.08847, allocations: 1.411 MB / 0.6623 GB, free: 1.617 MB / 494.3 MB Notification: Performance of [SIM] Detect States: time 0.003668/0.09214, allocations: 2.651 MB / 0.6649 GB, free: 14.91 MB / 0.4983 GB Notification: Performance of [SIM] Events: time 0.001012/0.09315, allocations: 0.5255 MB / 0.6654 GB, free: 14.37 MB / 0.4983 GB Notification: Performance of [SIM] Partitioning: time 0.009854/0.103, allocations: 5.816 MB / 0.6711 GB, free: 8.496 MB / 0.4983 GB Notification: Performance of [SIM] Causalize: time 0.06592/0.1689, allocations: 33.79 MB / 0.7041 GB, free: 6.668 MB / 0.5295 GB Notification: Performance of [SIM] After Index Reduction Inline: time 0.01916/0.1881, allocations: 13.12 MB / 0.7169 GB, free: 9.539 MB / 0.5452 GB Notification: Performance of [INI] Simplify: time 0.008413/0.1965, allocations: 3.753 MB / 0.7205 GB, free: 5.504 MB / 0.5452 GB Notification: Performance of [INI] Inline: time 0.0298/0.2263, allocations: 19.81 MB / 0.7399 GB, free: 1.648 MB / 0.5608 GB Notification: Performance of [INI] Partitioning: time 0.0007769/0.2271, allocations: 189.9 kB / 0.7401 GB, free: 1.422 MB / 0.5608 GB Notification: Performance of [INI] Cleanup: time 0.006292/0.2334, allocations: 2.772 MB / 0.7428 GB, free: 14.65 MB / 0.5764 GB Notification: Performance of [INI] Causalize: time 0.71/0.9434, allocations: 39.63 MB / 0.7815 GB, free: 119.6 MB / 0.5767 GB Notification: Performance of [INI] Tearing: time 0.004634/0.948, allocations: 2.822 MB / 0.7842 GB, free: 118.1 MB / 0.5767 GB Notification: Performance of [SIM] Initialization: time 2.194e-06/0.948, allocations: 0 / 0.7842 GB, free: 118.1 MB / 0.5767 GB Notification: Performance of [SIM] DAE-Mode: time 0.001668/0.9497, allocations: 0.506 MB / 0.7847 GB, free: 117.6 MB / 0.5767 GB Notification: Performance of [SIM] Remove Dummies: time 1.305e-05/0.9497, allocations: 7.25 kB / 0.7847 GB, free: 117.6 MB / 0.5767 GB Notification: Performance of [SIM] Tearing: time 0.006635/0.9563, allocations: 5.637 MB / 0.7902 GB, free: 112.9 MB / 0.5767 GB Notification: Performance of [SIM] Categorize: time 2.066e-05/0.9564, allocations: 0 / 0.7902 GB, free: 112.9 MB / 0.5767 GB Notification: Performance of [SIM] Solve: time 0.01046/0.9668, allocations: 4.033 MB / 0.7942 GB, free: 109.5 MB / 0.5767 GB Error: Internal error NBJacobian.SparsityColoring.PartialD2ColoringAlgC.getIndices failed because cref primary[1].p.i is neither a seed nor a partial candidate! " [Timeout remaining time 299] [Calling sys.exit(0), Time elapsed: 5.431837667012587] Failed to read output from testmodel.py, exit status != 0: 1.0593669379595667 1.069191961 1.044998402 Calling exit ...