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.001315/0.001315, allocations: 84.3 kB / 20.68 MB, free: 1.035 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.00153/0.00153, allocations: 168.8 kB / 23.95 MB, free: 4.172 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.392/1.392, allocations: 177.2 MB / 204.3 MB, free: 9.172 MB / 190.1 MB
"
[Timeout remaining time 179]
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.1703/0.1703, allocations: 29.44 MB / 290.1 MB, free: 3.277 MB / 270.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: Performance of FrontEnd - loaded program: time 1.703e-06/1.703e-06, allocations: 0 / 430.7 MB, free: 1.945 MB / 350.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.771e-05/2.942e-05, allocations: 5.234 kB / 430.7 MB, free: 1.941 MB / 350.1 MB
Notification: Performance of NFInst.instantiate(PowerSystems.Examples.AC3ph.Transformation.PhaseShifts): time 0.0126/0.01263, allocations: 10.39 MB / 441.1 MB, free: 7.5 MB / 366.1 MB
Notification: Performance of NFInst.instExpressions: time 0.007492/0.02012, allocations: 3.991 MB / 445.1 MB, free: 3.496 MB / 366.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0007817/0.0209, allocations: 63.44 kB / 445.2 MB, free: 3.434 MB / 366.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.003641/0.02454, allocations: 1.402 MB / 446.6 MB, free: 2.027 MB / 366.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.003712/0.02825, allocations: 1.555 MB / 448.1 MB, free: 476 kB / 366.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.1745/0.2028, allocations: 0.9405 MB / 449.1 MB, free: 15.7 MB / 366.1 MB
Notification: Performance of NFFlatten.flatten: time 0.00381/0.2066, allocations: 3.336 MB / 452.4 MB, free: 15.35 MB / 366.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001239/0.2078, allocations: 0.846 MB / 453.2 MB, free: 15.23 MB / 366.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003191/0.211, allocations: 1.812 MB / 455.1 MB, free: 15.05 MB / 366.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003575/0.2146, allocations: 1.918 MB / 457 MB, free: 14.92 MB / 366.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0004953/0.2151, allocations: 179.3 kB / 457.1 MB, free: 14.92 MB / 366.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001999/0.2171, allocations: 0.7787 MB / 457.9 MB, free: 14.91 MB / 366.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.001452/0.2185, allocations: 1.38 MB / 459.3 MB, free: 14.79 MB / 366.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.001152/0.2197, allocations: 0.8364 MB / 460.1 MB, free: 14.7 MB / 366.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.003798/0.2235, allocations: 3.284 MB / 463.4 MB, free: 13.93 MB / 366.1 MB
Notification: Performance of FrontEnd - DAE generated: time 5.17e-06/0.2235, allocations: 0 / 463.4 MB, free: 13.93 MB / 366.1 MB
Notification: Performance of FrontEnd: time 1.323e-06/0.2235, allocations: 0 / 463.4 MB, free: 13.93 MB / 366.1 MB
Notification: Performance of Transformations before backend: time 7.392e-05/0.2236, 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.00479/0.2283, allocations: 2.513 MB / 465.9 MB, free: 12.49 MB / 366.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.279e-05/0.2284, allocations: 12.95 kB / 466 MB, free: 12.48 MB / 366.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003141/0.2315, allocations: 0.6294 MB / 466.6 MB, free: 12.34 MB / 366.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002519/0.234, allocations: 1.318 MB / 467.9 MB, free: 11.73 MB / 366.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 8.969e-05/0.2341, allocations: 140.3 kB / 468 MB, free: 11.68 MB / 366.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004308/0.2346, allocations: 234.5 kB / 468.3 MB, free: 11.61 MB / 366.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.004089/0.2387, allocations: 3.136 MB / 471.4 MB, free: 10.04 MB / 366.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 5.641e-05/0.2387, allocations: 14 kB / 471.4 MB, free: 10.03 MB / 366.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001865/0.2389, allocations: 81.89 kB / 471.5 MB, free: 9.98 MB / 366.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.000693/0.2396, allocations: 321.7 kB / 471.8 MB, free: 9.875 MB / 366.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0036/0.2432, allocations: 2.553 MB / 474.4 MB, free: 8.695 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.0124/0.2556, allocations: 11.74 MB / 486.1 MB, free: 14.34 MB / 382.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.002062/0.2577, allocations: 1.247 MB / 487.4 MB, free: 13.08 MB / 382.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0009282/0.2586, allocations: 0.5996 MB / 487.9 MB, free: 12.48 MB / 382.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 7.22e-05/0.2587, allocations: 13.31 kB / 488 MB, free: 12.47 MB / 382.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.844e-05/0.2587, allocations: 59.86 kB / 488 MB, free: 12.4 MB / 382.1 MB
Notification: Performance of pre-optimization done (n=132): time 3.627e-06/0.2587, allocations: 0 / 488 MB, free: 12.4 MB / 382.1 MB
Notification: Performance of matching and sorting (n=134): time 0.006862/0.2656, allocations: 3.909 MB / 491.9 MB, free: 8.465 MB / 382.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.548e-05/0.2656, allocations: 94.42 kB / 492 MB, free: 8.355 MB / 382.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001659/0.2673, allocations: 1.291 MB / 493.3 MB, free: 7.047 MB / 382.1 MB
Notification: Performance of collectPreVariables (initialization): time 9.088e-05/0.2674, allocations: 53.7 kB / 493.4 MB, free: 6.988 MB / 382.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0008326/0.2682, allocations: 1.116 MB / 494.5 MB, free: 5.871 MB / 382.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0004176/0.2686, allocations: 0.4939 MB / 495 MB, free: 5.387 MB / 382.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.000572/0.2692, allocations: 407.8 kB / 495.4 MB, free: 4.98 MB / 382.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001259/0.2693, allocations: 301.1 kB / 495.7 MB, free: 4.684 MB / 382.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001092/0.2704, allocations: 0.6757 MB / 496.3 MB, free: 4.004 MB / 382.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001881/0.2723, allocations: 1.588 MB / 497.9 MB, free: 2.004 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.01057/0.2829, allocations: 4.53 MB / 0.4907 GB, free: 13.18 MB / 398.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.338e-05/0.2829, allocations: 12 kB / 0.4907 GB, free: 13.17 MB / 398.1 MB
Notification: Performance of matching and sorting (n=280) (initialization): time 0.005742/0.2887, allocations: 2.948 MB / 0.4936 GB, free: 10.41 MB / 398.1 MB
Notification: Performance of prepare postOptimizeDAE: time 3.543e-05/0.2887, allocations: 20 kB / 0.4936 GB, free: 10.39 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.001045/0.2897, allocations: 0.6115 MB / 0.4942 GB, free: 9.773 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.669019915163517]
Failed to read output from testmodel.py, exit status != 0:
0.29827413335442543 0.31156604599999993 0.08512541500000001
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7ff8bd6c4fd0>