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.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001265/0.001265, allocations: 108 kB / 20.43 MB, free: 1.473 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001311/0.001311, allocations: 185.7 kB / 23.71 MB, free: 4.711 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.444/1.444, allocations: 225.6 MB / 252.5 MB, free: 13.03 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems 2.0.0-master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems 2.0.0-master/package.mo): time 0.1841/0.1841, allocations: 38.54 MB / 346.3 MB, free: 2.141 MB / 270.1 MB
"
[Timeout remaining time 180]
Using package PowerSystems with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems 2.0.0-master/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.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: Performance of FrontEnd - loaded program: time 0.002044/0.002044, allocations: 59.89 kB / 484.5 MB, free: 11.17 MB / 350.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.315/0.317, allocations: 61.3 MB / 0.533 GB, free: 5.57 MB / 382.1 MB
Notification: Performance of NFInst.instantiate(PowerSystems.Examples.AC3ph.Transformation.PhaseShifts): time 0.01175/0.3288, allocations: 11.56 MB / 0.5443 GB, free: 12.76 MB / 398.1 MB
Notification: Performance of NFInst.instExpressions: time 0.009082/0.3379, allocations: 4.23 MB / 0.5485 GB, free: 9.344 MB / 398.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002246/0.3401, allocations: 64.47 kB / 0.5485 GB, free: 9.309 MB / 398.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.004401/0.3445, allocations: 1.477 MB / 0.55 GB, free: 8.176 MB / 398.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.004831/0.3494, allocations: 1.53 MB / 0.5515 GB, free: 6.965 MB / 398.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003578/0.3529, allocations: 0.9 MB / 0.5523 GB, free: 6.309 MB / 398.1 MB
Notification: Performance of NFFlatten.flatten: time 0.006167/0.3591, allocations: 3.291 MB / 0.5555 GB, free: 4.523 MB / 398.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001421/0.3605, allocations: 0.698 MB / 0.5562 GB, free: 4.062 MB / 398.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003171/0.3637, allocations: 1.4 MB / 0.5576 GB, free: 2.934 MB / 398.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003697/0.3674, allocations: 2.076 MB / 0.5596 GB, free: 0.8516 MB / 398.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0004316/0.3678, allocations: 176 kB / 0.5598 GB, free: 0.6797 MB / 398.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002094/0.3699, allocations: 0.7918 MB / 0.5606 GB, free: 15.88 MB / 414.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.001551/0.3715, allocations: 1.317 MB / 0.5618 GB, free: 14.57 MB / 414.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.001638/0.3731, allocations: 1.205 MB / 0.563 GB, free: 13.36 MB / 414.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.00409/0.3772, allocations: 3.191 MB / 0.5661 GB, free: 10.15 MB / 414.1 MB
Notification: Performance of FrontEnd - DAE generated: time 5.03e-06/0.3772, allocations: 0 / 0.5661 GB, free: 10.15 MB / 414.1 MB
Notification: Performance of FrontEnd: time 1.713e-06/0.3772, allocations: 0 / 0.5661 GB, free: 10.15 MB / 414.1 MB
Notification: Performance of Transformations before backend: time 4.816e-05/0.3773, allocations: 0 / 0.5661 GB, free: 10.15 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.00522/0.3825, allocations: 2.542 MB / 0.5686 GB, free: 7.562 MB / 414.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.337e-05/0.3825, allocations: 8.031 kB / 0.5686 GB, free: 7.555 MB / 414.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002602/0.3851, allocations: 482.9 kB / 0.5691 GB, free: 7.082 MB / 414.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003078/0.3882, allocations: 1.32 MB / 0.5704 GB, free: 5.723 MB / 414.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001238/0.3883, allocations: 132.1 kB / 0.5705 GB, free: 5.594 MB / 414.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0005078/0.3888, allocations: 232 kB / 0.5707 GB, free: 5.367 MB / 414.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.005087/0.3939, allocations: 3.147 MB / 0.5738 GB, free: 2.016 MB / 414.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 6.577e-05/0.394, allocations: 7.938 kB / 0.5738 GB, free: 2.008 MB / 414.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002104/0.3942, allocations: 79.97 kB / 0.5739 GB, free: 1.93 MB / 414.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0008316/0.395, allocations: 319.8 kB / 0.5742 GB, free: 1.613 MB / 414.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.004573/0.3996, allocations: 2.572 MB / 0.5767 GB, free: 15.02 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.01852/0.4181, allocations: 13.41 MB / 0.5898 GB, free: 0.7969 MB / 430.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.002173/0.4203, allocations: 1.258 MB / 0.591 GB, free: 15.52 MB / 446.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0009313/0.4212, allocations: 0.5873 MB / 0.5916 GB, free: 14.93 MB / 446.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 7.849e-05/0.4213, allocations: 29.2 kB / 0.5916 GB, free: 14.9 MB / 446.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.53e-05/0.4214, allocations: 51.81 kB / 0.5917 GB, free: 14.84 MB / 446.1 MB
Notification: Performance of pre-optimization done (n=132): time 3.327e-06/0.4214, allocations: 0 / 0.5917 GB, free: 14.84 MB / 446.1 MB
Notification: Performance of matching and sorting (n=134): time 0.006862/0.4282, allocations: 3.916 MB / 0.5955 GB, free: 10.89 MB / 446.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.221e-05/0.4283, allocations: 90.42 kB / 0.5956 GB, free: 10.79 MB / 446.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001711/0.43, allocations: 1.261 MB / 0.5968 GB, free: 9.512 MB / 446.1 MB
Notification: Performance of collectPreVariables (initialization): time 8.762e-05/0.4301, allocations: 45.7 kB / 0.5969 GB, free: 9.461 MB / 446.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0008681/0.431, allocations: 1.139 MB / 0.598 GB, free: 8.309 MB / 446.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0004406/0.4314, allocations: 0.4958 MB / 0.5985 GB, free: 7.809 MB / 446.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0005527/0.4319, allocations: 411.8 kB / 0.5989 GB, free: 7.398 MB / 446.1 MB
Notification: Performance of setup shared object (initialization): time 0.000124/0.4321, allocations: 301.1 kB / 0.5991 GB, free: 7.102 MB / 446.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001144/0.4332, allocations: 0.6796 MB / 0.5998 GB, free: 6.418 MB / 446.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001878/0.4351, allocations: 1.595 MB / 0.6014 GB, free: 4.391 MB / 446.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.01078/0.4459, allocations: 4.53 MB / 0.6058 GB, free: 15.43 MB / 462.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.131e-05/0.4459, allocations: 8 kB / 0.6058 GB, free: 15.43 MB / 462.1 MB
Notification: Performance of matching and sorting (n=280) (initialization): time 0.005694/0.4516, allocations: 2.945 MB / 0.6087 GB, free: 12.45 MB / 462.1 MB
Notification: Performance of prepare postOptimizeDAE: time 3.46e-05/0.4516, allocations: 19.94 kB / 0.6087 GB, free: 12.43 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.001023/0.4526, allocations: 0.6123 MB / 0.6093 GB, free: 11.82 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.047343666665256]
Failed to read output from testmodel.py, exit status != 0:
0.46126104798167944 0.47532873499999995 0.094874413
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f07a970d7b0>