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.001929/0.001929, allocations: 77.97 kB / 21.42 MB, free: 3.074 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.001705/0.001705, allocations: 165.3 kB / 24.82 MB, free: 9.27 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 1.093/1.093, allocations: 177.1 MB / 205.3 MB, free: 5.52 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.2821/0.2821, allocations: 29.42 MB / 291.2 MB, free: 2.441 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.864e-06/1.864e-06, allocations: 0 / 370.1 MB, free: 10.75 MB / 346.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.318e-05/2.505e-05, allocations: 2.312 kB / 370.1 MB, free: 10.74 MB / 346.7 MB
Notification: Performance of NFInst.instantiate(PowerSystems.Examples.AC3ph.Transformation.PhaseShifts): time 0.007989/0.008014, allocations: 9.407 MB / 379.5 MB, free: 1.285 MB / 346.7 MB
Notification: Performance of NFInst.instExpressions: time 0.005999/0.01401, allocations: 4.097 MB / 383.6 MB, free: 13.18 MB / 362.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001052/0.01506, allocations: 71.38 kB / 383.7 MB, free: 13.11 MB / 362.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.002091/0.01716, allocations: 1.073 MB / 384.8 MB, free: 12.03 MB / 362.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.002772/0.01993, allocations: 1.539 MB / 386.3 MB, free: 10.48 MB / 362.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001793/0.02172, allocations: 0.9465 MB / 387.2 MB, free: 9.531 MB / 362.7 MB
Notification: Performance of NFFlatten.flatten: time 0.00296/0.02468, allocations: 3.318 MB / 390.6 MB, free: 6.203 MB / 362.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0009698/0.02565, allocations: 0.8553 MB / 391.4 MB, free: 5.328 MB / 362.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002884/0.02854, allocations: 2.034 MB / 393.4 MB, free: 3.289 MB / 362.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001997/0.03053, allocations: 1.765 MB / 395.2 MB, free: 1.52 MB / 362.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.00026/0.03079, allocations: 160 kB / 395.4 MB, free: 1.363 MB / 362.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001475/0.03227, allocations: 0.7719 MB / 396.1 MB, free: 0.5898 MB / 362.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.000969/0.03324, allocations: 1.368 MB / 397.5 MB, free: 15.22 MB / 378.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0007722/0.03401, allocations: 0.8223 MB / 398.3 MB, free: 14.39 MB / 378.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.002718/0.03673, allocations: 2.309 MB / 400.6 MB, free: 12.08 MB / 378.7 MB
Notification: Performance of FrontEnd - DAE generated: time 5.02e-06/0.03673, allocations: 0 / 400.6 MB, free: 12.08 MB / 378.7 MB
Notification: Performance of FrontEnd: time 1.052e-06/0.03673, allocations: 0 / 400.6 MB, free: 12.08 MB / 378.7 MB
Notification: Performance of Transformations before backend: time 3.913e-05/0.03677, allocations: 0 / 400.6 MB, free: 12.08 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.002638/0.03941, allocations: 2.544 MB / 403.2 MB, free: 9.48 MB / 378.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.39e-05/0.03945, allocations: 11.98 kB / 403.2 MB, free: 9.469 MB / 378.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001656/0.04111, allocations: 0.6588 MB / 403.8 MB, free: 8.809 MB / 378.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001306/0.04242, allocations: 1.146 MB / 405 MB, free: 7.625 MB / 378.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 7.434e-05/0.04249, allocations: 140.1 kB / 405.1 MB, free: 7.488 MB / 378.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002888/0.04278, allocations: 224.7 kB / 405.4 MB, free: 7.27 MB / 378.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002763/0.04554, allocations: 1.966 MB / 407.3 MB, free: 5.035 MB / 378.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 4.507e-05/0.04559, allocations: 15.88 kB / 407.3 MB, free: 5.02 MB / 378.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001192/0.04571, allocations: 83.91 kB / 407.4 MB, free: 4.938 MB / 378.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003782/0.04608, allocations: 283.9 kB / 407.7 MB, free: 4.656 MB / 378.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001028/0.04711, allocations: 0.9213 MB / 408.6 MB, free: 3.691 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.009165/0.05628, allocations: 13.15 MB / 421.8 MB, free: 5.891 MB / 394.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.001059/0.05733, allocations: 0.6517 MB / 422.4 MB, free: 5.219 MB / 394.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0004187/0.05775, allocations: 255.2 kB / 422.7 MB, free: 4.961 MB / 394.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 7.664e-05/0.05783, allocations: 29.3 kB / 422.7 MB, free: 4.934 MB / 394.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.905e-05/0.05788, allocations: 50.7 kB / 422.7 MB, free: 4.871 MB / 394.7 MB
Notification: Performance of pre-optimization done (n=132): time 4.338e-06/0.05788, allocations: 4 kB / 422.7 MB, free: 4.867 MB / 394.7 MB
Notification: Performance of matching and sorting (n=134): time 0.003628/0.06151, allocations: 1.869 MB / 424.6 MB, free: 2.965 MB / 394.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.739e-05/0.06157, allocations: 94.42 kB / 424.7 MB, free: 2.855 MB / 394.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001414/0.06298, allocations: 1.217 MB / 425.9 MB, free: 1.602 MB / 394.7 MB
Notification: Performance of collectPreVariables (initialization): time 5.057e-05/0.06303, allocations: 49.83 kB / 426 MB, free: 1.547 MB / 394.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.000528/0.06356, allocations: 1.2 MB / 427.2 MB, free: 340 kB / 394.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0002563/0.06382, allocations: 0.5076 MB / 427.7 MB, free: 15.82 MB / 410.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0003264/0.06414, allocations: 425.4 kB / 428.1 MB, free: 15.39 MB / 410.7 MB
Notification: Performance of setup shared object (initialization): time 9.842e-05/0.06424, allocations: 476.8 kB / 428.6 MB, free: 14.92 MB / 410.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0002373/0.06448, allocations: 216.1 kB / 428.8 MB, free: 14.71 MB / 410.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0007901/0.06527, allocations: 1.482 MB / 430.3 MB, free: 12.66 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.001827/0.0671, allocations: 2.354 MB / 432.6 MB, free: 9.754 MB / 410.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.398e-06/0.06711, allocations: 8 kB / 432.6 MB, free: 9.746 MB / 410.7 MB
Notification: Performance of matching and sorting (n=275) (initialization): time 0.002024/0.06913, allocations: 1.947 MB / 434.6 MB, free: 7.766 MB / 410.7 MB
Notification: Performance of prepare postOptimizeDAE: time 2.483e-05/0.06915, allocations: 16 kB / 434.6 MB, free: 7.75 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.0003018/0.06946, allocations: 275.1 kB / 434.9 MB, free: 7.48 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.3409973713569343]