node-zwave-js: OTA Firmware update gets stuck after transmitting a fragment fails

2023-08-20T15:30:54.641Z DRIVER « [Node 097] [REQ] [BridgeApplicationCommand]
                                  │ RSSI: -89 dBm
                                  └─[Security2CCMessageEncapsulation]
                                    │ sequence number: 83
                                    │ security class:  S2_Authenticated
                                    └─[FirmwareUpdateMetaDataCCGet]
                                        total # of reports: 1
                                        report number:      428
2023-08-20T15:30:54.641Z CNTRLR » [Node 097] Sending firmware fragment 428 / 5900
2023-08-20T15:30:54.643Z DRIVER   all queues busy
2023-08-20T15:30:54.644Z SERIAL » 0x013c00a9000100612e9f037f00e5987a9084fd488daaa02a19f15957acb6fd165 (62 bytes)
                                  c361e4afde63b0cfd5acf8d729dd42e018e3f1e103e9f25000000000e0d
2023-08-20T15:30:54.644Z DRIVER » [Node 097] [REQ] [SendDataBridge]
                                  │ source node id:   1
                                  │ transmit options: 0x25
                                  │ callback id:      14
                                  └─[Security2CCMessageEncapsulation]
                                    │ sequence number: 127
                                    │ security class:  S2_Authenticated
                                    └─[FirmwareUpdateMetaDataCCReport]
                                        report #: 428
                                        is last:  false
2023-08-20T15:30:54.654Z SERIAL « [ACK]                                                                   (0x06)
2023-08-20T15:30:54.655Z SERIAL « 0x010401a90152                                                       (6 bytes)
2023-08-20T15:30:54.655Z SERIAL » [ACK]                                                                   (0x06)
2023-08-20T15:30:54.656Z DRIVER « [RES] [SendDataBridge]
                                    was sent: true
2023-08-20T15:30:56.806Z SERIAL « 0x011f00a80000010061119f035400291182b51ab4afaa1aa8ea294900a7007f7fc (33 bytes)
                                  a
2023-08-20T15:30:56.808Z SERIAL » [ACK]                                                                   (0x06)
2023-08-20T15:30:56.809Z DRIVER « [Node 097] [REQ] [BridgeApplicationCommand]
                                  │ RSSI: -89 dBm
                                  └─[Security2CCMessageEncapsulation]
                                    │ sequence number: 84
                                    │ security class:  S2_Authenticated
                                    └─[FirmwareUpdateMetaDataCCGet]
                                        total # of reports: 1
                                        report number:      429
2023-08-20T15:30:57.449Z SERIAL « 0x011f00a80000010061119f035400291182b51ab4afaa1aa8ea294900a7007f7fc (33 bytes)
                                  a
2023-08-20T15:30:57.451Z DRIVER   Dropping message with invalid payload
2023-08-20T15:30:57.452Z DRIVER « [Node 097] [REQ] [BridgeApplicationCommand]
                                  │ RSSI: -89 dBm
                                  └─[Security2CCMessageEncapsulation] [INVALID]
                                      error: Duplicate command (sequence number 84)
2023-08-20T15:30:57.452Z SERIAL » [ACK]                                                                   (0x06)
2023-08-20T15:30:58.135Z SERIAL « 0x011f00a80000010061119f035400291182b51ab4afaa1aa8ea294900a7007f7fc (33 bytes)
                                  a
2023-08-20T15:30:58.136Z DRIVER   Dropping message with invalid payload
2023-08-20T15:30:58.136Z DRIVER « [Node 097] [REQ] [BridgeApplicationCommand]
                                  │ RSSI: -89 dBm
                                  └─[Security2CCMessageEncapsulation] [INVALID]
                                      error: Duplicate command (sequence number 84)
2023-08-20T15:30:58.136Z SERIAL » [ACK]                                                                   (0x06)
2023-08-20T15:31:09.620Z SERIAL « 0x011f00a80000010061119f035400291182b51ab4afaa1aa8ea294900a6007f7fc (33 bytes)
                                  b
2023-08-20T15:31:09.623Z DRIVER   Dropping message with invalid payload
2023-08-20T15:31:09.623Z DRIVER « [Node 097] [REQ] [BridgeApplicationCommand]
                                  │ RSSI: -90 dBm
                                  └─[Security2CCMessageEncapsulation] [INVALID]
                                      error: Duplicate command (sequence number 84)
2023-08-20T15:31:09.623Z SERIAL » [ACK]                                                                   (0x06)
2023-08-20T15:31:12.703Z SERIAL « 0x011d00a90e0106ed007f7f7f7f7f000107000000000220b461000000000021    (31 bytes)
2023-08-20T15:31:12.704Z SERIAL » [ACK]                                                                   (0x06)
2023-08-20T15:31:12.705Z DRIVER « [REQ] [SendDataBridge]
                                    callback id:            14
                                    transmit status:        NoAck, took 17730 ms
                                    routing attempts:       32
                                    protocol & route speed: Z-Wave, 40 kbit/s
                                    TX channel no.:         1
                                    route failed here:      180 -> 97
                                    TX power:               0 dBm
                                    measured noise floor:   0 dBm
2023-08-20T15:31:12.708Z SERIAL » 0x013c00a9000100612e9f037f00e5987a9084fd488daaa02a19f15957acb6fd165 (62 bytes)
                                  c361e4afde63b0cfd5acf8d729dd42e018e3f1e103e9f25000000000e0d
2023-08-20T15:31:12.709Z DRIVER » [Node 097] [REQ] [SendDataBridge]
                                  │ source node id:   1
                                  │ transmit options: 0x25
                                  │ callback id:      14
                                  └─[Security2CCMessageEncapsulation]
                                    │ sequence number: 127
                                    │ security class:  S2_Authenticated
                                    └─[FirmwareUpdateMetaDataCCReport]
                                        report #: 428
                                        is last:  false
2023-08-20T15:31:12.721Z SERIAL « [ACK]                                                                   (0x06)
2023-08-20T15:31:12.722Z SERIAL « 0x010401a90152                                                       (6 bytes)
2023-08-20T15:31:12.722Z SERIAL » [ACK]                                                                   (0x06)
2023-08-20T15:31:12.722Z DRIVER « [RES] [SendDataBridge]
                                    was sent: true
2023-08-20T15:31:12.758Z SERIAL « 0x011d00a90e00000301a79f7f7f7f0000030a000000030100007f7f7f7f7f74    (31 bytes)
2023-08-20T15:31:12.758Z SERIAL » [ACK]                                                                   (0x06)
2023-08-20T15:31:12.759Z DRIVER « [REQ] [SendDataBridge]
                                    callback id:            14
                                    transmit status:        OK, took 30 ms
                                    repeater node IDs:      10
                                    routing attempts:       1
                                    protocol & route speed: Z-Wave, 100 kbit/s
                                    ACK RSSI:               -89 dBm
                                    ACK RSSI on repeaters:  -97 dBm
                                    ACK channel no.:        0
                                    TX channel no.:         0
2023-08-20T15:31:12.760Z DRIVER   all queues idle
2023-08-20T15:31:17.762Z DRIVER   all queues busy
2023-08-20T15:31:17.764Z SERIAL » 0x0103003bc7                                                         (5 bytes)
2023-08-20T15:31:17.765Z DRIVER » [REQ] [GetBackgroundRSSI]
2023-08-20T15:31:17.770Z SERIAL « [ACK]                                                                   (0x06)
2023-08-20T15:31:17.770Z SERIAL « 0x0107013b9192927f2c                                                 (9 bytes)
2023-08-20T15:31:17.771Z SERIAL » [ACK]                                                                   (0x06)
2023-08-20T15:31:17.772Z DRIVER « [RES] [GetBackgroundRSSI]
                                    channel 0: -111 dBm
                                    channel 1: -110 dBm
                                    channel 2: -110 dBm
2023-08-20T15:31:17.773Z DRIVER   all queues idle
2023-08-20T15:31:19.874Z SERIAL « 0x011f00a80000010061119f035500eafdc86c3b85798d69c77dc5ce00a7007f7f7 (33 bytes)
                                  6
2023-08-20T15:31:19.877Z SERIAL » [ACK]                                                                   (0x06)
2023-08-20T15:31:19.878Z DRIVER « [Node 097] [REQ] [BridgeApplicationCommand]
                                  │ RSSI: -89 dBm
                                  └─[Security2CCMessageEncapsulation]
                                    │ sequence number: 85
                                    │ security class:  S2_Authenticated
                                    └─[FirmwareUpdateMetaDataCCGet]
                                        total # of reports: 1
                                        report number:      429
2023-08-20T15:31:32.174Z SERIAL « 0x011f00a80000010061119f0356002e61728a370b837ff1ac260e4c00a7007f7f1 (33 bytes)
                                  a
2023-08-20T15:31:32.177Z SERIAL » [ACK]                                                                   (0x06)
2023-08-20T15:31:32.178Z DRIVER « [Node 097] [REQ] [BridgeApplicationCommand]
                                  │ RSSI: -89 dBm
                                  └─[Security2CCMessageEncapsulation]
                                    │ sequence number: 86
                                    │ security class:  S2_Authenticated
                                    └─[FirmwareUpdateMetaDataCCGet]
                                        total # of reports: 1
                                        report number:      429
2023-08-20T15:31:44.485Z SERIAL « 0x011f00a80000010061119f03570016b11cea662b61d4b93a8a682400a7007f7fb (33 bytes)
                                  9
2023-08-20T15:31:44.487Z SERIAL » [ACK]                                                                   (0x06)
2023-08-20T15:31:44.488Z DRIVER « [Node 097] [REQ] [BridgeApplicationCommand]
                                  │ RSSI: -89 dBm
                                  └─[Security2CCMessageEncapsulation]
                                    │ sequence number: 87
                                    │ security class:  S2_Authenticated
                                    └─[FirmwareUpdateMetaDataCCGet]
                                        total # of reports: 1
                                        report number:      429
2023-08-20T15:31:47.763Z DRIVER   all queues busy
2023-08-20T15:31:47.765Z SERIAL » 0x0103003bc7                                                         (5 bytes)
2023-08-20T15:31:47.765Z DRIVER » [REQ] [GetBackgroundRSSI]
2023-08-20T15:31:47.770Z SERIAL « [ACK]                                                                   (0x06)
2023-08-20T15:31:47.771Z SERIAL « 0x0107013b9093937f2d                                                 (9 bytes)
2023-08-20T15:31:47.771Z SERIAL » [ACK]                                                                   (0x06)
2023-08-20T15:31:47.772Z DRIVER « [RES] [GetBackgroundRSSI]
                                    channel 0: -112 dBm
                                    channel 1: -109 dBm
                                    channel 2: -109 dBm
2023-08-20T15:31:47.774Z DRIVER   all queues idle
2023-08-20T15:31:56.785Z SERIAL « 0x011f00a80000010061119f0358009756a138f98391cd15d0eb8b1100a7007f7f9 (33 bytes)
                                  0
2023-08-20T15:31:56.788Z SERIAL » [ACK]                                                                   (0x06)
2023-08-20T15:31:56.789Z DRIVER « [Node 097] [REQ] [BridgeApplicationCommand]
                                  │ RSSI: -89 dBm
                                  └─[Security2CCMessageEncapsulation]
                                    │ sequence number: 88
                                    │ security class:  S2_Authenticated
                                    └─[FirmwareUpdateMetaDataCCGet]
                                        total # of reports: 1
                                        report number:      429

About this issue

  • Original URL
  • State: closed
  • Created 10 months ago
  • Comments: 20 (7 by maintainers)

Most upvoted comments

Thanks for the feedback, but no need to comment further, as this just adds additional noise.

I am on latest stable HA (2023.8.4), but zwave js is version 11.13.0. I assume it will be included in the next (September, 2023.09) release. Will test as soon as I have the new release.

Z-Wave JS versions are independent of the HA version. If you’re using the core Z-Wave JS add-on, you can upgrade it now and have the fix. If you are using a different add-on (Z-Wave JS UI), you’ll have to wait for it to update. The 2023.9 HA release won’t include anything.

Thanks for the reply.

I am on latest stable HA (2023.8.4), but zwave js is version 11.13.0. I assume it will be included in the next (September, 2023.09) release. Will test as soon as I have the new release.