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.001595/0.001595, allocations: 111.1 kB / 20.44 MB, free: 1.062 MB / 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.002438/0.002438, allocations: 215.8 kB / 23.73 MB, free: 4.156 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.589/1.589, allocations: 230.6 MB / 257.5 MB, free: 7.809 MB / 206.1 MB
"
[Timeout remaining time 178]
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.186/0.186, allocations: 38.55 MB / 352.5 MB, free: 12.32 MB / 286.1 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.1.|meter1.p.2.|meter1.p.3.|meter1.v_norm|meter12.alpha_v|meter12.p.1.|meter12.p.2.|meter12.p.3.|meter12.v_norm|meter22.alpha_v|meter22.p.1.|meter22.p.2.|meter22.p.3.|meter22.v_norm|meter32.alpha_v|meter32.p.1.|meter32.p.2.|meter32.p.3.|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.1.|meter1.p.2.|meter1.p.3.|meter1.v_norm|meter12.alpha_v|meter12.p.1.|meter12.p.2.|meter12.p.3.|meter12.v_norm|meter22.alpha_v|meter22.p.1.|meter22.p.2.|meter22.p.3.|meter22.v_norm|meter32.alpha_v|meter32.p.1.|meter32.p.2.|meter32.p.3.|meter32.v_norm",fileNamePrefix="PowerSystems_latest_PowerSystems.Examples.AC3ph.Transformation.PhaseShifts") [Timeout 660]
"Notification: PowerSystems requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.002129/0.002129, allocations: 71.89 kB / 493 MB, free: 4.203 MB / 350.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3353/0.3375, allocations: 62.47 MB / 0.5424 GB, free: 404 kB / 382.1 MB
Notification: Performance of NFInst.instantiate(PowerSystems.Examples.AC3ph.Transformation.PhaseShifts): time 0.01293/0.3504, allocations: 10.49 MB / 0.5527 GB, free: 8.422 MB / 398.1 MB
Notification: Performance of NFInst.instExpressions: time 0.008782/0.3592, allocations: 3.987 MB / 0.5566 GB, free: 5.289 MB / 398.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001597/0.3608, allocations: 68.69 kB / 0.5566 GB, free: 5.25 MB / 398.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.004213/0.365, allocations: 1.391 MB / 0.558 GB, free: 4.199 MB / 398.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.004342/0.3693, allocations: 1.528 MB / 0.5595 GB, free: 2.688 MB / 398.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003114/0.3724, allocations: 0.9082 MB / 0.5604 GB, free: 1.777 MB / 398.1 MB
Notification: Performance of NFFlatten.flatten: time 0.005255/0.3777, allocations: 3.279 MB / 0.5636 GB, free: 14.49 MB / 414.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001921/0.3796, allocations: 0.8398 MB / 0.5644 GB, free: 13.62 MB / 414.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003796/0.3834, allocations: 1.421 MB / 0.5658 GB, free: 12.2 MB / 414.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.004538/0.388, allocations: 2.017 MB / 0.5678 GB, free: 10.17 MB / 414.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0008608/0.3888, allocations: 176 kB / 0.5679 GB, free: 10 MB / 414.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003098/0.3919, allocations: 0.7683 MB / 0.5687 GB, free: 9.23 MB / 414.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.002454/0.3944, allocations: 1.313 MB / 0.57 GB, free: 7.914 MB / 414.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.001972/0.3963, allocations: 1.003 MB / 0.5709 GB, free: 6.906 MB / 414.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.005029/0.4014, allocations: 3.234 MB / 0.5741 GB, free: 3.66 MB / 414.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.703e-06/0.4014, allocations: 0 / 0.5741 GB, free: 3.66 MB / 414.1 MB
Notification: Performance of FrontEnd: time 3.517e-06/0.4014, allocations: 0 / 0.5741 GB, free: 3.66 MB / 414.1 MB
Notification: Performance of Transformations before backend: time 5.831e-05/0.4014, allocations: 3.938 kB / 0.5741 GB, free: 3.656 MB / 414.1 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.005327/0.4068, allocations: 2.542 MB / 0.5766 GB, free: 1.066 MB / 414.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.8e-05/0.4068, allocations: 11.98 kB / 0.5766 GB, free: 1.055 MB / 414.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003238/0.4101, allocations: 0.6276 MB / 0.5772 GB, free: 436 kB / 414.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003297/0.4134, allocations: 1.316 MB / 0.5785 GB, free: 15.07 MB / 430.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001356/0.4135, allocations: 148.1 kB / 0.5786 GB, free: 14.93 MB / 430.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004861/0.414, allocations: 216 kB / 0.5788 GB, free: 14.71 MB / 430.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.005071/0.419, allocations: 3.143 MB / 0.5819 GB, free: 11.36 MB / 430.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 6.508e-05/0.4191, allocations: 15.83 kB / 0.5819 GB, free: 11.35 MB / 430.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002003/0.4193, allocations: 87.91 kB / 0.582 GB, free: 11.26 MB / 430.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0007766/0.4201, allocations: 323.8 kB / 0.5823 GB, free: 10.94 MB / 430.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.004616/0.4247, allocations: 2.553 MB / 0.5848 GB, free: 8.371 MB / 430.1 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.017/0.4417, allocations: 11.75 MB / 0.5963 GB, free: 12.06 MB / 446.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.002059/0.4438, allocations: 1.236 MB / 0.5975 GB, free: 10.81 MB / 446.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001001/0.4448, allocations: 0.5854 MB / 0.5981 GB, free: 10.23 MB / 446.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 8.095e-05/0.4448, allocations: 25.3 kB / 0.5981 GB, free: 10.2 MB / 446.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.762e-05/0.4449, allocations: 47.88 kB / 0.5981 GB, free: 10.15 MB / 446.1 MB
Notification: Performance of pre-optimization done (n=132): time 4.549e-06/0.4449, allocations: 4 kB / 0.5981 GB, free: 10.14 MB / 446.1 MB
Notification: Performance of matching and sorting (n=134): time 0.006717/0.4516, allocations: 3.924 MB / 0.602 GB, free: 6.184 MB / 446.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.88e-05/0.4517, allocations: 90.42 kB / 0.602 GB, free: 6.074 MB / 446.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001678/0.4534, allocations: 1.299 MB / 0.6033 GB, free: 4.758 MB / 446.1 MB
Notification: Performance of collectPreVariables (initialization): time 8.57e-05/0.4535, allocations: 45.83 kB / 0.6034 GB, free: 4.707 MB / 446.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0008333/0.4543, allocations: 1.131 MB / 0.6045 GB, free: 3.562 MB / 446.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0004185/0.4547, allocations: 0.492 MB / 0.6049 GB, free: 3.066 MB / 446.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0005297/0.4552, allocations: 407.9 kB / 0.6053 GB, free: 2.66 MB / 446.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001264/0.4554, allocations: 301.1 kB / 0.6056 GB, free: 2.363 MB / 446.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001117/0.4565, allocations: 0.6874 MB / 0.6063 GB, free: 1.672 MB / 446.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001857/0.4583, allocations: 1.58 MB / 0.6078 GB, free: 15.66 MB / 462.1 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.01032/0.4687, allocations: 4.538 MB / 0.6123 GB, free: 10.7 MB / 462.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.513e-05/0.4687, allocations: 11.94 kB / 0.6123 GB, free: 10.69 MB / 462.1 MB
Notification: Performance of matching and sorting (n=280) (initialization): time 0.005561/0.4742, allocations: 2.953 MB / 0.6152 GB, free: 7.707 MB / 462.1 MB
Notification: Performance of prepare postOptimizeDAE: time 3.976e-05/0.4743, allocations: 20 kB / 0.6152 GB, free: 7.688 MB / 462.1 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.001013/0.4753, allocations: 0.6084 MB / 0.6158 GB, free: 7.078 MB / 462.1 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: 3.3292861692607403]
Failed to read output from testmodel.py, exit status != 0:
0.483858285471797 0.50327786 0.097056763
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f3459962dd0>