genielabs / HomeGenie

HomeGenie, the programmable automation intelligence
https://homegenie.it
GNU General Public License v3.0
403 stars 157 forks source link

ZigBee Controller Not Working (SONOFF ZBDongle-P) #472

Open alphaDev23 opened 10 months ago

alphaDev23 commented 10 months ago

Using homegenie_1.4.0-beta.64_linux-x64.zip on an Ubuntu 20.04 and a SONOFF Zigbee 3.0 USB Dongle Plus Gateway, Universal Zigbee USB Gateway. The ZigBee device appears as a 'Bus 003 Device 100: ID 10c4:ea60 Silicon Labs CP210x UART Bridge' device on the system. The selected driver is 'Ember (Silcon Labs)' but the controller remains in an inactive state (red).

modinfo cp210x
filename:       /lib/modules/5.4.0-155-generic/kernel/drivers/usb/serial/cp210x.ko
license:        GPL v2
description:    Silicon Labs CP210x RS232 serial adaptor driver
srcversion:     4797AC2D722D7970DB8A4DD

Is there a HomeGenie package that needs to additionally be installed, is this a driver issue, or other?

genemars commented 10 months ago

Hi, do you know what controller version is? ZBDongle-P or ZBDongle-E?

The first one requires the Texas Instruments driver to be selected.
If it's the Ember one, then I just read on the Sonoff website that it requires software flow control set. In that case I'll try to add in the next release a UI option to select the baud rate and flow control that cannot currently be modified and are set to 115200 and None respectively.

alphaDev23 commented 10 months ago

Controller version is ZBDongle-P. When I select the device port and then the driver (CC25XX), the UI does not connect to the driver (wait icon circles without returning and when I refresh the screen, the red indicator remains).

Thoughts?

genemars commented 10 months ago

Maybe is a permissions issue or this kind of dongle not compatible with HG driver. Anyway I've just added the "flow control" option to the settings page.

alphaDev23 commented 10 months ago

I'm running 1.4 in a docker container with "privileged" set to true, so it should not be a permissions issue. The updated flow control is in "v1.4.0-beta.65"? If flow control needs to be adjusted, what are or where do I find these settings? I'm unfamiliar with the technical details of ZigBee.

genemars commented 10 months ago

Same place where you set the port and the driver to use.

Screenshot_20240111-233419376.jpg

alphaDev23 commented 10 months ago

I tried none, hardware, and software control with the CC25XX driver and it would not come back connected. That is, it exhibited the same behavior as the original post.

genemars commented 10 months ago

This is the library that HomeGenie is using for ZigBee I/O: https://github.com/Mr-Markus/ZigbeeNet It has no updates in the last 3 years, so I guess Sonoff dongle is not supported.

genemars commented 10 months ago

I've removed the flow control option from the UI to keep things simple since most controllers works with the default option.

But just in case someone needs to change it, the fc option along with a new one for baud rate can be changed editing the system_config.xml file directly.

Schermata del 2024-01-13 20-22-36

Flow control options value can be one of the following:

0 = None (default)
1 = XON/XOFF (Software)
2 = RTS/CTS (Hardware)
alphaDev23 commented 10 months ago

I found a ZigBee controller that uses EFR32MG21 and software control. Will this work with HomeGenie? See also, https://www.amazon.com/Wireless-Universal-Assistant-ZBDongle-EFR32MG21/dp/B0BG1LJ687/ref=sr_1_28?crid=3RDCLH3CNKS7&dib=eyJ2IjoiMSJ9.xfFEitihTiGU5eql5n0JRWkuPaxfiSOueUI0VPkKb5YX5ltFpAtQDE_87bjTOkJqRmbqEbyw3JD7iuM9anT1ColSX_qqs5vfS5eZbDJk3Ah9JppiiSmarIqWQtUkqnkxFDBjUtTn9aLjB4pXb9xiyw.MzoIM4KYGmxkketQBs5Pjzfr2EnB1tyVMd5fE-mI_oc&dib_tag=se&keywords=zigbee%2Bcontroller%2Busb&qid=1705201457&sprefix=zigbee%2Bcontr%2Caps%2C140&sr=8-28&th=1

genemars commented 10 months ago

Don't know if it will work because I never tried it. The notes on the ZigBeeNet repository say it should work (knowing the proper settings). I am currently using a ConBee II dongle which I can tell it works pretty well.

alphaDev23 commented 10 months ago

ConBee II works in HG but does not pair with the Sonoff ZigBee iPlug. Shouldn't it?

genemars commented 10 months ago

ConBee II works in HG but does not pair with the Sonoff ZigBee iPlug. Shouldn't it?

Was the iPlug already paired with another controller? If so, first you have to unpair/reset it.

alphaDev23 commented 10 months ago

It was not. The iPlug was straight out of the box.

genemars commented 10 months ago

Maybe you can enable logging from maintenance page and see if any relevant message pops up in the console, you should see at least ZigBee messages coming from the device.

alphaDev23 commented 10 months ago

I filtered the logs on |grep -i zigbee and there was nothing that came back from the device after putting the device into pairing mode. Here are the relevant logs from HomeGenie:

2024-01-22 04:07:48.2022 Info WebServiceGateway 172.21.17.1 HTTP    GET 200 /api/HomeAutomation.ZigBee/0/Controller.NodeAdd [OPEN]
2024-01-22 04:08:48.2168 Info WebServiceGateway 172.21.17.1 HTTP    GET 200 /api/HomeAutomation.ZigBee/0/Controller.NodeAdd [CLOSED AFTER 60.015 seconds]
genemars commented 10 months ago

The messages you reported are messages from the webservice about the API commands send by the client.

ZigBee messages looks more like:

024-01-22 13:01:29.9023 Debug ManagementRoutingResponse [25643/0 -> 0/0, cluster=32818, transId=71, Status=SUCCESS, RoutingTableEntries=0, StartIndex=0, RoutingTableList=]
2024-01-22 13:01:29.9467 Debug ManagementLqiResponse [50131/0 -> 0/0, cluster=32817, transId=72, Status=SUCCESS, NeighborTableEntries=6, StartIndex=4, NeighborTableList=NeighborTable [extendedPanId=00212EFFFF0597A0, extendedAddress=A4C1388E0E7A68BE, networkAddress=12035, deviceType=ROUTER, rxOnWhenIdle=RX_ON, relationship=SIBLING, permitJoining=UNKNOWN, depth=1, lqi=0], NeighborTable [extendedPanId=00212EFFFF0597A0, extendedAddress=B4E3F9FFFE5378EA, networkAddress=23759, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=CHILD, permitJoining=UNKNOWN, depth=2, lqi=42]]
2024-01-22 13:01:29.9830 Debug ManagementRoutingResponse [42634/0 -> 0/0, cluster=32818, transId=73, Status=SUCCESS, RoutingTableEntries=70, StartIndex=15, RoutingTableList=RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0]]
2024-01-22 13:01:29.9980 Debug ManagementLqiResponse [50131/0 -> 0/0, cluster=32817, transId=72, Status=SUCCESS, NeighborTableEntries=6, StartIndex=4, NeighborTableList=NeighborTable [extendedPanId=00212EFFFF0597A0, extendedAddress=A4C1388E0E7A68BE, networkAddress=12035, deviceType=ROUTER, rxOnWhenIdle=RX_ON, relationship=SIBLING, permitJoining=UNKNOWN, depth=1, lqi=0], NeighborTable [extendedPanId=00212EFFFF0597A0, extendedAddress=B4E3F9FFFE5378EA, networkAddress=23759, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=CHILD, permitJoining=UNKNOWN, depth=2, lqi=42]]
2024-01-22 13:01:29.9980 Debug NetworkAddressResponse [25643/0 -> 0/0, cluster=32768, transId=74, Status=SUCCESS, IeeeAddrRemoteDev=0017880100B1A9A7, NwkAddrRemoteDev=25643, StartIndex=0, NwkAddrAssocDevList=]
2024-01-22 13:01:30.0395 Debug ManagementRoutingResponse [42634/0 -> 0/0, cluster=32818, transId=76, Status=SUCCESS, RoutingTableEntries=70, StartIndex=30, RoutingTableList=RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0]]
2024-01-22 13:01:30.0722 Debug ManagementRoutingResponse [42634/0 -> 0/0, cluster=32818, transId=78, Status=SUCCESS, RoutingTableEntries=70, StartIndex=45, RoutingTableList=RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0]]
2024-01-22 13:01:30.0898 Debug NetworkAddressResponse [25643/0 -> 0/0, cluster=32768, transId=77, Status=SUCCESS, IeeeAddrRemoteDev=0017880100B1A9A7, NwkAddrRemoteDev=25643, StartIndex=0, NwkAddrAssocDevList=]
2024-01-22 13:01:30.1028 Debug ManagementRoutingResponse [42634/0 -> 0/0, cluster=32818, transId=79, Status=SUCCESS, RoutingTableEntries=70, StartIndex=60, RoutingTableList=RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=False, manyToOne=False, routeRecordRequired=False, nextHopAddress=0]]
2024-01-22 13:01:31.7401 Debug NetworkAddressResponse [42634/0 -> 0/0, cluster=32768, transId=80, Status=SUCCESS, IeeeAddrRemoteDev=CCCCCCFFFED460E1, NwkAddrRemoteDev=42634, StartIndex=0, NwkAddrAssocDevList=]
2024-01-22 13:01:31.7779 Debug NetworkAddressResponse [42634/0 -> 0/0, cluster=32768, transId=81, Status=SUCCESS, IeeeAddrRemoteDev=CCCCCCFFFED460E1, NwkAddrRemoteDev=42634, StartIndex=0, NwkAddrAssocDevList=]
2024-01-22 13:01:37.5073 Debug NetworkAddressRequest [0/0 -> 65533/0, cluster=0, transId=50, IeeeAddr=A4C1388E0E7A68BE, RequestType=0, StartIndex=0]
2024-01-22 13:01:37.7976 Debug ManagementLqiResponse [65055/0 -> 0/0, cluster=32817, transId=83, Status=SUCCESS, NeighborTableEntries=7, StartIndex=0, NeighborTableList=NeighborTable [extendedPanId=00212EFFFF0597A0, extendedAddress=00212EFFFF0597A0, networkAddress=0, deviceType=COORDINATOR, rxOnWhenIdle=RX_ON, relationship=SIBLING, permitJoining=ENABLED, depth=1, lqi=84], NeighborTable [extendedPanId=00212EFFFF0597A0, extendedAddress=A4C13846AB61C261, networkAddress=50131, deviceType=ROUTER, rxOnWhenIdle=RX_ON, relationship=SIBLING, permitJoining=ENABLED, depth=1, lqi=76], NeighborTable [extendedPanId=00212EFFFF0597A0, extendedAddress=CCCCCCFFFED460E1, networkAddress=42634, deviceType=ROUTER, rxOnWhenIdle=RX_ON, relationship=SIBLING, permitJoining=ENABLED, depth=1, lqi=62]]
2024-01-22 13:01:37.8445 Debug ManagementLqiResponse [65055/0 -> 0/0, cluster=32817, transId=84, Status=SUCCESS, NeighborTableEntries=7, StartIndex=3, NeighborTableList=NeighborTable [extendedPanId=00212EFFFF0597A0, extendedAddress=00178801087005A3, networkAddress=55833, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=PREVIOUS_CHILD, permitJoining=ENABLED, depth=1, lqi=82], NeighborTable [extendedPanId=00212EFFFF0597A0, extendedAddress=0017880100B1A9A7, networkAddress=25643, deviceType=ROUTER, rxOnWhenIdle=RX_ON, relationship=SIBLING, permitJoining=ENABLED, depth=1, lqi=50], NeighborTable [extendedPanId=00212EFFFF0597A0, extendedAddress=B4E3F9FFFE5378EA, networkAddress=23759, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=PREVIOUS_CHILD, permitJoining=ENABLED, depth=1, lqi=10]]

so you can grep for "cluster=" or "transId=". Btw, at this point I suspect that even if HG says the controller is recognized, is it indeed not accessible for some reason.

So, please do a dmesg to find out what device port is assigned to the dongle and then do a ls -la <device_port> just to ensure that permissions are valid for the user/group that is running homegenie.

Anyway this is what I see when I disable and then re-enable the dongle:

2024-01-22 13:09:24.2737 Info WebServiceGateway 10.101.101.10    HTTP    GET     200 /api/HomeAutomation.HomeGenie/Config/System.Configure/System.Info [CLOSED AFTER 0,016 seconds]
2024-01-22 13:09:28.1242 Debug Disabling Interface HomeAutomation.ZigBee
2024-01-22 13:09:28.2169 Info MIGService.Interfaces     HomeAutomation.ZigBee   MIG Interface   Status.IsEnabled        0
2024-01-22 13:09:34.6242 Debug Enabling Interface HomeAutomation.ZigBee
2024-01-22 13:09:35.2733 Info MIGService.Interfaces     HomeAutomation.ZigBee   MIG Interface   Status.IsEnabled        1
2024-01-22 13:09:35.2774 Info HomeAutomation.ZigBee     0       ZigBee Controller       Controller.Status       Added node 00178801087XXXXX
2024-01-22 13:09:35.2774 Info HomeAutomation.ZigBee     0       ZigBee Controller       Controller.Status       Added node 00124B00246XXXXX
2024-01-22 13:09:35.2995 Info HomeAutomation.ZigBee     0       ZigBee Controller       Controller.Status       Added node 0015BC001A0XXXXX
2024-01-22 13:09:35.2995 Debug ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=32822, transId=115, Status=SUCCESS]
2024-01-22 13:09:35.5840 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints    7
2024-01-22 13:09:35.5948 Info HomeAutomation.ZigBee     00178801087XXXXX        ZigBee Node     ZigBeeNode.Endpoints    2
2024-01-22 13:09:35.5948 Debug ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=32822, transId=117, Status=SUCCESS]
2024-01-22 13:09:35.6216 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.0.ID       1
2024-01-22 13:09:35.6246 Info HomeAutomation.ZigBee     00124B00246XXXXX        ZigBee Node     ZigBeeNode.Endpoints    1
2024-01-22 13:09:35.6246 Info HomeAutomation.ZigBee     00178801087XXXXX        ZigBee Node     ZigBeeNode.Endpoints.0.ID       1
2024-01-22 13:09:35.6246 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.0.3        Identify
2024-01-22 13:09:35.6246 Info HomeAutomation.ZigBee     00124B00246XXXXX        ZigBee Node     ZigBeeNode.Endpoints.0.ID       1
2024-01-22 13:09:35.6246 Info HomeAutomation.ZigBee     00178801087XXXXX        ZigBee Node     ZigBeeNode.Endpoints.0.0        Basic
2024-01-22 13:09:35.6799 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.0.5        Scenes
2024-01-22 13:09:35.6799 Info HomeAutomation.ZigBee     00124B00246XXXXX        ZigBee Node     ZigBeeNode.Endpoints.0.0        Basic
2024-01-22 13:09:35.6839 Info HomeAutomation.ZigBee     00178801087XXXXX        ZigBee Node     ZigBeeNode.Endpoints.1.ID       2
2024-01-22 13:09:35.6839 Info HomeAutomation.ZigBee     00124B00246XXXXX        ZigBee Node     ZigBeeNode.Endpoints.0.1        Power Configuration
2024-01-22 13:09:35.6968 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.0.6        On/Off
2024-01-22 13:09:35.6968 Info HomeAutomation.ZigBee     00178801087XXXXX        ZigBee Node     ZigBeeNode.Endpoints.1.0        Basic
2024-01-22 13:09:35.6968 Info HomeAutomation.ZigBee     00124B00246XXXXX        ZigBee Node     ZigBeeNode.Endpoints.0.3        Identify
2024-01-22 13:09:35.7367 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.1.ID       34
2024-01-22 13:09:35.7367 Info HomeAutomation.ZigBee     00124B00246XXXXX        ZigBee Node     ZigBeeNode.Endpoints.0.1280     IAS Zone
2024-01-22 13:09:35.7469 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.1.0        Basic
2024-01-22 13:09:35.7469 Info HomeAutomation.ZigBee     00178801087XXXXX        ZigBee Node     ZigBeeNode.Endpoints.1.1        Power Configuration
2024-01-22 13:09:35.7561 Info HomeAutomation.ZigBee     00178801087XXXXX        ZigBee Node     ZigBeeNode.Endpoints.1.64512    Custom Cluster #64512
2024-01-22 13:09:35.7561 Info HomeAutomation.ZigBee     00178801087XXXXX        ZigBee Node     ZigBeeNode.Endpoints.1.3        Identify
2024-01-22 13:09:35.7637 Info HomeAutomation.ZigBee     00178801087XXXXX        ZigBee Node     ZigBeeNode.Endpoints.1.15       Binary Input (Basic)
2024-01-22 13:09:35.7637 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.1.3        Identify
2024-01-22 13:09:35.7637 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.1.1030     Occupancy Sensing
2024-01-22 13:09:35.7637 Info HomeAutomation.ZigBee
2024-01-22 13:09:35.7740 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.2.ID       35
2024-01-22 13:09:35.7740 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.2.0        Basic
2024-01-22 13:09:35.7884 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.2.32       Poll Control
2024-01-22 13:09:35.8028 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.2.1        Power Configuration
2024-01-22 13:09:35.8134 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.2.3        Identify
2024-01-22 13:09:35.9661 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.2.1280     IAS Zone
2024-01-22 13:09:36.0013 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.2.15       Binary Input (Basic)
2024-01-22 13:09:36.0091 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.3.ID       38
2024-01-22 13:09:36.0091 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.3.0        Basic
2024-01-22 13:09:36.0139 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.3.1026     Temperature Measurement
2024-01-22 13:09:36.0139 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.3.3        Identify
2024-01-22 13:09:36.0139 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.4.ID       39
2024-01-22 13:09:36.0139 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.4.0        Basic
2024-01-22 13:09:36.0245 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.4.1024     Illuminance Measurement
2024-01-22 13:09:36.0245 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.4.3        Identify
2024-01-22 13:09:36.0245 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.5.ID       40
2024-01-22 13:09:36.0245 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.5.0        Basic
2024-01-22 13:09:36.0341 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.5.3        Identify
2024-01-22 13:09:36.0341 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.5.1030     Occupancy Sensing
2024-01-22 13:09:36.0341 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.6.ID       41
2024-01-22 13:09:36.0341 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.6.0        Basic
2024-01-22 13:09:36.0445 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.6.3        Identify
2024-01-22 13:09:36.0445 Info HomeAutomation.ZigBee     0015BC001A0XXXXX        ZigBee Node     ZigBeeNode.Endpoints.6.1030     Occupancy Sensing
2024-01-22 13:09:36.5498 Info HomeAutomation.ZigBee
2024-01-22 13:09:38.0040 Debug NetworkAddressResponse [50131/0 -> 0/0, cluster=32768, transId=118, Status=SUCCESS, IeeeAddrRemoteDev=A4C13846ABXXXXX, NwkAddrRemoteDev=50131, StartIndex=0, NwkAddrAssocDevList=]
2024-01-22 13:09:39.1408 Debug NetworkAddressResponse [50131/0 -> 0/0, cluster=32768, transId=122, Status=SUCCESS, IeeeAddrRemoteDev=B4E3F9FFFE5XXXXX, NwkAddrRemoteDev=23759, StartIndex=0, NwkAddrAssocDevList=]
2024-01-22 13:09:39.2462 Debug NetworkAddressResponse [42634/0 -> 0/0, cluster=32768, transId=123, Status=SUCCESS, IeeeAddrRemoteDev=0015BC001A0XXXXX, NwkAddrRemoteDev=63099, StartIndex=0, NwkAddrAssocDevList=]
2024-01-22 13:09:39.3272 Debug NetworkAddressResponse [42634/0 -> 0/0, cluster=32768, transId=124, Status=SUCCESS, IeeeAddrRemoteDev=CCCCCCFFFEXXXXX, NwkAddrRemoteDev=42634, StartIndex=0, NwkAddrAssocDevList=]
2024-01-22 13:09:39.3363 Debug NetworkAddressResponse [25643/0 -> 0/0, cluster=32768, transId=125, Status=SUCCESS, IeeeAddrRemoteDev=0017880100XXXXX, NwkAddrRemoteDev=25643, StartIndex=0, NwkAddrAssocDevList=]
2024-01-22 13:09:41.3785 Debug NetworkAddressResponse [42634/0 -> 0/0, cluster=32768, transId=128, Status=SUCCESS, IeeeAddrRemoteDev=0017880108XXXXX, NwkAddrRemoteDev=55833, StartIndex=0, NwkAddrAssocDevList=]
2024-01-22 13:09:43.2965 Debug NetworkAddressRequest [0/0 -> 65533/0, cluster=0, transId=69, IeeeAddr=00124B0024XXXXX, RequestType=0, StartIndex=0]
2024-01-22 13:09:44.0164 Debug NetworkAddressRequest [0/0 -> 65533/0, cluster=0, transId=70, IeeeAddr=00124B0024XXXXX, RequestType=0, StartIndex=0]
2024-01-22 13:09:46.2388 Debug NetworkAddressResponse [50131/0 -> 0/0, cluster=32768, transId=141, Status=SUCCESS, IeeeAddrRemoteDev=A4C13846AB6XXXXX, NwkAddrRemoteDev=50131, StartIndex=0, NwkAddrAssocDevList=]
2024-01-22 13:09:47.3891 Debug NetworkAddressResponse [25643/0 -> 0/0, cluster=32768, transId=142, Status=SUCCESS, IeeeAddrRemoteDev=0017880100BXXXXX, NwkAddrRemoteDev=25643, StartIndex=0, NwkAddrAssocDevList=]
2024-01-22 13:09:51.2925 Debug NetworkAddressRequest [0/0 -> 65533/0, cluster=0, transId=71, IeeeAddr=00124B00246XXXXX, RequestType=0, StartIndex=0]
2024-01-22 13:09:51.7841 Debug NetworkAddressRequest [0/0 -> 65533/0, cluster=0, transId=72, IeeeAddr=00124B00246XXXXX, RequestType=0, StartIndex=0]
2024-01-22 13:09:52.3957 Debug NetworkAddressResponse [65055/0 -> 0/0, cluster=32768, transId=147, Status=SUCCESS, IeeeAddrRemoteDev=7CB03EAA00AXXXXX, NwkAddrRemoteDev=65055, StartIndex=0, NwkAddrAssocDevList=]
2024-01-22 13:09:53.4321 Debug NetworkAddressResponse [65055/0 -> 0/0, cluster=32768, transId=150, Status=SUCCESS, IeeeAddrRemoteDev=7CB03EAA00AXXXXX, NwkAddrRemoteDev=65055, StartIndex=0, NwkAddrAssocDevList=]

when you enable the ZigBee interface and when you start the node add process you should see messages containing ManagementPermitJoiningResponse and NetworkAddressResponse events.

alphaDev23 commented 10 months ago

I'm not getting any messages that indicates that the iPlug is attempting to connect when in pairing mode:

2024-01-22 04:07:38.5392 Debug ManagementLqiResponse [0/0 -> 0/0, cluster=32817, transId=18, Status=SUCCESS, NeighborTableEntries=0, StartIndex=0, NeighborTableList=]
2024-01-22 04:07:38.5590 Debug ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=32822, transId=17, Status=SUCCESS]
2024-01-22 04:07:48.2146 Debug ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=32822, transId=19, Status=SUCCESS]
2024-01-22 04:08:48.2264 Debug ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=32822, transId=20, Status=SUCCESS]
2024-01-22 04:09:19.6240 Debug ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=32822, transId=21, Status=SUCCESS]
2024-01-22 04:10:19.6287 Debug ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=32822, transId=22, Status=SUCCESS]
2024-01-22 16:00:35.9828 Debug ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=32822, transId=23, Status=SUCCESS]
2024-01-22 16:01:35.9946 Debug ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=32822, transId=24, Status=SUCCESS]

Here are the following permissions for the device; however, the docker container is running as privileged so I don't believe that these permissions would affect access.

ls -alh /dev/ttyACM0 
crw-rw---- 1 root dialout 166, 0 Jan 18 21:02 /dev/ttyACM0

.

alphaDev23 commented 9 months ago

Any thoughts on my last message on how to fix?

alphaDev23 commented 9 months ago

@genemars Please advise.

alphaDev23 commented 9 months ago

@genemars Please advise.

genemars commented 9 months ago

@genemars Please advise.

Try running docker like this:

https://github.com/moby/moby/issues/43019#issuecomment-1181185952