Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PowerSystems_PowerSystems.Examples.AC3ph.Generation.Vsource.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.001699/0.001699, allocations: 73.88 kB / 21.24 MB, free: 0.5547 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.002519/0.002519, allocations: 160.1 kB / 24.5 MB, free: 3.715 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.455/1.455, allocations: 177.2 MB / 204.9 MB, free: 8.996 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.1783/0.1783, allocations: 29.43 MB / 290.7 MB, free: 3.273 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.Generation.Vsource,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="PowerSystems_PowerSystems.Examples.AC3ph.Generation.Vsource")
translateModel(PowerSystems.Examples.AC3ph.Generation.Vsource,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="PowerSystems_PowerSystems.Examples.AC3ph.Generation.Vsource") [Timeout 660]
"Notification: PowerSystems requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.002222/0.002222, allocations: 59.91 kB / 431.2 MB, free: 3.23 MB / 398.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3295/0.3318, allocations: 62.45 MB / 493.6 MB, free: 37.8 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(PowerSystems.Examples.AC3ph.Generation.Vsource): time 0.004849/0.3366, allocations: 4.601 MB / 498.2 MB, free: 34.68 MB / 446.1 MB
Notification: Performance of NFInst.instExpressions: time 0.001932/0.3385, allocations: 1.398 MB / 499.6 MB, free: 33.76 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0001805/0.3387, allocations: 21.41 kB / 499.7 MB, free: 33.75 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0009967/0.3397, allocations: 396 kB / 0.4883 GB, free: 33.53 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0004842/0.3402, allocations: 282.7 kB / 0.4886 GB, free: 33.36 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0005038/0.3407, allocations: 224.4 kB / 0.4888 GB, free: 33.26 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.0004995/0.3412, allocations: 0.6276 MB / 0.4894 GB, free: 32.98 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0003192/0.3415, allocations: 240.7 kB / 0.4896 GB, free: 32.88 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0003404/0.3419, allocations: 302.9 kB / 0.4899 GB, free: 32.75 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0004278/0.3423, allocations: 326.4 kB / 0.4902 GB, free: 32.64 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 5.38e-05/0.3423, allocations: 33.75 kB / 0.4903 GB, free: 32.64 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0002704/0.3426, allocations: 147.5 kB / 0.4904 GB, free: 32.61 MB / 446.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.000155/0.3428, allocations: 215.3 kB / 0.4906 GB, free: 32.53 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0001859/0.343, allocations: 202.6 kB / 0.4908 GB, free: 32.43 MB / 446.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.0004901/0.3434, allocations: 0.586 MB / 0.4914 GB, free: 32.21 MB / 446.1 MB
Notification: Performance of FrontEnd - DAE generated: time 3.738e-06/0.3435, allocations: 3.188 kB / 0.4914 GB, free: 32.21 MB / 446.1 MB
Notification: Performance of FrontEnd: time 1.012e-06/0.3435, allocations: 0 / 0.4914 GB, free: 32.21 MB / 446.1 MB
Notification: Performance of Transformations before backend: time 8.626e-06/0.3435, allocations: 0 / 0.4914 GB, free: 32.21 MB / 446.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 93
 * Number of variables: 93
Notification: Performance of Generate backend data structure: time 0.00102/0.3445, allocations: 0.6893 MB / 0.4921 GB, free: 31.69 MB / 446.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.894e-05/0.3445, allocations: 13.27 kB / 0.4921 GB, free: 31.69 MB / 446.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0008013/0.3453, allocations: 112.9 kB / 0.4922 GB, free: 31.66 MB / 446.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0006617/0.346, allocations: 383.8 kB / 0.4926 GB, free: 31.41 MB / 446.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 2.608e-05/0.346, allocations: 28.16 kB / 0.4926 GB, free: 31.4 MB / 446.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001614/0.3462, allocations: 65.36 kB / 0.4926 GB, free: 31.37 MB / 446.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0006021/0.3468, allocations: 495.7 kB / 0.4931 GB, free: 31.14 MB / 446.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.161e-05/0.3468, allocations: 0 / 0.4931 GB, free: 31.14 MB / 446.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 3.633e-05/0.3468, allocations: 18.66 kB / 0.4931 GB, free: 31.13 MB / 446.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.000194/0.347, allocations: 25.75 kB / 0.4932 GB, free: 31.13 MB / 446.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0004474/0.3475, allocations: 350.9 kB / 0.4935 GB, free: 31.02 MB / 446.1 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.001949/0.3494, allocations: 1.465 MB / 0.4949 GB, free: 30.05 MB / 446.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.000302/0.3497, allocations: 223 kB / 0.4951 GB, free: 29.94 MB / 446.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0001758/0.3499, allocations: 154 kB / 0.4953 GB, free: 29.88 MB / 446.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 1.88e-05/0.3499, allocations: 3.281 kB / 0.4953 GB, free: 29.88 MB / 446.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.261e-05/0.3499, allocations: 33.72 kB / 0.4953 GB, free: 29.84 MB / 446.1 MB
Notification: Performance of pre-optimization done (n=23): time 1.964e-06/0.3499, allocations: 0 / 0.4953 GB, free: 29.84 MB / 446.1 MB
Notification: Performance of matching and sorting (n=27): time 0.001649/0.3516, allocations: 1.048 MB / 0.4963 GB, free: 29.27 MB / 446.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.594e-05/0.3516, allocations: 84.33 kB / 0.4964 GB, free: 29.18 MB / 446.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0002239/0.3519, allocations: 317.1 kB / 0.4967 GB, free: 29 MB / 446.1 MB
Notification: Performance of collectPreVariables (initialization): time 2.019e-05/0.3519, allocations: 32.92 kB / 0.4968 GB, free: 28.96 MB / 446.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0001029/0.352, allocations: 258.9 kB / 0.497 GB, free: 28.75 MB / 446.1 MB
Notification: Performance of collectInitialBindings (initialization): time 5.536e-05/0.352, allocations: 93.14 kB / 0.4971 GB, free: 28.68 MB / 446.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 9.023e-05/0.3521, allocations: 75.62 kB / 0.4972 GB, free: 28.63 MB / 446.1 MB
Notification: Performance of setup shared object (initialization): time 5.622e-05/0.3522, allocations: 349.8 kB / 0.4975 GB, free: 28.3 MB / 446.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0001759/0.3524, allocations: 140.3 kB / 0.4976 GB, free: 28.26 MB / 446.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0001996/0.3526, allocations: 232.3 kB / 0.4978 GB, free: 28.11 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.002121/0.3547, allocations: 0.9512 MB / 0.4988 GB, free: 27.73 MB / 446.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.974e-06/0.3547, allocations: 0 / 0.4988 GB, free: 27.73 MB / 446.1 MB
Notification: Performance of matching and sorting (n=44) (initialization): time 0.001073/0.3557, allocations: 0.641 MB / 0.4994 GB, free: 27.45 MB / 446.1 MB
Notification: Performance of prepare postOptimizeDAE: time 1.669e-05/0.3558, allocations: 12.53 kB / 0.4994 GB, free: 27.45 MB / 446.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.000233/0.356, allocations: 156.9 kB / 0.4996 GB, free: 27.39 MB / 446.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.785203523002565]
Failed to read output from testmodel.py, exit status != 0:
0.3633635889273137 0.37348258900000003 0.027497003000000002
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f05a7ac2fe0>