Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PowerSysPro_PowerSysPro.Examples.TwoSourcesOneLineThreeSensors.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.001304/0.001304, allocations: 100.4 kB / 20.55 MB, free: 1.113 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.00171/0.00171, allocations: 215.6 kB / 23.86 MB, free: 4.309 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.567/1.567, allocations: 230.6 MB / 257.6 MB, free: 7.781 MB / 206.1 MB " [Timeout remaining time 178] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSysPro 2.1.3/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSysPro 2.1.3/package.mo): time 0.03966/0.03966, allocations: 6.133 MB / 320.2 MB, free: 12.7 MB / 254.1 MB " [Timeout remaining time 180] Using package PowerSysPro with version 2.1.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSysPro 2.1.3/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(PowerSysPro.Examples.TwoSourcesOneLineThreeSensors,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="PowerSysPro_PowerSysPro.Examples.TwoSourcesOneLineThreeSensors") translateModel(PowerSysPro.Examples.TwoSourcesOneLineThreeSensors,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="PowerSysPro_PowerSysPro.Examples.TwoSourcesOneLineThreeSensors") [Timeout 660] "Notification: Performance of FrontEnd - Absyn->SCode: time 2.494e-05/2.494e-05, allocations: 6.219 kB / 445.8 MB, free: 4.559 MB / 302.1 MB Notification: Performance of NFInst.instantiate(PowerSysPro.Examples.TwoSourcesOneLineThreeSensors): time 0.002342/0.002367, allocations: 1.584 MB / 447.3 MB, free: 2.977 MB / 302.1 MB Notification: Performance of NFInst.instExpressions: time 0.001903/0.00427, allocations: 1.458 MB / 448.8 MB, free: 1.504 MB / 302.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 8.725e-05/0.004357, allocations: 4 kB / 448.8 MB, free: 1.5 MB / 302.1 MB Notification: Performance of NFTyping.typeComponents: time 0.0003593/0.004716, allocations: 127.3 kB / 448.9 MB, free: 1.375 MB / 302.1 MB Notification: Performance of NFTyping.typeBindings: time 0.0002355/0.004952, allocations: 91.38 kB / 449 MB, free: 1.285 MB / 302.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.001371/0.006323, allocations: 0.8472 MB / 449.9 MB, free: 448 kB / 302.1 MB Notification: Performance of NFFlatten.flatten: time 0.0002677/0.006591, allocations: 286.8 kB / 450.1 MB, free: 160 kB / 302.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.0001815/0.006772, allocations: 65.5 kB / 450.2 MB, free: 92 kB / 302.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.0001429/0.006915, allocations: 103.6 kB / 450.3 MB, free: 15.98 MB / 318.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.0002192/0.007134, allocations: 123.3 kB / 450.4 MB, free: 15.87 MB / 318.1 MB Notification: Performance of NFPackage.collectConstants: time 3.678e-05/0.007171, allocations: 20 kB / 450.4 MB, free: 15.85 MB / 318.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.0004269/0.007598, allocations: 207.8 kB / 450.7 MB, free: 15.64 MB / 318.1 MB Notification: Performance of combineBinaries: time 0.0004876/0.008085, allocations: 0.5143 MB / 451.2 MB, free: 15.12 MB / 318.1 MB Notification: Performance of replaceArrayConstructors: time 0.0002561/0.008342, allocations: 351.3 kB / 451.5 MB, free: 14.78 MB / 318.1 MB Notification: Performance of NFVerifyModel.verify: time 3.84e-05/0.00838, allocations: 20 kB / 451.5 MB, free: 14.76 MB / 318.1 MB Notification: Performance of FrontEnd: time 3.212e-05/0.008412, allocations: 4 kB / 451.5 MB, free: 14.75 MB / 318.1 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 54 (48) * Number of variables: 60 (60) Notification: Performance of [SIM] Bindings: time 0.001775/0.01019, allocations: 1.569 MB / 453.1 MB, free: 13.05 MB / 318.1 MB Notification: Performance of [SIM] FunctionAlias: time 0.0002179/0.0104, allocations: 192.9 kB / 453.3 MB, free: 12.85 MB / 318.1 MB Notification: Performance of [SIM] Early Inline: time 0.001798/0.0122, allocations: 1.538 MB / 454.8 MB, free: 11.29 MB / 318.1 MB Notification: Performance of [SIM] Simplify 1: time 0.0003017/0.0125, allocations: 197.8 kB / 455 MB, free: 11.05 MB / 318.1 MB Notification: Performance of [SIM] Alias: time 0.001134/0.01364, allocations: 0.76 MB / 455.8 MB, free: 10.2 MB / 318.1 MB Notification: Performance of [SIM] Simplify 2: time 0.0002755/0.01391, allocations: 197.7 kB / 456 MB, free: 9.969 MB / 318.1 MB Notification: Performance of [SIM] Remove Stream: time 0.0001395/0.01405, allocations: 120.2 kB / 456.1 MB, free: 9.824 MB / 318.1 MB Notification: Performance of [SIM] Detect States: time 0.0003337/0.01439, allocations: 279.3 kB / 456.4 MB, free: 9.496 MB / 318.1 MB Notification: Performance of [SIM] Events: time 6.124e-05/0.01445, allocations: 35.97 kB / 456.4 MB, free: 9.461 MB / 318.1 MB Notification: Performance of [SIM] Partitioning: time 0.0006018/0.01505, allocations: 461.8 kB / 456.9 MB, free: 8.973 MB / 318.1 MB Error: Internal error NBSorting.tarjan failed to sort system: System Variables (45/45) ************************** (1|1) [ALGB] (1) Real src1.terminal.v.im (start = 0.0) (2|2) [ALGB] (1) flow Real line.terminalB.i.re (3|3) [ALGB] (1) Real line.IB = PowerSysPro.Functions.abs(line.terminalB.i) (4|4) [ALGB] (1) flow Real sensor1.terminalB.i.im (5|5) [ALGB] (1) flow Real sensor1.terminalA.i.re (6|6) [ALGB] (1) Real sensor1.Umes (7|7) [ALGB] (1) flow Real sensor2.terminalA.i.re (8|8) [ALGB] (1) Real sensor2.Umes (9|9) [ALGB] (1) Real sensor1.Smes (10|10) [ALGB] (1) Real sensor2.Smes (11|11) [ALGB] (1) Real line.UB = 1.7320508075688772 * PowerSysPro.Functions.abs(line.terminalB.v) (12|12) [ALGB] (1) Real sensor1.Qmes (13|13) [ALGB] (1) Real sensor2.Qmes (14|14) [ALGB] (1) Real sensor1.Pmes (15|15) [ALGB] (1) Real line.terminalB.v.re (start = line.UNom / 1.7320508075688772) (16|16) [ALGB] (1) flow Real src1.terminal.i.re (17|17) [ALGB] (1) Real sensor2.Pmes (18|18) [ALGB] (1) Real sensor1.terminalB.v.im (19|19) [ALGB] (1) Real sensor3.deltaU (20|20) [ALGB] (1) Real line.S = line.IA * 1.7320508075688772 * line.UA (21|21) [ALGB] (1) flow Real line.terminalA.i.im (start = 0.0) (22|22) [ALGB] (1) Real sensor2.terminalA.v.re (23|23) [ALGB] (1) Real sensor1.terminalA.v.re (24|24) [ALGB] (1) Real line.IA = PowerSysPro.Functions.abs(line.terminalA.i) (25|25) [ALGB] (1) flow Real src1.terminal.i.im (26|26) [ALGB] (1) flow Real sensor2.terminalA.i.im (27|27) [ALGB] (1) flow Real sensor1.terminalA.i.im (28|28) [ALGB] (1) Real sensor1.Imes (29|29) [ALGB] (1) Real sensor2.Imes (30|30) [ALGB] (1) Real src1.S = src1.I * 1.7320508075688772 * src1.U (31|31) [ALGB] (1) flow Real sensor1.terminalB.i.re (32|32) [ALGB] (1) Real line.terminalA.v.im (33|33) [ALGB] (1) Real line.UA = 1.7320508075688772 * PowerSysPro.Functions.abs(line.terminalA.v) (34|34) [ALGB] (1) flow Real line.terminalB.i.im (35|35) [ALGB] (1) flow Real line.terminalA.i.re (start = 0.0) (36|36) [ALGB] (1) Real sensor3.deltaI (37|37) [ALGB] (1) Real src1.I = PowerSysPro.Functions.abs(src1.terminal.i) (38|38) [ALGB] (1) Real src1.U = 1.7320508075688772 * PowerSysPro.Functions.abs(src1.terminal.v) (39|39) [ALGB] (1) Real line.terminalB.v.im (start = 0.0) (40|40) [ALGB] (1) Real sensor1.terminalA.v.im (41|41) [ALGB] (1) Real sensor2.terminalA.v.im (42|42) [ALGB] (1) Real src1.terminal.v.re (start = src1.UNom / 1.7320508075688772) (43|43) [ALGB] (1) Real line.terminalA.v.re (44|44) [ALGB] (1) Real sensor1.terminalB.v.re (45|45) [ALGB] (1) Real sensor3.deltaS System Equations (39/39) ************************** (1|1) [SCAL] (1) sensor2.Smes = 1.7320508075688772 * sensor2.Imes * sensor2.Umes ($RES_SIM_10) (2|2) [SCAL] (1) sensor2.Imes = (sensor2.terminalA.i.re ^ 2.0 + sensor2.terminalA.i.im ^ 2.0) ^ 0.5 ($RES_SIM_11) (3|3) [SCAL] (1) sensor2.Umes = 1.7320508075688772 * (sensor2.terminalA.v.re ^ 2.0 + sensor2.terminalA.v.im ^ 2.0) ^ 0.5 ($RES_SIM_12) (4|4) [SCAL] (1) line.terminalB.v.im = sensor2.terminalA.v.im ($RES_SIM_31) (5|5) [SCAL] (1) line.terminalB.v.re = sensor2.terminalA.v.re ($RES_SIM_32) (6|6) [SCAL] (1) sensor1.terminalB.i.im + line.terminalA.i.im = 0.0 ($RES_SIM_17) (7|7) [SCAL] (1) src1.terminal.v.im = sensor1.terminalA.v.im ($RES_SIM_33) (8|8) [SCAL] (1) sensor1.terminalB.i.re + line.terminalA.i.re = 0.0 ($RES_SIM_18) (9|9) [SCAL] (1) src1.I = (src1.terminal.i.re ^ 2.0 + src1.terminal.i.im ^ 2.0) ^ 0.5 ($RES_BND_40) (10|10) [SCAL] (1) src1.terminal.v.re = sensor1.terminalA.v.re ($RES_SIM_34) (11|11) [SCAL] (1) src1.U = 1.7320508075688772 * (src1.terminal.v.re ^ 2.0 + src1.terminal.v.im ^ 2.0) ^ 0.5 ($RES_BND_41) (12|12) [SCAL] (1) sensor1.terminalB.v.im = line.terminalA.v.im ($RES_SIM_35) (13|13) [SCAL] (1) src1.terminal.v.re = 0.5773502691896258 * src1.UNom * cos(src1.theta) ($RES_SIM_50) (14|14) [SCAL] (1) src1.S = src1.I * 1.7320508075688772 * src1.U ($RES_BND_42) (15|15) [SCAL] (1) sensor1.terminalB.v.re = line.terminalA.v.re ($RES_SIM_36) (16|16) [SCAL] (1) src1.terminal.v.im = 0.5773502691896258 * src1.UNom * sin(src1.theta) ($RES_SIM_51) (17|17) [SCAL] (1) line.IA = (line.terminalA.i.re ^ 2.0 + line.terminalA.i.im ^ 2.0) ^ 0.5 ($RES_BND_43) (18|18) [SCAL] (1) line.terminalA.i.re + line.terminalB.i.re = line.Y.re * (line.terminalA.v.re + line.terminalB.v.re) - line.Y.im * (line.terminalA.v.im + line.terminalB.v.im) ($RES_SIM_52) (19|19) [SCAL] (1) line.UA = 1.7320508075688772 * (line.terminalA.v.re ^ 2.0 + line.terminalA.v.im ^ 2.0) ^ 0.5 ($RES_BND_44) (20|20) [SCAL] (1) line.terminalA.i.im + line.terminalB.i.im = line.Y.re * (line.terminalA.v.im + line.terminalB.v.im) + line.Y.im * (line.terminalA.v.re + line.terminalB.v.re) ($RES_SIM_53) (21|21) [SCAL] (1) line.IB = (line.terminalB.i.re ^ 2.0 + line.terminalB.i.im ^ 2.0) ^ 0.5 ($RES_BND_45) (22|22) [SCAL] (1) line.terminalA.v.re - line.terminalB.v.re = line.Z.re * ((line.terminalA.i.re + line.Y.im * line.terminalA.v.im) - line.Y.re * line.terminalA.v.re) - line.Z.im * (line.terminalA.i.im - (line.Y.re * line.terminalA.v.im + line.Y.im * line.terminalA.v.re)) ($RES_SIM_54) (23|23) [SCAL] (1) line.UB = 1.7320508075688772 * (line.terminalB.v.re ^ 2.0 + line.terminalB.v.im ^ 2.0) ^ 0.5 ($RES_BND_46) (24|24) [SCAL] (1) line.terminalA.v.im - line.terminalB.v.im = line.Z.re * (line.terminalA.i.im - (line.Y.re * line.terminalA.v.im + line.Y.im * line.terminalA.v.re)) + line.Z.im * ((line.terminalA.i.re + line.Y.im * line.terminalA.v.im) - line.Y.re * line.terminalA.v.re) ($RES_SIM_55) (25|25) [SCAL] (1) line.S = line.IA * 1.7320508075688772 * line.UA ($RES_BND_47) (26|26) [SCAL] (1) sensor2.Pmes = 3.0 * sensor2.terminalA.v.im * sensor2.terminalA.i.im + 3.0 * sensor2.terminalA.v.re * sensor2.terminalA.i.re ($RES_SIM_56) (27|27) [SCAL] (1) sensor2.Qmes = 3.0 * sensor2.terminalA.v.im * sensor2.terminalA.i.re - 3.0 * sensor2.terminalA.v.re * sensor2.terminalA.i.im ($RES_SIM_57) (28|28) [SCAL] (1) sensor1.Pmes = 3.0 * sensor1.terminalA.v.im * sensor1.terminalA.i.im + 3.0 * sensor1.terminalA.v.re * sensor1.terminalA.i.re ($RES_SIM_58) (29|29) [SCAL] (1) sensor1.Qmes = 3.0 * sensor1.terminalA.v.im * sensor1.terminalA.i.re - 3.0 * sensor1.terminalA.v.re * sensor1.terminalA.i.im ($RES_SIM_59) (30|30) [SCAL] (1) sensor2.terminalA.i.im + line.terminalB.i.im = 0.0 ($RES_SIM_21) (31|31) [SCAL] (1) sensor2.terminalA.i.re + line.terminalB.i.re = 0.0 ($RES_SIM_22) (32|32) [SCAL] (1) sensor1.terminalA.i.im + src1.terminal.i.im = 0.0 ($RES_SIM_23) (33|33) [SCAL] (1) sensor1.terminalA.i.re + src1.terminal.i.re = 0.0 ($RES_SIM_24) (34|34) [SCAL] (1) sensor1.Umes = 1.7320508075688772 * (sensor1.terminalA.v.re ^ 2.0 + sensor1.terminalA.v.im ^ 2.0) ^ 0.5 ($RES_SIM_7) (35|35) [SCAL] (1) sensor1.Imes = (sensor1.terminalA.i.re ^ 2.0 + sensor1.terminalA.i.im ^ 2.0) ^ 0.5 ($RES_SIM_6) (36|36) [SCAL] (1) sensor1.Smes = 1.7320508075688772 * sensor1.Imes * sensor1.Umes ($RES_SIM_5) (37|37) [SCAL] (1) sensor3.deltaU = sensor1.Umes - sensor2.Umes ($RES_SIM_2) (38|38) [SCAL] (1) sensor3.deltaI = sensor1.Imes - sensor2.Imes ($RES_SIM_1) (39|39) [SCAL] (1) sensor3.deltaS = 1.7320508075688772 * (sensor1.Imes * sensor1.Umes - sensor2.Imes * sensor2.Umes) ($RES_SIM_0) =================== Scalar Matching =================== variable to equation ********************** var 1 --> eqn 16 var 2 --> eqn 18 var 3 --> eqn 21 var 4 --> eqn 6 var 5 --> eqn 29 var 6 --> eqn 34 var 7 --> eqn 31 var 8 --> eqn 37 var 9 --> eqn 36 var 10 --> eqn -1 var 11 --> eqn 23 var 12 --> eqn -1 var 13 --> eqn 27 var 14 --> eqn 28 var 15 --> eqn 5 var 16 --> eqn 33 var 17 --> eqn -1 var 18 --> eqn 12 var 19 --> eqn -1 var 20 --> eqn -1 var 21 --> eqn 20 var 22 --> eqn 3 var 23 --> eqn 10 var 24 --> eqn 25 var 25 --> eqn 9 var 26 --> eqn 2 var 27 --> eqn 32 var 28 --> eqn 35 var 29 --> eqn 1 var 30 --> eqn -1 var 31 --> eqn 8 var 32 --> eqn 22 var 33 --> eqn 19 var 34 --> eqn 30 var 35 --> eqn 17 var 36 --> eqn 38 var 37 --> eqn 14 var 38 --> eqn 11 var 39 --> eqn 4 var 40 --> eqn 7 var 41 --> eqn 26 var 42 --> eqn 13 var 43 --> eqn 24 var 44 --> eqn 15 var 45 --> eqn 39 equation to variable ********************** eqn 1 --> var 29 eqn 2 --> var 26 eqn 3 --> var 22 eqn 4 --> var 39 eqn 5 --> var 15 eqn 6 --> var 4 eqn 7 --> var 40 eqn 8 --> var 31 eqn 9 --> var 25 eqn 10 --> var 23 eqn 11 --> var 38 eqn 12 --> var 18 eqn 13 --> var 42 eqn 14 --> var 37 eqn 15 --> var 44 eqn 16 --> var 1 eqn 17 --> var 35 eqn 18 --> var 2 eqn 19 --> var 33 eqn 20 --> var 21 eqn 21 --> var 3 eqn 22 --> var 32 eqn 23 --> var 11 eqn 24 --> var 43 eqn 25 --> var 24 eqn 26 --> var 41 eqn 27 --> var 13 eqn 28 --> var 14 eqn 29 --> var 5 eqn 30 --> var 34 eqn 31 --> var 7 eqn 32 --> var 27 eqn 33 --> var 16 eqn 34 --> var 6 eqn 35 --> var 28 eqn 36 --> var 9 eqn 37 --> var 8 eqn 38 --> var 36 eqn 39 --> var 45 " [Timeout remaining time 660] [Calling sys.exit(0), Time elapsed: 2.578973118972499] Failed to read output from testmodel.py, exit status != 0: 0.024908509978558868 0.041452473000000004 0.029819899 Calling exit ...