Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_13_Buildings.Electrical.AC.ThreePhasesBalanced.Lines.Examples.ACLine.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.002446/0.002446, allocations: 80.88 kB / 21.56 MB, free: 2.93 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.003429/0.003429, allocations: 164.7 kB / 24.97 MB, free: 9.195 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.736/1.736, allocations: 177.1 MB / 205.4 MB, free: 5.488 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.232/3.232, allocations: 389.5 MB / 0.6361 GB, free: 12.28 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.ACLine,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.Electrical.AC.ThreePhasesBalanced.Lines.Examples.ACLine")
translateModel(Buildings.Electrical.AC.ThreePhasesBalanced.Lines.Examples.ACLine,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.Electrical.AC.ThreePhasesBalanced.Lines.Examples.ACLine") [Timeout 300]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.664e-05/2.664e-05, allocations: 6.25 kB / 0.7759 GB, free: 5.355 MB / 0.7292 GB
Notification: Performance of NFInst.instantiate(Buildings.Electrical.AC.ThreePhasesBalanced.Lines.Examples.ACLine): time 0.01602/0.01604, allocations: 9.694 MB / 0.7854 GB, free: 11.66 MB / 0.7448 GB
Notification: Performance of NFInst.instExpressions: time 0.01294/0.02899, allocations: 6.028 MB / 0.7913 GB, free: 5.645 MB / 0.7448 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001774/0.03076, allocations: 91.19 kB / 0.7914 GB, free: 5.555 MB / 0.7448 GB
Notification: Performance of NFTyping.typeComponents: time 0.00177/0.03253, allocations: 378 kB / 0.7917 GB, free: 5.184 MB / 0.7448 GB
Notification: Performance of NFTyping.typeBindings: time 0.005014/0.03755, allocations: 1.43 MB / 0.7931 GB, free: 3.77 MB / 0.7448 GB
Notification: Performance of NFTyping.typeClassSections: time 0.002193/0.03974, allocations: 0.6642 MB / 0.7938 GB, free: 3.109 MB / 0.7448 GB
Notification: Performance of NFFlatten.flatten: time 0.005378/0.04512, allocations: 3.173 MB / 0.7969 GB, free: 15.96 MB / 0.7605 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002907/0.04803, allocations: 1.055 MB / 0.7979 GB, free: 14.88 MB / 0.7605 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.00501/0.05304, allocations: 1.871 MB / 0.7997 GB, free: 13 MB / 0.7605 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.002262/0.0553, allocations: 0.8022 MB / 0.8005 GB, free: 12.2 MB / 0.7605 GB
Notification: Performance of NFPackage.collectConstants: time 0.0005775/0.05588, allocations: 133.7 kB / 0.8007 GB, free: 12.07 MB / 0.7605 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.001489/0.05736, allocations: 307.6 kB / 0.8009 GB, free: 11.77 MB / 0.7605 GB
Notification: Performance of combineBinaries: time 0.004634/0.062, allocations: 2.818 MB / 0.8037 GB, free: 8.934 MB / 0.7605 GB
Notification: Performance of replaceArrayConstructors: time 0.002395/0.06439, allocations: 1.701 MB / 0.8054 GB, free: 7.242 MB / 0.7605 GB
Notification: Performance of NFVerifyModel.verify: time 0.0006853/0.06508, allocations: 140 kB / 0.8055 GB, free: 7.105 MB / 0.7605 GB
Notification: Performance of FrontEnd: time 0.0003602/0.06544, allocations: 47.84 kB / 0.8055 GB, free: 7.059 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.01279/0.07823, allocations: 7.901 MB / 0.8133 GB, free: 14.9 MB / 0.7761 GB
Notification: Performance of [SIM] FunctionAlias: time 0.002911/0.08114, allocations: 1.502 MB / 0.8147 GB, free: 13.32 MB / 0.7761 GB
Notification: Performance of [SIM] Early Inline: time 0.01435/0.09549, allocations: 8.2 MB / 0.8227 GB, free: 5.055 MB / 0.7761 GB
Notification: Performance of [SIM] Simplify 1: time 0.001818/0.09731, allocations: 0.707 MB / 0.8234 GB, free: 4.273 MB / 0.7761 GB
Notification: Performance of [SIM] Alias: time 0.01396/0.1113, allocations: 7.306 MB / 0.8306 GB, free: 12.13 MB / 0.7917 GB
Notification: Performance of [SIM] Simplify 2: time 0.001313/0.1126, allocations: 0.5623 MB / 0.8311 GB, free: 11.52 MB / 0.7917 GB
Notification: Performance of [SIM] Remove Stream: time 0.0007346/0.1133, allocations: 384 kB / 0.8315 GB, free: 11.1 MB / 0.7917 GB
Notification: Performance of [SIM] Detect States: time 0.002338/0.1156, allocations: 1.402 MB / 0.8328 GB, free: 9.609 MB / 0.7917 GB
Notification: Performance of [SIM] Events: time 0.0008147/0.1165, allocations: 0.5073 MB / 0.8333 GB, free: 9.102 MB / 0.7917 GB
Notification: Performance of [SIM] Partitioning: time 0.003507/0.12, allocations: 1.837 MB / 0.8351 GB, free: 7.176 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 (64/88)
******************************
(1)       [ARRY] (2) (0.5 * line_3a.line.L) * line_3a.line.omega * $FUN_25 + line_3a.line.terminal_p.i * $FUN_26 = line_3a.terminal_p.v - line_3a.line.Vc; ($RES_SIM_61)
(2)       [SCAL] (1) E.terminal.v[2] = line_1.terminal_n.v[2]; ($RES_SIM_163)
(3)       [SCAL] (1) line_2b.line.omega = $DER.line_2a.terminal_n.theta[1]; ($RES_SIM_524)
(4)       [SCAL] (1) R2.omega = $DER.line_2a.terminal_n.theta[1]; ($RES_SIM_522)
(5)       [SCAL] (1) E.terminal.v[2] = line_sc.terminal_n.v[2]; ($RES_SIM_167)
(6)       [SCAL] (1) $DER.$FUN_1 = 6.283185307179586 * E.f; ($RES_SIM_521)
(7)       [SCAL] (1) E.terminal.v[1] = line_1.terminal_n.v[1]; ($RES_SIM_168)
(8)       [SCAL] (1) load_sc.omega = $DER.line_sc.terminal_n.theta[1]; ($RES_SIM_518)
(9)       [ARRY] (4) $FUN_7 = diagonal(fill(1.0, 2) .* line_1.line.R_actual / 2.0); ($RES_AUX_346)
(10)      [SCAL] (1) line_sc.line.omega = $DER.line_sc.terminal_n.theta[1]; ($RES_SIM_515)
(11)      [SCAL] (1) R3.omega = $DER.R3.terminal.theta[1]; ($RES_SIM_513)
(12)      [SCAL] (1) line_2a.line.omega = $DER.line_2a.terminal_n.theta[1]; ($RES_SIM_508)
(13)      [SCAL] (1) line_1.line.omega = $DER.line_1.terminal_n.theta[1]; ($RES_SIM_507)
(14)      [SCAL] (1) E.terminal.v[1] = line_sc.terminal_n.v[1]; ($RES_SIM_172)
(15)      [SCAL] (1) R1.omega = $DER.line_1.terminal_n.theta[1]; ($RES_SIM_504)
(16)      [FOR-] (2) ($RES_SIM_178)
(16)      [----] for $i1 in 1:2 loop
(16)      [----]   [SCAL] (1) R1.i[$i1] + line_1.terminal_p.i[$i1] = 0.0; ($RES_SIM_179)
(16)      [----] end for;
(17)      [SCAL] (1) $DER.E.terminal.theta[1] = $DER.line_1.terminal_n.theta[1]; ($RES_SIM_498)
(18)      [SCAL] (1) $DER.E.terminal.theta[1] = $DER.line_2a.terminal_n.theta[1]; ($RES_SIM_497)
(19)      [SCAL] (1) $DER.E.terminal.theta[1] = $DER.line_3b.terminal_n.theta[1]; ($RES_SIM_496)
(20)      [SCAL] (1) line_1.line.R_actual = (0.7240000000000001 * (line_1.line.M + ((-273.15) + line_1.TCable))) / (line_1.line.M + ((-273.15) + line_1.line.T_ref)); ($RES_SIM_106)
(21)      [SCAL] (1) $DER.E.terminal.theta[1] = $DER.line_sc.terminal_n.theta[1]; ($RES_SIM_493)
(22)      [FOR-] (2) ($RES_SIM_183)
(22)      [----] for $i1 in 1:2 loop
(22)      [----]   [SCAL] (1) line_sc.line.terminal_p.i[$i1] - line_sc.terminal_p.i[$i1] = 0.0; ($RES_SIM_184)
(22)      [----] end for;
(23)      [SCAL] (1) $DER.$FUN_1 = $DER.E.terminal.theta[1]; ($RES_SIM_491)
(24)      [SCAL] (1) load_sc.X = load_sc.omega * load_sc.L; ($RES_SIM_12)
(25)      [SCAL] (1) line_3b.line.omega = $DER.line_3b.terminal_n.theta[1]; ($RES_SIM_488)
(26)      [SCAL] (1) line_3a.line.omega = $DER.line_3a.terminal_p.theta[1]; ($RES_SIM_487)
(27)      [SCAL] (1) line_sc.line.R_actual = (0.7240000000000001 * (line_sc.line.M + ((-273.15) + line_sc.TCable))) / (line_sc.line.M + ((-273.15) + line_sc.line.T_ref)); ($RES_SIM_16)
(28)      [SCAL] (1) $DER.line_3b.terminal_n.theta[1] = $DER.line_3a.terminal_p.theta[1]; ($RES_SIM_483)
(29)      [SCAL] (1) R2.X = R2.omega * R2.L; ($RES_SIM_90)
(30)      [SCAL] (1) $DER.line_3b.terminal_n.theta[1] = $DER.R3.terminal.theta[1]; ($RES_SIM_481)
(31)      [SCAL] (1) R1.v[1] = {R1.R, -R1.X} * R1.i; ($RES_SIM_353)
(32)      [ARRY] (2) line_1.line.Vc = 0.5 .* (R1.v + line_1.terminal_n.v); ($RES_SIM_110)
(33)      [SCAL] (1) R1.v[2] = {R1.X, R1.R} * R1.i; ($RES_SIM_354)
(34)      [SCAL] (1) $FUN_25[1] = -line_3a.line.terminal_p.i[2]; ($RES_SIM_375)
(35)      [SCAL] (1) $FUN_25[2] = line_3a.line.terminal_p.i[1]; ($RES_SIM_376)
(36)      [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)
(37)      [SCAL] (1) R3.v[1] = {R3.R, -R3.X} * R3.i; ($RES_SIM_357)
(38)      [SCAL] (1) $FUN_31[1] = -line_3b.line.terminal_p.i[2]; ($RES_SIM_379)
(39)      [SCAL] (1) R3.v[2] = {R3.X, R3.R} * R3.i; ($RES_SIM_358)
(40)      [SCAL] (1) load_sc.v[1] = {load_sc.R, -load_sc.X} * load_sc.i; ($RES_SIM_359)
(41)      [ARRY] (4) $FUN_39 = diagonal(fill(1.0, 2) .* line_sc.line.R_actual / 2.0); ($RES_AUX_314)
(42)      [FOR-] (2) ($RES_SIM_193)
(42)      [----] for $i1 in 1:2 loop
(42)      [----]   [SCAL] (1) line_3b.line.terminal_p.i[$i1] - line_3b.terminal_p.i[$i1] = 0.0; ($RES_SIM_194)
(42)      [----] end for;
(43)      [ARRY] (2) line_sc.line.Vc = 0.5 .* (load_sc.v + line_sc.terminal_n.v); ($RES_SIM_20)
(44)      [SCAL] (1) line_3b.line.R_actual = (1.4480000000000002 * (line_3b.line.M + ((-273.15) + line_3b.TCable))) / (line_3b.line.M + ((-273.15) + line_3b.line.T_ref)); ($RES_SIM_43)
(45)      [ARRY] (2) (0.5 * line_sc.line.L) * line_sc.line.omega * $FUN_38 + line_sc.line.terminal_p.i * $FUN_39 = load_sc.v - line_sc.line.Vc; ($RES_SIM_22)
(46)      [ARRY] (2) (0.5 * line_3b.line.L) * line_3b.line.omega * $FUN_31 + line_3b.line.terminal_p.i * $FUN_32 = line_3b.terminal_p.v - line_3b.line.Vc; ($RES_SIM_49)
(47)      [SCAL] (1) $FUN_31[2] = line_3b.line.terminal_p.i[1]; ($RES_SIM_380)
(48)      [SCAL] (1) $FUN_38[1] = -line_sc.line.terminal_p.i[2]; ($RES_SIM_383)
(49)      [SCAL] (1) $FUN_38[2] = line_sc.line.terminal_p.i[1]; ($RES_SIM_384)
(50)      [FOR-] (2) ($RES_SIM_141)
(50)      [----] for $i1 in 1:2 loop
(50)      [----]   [SCAL] (1) load_sc.i[$i1] + line_sc.terminal_p.i[$i1] = 0.0; ($RES_SIM_142)
(50)      [----] end for;
(51)      [SCAL] (1) load_sc.v[2] = {load_sc.X, load_sc.R} * load_sc.i; ($RES_SIM_360)
(52)      [FOR-] (2) ($RES_SIM_203)
(52)      [----] for $i1 in 1:2 loop
(52)      [----]   [SCAL] (1) line_3a.line.terminal_p.i[$i1] - line_3a.terminal_p.i[$i1] = 0.0; ($RES_SIM_204)
(52)      [----] end for;
(53)      [SCAL] (1) $FUN_6[1] = -line_1.line.terminal_p.i[2]; ($RES_SIM_363)
(54)      [SCAL] (1) line_3a.terminal_p.i[2] + line_3b.terminal_p.i[2] + R3.i[2] = 0.0; ($RES_SIM_145)
(55)      [SCAL] (1) $FUN_6[2] = line_1.line.terminal_p.i[1]; ($RES_SIM_364)
(56)      [SCAL] (1) line_3a.terminal_p.i[1] + line_3b.terminal_p.i[1] + R3.i[1] = 0.0; ($RES_SIM_146)
(57)      [SCAL] (1) line_3b.terminal_p.v[2] = R3.v[2]; ($RES_SIM_148)
(58)      [ARRY] (4) $FUN_32 = diagonal(fill(1.0, 2) .* line_3b.line.R_actual / 2.0); ($RES_AUX_321)
(59)      [ARRY] (4) $FUN_26 = diagonal(fill(1.0, 2) .* line_3a.line.R_actual / 2.0); ($RES_AUX_327)
(60)      [SCAL] (1) R1.X = R1.omega * R1.L; ($RES_SIM_129)
(61)      [SCAL] (1) line_3a.line.R_actual = (1.4480000000000002 * (line_3a.line.M + ((-273.15) + line_3a.TCable))) / (line_3a.line.M + ((-273.15) + line_3a.line.T_ref)); ($RES_SIM_55)
(62)      [FOR-] (2) ($RES_SIM_232)
(62)      [----] for $i1 in 1:2 loop
(62)      [----]   [SCAL] (1) line_1.line.terminal_p.i[$i1] - line_1.terminal_p.i[$i1] = 0.0; ($RES_SIM_233)
(62)      [----] end for;
(63)      [SCAL] (1) R3.X = R3.omega * R3.L; ($RES_SIM_39)
(64)      [SCAL] (1) line_3b.terminal_p.v[1] = R3.v[1]; ($RES_SIM_150)


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