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.001344/0.001344, allocations: 103.6 kB / 21.18 MB, free: 0.6445 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.001478/0.001478, allocations: 209 kB / 24.48 MB, free: 3.938 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.444/1.444, allocations: 230.6 MB / 258.2 MB, free: 7.797 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.1885/0.1885, allocations: 38.55 MB / 353.2 MB, free: 12.34 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.002102/0.002102, allocations: 64.19 kB / 493.7 MB, free: 4.219 MB / 350.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3076/0.3098, allocations: 62.47 MB / 0.5432 GB, free: 0.6055 MB / 382.1 MB
Notification: Performance of NFInst.instantiate(PowerSystems.Examples.AC3ph.Transformation.PhaseShifts): time 0.01243/0.3222, allocations: 10.5 MB / 0.5534 GB, free: 8.621 MB / 398.1 MB
Notification: Performance of NFInst.instExpressions: time 0.007696/0.3299, allocations: 3.982 MB / 0.5573 GB, free: 5.492 MB / 398.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0008039/0.3307, allocations: 69.69 kB / 0.5574 GB, free: 5.453 MB / 398.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.003507/0.3342, allocations: 1.379 MB / 0.5587 GB, free: 4.414 MB / 398.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.003227/0.3374, allocations: 1.527 MB / 0.5602 GB, free: 2.887 MB / 398.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.002356/0.3398, allocations: 0.9121 MB / 0.5611 GB, free: 1.973 MB / 398.1 MB
Notification: Performance of NFFlatten.flatten: time 0.003777/0.3436, allocations: 3.275 MB / 0.5643 GB, free: 14.69 MB / 414.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001191/0.3447, allocations: 0.8515 MB / 0.5651 GB, free: 13.81 MB / 414.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002145/0.3469, allocations: 1.424 MB / 0.5665 GB, free: 12.38 MB / 414.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002897/0.3498, allocations: 2.009 MB / 0.5685 GB, free: 10.36 MB / 414.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0003181/0.3501, allocations: 180 kB / 0.5687 GB, free: 10.19 MB / 414.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001693/0.3518, allocations: 0.7684 MB / 0.5694 GB, free: 9.418 MB / 414.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.00136/0.3532, allocations: 1.317 MB / 0.5707 GB, free: 8.098 MB / 414.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.001436/0.3546, allocations: 0.9994 MB / 0.5717 GB, free: 7.094 MB / 414.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.004164/0.3588, allocations: 3.242 MB / 0.5748 GB, free: 3.84 MB / 414.1 MB
Notification: Performance of FrontEnd - DAE generated: time 4.579e-06/0.3588, allocations: 0 / 0.5748 GB, free: 3.84 MB / 414.1 MB
Notification: Performance of FrontEnd: time 2.104e-06/0.3588, allocations: 0 / 0.5748 GB, free: 3.84 MB / 414.1 MB
Notification: Performance of Transformations before backend: time 5.105e-05/0.3588, allocations: 3.984 kB / 0.5748 GB, free: 3.836 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.00498/0.3638, allocations: 2.531 MB / 0.5773 GB, free: 1.258 MB / 414.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.216e-05/0.3638, allocations: 15.98 kB / 0.5773 GB, free: 1.242 MB / 414.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003179/0.367, allocations: 0.6237 MB / 0.5779 GB, free: 0.6172 MB / 414.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003007/0.37, allocations: 1.312 MB / 0.5792 GB, free: 15.27 MB / 430.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001316/0.3702, allocations: 148.1 kB / 0.5794 GB, free: 15.12 MB / 430.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004855/0.3706, allocations: 224 kB / 0.5796 GB, free: 14.9 MB / 430.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.004939/0.3756, allocations: 3.135 MB / 0.5826 GB, free: 11.56 MB / 430.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 6.484e-05/0.3756, allocations: 19.89 kB / 0.5827 GB, free: 11.54 MB / 430.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001952/0.3758, allocations: 83.95 kB / 0.5827 GB, free: 11.46 MB / 430.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0007662/0.3766, allocations: 331.7 kB / 0.583 GB, free: 11.13 MB / 430.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.004294/0.3809, allocations: 2.553 MB / 0.5855 GB, free: 8.559 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.0162/0.3971, allocations: 11.73 MB / 0.597 GB, free: 12.26 MB / 446.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.002056/0.3992, allocations: 1.242 MB / 0.5982 GB, free: 11.01 MB / 446.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0009324/0.4001, allocations: 0.5971 MB / 0.5988 GB, free: 10.41 MB / 446.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 6.966e-05/0.4002, allocations: 13.31 kB / 0.5988 GB, free: 10.4 MB / 446.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.956e-05/0.4002, allocations: 55.86 kB / 0.5989 GB, free: 10.34 MB / 446.1 MB
Notification: Performance of pre-optimization done (n=132): time 3.657e-06/0.4002, allocations: 4 kB / 0.5989 GB, free: 10.33 MB / 446.1 MB
Notification: Performance of matching and sorting (n=134): time 0.006614/0.4068, allocations: 3.916 MB / 0.6027 GB, free: 6.379 MB / 446.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.502e-05/0.4069, allocations: 98.36 kB / 0.6028 GB, free: 6.262 MB / 446.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001595/0.4085, allocations: 1.291 MB / 0.604 GB, free: 4.953 MB / 446.1 MB
Notification: Performance of collectPreVariables (initialization): time 7.928e-05/0.4086, allocations: 53.83 kB / 0.6041 GB, free: 4.895 MB / 446.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0008631/0.4094, allocations: 1.123 MB / 0.6052 GB, free: 3.758 MB / 446.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0004265/0.4099, allocations: 499.8 kB / 0.6057 GB, free: 3.266 MB / 446.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0005433/0.4104, allocations: 419.8 kB / 0.6061 GB, free: 2.848 MB / 446.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001263/0.4105, allocations: 301.1 kB / 0.6064 GB, free: 2.551 MB / 446.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001121/0.4116, allocations: 0.6796 MB / 0.607 GB, free: 1.867 MB / 446.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001909/0.4136, allocations: 1.58 MB / 0.6086 GB, free: 15.86 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.0102/0.4238, allocations: 4.538 MB / 0.613 GB, free: 10.89 MB / 462.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.73e-05/0.4238, allocations: 8 kB / 0.613 GB, free: 10.89 MB / 462.1 MB
Notification: Performance of matching and sorting (n=280) (initialization): time 0.005518/0.4293, allocations: 2.961 MB / 0.6159 GB, free: 7.898 MB / 462.1 MB
Notification: Performance of prepare postOptimizeDAE: time 3.635e-05/0.4293, allocations: 23.94 kB / 0.6159 GB, free: 7.875 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.001035/0.4304, allocations: 0.6122 MB / 0.6165 GB, free: 7.262 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: 2.9733805479481816]
Failed to read output from testmodel.py, exit status != 0:
0.4387991139665246 0.453010884 0.09100960500000001
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7faffa8eee00>