Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PowerSystems_latest_PowerSystems.Examples.AC3ph.Transformation.PhaseShifts.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.000975/0.000975, allocations: 80.92 kB / 21.49 MB, free: 2.996 MB / 18.57 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.001467/0.001467, allocations: 170.1 kB / 24.91 MB, free: 8.723 MB / 18.57 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 0.9533/0.9533, allocations: 177.1 MB / 205.3 MB, free: 4.496 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems master/package.mo): time 0.139/0.139, allocations: 29.43 MB / 291.2 MB, free: 2.688 MB / 266.7 MB
"
[Timeout remaining time 180]
Using package PowerSystems with version 2.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems 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(PowerSystems.Examples.AC3ph.Transformation.PhaseShifts,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="Time|meter1.alpha_v|meter1.p.[0-9,]+.|meter1.v_norm|meter12.alpha_v|meter12.p.[0-9,]+.|meter12.v_norm|meter22.alpha_v|meter22.p.[0-9,]+.|meter22.v_norm|meter32.alpha_v|meter32.p.[0-9,]+.|meter32.v_norm",fileNamePrefix="PowerSystems_latest_PowerSystems.Examples.AC3ph.Transformation.PhaseShifts")
translateModel(PowerSystems.Examples.AC3ph.Transformation.PhaseShifts,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="Time|meter1.alpha_v|meter1.p.[0-9,]+.|meter1.v_norm|meter12.alpha_v|meter12.p.[0-9,]+.|meter12.v_norm|meter22.alpha_v|meter22.p.[0-9,]+.|meter22.v_norm|meter32.alpha_v|meter32.p.[0-9,]+.|meter32.v_norm",fileNamePrefix="PowerSystems_latest_PowerSystems.Examples.AC3ph.Transformation.PhaseShifts") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.333e-06/1.333e-06, allocations: 0 / 316.9 MB, free: 9.012 MB / 298.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.184e-05/2.317e-05, allocations: 6.312 kB / 316.9 MB, free: 9.004 MB / 298.7 MB
Notification: Performance of NFInst.instantiate(PowerSystems.Examples.AC3ph.Transformation.PhaseShifts): time 0.007878/0.007902, allocations: 9.48 MB / 326.4 MB, free: 15.48 MB / 314.7 MB
Notification: Performance of NFInst.instExpressions: time 0.005339/0.01324, allocations: 4.209 MB / 330.6 MB, free: 11.25 MB / 314.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0004542/0.01369, allocations: 63.44 kB / 330.6 MB, free: 11.19 MB / 314.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.001453/0.01515, allocations: 1.073 MB / 331.7 MB, free: 10.11 MB / 314.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.001793/0.01694, allocations: 1.535 MB / 333.3 MB, free: 8.57 MB / 314.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.00138/0.01832, allocations: 0.9232 MB / 334.2 MB, free: 7.645 MB / 314.7 MB
Notification: Performance of NFFlatten.flatten: time 0.002428/0.02075, allocations: 3.322 MB / 337.5 MB, free: 4.316 MB / 314.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001107/0.02186, allocations: 0.9216 MB / 338.4 MB, free: 3.363 MB / 314.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002165/0.02402, allocations: 2.022 MB / 340.4 MB, free: 1.336 MB / 314.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.1433/0.1673, allocations: 1.638 MB / 342.1 MB, free: 21.89 MB / 314.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0006519/0.1679, allocations: 162 kB / 342.2 MB, free: 21.89 MB / 314.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001711/0.1696, allocations: 0.7857 MB / 343 MB, free: 21.89 MB / 314.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.001142/0.1708, allocations: 1.369 MB / 344.4 MB, free: 21.89 MB / 314.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.001249/0.172, allocations: 0.8132 MB / 345.2 MB, free: 21.89 MB / 314.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.00271/0.1747, allocations: 2.311 MB / 347.5 MB, free: 21.74 MB / 314.7 MB
Notification: Performance of FrontEnd - DAE generated: time 4.488e-06/0.1747, allocations: 4.781 kB / 347.5 MB, free: 21.74 MB / 314.7 MB
Notification: Performance of FrontEnd: time 1.103e-06/0.1747, allocations: 0 / 347.5 MB, free: 21.74 MB / 314.7 MB
Notification: Performance of Transformations before backend: time 6.522e-05/0.1748, allocations: 0 / 347.5 MB, free: 21.74 MB / 314.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 576
 * Number of variables: 576
Notification: Performance of Generate backend data structure: time 0.002914/0.1777, allocations: 2.317 MB / 349.8 MB, free: 20.65 MB / 314.7 MB
Notification: Performance of prepare preOptimizeDAE: time 3.295e-05/0.1778, allocations: 10.06 kB / 349.8 MB, free: 20.65 MB / 314.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001692/0.1794, allocations: 0.5853 MB / 350.4 MB, free: 20.63 MB / 314.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001346/0.1808, allocations: 1.086 MB / 351.5 MB, free: 20.25 MB / 314.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 7.554e-05/0.1809, allocations: 142.8 kB / 351.7 MB, free: 20.25 MB / 314.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002894/0.1812, allocations: 224.4 kB / 351.9 MB, free: 20.24 MB / 314.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.00166/0.1828, allocations: 1.846 MB / 353.7 MB, free: 19.68 MB / 314.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 5.677e-05/0.1829, allocations: 10.53 kB / 353.7 MB, free: 19.68 MB / 314.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000144/0.183, allocations: 83.39 kB / 353.8 MB, free: 19.68 MB / 314.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003773/0.1834, allocations: 286.3 kB / 354.1 MB, free: 19.67 MB / 314.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001112/0.1845, allocations: 0.8082 MB / 354.9 MB, free: 19.58 MB / 314.7 MB
Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts.
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.006277/0.1908, allocations: 10.14 MB / 365 MB, free: 12.9 MB / 314.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.0005784/0.1914, allocations: 0.6322 MB / 365.7 MB, free: 12.78 MB / 314.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0002079/0.1916, allocations: 233.9 kB / 365.9 MB, free: 12.75 MB / 314.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 5.735e-05/0.1916, allocations: 27.81 kB / 365.9 MB, free: 12.75 MB / 314.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.707e-05/0.1917, allocations: 58.7 kB / 366 MB, free: 12.71 MB / 314.7 MB
Notification: Performance of pre-optimization done (n=132): time 2.495e-06/0.1917, allocations: 0 / 366 MB, free: 12.71 MB / 314.7 MB
Notification: Performance of matching and sorting (n=134): time 0.001935/0.1936, allocations: 1.69 MB / 367.7 MB, free: 11.81 MB / 314.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 2.784e-05/0.1936, allocations: 94.27 kB / 367.8 MB, free: 11.7 MB / 314.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0005011/0.1941, allocations: 1.026 MB / 368.8 MB, free: 10.96 MB / 314.7 MB
Notification: Performance of collectPreVariables (initialization): time 5e-05/0.1942, allocations: 48.75 kB / 368.8 MB, free: 10.91 MB / 314.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0002456/0.1944, allocations: 0.9886 MB / 369.8 MB, free: 9.973 MB / 314.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0001832/0.1946, allocations: 0.4919 MB / 370.3 MB, free: 9.508 MB / 314.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002557/0.1949, allocations: 374.1 kB / 370.7 MB, free: 9.18 MB / 314.7 MB
Notification: Performance of setup shared object (initialization): time 3.822e-05/0.1949, allocations: 305.1 kB / 371 MB, free: 8.879 MB / 314.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.000204/0.1951, allocations: 199.1 kB / 371.2 MB, free: 8.684 MB / 314.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0004364/0.1955, allocations: 1.062 MB / 372.2 MB, free: 7.273 MB / 314.7 MB
Notification: It was not possible to check the given initialization system for consistency symbolically, because the relevant equations are part of an algebraic loop. This is not supported yet.
Notification: Performance of analyzeInitialSystem (initialization): time 0.001272/0.1968, allocations: 1.903 MB / 374.2 MB, free: 5.141 MB / 314.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.154e-05/0.1968, allocations: 8 kB / 374.2 MB, free: 5.133 MB / 314.7 MB
Notification: Performance of matching and sorting (n=275) (initialization): time 0.001709/0.1985, allocations: 1.628 MB / 375.8 MB, free: 3.699 MB / 314.7 MB
Notification: Performance of prepare postOptimizeDAE: time 2.027e-05/0.1986, allocations: 16 kB / 375.8 MB, free: 3.684 MB / 314.7 MB
Error: Internal error IndexReduction.pantelidesIndexReduction failed! System is structurally singular and cannot be handled because the number of unassigned equations is larger than the number of states. Use -d=bltdump to get more information.
Notification: Performance of <failed> postOpt simplifyComplexFunction (initialization): time 0.0002619/0.1988, allocations: 259.1 kB / 376.1 MB, free: 3.43 MB / 314.7 MB
Error: post-optimization module simplifyComplexFunction (initialization) failed.
Error: No system for the symbolic initialization was generated
"
[Timeout remaining time 660]
[Calling sys.exit(0), Time elapsed: 1.931574986781925]