Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_13_Buildings.Electrical.AC.ThreePhasesBalanced.Lines.Examples.ACLineMedium.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.001955/0.001955, allocations: 80.88 kB / 21.56 MB, free: 2.934 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.002116/0.002116, allocations: 161 kB / 24.97 MB, free: 8.672 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 1.744/1.744, allocations: 177.1 MB / 205.4 MB, free: 5.465 MB / 186.7 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.x/package.mo): time 3.258/3.258, allocations: 389.5 MB / 0.6361 GB, free: 12.29 MB / 0.6042 GB
"
[Timeout remaining time 176]
Using package Buildings with version 13.0.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.x/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.ThreePhasesBalanced.Lines.Examples.ACLineMedium,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.Electrical.AC.ThreePhasesBalanced.Lines.Examples.ACLineMedium")
translateModel(Buildings.Electrical.AC.ThreePhasesBalanced.Lines.Examples.ACLineMedium,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.Electrical.AC.ThreePhasesBalanced.Lines.Examples.ACLineMedium") [Timeout 300]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.602e-05/2.602e-05, allocations: 5.688 kB / 0.7759 GB, free: 5.344 MB / 0.7292 GB
Notification: Performance of NFInst.instantiate(Buildings.Electrical.AC.ThreePhasesBalanced.Lines.Examples.ACLineMedium): time 0.0176/0.01763, allocations: 10.41 MB / 0.7861 GB, free: 10.93 MB / 0.7448 GB
Notification: Performance of NFInst.instExpressions: time 0.01532/0.03295, allocations: 6.951 MB / 0.7929 GB, free: 3.992 MB / 0.7448 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001792/0.03474, allocations: 96.62 kB / 0.793 GB, free: 3.898 MB / 0.7448 GB
Notification: Performance of NFTyping.typeComponents: time 0.00179/0.03653, allocations: 392.9 kB / 0.7934 GB, free: 3.512 MB / 0.7448 GB
Notification: Performance of NFTyping.typeBindings: time 0.006455/0.04299, allocations: 1.683 MB / 0.795 GB, free: 1.836 MB / 0.7448 GB
Notification: Performance of NFTyping.typeClassSections: time 0.00218/0.04517, allocations: 0.666 MB / 0.7957 GB, free: 1.176 MB / 0.7448 GB
Notification: Performance of NFFlatten.flatten: time 0.00568/0.05085, allocations: 3.295 MB / 0.7989 GB, free: 13.9 MB / 0.7605 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002754/0.0536, allocations: 1.062 MB / 0.7999 GB, free: 12.81 MB / 0.7605 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.006431/0.06003, allocations: 2.576 MB / 0.8024 GB, free: 10.24 MB / 0.7605 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.002215/0.06224, allocations: 0.819 MB / 0.8032 GB, free: 9.422 MB / 0.7605 GB
Notification: Performance of NFPackage.collectConstants: time 0.0005374/0.06278, allocations: 132 kB / 0.8034 GB, free: 9.293 MB / 0.7605 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.001487/0.06427, allocations: 340.8 kB / 0.8037 GB, free: 8.961 MB / 0.7605 GB
Notification: Performance of combineBinaries: time 0.005994/0.07026, allocations: 2.884 MB / 0.8065 GB, free: 6.059 MB / 0.7605 GB
Notification: Performance of replaceArrayConstructors: time 0.002581/0.07284, allocations: 1.758 MB / 0.8082 GB, free: 4.312 MB / 0.7605 GB
Notification: Performance of NFVerifyModel.verify: time 0.0008515/0.07369, allocations: 144 kB / 0.8083 GB, free: 4.172 MB / 0.7605 GB
Notification: Performance of FrontEnd: time 0.0003894/0.07408, allocations: 51.8 kB / 0.8084 GB, free: 4.121 MB / 0.7605 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 327 (253)
 * Number of variables: 327 (244)
Notification: Performance of [SIM] Bindings: time 0.01404/0.08812, allocations: 8.235 MB / 0.8164 GB, free: 11.62 MB / 0.7761 GB
Notification: Performance of [SIM] FunctionAlias: time 0.003097/0.09122, allocations: 1.545 MB / 0.8179 GB, free: 9.996 MB / 0.7761 GB
Notification: Performance of [SIM] Early Inline: time 0.0162/0.1074, allocations: 8.404 MB / 0.8262 GB, free: 1.523 MB / 0.7761 GB
Notification: Performance of [SIM] Simplify 1: time 0.001864/0.1093, allocations: 0.7077 MB / 0.8268 GB, free: 0.7422 MB / 0.7761 GB
Notification: Performance of [SIM] Alias: time 0.01424/0.1235, allocations: 7.373 MB / 0.834 GB, free: 8.531 MB / 0.7917 GB
Notification: Performance of [SIM] Simplify 2: time 0.001253/0.1248, allocations: 0.5738 MB / 0.8346 GB, free: 7.914 MB / 0.7917 GB
Notification: Performance of [SIM] Remove Stream: time 0.0007563/0.1255, allocations: 399.9 kB / 0.835 GB, free: 7.477 MB / 0.7917 GB
Notification: Performance of [SIM] Detect States: time 0.002531/0.1281, allocations: 1.423 MB / 0.8364 GB, free: 5.965 MB / 0.7917 GB
Notification: Performance of [SIM] Events: time 0.0008776/0.1289, allocations: 0.5151 MB / 0.8369 GB, free: 5.449 MB / 0.7917 GB
Notification: Performance of [SIM] Partitioning: time 0.003555/0.1325, allocations: 1.904 MB / 0.8387 GB, free: 3.457 MB / 0.7917 GB
Error: Internal error NBResolveSingularities.indexReduction failed because there was not enough state candidates to balance out the constraint equations.
Constraint Equations (58/76)
******************************
(1)       [SCAL] (1) $FUN_46[1] = -line_sc.line.Vc[2]; ($RES_SIM_403)
(2)       [SCAL] (1) $FUN_46[2] = line_sc.line.Vc[1]; ($RES_SIM_404)
(3)       [ARRY] (2) 2.8131403439164072e-8 * line_1.line.omega * $FUN_9 = line_1.line.Ic; ($RES_SIM_110)
(4)       [ARRY] (2) (0.5 * line_1.line.L) * line_1.line.omega * $FUN_8 + line_1.line.terminal_n.i * $FUN_7 = line_1.terminal_n.v - line_1.line.Vc; ($RES_SIM_111)
(5)       [SCAL] (1) E.terminal.v[2] = line_1.terminal_n.v[2]; ($RES_SIM_163)
(6)       [SCAL] (1) line_3b.line.omega = $DER.line_3b.terminal_n.theta[1]; ($RES_SIM_525)
(7)       [ARRY] (2) (0.5 * line_1.line.L) * line_1.line.omega * $FUN_6 + line_1.line.terminal_p.i * $FUN_7 = R1.v - line_1.line.Vc; ($RES_SIM_112)
(8)       [ARRY] (2) line_1.line.terminal_p.i + line_1.line.terminal_n.i = line_1.line.Ic; ($RES_SIM_113)
(9)       [SCAL] (1) E.terminal.v[2] = line_sc.terminal_n.v[2]; ($RES_SIM_167)
(10)      [FOR-] (2) ($RES_SIM_141)
(10)      [----] for $i1 in 1:2 loop
(10)      [----]   [SCAL] (1) load_sc.i[$i1] + line_sc.terminal_p.i[$i1] = 0.0; ($RES_SIM_142)
(10)      [----] end for;
(11)      [SCAL] (1) R1.v[1] = {R1.R, -R1.X} * R1.i; ($RES_SIM_359)
(12)      [SCAL] (1) E.terminal.v[1] = line_1.terminal_n.v[1]; ($RES_SIM_168)
(13)      [SCAL] (1) R2.omega = $DER.line_2a.terminal_n.theta[1]; ($RES_SIM_582)
(14)      [ARRY] (2) 2.8131403439164072e-8 * line_sc.line.omega * $FUN_46 = line_sc.line.Ic; ($RES_SIM_20)
(15)      [ARRY] (2) (0.5 * line_sc.line.L) * line_sc.line.omega * $FUN_45 + line_sc.line.terminal_n.i * $FUN_44 = line_sc.terminal_n.v - line_sc.line.Vc; ($RES_SIM_21)
(16)      [ARRY] (2) (0.5 * line_sc.line.L) * line_sc.line.omega * $FUN_43 + line_sc.line.terminal_p.i * $FUN_44 = load_sc.v - line_sc.line.Vc; ($RES_SIM_22)
(17)      [SCAL] (1) line_2a.line.omega = $DER.line_2a.terminal_n.theta[1]; ($RES_SIM_579)
(18)      [ARRY] (2) line_sc.line.terminal_p.i + line_sc.line.terminal_n.i = line_sc.line.Ic; ($RES_SIM_23)
(19)      [SCAL] (1) $DER.line_3b.terminal_n.theta[1] = $DER.line_3a.terminal_p.theta[1]; ($RES_SIM_507)
(20)      [SCAL] (1) $DER.E.terminal.theta[1] = $DER.line_1.terminal_n.theta[1]; ($RES_SIM_572)
(21)      [SCAL] (1) $DER.E.terminal.theta[1] = $DER.line_2a.terminal_n.theta[1]; ($RES_SIM_571)
(22)      [SCAL] (1) $DER.line_3b.terminal_n.theta[1] = $DER.R3.terminal.theta[1]; ($RES_SIM_505)
(23)      [SCAL] (1) $DER.E.terminal.theta[1] = $DER.line_3b.terminal_n.theta[1]; ($RES_SIM_570)
(24)      [SCAL] (1) $DER.E.terminal.theta[1] = $DER.line_sc.terminal_n.theta[1]; ($RES_SIM_569)
(25)      [SCAL] (1) line_1.line.omega = $DER.line_1.terminal_n.theta[1]; ($RES_SIM_568)
(26)      [SCAL] (1) R1.omega = $DER.line_1.terminal_n.theta[1]; ($RES_SIM_567)
(27)      [SCAL] (1) E.terminal.v[1] = line_sc.terminal_n.v[1]; ($RES_SIM_172)
(28)      [SCAL] (1) R1.v[2] = {R1.X, R1.R} * R1.i; ($RES_SIM_360)
(29)      [SCAL] (1) $DER.$FUN_1 = $DER.E.terminal.theta[1]; ($RES_SIM_566)
(30)      [FOR-] (2) ($RES_SIM_232)
(30)      [----] for $i1 in 1:2 loop
(30)      [----]   [SCAL] (1) line_1.line.terminal_p.i[$i1] - line_1.terminal_p.i[$i1] = 0.0; ($RES_SIM_233)
(30)      [----] end for;
(31)      [SCAL] (1) line_3a.line.omega = $DER.line_3a.terminal_p.theta[1]; ($RES_SIM_496)
(32)      [FOR-] (2) ($RES_SIM_178)
(32)      [----] for $i1 in 1:2 loop
(32)      [----]   [SCAL] (1) R1.i[$i1] + line_1.terminal_p.i[$i1] = 0.0; ($RES_SIM_179)
(32)      [----] end for;
(33)      [ARRY] (4) $FUN_7 = diagonal(fill(1.0, 2) .* line_1.line.R_actual / 2.0); ($RES_AUX_352)
(34)      [SCAL] (1) load_sc.v[1] = {load_sc.R, -load_sc.X} * load_sc.i; ($RES_SIM_365)
(35)      [SCAL] (1) $DER.$FUN_1 = 6.283185307179586 * E.f; ($RES_SIM_558)
(36)      [SCAL] (1) line_2b.line.omega = $DER.line_2a.terminal_n.theta[1]; ($RES_SIM_491)
(37)      [SCAL] (1) load_sc.v[2] = {load_sc.X, load_sc.R} * load_sc.i; ($RES_SIM_366)
(38)      [SCAL] (1) load_sc.omega = $DER.line_sc.terminal_n.theta[1]; ($RES_SIM_555)
(39)      [SCAL] (1) line_sc.line.omega = $DER.line_sc.terminal_n.theta[1]; ($RES_SIM_554)
(40)      [SCAL] (1) $FUN_8[2] = line_1.line.terminal_n.i[1]; ($RES_SIM_372)
(41)      [SCAL] (1) $FUN_6[1] = -line_1.line.terminal_p.i[2]; ($RES_SIM_369)
(42)      [SCAL] (1) $FUN_9[1] = -line_1.line.Vc[2]; ($RES_SIM_373)
(43)      [SCAL] (1) R3.omega = $DER.R3.terminal.theta[1]; ($RES_SIM_552)
(44)      [SCAL] (1) line_1.line.R_actual = (1.102362 * (line_1.line.M + ((-273.15) + line_1.TCable))) / (line_1.line.M + ((-273.15) + line_1.line.T_ref)); ($RES_SIM_106)
(45)      [SCAL] (1) $FUN_9[2] = line_1.line.Vc[1]; ($RES_SIM_374)
(46)      [SCAL] (1) $FUN_43[1] = -line_sc.line.terminal_p.i[2]; ($RES_SIM_399)
(47)      [SCAL] (1) R1.X = R1.omega * R1.L; ($RES_SIM_129)
(48)      [FOR-] (2) ($RES_SIM_183)
(48)      [----] for $i1 in 1:2 loop
(48)      [----]   [SCAL] (1) line_sc.line.terminal_p.i[$i1] - line_sc.terminal_p.i[$i1] = 0.0; ($RES_SIM_184)
(48)      [----] end for;
(49)      [SCAL] (1) load_sc.X = load_sc.omega * load_sc.L; ($RES_SIM_12)
(50)      [SCAL] (1) $FUN_43[2] = line_sc.line.terminal_p.i[1]; ($RES_SIM_400)
(51)      [SCAL] (1) R3.X = R3.omega * R3.L; ($RES_SIM_39)
(52)      [SCAL] (1) line_sc.line.R_actual = (1.102362 * (line_sc.line.M + ((-273.15) + line_sc.TCable))) / (line_sc.line.M + ((-273.15) + line_sc.line.T_ref)); ($RES_SIM_16)
(53)      [SCAL] (1) $FUN_6[2] = line_1.line.terminal_p.i[1]; ($RES_SIM_370)
(54)      [SCAL] (1) R2.X = R2.omega * R2.L; ($RES_SIM_90)
(55)      [SCAL] (1) $FUN_45[1] = -line_sc.line.terminal_n.i[2]; ($RES_SIM_401)
(56)      [ARRY] (4) $FUN_44 = diagonal(fill(1.0, 2) .* line_sc.line.R_actual / 2.0); ($RES_AUX_315)
(57)      [SCAL] (1) $FUN_8[1] = -line_1.line.terminal_n.i[2]; ($RES_SIM_371)
(58)      [SCAL] (1) $FUN_45[2] = line_sc.line.terminal_n.i[1]; ($RES_SIM_402)


State Candidate Variables (42/70)
***********************************
(1)       [ALGB] (2) protected flow Real[2] line_sc.line.terminal_n.i (start = {0.0 for $f1 in 1:2})
(2)       [ALGB] (1) protected Real R3.omega
(3)       [ALGB] (1) protected Real R2.omega
(4)       [ALGB] (1) protected Real line_1.line.omega
(5)       [ALGB] (2) flow Real[2] line_sc.terminal_p.i (start = {0.0 for $f1 in 1:2})
(6)       [ALGB] (1) protected Real R1.omega
(7)       [ALGB] (1) protected Real R1.X (start = 1.0)
(8)       [DDER] (1) Real[1] $DER.E.terminal.theta (StateSelect = avoid)
(9)       [ALGB] (1) protected Real load_sc.X (start = 1.0)
(10)      [ALGB] (2) protected Real[2] line_sc.line.Ic
(11)      [DDER] (1) Real[1] $DER.line_3b.terminal_n.theta (StateSelect = avoid)
(12)      [STAT] (2) protected Real[2] line_1.line.Vc (start = line_1.line.Vc_start, StateSelect = prefer)
(13)      [STAT] (2) protected Real[2] line_sc.line.Vc (start = line_sc.line.Vc_start, StateSelect = prefer)
(14)      [ALGB] (2) Real[2] line_sc.terminal_n.v
(15)      [ALGB] (2) Real[2] line_1.terminal_n.v
(16)      [DDER] (1) Real[1] $DER.line_2a.terminal_n.theta (StateSelect = avoid)
(17)      [DDER] (1) Real[1] $DER.line_sc.terminal_n.theta (StateSelect = avoid)
(18)      [ALGB] (1) protected Real load_sc.omega
(19)      [DDER] (1) Real[1] $DER.R3.terminal.theta (StateSelect = avoid)
(20)      [ALGB] (2) protected flow Real[2] line_sc.line.terminal_p.i (start = {0.0 for $f1 in 1:2})
(21)      [ALGB] (2) protected flow Real[2] line_1.line.terminal_n.i (start = {0.0 for $f1 in 1:2})
(22)      [ALGB] (2) protected flow Real[2] line_1.line.terminal_p.i (start = {0.0 for $f1 in 1:2})
(23)      [ALGB] (2) Real[2] load_sc.i = load_sc.i (start = {0.0 for $f1 in 1:2})
(24)      [ALGB] (2) Real[2] R1.v = R1.v (start = {1.0, 0.0})
(25)      [ALGB] (2) flow Real[2] line_1.terminal_p.i (start = {0.0 for $f1 in 1:2})
(26)      [ALGB] (2) Real[2] $FUN_46 (start = Buildings.Electrical.PhaseSystems.OnePhase.j(line_sc.line.Vc))
(27)      [ALGB] (1) protected Real line_1.line.R_actual
(28)      [ALGB] (2) Real[2] $FUN_45 (start = Buildings.Electrical.PhaseSystems.OnePhase.j(line_sc.line.terminal_n.i))
(29)      [ALGB] (4) Real[2, 2] $FUN_44
(30)      [ALGB] (2) Real[2] R1.i = R1.i (start = {0.0 for $f1 in 1:2})
(31)      [ALGB] (2) Real[2] $FUN_43 (start = Buildings.Electrical.PhaseSystems.OnePhase.j(line_sc.line.terminal_p.i))
(32)      [ALGB] (1) protected Real line_sc.line.R_actual
(33)      [DDER] (1) Real $DER.$FUN_1 (StateSelect = avoid)
(34)      [ALGB] (1) protected Real line_sc.line.omega
(35)      [ALGB] (2) protected Real[2] line_1.line.Ic
(36)      [ALGB] (2) Real[2] $FUN_9 (start = Buildings.Electrical.PhaseSystems.OnePhase.j(line_1.line.Vc))
(37)      [ALGB] (2) Real[2] $FUN_8 (start = Buildings.Electrical.PhaseSystems.OnePhase.j(line_1.line.terminal_n.i))
(38)      [ALGB] (4) Real[2, 2] $FUN_7
(39)      [DDER] (1) Real[1] $DER.line_1.terminal_n.theta (StateSelect = avoid)
(40)      [ALGB] (2) Real[2] $FUN_6 (start = Buildings.Electrical.PhaseSystems.OnePhase.j(line_1.line.terminal_p.i))
(41)      [ALGB] (2) Real[2] load_sc.v = load_sc.v (start = {1.0, 0.0})
(42)      [DDER] (1) Real[1] $DER.line_3a.terminal_p.theta (StateSelect = avoid)
"
[Timeout remaining time 300]
[Calling sys.exit(0), Time elapsed: 6.488859268836677]