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.001762/0.001762, 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.002177/0.002177, 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.419/1.419, allocations: 225.6 MB / 249.1 MB, free: 3.621 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.1805/0.1805, allocations: 38.5 MB / 342.5 MB, free: 8.027 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: "" <> buildModelFMU(PowerSystems.Examples.AC3ph.Transformation.PhaseShifts,fileNamePrefix="PowerSystems_PowerSystems_Examples_AC3ph_Transformation_PhaseShifts",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(PowerSystems.Examples.AC3ph.Transformation.PhaseShifts,fileNamePrefix="PowerSystems_PowerSystems_Examples_AC3ph_Transformation_PhaseShifts",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.001885/0.001885, allocations: 66.69 kB / 478.6 MB, free: 33.61 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1066/0.1084, allocations: 61.25 MB / 0.5272 GB, free: 1.375 MB / 382.1 MB
Notification: Performance of NFInst.instantiate(PowerSystems.Examples.AC3ph.Transformation.PhaseShifts): time 0.01396/0.1224, allocations: 11.45 MB / 0.5384 GB, free: 5.879 MB / 398.1 MB
Notification: Performance of NFInst.instExpressions: time 0.008436/0.1308, allocations: 4.218 MB / 0.5425 GB, free: 1.648 MB / 398.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001032/0.1319, allocations: 43.75 kB / 0.5425 GB, free: 1.605 MB / 398.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.003521/0.1354, allocations: 1.444 MB / 0.544 GB, free: 160 kB / 398.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.003458/0.1389, allocations: 1.522 MB / 0.5454 GB, free: 14.63 MB / 414.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.002265/0.1411, allocations: 0.8888 MB / 0.5463 GB, free: 13.74 MB / 414.1 MB
Notification: Performance of NFFlatten.flatten: time 0.003991/0.1451, allocations: 3.291 MB / 0.5495 GB, free: 10.44 MB / 414.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001259/0.1464, allocations: 0.8129 MB / 0.5503 GB, free: 9.598 MB / 414.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002039/0.1484, allocations: 1.389 MB / 0.5517 GB, free: 8.203 MB / 414.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002627/0.151, allocations: 2.049 MB / 0.5537 GB, free: 6.148 MB / 414.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0002855/0.1513, allocations: 176 kB / 0.5538 GB, free: 5.977 MB / 414.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001384/0.1527, allocations: 0.7606 MB / 0.5546 GB, free: 5.215 MB / 414.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.001268/0.154, allocations: 1.313 MB / 0.5559 GB, free: 3.898 MB / 414.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.001311/0.1553, allocations: 1.213 MB / 0.5571 GB, free: 2.68 MB / 414.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.003533/0.1588, allocations: 3.183 MB / 0.5602 GB, free: 15.48 MB / 430.1 MB
Notification: Performance of FrontEnd - DAE generated: time 4.238e-06/0.1588, allocations: 0 / 0.5602 GB, free: 15.48 MB / 430.1 MB
Notification: Performance of FrontEnd: time 1.452e-06/0.1588, allocations: 0 / 0.5602 GB, free: 15.48 MB / 430.1 MB
Notification: Performance of Transformations before backend: time 3.378e-05/0.1589, allocations: 4 kB / 0.5602 GB, free: 15.48 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.004475/0.1633, allocations: 2.598 MB / 0.5627 GB, free: 12.82 MB / 430.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.624e-05/0.1634, allocations: 19.88 kB / 0.5627 GB, free: 12.8 MB / 430.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0003597/0.1637, allocations: 312.8 kB / 0.563 GB, free: 12.48 MB / 430.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002202/0.1659, allocations: 486.9 kB / 0.5635 GB, free: 12 MB / 430.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003016/0.169, allocations: 1.419 MB / 0.5649 GB, free: 10.54 MB / 430.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001215/0.1691, allocations: 144.1 kB / 0.565 GB, free: 10.4 MB / 430.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.000519/0.1696, allocations: 227.8 kB / 0.5652 GB, free: 10.18 MB / 430.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.005123/0.1747, allocations: 3.147 MB / 0.5683 GB, free: 6.824 MB / 430.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 5.855e-05/0.1748, allocations: 7.938 kB / 0.5683 GB, free: 6.816 MB / 430.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001959/0.175, allocations: 83.95 kB / 0.5684 GB, free: 6.734 MB / 430.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0007608/0.1757, allocations: 323.8 kB / 0.5687 GB, free: 6.414 MB / 430.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.004519/0.1803, allocations: 2.568 MB / 0.5712 GB, free: 3.828 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.01833/0.1986, allocations: 13.57 MB / 0.5845 GB, free: 5.438 MB / 446.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.002253/0.2008, allocations: 1.396 MB / 0.5858 GB, free: 3.992 MB / 446.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0009645/0.2018, allocations: 0.5878 MB / 0.5864 GB, free: 3.402 MB / 446.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 7.891e-05/0.2019, allocations: 29.28 kB / 0.5864 GB, free: 3.375 MB / 446.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.381e-05/0.2019, allocations: 47.88 kB / 0.5865 GB, free: 3.32 MB / 446.1 MB
Notification: Performance of pre-optimization done (n=132): time 3.447e-06/0.2019, allocations: 0 / 0.5865 GB, free: 3.32 MB / 446.1 MB
Notification: Performance of matching and sorting (n=134): time 0.007072/0.209, allocations: 4.15 MB / 0.5905 GB, free: 15.07 MB / 462.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.63e-05/0.2091, allocations: 98.36 kB / 0.5906 GB, free: 14.96 MB / 462.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001735/0.2108, allocations: 1.265 MB / 0.5918 GB, free: 13.68 MB / 462.1 MB
Notification: Performance of collectPreVariables (initialization): time 8.839e-05/0.2109, allocations: 49.69 kB / 0.5919 GB, free: 13.62 MB / 462.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0008875/0.2118, allocations: 1.123 MB / 0.593 GB, free: 12.48 MB / 462.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0004583/0.2123, allocations: 0.5036 MB / 0.5935 GB, free: 11.98 MB / 462.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0005587/0.2128, allocations: 403.9 kB / 0.5939 GB, free: 11.57 MB / 462.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001455/0.213, allocations: 304.9 kB / 0.5942 GB, free: 11.28 MB / 462.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001128/0.2141, allocations: 0.6875 MB / 0.5948 GB, free: 10.59 MB / 462.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001874/0.216, allocations: 1.58 MB / 0.5964 GB, free: 8.578 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.01013/0.2261, allocations: 4.605 MB / 0.6009 GB, free: 3.531 MB / 462.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.042e-05/0.2261, allocations: 8 kB / 0.6009 GB, free: 3.523 MB / 462.1 MB
Notification: Performance of matching and sorting (n=280) (initialization): time 0.00562/0.2317, allocations: 3.032 MB / 0.6038 GB, free: 456 kB / 462.1 MB
Notification: Performance of prepare postOptimizeDAE: time 3.393e-05/0.2318, allocations: 20 kB / 0.6039 GB, free: 436 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.2333/0.4651, allocations: 0.6082 MB / 0.6045 GB, free: 70.13 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.044526272919029]
Failed to read output from testmodel.py, exit status != 0:
0.4716019802726805 0.484249476 0.322460795
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7efcc81ee020>