Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_latest_Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepDown.YD.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.0008308/0.0008308, allocations: 80.48 kB / 19.93 MB, free: 4.633 MB / 18.57 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.0008426/0.0008426, allocations: 173.9 kB / 23.21 MB, free: 1.352 MB / 18.57 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 0.8417/0.8417, allocations: 177.1 MB / 203.5 MB, free: 5.715 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo): time 1.722/1.722, allocations: 389 MB / 0.6338 GB, free: 12.72 MB / 0.6042 GB
"
[Timeout remaining time 178]
Using package Buildings with version 13.0.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 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(Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepDown.YD,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|err_V2.1.|err_V2.2.|err_V2.3.|err_V3.1.|err_V3.2.|err_V3.3.|err_V4.1.|err_V4.2.|err_V4.3.|err_Theta2.1.|err_Theta2.2.|err_Theta2.3.|err_Theta3.1.|err_Theta3.2.|err_Theta3.3.|err_Theta4.1.|err_Theta4.2.|err_Theta4.3.",fileNamePrefix="Buildings_latest_Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepDown.YD")
translateModel(Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepDown.YD,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|err_V2.1.|err_V2.2.|err_V2.3.|err_V3.1.|err_V3.2.|err_V3.3.|err_V4.1.|err_V4.2.|err_V4.3.|err_Theta2.1.|err_Theta2.2.|err_Theta2.3.|err_Theta3.1.|err_Theta3.2.|err_Theta3.3.|err_Theta4.1.|err_Theta4.2.|err_Theta4.3.",fileNamePrefix="Buildings_latest_Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepDown.YD") [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 1.463e-06/1.463e-06, allocations: 0 / 1.001 GB, free: 12.92 MB / 0.9636 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.529e-05/1.675e-05, allocations: 5.234 kB / 1.001 GB, free: 12.91 MB / 0.9636 GB
Notification: Performance of NFInst.instantiate(Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepDown.YD): time 0.01266/0.01268, allocations: 17.53 MB / 1.018 GB, free: 11.33 MB / 0.9792 GB
Notification: Performance of NFInst.instExpressions: time 0.007187/0.01987, allocations: 6.671 MB / 1.025 GB, free: 4.637 MB / 0.9792 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0006782/0.02054, allocations: 63.75 kB / 1.025 GB, free: 4.574 MB / 0.9792 GB
Notification: Performance of NFTyping.typeComponents: time 0.001887/0.02243, allocations: 1.488 MB / 1.026 GB, free: 3.082 MB / 0.9792 GB
Notification: Performance of NFTyping.typeBindings: time 0.001665/0.0241, allocations: 1.488 MB / 1.028 GB, free: 1.586 MB / 0.9792 GB
Notification: Performance of NFTyping.typeClassSections: time 0.001571/0.02567, allocations: 1.285 MB / 1.029 GB, free: 308 kB / 0.9792 GB
Notification: Performance of NFFlatten.flatten: time 0.003233/0.0289, allocations: 5.542 MB / 1.034 GB, free: 10.75 MB / 0.9948 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002688/0.03159, allocations: 3.39 MB / 1.038 GB, free: 7.262 MB / 0.9948 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.002542/0.03413, allocations: 3.337 MB / 1.041 GB, free: 3.918 MB / 0.9948 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.002189/0.03632, allocations: 2.884 MB / 1.044 GB, free: 1.027 MB / 0.9948 GB
Notification: Performance of NFPackage.collectConstants: time 0.0004136/0.03673, allocations: 0.5156 MB / 1.044 GB, free: 0.5117 MB / 0.9948 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.3932/0.43, allocations: 1.214 MB / 1.045 GB, free: 54.77 MB / 0.9948 GB
Notification: Performance of NFScalarize.scalarize: time 0.001794/0.4318, allocations: 2.411 MB / 1.048 GB, free: 54.77 MB / 0.9948 GB
Notification: Performance of NFVerifyModel.verify: time 0.001184/0.4329, allocations: 1.545 MB / 1.049 GB, free: 54.77 MB / 0.9948 GB
Notification: Performance of NFConvertDAE.convert: time 0.003653/0.4366, allocations: 7.998 MB / 1.057 GB, free: 54.5 MB / 0.9948 GB
Notification: Performance of FrontEnd - DAE generated: time 3.296e-06/0.4366, allocations: 1.125 kB / 1.057 GB, free: 54.5 MB / 0.9948 GB
Notification: Performance of FrontEnd: time 1.292e-06/0.4366, allocations: 0 / 1.057 GB, free: 54.5 MB / 0.9948 GB
Notification: Performance of Transformations before backend: time 3.552e-05/0.4366, allocations: 0 / 1.057 GB, free: 54.5 MB / 0.9948 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 994
 * Number of variables: 994
Notification: Performance of Generate backend data structure: time 0.003462/0.4401, allocations: 3.799 MB / 1.061 GB, free: 53.06 MB / 0.9948 GB
Notification: Performance of prepare preOptimizeDAE: time 3.796e-05/0.4401, allocations: 9.5 kB / 1.061 GB, free: 53.06 MB / 0.9948 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.005919/0.446, allocations: 2.437 MB / 1.063 GB, free: 52.85 MB / 0.9948 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002224/0.4483, allocations: 2.168 MB / 1.065 GB, free: 52.47 MB / 0.9948 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000101/0.4484, allocations: 268.5 kB / 1.065 GB, free: 52.46 MB / 0.9948 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004133/0.4488, allocations: 348.3 kB / 1.066 GB, free: 52.46 MB / 0.9948 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.007269/0.4561, allocations: 7.229 MB / 1.073 GB, free: 50.33 MB / 0.9948 GB
Notification: Performance of preOpt findStateOrder (simulation): time 6.215e-05/0.4561, allocations: 7.219 kB / 1.073 GB, free: 50.33 MB / 0.9948 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003259/0.4564, allocations: 159 kB / 1.073 GB, free: 50.22 MB / 0.9948 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002762/0.4567, allocations: 331.3 kB / 1.073 GB, free: 50.18 MB / 0.9948 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.009559/0.4663, allocations: 7.038 MB / 1.08 GB, free: 48.2 MB / 0.9948 GB
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.01641/0.4827, allocations: 17.89 MB / 1.098 GB, free: 38.01 MB / 0.9948 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.004597/0.4873, allocations: 3.719 MB / 1.101 GB, free: 36.74 MB / 0.9948 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.003401/0.4907, allocations: 2.537 MB / 1.104 GB, free: 35.98 MB / 0.9948 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001425/0.4908, allocations: 49.3 kB / 1.104 GB, free: 35.98 MB / 0.9948 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.664e-05/0.4909, allocations: 59.66 kB / 1.104 GB, free: 35.92 MB / 0.9948 GB
Notification: Performance of pre-optimization done (n=208): time 4.448e-06/0.4909, allocations: 0 / 1.104 GB, free: 35.92 MB / 0.9948 GB
Warning: The linear system: 
1 : line2.v1_n[1] - line2.v1_p[1] = line2.Z11[1] * line2.i1[1] - line2.Z11[2] * line2.i1[2] + line2.Z12[1] * line2.i2[1] - line2.Z12[2] * line2.i2[2] + line2.Z13[1] * line2.i3[1] - line2.Z13[2] * line2.i3[2]
2 : loadRL.load1.v[1] = line2.v1_p[1] - line2.v2_p[1]
3 : line2.v2_n[1] - line2.v2_p[1] = line2.Z21[1] * line2.i1[1] - line2.Z21[2] * line2.i1[2] + line2.Z22[1] * line2.i2[1] - line2.Z22[2] * line2.i2[2] + line2.Z23[1] * line2.i3[1] - line2.Z23[2] * line2.i3[2]
4 : transformer.conv1.V2[1] = line2.v1_n[1] - line2.v2_n[1]
[
  1.0 , 0.0 , 0.0 , -1.0 ;
  0.0 , 0.0 , 1.0 , -1.0 ;
  0.0 , 1.0 , -1.0 , 0.0 ;
  -1.0 , 1.0 , 0.0 , 0.0
]
  *
[
  line2.v1_n[1] ;
  line2.v2_n[1] ;
  line2.v2_p[1] ;
  line2.v1_p[1]
]
  =
[
  line2.Z11[1] * line2.i1[1] + line2.Z13[1] * line2.i3[1] + line2.Z12[1] * line2.i2[1] - line2.Z11[2] * line2.i1[2] - line2.Z13[2] * line2.i3[2] - line2.Z12[2] * line2.i2[2] ;
  -loadRL.load1.v[1] ;
  line2.Z21[1] * line2.i1[1] + line2.Z23[1] * line2.i3[1] + line2.Z22[1] * line2.i2[1] - line2.Z21[2] * line2.i1[2] - line2.Z23[2] * line2.i3[2] - line2.Z22[2] * line2.i2[2] ;
  -transformer.conv1.V2[1]
]
 might be structurally or numerically singular for variable line2.v1_p[1] since U(4,4) = 0.0. It might be hard to solve. Compilation continues anyway.
Notification: Performance of matching and sorting (n=271): time 0.05638/0.5472, allocations: 30.8 MB / 1.134 GB, free: 26.87 MB / 0.9948 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.471e-05/0.5473, allocations: 238 kB / 1.134 GB, free: 26.62 MB / 0.9948 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001472/0.5488, allocations: 1.437 MB / 1.136 GB, free: 25.83 MB / 0.9948 GB
Notification: Performance of collectPreVariables (initialization): time 0.0002067/0.549, allocations: 71.42 kB / 1.136 GB, free: 25.76 MB / 0.9948 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0004771/0.5495, allocations: 1.555 MB / 1.137 GB, free: 24.4 MB / 0.9948 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0005028/0.55, allocations: 0.9474 MB / 1.138 GB, free: 23.59 MB / 0.9948 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0007959/0.5508, allocations: 0.6357 MB / 1.139 GB, free: 23.23 MB / 0.9948 GB
Notification: Performance of setup shared object (initialization): time 2.482e-05/0.5508, allocations: 301.1 kB / 1.139 GB, free: 22.93 MB / 0.9948 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.003363/0.5541, allocations: 2.691 MB / 1.142 GB, free: 21.53 MB / 0.9948 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.003364/0.5575, allocations: 3.641 MB / 1.145 GB, free: 18.96 MB / 0.9948 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.006546/0.5641, allocations: 6.403 MB / 1.151 GB, free: 15.04 MB / 0.9948 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.295e-05/0.5641, allocations: 16.33 kB / 1.151 GB, free: 15.04 MB / 0.9948 GB
Warning: The linear system: 
1 : line2.v1_n[1] - line2.v1_p[1] = line2.Z11[1] * line2.i1[1] - line2.Z11[2] * line2.i1[2] + line2.Z12[1] * line2.i2[1] - line2.Z12[2] * line2.i2[2] + line2.Z13[1] * line2.i3[1] - line2.Z13[2] * line2.i3[2]
2 : loadRL.load1.v[1] = line2.v1_p[1] - line2.v2_p[1]
3 : line2.v2_n[1] - line2.v2_p[1] = line2.Z21[1] * line2.i1[1] - line2.Z21[2] * line2.i1[2] + line2.Z22[1] * line2.i2[1] - line2.Z22[2] * line2.i2[2] + line2.Z23[1] * line2.i3[1] - line2.Z23[2] * line2.i3[2]
4 : transformer.conv1.V2[1] = line2.v1_n[1] - line2.v2_n[1]
[
  1.0 , 0.0 , 0.0 , -1.0 ;
  0.0 , 0.0 , 1.0 , -1.0 ;
  0.0 , 1.0 , -1.0 , 0.0 ;
  -1.0 , 1.0 , 0.0 , 0.0
]
  *
[
  line2.v1_n[1] ;
  line2.v2_n[1] ;
  line2.v2_p[1] ;
  line2.v1_p[1]
]
  =
[
  line2.Z11[1] * line2.i1[1] + line2.Z13[1] * line2.i3[1] + line2.Z12[1] * line2.i2[1] - line2.Z11[2] * line2.i1[2] - line2.Z13[2] * line2.i3[2] - line2.Z12[2] * line2.i2[2] ;
  -loadRL.load1.v[1] ;
  line2.Z21[1] * line2.i1[1] + line2.Z23[1] * line2.i3[1] + line2.Z22[1] * line2.i2[1] - line2.Z21[2] * line2.i1[2] - line2.Z23[2] * line2.i3[2] - line2.Z22[2] * line2.i2[2] ;
  -transformer.conv1.V2[1]
]
 might be structurally or numerically singular for variable line2.v1_p[1] since U(4,4) = 0.0. It might be hard to solve. Compilation continues anyway.
Notification: Performance of matching and sorting (n=410) (initialization): time 0.01451/0.5786, allocations: 10.78 MB / 1.162 GB, free: 8.816 MB / 0.9948 GB
Notification: Performance of prepare postOptimizeDAE: time 2.874e-05/0.5786, allocations: 16 kB / 1.162 GB, free: 8.801 MB / 0.9948 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.228e-05/0.5787, allocations: 28 kB / 1.162 GB, free: 8.773 MB / 0.9948 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.009646/0.5883, allocations: 5.532 MB / 1.167 GB, free: 3.23 MB / 0.9948 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00218/0.5905, allocations: 0.9891 MB / 1.168 GB, free: 2.277 MB / 0.9948 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02621/0.6167, allocations: 27.41 MB / 1.195 GB, free: 6.637 MB / 1.026 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002785/0.6195, allocations: 0.5732 MB / 1.196 GB, free: 6.062 MB / 1.026 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002354/0.6197, allocations: 155.9 kB / 1.196 GB, free: 5.91 MB / 1.026 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.005266/0.625, allocations: 3.984 MB / 1.2 GB, free: 1.918 MB / 1.026 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.003866/0.6288, allocations: 3.606 MB / 1.203 GB, free: 13.93 MB / 1.042 GB
Error: Initialization problem is structural singular. Please, check the initial conditions.
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.04635/0.6752, allocations: 24.46 MB / 1.227 GB, free: 5.102 MB / 1.057 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 2.386e-05/0.6752, allocations: 8 kB / 1.227 GB, free: 5.094 MB / 1.057 GB
Warning: The linear system: 
1 : transformer.conv3.V2[2] = line2.v3_n[2] - line2.v1_n[2]
2 : line2.v3_n[2] - line2.v3_p[2] = line2.Z31[1] * line2.i1[2] + line2.Z31[2] * line2.i1[1] + line2.Z32[1] * line2.i2[2] + line2.Z32[2] * line2.i2[1] + line2.Z33[1] * line2.i3[2] + line2.Z33[2] * line2.i3[1]
3 : loadRL.load3.v[2] = line2.v3_p[2] - line2.v1_p[2]
4 : line2.v1_n[2] - line2.v1_p[2] = line2.Z11[1] * line2.i1[2] + line2.Z11[2] * line2.i1[1] + line2.Z12[1] * line2.i2[2] + line2.Z12[2] * line2.i2[1] + line2.Z13[1] * line2.i3[2] + line2.Z13[2] * line2.i3[1]
[
  1.0 , 0.0 , 0.0 , -1.0 ;
  0.0 , 0.0 , -1.0 , 1.0 ;
  0.0 , 1.0 , -1.0 , 0.0 ;
  1.0 , -1.0 , 0.0 , 0.0
]
  *
[
  line2.v1_n[2] ;
  line2.v1_p[2] ;
  line2.v3_p[2] ;
  line2.v3_n[2]
]
  =
[
  -transformer.conv3.V2[2] ;
  line2.Z31[1] * line2.i1[2] + line2.Z33[1] * line2.i3[2] + line2.Z33[2] * line2.i3[1] + line2.Z32[2] * line2.i2[1] + line2.Z32[1] * line2.i2[2] + line2.Z31[2] * line2.i1[1] ;
  -loadRL.load3.v[2] ;
  line2.Z11[1] * line2.i1[2] + line2.Z13[1] * line2.i3[2] + line2.Z13[2] * line2.i3[1] + line2.Z12[2] * line2.i2[1] + line2.Z12[1] * line2.i2[2] + line2.Z11[2] * line2.i1[1]
]
 might be structurally or numerically singular for variable line2.v3_n[2] since U(4,4) = 0.0. It might be hard to solve. Compilation continues anyway.
Warning: The linear system: 
1 : transformer.conv1.V2[1] = line2.v1_n[1] - line2.v2_n[1]
2 : line2.v1_n[1] - line2.v1_p[1] = line2.Z11[1] * line2.i1[1] - line2.Z11[2] * line2.i1[2] + line2.Z12[1] * line2.i2[1] - line2.Z12[2] * line2.i2[2] + line2.Z13[1] * line2.i3[1] - line2.Z13[2] * line2.i3[2]
3 : loadRL.load1.v[1] = line2.v1_p[1] - line2.v2_p[1]
4 : line2.v2_n[1] - line2.v2_p[1] = line2.Z21[1] * line2.i1[1] - line2.Z21[2] * line2.i1[2] + line2.Z22[1] * line2.i2[1] - line2.Z22[2] * line2.i2[2] + line2.Z23[1] * line2.i3[1] - line2.Z23[2] * line2.i3[2]
[
  1.0 , 0.0 , 0.0 , -1.0 ;
  0.0 , 0.0 , -1.0 , 1.0 ;
  0.0 , 1.0 , -1.0 , 0.0 ;
  1.0 , -1.0 , 0.0 , 0.0
]
  *
[
  line2.v2_n[1] ;
  line2.v2_p[1] ;
  line2.v1_p[1] ;
  line2.v1_n[1]
]
  =
[
  -transformer.conv1.V2[1] ;
  line2.Z11[1] * line2.i1[1] + line2.Z13[1] * line2.i3[1] + line2.Z12[1] * line2.i2[1] - line2.Z11[2] * line2.i1[2] - line2.Z13[2] * line2.i3[2] - line2.Z12[2] * line2.i2[2] ;
  -loadRL.load1.v[1] ;
  line2.Z21[1] * line2.i1[1] + line2.Z23[1] * line2.i3[1] + line2.Z22[1] * line2.i2[1] - line2.Z21[2] * line2.i1[2] - line2.Z23[2] * line2.i3[2] - line2.Z22[2] * line2.i2[2]
]
 might be structurally or numerically singular for variable line2.v1_n[1] since U(4,4) = 0.0. It might be hard to solve. Compilation continues anyway.
Notification: Performance of matching and sorting (n=410) (initialization_lambda0): time 0.01453/0.6897, allocations: 10.17 MB / 1.237 GB, free: 11.07 MB / 1.073 GB
Notification: Performance of prepare postOptimizeDAE: time 2.492e-05/0.6898, allocations: 16 kB / 1.237 GB, free: 11.06 MB / 1.073 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.515e-05/0.6898, allocations: 28 kB / 1.237 GB, free: 11.03 MB / 1.073 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.00797/0.6978, allocations: 4.321 MB / 1.241 GB, free: 6.699 MB / 1.073 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002467/0.7002, allocations: 1.037 MB / 1.242 GB, free: 5.688 MB / 1.073 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02166/0.7219, allocations: 26.12 MB / 1.268 GB, free: 11.05 MB / 1.104 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002769/0.7247, allocations: 0.5538 MB / 1.268 GB, free: 10.5 MB / 1.104 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002229/0.7249, allocations: 159.9 kB / 1.269 GB, free: 10.34 MB / 1.104 GB
Warning: Assuming fixed start value for the following 2 variables:
         node4.wyeToDelta.delta.phase[3].v[2]:VARIABLE(flow=false unit = \"V\" fixed = true )  \"Voltage vector\" type: Real [3,2]
         node4.wyeToDelta.delta.phase[2].v[1]:VARIABLE(flow=false unit = \"V\" fixed = true )  \"Voltage vector\" type: Real [3,2]
Warning: The initial conditions are over specified. The following 2 initial equations are redundant, so they are removed from the initialization_lambda0 system:
         0.0 = (-line2.i2[2]) - line2.i3[2] - line2.i1[2]
         0.0 = (-line2.i2[1]) - line2.i3[1] - line2.i1[1].
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 119
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (301):
 * Single equations (assignments): 298
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 2 systems
   {(1,3,100.0%), (10,44,70.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(10,44)}
Notification: Performance of prepare postOptimizeDAE: time 0.0007724/0.7257, allocations: 403.2 kB / 1.269 GB, free: 9.941 MB / 1.104 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003781/0.726, allocations: 202.7 kB / 1.269 GB, free: 9.742 MB / 1.104 GB
Warning: The linear system: 
1 : line2.v1_n[1] - line2.v1_p[1] = line2.Z11[1] * line2.i1[1] - line2.Z11[2] * line2.i1[2] + line2.Z12[1] * line2.i2[1] - line2.Z12[2] * line2.i2[2] + line2.Z13[1] * line2.i3[1] - line2.Z13[2] * line2.i3[2]
2 : loadRL.load1.v[1] = line2.v1_p[1] - line2.v2_p[1]
3 : line2.v2_n[1] - line2.v2_p[1] = line2.Z21[1] * line2.i1[1] - line2.Z21[2] * line2.i1[2] + line2.Z22[1] * line2.i2[1] - line2.Z22[2] * line2.i2[2] + line2.Z23[1] * line2.i3[1] - line2.Z23[2] * line2.i3[2]
4 : transformer.conv1.V2[1] = line2.v1_n[1] - line2.v2_n[1]
[
  1.0 , 0.0 , 0.0 , -1.0 ;
  0.0 , 0.0 , 1.0 , -1.0 ;
  0.0 , 1.0 , -1.0 , 0.0 ;
  -1.0 , 1.0 , 0.0 , 0.0
]
  *
[
  line2.v1_n[1] ;
  line2.v2_n[1] ;
  line2.v2_p[1] ;
  line2.v1_p[1]
]
  =
[
  line2.Z11[1] * line2.i1[1] + line2.Z13[1] * line2.i3[1] + line2.Z12[1] * line2.i2[1] - line2.Z11[2] * line2.i1[2] - line2.Z13[2] * line2.i3[2] - line2.Z12[2] * line2.i2[2] ;
  -loadRL.load1.v[1] ;
  line2.Z21[1] * line2.i1[1] + line2.Z23[1] * line2.i3[1] + line2.Z22[1] * line2.i2[1] - line2.Z21[2] * line2.i1[2] - line2.Z23[2] * line2.i3[2] - line2.Z22[2] * line2.i2[2] ;
  -transformer.conv1.V2[1]
]
 might be structurally or numerically singular for variable line2.v1_p[1] since U(4,4) = 0.0. It might be hard to solve. Compilation continues anyway.
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01461/0.7407, allocations: 9.922 MB / 1.279 GB, free: 15.79 MB / 1.12 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.599e-05/0.7407, allocations: 28 kB / 1.279 GB, free: 15.76 MB / 1.12 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.616e-05/0.7407, allocations: 15.94 kB / 1.279 GB, free: 15.74 MB / 1.12 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.783e-05/0.7407, allocations: 15.95 kB / 1.279 GB, free: 15.73 MB / 1.12 GB
Warning: The linear system: 
1 : loadRL.load1.v[1] = line2.v1_p[1] - line2.v2_p[1]
2 : line2.v2_n[1] - line2.v2_p[1] = line2.Z21[1] * line2.i1[1] - line2.Z21[2] * line2.i1[2] + line2.Z22[1] * line2.i2[1] - line2.Z22[2] * line2.i2[2] + line2.Z23[1] * line2.i3[1] - line2.Z23[2] * line2.i3[2]
3 : transformer.conv1.V2[1] = line2.v1_n[1] - line2.v2_n[1]
4 : line2.v1_n[1] - line2.v1_p[1] = line2.Z11[1] * line2.i1[1] - line2.Z11[2] * line2.i1[2] + line2.Z12[1] * line2.i2[1] - line2.Z12[2] * line2.i2[2] + line2.Z13[1] * line2.i3[1] - line2.Z13[2] * line2.i3[2]
[
  -1.0 , 0.0 , 0.0 , 1.0 ;
  0.0 , 0.0 , 1.0 , -1.0 ;
  0.0 , -1.0 , 1.0 , 0.0 ;
  -1.0 , 1.0 , 0.0 , 0.0
]
  *
[
  line2.v1_p[1] ;
  line2.v1_n[1] ;
  line2.v2_n[1] ;
  line2.v2_p[1]
]
  =
[
  -loadRL.load1.v[1] ;
  line2.Z21[1] * line2.i1[1] + line2.Z23[1] * line2.i3[1] + line2.Z22[1] * line2.i2[1] - line2.Z21[2] * line2.i1[2] - line2.Z23[2] * line2.i3[2] - line2.Z22[2] * line2.i2[2] ;
  -transformer.conv1.V2[1] ;
  line2.Z11[1] * line2.i1[1] + line2.Z13[1] * line2.i3[1] + line2.Z12[1] * line2.i2[1] - line2.Z11[2] * line2.i1[2] - line2.Z13[2] * line2.i3[2] - line2.Z12[2] * line2.i2[2]
]
 might be structurally or numerically singular for variable line2.v2_p[1] since U(4,4) = 0.0. It might be hard to solve. Compilation continues anyway.
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01956/0.7603, allocations: 14.79 MB / 1.293 GB, free: 0.8359 MB / 1.12 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 9.488e-06/0.7603, allocations: 8.938 kB / 1.293 GB, free: 0.8281 MB / 1.12 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001602/0.7619, allocations: 0.7294 MB / 1.294 GB, free: 100 kB / 1.12 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.00942/0.7713, allocations: 5.176 MB / 1.299 GB, free: 10.9 MB / 1.135 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001861/0.7715, allocations: 71.95 kB / 1.299 GB, free: 10.83 MB / 1.135 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.02439/0.7959, allocations: 26.45 MB / 1.325 GB, free: 28 kB / 1.151 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 6.231e-06/0.7959, allocations: 12.03 kB / 1.325 GB, free: 16 kB / 1.151 GB
Warning: The linear system: 
1 : transformer.conv1.V2[1] = line2.v1_n[1] - line2.v2_n[1]
2 : line2.v1_n[1] - line2.v1_p[1] = line2.Z11[1] * line2.i1[1] - line2.Z11[2] * line2.i1[2] + line2.Z12[1] * line2.i2[1] - line2.Z12[2] * line2.i2[2] + line2.Z13[1] * line2.i3[1] - line2.Z13[2] * line2.i3[2]
3 : loadRL.load1.v[1] = line2.v1_p[1] - line2.v2_p[1]
4 : line2.v2_n[1] - line2.v2_p[1] = line2.Z21[1] * line2.i1[1] - line2.Z21[2] * line2.i1[2] + line2.Z22[1] * line2.i2[1] - line2.Z22[2] * line2.i2[2] + line2.Z23[1] * line2.i3[1] - line2.Z23[2] * line2.i3[2]
[
  1.0 , 0.0 , 0.0 , -1.0 ;
  0.0 , 0.0 , -1.0 , 1.0 ;
  0.0 , 1.0 , -1.0 , 0.0 ;
  1.0 , -1.0 , 0.0 , 0.0
]
  *
[
  line2.v2_n[1] ;
  line2.v2_p[1] ;
  line2.v1_p[1] ;
  line2.v1_n[1]
]
  =
[
  -transformer.conv1.V2[1] ;
  line2.Z11[1] * line2.i1[1] + line2.Z13[1] * line2.i3[1] + line2.Z12[1] * line2.i2[1] - line2.Z11[2] * line2.i1[2] - line2.Z13[2] * line2.i3[2] - line2.Z12[2] * line2.i2[2] ;
  -loadRL.load1.v[1] ;
  line2.Z21[1] * line2.i1[1] + line2.Z23[1] * line2.i3[1] + line2.Z22[1] * line2.i2[1] - line2.Z21[2] * line2.i1[2] - line2.Z23[2] * line2.i3[2] - line2.Z22[2] * line2.i2[2]
]
 might be structurally or numerically singular for variable line2.v1_n[1] since U(4,4) = 0.0. It might be hard to solve. Compilation continues anyway.
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01465/0.8105, allocations: 9.847 MB / 1.335 GB, free: 5.957 MB / 1.167 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001427/0.812, allocations: 0.6505 MB / 1.335 GB, free: 5.309 MB / 1.167 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002374/0.8122, allocations: 59.98 kB / 1.335 GB, free: 5.25 MB / 1.167 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001766/0.814, allocations: 0.5031 MB / 1.336 GB, free: 4.746 MB / 1.167 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0005213/0.8145, allocations: 242.3 kB / 1.336 GB, free: 4.512 MB / 1.167 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001469/0.8146, allocations: 95.94 kB / 1.336 GB, free: 4.418 MB / 1.167 GB
Notification: Performance of sorting global known variables: time 0.000982/0.8156, allocations: 0.944 MB / 1.337 GB, free: 3.469 MB / 1.167 GB
Notification: Performance of sort global known variables: time 7e-08/0.8156, allocations: 0 / 1.337 GB, free: 3.469 MB / 1.167 GB
Notification: Performance of remove unused functions: time 0.004077/0.8197, allocations: 1.852 MB / 1.339 GB, free: 1.617 MB / 1.167 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 34
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (165):
 * Single equations (assignments): 162
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 2 systems
   {(10,44,70.0%), (1,3,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(10,44)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.0003394/0.82, allocations: 174.9 kB / 1.339 GB, free: 1.438 MB / 1.167 GB
Notification: Performance of simCode: created initialization part: time 0.01703/0.8371, allocations: 12.01 MB / 1.351 GB, free: 5.348 MB / 1.182 GB
Notification: Performance of simCode: created event and clocks part: time 9.638e-06/0.8371, allocations: 4 kB / 1.351 GB, free: 5.344 MB / 1.182 GB
Notification: Performance of simCode: created simulation system equations: time 0.005779/0.8428, allocations: 4.265 MB / 1.355 GB, free: 1.043 MB / 1.182 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001406/0.8442, allocations: 267.2 kB / 1.355 GB, free: 0.7852 MB / 1.182 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01437/0.8586, allocations: 10.22 MB / 1.365 GB, free: 6.434 MB / 1.198 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.00219/0.8608, allocations: 2.363 MB / 1.368 GB, free: 4.027 MB / 1.198 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0008076/0.8616, allocations: 208.3 kB / 1.368 GB, free: 3.824 MB / 1.198 GB
Notification: Performance of SimCode: time 1.363e-06/0.8616, allocations: 0 / 1.368 GB, free: 3.824 MB / 1.198 GB
Notification: Performance of Templates: time 0.07561/0.9372, allocations: 109.2 MB / 1.474 GB, free: 6.621 MB / 1.307 GB
"
[Timeout remaining time 299]
make -j1 -f Buildings_latest_Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepDown.YD.makefile [Timeout 300]
(rm -f Buildings_latest_Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepDown.YD.pipe ; mkfifo Buildings_latest_Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepDown.YD.pipe ; head -c 1048576 < Buildings_latest_Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepDown.YD.pipe >> ../files/Buildings_latest_Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepDown.YD.sim & ./Buildings_latest_Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepDown.YD  -abortSlowSimulation -alarm=800  -emit_protected -lv LOG_STATS > Buildings_latest_Buildings.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepDown.YD.pipe 2>&1) [Timeout 800]
[Calling os._exit(0), Time elapsed: 10.858466811478138]
Failed to read output from testmodel.py, exit status != 0:
0.9431893955916166 0.95131212 0.5130110959999999
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x73e1a4a7a210>