search

nmakel / solaredge_meterproxy

Modbus proxy for SolarEdge inverters and unsupported kWh meters
MIT License
32 stars 20 forks source link

"DEBUG: Frame check failed, ignoring!!" on SE6K and SDM630 V2 #16

Open SebuZet opened 2 years ago

SebuZet commented 2 years ago

Hi, Im trying to run tool but I've got error message likethis:

2022-02-24 07:11:38 DEBUG: setValues[3] 1101:82
2022-02-24 07:11:38 DEBUG: Frame check failed, ignoring!!
2022-02-24 07:11:38 DEBUG: Resetting frame - Current Frame in buffer - 0x80 0x0 0xf8 0x0 0x80 0x0 0x0 0xf8 0x0 0xf8 0x78 0x0 0x0 0x0 0xf8 0x78 0xf 0x80 0x0 0xf8 0x0 0x80 0x0 0x0 0xf8 0x0 0xf8 0x78 0x0 0x0 0x0 0xf8 0x78 0xf

My config (kemp-rtu.conf):

[server]
device = /dev/ttySC0
baud = 38400
parity = E
#timeout = 1
log_level = INFO
meters = sdm

[sdm]
baud = 38400
device = /dev/ttySC1
dst_address = 2
src_address = 5
type = sdm630
serial_number = 123321
ct_current = 5
ct_inverted = 0
phase_offset = 120
refresh_rate = 5

I'm using semp-rtu.py

I can confirm that connection with SDM meter is working correctly. What am I doing wrong?

SebuZet commented 2 years ago

Can anyone help mee with this? I've tried to set framer = rtu in [server] section but it didn't help. It look like problems with SE connection

SebuZet commented 2 years ago

Ok, I've managed connection problems. It looks like my SE doesn't change baud rate as expected. Now it is (almost) working. SE shows that meter status is OK. When I run exmaple.py (from solaredge_modbus module) I've got such output:

{
    "c_manufacturer": "SolarEdge",
    "c_model": "SE6K-RW0TEBNN4",
    "c_version": "0004.0014.0228",
    "c_serialnumber": "XX",
    "c_deviceaddress": 1,
    "c_sunspec_did": 103,
    "current": 0,
    "l1_current": 0,
    "l2_current": 0,
    "l3_current": 0,
    "current_scale": -2,
    "l1_voltage": 4150,
    "l2_voltage": 4179,
    "l3_voltage": 4162,
    "l1n_voltage": 2396,
    "l2n_voltage": 2413,
    "l3n_voltage": 2402,
    "voltage_scale": -1,
    "power_ac": 0,
    "power_ac_scale": 0,
    "frequency": 5001,
    "frequency_scale": -2,
    "power_apparent": 0,
    "power_apparent_scale": 0,
    "power_reactive": 0,
    "power_reactive_scale": 0,
    "power_factor": 0,
    "power_factor_scale": 0,
    "energy_total": 0,
    "energy_total_scale": 0,
    "current_dc": 0,
    "current_dc_scale": 0,
    "voltage_dc": 3,
    "voltage_dc_scale": -1,
    "power_dc": 0,
    "power_dc_scale": 0,
    "temperature": 2040,
    "temperature_scale": -2,
    "status": 2,
    "vendor_status": 0,
    "rrcr_state": 0,
    "active_power_limit": 100,
    "cosphi": 0,
    "meters": {
        "Meter1": {
            "c_manufacturer": "SolarEdge",
            "c_model": "SE-RGMTR-1D-240C-A",
            "c_option": "Export+Import",
            "c_version": "0",
            "c_serialnumber": "0",
            "c_deviceaddress": 2,
            "c_sunspec_did": 201,
            "current": -32768,
            "l1_current": -32768,
            "l2_current": -32768,
            "l3_current": -32768,
            "current_scale": -32768,
            "voltage_ln": -32768,
            "l1n_voltage": -32768,
            "l2n_voltage": -32768,
            "l3n_voltage": -32768,
            "voltage_ll": -32768,
            "l12_voltage": -32768,
            "l23_voltage": -32768,
            "l31_voltage": -32768,
            "voltage_scale": -32768,
            "frequency": -32768,
            "frequency_scale": -32768,
            "power": -32768,
            "l1_power": -32768,
            "l2_power": -32768,
            "l3_power": -32768,
            "power_scale": -32768,
            "power_apparent": -32768,
            "l1_power_apparent": -32768,
            "l2_power_apparent": -32768,
            "l3_power_apparent": -32768,
            "power_apparent_scale": -32768,
            "power_reactive": -32768,
            "l1_power_reactive": -32768,
            "l2_power_reactive": -32768,
            "l3_power_reactive": -32768,
            "power_reactive_scale": -32768,
            "power_factor": -32768,
            "l1_power_factor": -32768,
            "l2_power_factor": -32768,
            "l3_power_factor": -32768,
            "power_factor_scale": -32768,
            "export_energy_active": 0,
            "l1_export_energy_active": 0,
            "l2_export_energy_active": 0,
            "l3_export_energy_active": 0,
            "import_energy_active": 0,
            "l1_import_energy_active": 0,
            "l2_import_energy_active": 0,
            "l3_import_energy_active": 0,
            "energy_active_scale": 0,
            "export_energy_apparent": 0,
            "l1_export_energy_apparent": 0,
            "l2_export_energy_apparent": 0,
            "l3_export_energy_apparent": 0,
            "import_energy_apparent": 0,
            "l1_import_energy_apparent": 0,
            "l2_import_energy_apparent": 0,
            "l3_import_energy_apparent": 0,
            "energy_apparent_scale": -32768,
            "import_energy_reactive_q1": 0,
            "l1_import_energy_reactive_q1": 0,
            "l2_import_energy_reactive_q1": 0,
            "l3_import_energy_reactive_q1": 0,
            "import_energy_reactive_q2": 0,
            "l1_import_energy_reactive_q2": 0,
            "l2_import_energy_reactive_q2": 0,
            "l3_import_energy_reactive_q2": 0,
            "export_energy_reactive_q3": 0,
            "l1_export_energy_reactive_q3": 0,
            "l2_export_energy_reactive_q3": 0,
            "l3_export_energy_reactive_q3": 0,
            "export_energy_reactive_q4": 0,
            "l1_export_energy_reactive_q4": 0,
            "l2_export_energy_reactive_q4": 0,
            "l3_export_energy_reactive_q4": 0,
            "energy_reactive_scale": -32768
        }
    },
    "batteries": {}
}

Data from meter look very strange. What should check?

nmakel commented 2 years ago

Ok, I've managed connection problems. It looks like my SE doesn't change baud rate as expected.

Correct. When the SE is a master polling meters it will switch back to 9600 baud, irrespective of the baudrate you set when the SE is in Sunspec mode.

Now it is (almost) working. SE shows that meter status is OK. When I run exmaple.py (from solaredge_modbus module) I've got such output:

It would be useful to have the debug output of semp-rtu.py when the SE is trying to poll your meter. You can enable this much more verbose output by adding log_level = DEBUG to your [server] configuration. Please post a few seconds of this output so I can have a look at the register that the SE is asking for.

Also, could you note the settings with which you have configured the meter on the SE?

SebuZet commented 2 years ago

Thank you for your response.

I've attached log file. Same part of logs are below:

2022-02-28 17:16:27 DEBUG: setValues[3] 1601:23
2022-02-28 17:16:27 DEBUG: [0, 2, 4, 0, 0, 5]
2022-02-28 17:16:27 DEBUG: setValues[3] 1651:6
2022-02-28 17:16:27 DEBUG: [57785, 1, 0, 0, 0, 0, 202, 31, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]
2022-02-28 17:16:27 DEBUG: setValues[3] 1701:23
2022-02-28 17:16:27 INFO: Created <Thread(t_update_2, initial)>: sdm sdm630 SDM630(/dev/ttySC1, connectionType.RTU: stopbits=1, parity=N, baud=38400, timeout=1, retries=3, unit=0x5)
2022-02-28 17:16:28 DEBUG: device: SDM630(/dev/ttySC1, connectionType.RTU: stopbits=1, parity=N, baud=38400, timeout=1, retries=3, unit=0x5)
2022-02-28 17:16:28 INFO: Starting <Thread(t_update_2, started 3053687872)>
2022-02-28 17:16:28 DEBUG: Client Connected [/dev/ttySC0:/dev/ttySC0]
2022-02-28 17:16:28 DEBUG: Started thread to serve client
2022-02-28 17:16:28 DEBUG: Current transaction state - IDLE
2022-02-28 17:16:28 DEBUG: Running transaction 1
2022-02-28 17:16:28 DEBUG: SEND: 0x5 0x4 0x0 0x0 0x0 0x50 0xf1 0xb2
2022-02-28 17:16:28 DEBUG: New Transaction state 'SENDING'
2022-02-28 17:16:28 DEBUG: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-02-28 17:16:28 DEBUG: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-02-28 17:16:28 DEBUG: RECV: 0x5 0x4 0xa0 0x43 0x6f 0x30 0xe8 0x43 0x6f 0xc2 0x3a 0x43 0x6f 0x3f 0x73 0x3f 0xd5 0x54 0x6f 0x3f 0x1 0x39 0x2d 0x3f 0xef 0x6f 0xd7 0x43 0x65 0xf6 0x56 0x42 0x8e 0x68 0x69 0x43 0x8e 0x33 0xf7 0x43 0xc7 0x52 0x94 0x42 0xf2 0xc 0xd7 0x43 0xdf 0xc4 0xc1 0xc3 0x94 0x9f 0x2c 0xc2 0x93 0x4e 0x2a 0xc3 0x8e 0x39 0x82 0x3f 0x13 0xad 0x11 0x3f 0x16 0x9d 0x78 0x3f 0x22 0xaf 0x8f 0xc2 0x5b 0x18 0xa6 0xc2 0x57 0xdb 0xcf 0xc2 0x4a 0x53 0x90 0x43 0x6f 0x62 0xf3 0x0 0x0 0x0 0x0 0x3f 0xad 0x7 0x7 0x40 0x81 0xc5 0x45 0x0 0x0 0x0 0x0 0x44 0x12 0xf3 0xcd 0x0 0x0 0x0 0x0 0x44 0x72 0x96 0x9c 0x0 0x0 0x0 0x0 0xc4 0x24 0x19 0xb0 0x3f 0x1b 0x13 0xa4 0x0 0x0 0x0 0x0 0xc2 0x52 0xe0 0xff 0x0 0x0 0x0 0x0 0x42 0x47 0x8b 0x5b 0x44 0xa3 0x76 0x14 0x0 0x0 0x0 0x0 0x3c 0xe5 0x60 0x41 0x44 0x91 0x6d 0x91 0x8f 0x57
2022-02-28 17:16:28 DEBUG: Getting Frame - 0x4 0xa0 0x43 0x6f 0x30 0xe8 0x43 0x6f 0xc2 0x3a 0x43 0x6f 0x3f 0x73 0x3f 0xd5 0x54 0x6f 0x3f 0x1 0x39 0x2d 0x3f 0xef 0x6f 0xd7 0x43 0x65 0xf6 0x56 0x42 0x8e 0x68 0x69 0x43 0x8e 0x33 0xf7 0x43 0xc7 0x52 0x94 0x42 0xf2 0xc 0xd7 0x43 0xdf 0xc4 0xc1 0xc3 0x94 0x9f 0x2c 0xc2 0x93 0x4e 0x2a 0xc3 0x8e 0x39 0x82 0x3f 0x13 0xad 0x11 0x3f 0x16 0x9d 0x78 0x3f 0x22 0xaf 0x8f 0xc2 0x5b 0x18 0xa6 0xc2 0x57 0xdb 0xcf 0xc2 0x4a 0x53 0x90 0x43 0x6f 0x62 0xf3 0x0 0x0 0x0 0x0 0x3f 0xad 0x7 0x7 0x40 0x81 0xc5 0x45 0x0 0x0 0x0 0x0 0x44 0x12 0xf3 0xcd 0x0 0x0 0x0 0x0 0x44 0x72 0x96 0x9c 0x0 0x0 0x0 0x0 0xc4 0x24 0x19 0xb0 0x3f 0x1b 0x13 0xa4 0x0 0x0 0x0 0x0 0xc2 0x52 0xe0 0xff 0x0 0x0 0x0 0x0 0x42 0x47 0x8b 0x5b 0x44 0xa3 0x76 0x14 0x0 0x0 0x0 0x0 0x3c 0xe5 0x60 0x41 0x44 0x91 0x6d 0x91
2022-02-28 17:16:28 DEBUG: Factory Response[ReadInputRegistersResponse: 4]
2022-02-28 17:16:28 DEBUG: Frame advanced, resetting header!!
2022-02-28 17:16:28 DEBUG: Adding transaction 5
2022-02-28 17:16:28 DEBUG: Getting transaction 5
2022-02-28 17:16:28 DEBUG: Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2022-02-28 17:16:28 DEBUG: [17263, 12520, 17263, 49722, 17263, 16243, 16341, 21615, 16129, 14637, 16367, 28631, 17253, 63062, 17038, 26729, 17294, 13303, 17351, 21140, 17138, 3287, 17375, 50369, 50068, 40748, 49811, 20010, 50062, 14722, 16147, 44305, 16150, 40312, 16162, 44943, 49755, 6310, 49751, 56271, 49738, 21392, 17263, 25331, 0, 0, 16301, 1799, 16513, 50501, 0, 0, 17426, 62413, 0, 0, 17522, 38556, 0, 0, 50212, 6576, 16155, 5028, 0, 0, 49746, 57599, 0, 0, 16967, 35675, 17571, 30228, 0, 0, 15589, 24641, 17553, 28049]
2022-02-28 17:16:28 DEBUG: [b'Co', b'0\xe8']
2022-02-28 17:16:28 DEBUG: [b'Co', b'\xc2:']
2022-02-28 17:16:28 DEBUG: [b'Co', b'?s']
2022-02-28 17:16:28 DEBUG: [b'?\xd5', b'To']
2022-02-28 17:16:28 DEBUG: [b'?\x01', b'9-']
2022-02-28 17:16:28 DEBUG: [b'?\xef', b'o\xd7']
2022-02-28 17:16:28 DEBUG: [b'Ce', b'\xf6V']
2022-02-28 17:16:28 DEBUG: [b'B\x8e', b'hi']
2022-02-28 17:16:28 DEBUG: [b'C\x8e', b'3\xf7']
2022-02-28 17:16:28 DEBUG: [b'C\xc7', b'R\x94']
2022-02-28 17:16:28 DEBUG: [b'B\xf2', b'\x0c\xd7']
2022-02-28 17:16:28 DEBUG: [b'C\xdf', b'\xc4\xc1']
2022-02-28 17:16:28 DEBUG: [b'\xc3\x94', b'\x9f,']
2022-02-28 17:16:28 DEBUG: [b'\xc2\x93', b'N*']
2022-02-28 17:16:28 DEBUG: [b'\xc3\x8e', b'9\x82']
2022-02-28 17:16:28 DEBUG: [b'?\x13', b'\xad\x11']
2022-02-28 17:16:28 DEBUG: [b'?\x16', b'\x9dx']
2022-02-28 17:16:28 DEBUG: [b'?"', b'\xaf\x8f']
2022-02-28 17:16:28 DEBUG: [b'\xc2[', b'\x18\xa6']
2022-02-28 17:16:28 DEBUG: [b'\xc2W', b'\xdb\xcf']
2022-02-28 17:16:28 DEBUG: [b'\xc2J', b'S\x90']
2022-02-28 17:16:28 DEBUG: [b'Co', b'b\xf3']
2022-02-28 17:16:28 DEBUG: [b'?\xad', b'\x07\x07']
2022-02-28 17:16:28 DEBUG: [b'@\x81', b'\xc5E']
2022-02-28 17:16:28 DEBUG: [b'D\x12', b'\xf3\xcd']
2022-02-28 17:16:28 DEBUG: [b'Dr', b'\x96\x9c']
2022-02-28 17:16:28 DEBUG: [b'\xc4$', b'\x19\xb0']
2022-02-28 17:16:28 DEBUG: [b'?\x1b', b'\x13\xa4']
2022-02-28 17:16:28 DEBUG: [b'\xc2R', b'\xe0\xff']
2022-02-28 17:16:28 DEBUG: [b'BG', b'\x8b[']
2022-02-28 17:16:28 DEBUG: [b'D\xa3', b'v\x14']
2022-02-28 17:16:28 DEBUG: [b'\x00\x00', b'\x00\x00']
2022-02-28 17:16:28 DEBUG: [b'<\xe5', b'`A']
2022-02-28 17:16:28 DEBUG: [b'D\x91', b'm\x91']
2022-02-28 17:16:28 DEBUG: Current transaction state - TRANSACTION_COMPLETE
2022-02-28 17:16:28 DEBUG: Running transaction 2
2022-02-28 17:16:28 DEBUG: SEND: 0x5 0x4 0x0 0x50 0x0 0x1c 0xf0 0x56
2022-02-28 17:16:28 DEBUG: Changing state to IDLE - Last Frame End - 1646068588.35602, Current Time stamp - 1646068588.455059
2022-02-28 17:16:28 DEBUG: New Transaction state 'SENDING'
2022-02-28 17:16:28 DEBUG: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-02-28 17:16:28 DEBUG: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-02-28 17:16:28 DEBUG: RECV: 0x5 0x4 0x38 0x44 0xda 0xca 0xb9 0x46 0xc 0xbe 0xdc 0x44 0x23 0x92 0xbf 0x45 0x76 0x58 0xef 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x44 0x7a 0x4 0xd 0x45 0x83 0x50 0xc7 0x3f 0xbd 0x8a 0x61 0x41 0x27 0xe1 0x10 0x14 0x2b
2022-02-28 17:16:28 DEBUG: Getting Frame - 0x4 0x38 0x44 0xda 0xca 0xb9 0x46 0xc 0xbe 0xdc 0x44 0x23 0x92 0xbf 0x45 0x76 0x58 0xef 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x44 0x7a 0x4 0xd 0x45 0x83 0x50 0xc7 0x3f 0xbd 0x8a 0x61 0x41 0x27 0xe1 0x10
2022-02-28 17:16:28 DEBUG: Factory Response[ReadInputRegistersResponse: 4]
2022-02-28 17:16:28 DEBUG: Frame advanced, resetting header!!
2022-02-28 17:16:28 DEBUG: Adding transaction 5
2022-02-28 17:16:28 DEBUG: Getting transaction 5
2022-02-28 17:16:28 DEBUG: Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2022-02-28 17:16:28 DEBUG: [17626, 51897, 17932, 48860, 17443, 37567, 17782, 22767, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 17530, 1037, 17795, 20679, 16317, 35425, 16679, 57616]
2022-02-28 17:16:28 DEBUG: [b'D\xda', b'\xca\xb9']
2022-02-28 17:16:28 DEBUG: [b'F\x0c', b'\xbe\xdc']
2022-02-28 17:16:28 DEBUG: [b'D#', b'\x92\xbf']
2022-02-28 17:16:28 DEBUG: [b'Ev', b'X\xef']
2022-02-28 17:16:28 DEBUG: [b'\x00\x00', b'\x00\x00']
2022-02-28 17:16:28 DEBUG: [b'\x00\x00', b'\x00\x00']
2022-02-28 17:16:28 DEBUG: [b'\x00\x00', b'\x00\x00']
2022-02-28 17:16:28 DEBUG: [b'\x00\x00', b'\x00\x00']
2022-02-28 17:16:28 DEBUG: [b'Dz', b'\x04\r']
2022-02-28 17:16:28 DEBUG: [b'E\x83', b'P\xc7']
2022-02-28 17:16:28 DEBUG: [b'?\xbd', b'\x8aa']
2022-02-28 17:16:28 DEBUG: [b"A'", b'\xe1\x10']
2022-02-28 17:16:28 DEBUG: Current transaction state - TRANSACTION_COMPLETE
2022-02-28 17:16:28 DEBUG: Running transaction 3
2022-02-28 17:16:28 DEBUG: SEND: 0x5 0x4 0x0 0xc8 0x0 0x46 0xf1 0x82
2022-02-28 17:16:28 DEBUG: Changing state to IDLE - Last Frame End - 1646068588.481435, Current Time stamp - 1646068588.518707
2022-02-28 17:16:28 DEBUG: New Transaction state 'SENDING'
2022-02-28 17:16:28 DEBUG: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-02-28 17:16:28 DEBUG: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-02-28 17:16:28 DEBUG: RECV: 0x5 0x4 0x8c 0x43 0xcf 0x61 0x8 0x43 0xcf 0x66 0x8a 0x43 0xcf 0x29 0x35 0x43 0xcf 0x50 0x43 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x3f 0xa4 0x72 0xf1 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x40 0xea 0xc8 0xfc 0x40 0xeb 0xd4 0x51 0x40 0xe9 0xc6 0xd6 0x42 0xd 0xf2 0x87 0x42 0x79 0xcf 0x98 0x42 0x44 0x3a 0x7f 0x0 0x0 0x0 0x0 0x40 0xea 0xf0 0xc1 0x42 0x43 0xfe 0xdf 0x0 0x0 0x0 0x0 0xc2 0x52 0xf5 0x4b 0x0 0x0 0x0 0x0 0x3f 0xf6 0x3 0xd4 0x3f 0xe 0x67 0x9f 0x3f 0xda 0xca 0x4 0x41 0x34 0x4d 0x37 0x40 0xc4 0xf4 0x5 0x41 0x21 0xd1 0x69 0xef 0x3a
2022-02-28 17:16:28 DEBUG: Getting Frame - 0x4 0x8c 0x43 0xcf 0x61 0x8 0x43 0xcf 0x66 0x8a 0x43 0xcf 0x29 0x35 0x43 0xcf 0x50 0x43 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x3f 0xa4 0x72 0xf1 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x40 0xea 0xc8 0xfc 0x40 0xeb 0xd4 0x51 0x40 0xe9 0xc6 0xd6 0x42 0xd 0xf2 0x87 0x42 0x79 0xcf 0x98 0x42 0x44 0x3a 0x7f 0x0 0x0 0x0 0x0 0x40 0xea 0xf0 0xc1 0x42 0x43 0xfe 0xdf 0x0 0x0 0x0 0x0 0xc2 0x52 0xf5 0x4b 0x0 0x0 0x0 0x0 0x3f 0xf6 0x3 0xd4 0x3f 0xe 0x67 0x9f 0x3f 0xda 0xca 0x4 0x41 0x34 0x4d 0x37 0x40 0xc4 0xf4 0x5 0x41 0x21 0xd1 0x69
2022-02-28 17:16:28 DEBUG: Factory Response[ReadInputRegistersResponse: 4]
2022-02-28 17:16:28 DEBUG: Frame advanced, resetting header!!
2022-02-28 17:16:28 DEBUG: Adding transaction 5
2022-02-28 17:16:28 DEBUG: Getting transaction 5
2022-02-28 17:16:28 DEBUG: Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2022-02-28 17:16:28 DEBUG: [17359, 24840, 17359, 26250, 17359, 10549, 17359, 20547, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 16292, 29425, 0, 0, 0, 0, 0, 0, 0, 0, 16618, 51452, 16619, 54353, 16617, 50902, 16909, 62087, 17017, 53144, 16964, 14975, 0, 0, 16618, 61633, 16963, 65247, 0, 0, 49746, 62795, 0, 0, 16374, 980, 16142, 26527, 16346, 51716, 16692, 19767, 16580, 62469, 16673, 53609]
2022-02-28 17:16:28 DEBUG: [b'C\xcf', b'a\x08']
2022-02-28 17:16:28 DEBUG: [b'C\xcf', b'f\x8a']
2022-02-28 17:16:28 DEBUG: [b'C\xcf', b')5']
2022-02-28 17:16:28 DEBUG: [b'C\xcf', b'PC']
2022-02-28 17:16:28 DEBUG: [b'?\xa4', b'r\xf1']
2022-02-28 17:16:28 DEBUG: [b'@\xea', b'\xc8\xfc']
2022-02-28 17:16:28 DEBUG: [b'@\xeb', b'\xd4Q']
2022-02-28 17:16:28 DEBUG: [b'@\xe9', b'\xc6\xd6']
2022-02-28 17:16:28 DEBUG: [b'B\r', b'\xf2\x87']
2022-02-28 17:16:28 DEBUG: [b'By', b'\xcf\x98']
2022-02-28 17:16:28 DEBUG: [b'BD', b':\x7f']
2022-02-28 17:16:28 DEBUG: [b'@\xea', b'\xf0\xc1']
2022-02-28 17:16:28 DEBUG: [b'BC', b'\xfe\xdf']
2022-02-28 17:16:28 DEBUG: [b'\xc2R', b'\xf5K']
2022-02-28 17:16:28 DEBUG: [b'?\xf6', b'\x03\xd4']
2022-02-28 17:16:28 DEBUG: [b'?\x0e', b'g\x9f']
2022-02-28 17:16:28 DEBUG: [b'?\xda', b'\xca\x04']
2022-02-28 17:16:28 DEBUG: [b'A4', b'M7']
2022-02-28 17:16:28 DEBUG: [b'@\xc4', b'\xf4\x05']
2022-02-28 17:16:28 DEBUG: [b'A!', b'\xd1i']
2022-02-28 17:16:28 DEBUG: Current transaction state - TRANSACTION_COMPLETE
2022-02-28 17:16:28 DEBUG: Running transaction 4
2022-02-28 17:16:28 DEBUG: SEND: 0x5 0x4 0x1 0x4e 0x0 0x30 0x90 0x71
2022-02-28 17:16:28 DEBUG: Changing state to IDLE - Last Frame End - 1646068588.658423, Current Time stamp - 1646068588.71052
2022-02-28 17:16:28 DEBUG: New Transaction state 'SENDING'
2022-02-28 17:16:28 DEBUG: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-02-28 17:16:28 DEBUG: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-02-28 17:16:28 DEBUG: RECV: 0x5 0x4 0x60 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x44 0xa3 0x76 0x14 0x44 0x91 0x6e 0x76 0x44 0x9 0x79 0xba 0x43 0xd2 0x1a 0x3d 0x43 0xa8 0xca 0x9f 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x44 0x9 0x79 0xba 0x43 0xd2 0x1a 0x3d 0x43 0xa8 0xca 0x9f 0x3c 0x34 0x39 0x58 0x3c 0x8b 0x43 0x95 0x0 0x0 0x0 0x0 0x44 0x3 0x77 0x2b 0x43 0x10 0xb8 0xd4 0x43 0xf6 0x6b 0x85 0x44 0x3 0x77 0xdf 0x43 0x10 0xbd 0x2f 0x43 0xf6 0x6b 0x85 0x52 0xce
2022-02-28 17:16:28 DEBUG: Getting Frame - 0x4 0x60 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x44 0xa3 0x76 0x14 0x44 0x91 0x6e 0x76 0x44 0x9 0x79 0xba 0x43 0xd2 0x1a 0x3d 0x43 0xa8 0xca 0x9f 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x44 0x9 0x79 0xba 0x43 0xd2 0x1a 0x3d 0x43 0xa8 0xca 0x9f 0x3c 0x34 0x39 0x58 0x3c 0x8b 0x43 0x95 0x0 0x0 0x0 0x0 0x44 0x3 0x77 0x2b 0x43 0x10 0xb8 0xd4 0x43 0xf6 0x6b 0x85 0x44 0x3 0x77 0xdf 0x43 0x10 0xbd 0x2f 0x43 0xf6 0x6b 0x85
2022-02-28 17:16:28 DEBUG: Factory Response[ReadInputRegistersResponse: 4]
2022-02-28 17:16:28 DEBUG: Frame advanced, resetting header!!
2022-02-28 17:16:28 DEBUG: Adding transaction 5
2022-02-28 17:16:28 DEBUG: Getting transaction 5
2022-02-28 17:16:28 DEBUG: Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2022-02-28 17:16:28 DEBUG: [0, 0, 0, 0, 0, 0, 0, 0, 17571, 30228, 17553, 28278, 17417, 31162, 17362, 6717, 17320, 51871, 0, 0, 0, 0, 0, 0, 17417, 31162, 17362, 6717, 17320, 51871, 15412, 14680, 15499, 17301, 0, 0, 17411, 30507, 17168, 47316, 17398, 27525, 17411, 30687, 17168, 48431, 17398, 27525]
2022-02-28 17:16:28 DEBUG: [b'\x00\x00', b'\x00\x00']
2022-02-28 17:16:28 DEBUG: [b'\x00\x00', b'\x00\x00']
2022-02-28 17:16:28 DEBUG: [b'\x00\x00', b'\x00\x00']
2022-02-28 17:16:28 DEBUG: [b'\x00\x00', b'\x00\x00']
2022-02-28 17:16:28 DEBUG: [b'D\xa3', b'v\x14']
2022-02-28 17:16:28 DEBUG: [b'D\x91', b'nv']
2022-02-28 17:16:28 DEBUG: [b'D\t', b'y\xba']
2022-02-28 17:16:28 DEBUG: [b'C\xd2', b'\x1a=']
2022-02-28 17:16:28 DEBUG: [b'C\xa8', b'\xca\x9f']
2022-02-28 17:16:28 DEBUG: [b'\x00\x00', b'\x00\x00']
2022-02-28 17:16:28 DEBUG: [b'\x00\x00', b'\x00\x00']
2022-02-28 17:16:28 DEBUG: [b'\x00\x00', b'\x00\x00']
2022-02-28 17:16:28 DEBUG: [b'D\t', b'y\xba']
2022-02-28 17:16:28 DEBUG: [b'C\xd2', b'\x1a=']
2022-02-28 17:16:28 DEBUG: [b'C\xa8', b'\xca\x9f']
2022-02-28 17:16:28 DEBUG: [b'<4', b'9X']
2022-02-28 17:16:28 DEBUG: [b'<\x8b', b'C\x95']
2022-02-28 17:16:28 DEBUG: [b'\x00\x00', b'\x00\x00']
2022-02-28 17:16:28 DEBUG: [b'D\x03', b'w+']
2022-02-28 17:16:28 DEBUG: [b'C\x10', b'\xb8\xd4']
2022-02-28 17:16:28 DEBUG: [b'C\xf6', b'k\x85']
2022-02-28 17:16:28 DEBUG: [b'D\x03', b'w\xdf']
2022-02-28 17:16:28 DEBUG: [b'C\x10', b'\xbd/']
2022-02-28 17:16:28 DEBUG: [b'C\xf6', b'k\x85']
2022-02-28 17:16:28 DEBUG: values: {'l1_voltage': 239.1910400390625, 'l2_voltage': 239.75869750976562, 'l3_voltage': 239.2478485107422, 'l1_current': 1.6666392087936401, 'l2_current': 0.5047786831855774, 'l3_current': 1.8706005811691284, 'l1_power_active': 229.96224975585938, 'l2_power_active': 71.20392608642578, 'l3_power_active': 284.4059753417969, 'l1_power_apparent': 398.6451416015625, 'l2_power_apparent': 121.02507781982422, 'l3_power_apparent': 447.5371398925781, 'l1_power_reactive': -297.2435302734375, 'l2_power_reactive': -73.65266418457031, 'l3_power_reactive': -284.44927978515625, 'l1_power_factor': 0.5768595337867737, 'l2_power_factor': 0.5883402824401855, 'l3_power_factor': 0.6354913115501404, 'l1_phase_angle': -54.774070739746094, 'l2_phase_angle': -53.964656829833984, 'l3_phase_angle': -50.58160400390625, 'voltage_ln': 239.3865203857422, 'current_ln': 1.3517769575119019, 'total_line_current': 4.055330753326416, 'total_power_active': 587.8093872070312, 'total_power_apparent': 970.353271484375, 'total_power_reactive': -656.4013671875, 'total_power_factor': 0.6057684421539307, 'total_phase_angle': -52.719722747802734, 'frequency': 49.88608932495117, 'import_energy_active': 1307.68994140625, 'export_energy_active': 0.0, 'import_energy_reactive': 0.0279999990016222, 'export_energy_reactive': 1163.4239501953125, 'total_energy_apparent': 1750.3350830078125, 'total_current': 9007.71484375, 'total_import_demand_power_active': 654.2929077148438, 'maximum_import_demand_power_apparent': 3941.558349609375, 'import_demand_power_active': 0.0, 'maximum_import_demand_power_active': 0.0, 'export_demand_power_active': 0.0, 'maximum_export_demand_power_active': 0.0, 'total_demand_power_apparent': 1000.0632934570312, 'maximum_demand_power_apparent': 4202.09716796875, 'neutral_demand_current': 1.4807854890823364, 'maximum_neutral_demand_current': 10.492446899414062, 'l12_voltage': 414.758056640625, 'l23_voltage': 414.80108642578125, 'l31_voltage': 414.3219299316406, 'voltage_ll': 414.6270446777344, 'neutral_current': 1.2847577333450317, 'l1n_voltage_thd': 7.337034225463867, 'l2n_voltage_thd': 7.3696675300598145, 'l3n_voltage_thd': 7.3055219650268555, 'l1_current_thd': 35.48684310913086, 'l2_current_thd': 62.452728271484375, 'l3_current_thd': 49.057125091552734, 'voltage_ln_thd': 7.341888904571533, 'current_thd': 48.998897552490234, 'total_pf': -52.73954391479492, 'l1_demand_current': 1.9219918251037598, 'l2_demand_current': 0.5562686324119568, 'l3_demand_current': 1.7092900276184082, 'maximum_l1_demand_current': 11.268851280212402, 'maximum_l2_demand_current': 6.154787540435791, 'maximum_l3_demand_current': 10.113625526428223, 'l12_voltage_thd': 0.0, 'l23_voltage_thd': 0.0, 'l31_voltage_thd': 0.0, 'voltage_ll_thd': 0.0, 'total_energy_active': 1307.68994140625, 'total_energy_reactive': 1163.451904296875, 'l1_demand_energy_active': 549.9019775390625, 'l2_demand_energy_active': 420.2049865722656, 'l3_demand_energy_active': 337.5829772949219, 'l1_import_energy_active': 0.0, 'l2_import_energy_active': 0.0, 'l3_import_energy_active': 0.0, 'l1_energy_active': 549.9019775390625, 'l2_energy_active': 420.2049865722656, 'l3_energy_active': 337.5829772949219, 'l1_demand_energy_reactive': 0.010999999940395355, 'l2_demand_energy_reactive': 0.016999999061226845, 'l3_demand_energy_reactive': 0.0, 'l1_import_energy_reactive': 525.8619995117188, 'l2_import_energy_reactive': 144.72198486328125, 'l3_import_energy_reactive': 492.8399963378906, 'l1_energy_reactive': 525.8729858398438, 'l2_energy_reactive': 144.73899841308594, 'l3_energy_reactive': 492.8399963378906}
2022-02-28 17:16:28 DEBUG: [30228, 17571, 30228, 17571, 30228, 17571, 30228, 17571, 62413, 17426, 63062, 17253, 26729, 17038, 13303, 17294, 25331, 17263, 12520, 17263, 49722, 17263, 16243, 17263, 20547, 17359, 24840, 17359, 26250, 17359, 10549, 17359, 35675, 16967]
2022-02-28 17:16:28 DEBUG: setValues[3] 1001:34
2022-02-28 17:16:28 DEBUG: [30228, 17571, 0, 0, 0, 0, 30228, 17571, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 28278, 17553, 28278, 17553, 0, 0, 0, 0, 51897, 17626, 51897, 17626, 0, 0, 0, 0, 5028, 16155, 44305, 16147, 40312, 16150, 44943, 16162, 6576, 50212, 40748, 50068, 20010, 49811, 14722, 50062, 38556, 17522, 21140, 17351, 3287, 17138, 50369, 17375, 21615, 16341, 14637, 16129, 28631, 16367, 37567, 17443, 0, 0, 0, 0, 1037, 17530, 56467, 17381, 24264, 17157, 30932, 17356]
2022-02-28 17:16:28 DEBUG: setValues[3] 1101:82
2022-02-28 17:16:29 DEBUG: Getting Frame - 0x3 0x6 0x40 0x0 0x17
2022-02-28 17:16:29 DEBUG: Factory Request[ReadHoldingRegistersRequest: 3]
2022-02-28 17:16:29 DEBUG: Frame advanced, resetting header!!
2022-02-28 17:16:29 DEBUG: validate: fc-[3] address-1601: count-23
2022-02-28 17:16:29 DEBUG: getValues fc-[3] address-1601: count-23
2022-02-28 17:16:29 DEBUG: send: [ReadHoldingRegistersResponse (23)]- b'02032e000000000005000500050005000000010000000f0001271027102710fc18fc18fc1805dc0078000000004e2000000a36'
2022-02-28 17:16:30 DEBUG: Getting Frame - 0x3 0x6 0x40 0x0 0x17
2022-02-28 17:16:30 DEBUG: Factory Request[ReadHoldingRegistersRequest: 3]
2022-02-28 17:16:30 DEBUG: Frame advanced, resetting header!!
2022-02-28 17:16:30 DEBUG: validate: fc-[3] address-1601: count-23
2022-02-28 17:16:30 DEBUG: getValues fc-[3] address-1601: count-23
2022-02-28 17:16:30 DEBUG: send: [ReadHoldingRegistersResponse (23)]- b'02032e000000000005000500050005000000010000000f0001271027102710fc18fc18fc1805dc0078000000004e2000000a36'
2022-02-28 17:16:30 DEBUG: device: SDM630(/dev/ttySC1, connectionType.RTU: stopbits=1, parity=N, baud=38400, timeout=1, retries=3, unit=0x5)
2022-02-28 17:16:30 DEBUG: Current transaction state - TRANSACTION_COMPLETE
2022-02-28 17:16:30 DEBUG: Running transaction 5
2022-02-28 17:16:30 DEBUG: SEND: 0x5 0x4 0x0 0x0 0x0 0x50 0xf1 0xb2
2022-02-28 17:16:30 DEBUG: Changing state to IDLE - Last Frame End - 1646068588.749954, Current Time stamp - 1646068590.838359
2022-02-28 17:16:30 DEBUG: New Transaction state 'SENDING'
2022-02-28 17:16:30 DEBUG: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-02-28 17:16:30 DEBUG: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-02-28 17:16:30 DEBUG: RECV: 0x5 0x4 0xa0 0x43 0x6f 0x1b 0xc9 0x43 0x6f 0xcb 0x59 0x43 0x6f 0x3c 0x44 0x40 0xc 0xc0 0xca 0x3f 0x1 0xb4 0xd7 0x3f 0xf0 0x48 0xc8 0x43 0x96 0xfc 0xf2 0x42 0x90 0x9c 0xe 0x43 0x8e 0x86 0xd5 0x44 0x3 0x77 0x43 0x42 0xf2 0xfd 0xb8 0x43 0xe0 0x8c 0x83 0xc3 0x93 0x3c 0x9c 0xc2 0x92 0xba 0xb8 0xc3 0x8e 0xa6 0x56 0x3f 0x13 0x1 0xed 0x3f 0x16 0xb6 0x7d 0x3f 0x22 0xc9 0xe9 0xc2 0x5c 0x12 0xa1 0xc2 0x57 0xc0 0x1a 0xc2 0x4a 0x11 0xdc 0x43 0x6f 0x63 0x8 0x0 0x0 0x0 0x0 0x3f 0xae 0x11 0x64 0x40 0x82 0x8d 0xb 0x0 0x0 0x0 0x0 0x44 0x13 0x6d 0x92 0x0 0x0 0x0 0x0 0x44 0x74 0x9 0x43 0x0 0x0 0x0 0x0 0xc4 0x23 0xfa 0x18 0x3f 0x1a 0xa7 0xd8 0x0 0x0 0x0 0x0 0xc2 0x53 0x5a 0x35 0x0 0x0 0x0 0x0 0x42 0x47 0x9e 0x6 0x44 0xa3 0x76 0x14 0x0 0x0 0x0 0x0 0x3c 0xe5 0x60 0x41 0x44 0x91 0x6d 0x99 0xa2 0x3c
2022-02-28 17:16:30 DEBUG: Getting Frame - 0x4 0xa0 0x43 0x6f 0x1b 0xc9 0x43 0x6f 0xcb 0x59 0x43 0x6f 0x3c 0x44 0x40 0xc 0xc0 0xca 0x3f 0x1 0xb4 0xd7 0x3f 0xf0 0x48 0xc8 0x43 0x96 0xfc 0xf2 0x42 0x90 0x9c 0xe 0x43 0x8e 0x86 0xd5 0x44 0x3 0x77 0x43 0x42 0xf2 0xfd 0xb8 0x43 0xe0 0x8c 0x83 0xc3 0x93 0x3c 0x9c 0xc2 0x92 0xba 0xb8 0xc3 0x8e 0xa6 0x56 0x3f 0x13 0x1 0xed 0x3f 0x16 0xb6 0x7d 0x3f 0x22 0xc9 0xe9 0xc2 0x5c 0x12 0xa1 0xc2 0x57 0xc0 0x1a 0xc2 0x4a 0x11 0xdc 0x43 0x6f 0x63 0x8 0x0 0x0 0x0 0x0 0x3f 0xae 0x11 0x64 0x40 0x82 0x8d 0xb 0x0 0x0 0x0 0x0 0x44 0x13 0x6d 0x92 0x0 0x0 0x0 0x0 0x44 0x74 0x9 0x43 0x0 0x0 0x0 0x0 0xc4 0x23 0xfa 0x18 0x3f 0x1a 0xa7 0xd8 0x0 0x0 0x0 0x0 0xc2 0x53 0x5a 0x35 0x0 0x0 0x0 0x0 0x42 0x47 0x9e 0x6 0x44 0xa3 0x76 0x14 0x0 0x0 0x0 0x0 0x3c 0xe5 0x60 0x41 0x44 0x91 0x6d 0x99
2022-02-28 17:16:30 DEBUG: Factory Response[ReadInputRegistersResponse: 4]
2022-02-28 17:16:30 DEBUG: Frame advanced, resetting header!!

I've set up meter as WattNode. I was following up your instructions. SE addr: 1 Meter Addr: 2 I can provide screenshots from setApp application. Meter set as I+E.

log.txt.zip

SebuZet commented 2 years ago

I think that this output is from try with your static data (from another issue).

2022-02-28 21:31:48 DEBUG: [0, 0, 5, 5, 5, 5, 0, 1, 0, 15, 1, 10000, 10000, 10000, 64536, 64536, 64536, 1500, 120, 0, 0, 20000, 0]
2022-02-28 21:31:48 DEBUG: setValues[3] 1601:23
2022-02-28 21:31:48 DEBUG: [0, 2, 4, 0, 0, 5]
2022-02-28 21:31:48 DEBUG: setValues[3] 1651:6
2022-02-28 21:31:48 DEBUG: [57785, 1, 0, 0, 0, 0, 202, 31, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]
2022-02-28 21:31:48 DEBUG: setValues[3] 1701:23
2022-02-28 21:31:48 INFO: Created <Thread(t_update_2, initial)>: sdm setdata True
2022-02-28 21:31:49 INFO: return set data set from NMakel
2022-02-28 21:31:49 INFO: Starting <Thread(t_update_2, started 3053962304)>
2022-02-28 21:31:49 DEBUG: [27441, 17880, 22036, 17761, 27441, 17880, 22036, 17761, 62259, 50059, 0, 0, 0, 0, 0, 0, 58982, 17260, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 52429, 16967]
2022-02-28 21:31:49 DEBUG: setValues[3] 1001:34
2022-02-28 21:31:49 DEBUG: Client Connected [/dev/ttySC0:/dev/ttySC0]
2022-02-28 21:31:49 DEBUG: Started thread to serve client
2022-02-28 21:31:49 DEBUG: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 32846, 17743, 32846, 17743, 0, 0, 0, 0, 0, 0, 24946, 17941, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 13705, 48852, 0, 0, 0, 0, 0, 0, 42598, 50201, 0, 0, 0, 0, 0, 0, 56372, 17448, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 26152, 17480, 0, 0, 13366, 17745, 0, 0, 0, 0, 0, 0, 0, 0]
2022-02-28 21:31:49 DEBUG: setValues[3] 1101:82
2022-02-28 21:31:50 DEBUG: Getting Frame - 0x3 0x6 0x40 0x0 0x17
2022-02-28 21:31:50 DEBUG: Factory Request[ReadHoldingRegistersRequest: 3]
2022-02-28 21:31:50 DEBUG: Frame advanced, resetting header!!
2022-02-28 21:31:50 DEBUG: validate: fc-[3] address-1601: count-23
2022-02-28 21:31:50 DEBUG: getValues fc-[3] address-1601: count-23
2022-02-28 21:31:50 DEBUG: send: [ReadHoldingRegistersResponse (23)]- b'02032e000000000005000500050005000000010000000f0001271027102710fc18fc18fc1805dc0078000000004e2000000a36'
2022-02-28 21:31:51 INFO: return set data set from NMakel
2022-02-28 21:31:51 DEBUG: [27441, 17880, 22036, 17761, 27441, 17880, 22036, 17761, 62259, 50059, 0, 0, 0, 0, 0, 0, 58982, 17260, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 52429, 16967]
2022-02-28 21:31:51 DEBUG: Getting Frame - 0x3 0x6 0x40 0x0 0x17
2022-02-28 21:31:51 DEBUG: Factory Request[ReadHoldingRegistersRequest: 3]
2022-02-28 21:31:51 DEBUG: Frame advanced, resetting header!!
2022-02-28 21:31:51 DEBUG: validate: fc-[3] address-1601: count-23
2022-02-28 21:31:51 DEBUG: getValues fc-[3] address-1601: count-23
2022-02-28 21:31:51 DEBUG: send: [ReadHoldingRegistersResponse (23)]- b'02032e000000000005000500050005000000010000000f0001271027102710fc18fc18fc1805dc0078000000004e2000000a36'
2022-02-28 21:31:51 DEBUG: setValues[3] 1001:34
2022-02-28 21:31:51 DEBUG: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 32846, 17743, 32846, 17743, 0, 0, 0, 0, 0, 0, 24946, 17941, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 13705, 48852, 0, 0, 0, 0, 0, 0, 42598, 50201, 0, 0, 0, 0, 0, 0, 56372, 17448, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 26152, 17480, 0, 0, 13366, 17745, 0, 0, 0, 0, 0, 0, 0, 0]
2022-02-28 21:31:51 DEBUG: setValues[3] 1101:82
2022-02-28 21:31:52 DEBUG: Getting Frame - 0x6 0x6 0x47 0x0 0x0
2022-02-28 21:31:52 DEBUG: Factory Request[WriteSingleRegisterRequest: 6]
2022-02-28 21:31:52 DEBUG: Frame advanced, resetting header!!
2022-02-28 21:31:52 DEBUG: validate: fc-[6] address-1608: count-1
2022-02-28 21:31:52 DEBUG: setValues[6] 1608:1
2022-02-28 21:31:52 DEBUG: getValues fc-[6] address-1608: count-1
2022-02-28 21:31:52 DEBUG: send: [WriteRegisterResponse 1607 => 0]- b'0206064700003964'
2022-02-28 21:31:53 INFO: return set data set from NMakel
2022-02-28 21:31:53 DEBUG: [27441, 17880, 22036, 17761, 27441, 17880, 22036, 17761, 62259, 50059, 0, 0, 0, 0, 0, 0, 58982, 17260, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 52429, 16967]
2022-02-28 21:31:53 DEBUG: setValues[3] 1001:34
2022-02-28 21:31:53 DEBUG: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 32846, 17743, 32846, 17743, 0, 0, 0, 0, 0, 0, 24946, 17941, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 13705, 48852, 0, 0, 0, 0, 0, 0, 42598, 50201, 0, 0, 0, 0, 0, 0, 56372, 17448, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 26152, 17480, 0, 0, 13366, 17745, 0, 0, 0, 0, 0, 0, 0, 0]
2022-02-28 21:31:53 DEBUG: setValues[3] 1101:82
2022-02-28 21:31:53 DEBUG: Getting Frame - 0x3 0x6 0x40 0x0 0x17
2022-02-28 21:31:53 DEBUG: Factory Request[ReadHoldingRegistersRequest: 3]
2022-02-28 21:31:53 DEBUG: Frame advanced, resetting header!!
2022-02-28 21:31:53 DEBUG: validate: fc-[3] address-1601: count-23
2022-02-28 21:31:53 DEBUG: getValues fc-[3] address-1601: count-23
2022-02-28 21:31:53 DEBUG: send: [ReadHoldingRegistersResponse (23)]- b'02032e000000000005000500050005000000000000000f0001271027102710fc18fc18fc1805dc0078000000004e2000009bf6'
2022-02-28 21:31:54 DEBUG: Getting Frame - 0x6 0x6 0x47 0x0 0x0
2022-02-28 21:31:54 DEBUG: Factory Request[WriteSingleRegisterRequest: 6]
2022-02-28 21:31:54 DEBUG: Frame advanced, resetting header!!
2022-02-28 21:31:54 DEBUG: validate: fc-[6] address-1608: count-1
2022-02-28 21:31:54 DEBUG: setValues[6] 1608:1
2022-02-28 21:31:54 DEBUG: getValues fc-[6] address-1608: count-1
2022-02-28 21:31:54 DEBUG: send: [WriteRegisterResponse 1607 => 0]- b'0206064700003964'
2022-02-28 21:31:55 INFO: return set data set from NMakel
2022-02-28 21:31:55 DEBUG: [27441, 17880, 22036, 17761, 27441, 17880, 22036, 17761, 62259, 50059, 0, 0, 0, 0, 0, 0, 58982, 17260, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 52429, 16967]
2022-02-28 21:31:55 DEBUG: setValues[3] 1001:34
2022-02-28 21:31:55 DEBUG: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 32846, 17743, 32846, 17743, 0, 0, 0, 0, 0, 0, 24946, 17941, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 13705, 48852, 0, 0, 0, 0, 0, 0, 42598, 50201, 0, 0, 0, 0, 0, 0, 56372, 17448, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 26152, 17480, 0, 0, 13366, 17745, 0, 0, 0, 0, 0, 0, 0, 0]
2022-02-28 21:31:55 DEBUG: Getting Frame - 0x3 0x6 0x40 0x0 0x17
2022-02-28 21:31:55 DEBUG: Factory Request[ReadHoldingRegistersRequest: 3]
2022-02-28 21:31:55 DEBUG: Frame advanced, resetting header!!
2022-02-28 21:31:55 DEBUG: validate: fc-[3] address-1601: count-23
2022-02-28 21:31:55 DEBUG: getValues fc-[3] address-1601: count-23
2022-02-28 21:31:55 DEBUG: send: [ReadHoldingRegistersResponse (23)]- b'02032e000000000005000500050005000000000000000f0001271027102710fc18fc18fc1805dc0078000000004e2000009bf6'
2022-02-28 21:31:55 DEBUG: setValues[3] 1101:82
2022-02-28 21:31:56 DEBUG: Getting Frame - 0x6 0x6 0x47 0x0 0x0
2022-02-28 21:31:56 DEBUG: Factory Request[WriteSingleRegisterRequest: 6]
2022-02-28 21:31:56 DEBUG: Frame advanced, resetting header!!
2022-02-28 21:31:56 DEBUG: validate: fc-[6] address-1608: count-1
2022-02-28 21:31:56 DEBUG: setValues[6] 1608:1
2022-02-28 21:31:56 DEBUG: getValues fc-[6] address-1608: count-1
2022-02-28 21:31:56 DEBUG: send: [WriteRegisterResponse 1607 => 0]- b'0206064700003964'
nmakel commented 2 years ago

So a quick glance tells me two things: (1) the SDM630 is responding as expected and is providing sensible readings, and (2) the SE is communicating with semp-rtu.py and repeatedly asking for block 1601 values which contain basic configuration values of the meter.

It would be helpful to know whether the SE asks for blocks 1651 and 1701 at any point, most probably after (re)booting. If so, this means the SE is able to receive and decode the responses.

When the SE is satisfied we would expect it to start polling 1001 and 1101 multiple times per second. Looks like the SE isn't happy, and never gets to that stage. This would suggest something is missing, or incorrect in blocks 1601, 1651 or 1701.

SebuZet commented 2 years ago

Thank you for replay. Yes, SE is asking for 1701 register.

This is list of register SE is asking for:

pi@raspberrypi:~/solaredge_meterproxy $ cat out3.txt | grep setValues
2022-03-04 08:15:53 DEBUG: setValues[3] 1601:23
2022-03-04 08:15:53 DEBUG: setValues[3] 1651:6
2022-03-04 08:15:53 DEBUG: setValues[3] 1701:23
2022-03-04 08:15:54 DEBUG: setValues[3] 1001:34
2022-03-04 08:15:54 DEBUG: setValues[3] 1101:82
2022-03-04 08:15:56 DEBUG: setValues[3] 1001:34
2022-03-04 08:15:56 DEBUG: setValues[3] 1101:82
2022-03-04 08:15:58 DEBUG: setValues[3] 1001:34
2022-03-04 08:15:58 DEBUG: setValues[3] 1101:82
2022-03-04 08:15:59 DEBUG: setValues[6] 1608:1
2022-03-04 08:16:01 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:01 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:02 DEBUG: setValues[6] 1608:1
2022-03-04 08:16:03 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:03 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:05 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:05 DEBUG: setValues[6] 1608:1
2022-03-04 08:16:05 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:07 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:07 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:09 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:09 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:11 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:11 DEBUG: setValues[6] 1608:1
2022-03-04 08:16:11 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:13 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:13 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:15 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:15 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:17 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:17 DEBUG: setValues[6] 1608:1
2022-03-04 08:16:17 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:19 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:19 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:21 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:21 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:23 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:23 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:25 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:25 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:27 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:27 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:28 DEBUG: setValues[6] 1608:1
2022-03-04 08:16:29 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:29 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:30 DEBUG: setValues[6] 1603:1
2022-03-04 08:16:31 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:31 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:33 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:33 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:35 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:35 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:36 DEBUG: setValues[6] 1603:1
2022-03-04 08:16:37 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:37 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:39 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:39 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:40 DEBUG: setValues[6] 1603:1
2022-03-04 08:16:41 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:41 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:43 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:43 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:44 DEBUG: setValues[6] 1603:1
2022-03-04 08:16:45 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:45 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:47 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:47 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:48 DEBUG: setValues[6] 1603:1
2022-03-04 08:16:49 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:49 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:52 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:52 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:54 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:54 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:56 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:56 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:58 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:58 DEBUG: setValues[3] 1101:82
2022-03-04 08:17:00 DEBUG: setValues[3] 1001:34
2022-03-04 08:17:00 DEBUG: setValues[3] 1101:82
2022-03-04 08:17:02 DEBUG: setValues[3] 1001:34
2022-03-04 08:17:02 DEBUG: setValues[3] 1101:82
2022-03-04 08:17:03 DEBUG: setValues[6] 1608:1
2022-03-04 08:17:04 DEBUG: setValues[3] 1001:34
2022-03-04 08:17:04 DEBUG: setValues[3] 1101:82
2022-03-04 08:17:06 DEBUG: setValues[3] 1001:34
2022-03-04 08:17:06 DEBUG: setValues[3] 1101:82
2022-03-04 08:17:08 DEBUG: setValues[3] 1001:34
2022-03-04 08:17:08 DEBUG: setValues[3] 1101:82
2022-03-04 08:17:09 DEBUG: setValues[6] 1603:1
2022-03-04 08:17:10 DEBUG: setValues[3] 1001:34
2022-03-04 08:17:10 DEBUG: setValues[3] 1101:82
2022-03-04 08:17:12 DEBUG: setValues[3] 1001:34
2022-03-04 08:17:12 DEBUG: setValues[3] 1101:82
2022-03-04 08:17:14 DEBUG: setValues[3] 1001:34
2022-03-04 08:17:14 DEBUG: setValues[3] 1101:82
2022-03-04 08:17:16 DEBUG: setValues[3] 1001:34
2022-03-04 08:17:16 DEBUG: setValues[3] 1101:82
2022-03-04 08:17:17 DEBUG: setValues[6] 1603:1
2022-03-04 08:17:18 DEBUG: setValues[3] 1001:34
2022-03-04 08:17:18 DEBUG: setValues[3] 1101:82

And first few seconds of output:

2022-03-04 08:15:53 DEBUG: [0, 0, 5, 5, 5, 5, 0, 1, 0, 15, 1, 10000, 10000, 10000, 0, 0, 0, 1500, 120, 0, 0, 20000, 0]
2022-03-04 08:15:53 DEBUG: setValues[3] 1601:23
2022-03-04 08:15:53 DEBUG: [0, 2, 4, 0, 0, 5]
2022-03-04 08:15:53 DEBUG: setValues[3] 1651:6
2022-03-04 08:15:53 DEBUG: [57785, 1, 0, 0, 0, 0, 201, 31, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]
2022-03-04 08:15:53 DEBUG: setValues[3] 1701:23
2022-03-04 08:15:53 INFO: Created <Thread(t_update_2, initial)>: sdm setdata True
2022-03-04 08:15:54 INFO: return set data set from NMakel
2022-03-04 08:15:54 INFO: Starting <Thread(t_update_2, started 3054056512)>
2022-03-04 08:15:54 DEBUG: [27441, 17880, 22036, 17761, 27441, 17880, 22036, 17761, 62259, 50059, 0, 0, 0, 0, 0, 0, 58982, 17260, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 52429, 16967]
2022-03-04 08:15:54 DEBUG: setValues[3] 1001:34
2022-03-04 08:15:54 DEBUG: Client Connected [/dev/ttySC0:/dev/ttySC0]
2022-03-04 08:15:54 DEBUG: Started thread to serve client
2022-03-04 08:15:54 DEBUG: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 32846, 17743, 32846, 17743, 0, 0, 0, 0, 0, 0, 24946, 17941, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 13705, 48852, 0, 0, 0, 0, 0, 0, 42598, 50201, 0, 0, 0, 0, 0, 0, 56372, 17448, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 26152, 17480, 0, 0, 13366, 17745, 0, 0, 0, 0, 0, 0, 0, 0]
2022-03-04 08:15:54 DEBUG: setValues[3] 1101:82
2022-03-04 08:15:55 DEBUG: Getting Frame - 0x3 0x6 0x40 0x0 0x17
2022-03-04 08:15:55 DEBUG: Factory Request[ReadHoldingRegistersRequest: 3]
2022-03-04 08:15:55 DEBUG: Frame advanced, resetting header!!
2022-03-04 08:15:55 DEBUG: validate: fc-[3] address-1601: count-23
2022-03-04 08:15:55 DEBUG: getValues fc-[3] address-1601: count-23
2022-03-04 08:15:55 DEBUG: send: [ReadHoldingRegistersResponse (23)]- b'02032e000000000005000500050005000000010000000f000127102710271000000000000005dc0078000000004e200000f43e'
2022-03-04 08:15:56 INFO: return set data set from NMakel
2022-03-04 08:15:56 DEBUG: [27441, 17880, 22036, 17761, 27441, 17880, 22036, 17761, 62259, 50059, 0, 0, 0, 0, 0, 0, 58982, 17260, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 52429, 16967]
2022-03-04 08:15:56 DEBUG: setValues[3] 1001:34
2022-03-04 08:15:56 DEBUG: Getting Frame - 0x3 0x6 0x40 0x0 0x17
2022-03-04 08:15:56 DEBUG: Factory Request[ReadHoldingRegistersRequest: 3]
2022-03-04 08:15:56 DEBUG: Frame advanced, resetting header!!
2022-03-04 08:15:56 DEBUG: validate: fc-[3] address-1601: count-23
2022-03-04 08:15:56 DEBUG: getValues fc-[3] address-1601: count-23
2022-03-04 08:15:56 DEBUG: send: [ReadHoldingRegistersResponse (23)]- b'02032e000000000005000500050005000000010000000f000127102710271000000000000005dc0078000000004e200000f43e'
2022-03-04 08:15:56 DEBUG: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 32846, 17743, 32846, 17743, 0, 0, 0, 0, 0, 0, 24946, 17941, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 13705, 48852, 0, 0, 0, 0, 0, 0, 42598, 50201, 0, 0, 0, 0, 0, 0, 56372, 17448, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 26152, 17480, 0, 0, 13366, 17745, 0, 0, 0, 0, 0, 0, 0, 0]
2022-03-04 08:15:56 DEBUG: setValues[3] 1101:82
2022-03-04 08:15:57 DEBUG: Getting Frame - 0x3 0x6 0x40 0x0 0x17
2022-03-04 08:15:57 DEBUG: Factory Request[ReadHoldingRegistersRequest: 3]
2022-03-04 08:15:57 DEBUG: Frame advanced, resetting header!!
2022-03-04 08:15:57 DEBUG: validate: fc-[3] address-1601: count-23
2022-03-04 08:15:57 DEBUG: getValues fc-[3] address-1601: count-23
2022-03-04 08:15:57 DEBUG: send: [ReadHoldingRegistersResponse (23)]- b'02032e000000000005000500050005000000010000000f000127102710271000000000000005dc0078000000004e200000f43e'
2022-03-04 08:15:58 INFO: return set data set from NMakel
2022-03-04 08:15:58 DEBUG: [27441, 17880, 22036, 17761, 27441, 17880, 22036, 17761, 62259, 50059, 0, 0, 0, 0, 0, 0, 58982, 17260, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 52429, 16967]
2022-03-04 08:15:58 DEBUG: setValues[3] 1001:34
2022-03-04 08:15:58 DEBUG: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 32846, 17743, 32846, 17743, 0, 0, 0, 0, 0, 0, 24946, 17941, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 13705, 48852, 0, 0, 0, 0, 0, 0, 42598, 50201, 0, 0, 0, 0, 0, 0, 56372, 17448, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 26152, 17480, 0, 0, 13366, 17745, 0, 0, 0, 0, 0, 0, 0, 0]
2022-03-04 08:15:58 DEBUG: setValues[3] 1101:82
2022-03-04 08:15:59 DEBUG: Getting Frame - 0x6 0x6 0x47 0x0 0x0
2022-03-04 08:15:59 DEBUG: Factory Request[WriteSingleRegisterRequest: 6]
2022-03-04 08:15:59 DEBUG: Frame advanced, resetting header!!
2022-03-04 08:15:59 DEBUG: validate: fc-[6] address-1608: count-1
2022-03-04 08:15:59 DEBUG: setValues[6] 1608:1
2022-03-04 08:15:59 DEBUG: getValues fc-[6] address-1608: count-1
2022-03-04 08:15:59 DEBUG: send: [WriteRegisterResponse 1607 => 0]- b'0206064700003964'
2022-03-04 08:16:00 DEBUG: Getting Frame - 0x3 0x6 0x40 0x0 0x17
2022-03-04 08:16:00 DEBUG: Factory Request[ReadHoldingRegistersRequest: 3]
2022-03-04 08:16:01 INFO: return set data set from NMakel
2022-03-04 08:16:01 DEBUG: [27441, 17880, 22036, 17761, 27441, 17880, 22036, 17761, 62259, 50059, 0, 0, 0, 0, 0, 0, 58982, 17260, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 52429, 16967]
2022-03-04 08:16:01 DEBUG: Frame advanced, resetting header!!
2022-03-04 08:16:01 DEBUG: validate: fc-[3] address-1601: count-23
2022-03-04 08:16:01 DEBUG: getValues fc-[3] address-1601: count-23
2022-03-04 08:16:01 DEBUG: send: [ReadHoldingRegistersResponse (23)]- b'02032e000000000005000500050005000000000000000f000127102710271000000000000005dc0078000000004e20000065fe'
2022-03-04 08:16:01 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:01 DEBUG: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 32846, 17743, 32846, 17743, 0, 0, 0, 0, 0, 0, 24946, 17941, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 13705, 48852, 0, 0, 0, 0, 0, 0, 42598, 50201, 0, 0, 0, 0, 0, 0, 56372, 17448, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 26152, 17480, 0, 0, 13366, 17745, 0, 0, 0, 0, 0, 0, 0, 0]
2022-03-04 08:16:01 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:02 DEBUG: Getting Frame - 0x6 0x6 0x47 0x0 0x0
2022-03-04 08:16:02 DEBUG: Factory Request[WriteSingleRegisterRequest: 6]
2022-03-04 08:16:02 DEBUG: Frame advanced, resetting header!!
2022-03-04 08:16:02 DEBUG: validate: fc-[6] address-1608: count-1
2022-03-04 08:16:02 DEBUG: setValues[6] 1608:1
2022-03-04 08:16:02 DEBUG: getValues fc-[6] address-1608: count-1
2022-03-04 08:16:02 DEBUG: send: [WriteRegisterResponse 1607 => 0]- b'0206064700003964'
2022-03-04 08:16:03 INFO: return set data set from NMakel
2022-03-04 08:16:03 DEBUG: [27441, 17880, 22036, 17761, 27441, 17880, 22036, 17761, 62259, 50059, 0, 0, 0, 0, 0, 0, 58982, 17260, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 52429, 16967]
2022-03-04 08:16:03 DEBUG: Getting Frame - 0x3 0x6 0x40 0x0 0x17
2022-03-04 08:16:03 DEBUG: Factory Request[ReadHoldingRegistersRequest: 3]
2022-03-04 08:16:03 DEBUG: Frame advanced, resetting header!!
2022-03-04 08:16:03 DEBUG: validate: fc-[3] address-1601: count-23
2022-03-04 08:16:03 DEBUG: getValues fc-[3] address-1601: count-23
2022-03-04 08:16:03 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:03 DEBUG: send: [ReadHoldingRegistersResponse (23)]- b'02032e000000000005000500050005000000000000000f000127102710271000000000000005dc0078000000004e20000065fe'
2022-03-04 08:16:03 DEBUG: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 32846, 17743, 32846, 17743, 0, 0, 0, 0, 0, 0, 24946, 17941, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 13705, 48852, 0, 0, 0, 0, 0, 0, 42598, 50201, 0, 0, 0, 0, 0, 0, 56372, 17448, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 26152, 17480, 0, 0, 13366, 17745, 0, 0, 0, 0, 0, 0, 0, 0]
2022-03-04 08:16:03 DEBUG: setValues[3] 1101:82
2022-03-04 08:16:04 DEBUG: Getting Frame - 0x3 0x6 0x72 0x0 0x6
2022-03-04 08:16:04 DEBUG: Factory Request[ReadHoldingRegistersRequest: 3]
2022-03-04 08:16:04 DEBUG: Frame advanced, resetting header!!
2022-03-04 08:16:04 DEBUG: validate: fc-[3] address-1651: count-6
2022-03-04 08:16:04 DEBUG: getValues fc-[3] address-1651: count-6
2022-03-04 08:16:04 DEBUG: send: [ReadHoldingRegistersResponse (6)]- b'02030c0000000200040000000000054cd2'
2022-03-04 08:16:05 INFO: return set data set from NMakel
2022-03-04 08:16:05 DEBUG: [27441, 17880, 22036, 17761, 27441, 17880, 22036, 17761, 62259, 50059, 0, 0, 0, 0, 0, 0, 58982, 17260, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 52429, 16967]
2022-03-04 08:16:05 DEBUG: Getting Frame - 0x6 0x6 0x47 0x0 0x0
2022-03-04 08:16:05 DEBUG: Factory Request[WriteSingleRegisterRequest: 6]
2022-03-04 08:16:05 DEBUG: setValues[3] 1001:34
2022-03-04 08:16:05 DEBUG: Frame advanced, resetting header!!
2022-03-04 08:16:05 DEBUG: validate: fc-[6] address-1608: count-1
2022-03-04 08:16:05 DEBUG: setValues[6] 1608:1
2022-03-04 08:16:05 DEBUG: getValues fc-[6] address-1608: count-1
2022-03-04 08:16:05 DEBUG: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 32846, 17743, 32846, 17743, 0, 0, 0, 0, 0, 0, 24946, 17941, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 13705, 48852, 0, 0, 0, 0, 0, 0, 42598, 50201, 0, 0, 0, 0, 0, 0, 56372, 17448, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 26152, 17480, 0, 0, 13366, 17745, 0, 0, 0, 0, 0, 0, 0, 0]
SebuZet commented 2 years ago

And my current meter congig looks like this:

slave_ctx = ModbusSlaveContext()

block_1601 = BinaryPayloadBuilder(byteorder=Endian.Big, wordorder=Endian.Little)
block_1601.add_32bit_int(0) # config passcode
block_1601.add_16bit_int(confparser[meter].getint("ct_current", fallback=default_config["meters"]["ct_current"])) # ct rated current
block_1601.add_16bit_int(confparser[meter].getint("ct_current", fallback=default_config["meters"]["ct_current"])) # ct rated current l1
block_1601.add_16bit_int(confparser[meter].getint("ct_current", fallback=default_config["meters"]["ct_current"])) # ct rated current l2
block_1601.add_16bit_int(confparser[meter].getint("ct_current", fallback=default_config["meters"]["ct_current"])) # ct rated current l3
block_1601.add_16bit_int(confparser[meter].getint("ct_inverted", fallback=default_config["meters"]["ct_inverted"])) # ct direction inversion
block_1601.add_16bit_int(1) # measurement averaging
block_1601.add_16bit_int(0) # power scale
block_1601.add_16bit_int(15) # demand period
block_1601.add_16bit_int(1) # demand subintervals
block_1601.add_16bit_int(10000) # power/energy adjustment l1
block_1601.add_16bit_int(10000) # power/energy adjustment l2
block_1601.add_16bit_int(10000) # power/energy adjustment l3
block_1601.add_16bit_int(0) # ct phase angle adjustment l1
block_1601.add_16bit_int(0) # ct phase angle adjustment l2
block_1601.add_16bit_int(0) # ct phase angle adjustment l3
block_1601.add_16bit_int(1500) # minimum power reading
block_1601.add_16bit_int(confparser[meter].getint("phase_offset", fallback=default_config["meters"]["phase_offset"])) # phase offset
block_1601.add_16bit_int(0) # reset energy
block_1601.add_16bit_int(0) # reset demand
block_1601.add_16bit_int(20000) # current scale
block_1601.add_16bit_int(0) # io pin mode
slave_ctx.setValues(3, 1600, block_1601.to_registers())

block_1651 = BinaryPayloadBuilder(byteorder=Endian.Big, wordorder=Endian.Little)
block_1651.add_16bit_int(0) # apply config
block_1651.add_16bit_int(address) # modbus address
block_1651.add_16bit_int(4) #4 baud rate
block_1651.add_16bit_int(0) # parity mode
block_1651.add_16bit_int(0) # modbus mode
block_1651.add_16bit_int(5) # message delay
slave_ctx.setValues(3, 1650, block_1651.to_registers())

block_1701 = BinaryPayloadBuilder(byteorder=Endian.Big, wordorder=Endian.Little)
block_1701.add_32bit_int(confparser[meter].getint("serial_number", fallback=default_config["meters"]["serial_number"])) # serial number
block_1701.add_32bit_int(0) # uptime (s)
block_1701.add_32bit_int(0) # total uptime (s)
block_1701.add_16bit_int(201) # wattnode model
block_1701.add_16bit_int(31) # firmware version
block_1701.add_16bit_int(0) # wattnode options
block_1701.add_16bit_int(0) # error status
block_1701.add_16bit_int(0) # power fail count
block_1701.add_16bit_int(0) # crc error count
block_1701.add_16bit_int(0) # frame error count
block_1701.add_16bit_int(0) # packet error count
block_1701.add_16bit_int(0) # overrun count
block_1701.add_16bit_int(0) # error status 1
block_1701.add_16bit_int(0) # error status 2
block_1701.add_16bit_int(0) # error status 3
block_1701.add_16bit_int(0) # error status 4
block_1701.add_16bit_int(0) # error status 5
block_1701.add_16bit_int(0) # error status 6
block_1701.add_16bit_int(0) # error status 7
block_1701.add_16bit_int(0) # error status 8
slave_ctx.setValues(3, 1700, block_1701.to_registers())

I was playing with some values but without success