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.001882/0.001882, allocations: 121.4 kB / 17.79 MB, free: 5.121 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.00219/0.00219, allocations: 190.9 kB / 20.73 MB, free: 2.184 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.511/1.511, allocations: 225.6 MB / 249.1 MB, free: 3.629 MB / 190.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.221/0.221, allocations: 38.52 MB / 342.5 MB, free: 8.082 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: "" <> buildModelFMU(PowerSystems.Examples.AC3ph.Transformation.PhaseShifts,fileNamePrefix="PowerSystems_latest_PowerSystems_Examples_AC3ph_Transformation_PhaseShifts",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(PowerSystems.Examples.AC3ph.Transformation.PhaseShifts,fileNamePrefix="PowerSystems_latest_PowerSystems_Examples_AC3ph_Transformation_PhaseShifts",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.001984/0.001984, allocations: 72.84 kB / 478.6 MB, free: 33.77 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1106/0.1126, allocations: 61.24 MB / 0.5272 GB, free: 1.406 MB / 382.1 MB
Notification: Performance of NFInst.instantiate(PowerSystems.Examples.AC3ph.Transformation.PhaseShifts): time 0.01473/0.1274, allocations: 11.44 MB / 0.5384 GB, free: 5.914 MB / 398.1 MB
Notification: Performance of NFInst.instExpressions: time 0.008298/0.1357, allocations: 4.214 MB / 0.5425 GB, free: 1.688 MB / 398.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.000994/0.1366, allocations: 43.75 kB / 0.5426 GB, free: 1.645 MB / 398.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.003449/0.1401, allocations: 1.442 MB / 0.544 GB, free: 204 kB / 398.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.00328/0.1434, allocations: 1.518 MB / 0.5454 GB, free: 14.68 MB / 414.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.002232/0.1456, allocations: 0.8927 MB / 0.5463 GB, free: 13.78 MB / 414.1 MB
Notification: Performance of NFFlatten.flatten: time 0.004006/0.1496, allocations: 3.295 MB / 0.5495 GB, free: 10.48 MB / 414.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001215/0.1508, allocations: 0.8129 MB / 0.5503 GB, free: 9.637 MB / 414.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.00223/0.1531, allocations: 1.382 MB / 0.5517 GB, free: 8.25 MB / 414.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003052/0.1561, allocations: 2.056 MB / 0.5537 GB, free: 6.188 MB / 414.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.000396/0.1565, allocations: 176 kB / 0.5539 GB, free: 6.016 MB / 414.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001583/0.1581, allocations: 0.7606 MB / 0.5546 GB, free: 5.254 MB / 414.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.001524/0.1596, allocations: 1.317 MB / 0.5559 GB, free: 3.934 MB / 414.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.001606/0.1612, allocations: 1.209 MB / 0.5571 GB, free: 2.719 MB / 414.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.004046/0.1653, allocations: 3.183 MB / 0.5602 GB, free: 15.52 MB / 430.1 MB
Notification: Performance of FrontEnd - DAE generated: time 5.28e-06/0.1653, allocations: 0 / 0.5602 GB, free: 15.52 MB / 430.1 MB
Notification: Performance of FrontEnd: time 1.583e-06/0.1653, allocations: 4 kB / 0.5602 GB, free: 15.52 MB / 430.1 MB
Notification: Performance of Transformations before backend: time 5.314e-05/0.1653, allocations: 0 / 0.5602 GB, free: 15.52 MB / 430.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.004884/0.1702, allocations: 2.606 MB / 0.5627 GB, free: 12.85 MB / 430.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.959e-05/0.1703, allocations: 11.89 kB / 0.5627 GB, free: 12.84 MB / 430.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0003913/0.1706, allocations: 312.8 kB / 0.563 GB, free: 12.52 MB / 430.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002367/0.173, allocations: 486.9 kB / 0.5635 GB, free: 12.04 MB / 430.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003516/0.1765, allocations: 1.419 MB / 0.5649 GB, free: 10.58 MB / 430.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001369/0.1767, allocations: 144.1 kB / 0.565 GB, free: 10.44 MB / 430.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0005718/0.1772, allocations: 231.7 kB / 0.5652 GB, free: 10.21 MB / 430.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.005575/0.1828, allocations: 3.143 MB / 0.5683 GB, free: 6.863 MB / 430.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 6.471e-05/0.1829, allocations: 15.92 kB / 0.5683 GB, free: 6.848 MB / 430.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001898/0.1831, allocations: 79.97 kB / 0.5684 GB, free: 6.77 MB / 430.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0007472/0.1838, allocations: 319.8 kB / 0.5687 GB, free: 6.453 MB / 430.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.004531/0.1883, allocations: 2.564 MB / 0.5712 GB, free: 3.871 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.0184/0.2067, allocations: 13.58 MB / 0.5845 GB, free: 5.477 MB / 446.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.002415/0.2092, allocations: 1.403 MB / 0.5858 GB, free: 4.023 MB / 446.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001033/0.2102, allocations: 0.5877 MB / 0.5864 GB, free: 3.434 MB / 446.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 7.716e-05/0.2103, allocations: 25.3 kB / 0.5864 GB, free: 3.41 MB / 446.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.424e-05/0.2103, allocations: 47.88 kB / 0.5865 GB, free: 3.355 MB / 446.1 MB
Notification: Performance of pre-optimization done (n=132): time 3.717e-06/0.2103, allocations: 0 / 0.5865 GB, free: 3.355 MB / 446.1 MB
Notification: Performance of matching and sorting (n=134): time 0.007126/0.2174, allocations: 4.15 MB / 0.5905 GB, free: 15.11 MB / 462.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.934e-05/0.2175, allocations: 94.41 kB / 0.5906 GB, free: 15 MB / 462.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001703/0.2192, allocations: 1.265 MB / 0.5919 GB, free: 13.71 MB / 462.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0001094/0.2193, allocations: 45.7 kB / 0.5919 GB, free: 13.66 MB / 462.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0009638/0.2203, allocations: 1.127 MB / 0.593 GB, free: 12.52 MB / 462.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0004569/0.2208, allocations: 0.4958 MB / 0.5935 GB, free: 12.02 MB / 462.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0005461/0.2213, allocations: 411.8 kB / 0.5939 GB, free: 11.61 MB / 462.1 MB
Notification: Performance of setup shared object (initialization): time 0.000126/0.2214, allocations: 308.9 kB / 0.5942 GB, free: 11.31 MB / 462.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001087/0.2225, allocations: 0.6796 MB / 0.5948 GB, free: 10.63 MB / 462.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001854/0.2244, allocations: 1.583 MB / 0.5964 GB, free: 8.617 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.01014/0.2345, allocations: 4.598 MB / 0.6009 GB, free: 3.578 MB / 462.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.326e-05/0.2345, allocations: 12 kB / 0.6009 GB, free: 3.566 MB / 462.1 MB
Notification: Performance of matching and sorting (n=280) (initialization): time 0.005972/0.2405, allocations: 3.032 MB / 0.6038 GB, free: 500 kB / 462.1 MB
Notification: Performance of prepare postOptimizeDAE: time 4.238e-05/0.2405, allocations: 16 kB / 0.6039 GB, free: 484 kB / 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.2704/0.5109, allocations: 0.6102 MB / 0.6045 GB, free: 69.83 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.4365676399320364]
Failed to read output from testmodel.py, exit status != 0:
0.5183536857366562 0.539493411 0.36956662100000004
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fe283616020>