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.001056/0.001056, allocations: 80.8 kB / 21.5 MB, free: 2.992 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.001123/0.001123, allocations: 174.3 kB / 24.91 MB, free: 8.766 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.9979/0.9979, allocations: 177.1 MB / 205.3 MB, free: 5.434 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.2331/0.2331, allocations: 29.42 MB / 291.2 MB, free: 2.492 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.533e-06/1.533e-06, allocations: 0 / 316.8 MB, free: 24 kB / 282.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.637e-05/2.79e-05, allocations: 3.422 kB / 316.8 MB, free: 20 kB / 282.7 MB
Notification: Performance of NFInst.instantiate(PowerSystems.Examples.AC3ph.Transformation.PhaseShifts): time 0.00798/0.008008, allocations: 9.436 MB / 326.3 MB, free: 6.543 MB / 298.7 MB
Notification: Performance of NFInst.instExpressions: time 0.006323/0.01433, allocations: 4.213 MB / 330.5 MB, free: 2.316 MB / 298.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001128/0.01546, allocations: 63.44 kB / 330.5 MB, free: 2.254 MB / 298.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.002288/0.01775, allocations: 1.077 MB / 331.6 MB, free: 1.172 MB / 298.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.002775/0.02052, allocations: 1.543 MB / 333.2 MB, free: 15.62 MB / 314.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001796/0.02232, allocations: 0.9621 MB / 334.1 MB, free: 14.65 MB / 314.7 MB
Notification: Performance of NFFlatten.flatten: time 0.004737/0.02706, allocations: 3.314 MB / 337.4 MB, free: 11.33 MB / 314.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002032/0.02909, allocations: 0.9217 MB / 338.4 MB, free: 10.38 MB / 314.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003603/0.03269, allocations: 2.03 MB / 340.4 MB, free: 8.34 MB / 314.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.00225/0.03494, allocations: 1.64 MB / 342 MB, free: 6.695 MB / 314.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0005258/0.03547, allocations: 160 kB / 342.2 MB, free: 6.539 MB / 314.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001578/0.03704, allocations: 0.7681 MB / 343 MB, free: 5.77 MB / 314.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.001185/0.03823, allocations: 1.364 MB / 344.3 MB, free: 4.402 MB / 314.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.001009/0.03924, allocations: 0.8184 MB / 345.1 MB, free: 3.582 MB / 314.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.002973/0.04221, allocations: 2.309 MB / 347.5 MB, free: 1.266 MB / 314.7 MB
Notification: Performance of FrontEnd - DAE generated: time 4.448e-06/0.04221, allocations: 0 / 347.5 MB, free: 1.266 MB / 314.7 MB
Notification: Performance of FrontEnd: time 1.663e-06/0.04222, allocations: 0 / 347.5 MB, free: 1.266 MB / 314.7 MB
Notification: Performance of Transformations before backend: time 4.782e-05/0.04226, allocations: 0 / 347.5 MB, free: 1.266 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.002505/0.04477, allocations: 2.322 MB / 349.8 MB, free: 14.9 MB / 330.7 MB
Notification: Performance of prepare preOptimizeDAE: time 3.812e-05/0.04481, allocations: 11.98 kB / 349.8 MB, free: 14.89 MB / 330.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001578/0.04638, allocations: 0.586 MB / 350.4 MB, free: 14.3 MB / 330.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001403/0.04779, allocations: 1.077 MB / 351.4 MB, free: 13.19 MB / 330.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 8.628e-05/0.04787, allocations: 148.1 kB / 351.6 MB, free: 13.04 MB / 330.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002991/0.04817, allocations: 220.7 kB / 351.8 MB, free: 12.83 MB / 330.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002192/0.05037, allocations: 1.849 MB / 353.7 MB, free: 10.77 MB / 330.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 4.823e-05/0.05041, allocations: 15.94 kB / 353.7 MB, free: 10.75 MB / 330.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001191/0.05053, allocations: 83.95 kB / 353.8 MB, free: 10.67 MB / 330.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003547/0.05089, allocations: 279.9 kB / 354 MB, free: 10.39 MB / 330.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001111/0.052, allocations: 0.8123 MB / 354.8 MB, free: 9.539 MB / 330.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.009045/0.06104, allocations: 10.15 MB / 365 MB, free: 14.83 MB / 346.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.0007071/0.06175, allocations: 0.6357 MB / 365.6 MB, free: 14.17 MB / 346.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.000254/0.062, allocations: 228.5 kB / 365.9 MB, free: 13.94 MB / 346.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 6.232e-05/0.06207, allocations: 25.3 kB / 365.9 MB, free: 13.91 MB / 346.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.475e-05/0.0621, allocations: 51.88 kB / 365.9 MB, free: 13.86 MB / 346.7 MB
Notification: Performance of pre-optimization done (n=132): time 2.945e-06/0.0621, allocations: 0 / 365.9 MB, free: 13.86 MB / 346.7 MB
Notification: Performance of matching and sorting (n=134): time 0.002917/0.06502, allocations: 1.688 MB / 367.6 MB, free: 12.13 MB / 346.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.035e-05/0.06506, allocations: 94.42 kB / 367.7 MB, free: 12.02 MB / 346.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0006726/0.06573, allocations: 1.026 MB / 368.7 MB, free: 10.98 MB / 346.7 MB
Notification: Performance of collectPreVariables (initialization): time 6.091e-05/0.0658, allocations: 45.7 kB / 368.8 MB, free: 10.93 MB / 346.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0004302/0.06623, allocations: 0.9884 MB / 369.8 MB, free: 9.922 MB / 346.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0002401/0.06647, allocations: 0.4996 MB / 370.3 MB, free: 9.418 MB / 346.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0003031/0.06677, allocations: 383.7 kB / 370.6 MB, free: 9.035 MB / 346.7 MB
Notification: Performance of setup shared object (initialization): time 6.744e-05/0.06684, allocations: 305.1 kB / 370.9 MB, free: 8.734 MB / 346.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0002359/0.06707, allocations: 192.2 kB / 371.1 MB, free: 8.543 MB / 346.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0007/0.06777, allocations: 1.063 MB / 372.2 MB, free: 7.062 MB / 346.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.00236/0.07013, allocations: 1.91 MB / 374.1 MB, free: 4.746 MB / 346.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.112e-05/0.07015, allocations: 8 kB / 374.1 MB, free: 4.738 MB / 346.7 MB
Notification: Performance of matching and sorting (n=275) (initialization): time 0.002547/0.0727, allocations: 1.627 MB / 375.7 MB, free: 3.078 MB / 346.7 MB
Notification: Performance of prepare postOptimizeDAE: time 3.116e-05/0.07273, allocations: 20 kB / 375.8 MB, free: 3.059 MB / 346.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.0004025/0.07313, allocations: 263.1 kB / 376 MB, free: 2.805 MB / 346.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.8538291077129543]