Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_dev_IDEAS.Electrical.AC.ThreePhasesUnbalanced.Lines.Examples.ACLine_RL_N.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.0006312/0.0006312, allocations: 80.25 kB / 20.06 MB, free: 4.465 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.0007113/0.0007113, allocations: 173.9 kB / 23.36 MB, free: 1.16 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.8257/0.8257, allocations: 177.2 MB / 203.7 MB, free: 5.691 MB / 186.7 MB " [Timeout remaining time 179] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/package.mo", uses=false) [Timeout 180] "[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/Examples/PPD12/Structure.mo:531:21-535:34:writable] Warning: Lexer treating \\ as \\\\, since \\e is not a valid Modelica escape sequence. [/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/Examples/PPD12/Structure.mo:531:21-535:42:writable] Warning: Lexer treating \\ as \\\\, since \\< is not a valid Modelica escape sequence. Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/package.mo): time 0.6834/0.6834, allocations: 154.5 MB / 414.7 MB, free: 0.793 MB / 378.7 MB [/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/Utilities/Cryptographics/BaseClasses/sha.mo:2:1-19:8:writable] Warning: External function annotation should occur on the external-clause, not on the function. [/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/Utilities/File/sha2.mo:2:1-9:9:writable] Warning: External function annotation should occur on the external-clause, not on the function. " [Timeout remaining time 179] Using package IDEAS with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 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(IDEAS.Electrical.AC.ThreePhasesUnbalanced.Lines.Examples.ACLine_RL_N,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Electrical.AC.ThreePhasesUnbalanced.Lines.Examples.ACLine_RL_N") translateModel(IDEAS.Electrical.AC.ThreePhasesUnbalanced.Lines.Examples.ACLine_RL_N,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Electrical.AC.ThreePhasesUnbalanced.Lines.Examples.ACLine_RL_N") [Timeout 660] "Notification: Performance of FrontEnd - Absyn->SCode: time 2.238e-05/2.238e-05, allocations: 6.25 kB / 0.6196 GB, free: 13.08 MB / 0.5886 GB Notification: Performance of NFInst.instantiate(IDEAS.Electrical.AC.ThreePhasesUnbalanced.Lines.Examples.ACLine_RL_N): time 0.01416/0.01418, allocations: 19.11 MB / 0.6382 GB, free: 9.895 MB / 0.6042 GB Notification: Performance of NFInst.instExpressions: time 0.008722/0.0229, allocations: 6.401 MB / 0.6445 GB, free: 3.477 MB / 0.6042 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.001686/0.02459, allocations: 115.1 kB / 0.6446 GB, free: 3.363 MB / 0.6042 GB Notification: Performance of NFTyping.typeComponents: time 0.002791/0.02738, allocations: 2.252 MB / 0.6468 GB, free: 1.102 MB / 0.6042 GB Notification: Performance of NFTyping.typeBindings: time 0.002969/0.03035, allocations: 1.274 MB / 0.648 GB, free: 15.82 MB / 0.6198 GB Notification: Performance of NFTyping.typeClassSections: time 0.002525/0.03288, allocations: 1.294 MB / 0.6493 GB, free: 14.52 MB / 0.6198 GB Notification: Performance of NFFlatten.flatten: time 0.006419/0.03929, allocations: 4.862 MB / 0.654 GB, free: 9.648 MB / 0.6198 GB Notification: Performance of NFFlatten.resolveConnections: time 0.004632/0.04393, allocations: 2.36 MB / 0.6563 GB, free: 7.219 MB / 0.6198 GB Notification: Performance of NFEvalConstants.evaluate: time 0.005877/0.0498, allocations: 2.565 MB / 0.6588 GB, free: 4.648 MB / 0.6198 GB Notification: Performance of NFSimplifyModel.simplify: time 0.001591/0.05139, allocations: 1.321 MB / 0.6601 GB, free: 3.324 MB / 0.6198 GB Notification: Performance of NFPackage.collectConstants: time 0.0003613/0.05176, allocations: 272 kB / 0.6604 GB, free: 3.059 MB / 0.6198 GB Notification: Performance of NFFlatten.collectFunctions: time 0.001168/0.05292, allocations: 0.7367 MB / 0.6611 GB, free: 2.32 MB / 0.6198 GB Notification: Performance of combineBinaries: time 0.002285/0.05521, allocations: 3.994 MB / 0.665 GB, free: 14.3 MB / 0.6355 GB Notification: Performance of replaceArrayConstructors: time 0.001118/0.05633, allocations: 2.306 MB / 0.6673 GB, free: 11.97 MB / 0.6355 GB Notification: Performance of NFVerifyModel.verify: time 0.0004191/0.05675, allocations: 188 kB / 0.6674 GB, free: 11.79 MB / 0.6355 GB Notification: Performance of FrontEnd: time 0.000273/0.05702, allocations: 55.8 kB / 0.6675 GB, free: 11.73 MB / 0.6355 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 799 (485) * Number of variables: 798 (330) Notification: Performance of [SIM] Bindings: time 0.007911/0.06493, allocations: 10.8 MB / 0.678 GB, free: 0.6328 MB / 0.6355 GB Notification: Performance of [SIM] FunctionAlias: time 0.3272/0.3922, allocations: 1.916 MB / 0.6799 GB, free: 63.17 MB / 0.6355 GB Notification: Performance of [SIM] Early Inline: time 0.007869/0.4, allocations: 9.444 MB / 0.6891 GB, free: 62.93 MB / 0.6355 GB Notification: Performance of [SIM] Simplify 1: time 0.002037/0.4021, allocations: 0.9692 MB / 0.6901 GB, free: 62.7 MB / 0.6355 GB Warning: NBAlias.setStartFixed: Alias set with conflicting unfixed start values detected. Use -d=dumprepl for more information. Notification: Performance of [SIM] Alias: time 0.01043/0.4125, allocations: 10.37 MB / 0.7002 GB, free: 57.16 MB / 0.6355 GB Notification: Performance of [SIM] Simplify 2: time 0.001092/0.4136, allocations: 0.8112 MB / 0.701 GB, free: 56.89 MB / 0.6355 GB Notification: Performance of [SIM] Remove Stream: time 0.0005084/0.4141, allocations: 0.6387 MB / 0.7016 GB, free: 56.48 MB / 0.6355 GB Notification: Performance of [SIM] Detect States: time 0.001521/0.4156, allocations: 2.112 MB / 0.7037 GB, free: 55.24 MB / 0.6355 GB Notification: Performance of [SIM] Events: time 0.0001572/0.4158, allocations: 195 kB / 0.7039 GB, free: 55.14 MB / 0.6355 GB Notification: Performance of [SIM] Partitioning: time 0.002244/0.418, allocations: 2.974 MB / 0.7068 GB, free: 53.66 MB / 0.6355 GB Error: Internal error NBResolveSingularities.indexReduction failed because there was not enough state candidates to balance out the constraint equations. Constraint Equations (69/81) ****************************** (1) [SCAL] (1) load_sc_2.load3.terminal.theta[1] = load_sc_2.adaWye.terminals[3].theta[1] ($RES_SIM_422) (2) [SCAL] (1) R_1.terminal_n.phase[2].theta[1] = R_1.phase2.terminal_p.theta[1] ($RES_SIM_368) (3) [FOR-] (3) ($RES_SIM_106) (3) [----] for $i1 in 1:3 loop (3) [----] [ARRY] (1) load_sc_2.adaWye.terminal.phase[$i1].theta = load_sc_2.adaWye.terminals[$i1].theta ($RES_SIM_107) (3) [----] end for; (4) [ARRY] (1) RL_2.terminal_p.phase[1].theta = RL_2.terminal_p.phase[4].theta ($RES_SIM_101) (5) [SCAL] (1) R_1.neutral.terminal_p.theta[1] = R_1.terminal_n.phase[4].theta[1] ($RES_SIM_338) (6) [SCAL] (1) R_1.terminal_n.phase[3].theta[1] = R_1.phase3.terminal_p.theta[1] ($RES_SIM_363) (7) [SCAL] (1) $FUN_2 = E.vPhase[2].terminal.theta[1] ($RES_AUX_589) (8) [SCAL] (1) $FUN_3 = E.vPhase[1].terminal.theta[1] ($RES_AUX_588) (9) [SCAL] (1) L_1.terminal_n.phase[1].theta[1] = L_1.phase1.terminal_p.theta[1] ($RES_SIM_333) (10) [SCAL] (1) E.terminal.phase[2].theta[1] = RL_2.terminal_n.phase[2].theta[1] ($RES_SIM_279) (11) [SCAL] (1) E.vPhase[1].terminal.theta[1] = E.terminal.phase[1].theta[1] ($RES_SIM_505) (12) [SCAL] (1) E.terminal.phase[2].theta[1] = R_1.terminal_n.phase[2].theta[1] ($RES_SIM_278) (13) [SCAL] (1) load_sc_1.load1.theRef = load_sc_1.load1.terminal.theta[1] ($RES_AUX_583) (14) [SCAL] (1) E.terminal.phase[3].theta[1] = RL_2.terminal_n.phase[3].theta[1] ($RES_SIM_277) (15) [SCAL] (1) load_sc_1.load2.theRef = load_sc_1.load2.terminal.theta[1] ($RES_AUX_582) (16) [SCAL] (1) E.terminal.phase[3].theta[1] = R_1.terminal_n.phase[3].theta[1] ($RES_SIM_276) (17) [SCAL] (1) load_sc_1.load3.theRef = load_sc_1.load3.terminal.theta[1] ($RES_AUX_581) (18) [SCAL] (1) E.vPhase[2].terminal.theta[1] = E.terminal.phase[2].theta[1] ($RES_SIM_502) (19) [SCAL] (1) E.terminal.phase[4].theta[1] = RL_2.terminal_n.phase[4].theta[1] ($RES_SIM_275) (20) [SCAL] (1) E.vPhase[3].terminal.theta[1] = E.terminal.phase[3].theta[1] ($RES_SIM_499) (21) [SCAL] (1) load_sc_2.load1.theRef = load_sc_2.load1.terminal.theta[1] ($RES_AUX_580) (22) [SCAL] (1) E.terminal.phase[4].theta[1] = R_1.terminal_n.phase[4].theta[1] ($RES_SIM_274) (23) [SCAL] (1) L_1.phase1.theRef = L_1.phase1.terminal_p.theta[1] ($RES_AUX_553) (24) [SCAL] (1) L_1.phase2.theRef = L_1.phase2.terminal_p.theta[1] ($RES_AUX_551) (25) [SCAL] (1) $FUN_1 = 6.283185307179586 * E.vPhase[3].f * time ($RES_SIM_242) (26) [SCAL] (1) $FUN_2 = 6.283185307179586 * E.vPhase[2].f * time ($RES_SIM_241) (27) [SCAL] (1) $FUN_3 = 6.283185307179586 * E.vPhase[1].f * time ($RES_SIM_240) (28) [FOR-] (3) ($RES_SIM_188) (28) [----] for $i1 in 1:3 loop (28) [----] [ARRY] (1) load_sc_1.connection3to4.terminal3.phase[$i1].theta = load_sc_1.adaWye.terminal.phase[$i1].theta ($RES_SIM_189) (28) [----] end for; (29) [SCAL] (1) RL_2.terminal_n.phase[1].theta[1] = RL_2.phase1.terminal_p.theta[1] ($RES_SIM_413) (30) [SCAL] (1) RL_2.phase1.terminal_p.theta[1] = RL_2.terminal_p.phase[1].theta[1] ($RES_SIM_398) (31) [SCAL] (1) L_1.terminal_n.phase[2].theta[1] = L_1.phase2.terminal_p.theta[1] ($RES_SIM_328) (32) [SCAL] (1) RL_2.phase2.terminal_p.theta[1] = RL_2.terminal_p.phase[2].theta[1] ($RES_SIM_393) (33) [SCAL] (1) R_1.phase1.terminal_p.theta[1] = L_1.terminal_n.phase[1].theta[1] ($RES_SIM_358) (34) [SCAL] (1) L_1.terminal_n.phase[3].theta[1] = L_1.phase3.terminal_p.theta[1] ($RES_SIM_323) (35) [SCAL] (1) L_1.phase3.theRef = L_1.phase3.terminal_p.theta[1] ($RES_AUX_549) (36) [SCAL] (1) L_1.phase4.theRef = L_1.phase4.terminal_p.theta[1] ($RES_AUX_547) (37) [SCAL] (1) load_sc_1.load1.terminal.theta[1] = load_sc_1.adaWye.terminals[1].theta[1] ($RES_SIM_471) (38) [SCAL] (1) R_1.phase2.terminal_p.theta[1] = L_1.terminal_n.phase[2].theta[1] ($RES_SIM_353) (39) [SCAL] (1) RL_2.phase3.terminal_p.theta[1] = RL_2.terminal_p.phase[3].theta[1] ($RES_SIM_388) (40) [SCAL] (1) RL_2.terminal_n.phase[2].theta[1] = RL_2.phase2.terminal_p.theta[1] ($RES_SIM_408) (41) [SCAL] (1) load_sc_2.load2.theRef = load_sc_2.load2.terminal.theta[1] ($RES_AUX_579) (42) [SCAL] (1) load_sc_2.load3.theRef = load_sc_2.load3.terminal.theta[1] ($RES_AUX_578) (43) [SCAL] (1) RL_2.phase1.theRef = RL_2.phase1.terminal_p.theta[1] ($RES_AUX_577) (44) [SCAL] (1) RL_2.terminal_n.phase[3].theta[1] = RL_2.phase3.terminal_p.theta[1] ($RES_SIM_403) (45) [SCAL] (1) RL_2.neutral.terminal_p.theta[1] = RL_2.terminal_p.phase[4].theta[1] ($RES_SIM_383) (46) [SCAL] (1) RL_2.phase2.theRef = RL_2.phase2.terminal_p.theta[1] ($RES_AUX_573) (47) [SCAL] (1) L_1.phase4.terminal_p.theta[1] = L_1.terminal_n.phase[4].theta[1] ($RES_SIM_298) (48) [SCAL] (1) L_1.phase1.terminal_p.theta[1] = L_1.terminal_p.phase[1].theta[1] ($RES_SIM_318) (49) [SCAL] (1) L_1.phase2.terminal_p.theta[1] = L_1.terminal_p.phase[2].theta[1] ($RES_SIM_313) (50) [SCAL] (1) load_sc_1.load2.terminal.theta[1] = load_sc_1.adaWye.terminals[2].theta[1] ($RES_SIM_466) (51) [SCAL] (1) load_sc_2.load1.terminal.theta[1] = load_sc_2.adaWye.terminals[1].theta[1] ($RES_SIM_432) (52) [FOR-] (3) ($RES_SIM_99) (52) [----] for $i1 in 1:3 loop (52) [----] [ARRY] (1) load_sc_2.connection3to4.terminal3.phase[$i1].theta = RL_2.terminal_p.phase[$i1].theta ($RES_SIM_100) (52) [----] end for; (53) [SCAL] (1) load_sc_1.load3.terminal.theta[1] = load_sc_1.adaWye.terminals[3].theta[1] ($RES_SIM_461) (54) [FOR-] (3) ($RES_SIM_178) (54) [----] for $i1 in 1:3 loop (54) [----] [ARRY] (1) load_sc_1.adaWye.terminal.phase[$i1].theta = load_sc_1.adaWye.terminals[$i1].theta ($RES_SIM_179) (54) [----] end for; (55) [SCAL] (1) L_1.phase3.terminal_p.theta[1] = L_1.terminal_p.phase[3].theta[1] ($RES_SIM_308) (56) [ARRY] (1) L_1.terminal_p.phase[1].theta = L_1.terminal_p.phase[4].theta ($RES_SIM_173) (57) [SCAL] (1) RL_2.neutral.terminal_p.theta[1] = RL_2.terminal_n.phase[4].theta[1] ($RES_SIM_378) (58) [SCAL] (1) R_1.phase3.terminal_p.theta[1] = L_1.terminal_n.phase[3].theta[1] ($RES_SIM_348) (59) [FOR-] (3) ($RES_SIM_171) (59) [----] for $i1 in 1:3 loop (59) [----] [ARRY] (1) load_sc_1.connection3to4.terminal3.phase[$i1].theta = L_1.terminal_p.phase[$i1].theta ($RES_SIM_172) (59) [----] end for; (60) [SCAL] (1) L_1.phase4.terminal_p.theta[1] = L_1.terminal_p.phase[4].theta[1] ($RES_SIM_303) (61) [SCAL] (1) R_1.terminal_n.phase[1].theta[1] = R_1.phase1.terminal_p.theta[1] ($RES_SIM_373) (62) [SCAL] (1) R_1.neutral.terminal_p.theta[1] = L_1.terminal_n.phase[4].theta[1] ($RES_SIM_343) (63) [SCAL] (1) RL_2.phase3.theRef = RL_2.phase3.terminal_p.theta[1] ($RES_AUX_569) (64) [SCAL] (1) RL_2.neutral.theRef = RL_2.neutral.terminal_p.theta[1] ($RES_AUX_565) (65) [SCAL] (1) $FUN_1 = E.vPhase[3].terminal.theta[1] ($RES_AUX_590) (66) [SCAL] (1) E.terminal.phase[1].theta[1] = RL_2.terminal_n.phase[1].theta[1] ($RES_SIM_281) (67) [SCAL] (1) E.terminal.phase[1].theta[1] = R_1.terminal_n.phase[1].theta[1] ($RES_SIM_280) (68) [SCAL] (1) load_sc_2.load2.terminal.theta[1] = load_sc_2.adaWye.terminals[2].theta[1] ($RES_SIM_427) (69) [FOR-] (3) ($RES_SIM_116) (69) [----] for $i1 in 1:3 loop (69) [----] [ARRY] (1) load_sc_2.connection3to4.terminal3.phase[$i1].theta = load_sc_2.adaWye.terminal.phase[$i1].theta ($RES_SIM_117) (69) [----] end for; State Candidate Variables (48/80) *********************************** (1) [ALGB] (1) Real[1] R_1.phase2.terminal_p.theta (2) [ALGB] (1) Real $FUN_3 (3) [ALGB] (1) Real $FUN_2 (4) [ALGB] (1) Real[1] L_1.phase3.terminal_p.theta (5) [ALGB] (1) Real $FUN_1 (6) [ALGB] (4) Real[4, 1] E.terminal.phase.theta (7) [ALGB] (1) Real[1] load_sc_1.load3.terminal.theta (8) [STAT] (1) protected Real load_sc_2.load3.theRef (9) [STAT] (1) protected Real load_sc_1.load3.theRef (10) [ALGB] (1) Real[1] load_sc_2.load3.terminal.theta (11) [ALGB] (1) Real[1] R_1.phase3.terminal_p.theta (12) [STAT] (1) protected Real L_1.phase4.theRef (13) [ALGB] (1) Real[1] L_1.phase4.terminal_p.theta (14) [ALGB] (1) Real[1] RL_2.phase2.terminal_p.theta (15) [STAT] (1) protected Real RL_2.phase1.theRef (16) [ALGB] (4) Real[4, 1] RL_2.terminal_n.phase.theta (17) [STAT] (1) protected Real L_1.phase3.theRef (18) [ALGB] (3) Real[3, 1] E.vPhase.terminal.theta (19) [STAT] (1) protected Real load_sc_2.load1.theRef (20) [ALGB] (1) Real[1] load_sc_1.load1.terminal.theta (21) [STAT] (1) protected Real load_sc_1.load1.theRef (22) [ALGB] (1) Real[1] load_sc_2.load1.terminal.theta (23) [STAT] (1) protected Real L_1.phase2.theRef (24) [ALGB] (4) Real[4, 1] L_1.terminal_n.phase.theta (25) [ALGB] (1) Real[1] RL_2.phase3.terminal_p.theta (26) [STAT] (1) protected Real RL_2.phase2.theRef (27) [STAT] (1) protected Real L_1.phase1.theRef (28) [ALGB] (4) Real[4, 1] RL_2.terminal_p.phase.theta (29) [ALGB] (4) Real[4, 1] R_1.terminal_n.phase.theta (30) [ALGB] (1) Real[1] load_sc_1.load2.terminal.theta (31) [ALGB] (3) protected Real[3, 1] load_sc_1.adaWye.terminals.theta (32) [STAT] (1) protected Real load_sc_2.load2.theRef (33) [ALGB] (3) protected Real[3, 1] load_sc_2.adaWye.terminals.theta (34) [STAT] (1) protected Real load_sc_1.load2.theRef (35) [ALGB] (1) Real[1] load_sc_2.load2.terminal.theta (36) [ALGB] (4) Real[4, 1] L_1.terminal_p.phase.theta (37) [STAT] (1) protected Real RL_2.neutral.theRef (38) [ALGB] (1) Real[1] L_1.phase1.terminal_p.theta (39) [ALGB] (1) Real[1] R_1.neutral.terminal_p.theta (40) [ALGB] (1) Real[1] RL_2.phase1.terminal_p.theta (41) [ALGB] (1) Real[1] RL_2.neutral.terminal_p.theta (42) [STAT] (1) protected Real RL_2.phase3.theRef (43) [ALGB] (1) Real[1] R_1.phase1.terminal_p.theta (44) [ALGB] (1) Real[1] L_1.phase2.terminal_p.theta (45) [ALGB] (3) Real[3, 1] load_sc_1.connection3to4.terminal3.phase.theta (46) [ALGB] (3) protected Real[3, 1] load_sc_2.adaWye.terminal.phase.theta (47) [ALGB] (3) Real[3, 1] load_sc_2.connection3to4.terminal3.phase.theta (48) [ALGB] (3) protected Real[3, 1] load_sc_1.adaWye.terminal.phase.theta " [Timeout remaining time 660] [Calling sys.exit(0), Time elapsed: 2.8538691960275173] Failed to read output from testmodel.py, exit status != 0: 0.432824463583529 0.44055967 0.381630915 Calling exit ...