Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_latest_IBPSA.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.001535/0.001535, allocations: 114.9 kB / 17.84 MB, free: 5.312 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.001467/0.001467, allocations: 201.6 kB / 20.8 MB, free: 2.371 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.626/1.626, allocations: 230.6 MB / 254.2 MB, free: 15.08 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA master/package.mo): time 1.02/1.02, allocations: 150 MB / 460.2 MB, free: 5.371 MB / 350.1 MB
"
[Timeout remaining time 179]
Using package IBPSA with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 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: "" <> buildModelFMU(IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepDown.YD,fileNamePrefix="IBPSA_latest_IBPSA_Electrical_AC_ThreePhasesUnbalanced_Validation_IEEETests_Test4NodesFeeder_UnbalancedStepDown_YD",fmuType="cs",version="2.0",platforms={"static"})
"" <> buildModelFMU(IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepDown.YD,fileNamePrefix="IBPSA_latest_IBPSA_Electrical_AC_ThreePhasesUnbalanced_Validation_IEEETests_Test4NodesFeeder_UnbalancedStepDown_YD",fmuType="cs",version="2.0",platforms={"static"}) [Timeout 660]
"Notification: IBPSA 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.001996/0.001996, allocations: 65.12 kB / 0.6369 GB, free: 30.41 MB / 0.4981 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1803/0.1823, allocations: 89.14 MB / 0.724 GB, free: 4.055 MB / 0.5294 GB
Notification: Performance of NFInst.instantiate(IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepDown.YD): time 0.02571/0.208, allocations: 18.6 MB / 0.7421 GB, free: 1.387 MB / 0.545 GB
Notification: Performance of NFInst.instExpressions: time 0.01383/0.2218, allocations: 6.921 MB / 0.7489 GB, free: 10.45 MB / 0.5606 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001763/0.2236, allocations: 63.75 kB / 0.7489 GB, free: 10.39 MB / 0.5606 GB
Notification: Performance of NFTyping.typeComponents: time 0.004656/0.2282, allocations: 1.499 MB / 0.7504 GB, free: 8.883 MB / 0.5606 GB
Notification: Performance of NFTyping.typeBindings: time 0.004157/0.2324, allocations: 1.455 MB / 0.7518 GB, free: 7.422 MB / 0.5606 GB
Notification: Performance of NFTyping.typeClassSections: time 0.003534/0.2359, allocations: 1.273 MB / 0.7531 GB, free: 6.148 MB / 0.5606 GB
Notification: Performance of NFFlatten.flatten: time 0.006927/0.2428, allocations: 5.476 MB / 0.7584 GB, free: 0.6602 MB / 0.5606 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.007923/0.2508, allocations: 5.179 MB / 0.7635 GB, free: 11.37 MB / 0.5762 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.003897/0.2547, allocations: 2.408 MB / 0.7658 GB, free: 8.957 MB / 0.5762 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.005328/0.26, allocations: 3.167 MB / 0.7689 GB, free: 5.781 MB / 0.5762 GB
Notification: Performance of NFPackage.collectConstants: time 0.00153/0.2615, allocations: 0.5195 MB / 0.7694 GB, free: 5.262 MB / 0.5762 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.003487/0.265, allocations: 1.287 MB / 0.7707 GB, free: 3.973 MB / 0.5762 GB
Notification: Performance of NFScalarize.scalarize: time 0.003735/0.2688, allocations: 2.303 MB / 0.7729 GB, free: 1.664 MB / 0.5762 GB
Notification: Performance of NFVerifyModel.verify: time 0.004603/0.2734, allocations: 2.198 MB / 0.7751 GB, free: 15.45 MB / 0.5919 GB
Notification: Performance of NFConvertDAE.convert: time 0.01188/0.2852, allocations: 7.796 MB / 0.7827 GB, free: 7.637 MB / 0.5919 GB
Notification: Performance of FrontEnd - DAE generated: time 6.902e-06/0.2852, allocations: 0 / 0.7827 GB, free: 7.637 MB / 0.5919 GB
Notification: Performance of FrontEnd: time 1.773e-06/0.2852, allocations: 0 / 0.7827 GB, free: 7.637 MB / 0.5919 GB
Notification: Performance of Transformations before backend: time 0.0002393/0.2855, allocations: 0 / 0.7827 GB, free: 7.637 MB / 0.5919 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.01424/0.2997, allocations: 3.911 MB / 0.7865 GB, free: 3.66 MB / 0.5919 GB
Notification: Performance of prepare preOptimizeDAE: time 5.418e-05/0.2998, allocations: 8.031 kB / 0.7865 GB, free: 3.652 MB / 0.5919 GB
Notification: Performance of preOpt introduceOutputRealDerivatives (simulation): time 0.0001465/0.2999, allocations: 103.3 kB / 0.7866 GB, free: 3.551 MB / 0.5919 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.001915/0.3018, allocations: 0.5387 MB / 0.7871 GB, free: 3.004 MB / 0.5919 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01494/0.3168, allocations: 2.436 MB / 0.7895 GB, free: 0.5625 MB / 0.5919 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.005998/0.3228, allocations: 2.199 MB / 0.7917 GB, free: 14.32 MB / 0.6075 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003761/0.3231, allocations: 275.1 kB / 0.7919 GB, free: 14.05 MB / 0.6075 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001916/0.3251, allocations: 344 kB / 0.7923 GB, free: 13.71 MB / 0.6075 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02/0.3451, allocations: 7.155 MB / 0.7992 GB, free: 6.457 MB / 0.6075 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001261/0.3452, allocations: 11.92 kB / 0.7993 GB, free: 6.445 MB / 0.6075 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001089/0.3463, allocations: 152 kB / 0.7994 GB, free: 6.297 MB / 0.6075 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0005692/0.3468, allocations: 334.1 kB / 0.7997 GB, free: 5.969 MB / 0.6075 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01911/0.366, allocations: 7.044 MB / 0.8066 GB, free: 14.91 MB / 0.6231 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.0444/0.4104, allocations: 26.61 MB / 0.8326 GB, free: 2.121 MB / 0.6387 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.009861/0.4202, allocations: 4.867 MB / 0.8373 GB, free: 12.95 MB / 0.6544 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.006928/0.4271, allocations: 2.528 MB / 0.8398 GB, free: 10.41 MB / 0.6544 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003846/0.4275, allocations: 135 kB / 0.8399 GB, free: 10.26 MB / 0.6544 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 7.626e-05/0.4276, allocations: 68.44 kB / 0.84 GB, free: 10.18 MB / 0.6544 GB
Notification: Performance of pre-optimization done (n=208): time 5.67e-06/0.4276, allocations: 0 / 0.84 GB, free: 10.18 MB / 0.6544 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.4664/0.894, allocations: 31.09 MB / 0.8704 GB, free: 128.7 MB / 0.67 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.495e-05/0.8941, allocations: 239.9 kB / 0.8706 GB, free: 128.5 MB / 0.67 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001859/0.896, allocations: 1.47 MB / 0.872 GB, free: 128.2 MB / 0.67 GB
Notification: Performance of collectPreVariables (initialization): time 0.0004106/0.8964, allocations: 73.67 kB / 0.8721 GB, free: 128.2 MB / 0.67 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0006738/0.897, allocations: 1.55 MB / 0.8736 GB, free: 127.6 MB / 0.67 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0007021/0.8977, allocations: 0.9693 MB / 0.8746 GB, free: 127 MB / 0.67 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001111/0.8989, allocations: 0.6353 MB / 0.8752 GB, free: 126.7 MB / 0.67 GB
Notification: Performance of setup shared object (initialization): time 4.241e-05/0.8989, allocations: 306 kB / 0.8755 GB, free: 126.4 MB / 0.67 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.006244/0.9051, allocations: 2.691 MB / 0.8781 GB, free: 125.6 MB / 0.67 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00701/0.9121, allocations: 3.562 MB / 0.8816 GB, free: 123.7 MB / 0.67 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01172/0.9239, allocations: 6.412 MB / 0.8878 GB, free: 120.5 MB / 0.67 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.184e-05/0.9239, allocations: 7.906 kB / 0.8878 GB, free: 120.5 MB / 0.67 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.02608/0.95, allocations: 10.95 MB / 0.8985 GB, free: 114.6 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 3.746e-05/0.95, allocations: 18.88 kB / 0.8986 GB, free: 114.6 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.966e-05/0.9501, allocations: 25.94 kB / 0.8986 GB, free: 114.6 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01918/0.9692, allocations: 5.539 MB / 0.904 GB, free: 110.2 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004379/0.9736, allocations: 0.9867 MB / 0.905 GB, free: 109.2 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03514/1.009, allocations: 28.2 MB / 0.9325 GB, free: 80.65 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004516/1.013, allocations: 0.5751 MB / 0.9331 GB, free: 80.07 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0004359/1.014, allocations: 155.9 kB / 0.9332 GB, free: 79.92 MB / 0.67 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.006771/1.02, allocations: 3.98 MB / 0.9371 GB, free: 75.93 MB / 0.67 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.005492/1.026, allocations: 3.532 MB / 0.9405 GB, free: 72.02 MB / 0.67 GB
Error: Initialization problem is structural singular. Please, check the initial conditions.
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.0826/1.109, allocations: 24.63 MB / 0.9646 GB, free: 46.9 MB / 0.67 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 2.57e-05/1.109, allocations: 8 kB / 0.9646 GB, free: 46.89 MB / 0.67 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.02376/1.132, allocations: 10.47 MB / 0.9748 GB, free: 36.28 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 3.19e-05/1.132, allocations: 19.94 kB / 0.9748 GB, free: 36.26 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.876e-05/1.132, allocations: 28 kB / 0.9749 GB, free: 36.23 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01444/1.147, allocations: 4.322 MB / 0.9791 GB, free: 31.9 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004486/1.151, allocations: 1.045 MB / 0.9801 GB, free: 30.85 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02898/1.18, allocations: 27.14 MB / 1.007 GB, free: 3.012 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004555/1.185, allocations: 0.5545 MB / 1.007 GB, free: 2.457 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003753/1.185, allocations: 144 kB / 1.007 GB, free: 2.316 MB / 0.67 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.0009436/1.186, allocations: 413 kB / 1.008 GB, free: 1.926 MB / 0.67 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005376/1.187, allocations: 210.7 kB / 1.008 GB, free: 1.719 MB / 0.67 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.02826/1.215, allocations: 10.08 MB / 1.018 GB, free: 7.57 MB / 0.6856 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.887e-05/1.215, allocations: 28 kB / 1.018 GB, free: 7.543 MB / 0.6856 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 2.223e-05/1.215, allocations: 12 kB / 1.018 GB, free: 7.531 MB / 0.6856 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 5.383e-05/1.215, allocations: 19.94 kB / 1.018 GB, free: 7.512 MB / 0.6856 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.03904/1.254, allocations: 16.2 MB / 1.034 GB, free: 7.137 MB / 0.7012 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.397e-05/1.254, allocations: 11.94 kB / 1.034 GB, free: 7.125 MB / 0.7012 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.00407/1.258, allocations: 0.7332 MB / 1.034 GB, free: 6.395 MB / 0.7012 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.02054/1.279, allocations: 5.18 MB / 1.039 GB, free: 1.199 MB / 0.7012 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.000543/1.279, allocations: 71.95 kB / 1.039 GB, free: 1.129 MB / 0.7012 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.04537/1.325, allocations: 27.26 MB / 1.066 GB, free: 5.391 MB / 0.7325 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.25e-05/1.325, allocations: 16.03 kB / 1.066 GB, free: 5.375 MB / 0.7325 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.02823/1.353, allocations: 10.02 MB / 1.076 GB, free: 11.1 MB / 0.7481 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.002226/1.355, allocations: 0.7376 MB / 1.077 GB, free: 10.34 MB / 0.7481 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0005845/1.356, allocations: 55.98 kB / 1.077 GB, free: 10.29 MB / 0.7481 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0039/1.36, allocations: 0.507 MB / 1.077 GB, free: 9.777 MB / 0.7481 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.000972/1.361, allocations: 226.4 kB / 1.077 GB, free: 9.555 MB / 0.7481 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002857/1.361, allocations: 87.95 kB / 1.077 GB, free: 9.469 MB / 0.7481 GB
Notification: Performance of sorting global known variables: time 0.001715/1.363, allocations: 1.005 MB / 1.078 GB, free: 8.461 MB / 0.7481 GB
Notification: Performance of sort global known variables: time 1.21e-07/1.363, allocations: 0 / 1.078 GB, free: 8.461 MB / 0.7481 GB
Notification: Performance of remove unused functions: time 0.006554/1.369, allocations: 1.855 MB / 1.08 GB, free: 6.605 MB / 0.7481 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)}
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 Backend phase and start with SimCode phase: time 0.02876/1.398, allocations: 10.68 MB / 1.091 GB, free: 11.68 MB / 0.7637 GB
Notification: Performance of simCode: created initialization part: time 0.03177/1.43, allocations: 12.03 MB / 1.102 GB, free: 15.54 MB / 0.7794 GB
Notification: Performance of simCode: created event and clocks part: time 8.475e-06/1.43, allocations: 0.5 kB / 1.102 GB, free: 15.54 MB / 0.7794 GB
Notification: Performance of simCode: created simulation system equations: time 0.01155/1.441, allocations: 4.274 MB / 1.107 GB, free: 11.23 MB / 0.7794 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002314/1.444, allocations: 285.9 kB / 1.107 GB, free: 10.95 MB / 0.7794 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.
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 : transformer.conv1.V2[1] = line2.v1_n[1] - line2.v2_n[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 : loadRL.load1.v[1] = line2.v1_p[1] - line2.v2_p[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_p[1] ;
  line2.v2_p[1] ;
  line2.v2_n[1] ;
  line2.v1_n[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] ;
  -transformer.conv1.V2[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] ;
  -loadRL.load1.v[1]
]
 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 simCode: created linear, non-linear and system jacobian parts: time 0.1142/1.558, allocations: 42.32 MB / 1.148 GB, free: 15.33 MB / 0.8262 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.004284/1.562, allocations: 2.853 MB / 1.151 GB, free: 12.44 MB / 0.8262 GB
Notification: Performance of simCode: alias equations: time 0.004772/1.567, allocations: 1.653 MB / 1.153 GB, free: 10.78 MB / 0.8262 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.002125/1.569, allocations: 1.226 MB / 1.154 GB, free: 9.555 MB / 0.8262 GB
Notification: Performance of SimCode: time 1.012e-06/1.569, allocations: 3.938 kB / 1.154 GB, free: 9.551 MB / 0.8262 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.5149/2.084, allocations: 104.2 MB / 1.256 GB, free: 267.3 MB / 0.8575 GB
Notification: Performance of buildModelFMU: Generate platform static: time 17.71/19.79, allocations: 25.17 kB / 1.256 GB, free: 267.3 MB / 0.8575 GB
"
[Timeout remaining time 640]
(rm -f IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepDown.YD.pipe ; mkfifo IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepDown.YD.pipe ; head -c 1048576 < IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepDown.YD.pipe >> ../files/IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepDown.YD.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator  --tempDir=temp_IBPSA_latest_IBPSA_Electrical_AC_ThreePhasesUnbalanced_Validation_IEEETests_Test4NodesFeeder_UnbalancedStepDown_YD_fmu --startTime=0 --stopTime=1 --stepSize=0.0004 --timeout=50 --tolerance=1e-06 IBPSA_latest_IBPSA_Electrical_AC_ThreePhasesUnbalanced_Validation_IEEETests_Test4NodesFeeder_UnbalancedStepDown_YD.fmu > IBPSA_latest_IBPSA.Electrical.AC.ThreePhasesUnbalanced.Validation.IEEETests.Test4NodesFeeder.UnbalancedStepDown.YD.pipe 2>&1) [Timeout 52.5]
[Calling os._exit(0), Time elapsed: 24.004288891039323]
Failed to read output from testmodel.py, exit status != 0:
19.991134195006453 20.01212515 19.72306467
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fb42eb333a0>