Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PowerSystems_PowerSystems.Examples.Generic.Test.PMeterTest.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems 1.0.1/package.mo", uses=false)
Using package PowerSystems with version 1.0.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems 1.0.1/package.mo)
Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(PowerSystems.Examples.Generic.Test.PMeterTest,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="PowerSystems_PowerSystems.Examples.Generic.Test.PMeterTest")
translateModel(PowerSystems.Examples.Generic.Test.PMeterTest,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="PowerSystems_PowerSystems.Examples.Generic.Test.PMeterTest")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001035/0.001035, allocations: 115.3 kB / 17.75 MB, free: 5.195 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001065/0.001065, allocations: 189.9 kB / 18.68 MB, free: 4.27 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.065/1.065, allocations: 205.1 MB / 224.6 MB, free: 12.24 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerSystems 1.0.1/package.mo): time 0.1497/0.1497, allocations: 37.99 MB / 309.9 MB, free: 5.961 MB / 254.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 9.017e-06/9.017e-06, allocations: 2.281 kB / 432.5 MB, free: 15.73 MB / 318.1 MB
Notification: Performance of NFInst.instantiate(PowerSystems.Examples.Generic.Test.PMeterTest): time 0.002557/0.002573, allocations: 3.432 MB / 435.9 MB, free: 12.29 MB / 318.1 MB
Notification: Performance of NFInst.instExpressions: time 0.001226/0.00381, allocations: 1.567 MB / 437.5 MB, free: 10.71 MB / 318.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 7.548e-05/0.003896, allocations: 4 kB / 437.5 MB, free: 10.7 MB / 318.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.000537/0.004439, allocations: 273.5 kB / 437.7 MB, free: 10.44 MB / 318.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.00033/0.004783, allocations: 271 kB / 438 MB, free: 10.17 MB / 318.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0001763/0.004968, allocations: 135.6 kB / 438.1 MB, free: 10.04 MB / 318.1 MB
Notification: Performance of NFFlatten.flatten: time 0.0002442/0.00522, allocations: 363 kB / 438.5 MB, free: 9.684 MB / 318.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0001656/0.005393, allocations: 97.48 kB / 438.6 MB, free: 9.59 MB / 318.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0001238/0.005524, allocations: 135.4 kB / 438.7 MB, free: 9.457 MB / 318.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0001074/0.005639, allocations: 119.5 kB / 438.8 MB, free: 9.34 MB / 318.1 MB
Notification: Performance of NFPackage.collectConstants: time 1.532e-05/0.00566, allocations: 8 kB / 438.8 MB, free: 9.332 MB / 318.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0002361/0.005901, allocations: 271.2 kB / 439.1 MB, free: 9.066 MB / 318.1 MB
Notification: Performance of combineBinaries: time 0.0001324/0.006041, allocations: 305 kB / 439.4 MB, free: 8.766 MB / 318.1 MB
Notification: Performance of replaceArrayConstructors: time 7.282e-05/0.006119, allocations: 217.5 kB / 439.6 MB, free: 8.551 MB / 318.1 MB
Notification: Performance of NFVerifyModel.verify: time 2.517e-05/0.00615, allocations: 23.94 kB / 439.6 MB, free: 8.527 MB / 318.1 MB
Notification: Performance of FrontEnd: time 1.047e-05/0.006167, allocations: 4 kB / 439.7 MB, free: 8.523 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: 49 (33)
 * Number of variables: 49 (32)
Notification: Performance of Bindings: time 0.0005363/0.006707, allocations: 0.884 MB / 440.5 MB, free: 7.535 MB / 318.1 MB
Notification: Performance of FunctionAlias: time 2.494e-05/0.006738, allocations: 31.94 kB / 440.6 MB, free: 7.504 MB / 318.1 MB
Notification: Performance of Early Inline: time 0.0003336/0.007076, allocations: 0.545 MB / 441.1 MB, free: 6.93 MB / 318.1 MB
Notification: Performance of simplify1: time 2.668e-05/0.00711, allocations: 31.94 kB / 441.1 MB, free: 6.898 MB / 318.1 MB
Notification: Performance of Alias: time 0.0003492/0.007464, allocations: 499.3 kB / 441.6 MB, free: 6.328 MB / 318.1 MB
Notification: Performance of simplify2: time 2.409e-05/0.007496, allocations: 35.95 kB / 441.7 MB, free: 6.293 MB / 318.1 MB
Notification: Performance of Events: time 2.671e-05/0.007528, allocations: 31.95 kB / 441.7 MB, free: 6.262 MB / 318.1 MB
Notification: Performance of Detect States: time 0.0001933/0.007725, allocations: 120.1 kB / 441.8 MB, free: 6.129 MB / 318.1 MB
Notification: Performance of Partitioning: time 0.0001188/0.00785, allocations: 164.2 kB / 442 MB, free: 5.953 MB / 318.1 MB
Error: Internal error NBSlice.fillDependencyArray failed because number of flattened indices 1 for dependency load.terminal.v[1] could not be divided by the body size 2 without rest.
Error: Internal error NBAdjacency.Matrix.createPseudo failed for:
[ARRY] (2) {load.terminal.v * load.terminal.i, -{-load.terminal.v[2], load.terminal.v[1]} * load.terminal.i} = {load.P, load.P * tan(load.phi)} ($RES_SIM_6)
Error: Internal error NBAdjacency.Matrix.create failed to create adjacency matrix for system:
System Variables (24/41)
**************************
(1)       [ALGB] (2) flow Real[2] pMeter.terminal_n.i (nominal = {1.0 for $i1 in 1:2})
(2)       [ALGB] (2) Real[2] pMeter.terminal_n.theta
(3)       [ALGB] (1) Real pMeter.V = PowerSystems.Examples.Generic.Test.PMeterTest.pMeter.PhaseSystem.systemVoltage(pMeter.v) (nominal = 1000.0)
(4)       [ALGB] (2) Real[2] pMeter.p = PowerSystems.Examples.Generic.Test.PMeterTest.pMeter.PhaseSystem.phasePowers_vi(pMeter.v, pMeter.i)
(5)       [ALGB] (2) flow Real[2] source.terminal.i (nominal = {1.0 for $i1 in 1:2})
(6)       [ALGB] (1) Real pMeter.P
(7)       [ALGB] (2) Real[2] source.p = PowerSystems.Examples.Generic.Test.PMeterTest.source.PhaseSystem.phasePowers_vi(source.terminal.v, source.terminal.i)
(8)       [ALGB] (2) Real[2] load.p = PowerSystems.Examples.Generic.Test.PMeterTest.load.PhaseSystem.phasePowers_vi(load.terminal.v, load.terminal.i)
(9)       [ALGB] (2) Real[2] pMeter.i (start = pMeter.i_start, nominal = {1.0 for $i1 in 1:2})
(10)      [ALGB] (2) flow Real[2] load.terminal.i (nominal = {1.0 for $i1 in 1:2})
(11)      [ALGB] (1) Real source.phi = PowerSystems.Examples.Generic.Test.PMeterTest.source.PhaseSystem.phase(source.terminal.v) - PowerSystems.Examples.Generic.Test.PMeterTest.source.PhaseSystem.phase(-source.terminal.i)
(12)      [ALGB] (2) Real[2] pMeter.terminal_p.v (nominal = {1000.0 for $i1 in 1:2})
(13)      [ALGB] (1) Real pMeter.I = PowerSystems.Examples.Generic.Test.PMeterTest.pMeter.PhaseSystem.systemCurrent(pMeter.i) (nominal = 1.0)
(14)      [ALGB] (2) Real[2] pMeter.terminal_p.theta
(15)      [ALGB] (2) Real[2] pMeter.terminal_n.v (nominal = {1000.0 for $i1 in 1:2})
(16)      [ALGB] (1) Real system.thetaRef = system.thetaRef
(17)      [ALGB] (2) Real[2] source.terminal.theta
(18)      [ALGB] (1) Real pMeter.phi = PowerSystems.Examples.Generic.Test.PMeterTest.pMeter.PhaseSystem.phase(pMeter.v) - PowerSystems.Examples.Generic.Test.PMeterTest.pMeter.PhaseSystem.phase(pMeter.i)
(19)      [ALGB] (2) Real[2] source.terminal.v (nominal = {1000.0 for $i1 in 1:2})
(20)      [ALGB] (1) Real system.thetaRel = system.thetaRef - system.thetaRef
(21)      [ALGB] (2) Real[2] pMeter.v (start = pMeter.v_start, nominal = {1000.0 for $i1 in 1:2})
(22)      [ALGB] (2) flow Real[2] pMeter.terminal_p.i (nominal = {1.0 for $i1 in 1:2})
(23)      [ALGB] (2) Real[2] load.terminal.v (start = load.v_start, nominal = {1000.0 for $i1 in 1:2})
(24)      [ALGB] (2) Real[2] load.terminal.theta


System Equations (25/41)
**************************
(1)       [ARRY] (2) pMeter.terminal_p.theta = pMeter.terminal_n.theta ($RES_SIM_9)
(2)       [ARRY] (2) pMeter.v = pMeter.terminal_p.v - pMeter.terminal_n.v ($RES_SIM_8)
(3)       [ARRY] (2) pMeter.i = pMeter.terminal_p.i ($RES_SIM_7)
(4)       [ARRY] (2) {load.terminal.v * load.terminal.i, -{-load.terminal.v[2], load.terminal.v[1]} * load.terminal.i} = {load.P, load.P * tan(load.phi)} ($RES_SIM_6)
(5)       [ARRY] (2) {0.0 for $i1 in 1:2} = pMeter.terminal_p.i + pMeter.terminal_n.i ($RES_SIM_10)
(6)       [ARRY] (2) pMeter.v = {0.0 for $i1 in 1:2} ($RES_SIM_11)
(7)       [SCAL] (1) pMeter.P = pMeter.terminal_p.v * pMeter.i ($RES_SIM_12)
(8)       [SCAL] (1) source.terminal.theta[1] = system.thetaRel ($RES_SIM_13)
(9)       [SCAL] (1) system.thetaRef = 314.1592653589793 * time ($RES_SIM_2)
(10)      [ARRY] (2) pMeter.terminal_n.v = load.terminal.v ($RES_SIM_20)
(11)      [SCAL] (1) source.terminal.theta[2] = system.thetaRef ($RES_SIM_14)
(12)      [ARRY] (2) pMeter.terminal_n.theta = load.terminal.theta ($RES_SIM_21)
(13)      [ARRY] (2) source.terminal.v = {source.V * cos(source.terminal.theta[1]), source.V * sin(source.terminal.theta[1])} ($RES_SIM_15)
(14)      [FOR-] (2) ($RES_SIM_22)
(14)      [----] for $i1 in 1:2 loop
(14)      [----]   [SCAL] (1) source.terminal.i[$i1] + pMeter.terminal_p.i[$i1] = 0.0 ($RES_SIM_23)
(14)      [----] end for;
(15)      [ARRY] (2) source.terminal.v = pMeter.terminal_p.v ($RES_SIM_24)
(16)      [FOR-] (2) ($RES_SIM_18)
(16)      [----] for $i1 in 1:2 loop
(16)      [----]   [SCAL] (1) pMeter.terminal_n.i[$i1] + load.terminal.i[$i1] = 0.0 ($RES_SIM_19)
(16)      [----] end for;
(17)      [SCAL] (1) pMeter.I = sqrt(pMeter.i * pMeter.i) ($RES_BND_30)
(18)      [ARRY] (2) source.terminal.theta = pMeter.terminal_p.theta ($RES_SIM_25)
(19)      [SCAL] (1) pMeter.phi = atan2(pMeter.v[2], pMeter.v[1]) - atan2(pMeter.i[2], pMeter.i[1]) ($RES_BND_31)
(20)      [ARRY] (2) load.p = {load.terminal.v * load.terminal.i, -{-load.terminal.v[2], load.terminal.v[1]} * load.terminal.i} ($RES_BND_32)
(21)      [ARRY] (2) source.p = {source.terminal.v * source.terminal.i, -{-source.terminal.v[2], source.terminal.v[1]} * source.terminal.i} ($RES_BND_26)
(22)      [SCAL] (1) system.thetaRel = system.thetaRef - system.thetaRef ($RES_BND_33)
(23)      [SCAL] (1) source.phi = atan2(source.terminal.v[2], source.terminal.v[1]) - atan2((-source.terminal.i)[2], (-source.terminal.i)[1]) ($RES_BND_27)
(24)      [ARRY] (2) pMeter.p = {pMeter.v * pMeter.i, -{-pMeter.v[2], pMeter.v[1]} * pMeter.i} ($RES_BND_28)
(25)      [SCAL] (1) pMeter.V = sqrt(pMeter.v * pMeter.v) ($RES_BND_29)