Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_13_Buildings.Electrical.AC.OnePhase.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.005419/0.005419, 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.002117/0.002117, allocations: 165.2 kB / 24.97 MB, free: 9.184 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.67/1.67, allocations: 177.1 MB / 205.4 MB, free: 5.09 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.328/3.328, allocations: 389.5 MB / 0.6361 GB, free: 12.32 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.OnePhase.Lines.Examples.ACLine,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.Electrical.AC.OnePhase.Lines.Examples.ACLine")
translateModel(Buildings.Electrical.AC.OnePhase.Lines.Examples.ACLine,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.Electrical.AC.OnePhase.Lines.Examples.ACLine") [Timeout 300]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.675e-05/2.675e-05, allocations: 2.312 kB / 0.7759 GB, free: 5.363 MB / 0.7292 GB
Notification: Performance of NFInst.instantiate(Buildings.Electrical.AC.OnePhase.Lines.Examples.ACLine): time 0.0123/0.01232, allocations: 7.714 MB / 0.7835 GB, free: 13.65 MB / 0.7449 GB
Notification: Performance of NFInst.instExpressions: time 0.01217/0.02449, allocations: 5.924 MB / 0.7893 GB, free: 7.742 MB / 0.7449 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001255/0.02575, allocations: 87.25 kB / 0.7893 GB, free: 7.656 MB / 0.7449 GB
Notification: Performance of NFTyping.typeComponents: time 0.001189/0.02694, allocations: 373.6 kB / 0.7897 GB, free: 7.289 MB / 0.7449 GB
Notification: Performance of NFTyping.typeBindings: time 0.003847/0.03079, allocations: 1.424 MB / 0.7911 GB, free: 5.875 MB / 0.7449 GB
Notification: Performance of NFTyping.typeClassSections: time 0.001438/0.03222, allocations: 0.5629 MB / 0.7916 GB, free: 5.316 MB / 0.7449 GB
Notification: Performance of NFFlatten.flatten: time 0.004353/0.03658, allocations: 2.927 MB / 0.7945 GB, free: 2.41 MB / 0.7449 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001873/0.03845, allocations: 1.012 MB / 0.7955 GB, free: 1.371 MB / 0.7449 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.003328/0.04178, allocations: 1.766 MB / 0.7972 GB, free: 15.61 MB / 0.7605 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001331/0.04311, allocations: 0.7368 MB / 0.7979 GB, free: 14.87 MB / 0.7605 GB
Notification: Performance of NFPackage.collectConstants: time 0.000206/0.04332, allocations: 112.8 kB / 0.798 GB, free: 14.76 MB / 0.7605 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0008006/0.04412, allocations: 283.6 kB / 0.7983 GB, free: 14.48 MB / 0.7605 GB
Notification: Performance of combineBinaries: time 0.00348/0.0476, allocations: 2.667 MB / 0.8009 GB, free: 11.8 MB / 0.7605 GB
Notification: Performance of replaceArrayConstructors: time 0.001866/0.04946, allocations: 1.651 MB / 0.8025 GB, free: 10.15 MB / 0.7605 GB
Notification: Performance of NFVerifyModel.verify: time 0.0003182/0.04978, allocations: 136.5 kB / 0.8027 GB, free: 10.02 MB / 0.7605 GB
Notification: Performance of FrontEnd: time 0.0002378/0.05002, allocations: 52.73 kB / 0.8027 GB, free: 9.969 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: 315 (247)
 * Number of variables: 315 (238)
Notification: Performance of [SIM] Bindings: time 0.009701/0.05972, allocations: 7.435 MB / 0.81 GB, free: 2.281 MB / 0.7605 GB
Notification: Performance of [SIM] FunctionAlias: time 0.002089/0.06181, allocations: 1.375 MB / 0.8113 GB, free: 0.8164 MB / 0.7605 GB
Notification: Performance of [SIM] Early Inline: time 0.01049/0.0723, allocations: 7.286 MB / 0.8184 GB, free: 9.453 MB / 0.7761 GB
Notification: Performance of [SIM] Simplify 1: time 0.001196/0.0735, allocations: 0.6439 MB / 0.8191 GB, free: 8.734 MB / 0.7761 GB
Warning: NBAlias.selectStartByConfidence: Alias set with conflicting unfixed start values of equal confidence detected. Use -d=dumprepl for more information.
Notification: Performance of [SIM] Alias: time 0.0127/0.0862, allocations: 7.367 MB / 0.8262 GB, free: 496 kB / 0.7761 GB
Notification: Performance of [SIM] Simplify 2: time 0.0009335/0.08713, allocations: 481 kB / 0.8267 GB, free: 15.97 MB / 0.7917 GB
Notification: Performance of [SIM] Remove Stream: time 0.0004783/0.08761, allocations: 331.9 kB / 0.827 GB, free: 15.6 MB / 0.7917 GB
Notification: Performance of [SIM] Detect States: time 0.001875/0.08949, allocations: 1.159 MB / 0.8282 GB, free: 14.36 MB / 0.7917 GB
Notification: Performance of [SIM] Events: time 0.0005018/0.08999, allocations: 411.6 kB / 0.8285 GB, free: 13.96 MB / 0.7917 GB
Notification: Performance of [SIM] Partitioning: time 0.00232/0.09231, allocations: 1.523 MB / 0.83 GB, free: 12.34 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 (60/82)
******************************
(1)       [SCAL] (1) line_3b.line.omega = $DER.line_3b.terminal_n.theta[1]; ($RES_SIM_486)
(2)       [SCAL] (1) E.terminal.v[2] = line_1.terminal_n.v[2]; ($RES_SIM_157)
(3)       [SCAL] (1) E.terminal.v[2] = line_2a.terminal_n.v[2]; ($RES_SIM_158)
(4)       [ARRY] (2) 7.745540563805572e-5 * line_2a.line.omega * $FUN_11 + line_2a.line.i_p * $FUN_12 = line_2b.terminal_n.v - line_2a.terminal_n.v; ($RES_SIM_94)
(5)       [ARRY] (4) $FUN_7 = diagonal(fill(1.0, 2) .* line_1.line.R_actual); ($RES_AUX_334)
(6)       [SCAL] (1) $FUN_6[1] = -line_1.line.i_p[2]; ($RES_SIM_351)
(7)       [SCAL] (1) $FUN_6[2] = line_1.line.i_p[1]; ($RES_SIM_352)
(8)       [SCAL] (1) line_2b.line.R_actual = (0.12066666666666669 * (line_2b.line.M + ((-273.15) + line_2b.TCable))) / (line_2b.line.M + ((-273.15) + line_2b.line.T_ref)); ($RES_SIM_64)
(9)       [SCAL] (1) $FUN_11[1] = -line_2a.line.i_p[2]; ($RES_SIM_353)
(10)      [ARRY] (2) 7.745540563805572e-5 * line_2b.line.omega * $FUN_17 + line_2b.line.i_p * $FUN_18 = R2.v - line_2b.terminal_n.v; ($RES_SIM_68)
(11)      [SCAL] (1) $FUN_11[2] = line_2a.line.i_p[1]; ($RES_SIM_354)
(12)      [SCAL] (1) $DER.line_3b.terminal_n.theta[1] = $DER.line_3a.terminal_p.theta[1]; ($RES_SIM_474)
(13)      [SCAL] (1) $FUN_17[1] = -line_2b.line.i_p[2]; ($RES_SIM_355)
(14)      [SCAL] (1) $DER.line_3b.terminal_n.theta[1] = $DER.R3.terminal.theta[1]; ($RES_SIM_473)
(15)      [SCAL] (1) E.terminal.v[2] = line_sc.terminal_n.v[2]; ($RES_SIM_161)
(16)      [SCAL] (1) $FUN_17[2] = line_2b.line.i_p[1]; ($RES_SIM_356)
(17)      [SCAL] (1) $DER.$FUN_1 = 6.283185307179586 * E.f; ($RES_SIM_472)
(18)      [SCAL] (1) E.terminal.v[1] = line_1.terminal_n.v[1]; ($RES_SIM_162)
(19)      [SCAL] (1) line_3a.line.omega = $DER.line_3a.terminal_p.theta[1]; ($RES_SIM_471)
(20)      [SCAL] (1) E.terminal.v[1] = line_2a.terminal_n.v[1]; ($RES_SIM_163)
(21)      [SCAL] (1) line_2b.line.omega = $DER.line_2a.terminal_n.theta[1]; ($RES_SIM_468)
(22)      [SCAL] (1) load_sc.omega = $DER.line_sc.terminal_n.theta[1]; ($RES_SIM_467)
(23)      [SCAL] (1) E.terminal.v[1] = line_sc.terminal_n.v[1]; ($RES_SIM_166)
(24)      [SCAL] (1) R2.omega = $DER.line_2a.terminal_n.theta[1]; ($RES_SIM_466)
(25)      [SCAL] (1) line_sc.line.omega = $DER.line_sc.terminal_n.theta[1]; ($RES_SIM_465)
(26)      [ARRY] (2) 1.5491081127611144e-4 * line_sc.line.omega * $FUN_33 + line_sc.line.i_p * $FUN_34 = load_sc.v - line_sc.terminal_n.v; ($RES_SIM_20)
(27)      [FOR-] (2) ($RES_SIM_172)
(27)      [----] for $i1 in 1:2 loop
(27)      [----]   [SCAL] (1) R1.i[$i1] + line_1.terminal_p.i[$i1] = 0.0; ($RES_SIM_173)
(27)      [----] end for;
(28)      [FOR-] (2) ($RES_SIM_177)
(28)      [----] for $i1 in 1:2 loop
(28)      [----]   [SCAL] (1) line_sc.line.i_p[$i1] - line_sc.terminal_p.i[$i1] = 0.0; ($RES_SIM_178)
(28)      [----] end for;
(29)      [FOR-] (2) ($RES_SIM_135)
(29)      [----] for $i1 in 1:2 loop
(29)      [----]   [SCAL] (1) load_sc.i[$i1] + line_sc.terminal_p.i[$i1] = 0.0; ($RES_SIM_136)
(29)      [----] end for;
(30)      [SCAL] (1) R2.X = R2.omega * R2.L; ($RES_SIM_86)
(31)      [SCAL] (1) $FUN_33[1] = -line_sc.line.i_p[2]; ($RES_SIM_361)
(32)      [SCAL] (1) $FUN_33[2] = line_sc.line.i_p[1]; ($RES_SIM_362)
(33)      [SCAL] (1) R1.v[1] = {R1.R, -R1.X} * R1.i; ($RES_SIM_341)
(34)      [SCAL] (1) R1.v[2] = {R1.X, R1.R} * R1.i; ($RES_SIM_342)
(35)      [SCAL] (1) R2.v[1] = {R2.R, -R2.X} * R2.i; ($RES_SIM_343)
(36)      [SCAL] (1) R2.v[2] = {R2.X, R2.R} * R2.i; ($RES_SIM_344)
(37)      [FOR-] (2) ($RES_SIM_206)
(37)      [----] for $i1 in 1:2 loop
(37)      [----]   [SCAL] (1) line_2b.line.i_p[$i1] - line_2b.terminal_p.i[$i1] = 0.0; ($RES_SIM_207)
(37)      [----] end for;
(38)      [SCAL] (1) line_1.line.R_actual = (0.24133333333333337 * (line_1.line.M + ((-273.15) + line_1.TCable))) / (line_1.line.M + ((-273.15) + line_1.line.T_ref)); ($RES_SIM_101)
(39)      [SCAL] (1) line_2a.line.omega = $DER.line_2a.terminal_n.theta[1]; ($RES_SIM_510)
(40)      [SCAL] (1) line_1.line.omega = $DER.line_1.terminal_n.theta[1]; ($RES_SIM_508)
(41)      [SCAL] (1) R1.X = R1.omega * R1.L; ($RES_SIM_123)
(42)      [SCAL] (1) load_sc.v[1] = {load_sc.R, -load_sc.X} * load_sc.i; ($RES_SIM_347)
(43)      [FOR-] (2) ($RES_SIM_226)
(43)      [----] for $i1 in 1:2 loop
(43)      [----]   [SCAL] (1) line_1.line.i_p[$i1] - line_1.terminal_p.i[$i1] = 0.0; ($RES_SIM_227)
(43)      [----] end for;
(44)      [SCAL] (1) R1.omega = $DER.line_1.terminal_n.theta[1]; ($RES_SIM_506)
(45)      [SCAL] (1) load_sc.v[2] = {load_sc.X, load_sc.R} * load_sc.i; ($RES_SIM_348)
(46)      [ARRY] (2) 1.5491081127611144e-4 * line_1.line.omega * $FUN_6 + line_1.line.i_p * $FUN_7 = R1.v - line_1.terminal_n.v; ($RES_SIM_105)
(47)      [FOR-] (2) ($RES_SIM_147)
(47)      [----] for $i1 in 1:2 loop
(47)      [----]   [SCAL] (1) R2.i[$i1] + line_2b.terminal_p.i[$i1] = 0.0; ($RES_SIM_148)
(47)      [----] end for;
(48)      [SCAL] (1) $DER.E.terminal.theta[1] = $DER.line_1.terminal_n.theta[1]; ($RES_SIM_499)
(49)      [SCAL] (1) load_sc.X = load_sc.omega * load_sc.L; ($RES_SIM_12)
(50)      [ARRY] (4) $FUN_18 = diagonal(fill(1.0, 2) .* line_2b.line.R_actual); ($RES_AUX_323)
(51)      [SCAL] (1) $DER.$FUN_1 = $DER.E.terminal.theta[1]; ($RES_SIM_496)
(52)      [SCAL] (1) $DER.E.terminal.theta[1] = $DER.line_2a.terminal_n.theta[1]; ($RES_SIM_495)
(53)      [ARRY] (4) $FUN_34 = diagonal(fill(1.0, 2) .* line_sc.line.R_actual); ($RES_AUX_307)
(54)      [ARRY] (4) $FUN_12 = diagonal(fill(1.0, 2) .* line_2a.line.R_actual); ($RES_AUX_329)
(55)      [SCAL] (1) $DER.E.terminal.theta[1] = $DER.line_3b.terminal_n.theta[1]; ($RES_SIM_494)
(56)      [SCAL] (1) $DER.E.terminal.theta[1] = $DER.line_sc.terminal_n.theta[1]; ($RES_SIM_493)
(57)      [SCAL] (1) R3.X = R3.omega * R3.L; ($RES_SIM_38)
(58)      [SCAL] (1) line_sc.line.R_actual = (0.24133333333333337 * (line_sc.line.M + ((-273.15) + line_sc.TCable))) / (line_sc.line.M + ((-273.15) + line_sc.line.T_ref)); ($RES_SIM_16)
(59)      [SCAL] (1) R3.omega = $DER.R3.terminal.theta[1]; ($RES_SIM_490)
(60)      [SCAL] (1) line_2a.line.R_actual = (0.12066666666666669 * (line_2a.line.M + ((-273.15) + line_2a.TCable))) / (line_2a.line.M + ((-273.15) + line_2a.line.T_ref)); ($RES_SIM_90)


State Candidate Variables (47/79)
***********************************
(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 R2.X (start = 1.0)
(7)       [STAT] (2) protected Real[2] line_2b.line.i_p (start = {0.0 for $f1 in 1:2}, StateSelect = prefer)
(8)       [ALGB] (1) protected Real R1.X (start = 1.0)
(9)       [DDER] (1) Real[1] $DER.E.terminal.theta (StateSelect = avoid)
(10)      [ALGB] (1) protected Real load_sc.X (start = 1.0)
(11)      [ALGB] (4) Real[2, 2] $FUN_34
(12)      [ALGB] (4) Real[2, 2] $FUN_18
(13)      [ALGB] (2) Real[2] $FUN_33 (start = Buildings.Electrical.AC.OnePhase.Lines.Examples.ACLine.line_1.line.PhaseSystem_p.j(line_sc.line.i_p))
(14)      [ALGB] (2) Real[2] $FUN_17 (start = Buildings.Electrical.AC.OnePhase.Lines.Examples.ACLine.line_1.line.PhaseSystem_p.j(line_2b.line.i_p))
(15)      [DDER] (1) Real[1] $DER.line_3b.terminal_n.theta (StateSelect = avoid)
(16)      [ALGB] (2) flow Real[2] line_2b.terminal_p.i (start = {0.0 for $f1 in 1:2})
(17)      [ALGB] (4) Real[2, 2] $FUN_12
(18)      [ALGB] (2) Real[2] $FUN_11 (start = Buildings.Electrical.AC.OnePhase.Lines.Examples.ACLine.line_1.line.PhaseSystem_p.j(line_2a.line.i_p))
(19)      [ALGB] (2) Real[2] line_sc.terminal_n.v
(20)      [ALGB] (2) Real[2] line_1.terminal_n.v
(21)      [DDER] (1) Real[1] $DER.line_2a.terminal_n.theta (StateSelect = avoid)
(22)      [DDER] (1) Real[1] $DER.line_sc.terminal_n.theta (StateSelect = avoid)
(23)      [ALGB] (1) protected Real load_sc.omega
(24)      [DDER] (1) Real[1] $DER.R3.terminal.theta (StateSelect = avoid)
(25)      [ALGB] (2) Real[2] line_2b.terminal_n.v
(26)      [ALGB] (2) Real[2] line_2a.terminal_n.v
(27)      [ALGB] (2) Real[2] load_sc.i = load_sc.i (start = {0.0 for $f1 in 1:2})
(28)      [ALGB] (2) Real[2] R2.v = R2.v (start = {1.0, 0.0})
(29)      [ALGB] (2) Real[2] R1.v = R1.v (start = {1.0, 0.0})
(30)      [ALGB] (2) flow Real[2] line_1.terminal_p.i (start = {0.0 for $f1 in 1:2})
(31)      [ALGB] (1) protected Real line_1.line.R_actual
(32)      [ALGB] (2) Real[2] R2.i = R2.i (start = {0.0 for $f1 in 1:2})
(33)      [ALGB] (2) Real[2] R1.i = R1.i (start = {0.0 for $f1 in 1:2})
(34)      [ALGB] (1) protected Real line_sc.line.R_actual
(35)      [DDER] (1) Real $DER.$FUN_1 (StateSelect = avoid)
(36)      [ALGB] (1) protected Real line_sc.line.omega
(37)      [STAT] (2) protected Real[2] line_1.line.i_p (start = {0.0 for $f1 in 1:2}, StateSelect = prefer)
(38)      [ALGB] (1) protected Real line_2b.line.R_actual
(39)      [ALGB] (4) Real[2, 2] $FUN_7
(40)      [DDER] (1) Real[1] $DER.line_1.terminal_n.theta (StateSelect = avoid)
(41)      [ALGB] (2) Real[2] $FUN_6 (start = Buildings.Electrical.AC.OnePhase.Lines.Examples.ACLine.line_1.line.PhaseSystem_p.j(line_1.line.i_p))
(42)      [ALGB] (1) protected Real line_2a.line.R_actual
(43)      [ALGB] (1) protected Real line_2b.line.omega
(44)      [ALGB] (1) protected Real line_2a.line.omega
(45)      [STAT] (2) protected Real[2] line_sc.line.i_p (start = {0.0 for $f1 in 1:2}, StateSelect = prefer)
(46)      [ALGB] (2) Real[2] load_sc.v = load_sc.v (start = {1.0, 0.0})
(47)      [DDER] (1) Real[1] $DER.line_3a.terminal_p.theta (StateSelect = avoid)
"
[Timeout remaining time 300]
[Calling sys.exit(0), Time elapsed: 6.688654739409685]