Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Can't seem to receive information from SOK BMS - ERROR [sampling] Battery 1 error (#1): timeout waiting for XX command. #178

Open
Ampakinetic opened this issue Dec 17, 2023 · 6 comments
Open

Can't seem to receive information from SOK BMS - ERROR [sampling] Battery 1 error (#1): timeout waiting for XX command. #178

Ampakinetic opened this issue Dec 17, 2023 · 6 comments

Comments

@Ampakinetic
Copy link

Ampakinetic commented Dec 17, 2023 •
edited

Hi, I have brand new SOK 24V battery and have no problem connecting to it with the ABC-BMS app. However the sent commands don't seem to get any responses back with batmon. I've added a little bit of extra logging and also tried to call the name command before the info command but it's got the same timeout error either way. The verbose log is below, happy to provide more information if it can be helpful.

Paste log output between BEGIN and END:
BEGIN 
soa@orangepizero:~/batmon-ha$ python3 main.py
error reading /data/options.json, trying options.json [Errno 2] No such file or directory: '/data/options.json'
20:40:51 INFO [bt] BT Discovery:
20:40:57 INFO [bt] BT 01:B6:EC:DA:91:54                SOK-24V1247
20:40:57 INFO [main] Verbose logging enabled
20:40:57 INFO [main] Bleak version 0.13.1a1, BtBackend version bluez-v5.55
20:40:57 DEBUG [bt] BMS SokBt(01:B6:EC:DA:91:54,Battery 1) keep alive enabled
20:40:57 DEBUG [client] Sending CONNECT (u1, p1, wr0, wq0, wf0, c1, k60) client_id=b''
20:40:57 INFO [main] Fetching 1 BMS + 0 virtual + 0 others serially, period=1.00s, keep_alive=True
20:40:57 INFO [sampling] connecting bms SokBt(01:B6:EC:DA:91:54,Battery 1)
20:40:57 INFO [bt] connecting Battery 1 (01:B6:EC:DA:91:54) adapter=default timeout=20
20:40:57 DEBUG [client] Connecting to device @ 01:B6:EC:DA:91:54 with hci0
20:40:57 DEBUG [client] Received CONNACK (0, 0)
20:40:57 DEBUG [scanner] cached devices: {'/org/bluez/hci0/dev_01_B6_EC_DA_91_54': {'Address': '01:B6:EC:DA:91:54', 'AddressType': 'public', 'Name': 'SOK-24V1247', 'Alias': 'SOK-24V1247', 'Paired': False, 'Trusted': False, 'Blocked': False, 'LegacyPairing': False, 'Connected': False, 'UUIDs': ['0000fff0-0000-1000-8000-00805f9b34fb'], 'Adapter': '/org/bluez/hci0', 'ServicesResolved': False}}
20:40:58 DEBUG [scanner] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0): ['org.bluez.Adapter1', {'Discovering': <dbus_next.signature.Variant ('b', True)>}, []]
20:40:58 DEBUG [scanner] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54): ['org.bluez.Device1', {'RSSI': <dbus_next.signature.Variant ('n', -69)>}, []]
20:40:58 DEBUG [scanner] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54): ['org.bluez.Device1', {}, ['RSSI']]
20:40:58 DEBUG [scanner] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0): ['org.bluez.Adapter1', {'Discovering': <dbus_next.signature.Variant ('b', False)>}, []]
20:40:58 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54): ['org.bluez.Device1', {'Connected': <dbus_next.signature.Variant ('b', True)>}, []]
20:40:58 DEBUG [client] Connection successful (/org/bluez/hci0/dev_01_B6_EC_DA_91_54)
20:40:58 DEBUG [client] Waiting for ServicesResolved (/org/bluez/hci0/dev_01_B6_EC_DA_91_54)
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service000a', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattService1': {'UUID': <dbus_next.signature.Variant ('s', 00001801-0000-1000-8000-00805f9b34fb)>, 'Device': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54)>, 'Primary': <dbus_next.signature.Variant ('b', True)>, 'Includes': <dbus_next.signature.Variant ('ao', [])>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service000b', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattService1': {'UUID': <dbus_next.signature.Variant ('s', 0000ffe0-0000-1000-8000-00805f9b34fb)>, 'Device': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54)>, 'Primary': <dbus_next.signature.Variant ('b', True)>, 'Includes': <dbus_next.signature.Variant ('ao', [])>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service000b/char000c', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_next.signature.Variant ('s', 0000ffe2-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service000b)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>, 'Flags': <dbus_next.signature.Variant ('as', ['write-without-response'])>, 'WriteAcquired': <dbus_next.signature.Variant ('b', False)>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service000b/char000e', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_next.signature.Variant ('s', 0000ffe1-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service000b)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>, 'Notifying': <dbus_next.signature.Variant ('b', False)>, 'Flags': <dbus_next.signature.Variant ('as', ['read', 'notify'])>, 'NotifyAcquired': <dbus_next.signature.Variant ('b', False)>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service000b/char000e/desc0010', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_next.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service000b/char000e)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattService1': {'UUID': <dbus_next.signature.Variant ('s', 0000180a-0000-1000-8000-00805f9b34fb)>, 'Device': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54)>, 'Primary': <dbus_next.signature.Variant ('b', True)>, 'Includes': <dbus_next.signature.Variant ('ao', [])>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char0012', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_next.signature.Variant ('s', 00002a29-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>, 'Flags': <dbus_next.signature.Variant ('as', ['read'])>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char0014', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_next.signature.Variant ('s', 00002a24-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>, 'Flags': <dbus_next.signature.Variant ('as', ['read'])>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char0016', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_next.signature.Variant ('s', 00002a25-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>, 'Flags': <dbus_next.signature.Variant ('as', ['read'])>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char0018', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_next.signature.Variant ('s', 00002a27-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>, 'Flags': <dbus_next.signature.Variant ('as', ['read'])>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char001a', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_next.signature.Variant ('s', 00002a26-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>, 'Flags': <dbus_next.signature.Variant ('as', ['read'])>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char001c', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_next.signature.Variant ('s', 00002a28-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>, 'Flags': <dbus_next.signature.Variant ('as', ['read'])>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char001e', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_next.signature.Variant ('s', 00002a23-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>, 'Flags': <dbus_next.signature.Variant ('as', ['read'])>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char0020', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_next.signature.Variant ('s', 00002a2a-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>, 'Flags': <dbus_next.signature.Variant ('as', ['read'])>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char0022', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_next.signature.Variant ('s', 00002a50-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>, 'Flags': <dbus_next.signature.Variant ('as', ['read'])>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0024', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattService1': {'UUID': <dbus_next.signature.Variant ('s', 0000180f-0000-1000-8000-00805f9b34fb)>, 'Device': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54)>, 'Primary': <dbus_next.signature.Variant ('b', True)>, 'Includes': <dbus_next.signature.Variant ('ao', [])>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0024/char0025', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_next.signature.Variant ('s', 00002a19-0000-1000-8000-00805f9b34fb)>, 'Service': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0024)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>, 'Notifying': <dbus_next.signature.Variant ('b', False)>, 'Flags': <dbus_next.signature.Variant ('as', ['read', 'notify'])>, 'NotifyAcquired': <dbus_next.signature.Variant ('b', False)>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0024/char0025/desc0027', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_next.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0024/char0025)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0028', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattService1': {'UUID': <dbus_next.signature.Variant ('s', f000ffc0-0451-4000-b000-000000000000)>, 'Device': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54)>, 'Primary': <dbus_next.signature.Variant ('b', True)>, 'Includes': <dbus_next.signature.Variant ('ao', [])>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0028/char0029', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_next.signature.Variant ('s', f000ffc1-0451-4000-b000-000000000000)>, 'Service': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0028)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>, 'Notifying': <dbus_next.signature.Variant ('b', False)>, 'Flags': <dbus_next.signature.Variant ('as', ['write-without-response', 'write', 'notify'])>, 'WriteAcquired': <dbus_next.signature.Variant ('b', False)>, 'NotifyAcquired': <dbus_next.signature.Variant ('b', False)>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0028/char0029/desc002b', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_next.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0028/char0029)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0028/char0029/desc002c', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_next.signature.Variant ('s', 00002901-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0028/char0029)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0028/char002d', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattCharacteristic1': {'UUID': <dbus_next.signature.Variant ('s', f000ffc2-0451-4000-b000-000000000000)>, 'Service': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0028)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>, 'Notifying': <dbus_next.signature.Variant ('b', False)>, 'Flags': <dbus_next.signature.Variant ('as', ['write-without-response', 'write', 'notify'])>, 'WriteAcquired': <dbus_next.signature.Variant ('b', False)>, 'NotifyAcquired': <dbus_next.signature.Variant ('b', False)>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0028/char002d/desc002f', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_next.signature.Variant ('s', 00002902-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0028/char002d)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded (/): ['/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0028/char002d/desc0030', {'org.freedesktop.DBus.Introspectable': {}, 'org.bluez.GattDescriptor1': {'UUID': <dbus_next.signature.Variant ('s', 00002901-0000-1000-8000-00805f9b34fb)>, 'Characteristic': <dbus_next.signature.Variant ('o', /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0028/char002d)>, 'Value': <dbus_next.signature.Variant ('ay', b'')>}, 'org.freedesktop.DBus.Properties': {}}]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54): ['org.bluez.Device1', {'UUIDs': <dbus_next.signature.Variant ('as', ['00001800-0000-1000-8000-00805f9b34fb', '00001801-0000-1000-8000-00805f9b34fb', '0000180a-0000-1000-8000-00805f9b34fb', '0000180f-0000-1000-8000-00805f9b34fb', '0000ffe0-0000-1000-8000-00805f9b34fb', 'f000ffc0-0451-4000-b000-000000000000'])>, 'ServicesResolved': <dbus_next.signature.Variant ('b', True)>}, []]
20:40:59 INFO [bt] [Service] 00001801-0000-1000-8000-00805f9b34fb (Handle: 10): Generic Attribute Profile
20:40:59 INFO [bt] [Service] 0000ffe0-0000-1000-8000-00805f9b34fb (Handle: 11): Vendor specific
20:40:59 INFO [bt]      [Characteristic] 0000ffe2-0000-1000-8000-00805f9b34fb (Handle: 12): Vendor specific (write-without-response), Value: None
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54): ['org.bluez.Device1', {'Modalias': <dbus_next.signature.Variant ('s', usb:v045Ep0040d0300)>}, []]
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service000b/char000e): ['org.bluez.GattCharacteristic1', {'Value': <dbus_next.signature.Variant ('ay', b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00')>}, []]
20:40:59 DEBUG [client] Read Characteristic 0000ffe1-0000-1000-8000-00805f9b34fb | /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service000b/char000e: bytearray(b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00')
20:40:59 INFO [bt]      [Characteristic] 0000ffe1-0000-1000-8000-00805f9b34fb (Handle: 14): Vendor specific (read,notify), Value: b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00'
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service000b/char000e/desc0010): ['org.bluez.GattDescriptor1', {'Value': <dbus_next.signature.Variant ('ay', b'\x00\x00')>}, []]
20:40:59 DEBUG [client] Read Descriptor 16 | /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service000b/char000e/desc0010: bytearray(b'\x00\x00')
20:40:59 INFO [bt]              [Descriptor] 00002902-0000-1000-8000-00805f9b34fb (Handle: 16): Client Characteristic Configuration) | Value: b'\x00\x00'
20:40:59 INFO [bt] [Service] 0000180a-0000-1000-8000-00805f9b34fb (Handle: 17): Device Information
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char0012): ['org.bluez.GattCharacteristic1', {'Value': <dbus_next.signature.Variant ('ay', b'BEKEN SAS')>}, []]
20:40:59 DEBUG [client] Read Characteristic 00002a29-0000-1000-8000-00805f9b34fb | /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char0012: bytearray(b'BEKEN SAS')
20:40:59 INFO [bt]      [Characteristic] 00002a29-0000-1000-8000-00805f9b34fb (Handle: 18): Manufacturer Name String (read), Value: b'BEKEN SAS'
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char0014): ['org.bluez.GattCharacteristic1', {'Value': <dbus_next.signature.Variant ('ay', b'BK-BLE-1.0')>}, []]
20:40:59 DEBUG [client] Read Characteristic 00002a24-0000-1000-8000-00805f9b34fb | /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char0014: bytearray(b'BK-BLE-1.0')
20:40:59 INFO [bt]      [Characteristic] 00002a24-0000-1000-8000-00805f9b34fb (Handle: 20): Model Number String (read), Value: b'BK-BLE-1.0'
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char0016): ['org.bluez.GattCharacteristic1', {'Value': <dbus_next.signature.Variant ('ay', b'1.0.0.0-LE')>}, []]
20:40:59 DEBUG [client] Read Characteristic 00002a25-0000-1000-8000-00805f9b34fb | /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char0016: bytearray(b'1.0.0.0-LE')
20:40:59 INFO [bt]      [Characteristic] 00002a25-0000-1000-8000-00805f9b34fb (Handle: 22): Serial Number String (read), Value: b'1.0.0.0-LE'
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char0018): ['org.bluez.GattCharacteristic1', {'Value': <dbus_next.signature.Variant ('ay', b'1.0.0')>}, []]
20:40:59 DEBUG [client] Read Characteristic 00002a27-0000-1000-8000-00805f9b34fb | /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char0018: bytearray(b'1.0.0')
20:40:59 INFO [bt]      [Characteristic] 00002a27-0000-1000-8000-00805f9b34fb (Handle: 24): Hardware Revision String (read), Value: b'1.0.0'
20:40:59 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char001a): ['org.bluez.GattCharacteristic1', {'Value': <dbus_next.signature.Variant ('ay', b'6.1.2')>}, []]
20:40:59 DEBUG [client] Read Characteristic 00002a26-0000-1000-8000-00805f9b34fb | /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char001a: bytearray(b'6.1.2')
20:40:59 INFO [bt]      [Characteristic] 00002a26-0000-1000-8000-00805f9b34fb (Handle: 26): Firmware Revision String (read), Value: b'6.1.2'
20:41:00 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char001c): ['org.bluez.GattCharacteristic1', {'Value': <dbus_next.signature.Variant ('ay', b'6.3.0')>}, []]
20:41:00 DEBUG [client] Read Characteristic 00002a28-0000-1000-8000-00805f9b34fb | /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char001c: bytearray(b'6.3.0')
20:41:00 INFO [bt]      [Characteristic] 00002a28-0000-1000-8000-00805f9b34fb (Handle: 28): Software Revision String (read), Value: b'6.3.0'
20:41:00 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char001e): ['org.bluez.GattCharacteristic1', {'Value': <dbus_next.signature.Variant ('ay', b'\x124V\xff\xfe\x9a\xbc\xde')>}, []]
20:41:00 DEBUG [client] Read Characteristic 00002a23-0000-1000-8000-00805f9b34fb | /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char001e: bytearray(b'\x124V\xff\xfe\x9a\xbc\xde')
20:41:00 INFO [bt]      [Characteristic] 00002a23-0000-1000-8000-00805f9b34fb (Handle: 30): System ID (read), Value: b'\x124V\xff\xfe\x9a\xbc\xde'
20:41:00 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char0020): ['org.bluez.GattCharacteristic1', {'Value': <dbus_next.signature.Variant ('ay', b'\xff\xee\xdd\xcc\xbb\xaa')>}, []]
20:41:00 DEBUG [client] Read Characteristic 00002a2a-0000-1000-8000-00805f9b34fb | /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char0020: bytearray(b'\xff\xee\xdd\xcc\xbb\xaa')
20:41:00 INFO [bt]      [Characteristic] 00002a2a-0000-1000-8000-00805f9b34fb (Handle: 32): IEEE 11073-20601 Regulatory Cert. Data List (read), Value: b'\xff\xee\xdd\xcc\xbb\xaa'
20:41:00 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char0022): ['org.bluez.GattCharacteristic1', {'Value': <dbus_next.signature.Variant ('ay', b'\x02^\x04@\x00\x00\x03')>}, []]
20:41:00 DEBUG [client] Read Characteristic 00002a50-0000-1000-8000-00805f9b34fb | /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0011/char0022: bytearray(b'\x02^\x04@\x00\x00\x03')
20:41:00 INFO [bt]      [Characteristic] 00002a50-0000-1000-8000-00805f9b34fb (Handle: 34): PnP ID (read), Value: b'\x02^\x04@\x00\x00\x03'
20:41:00 INFO [bt] [Service] 0000180f-0000-1000-8000-00805f9b34fb (Handle: 36): Battery Service
20:41:00 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0024/char0025): ['org.bluez.GattCharacteristic1', {'Value': <dbus_next.signature.Variant ('ay', b'\x00')>}, []]
20:41:00 DEBUG [client] Read Characteristic 00002a19-0000-1000-8000-00805f9b34fb | /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0024/char0025: bytearray(b'\x00')
20:41:00 INFO [bt]      [Characteristic] 00002a19-0000-1000-8000-00805f9b34fb (Handle: 37): Battery Level (read,notify), Value: b'\x00'
20:41:00 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0024/char0025/desc0027): ['org.bluez.GattDescriptor1', {'Value': <dbus_next.signature.Variant ('ay', b'\x01\x00')>}, []]
20:41:00 DEBUG [client] Read Descriptor 39 | /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0024/char0025/desc0027: bytearray(b'\x01\x00')
20:41:00 INFO [bt]              [Descriptor] 00002902-0000-1000-8000-00805f9b34fb (Handle: 39): Client Characteristic Configuration) | Value: b'\x01\x00'
20:41:00 INFO [bt] [Service] f000ffc0-0451-4000-b000-000000000000 (Handle: 40): Unknown
20:41:00 INFO [bt]      [Characteristic] f000ffc1-0451-4000-b000-000000000000 (Handle: 41): Unknown (write-without-response,write,notify), Value: None
20:41:00 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0028/char0029/desc002b): ['org.bluez.GattDescriptor1', {'Value': <dbus_next.signature.Variant ('ay', b'\x00\x00')>}, []]
20:41:00 DEBUG [client] Read Descriptor 43 | /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0028/char0029/desc002b: bytearray(b'\x00\x00')
20:41:00 INFO [bt]              [Descriptor] 00002902-0000-1000-8000-00805f9b34fb (Handle: 43): Client Characteristic Configuration) | Value: b'\x00\x00'
20:41:00 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0028/char0029/desc002c): ['org.bluez.GattDescriptor1', {'Value': <dbus_next.signature.Variant ('ay', b'Img Identify\x00')>}, []]
20:41:00 DEBUG [client] Read Descriptor 44 | /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0028/char0029/desc002c: bytearray(b'Img Identify\x00')
20:41:00 INFO [bt]              [Descriptor] 00002901-0000-1000-8000-00805f9b34fb (Handle: 44): Characteristic User Description) | Value: b'Img Identify\x00'
20:41:00 INFO [bt]      [Characteristic] f000ffc2-0451-4000-b000-000000000000 (Handle: 45): Unknown (write-without-response,write,notify), Value: None
20:41:00 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0028/char002d/desc002f): ['org.bluez.GattDescriptor1', {'Value': <dbus_next.signature.Variant ('ay', b'\x00\x00')>}, []]
20:41:00 DEBUG [client] Read Descriptor 47 | /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0028/char002d/desc002f: bytearray(b'\x00\x00')
20:41:00 INFO [bt]              [Descriptor] 00002902-0000-1000-8000-00805f9b34fb (Handle: 47): Client Characteristic Configuration) | Value: b'\x00\x00'
20:41:00 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0028/char002d/desc0030): ['org.bluez.GattDescriptor1', {'Value': <dbus_next.signature.Variant ('ay', b'Img Block\x00')>}, []]
20:41:00 DEBUG [client] Read Descriptor 48 | /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service0028/char002d/desc0030: bytearray(b'Img Block\x00')
20:41:00 INFO [bt]              [Descriptor] 00002901-0000-1000-8000-00805f9b34fb (Handle: 48): Characteristic User Description) | Value: b'Img Block\x00'
20:41:00 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service000b/char000e): ['org.bluez.GattCharacteristic1', {'Notifying': <dbus_next.signature.Variant ('b', True)>}, []]
20:41:00 INFO [sampling] connected bms SokBt(01:B6:EC:DA:91:54,Battery 1)!
20:41:00 DEBUG [sok] SOK: Send 0xC0 command]
20:41:00 DEBUG [sok] SOK: Formatting command [[238, 192, 0, 0, 0, 65]]
20:41:00 DEBUG [client] Write Characteristic 0000ffe2-0000-1000-8000-00805f9b34fb | /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service000b/char000c: b'\xee\xc0\x00\x00\x00'
20:41:30 ERROR [sampling] Battery 1 error (#1): timeout waiting for 192
Traceback (most recent call last):
  File "/usr/lib/python3.9/asyncio/tasks.py", line 492, in wait_for
    fut.result()
asyncio.exceptions.CancelledError

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/home/soa/batmon-ha/bmslib/__init__.py", line 81, in wait_for
    return await asyncio.wait_for(self._futures.get(name), timeout)
  File "/usr/lib/python3.9/asyncio/tasks.py", line 494, in wait_for
    raise exceptions.TimeoutError() from exc
asyncio.exceptions.TimeoutError

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/soa/batmon-ha/bmslib/sampling.py", line 148, in __call__
    s = await self._sample_inner()
  File "/home/soa/batmon-ha/bmslib/sampling.py", line 221, in _sample_inner
    sample = await bms.fetch()
  File "/home/soa/batmon-ha/bmslib/models/sok.py", line 121, in fetch
    buf = await self._q(cmd=0xC0)  # name
  File "/home/soa/batmon-ha/bmslib/models/sok.py", line 117, in _q
    return await self._fetch_futures.wait_for(cmd, self.TIMEOUT)
  File "/home/soa/batmon-ha/bmslib/__init__.py", line 84, in wait_for
    raise asyncio.TimeoutError("timeout waiting for %s" % name)
asyncio.exceptions.TimeoutError: timeout waiting for 192
20:41:30 INFO [sampling] Bleak version 0.13.1a1
20:41:30 DEBUG [sok] SOK: Send 0xC0 command]
20:41:30 DEBUG [sok] SOK: Formatting command [[238, 192, 0, 0, 0, 65]]
20:41:30 DEBUG [client] Write Characteristic 0000ffe2-0000-1000-8000-00805f9b34fb | /org/bluez/hci0/dev_01_B6_EC_DA_91_54/service000b/char000c: b'\xee\xc0\x00\x00\x00'
^C20:41:54 INFO [main] exit signal handler... (2, <frame at 0xb5f88338, file '/usr/lib/python3.9/selectors.py', line 469, code select>), {}, shutdown was False
20:41:57 INFO [main] Background thread ends. shutdown=1
20:41:57 DEBUG [client] Sending PINGREQ
20:41:57 DEBUG [client] Received PINGRESP
20:42:00 ERROR [sampling] Battery 1 error (#2): timeout waiting for 192
Traceback (most recent call last):
  File "/usr/lib/python3.9/asyncio/tasks.py", line 492, in wait_for
    fut.result()
asyncio.exceptions.CancelledError

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/home/soa/batmon-ha/bmslib/__init__.py", line 81, in wait_for
    return await asyncio.wait_for(self._futures.get(name), timeout)
  File "/usr/lib/python3.9/asyncio/tasks.py", line 494, in wait_for
    raise exceptions.TimeoutError() from exc
asyncio.exceptions.TimeoutError

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/soa/batmon-ha/bmslib/sampling.py", line 148, in __call__
    s = await self._sample_inner()
  File "/home/soa/batmon-ha/bmslib/sampling.py", line 221, in _sample_inner
    sample = await bms.fetch()
  File "/home/soa/batmon-ha/bmslib/models/sok.py", line 121, in fetch
    buf = await self._q(cmd=0xC0)  # name
  File "/home/soa/batmon-ha/bmslib/models/sok.py", line 117, in _q
    return await self._fetch_futures.wait_for(cmd, self.TIMEOUT)
  File "/home/soa/batmon-ha/bmslib/__init__.py", line 84, in wait_for
    raise asyncio.TimeoutError("timeout waiting for %s" % name)
asyncio.exceptions.TimeoutError: timeout waiting for 192
20:42:00 INFO [sampling] Bleak version 0.13.1a1
20:42:00 WARNING [sampling] Battery 1 disconnect because no data has been flowing for some time
20:42:00 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54/service000b/char000e): ['org.bluez.GattCharacteristic1', {'Notifying': <dbus_next.signature.Variant ('b', False)>}, []]
20:42:00 DEBUG [client] Disconnecting (/org/bluez/hci0/dev_01_B6_EC_DA_91_54)
20:42:03 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54): ['org.bluez.Device1', {'ServicesResolved': <dbus_next.signature.Variant ('b', False)>}, []]
20:42:03 DEBUG [client] received D-Bus signal: org.freedesktop.DBus.Properties.PropertiesChanged (/org/bluez/hci0/dev_01_B6_EC_DA_91_54): ['org.bluez.Device1', {'Connected': <dbus_next.signature.Variant ('b', False)>}, []]
20:42:03 DEBUG [client] Device disconnected (/org/bluez/hci0/dev_01_B6_EC_DA_91_54)
20:42:03 DEBUG [client] _remove_signal_handlers(/org/bluez/hci0/dev_01_B6_EC_DA_91_54)
20:42:03 DEBUG [client] _disconnect_message_bus(/org/bluez/hci0/dev_01_B6_EC_DA_91_54)
20:42:03 WARNING [bt] BMS SokBt(01:B6:EC:DA:91:54,Battery 1) disconnected after 64.1s!
20:42:03 ERROR [main] 1 exceptions occurred fetching BMSs
20:42:03 ERROR [main] Error (num 1, max 0) reading BMS: timeout waiting for 192
20:42:03 ERROR [main] Stack: Traceback (most recent call last):
  File "/usr/lib/python3.9/asyncio/tasks.py", line 492, in wait_for
    fut.result()
asyncio.exceptions.CancelledError

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/home/soa/batmon-ha/bmslib/__init__.py", line 81, in wait_for
    return await asyncio.wait_for(self._futures.get(name), timeout)
  File "/usr/lib/python3.9/asyncio/tasks.py", line 494, in wait_for
    raise exceptions.TimeoutError() from exc
asyncio.exceptions.TimeoutError

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/soa/batmon-ha/main.py", line 43, in fetch_loop
    if await fn():
  File "/home/soa/batmon-ha/main.py", line 357, in fn
    raise exceptions[0]
  File "/home/soa/batmon-ha/main.py", line 352, in fn
    await t()
  File "/home/soa/batmon-ha/bmslib/sampling.py", line 148, in __call__
    s = await self._sample_inner()
  File "/home/soa/batmon-ha/bmslib/sampling.py", line 221, in _sample_inner
    sample = await bms.fetch()
  File "/home/soa/batmon-ha/bmslib/models/sok.py", line 121, in fetch
    buf = await self._q(cmd=0xC0)  # name
  File "/home/soa/batmon-ha/bmslib/models/sok.py", line 117, in _q
    return await self._fetch_futures.wait_for(cmd, self.TIMEOUT)
  File "/home/soa/batmon-ha/bmslib/__init__.py", line 84, in wait_for
    raise asyncio.TimeoutError("timeout waiting for %s" % name)
asyncio.exceptions.TimeoutError: timeout waiting for 192

20:42:04 INFO [main] fetch_loop <function main.<locals>.fn at 0xb58c18e0> ends
20:42:04 INFO [main] All fetch loops ended. shutdown is already 1
20:42:04 INFO [main] Disconnecting SokBt(01:B6:EC:DA:91:54,Battery 1)
20:42:04 INFO [main] exit signal handler... (), {}, shutdown was True
soa@orangepizero:~/batmon-ha$

END
@fl4p
Copy link
Owner

fl4p commented Dec 19, 2023

how far is the bms away?
timeouts are usually either protocol issues or poor BLE connection.

@Ampakinetic
Copy link
Author

Ampakinetic commented Dec 19, 2023 •
edited

Thanks for responding. In this case the BMS is about 30cm away from the BT dongle because I've got an OrangePi right next to it to also talk to the invertor.

Using bluetoothctl I can connect to the battery and query eg the manufacturer name field without problems, so it's probably not a Linux BT driver issue, and because of the short comms distance and because I can use the app to talk to it consistently, I don't think it's interference.

The batch of batteries this one came from is very new, it was straight off the boat from China. The ABC BMS app was last updated 6th Jun 2023, maybe they've changed the protocol a bit since the reverse engineering? But I don't know enough about BT to try looking at the app traffic myself.

@Ampakinetic
Copy link
Author

Is there a way I can test for a protocol or connection issue with bluetoothctl ? Does anyone else with SOK batteries have issues or does it run fine?

@fl4p
Copy link
Owner

fl4p commented Dec 23, 2023

looks like a protocol issue. someone has to reverse engineer it

@rfc5841
Copy link

rfc5841 commented Dec 31, 2023

I have this issue as well, with some SOK 48V 100ah batteries. The ABC android app also works fine just cant get it to work in batmon

@rmkilc
Copy link

rmkilc commented Mar 9, 2024

I got a SOK 24V 100Ah today and it looks like I am having the same issue. The app works fine but I am getting the same timeout waiting for 192 as OP posted. I have tried two different bluetooth dongles with them as close as touching the battery.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
4 participants
@rmkilc @fl4p @Ampakinetic @rfc5841