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.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.001668/0.001668, allocations: 82.36 kB / 20.68 MB, free: 1.023 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.001664/0.001664, allocations: 162.9 kB / 23.96 MB, free: 4.145 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.476/1.476, allocations: 177.1 MB / 204.3 MB, free: 9.195 MB / 190.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.1789/0.1789, allocations: 29.44 MB / 290.1 MB, free: 3.285 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.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="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 1.393e-06/1.393e-06, allocations: 0 / 430.7 MB, free: 1.961 MB / 350.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.545e-05/2.684e-05, allocations: 9.453 kB / 430.7 MB, free: 1.953 MB / 350.1 MB
Notification: Performance of NFInst.instantiate(PowerSystems.Examples.AC3ph.Transformation.PhaseShifts): time 0.01299/0.01302, allocations: 10.39 MB / 441.1 MB, free: 7.508 MB / 366.1 MB
Notification: Performance of NFInst.instExpressions: time 0.008418/0.02144, allocations: 3.991 MB / 445.1 MB, free: 3.504 MB / 366.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001463/0.0229, allocations: 63.44 kB / 445.2 MB, free: 3.441 MB / 366.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.004671/0.02757, allocations: 1.392 MB / 446.6 MB, free: 2.043 MB / 366.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.004513/0.03209, allocations: 1.555 MB / 448.1 MB, free: 492 kB / 366.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.1913/0.2234, allocations: 0.9412 MB / 449 MB, free: 15.68 MB / 366.1 MB
Notification: Performance of NFFlatten.flatten: time 0.004469/0.2278, allocations: 3.338 MB / 452.4 MB, free: 15.32 MB / 366.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001544/0.2294, allocations: 0.8515 MB / 453.2 MB, free: 15.2 MB / 366.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003247/0.2326, allocations: 1.811 MB / 455 MB, free: 15.02 MB / 366.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003332/0.2359, allocations: 1.92 MB / 457 MB, free: 14.89 MB / 366.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0004337/0.2364, allocations: 175.2 kB / 457.1 MB, free: 14.89 MB / 366.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001902/0.2383, allocations: 0.7795 MB / 457.9 MB, free: 14.88 MB / 366.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.001615/0.2399, allocations: 1.379 MB / 459.3 MB, free: 14.76 MB / 366.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.001323/0.2412, allocations: 0.8389 MB / 460.1 MB, free: 14.67 MB / 366.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.004874/0.2461, allocations: 3.276 MB / 463.4 MB, free: 13.93 MB / 366.1 MB
Notification: Performance of FrontEnd - DAE generated: time 4.618e-06/0.2461, allocations: 1.406 kB / 463.4 MB, free: 13.93 MB / 366.1 MB
Notification: Performance of FrontEnd: time 1.372e-06/0.2461, allocations: 0 / 463.4 MB, free: 13.93 MB / 366.1 MB
Notification: Performance of Transformations before backend: time 9.636e-05/0.2462, allocations: 0 / 463.4 MB, free: 13.93 MB / 366.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.00536/0.2516, allocations: 2.515 MB / 465.9 MB, free: 12.5 MB / 366.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.862e-05/0.2516, allocations: 13.3 kB / 465.9 MB, free: 12.49 MB / 366.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003422/0.255, allocations: 0.6299 MB / 466.6 MB, free: 12.34 MB / 366.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003379/0.2584, allocations: 1.321 MB / 467.9 MB, free: 11.73 MB / 366.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001277/0.2585, allocations: 145.9 kB / 468 MB, free: 11.68 MB / 366.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0006571/0.2592, allocations: 228.1 kB / 468.3 MB, free: 11.61 MB / 366.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.005026/0.2642, allocations: 3.14 MB / 471.4 MB, free: 10.05 MB / 366.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 7.408e-05/0.2643, allocations: 13.25 kB / 471.4 MB, free: 10.05 MB / 366.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002189/0.2645, allocations: 80.86 kB / 471.5 MB, free: 9.996 MB / 366.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0008299/0.2653, allocations: 323.4 kB / 471.8 MB, free: 9.887 MB / 366.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.005274/0.2706, allocations: 2.551 MB / 474.4 MB, free: 8.707 MB / 366.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.01611/0.2867, allocations: 11.74 MB / 486.1 MB, free: 14.35 MB / 382.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.002217/0.2889, allocations: 1.25 MB / 487.3 MB, free: 13.09 MB / 382.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0009456/0.2899, allocations: 0.5906 MB / 487.9 MB, free: 12.5 MB / 382.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 8.072e-05/0.29, allocations: 21.3 kB / 488 MB, free: 12.48 MB / 382.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.531e-05/0.29, allocations: 43.88 kB / 488 MB, free: 12.43 MB / 382.1 MB
Notification: Performance of pre-optimization done (n=132): time 5.6e-06/0.29, allocations: 7.219 kB / 488 MB, free: 12.43 MB / 382.1 MB
Notification: Performance of matching and sorting (n=134): time 0.007599/0.2976, allocations: 3.926 MB / 491.9 MB, free: 8.473 MB / 382.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 8.601e-05/0.2977, allocations: 94.41 kB / 492 MB, free: 8.363 MB / 382.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001826/0.2995, allocations: 1.295 MB / 493.3 MB, free: 7.051 MB / 382.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0001061/0.2996, allocations: 45.7 kB / 493.4 MB, free: 7 MB / 382.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0009104/0.3005, allocations: 1.114 MB / 494.5 MB, free: 5.883 MB / 382.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0004577/0.301, allocations: 497.9 kB / 495 MB, free: 5.402 MB / 382.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0005941/0.3016, allocations: 409.2 kB / 495.4 MB, free: 4.996 MB / 382.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001348/0.3017, allocations: 305.1 kB / 495.7 MB, free: 4.695 MB / 382.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001187/0.3029, allocations: 0.683 MB / 496.3 MB, free: 4.012 MB / 382.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00191/0.3048, allocations: 1.585 MB / 497.9 MB, free: 2.012 MB / 382.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.01105/0.3159, allocations: 4.527 MB / 0.4907 GB, free: 13.2 MB / 398.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.835e-05/0.3159, allocations: 8 kB / 0.4907 GB, free: 13.19 MB / 398.1 MB
Notification: Performance of matching and sorting (n=280) (initialization): time 0.005809/0.3217, allocations: 2.958 MB / 0.4936 GB, free: 10.41 MB / 398.1 MB
Notification: Performance of prepare postOptimizeDAE: time 3.391e-05/0.3217, allocations: 16 kB / 0.4936 GB, free: 10.4 MB / 398.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.001105/0.3228, allocations: 0.6093 MB / 0.4942 GB, free: 9.785 MB / 398.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.806286185979843]
Failed to read output from testmodel.py, exit status != 0:
0.3313773274421692 0.34630487400000004 0.096882968
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f9b0f0811e0>