Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PowerSystems_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.001336/0.001336, allocations: 112.4 kB / 20.43 MB, free: 1.449 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.001499/0.001499, allocations: 191.8 kB / 23.72 MB, free: 4.68 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.517/1.517, allocations: 225.6 MB / 252.5 MB, free: 13.01 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems 2.0.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems 2.0.0/package.mo): time 0.2047/0.2047, allocations: 38.53 MB / 346.3 MB, free: 2.09 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/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="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="PowerSystems_PowerSystems.Examples.AC3ph.Transformation.PhaseShifts")
translateModel(PowerSystems.Examples.AC3ph.Transformation.PhaseShifts,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="PowerSystems_PowerSystems.Examples.AC3ph.Transformation.PhaseShifts") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.002011/0.002011, allocations: 68.06 kB / 484.5 MB, free: 11.16 MB / 350.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3788/0.3808, 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.01188/0.3927, allocations: 11.55 MB / 0.5443 GB, free: 12.75 MB / 398.1 MB
Notification: Performance of NFInst.instExpressions: time 0.009745/0.4025, allocations: 4.233 MB / 0.5484 GB, free: 9.328 MB / 398.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001739/0.4042, allocations: 65.91 kB / 0.5485 GB, free: 9.293 MB / 398.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.004507/0.4087, allocations: 1.474 MB / 0.5499 GB, free: 8.164 MB / 398.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.005242/0.414, allocations: 1.529 MB / 0.5514 GB, free: 6.953 MB / 398.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.00344/0.4174, allocations: 0.9083 MB / 0.5523 GB, free: 6.289 MB / 398.1 MB
Notification: Performance of NFFlatten.flatten: time 0.007041/0.4244, allocations: 3.288 MB / 0.5555 GB, free: 4.508 MB / 398.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002095/0.4265, allocations: 0.6986 MB / 0.5562 GB, free: 4.047 MB / 398.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003578/0.4301, allocations: 1.401 MB / 0.5576 GB, free: 2.91 MB / 398.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.004086/0.4342, allocations: 2.076 MB / 0.5596 GB, free: 0.8281 MB / 398.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0008053/0.435, allocations: 172 kB / 0.5598 GB, free: 0.6602 MB / 398.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002677/0.4377, allocations: 0.7879 MB / 0.5605 GB, free: 15.87 MB / 414.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.002241/0.4399, allocations: 1.317 MB / 0.5618 GB, free: 14.55 MB / 414.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.003171/0.4431, allocations: 1.213 MB / 0.563 GB, free: 13.33 MB / 414.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.006036/0.4491, allocations: 3.183 MB / 0.5661 GB, free: 10.14 MB / 414.1 MB
Notification: Performance of FrontEnd - DAE generated: time 8.446e-06/0.4491, allocations: 4 kB / 0.5661 GB, free: 10.13 MB / 414.1 MB
Notification: Performance of FrontEnd: time 2.494e-06/0.4491, allocations: 4 kB / 0.5661 GB, free: 10.13 MB / 414.1 MB
Notification: Performance of Transformations before backend: time 8.304e-05/0.4492, allocations: 4 kB / 0.5661 GB, free: 10.12 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.006126/0.4553, allocations: 2.535 MB / 0.5686 GB, free: 7.543 MB / 414.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.266e-05/0.4554, allocations: 12.03 kB / 0.5686 GB, free: 7.531 MB / 414.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002686/0.4581, allocations: 482.9 kB / 0.5691 GB, free: 7.059 MB / 414.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003443/0.4615, allocations: 1.316 MB / 0.5704 GB, free: 5.703 MB / 414.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001305/0.4617, allocations: 152.1 kB / 0.5705 GB, free: 5.555 MB / 414.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0005432/0.4622, allocations: 227.9 kB / 0.5707 GB, free: 5.332 MB / 414.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.006019/0.4682, allocations: 3.135 MB / 0.5738 GB, free: 1.992 MB / 414.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 7.009e-05/0.4683, allocations: 11.94 kB / 0.5738 GB, free: 1.98 MB / 414.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002208/0.4685, allocations: 83.95 kB / 0.5739 GB, free: 1.898 MB / 414.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0008517/0.4694, allocations: 319.8 kB / 0.5742 GB, free: 1.582 MB / 414.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.005123/0.4745, allocations: 2.572 MB / 0.5767 GB, free: 14.99 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.02138/0.4959, allocations: 13.41 MB / 0.5898 GB, free: 0.7656 MB / 430.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.002333/0.4982, allocations: 1.25 MB / 0.591 GB, free: 15.5 MB / 446.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001026/0.4992, allocations: 0.5873 MB / 0.5916 GB, free: 14.91 MB / 446.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 8.876e-05/0.4993, allocations: 25.3 kB / 0.5916 GB, free: 14.88 MB / 446.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 6.075e-05/0.4994, allocations: 51.81 kB / 0.5917 GB, free: 14.82 MB / 446.1 MB
Notification: Performance of pre-optimization done (n=132): time 3.527e-06/0.4994, allocations: 0 / 0.5917 GB, free: 14.82 MB / 446.1 MB
Notification: Performance of matching and sorting (n=134): time 0.008057/0.5074, allocations: 3.92 MB / 0.5955 GB, free: 10.87 MB / 446.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 8.8e-05/0.5075, allocations: 90.42 kB / 0.5956 GB, free: 10.77 MB / 446.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002184/0.5097, allocations: 1.265 MB / 0.5968 GB, free: 9.484 MB / 446.1 MB
Notification: Performance of collectPreVariables (initialization): time 9.147e-05/0.5098, allocations: 45.7 kB / 0.5969 GB, free: 9.434 MB / 446.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0009423/0.5107, allocations: 1.131 MB / 0.598 GB, free: 8.289 MB / 446.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.000487/0.5112, allocations: 0.4997 MB / 0.5985 GB, free: 7.785 MB / 446.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0005691/0.5118, allocations: 407.9 kB / 0.5988 GB, free: 7.379 MB / 446.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001368/0.5119, allocations: 301.2 kB / 0.5991 GB, free: 7.082 MB / 446.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001117/0.5131, allocations: 0.6835 MB / 0.5998 GB, free: 6.395 MB / 446.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001909/0.515, allocations: 1.583 MB / 0.6013 GB, free: 4.379 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.01051/0.5255, allocations: 4.534 MB / 0.6058 GB, free: 15.42 MB / 462.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.255e-05/0.5255, allocations: 8 kB / 0.6058 GB, free: 15.41 MB / 462.1 MB
Notification: Performance of matching and sorting (n=280) (initialization): time 0.006244/0.5317, allocations: 2.953 MB / 0.6087 GB, free: 12.43 MB / 462.1 MB
Notification: Performance of prepare postOptimizeDAE: time 4.258e-05/0.5318, allocations: 19.94 kB / 0.6087 GB, free: 12.41 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.001059/0.5328, allocations: 0.6084 MB / 0.6093 GB, free: 11.8 MB / 462.1 MB
Error: post-optimization module simplifyComplexFunction (initialization) failed.
Error: No system for the symbolic initialization was generated
"
[Timeout remaining time 659]
[Calling sys.exit(0), Time elapsed: 3.248782522045076]
Failed to read output from testmodel.py, exit status != 0:
0.5418378198519349 0.559380592 0.10554381
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7ff1242417b0>