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.001041/0.001041, allocations: 79.58 kB / 21.18 MB, free: 3.387 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.0009619/0.0009619, allocations: 161 kB / 24.56 MB, free: 20 kB / 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.9512/0.9512, allocations: 177.1 MB / 205 MB, free: 5.594 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.2503/0.2503, allocations: 29.41 MB / 290.9 MB, free: 2.527 MB / 266.7 MB
"
[Timeout remaining time 180]
Using package PowerSystems with version 2.0.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.282e-06/1.282e-06, allocations: 0 / 369.6 MB, free: 11.02 MB / 346.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.49e-05/2.618e-05, allocations: 6.312 kB / 369.6 MB, free: 11.02 MB / 346.7 MB
Notification: Performance of NFInst.instantiate(PowerSystems.Examples.AC3ph.Transformation.PhaseShifts): time 0.007595/0.007621, allocations: 9.535 MB / 379.2 MB, free: 1.434 MB / 346.7 MB
Notification: Performance of NFInst.instExpressions: time 0.00614/0.01376, allocations: 4.167 MB / 383.3 MB, free: 13.25 MB / 362.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00106/0.01482, allocations: 67.44 kB / 383.4 MB, free: 13.19 MB / 362.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.001381/0.0162, allocations: 0.5862 MB / 384 MB, free: 12.6 MB / 362.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.00272/0.01892, allocations: 1.543 MB / 385.5 MB, free: 11.05 MB / 362.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001946/0.02087, allocations: 0.9504 MB / 386.5 MB, free: 10.09 MB / 362.7 MB
Notification: Performance of NFFlatten.flatten: time 0.003546/0.02441, allocations: 3.345 MB / 389.8 MB, free: 6.738 MB / 362.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001206/0.02562, allocations: 0.8514 MB / 390.7 MB, free: 5.867 MB / 362.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002511/0.02813, allocations: 1.78 MB / 392.5 MB, free: 4.082 MB / 362.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002192/0.03032, allocations: 1.94 MB / 394.4 MB, free: 2.137 MB / 362.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0004079/0.03073, allocations: 176 kB / 394.6 MB, free: 1.965 MB / 362.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001274/0.032, allocations: 0.7798 MB / 395.3 MB, free: 1.184 MB / 362.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0009282/0.03293, allocations: 1.391 MB / 396.7 MB, free: 15.79 MB / 378.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0009728/0.0339, allocations: 0.8496 MB / 397.6 MB, free: 14.94 MB / 378.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.002413/0.03632, allocations: 2.391 MB / 400 MB, free: 12.54 MB / 378.7 MB
Notification: Performance of FrontEnd - DAE generated: time 5.33e-06/0.03632, allocations: 3.938 kB / 400 MB, free: 12.54 MB / 378.7 MB
Notification: Performance of FrontEnd: time 1.372e-06/0.03632, allocations: 0 / 400 MB, free: 12.54 MB / 378.7 MB
Notification: Performance of Transformations before backend: time 7.281e-05/0.0364, allocations: 0 / 400 MB, free: 12.54 MB / 378.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.00299/0.03939, allocations: 2.661 MB / 402.6 MB, free: 9.82 MB / 378.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.201e-05/0.03943, allocations: 11.98 kB / 402.7 MB, free: 9.809 MB / 378.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001862/0.04129, allocations: 0.8265 MB / 403.5 MB, free: 8.98 MB / 378.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001747/0.04304, allocations: 1.314 MB / 404.8 MB, free: 7.629 MB / 378.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 7.394e-05/0.04311, allocations: 144.1 kB / 404.9 MB, free: 7.488 MB / 378.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003203/0.04343, allocations: 220.7 kB / 405.2 MB, free: 7.273 MB / 378.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002369/0.0458, allocations: 2.119 MB / 407.3 MB, free: 4.887 MB / 378.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 4.547e-05/0.04585, allocations: 11.88 kB / 407.3 MB, free: 4.875 MB / 378.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000123/0.04597, allocations: 87.95 kB / 407.4 MB, free: 4.789 MB / 378.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0004092/0.04638, allocations: 315.8 kB / 407.7 MB, free: 4.477 MB / 378.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001879/0.04826, allocations: 1.136 MB / 408.8 MB, free: 3.316 MB / 378.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.01038/0.05863, allocations: 13.34 MB / 422.1 MB, free: 5.332 MB / 394.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.001057/0.05969, allocations: 0.7437 MB / 422.9 MB, free: 4.574 MB / 394.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0003755/0.06007, allocations: 311.2 kB / 423.2 MB, free: 4.262 MB / 394.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 6.562e-05/0.06013, allocations: 25.31 kB / 423.2 MB, free: 4.238 MB / 394.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.553e-05/0.06017, allocations: 50.7 kB / 423.3 MB, free: 4.176 MB / 394.7 MB
Notification: Performance of pre-optimization done (n=132): time 3.657e-06/0.06017, allocations: 0 / 423.3 MB, free: 4.176 MB / 394.7 MB
Notification: Performance of matching and sorting (n=134): time 0.003754/0.06392, allocations: 2.655 MB / 425.9 MB, free: 1.48 MB / 394.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.361e-05/0.06398, allocations: 94.41 kB / 426 MB, free: 1.371 MB / 394.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001101/0.06508, allocations: 1.246 MB / 427.3 MB, free: 92 kB / 394.7 MB
Notification: Performance of collectPreVariables (initialization): time 6.067e-05/0.06514, allocations: 49.69 kB / 427.3 MB, free: 36 kB / 394.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0006217/0.06576, allocations: 1.188 MB / 428.5 MB, free: 14.83 MB / 410.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0003311/0.06609, allocations: 0.5037 MB / 429 MB, free: 14.32 MB / 410.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0003784/0.06647, allocations: 421.8 kB / 429.4 MB, free: 13.9 MB / 410.7 MB
Notification: Performance of setup shared object (initialization): time 0.0001223/0.06659, allocations: 476.8 kB / 429.9 MB, free: 13.43 MB / 410.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0004604/0.06705, allocations: 288.2 kB / 430.2 MB, free: 13.15 MB / 410.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001042/0.0681, allocations: 1.595 MB / 431.8 MB, free: 10.97 MB / 410.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.002674/0.07077, allocations: 2.618 MB / 434.4 MB, free: 7.773 MB / 410.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.983e-05/0.07079, allocations: 11.98 kB / 434.4 MB, free: 7.762 MB / 410.7 MB
Notification: Performance of matching and sorting (n=280) (initialization): time 0.002959/0.07375, allocations: 2.157 MB / 436.5 MB, free: 5.57 MB / 410.7 MB
Notification: Performance of prepare postOptimizeDAE: time 2.972e-05/0.07378, allocations: 24.25 kB / 436.6 MB, free: 5.547 MB / 410.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.0004352/0.07421, allocations: 323.1 kB / 436.9 MB, free: 5.23 MB / 410.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: 2.1346435411833227]