18:50:09.193 DRIVER ███████╗ ██╗ ██╗ █████╗ ██╗ ██╗ ███████╗ ██╗ ███████╗ ╚══███╔╝ ██║ ██║ ██╔══██╗ ██║ ██║ ██╔════╝ ██║ ██╔════╝ ███╔╝ ██║ █╗ ██║ ███████║ ██║ ██║ █████╗ █████╗ ██║ ███████╗ ███╔╝ ██║███╗██║ ██╔══██║ ╚██╗ ██╔╝ ██╔══╝ ╚════╝ ██ ██║ ╚════██║ ███████╗ ╚███╔███╔╝ ██║ ██║ ╚████╔╝ ███████╗ ╚█████╔╝ ███████║ ╚══════╝ ╚══╝╚══╝ ╚═╝ ╚═╝ ╚═══╝ ╚══════╝ ╚════╝ ╚══════╝ 18:50:09.197 DRIVER version 2.11.0 18:50:09.198 DRIVER 18:50:09.199 DRIVER starting driver... 18:50:09.199 DRIVER opening serial port /dev/ttyACM0 18:50:09.218 DRIVER serial port opened 18:50:09.224 SERIAL » [NAK] (0x15) 18:50:10.728 DRIVER loading configuration... 18:50:11.132 DRIVER beginning interview... 18:50:11.150 SERIAL » 0x01030015e9 (5 bytes) 18:50:11.134 DRIVER added request handler for AddNodeToNetwork (0x4a)... 1 registered 18:50:11.135 DRIVER added request handler for RemoveNodeFromNetwork (0x4b)... 1 registered 18:50:11.148 DRIVER workOffSendQueue > sending next message (GetControllerVersion)... 18:50:11.141 CNTRLR beginning interview... 18:50:11.142 CNTRLR querying version info... 18:50:11.161 SERIAL « [ACK] (0x06) 18:50:11.164 DRIVER ACK received from controller for current transaction 18:50:11.180 CNTRLR received version info: controller type: Static Controller library version: Z-Wave 4.38 18:50:11.167 SERIAL « 0x06011001155a2d5761766520342e3338000199 (19 bytes) 18:50:11.169 SERIAL Buffer := 0x011001155a2d5761766520342e3338000199 (18 bytes) 18:50:11.172 SERIAL » [ACK] (0x06) 18:50:11.185 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:11.189 SERIAL » 0x01030020dc (5 bytes) 18:50:11.176 DRIVER « [RES] [GetControllerVersion] [final] 18:50:11.178 DRIVER ACK already received, resolving transaction 18:50:11.179 DRIVER resuming send queue 18:50:11.188 DRIVER workOffSendQueue > sending next message (GetControllerId)... 18:50:11.190 DRIVER The send queue is empty 18:50:11.182 CNTRLR querying controller IDs... 18:50:11.204 DRIVER handling application command request Battery (0x80) for node 3 18:50:11.204 DRIVER the node is unknown or not initialized yet, discarding... 18:50:11.192 SERIAL « 0x0109000400030380033342 (11 bytes) 18:50:11.193 SERIAL Buffer := 0x0109000400030380033342 (11 bytes) 18:50:11.201 SERIAL » [ACK] (0x06) 18:50:11.205 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:11.208 DRIVER CAN received - scheduling transmission attempt (2/3) in 100 ms... 18:50:11.207 SERIAL « [CAN] (0x18) 18:50:11.209 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:11.210 SERIAL « 0x01 (1 bytes) 18:50:11.211 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:11.217 DRIVER Dropping message because the driver is not ready to handle it yet. 18:50:11.212 SERIAL « 0x0c0004000306430301420802fb (13 bytes) 18:50:11.213 SERIAL Buffer := 0x010c0004000306430301420802fb (14 bytes) 18:50:11.217 SERIAL » [ACK] (0x06) 18:50:11.218 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:11.222 SERIAL « 0x01 (1 bytes) 18:50:11.223 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:11.228 DRIVER handling application command request Climate Control Schedule (0x46) for node 3 18:50:11.224 SERIAL « 0x0a00040003044608007fc7 (11 bytes) 18:50:11.224 SERIAL Buffer := 0x010a00040003044608007fc7 (12 bytes) 18:50:11.227 SERIAL » [ACK] (0x06) 18:50:11.230 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:11.229 DRIVER the node is unknown or not initialized yet, discarding... 18:50:11.310 DRIVER » [REQ] [GetControllerId] [attempt 2/3] 18:50:11.310 SERIAL » 0x01030020dc (5 bytes) 18:50:11.315 SERIAL « [ACK] (0x06) 18:50:11.316 DRIVER ACK received from controller for current transaction 18:50:11.316 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:11.320 DRIVER « [RES] [GetControllerId] [final] 18:50:11.323 CNTRLR received controller IDs: home ID: 0xe84eb4ea own node ID: 1 18:50:11.318 SERIAL « 0x01080120e84eb4ea012f (10 bytes) 18:50:11.318 SERIAL Buffer := 0x01080120e84eb4ea012f (10 bytes) 18:50:11.320 SERIAL » [ACK] (0x06) 18:50:11.325 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:11.321 DRIVER ACK already received, resolving transaction 18:50:11.322 DRIVER resuming send queue 18:50:11.326 DRIVER workOffSendQueue > sending next message (GetControllerCapabilities)... 18:50:11.327 DRIVER The send queue is empty 18:50:11.323 CNTRLR querying controller capabilities... 18:50:11.327 SERIAL » 0x01030005f9 (5 bytes) 18:50:11.332 SERIAL « [ACK] (0x06) 18:50:11.333 DRIVER ACK received from controller for current transaction 18:50:11.334 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:11.337 DRIVER « [RES] [GetControllerCapabilities] [final] 18:50:11.340 CNTRLR received controller capabilities: controller role: primary is in other network: false is SIS present: true was real primary: true is a SUC: true 18:50:11.335 SERIAL « 0x010401051ce3 (6 bytes) 18:50:11.336 SERIAL Buffer := 0x010401051ce3 (6 bytes) 18:50:11.337 SERIAL » [ACK] (0x06) 18:50:11.341 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:11.338 DRIVER ACK already received, resolving transaction 18:50:11.338 DRIVER resuming send queue 18:50:11.343 DRIVER workOffSendQueue > sending next message (GetSerialApiCapabilities)... 18:50:11.344 DRIVER The send queue is empty 18:50:11.340 CNTRLR querying API capabilities... 18:50:11.343 SERIAL » 0x01030007fb (5 bytes) 18:50:11.349 SERIAL « [ACK] (0x06) 18:50:11.349 DRIVER ACK received from controller for current transaction 18:50:11.350 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:11.355 DRIVER « [RES] [GetSerialApiCapabilities] [final] 18:50:11.357 CNTRLR received API capabilities: serial API version: 5.7 manufacturer ID: 0x0115 product type: 0x0400 product ID: 0x01 supported functions: · GetSerialApiInitData (0x02) · FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION (0x03) · ApplicationCommand (0x04) · GetControllerCapabilities (0x05) · SetSerialApiTimeouts (0x06) · GetSerialApiCapabilities (0x07) · FUNC_ID_SERIAL_API_SOFT_RESET (0x08) · UNKNOWN_FUNC_UNKNOWN_0x09 (0x09) · UNKNOWN_FUNC_UNKNOWN_0x0a (0x0a) · UNKNOWN_FUNC_RF_RECEIVE_MODE (0x10) · UNKNOWN_FUNC_SET_SLEEP_MODE (0x11) · FUNC_ID_ZW_SEND_NODE_INFORMATION (0x12) · SendData (0x13) · UNKNOWN_FUNC_SEND_DATA_MULTI (0x14) · GetControllerVersion (0x15) · UNKNOWN_FUNC_SEND_DATA_ABORT (0x16) · FUNC_ID_ZW_R_F_POWER_LEVEL_SET (0x17) · UNKNOWN_FUNC_SEND_DATA_META (0x18) · FUNC_ID_ZW_GET_RANDOM (0x1c) · GetControllerId (0x20) · UNKNOWN_FUNC_MEMORY_GET_BYTE (0x21) · UNKNOWN_FUNC_MEMORY_PUT_BYTE (0x22) · UNKNOWN_FUNC_MEMORY_GET_BUFFER (0x23) · UNKNOWN_FUNC_MEMORY_PUT_BUFFER (0x24) · UNKNOWN_FUNC_UNKNOWN_0x27 (0x27) · UNKNOWN_FUNC_UNKNOWN_0x28 (0x28) · UNKNOWN_FUNC_UNKNOWN_0x29 (0x29) · UNKNOWN_FUNC_UNKNOWN_0x2a (0x2a) · UNKNOWN_FUNC_UNKNOWN_0x2b (0x2b) · UNKNOWN_FUNC_UNKNOWN_0x2c (0x2c) · UNKNOWN_FUNC_UNKNOWN_0x2d (0x2d) · GetNodeProtocolInfo (0x41) · HardReset (0x42) · FUNC_ID_ZW_REPLICATION_COMMAND_COMPLETE (0x44) · FUNC_ID_ZW_REPLICATION_SEND_DATA (0x45) · FUNC_ID_ZW_ASSIGN_RETURN_ROUTE (0x46) · FUNC_ID_ZW_DELETE_RETURN_ROUTE (0x47) · RequestNodeNeighborUpdate (0x48) · ApplicationUpdateRequest (0x49) · AddNodeToNetwork (0x4a) · RemoveNodeFromNetwork (0x4b) · FUNC_ID_ZW_CREATE_NEW_PRIMARY (0x4c) · FUNC_ID_ZW_CONTROLLER_CHANGE (0x4d) · FUNC_ID_ZW_SET_LEARN_MODE (0x50) · FUNC_ID_ZW_ASSIGN_SUC_RETURN_ROUTE (0x51) · FUNC_ID_ZW_REQUEST_NETWORK_UPDATE (0x53) · FUNC_ID_ZW_SET_SUC_NODE_ID (0x54) · FUNC_ID_ZW_DELETE_SUC_RETURN_ROUTE (0x55) · GetSUCNodeId (0x56) · UNKNOWN_FUNC_SEND_SUC_ID (0x57) · FUNC_ID_ZW_EXPLORE_REQUEST_INCLUSION (0x5e) · RequestNodeInfo (0x60) · RemoveFailedNode (0x61) · IsFailedNode (0x62) · FUNC_ID_ZW_REPLACE_FAILED_NODE (0x63) · UNKNOWN_FUNC_UNKNOWN_0x66 (0x66) · UNKNOWN_FUNC_UNKNOWN_0x67 (0x67) · UNKNOWN_FUNC_UNKNOWN_0x78 (0x78) · GetRoutingInfo (0x80) · UNKNOWN_FUNC_LOCK_ROUTE_RESPONSE (0x90) · UNKNOWN_FUNC_UNKNOWN_0x92 (0x92) · UNKNOWN_FUNC_UNKNOWN_0x93 (0x93) · UNKNOWN_FUNC_UNKNOWN_0x98 (0x98) · UNKNOWN_FUNC_UNKNOWN_0xB4 (0xb4) · UNKNOWN_FUNC_WATCH_DOG_ENABLE (0xb6) · UNKNOWN_FUNC_WATCH_DOG_DISABLE (0xb7) · UNKNOWN_FUNC_WATCH_DOG_KICK (0xb8) · UNKNOWN_FUNC_UNKNOWN_0xB9 (0xb9) · UNKNOWN_FUNC_RF_POWERLEVEL_GET (0xba) · UNKNOWN_FUNC_GET_LIBRARY_TYPE (0xbd) · UNKNOWN_FUNC_SEND_TEST_FRAME (0xbe) · UNKNOWN_FUNC_GET_PROTOCOL_STATUS (0xbf) · UNKNOWN_FUNC_UNKNOWN_0xD2 (0xd2) · UNKNOWN_FUNC_UNKNOWN_0xD3 (0xd3) · UNKNOWN_FUNC_UNKNOWN_0xD4 (0xd4) · UNKNOWN_FUNC_UNKNOWN_0xEF (0xef) · UNKNOWN_FUNC_UNKNOWN_0xF2 (0xf2) · UNKNOWN_FUNC_UNKNOWN_0xF4 (0xf4) · UNKNOWN_FUNC_UNKNOWN_0xF5 (0xf5) 18:50:11.351 SERIAL « 0x012b01070507011504000001fe83ff88cf1f0000fb9f7da067008080008086000 (45 bytes) 000e87300000e0000401a000c 18:50:11.352 SERIAL Buffer := 0x012b01070507011504000001fe83ff88cf1f0000fb9f7da06700808 (45 bytes) 0008086000000e87300000e0000401a000c 18:50:11.354 SERIAL » [ACK] (0x06) 18:50:11.360 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:11.362 SERIAL » 0x01030056aa (5 bytes) 18:50:11.355 DRIVER ACK already received, resolving transaction 18:50:11.356 DRIVER resuming send queue 18:50:11.361 DRIVER workOffSendQueue > sending next message (GetSUCNodeId)... 18:50:11.363 DRIVER The send queue is empty 18:50:11.359 CNTRLR finding SUC... 18:50:11.367 SERIAL « [ACK] (0x06) 18:50:11.367 DRIVER ACK received from controller for current transaction 18:50:11.368 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:11.372 DRIVER « [RES] [GetSUCNodeId] [final] 18:50:11.375 CNTRLR SUC has node ID 1 18:50:11.370 SERIAL « 0x0104015601ad (6 bytes) 18:50:11.370 SERIAL Buffer := 0x0104015601ad (6 bytes) 18:50:11.371 SERIAL » [ACK] (0x06) 18:50:11.377 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:11.373 DRIVER ACK already received, resolving transaction 18:50:11.373 DRIVER resuming send queue 18:50:11.378 DRIVER workOffSendQueue > sending next message (GetSerialApiInitData)... 18:50:11.380 DRIVER The send queue is empty 18:50:11.375 CNTRLR querying node information... 18:50:11.379 SERIAL » 0x01030002fe (5 bytes) 18:50:11.384 SERIAL « [ACK] (0x06) 18:50:11.384 DRIVER ACK received from controller for current transaction 18:50:11.386 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:11.476 SERIAL « 0x01 (1 bytes) 18:50:11.477 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:11.478 SERIAL « 0x25010205081dff0000000000000000000000000000000000000000 (27 bytes) 18:50:11.479 SERIAL [incomplete] Buffer := 0x0125010205081dff00000000000000000000000000 (28 bytes) 00000000000000 18:50:11.480 SERIAL « 0x0000000000000000050033 (11 bytes) 18:50:11.483 DRIVER « [RES] [GetSerialApiInitData] [final] 18:50:11.486 CNTRLR received node information: controller role: primary controller is a SUC: true controller is a slave: false controller supports timers: false nodes in the network: 1, 2, 3, 4, 5, 6, 7, 8 18:50:11.484 DRIVER ACK already received, resolving transaction 18:50:11.485 DRIVER resuming send queue 18:50:11.491 DRIVER workOffSendQueue > sending next message (SetSerialApiTimeouts)... 18:50:11.493 DRIVER The send queue is empty 18:50:11.481 SERIAL Buffer := 0x0125010205081dff000000000000000000000000000000000000000 (39 bytes) 00000000000000000050033 18:50:11.483 SERIAL » [ACK] (0x06) 18:50:11.490 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:11.492 SERIAL » 0x01050006640f97 (7 bytes) 18:50:11.489 CNTRLR setting serial API timeouts: ack = 1000 ms, byte = 150 ms 18:50:11.497 SERIAL « [ACK] (0x06) 18:50:11.498 DRIVER ACK received from controller for current transaction 18:50:11.498 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:11.502 DRIVER « [RES] [SetSerialApiTimeouts] [final] 18:50:11.504 CNTRLR serial API timeouts overwritten. The old values were: ack = 1000 ms, byte = 15 0 ms 18:50:11.500 SERIAL « 0x01050106640f96 (7 bytes) 18:50:11.500 SERIAL Buffer := 0x01050106640f96 (7 bytes) 18:50:11.501 SERIAL » [ACK] (0x06) 18:50:11.506 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:11.503 DRIVER ACK already received, resolving transaction 18:50:11.503 DRIVER resuming send queue 18:50:11.506 DRIVER driver ready 18:50:11.513 DRIVER The send queue is empty 18:50:11.505 CNTRLR Interview completed 18:50:11.515 DRIVER Cache file for homeId 0xe84eb4ea found, attempting to restore the network from cache... 18:50:11.546 CNTRLR [Node 002] trying to load device config 18:50:11.556 CNTRLR [Node 002] device config loaded 18:50:11.560 CNTRLR [Node 003] trying to load device config 18:50:11.564 CNTRLR [Node 003] device config loaded 18:50:11.567 CNTRLR [Node 004] trying to load device config 18:50:11.571 CNTRLR [Node 004] device config loaded 18:50:11.575 CNTRLR [Node 005] trying to load device config 18:50:11.579 CNTRLR [Node 005] device config loaded 18:50:11.582 CNTRLR [Node 006] trying to load device config 18:50:11.587 CNTRLR [Node 006] device config loaded 18:50:11.605 CNTRLR [Node 007] trying to load device config 18:50:11.645 CNTRLR [Node 007] device config loaded 18:50:11.649 CNTRLR [Node 008] trying to load device config 18:50:11.655 CNTRLR [Node 008] device config loaded 18:50:11.656 DRIVER Restoring the network from cache was successful! 18:50:11.658 CNTRLR [Node 001] Beginning interview - last completed stage: RestartFromCache 18:50:11.707 CNTRLR [Node 001] Interview stage completed: CommandClasses 18:50:11.712 DRIVER workOffSendQueue > sending next message (GetRoutingInfo) to node 1... 18:50:11.713 SERIAL » 0x010700800100000079 (9 bytes) 18:50:11.708 CNTRLR [Node 001] Interview stage completed: OverwriteConfig 18:50:11.709 CNTRLR » [Node 001] requesting node neighbors... 18:50:11.717 SERIAL « [ACK] (0x06) 18:50:11.718 DRIVER ACK received from controller for current transaction 18:50:11.718 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:11.722 DRIVER « [RES] [GetRoutingInfo] [final] 18:50:11.725 CNTRLR « [Node 001] node neighbors received: 2, 3, 4, 5, 6, 7, 8 18:50:11.720 SERIAL « 0x01200180fe0000000000000000000000000000000000000000000000000000000 (34 bytes) 0a0 18:50:11.720 SERIAL Buffer := 0x01200180fe000000000000000000000000000000000000000000000 (34 bytes) 00000000000a0 18:50:11.721 SERIAL » [ACK] (0x06) 18:50:11.725 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:11.723 DRIVER ACK already received, resolving transaction 18:50:11.724 DRIVER resuming send queue 18:50:11.727 DRIVER The send queue is empty 18:50:11.726 CNTRLR [Node 001] Interview stage completed: Neighbors 18:50:11.759 CNTRLR [Node 001] Interview completed 18:50:11.763 CNTRLR [Node 001] The node is ready to be used 18:50:11.767 CNTRLR [Node 002] Beginning interview - last completed stage: RestartFromCache 18:50:11.774 CNTRLR [Node 002] ManufacturerSpecificCC: doing a partial interview... 18:50:11.777 CNTRLR [Node 002] [~] [Manufacturer Specific] interviewComple [Endpoint 0] [internal] te: true => true 18:50:11.777 CNTRLR [Node 003] Beginning interview - last completed stage: RestartFromCache 18:50:11.781 CNTRLR [Node 003] ManufacturerSpecificCC: doing a partial interview... 18:50:11.782 CNTRLR [Node 003] [~] [Manufacturer Specific] interviewComple [Endpoint 0] [internal] te: true => true 18:50:11.782 CNTRLR [Node 004] Beginning interview - last completed stage: RestartFromCache 18:50:11.787 CNTRLR [Node 004] ManufacturerSpecificCC: doing a partial interview... 18:50:11.788 CNTRLR [Node 004] [~] [Manufacturer Specific] interviewComple [Endpoint 0] [internal] te: true => true 18:50:11.789 CNTRLR [Node 005] Beginning interview - last completed stage: RestartFromCache 18:50:11.796 CNTRLR [Node 005] ManufacturerSpecificCC: doing a partial interview... 18:50:11.797 CNTRLR [Node 005] [~] [Manufacturer Specific] interviewComple [Endpoint 0] [internal] te: true => true 18:50:11.798 CNTRLR [Node 006] Beginning interview - last completed stage: RestartFromCache 18:50:11.800 CNTRLR [Node 006] ManufacturerSpecificCC: doing a partial interview... 18:50:11.802 CNTRLR [Node 006] [~] [Manufacturer Specific] interviewComple [Endpoint 0] [internal] te: true => true 18:50:11.802 CNTRLR [Node 007] Beginning interview - last completed stage: RestartFromCache 18:50:11.811 CNTRLR [Node 007] ManufacturerSpecificCC: doing a partial interview... 18:50:11.812 CNTRLR [Node 007] [~] [Manufacturer Specific] interviewComple [Endpoint 0] [internal] te: true => true 18:50:11.812 CNTRLR [Node 008] Beginning interview - last completed stage: RestartFromCache 18:50:11.815 CNTRLR [Node 008] ManufacturerSpecificCC: doing a partial interview... 18:50:11.817 CNTRLR [Node 008] [~] [Manufacturer Specific] interviewComple [Endpoint 0] [internal] te: true => true 18:50:11.820 CNTRLR [Node 003] VersionCC: doing a partial interview... 18:50:11.821 CNTRLR [Node 003] [~] [Version] interviewComplete: true => tr [Endpoint 0] [internal] ue 18:50:11.822 CNTRLR [Node 004] VersionCC: doing a partial interview... 18:50:11.823 CNTRLR [Node 004] [~] [Version] interviewComplete: true => tr [Endpoint 0] [internal] ue 18:50:11.824 CNTRLR [Node 005] VersionCC: doing a partial interview... 18:50:11.825 CNTRLR [Node 005] [~] [Version] interviewComplete: true => tr [Endpoint 0] [internal] ue 18:50:11.825 CNTRLR [Node 006] VersionCC: doing a partial interview... 18:50:11.826 CNTRLR [Node 006] [~] [Version] interviewComplete: true => tr [Endpoint 0] [internal] ue 18:50:11.827 CNTRLR [Node 007] VersionCC: doing a partial interview... 18:50:11.828 CNTRLR [Node 007] [~] [Version] interviewComplete: true => tr [Endpoint 0] [internal] ue 18:50:11.829 CNTRLR [Node 008] VersionCC: doing a partial interview... 18:50:11.829 CNTRLR [Node 008] [~] [Version] interviewComplete: true => tr [Endpoint 0] [internal] ue 18:50:11.830 CNTRLR [Node 003] trying to load device config 18:50:11.831 CNTRLR [Node 004] trying to load device config 18:50:11.832 CNTRLR [Node 005] trying to load device config 18:50:11.832 CNTRLR [Node 006] trying to load device config 18:50:11.833 CNTRLR [Node 007] trying to load device config 18:50:11.836 CNTRLR [Node 008] trying to load device config 18:50:11.875 CNTRLR [Node 003] device config loaded 18:50:11.907 DRIVER workOffSendQueue > sending next message (SendData) to node 3... 18:50:11.909 SERIAL » 0x010a00130303430200250183 (12 bytes) 18:50:11.881 CNTRLR [Node 003] ThermostatSetpointCC: doing a complete interview... 18:50:11.882 CNTRLR » [Node 003] querying current value of setpoint N/A... 18:50:11.888 CNTRLR [Node 004] device config loaded 18:50:11.889 CNTRLR [Node 004] ThermostatSetpointCC: doing a complete interview... 18:50:11.890 CNTRLR » [Node 004] querying current value of setpoint N/A... 18:50:11.894 CNTRLR [Node 006] device config loaded 18:50:11.895 CNTRLR [Node 006] ThermostatSetpointCC: doing a complete interview... 18:50:11.895 CNTRLR » [Node 006] querying current value of setpoint N/A... 18:50:11.898 CNTRLR [Node 008] device config loaded 18:50:11.900 CNTRLR [Node 008] MultilevelSensorCC: doing a partial interview... 18:50:11.901 CNTRLR » [Node 008] querying current sensor reading... 18:50:11.904 CNTRLR [Node 005] device config loaded 18:50:11.905 CNTRLR [Node 005] ThermostatSetpointCC: doing a complete interview... 18:50:11.906 CNTRLR » [Node 005] querying current value of setpoint N/A... 18:50:11.937 CNTRLR [Node 002] VersionCC: doing a partial interview... 18:50:11.938 CNTRLR [Node 002] [~] [Version] interviewComplete: true => tr [Endpoint 0] [internal] ue 18:50:11.939 CNTRLR [Node 002] trying to load device config 18:50:11.910 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:11.910 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:11.911 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:11.911 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:11.951 CNTRLR [Node 007] device config loaded 18:50:11.984 SERIAL « [ACK] (0x06) 18:50:11.985 DRIVER ACK received from controller for current transaction 18:50:11.986 SERIAL « 0x060104011301e8 (7 bytes) 18:50:11.986 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:11.987 SERIAL » [ACK] (0x06) 18:50:11.989 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:11.988 DRIVER « [RES] [SendData] [confirmation] 18:50:11.995 CNTRLR [Node 002] device config loaded 18:50:12.002 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:12.002 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:11.996 CNTRLR [Node 002] ThermostatSetpointCC: doing a complete interview... 18:50:11.996 CNTRLR » [Node 002] querying current value of setpoint N/A... 18:50:11.999 CNTRLR [Node 007] BinarySwitchCC: doing a partial interview... 18:50:12.000 CNTRLR » [Node 007] querying Binary Switch state... 18:50:16.201 SERIAL « 0x01 (1 bytes) 18:50:16.204 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:16.214 DRIVER « [REQ] [SendData] [fatal_node] 18:50:16.215 CNTRLR [Node 003] The node did not respond because it is asleep, moving its messages to the wakeup queue 18:50:16.207 SERIAL « 0x070013010101ac46 (8 bytes) 18:50:16.212 SERIAL Buffer := 0x01070013010101ac46 (9 bytes) 18:50:16.213 SERIAL » [ACK] (0x06) 18:50:16.225 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:16.228 SERIAL » 0x010a00130403430200250287 (12 bytes) 18:50:16.227 DRIVER workOffSendQueue > sending next message (SendData) to node 4... 18:50:16.218 CNTRLR [Node 003] The node is now asleep. 18:50:16.223 CNTRLR [Node 003] The node is ready to be used 18:50:16.233 SERIAL « [ACK] (0x06) 18:50:16.234 DRIVER ACK received from controller for current transaction 18:50:16.235 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:16.245 SERIAL « 0x01 (1 bytes) 18:50:16.246 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:16.260 DRIVER « [RES] [SendData] [confirmation] 18:50:16.258 SERIAL « 0x04011301e8 (5 bytes) 18:50:16.258 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:16.259 SERIAL » [ACK] (0x06) 18:50:16.271 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:21.170 SERIAL « 0x01 (1 bytes) 18:50:21.171 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:21.175 DRIVER « [REQ] [SendData] [fatal_node] 18:50:21.175 CNTRLR [Node 004] The node did not respond because it is asleep, moving its messages to the wakeup queue 18:50:21.173 SERIAL « 0x070013020101ed04 (8 bytes) 18:50:21.173 SERIAL Buffer := 0x01070013020101ed04 (9 bytes) 18:50:21.174 SERIAL » [ACK] (0x06) 18:50:21.180 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:21.182 SERIAL » 0x010a00130603430200250384 (12 bytes) 18:50:21.181 DRIVER workOffSendQueue > sending next message (SendData) to node 6... 18:50:21.176 CNTRLR [Node 004] The node is now asleep. 18:50:21.178 CNTRLR [Node 004] The node is ready to be used 18:50:21.188 SERIAL « [ACK] (0x06) 18:50:21.189 DRIVER ACK received from controller for current transaction 18:50:21.190 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:21.197 SERIAL « 0x01 (1 bytes) 18:50:21.197 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:21.202 DRIVER « [RES] [SendData] [confirmation] 18:50:21.200 SERIAL « 0x04011301e8 (5 bytes) 18:50:21.201 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:21.201 SERIAL » [ACK] (0x06) 18:50:21.202 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:26.173 SERIAL « 0x01 (1 bytes) 18:50:26.174 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:26.178 DRIVER « [REQ] [SendData] [fatal_node] 18:50:26.179 CNTRLR [Node 006] The node did not respond because it is asleep, moving its messages to the wakeup queue 18:50:26.176 SERIAL « 0x070013030101f31b (8 bytes) 18:50:26.177 SERIAL Buffer := 0x01070013030101f31b (9 bytes) 18:50:26.177 SERIAL » [ACK] (0x06) 18:50:26.184 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:26.190 SERIAL » 0x01090013080231042504fb (11 bytes) 18:50:26.188 DRIVER workOffSendQueue > sending next message (SendData) to node 8... 18:50:26.180 CNTRLR [Node 006] The node is now asleep. 18:50:26.181 CNTRLR [Node 006] The node is ready to be used 18:50:26.195 SERIAL « [ACK] (0x06) 18:50:26.195 DRIVER ACK received from controller for current transaction 18:50:26.196 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:26.201 SERIAL « 0x01 (1 bytes) 18:50:26.202 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:26.205 DRIVER « [RES] [SendData] [confirmation] 18:50:26.204 SERIAL « 0x04011301e8 (5 bytes) 18:50:26.204 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:26.204 SERIAL » [ACK] (0x06) 18:50:26.205 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:31.161 SERIAL « 0x01 (1 bytes) 18:50:31.162 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:31.164 DRIVER « [REQ] [SendData] [fatal_node] 18:50:31.164 CNTRLR [Node 008] The node did not respond because it is asleep, moving its messages to the wakeup queue 18:50:31.163 SERIAL « 0x070013040101f01f (8 bytes) 18:50:31.163 SERIAL Buffer := 0x01070013040101f01f (9 bytes) 18:50:31.163 SERIAL » [ACK] (0x06) 18:50:31.169 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:31.171 SERIAL » 0x010a00130503430200250581 (12 bytes) 18:50:31.170 DRIVER workOffSendQueue > sending next message (SendData) to node 5... 18:50:31.165 CNTRLR [Node 008] The node is now asleep. 18:50:31.166 CNTRLR [Node 008] The node is ready to be used 18:50:31.177 SERIAL « [ACK] (0x06) 18:50:31.177 DRIVER ACK received from controller for current transaction 18:50:31.178 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:31.184 SERIAL « 0x01 (1 bytes) 18:50:31.184 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:31.187 SERIAL « 0x04011301e8 (5 bytes) 18:50:31.189 DRIVER « [RES] [SendData] [confirmation] 18:50:31.188 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:31.188 SERIAL » [ACK] (0x06) 18:50:31.189 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:35.462 SERIAL « 0x01 (1 bytes) 18:50:35.463 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:35.467 DRIVER « [REQ] [SendData] [fatal_node] 18:50:35.468 CNTRLR [Node 005] The node did not respond because it is asleep, moving its messages to the wakeup queue 18:50:35.465 SERIAL « 0x070013050101ac42 (8 bytes) 18:50:35.465 SERIAL Buffer := 0x01070013050101ac42 (9 bytes) 18:50:35.466 SERIAL » [ACK] (0x06) 18:50:35.474 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:35.477 SERIAL » 0x010a00130203430200250685 (12 bytes) 18:50:35.476 DRIVER workOffSendQueue > sending next message (SendData) to node 2... 18:50:35.469 CNTRLR [Node 005] The node is now asleep. 18:50:35.471 CNTRLR [Node 005] The node is ready to be used 18:50:35.483 SERIAL « [ACK] (0x06) 18:50:35.484 DRIVER ACK received from controller for current transaction 18:50:35.485 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:35.491 SERIAL « 0x01 (1 bytes) 18:50:35.491 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:35.495 DRIVER « [RES] [SendData] [confirmation] 18:50:35.493 SERIAL « 0x04011301e8 (5 bytes) 18:50:35.494 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:35.495 SERIAL » [ACK] (0x06) 18:50:35.496 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:39.737 SERIAL « 0x01 (1 bytes) 18:50:39.738 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:39.742 DRIVER « [REQ] [SendData] [fatal_node] 18:50:39.743 CNTRLR [Node 002] The node did not respond because it is asleep, moving its messages to the wakeup queue 18:50:39.741 SERIAL « 0x070013060101a944 (8 bytes) 18:50:39.741 SERIAL Buffer := 0x01070013060101a944 (9 bytes) 18:50:39.742 SERIAL » [ACK] (0x06) 18:50:39.751 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:39.753 SERIAL » 0x01090013070225022507e5 (11 bytes) 18:50:39.753 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:39.745 CNTRLR [Node 002] The node is now asleep. 18:50:39.751 CNTRLR [Node 002] The node is ready to be used 18:50:39.759 SERIAL « [ACK] (0x06) 18:50:39.762 DRIVER ACK received from controller for current transaction 18:50:39.762 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:39.768 SERIAL « 0x0104011301e8 (6 bytes) 18:50:39.771 DRIVER « [RES] [SendData] [confirmation] 18:50:39.769 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:39.770 SERIAL » [ACK] (0x06) 18:50:39.772 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:39.781 SERIAL « 0x01 (1 bytes) 18:50:39.781 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:39.784 DRIVER « [REQ] [SendData] [confirmation] 18:50:39.782 SERIAL « 0x07001307000002ee (8 bytes) 18:50:39.783 SERIAL Buffer := 0x0107001307000002ee (9 bytes) 18:50:39.783 SERIAL » [ACK] (0x06) 18:50:39.787 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:39.785 DRIVER ACK received from node for current transaction. RTT = 31.93 ms 18:50:39.817 SERIAL « 0x01090004000703250300d0 (11 bytes) 18:50:39.821 CNTRLR [Node 007] [~] [Binary Switch] currentValue: false => false [Endpoint 0] 18:50:39.824 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:39.817 SERIAL Buffer := 0x01090004000703250300d0 (11 bytes) 18:50:39.823 SERIAL » [ACK] (0x06) 18:50:39.827 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:39.827 CNTRLR « [Node 007] received Binary Switch state: current value: false 18:50:39.828 CNTRLR [Node 007] [~] [Binary Switch] interviewComplete: true [Endpoint 0] [internal] => true 18:50:39.825 DRIVER ACK already received, resolving transaction 18:50:39.826 DRIVER resuming send queue 18:50:39.830 DRIVER The remaining 6 messages are for sleeping nodes, not sending anything! 18:50:39.870 CNTRLR [Node 007] MultilevelSwitchCC: doing a partial interview... 18:50:39.873 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:39.874 SERIAL » 0x01090013070226022508e9 (11 bytes) 18:50:39.871 CNTRLR » [Node 007] requesting current switch state... 18:50:39.879 SERIAL « [ACK] (0x06) 18:50:39.879 DRIVER ACK received from controller for current transaction 18:50:39.880 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:39.886 SERIAL « 0x01 (1 bytes) 18:50:39.887 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:39.890 DRIVER « [RES] [SendData] [confirmation] 18:50:39.888 SERIAL « 0x04011301e8 (5 bytes) 18:50:39.889 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:39.889 SERIAL » [ACK] (0x06) 18:50:39.890 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:39.901 SERIAL « 0x01 (1 bytes) 18:50:39.901 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:39.904 DRIVER « [REQ] [SendData] [confirmation] 18:50:39.902 SERIAL « 0x07001308000002e1 (8 bytes) 18:50:39.903 SERIAL Buffer := 0x0107001308000002e1 (9 bytes) 18:50:39.903 SERIAL » [ACK] (0x06) 18:50:39.906 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:39.905 DRIVER ACK received from node for current transaction. RTT = 30.78 ms 18:50:39.934 SERIAL « 0x01 (1 bytes) 18:50:39.935 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:39.939 CNTRLR [Node 007] [~] [Multilevel Switch] currentValue: 0 => 0 [Endpoint 0] 18:50:39.942 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:39.936 SERIAL « 0x090004000703260300d3 (10 bytes) 18:50:39.936 SERIAL Buffer := 0x01090004000703260300d3 (11 bytes) 18:50:39.941 SERIAL » [ACK] (0x06) 18:50:39.944 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:39.945 CNTRLR [Node 007] [~] [Multilevel Switch] interviewComplete: [Endpoint 0] [internal] true => true 18:50:39.943 DRIVER ACK already received, resolving transaction 18:50:39.943 DRIVER resuming send queue 18:50:39.951 DRIVER The remaining 6 messages are for sleeping nodes, not sending anything! 18:50:39.989 CNTRLR [Node 007] MeterCC: doing a partial interview... 18:50:39.992 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:39.993 SERIAL » 0x01090013070232032509fd (11 bytes) 18:50:39.990 CNTRLR » [Node 007] querying meter support... 18:50:39.997 SERIAL « [ACK] (0x06) 18:50:39.998 DRIVER ACK received from controller for current transaction 18:50:39.999 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.004 SERIAL « 0x01 (1 bytes) 18:50:40.005 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.010 DRIVER « [RES] [SendData] [confirmation] 18:50:40.006 SERIAL « 0x04011301e8 (5 bytes) 18:50:40.006 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:40.007 SERIAL » [ACK] (0x06) 18:50:40.011 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.021 SERIAL « 0x01 (1 bytes) 18:50:40.022 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.027 DRIVER « [REQ] [SendData] [confirmation] 18:50:40.023 SERIAL « 0x07001309000002e0 (8 bytes) 18:50:40.024 SERIAL Buffer := 0x0107001309000002e0 (9 bytes) 18:50:40.026 SERIAL » [ACK] (0x06) 18:50:40.028 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.028 DRIVER ACK received from node for current transaction. RTT = 34.88 ms 18:50:40.031 SERIAL « 0x01 (1 bytes) 18:50:40.032 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.038 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:40.041 CNTRLR « [Node 007] received meter support: type: Electric supported scales: * kWh, * W supported rate types: * Consumed supports reset: true 18:50:40.034 SERIAL « 0x0a00040007043204a10560 (11 bytes) 18:50:40.035 SERIAL Buffer := 0x010a00040007043204a10560 (12 bytes) 18:50:40.037 SERIAL » [ACK] (0x06) 18:50:40.039 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.038 DRIVER ACK already received, resolving transaction 18:50:40.039 DRIVER resuming send queue 18:50:40.045 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:40.047 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:40.042 CNTRLR » [Node 007] querying meter value (type = Electric, scale = kWh, rate type = Con sumed)... 18:50:40.046 SERIAL » 0x010a00130703320140250abe (12 bytes) 18:50:40.052 SERIAL « [ACK] (0x06) 18:50:40.053 DRIVER ACK received from controller for current transaction 18:50:40.053 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.058 SERIAL « 0x01 (1 bytes) 18:50:40.059 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.062 DRIVER « [RES] [SendData] [confirmation] 18:50:40.060 SERIAL « 0x04011301e8 (5 bytes) 18:50:40.061 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:40.061 SERIAL » [ACK] (0x06) 18:50:40.063 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.073 SERIAL « 0x01 (1 bytes) 18:50:40.074 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.079 DRIVER « [REQ] [SendData] [confirmation] 18:50:40.077 SERIAL « 0x0700130a000002e3 (8 bytes) 18:50:40.078 SERIAL Buffer := 0x010700130a000002e3 (9 bytes) 18:50:40.079 SERIAL » [ACK] (0x06) 18:50:40.081 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.080 DRIVER ACK received from node for current transaction. RTT = 34.90 ms 18:50:40.132 SERIAL « 0x01 (1 bytes) 18:50:40.135 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.144 CNTRLR [Node 007] [Meter] value[65537]: metadata updated [Endpoint 0] 18:50:40.155 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:40.136 SERIAL « 0x10000400070a32022124000003600000b0 (17 bytes) 18:50:40.137 SERIAL Buffer := 0x0110000400070a32022124000003600000b0 (18 bytes) 18:50:40.154 SERIAL » [ACK] (0x06) 18:50:40.156 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.163 SERIAL » 0x010a00130703320150250baf (12 bytes) 18:50:40.147 CNTRLR [Node 007] [~] [Meter] value[65537]: 86.2 => 86.4 [Endpoint 0] 18:50:40.151 CNTRLR [Node 007] [Meter] deltaTime[65537]: metadata updated [Endpoint 0] 18:50:40.153 CNTRLR [Node 007] [~] [Meter] deltaTime[65537]: 0 => 0 [Endpoint 0] 18:50:40.157 CNTRLR » [Node 007] querying meter value (type = Electric, scale = W, rate type = Consu med)... 18:50:40.155 DRIVER ACK already received, resolving transaction 18:50:40.156 DRIVER resuming send queue 18:50:40.162 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:40.163 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:40.168 SERIAL « [ACK] (0x06) 18:50:40.169 DRIVER ACK received from controller for current transaction 18:50:40.170 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.175 SERIAL « 0x01 (1 bytes) 18:50:40.176 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.181 DRIVER « [RES] [SendData] [confirmation] 18:50:40.179 SERIAL « 0x04011301e8 (5 bytes) 18:50:40.180 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:40.180 SERIAL » [ACK] (0x06) 18:50:40.182 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.191 SERIAL « 0x01 (1 bytes) 18:50:40.192 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.196 DRIVER « [REQ] [SendData] [confirmation] 18:50:40.194 SERIAL « 0x0700130b000002e2 (8 bytes) 18:50:40.194 SERIAL Buffer := 0x010700130b000002e2 (9 bytes) 18:50:40.195 SERIAL » [ACK] (0x06) 18:50:40.197 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.196 DRIVER ACK received from node for current transaction. RTT = 33.95 ms 18:50:40.241 SERIAL « 0x01 (1 bytes) 18:50:40.241 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.244 CNTRLR [Node 007] [Meter] value[66049]: metadata updated [Endpoint 0] 18:50:40.253 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:40.242 SERIAL « 0x10000400070a32022134000000000000c3 (17 bytes) 18:50:40.243 SERIAL Buffer := 0x0110000400070a32022134000000000000c3 (18 bytes) 18:50:40.252 SERIAL » [ACK] (0x06) 18:50:40.255 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.247 CNTRLR [Node 007] [~] [Meter] value[66049]: 0 => 0 [Endpoint 0] 18:50:40.249 CNTRLR [Node 007] [Meter] deltaTime[66049]: metadata updated [Endpoint 0] 18:50:40.251 CNTRLR [Node 007] [~] [Meter] deltaTime[66049]: 0 => 0 [Endpoint 0] 18:50:40.256 CNTRLR [Node 007] [~] [Meter] interviewComplete: true => true [Endpoint 0] [internal] 18:50:40.253 DRIVER ACK already received, resolving transaction 18:50:40.254 DRIVER resuming send queue 18:50:40.258 DRIVER The remaining 6 messages are for sleeping nodes, not sending anything! 18:50:40.293 CNTRLR [Node 007] ZWavePlusCC: doing a partial interview... 18:50:40.340 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:40.343 SERIAL » 0x010a00130703700501250cbf (12 bytes) 18:50:40.294 CNTRLR [Node 007] [~] [Z-Wave Plus Info] interviewComplete: t [Endpoint 0] [internal] rue => true 18:50:40.296 CNTRLR [Node 007] ConfigurationCC: Loading configuration parameters from device confi g 18:50:40.299 CNTRLR [Node 007] [+] [Configuration] isParamInformationFromConfig: false  [internal] 18:50:40.301 CNTRLR [Node 007] [Configuration] 1: metadata updated 18:50:40.304 CNTRLR [Node 007] [Configuration] 5: metadata updated 18:50:40.306 CNTRLR [Node 007] [Configuration] 10: metadata updated 18:50:40.308 CNTRLR [Node 007] [Configuration] 11: metadata updated 18:50:40.309 CNTRLR [Node 007] [Configuration] 12: metadata updated 18:50:40.311 CNTRLR [Node 007] [Configuration] 21: metadata updated 18:50:40.313 CNTRLR [Node 007] [Configuration] 30: metadata updated 18:50:40.315 CNTRLR [Node 007] [Configuration] 40: metadata updated 18:50:40.317 CNTRLR [Node 007] [Configuration] 42: metadata updated 18:50:40.319 CNTRLR [Node 007] [Configuration] 60: metadata updated 18:50:40.321 CNTRLR [Node 007] [Configuration] 61: metadata updated 18:50:40.323 CNTRLR [Node 007] [Configuration] 65: metadata updated 18:50:40.325 CNTRLR [Node 007] [Configuration] 66: metadata updated 18:50:40.328 CNTRLR [Node 007] [Configuration] 67: metadata updated 18:50:40.330 CNTRLR [Node 007] [Configuration] 68: metadata updated 18:50:40.331 CNTRLR [Node 007] [Configuration] 110: metadata updated 18:50:40.333 CNTRLR [Node 007] [Configuration] 120: metadata updated 18:50:40.335 CNTRLR [Node 007] [~] [Configuration] isParamInformationFromConfig: false [internal] => true 18:50:40.337 CNTRLR » [Node 007] querying parameter #1 value... 18:50:40.385 SERIAL « [ACK] (0x06) 18:50:40.386 DRIVER ACK received from controller for current transaction 18:50:40.387 SERIAL « 0x060104011301e8 (7 bytes) 18:50:40.387 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:40.387 SERIAL » [ACK] (0x06) 18:50:40.389 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.388 DRIVER « [RES] [SendData] [confirmation] 18:50:40.394 SERIAL « 0x0107 (2 bytes) 18:50:40.395 SERIAL [incomplete] Buffer := 0x0107 (2 bytes) 18:50:40.405 DRIVER « [REQ] [SendData] [confirmation] 18:50:40.403 SERIAL « 0x00130c000002e5 (7 bytes) 18:50:40.404 SERIAL Buffer := 0x010700130c000002e5 (9 bytes) 18:50:40.404 SERIAL » [ACK] (0x06) 18:50:40.407 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.406 DRIVER ACK received from node for current transaction. RTT = 62.91 ms 18:50:40.418 SERIAL « 0x010b0004000705700601010185 (13 bytes) 18:50:40.423 CNTRLR [Node 007] [~] [Configuration] 1: 1 => 1 18:50:40.427 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:40.419 SERIAL Buffer := 0x010b0004000705700601010185 (13 bytes) 18:50:40.426 SERIAL » [ACK] (0x06) 18:50:40.429 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.433 SERIAL » 0x010a00130703700505250dba (12 bytes) 18:50:40.430 CNTRLR » [Node 007] querying parameter #5 value... 18:50:40.427 DRIVER ACK already received, resolving transaction 18:50:40.428 DRIVER resuming send queue 18:50:40.433 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:40.434 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:40.442 SERIAL « [ACK] (0x06) 18:50:40.443 DRIVER ACK received from controller for current transaction 18:50:40.443 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.446 SERIAL « 0x01 (1 bytes) 18:50:40.447 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.450 DRIVER « [RES] [SendData] [confirmation] 18:50:40.448 SERIAL « 0x04011301e8 (5 bytes) 18:50:40.449 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:40.449 SERIAL » [ACK] (0x06) 18:50:40.451 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.461 SERIAL « 0x01 (1 bytes) 18:50:40.462 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.466 DRIVER « [REQ] [SendData] [confirmation] 18:50:40.464 SERIAL « 0x0700130d000002e4 (8 bytes) 18:50:40.465 SERIAL Buffer := 0x010700130d000002e4 (9 bytes) 18:50:40.465 SERIAL » [ACK] (0x06) 18:50:40.467 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.466 DRIVER ACK received from node for current transaction. RTT = 32.95 ms 18:50:40.501 SERIAL « 0x01 (1 bytes) 18:50:40.503 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.509 CNTRLR [Node 007] [~] [Configuration] 5: 0 => 0 18:50:40.512 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:40.505 SERIAL « 0x0b0004000705700605010080 (12 bytes) 18:50:40.506 SERIAL Buffer := 0x010b0004000705700605010080 (13 bytes) 18:50:40.511 SERIAL » [ACK] (0x06) 18:50:40.514 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.515 CNTRLR » [Node 007] querying parameter #10 value... 18:50:40.513 DRIVER ACK already received, resolving transaction 18:50:40.513 DRIVER resuming send queue 18:50:40.517 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:40.520 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:40.519 SERIAL » 0x010a0013070370050a250eb6 (12 bytes) 18:50:40.524 SERIAL « [ACK] (0x06) 18:50:40.525 DRIVER ACK received from controller for current transaction 18:50:40.525 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.530 SERIAL « 0x01 (1 bytes) 18:50:40.531 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.534 DRIVER « [RES] [SendData] [confirmation] 18:50:40.533 SERIAL « 0x04011301e8 (5 bytes) 18:50:40.533 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:40.533 SERIAL » [ACK] (0x06) 18:50:40.535 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.547 SERIAL « 0x01 (1 bytes) 18:50:40.547 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.551 DRIVER « [REQ] [SendData] [confirmation] 18:50:40.549 SERIAL « 0x0700130e000002e7 (8 bytes) 18:50:40.550 SERIAL Buffer := 0x010700130e000002e7 (9 bytes) 18:50:40.550 SERIAL » [ACK] (0x06) 18:50:40.552 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.551 DRIVER ACK received from node for current transaction. RTT = 32.32 ms 18:50:40.588 SERIAL « 0x01 (1 bytes) 18:50:40.589 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.593 CNTRLR [Node 007] [~] [Configuration] 10: 255 => 255 18:50:40.595 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:40.591 SERIAL « 0x0c000400070670060a0200ff77 (13 bytes) 18:50:40.591 SERIAL Buffer := 0x010c000400070670060a0200ff77 (14 bytes) 18:50:40.594 SERIAL » [ACK] (0x06) 18:50:40.596 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.597 CNTRLR » [Node 007] querying parameter #11 value... 18:50:40.595 DRIVER ACK already received, resolving transaction 18:50:40.596 DRIVER resuming send queue 18:50:40.599 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:40.600 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:40.599 SERIAL » 0x010a0013070370050b250fb6 (12 bytes) 18:50:40.605 SERIAL « [ACK] (0x06) 18:50:40.606 DRIVER ACK received from controller for current transaction 18:50:40.607 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.611 SERIAL « 0x01 (1 bytes) 18:50:40.612 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.617 DRIVER « [RES] [SendData] [confirmation] 18:50:40.615 SERIAL « 0x04011301e8 (5 bytes) 18:50:40.616 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:40.616 SERIAL » [ACK] (0x06) 18:50:40.617 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.627 SERIAL « 0x01 (1 bytes) 18:50:40.628 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.631 SERIAL « 0x0700130f000002e6 (8 bytes) 18:50:40.633 DRIVER « [REQ] [SendData] [confirmation] 18:50:40.631 SERIAL Buffer := 0x010700130f000002e6 (9 bytes) 18:50:40.632 SERIAL » [ACK] (0x06) 18:50:40.634 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.634 DRIVER ACK received from node for current transaction. RTT = 34.11 ms 18:50:40.667 SERIAL « 0x01 (1 bytes) 18:50:40.668 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.672 CNTRLR [Node 007] [~] [Configuration] 11: 0 => 0 18:50:40.674 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:40.669 SERIAL « 0x0c000400070670060b02000089 (13 bytes) 18:50:40.670 SERIAL Buffer := 0x010c000400070670060b02000089 (14 bytes) 18:50:40.674 SERIAL » [ACK] (0x06) 18:50:40.676 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.677 CNTRLR » [Node 007] querying parameter #12 value... 18:50:40.675 DRIVER ACK already received, resolving transaction 18:50:40.676 DRIVER resuming send queue 18:50:40.679 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:40.681 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:40.680 SERIAL » 0x010a0013070370050c2510ae (12 bytes) 18:50:40.686 SERIAL « [ACK] (0x06) 18:50:40.686 DRIVER ACK received from controller for current transaction 18:50:40.687 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.693 SERIAL « 0x01 (1 bytes) 18:50:40.694 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.699 DRIVER « [RES] [SendData] [confirmation] 18:50:40.697 SERIAL « 0x04011301e8 (5 bytes) 18:50:40.698 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:40.699 SERIAL » [ACK] (0x06) 18:50:40.700 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.707 SERIAL « 0x01 (1 bytes) 18:50:40.708 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.711 DRIVER « [REQ] [SendData] [confirmation] 18:50:40.709 SERIAL « 0x07001310000002f9 (8 bytes) 18:50:40.709 SERIAL Buffer := 0x0107001310000002f9 (9 bytes) 18:50:40.710 SERIAL » [ACK] (0x06) 18:50:40.712 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.711 DRIVER ACK received from node for current transaction. RTT = 31.36 ms 18:50:40.750 SERIAL « 0x010c (2 bytes) 18:50:40.751 SERIAL [incomplete] Buffer := 0x010c (2 bytes) 18:50:40.756 CNTRLR [Node 007] [~] [Configuration] 12: 0 => 0 18:50:40.759 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:40.754 SERIAL « 0x000400070670060c0200008e (12 bytes) 18:50:40.755 SERIAL Buffer := 0x010c000400070670060c0200008e (14 bytes) 18:50:40.758 SERIAL » [ACK] (0x06) 18:50:40.761 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.761 CNTRLR » [Node 007] querying parameter #21 value... 18:50:40.759 DRIVER ACK already received, resolving transaction 18:50:40.760 DRIVER resuming send queue 18:50:40.764 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:40.765 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:40.764 SERIAL » 0x010a001307037005152511b6 (12 bytes) 18:50:40.769 SERIAL « [ACK] (0x06) 18:50:40.770 DRIVER ACK received from controller for current transaction 18:50:40.770 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.777 SERIAL « 0x01 (1 bytes) 18:50:40.777 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.780 DRIVER « [RES] [SendData] [confirmation] 18:50:40.779 SERIAL « 0x04011301e8 (5 bytes) 18:50:40.779 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:40.779 SERIAL » [ACK] (0x06) 18:50:40.780 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.791 SERIAL « 0x01 (1 bytes) 18:50:40.792 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.794 SERIAL « 0x07001311000003f9 (8 bytes) 18:50:40.796 DRIVER « [REQ] [SendData] [confirmation] 18:50:40.795 SERIAL Buffer := 0x0107001311000003f9 (9 bytes) 18:50:40.795 SERIAL » [ACK] (0x06) 18:50:40.797 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.796 DRIVER ACK received from node for current transaction. RTT = 32.34 ms 18:50:40.835 SERIAL « 0x010b0004000705700615010090 (13 bytes) 18:50:40.839 CNTRLR [Node 007] [~] [Configuration] 21: 0 => 0 18:50:40.843 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:40.836 SERIAL Buffer := 0x010b0004000705700615010090 (13 bytes) 18:50:40.842 SERIAL » [ACK] (0x06) 18:50:40.846 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.849 SERIAL » 0x010a0013070370051e2512be (12 bytes) 18:50:40.847 CNTRLR » [Node 007] querying parameter #30 value... 18:50:40.844 DRIVER ACK already received, resolving transaction 18:50:40.845 DRIVER resuming send queue 18:50:40.848 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:40.849 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:40.854 SERIAL « [ACK] (0x06) 18:50:40.855 DRIVER ACK received from controller for current transaction 18:50:40.855 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.864 SERIAL « 0x0104011301e8 (6 bytes) 18:50:40.867 DRIVER « [RES] [SendData] [confirmation] 18:50:40.865 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:40.866 SERIAL » [ACK] (0x06) 18:50:40.868 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.875 SERIAL « 0x01 (1 bytes) 18:50:40.876 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.877 SERIAL « 0x07001312000002fb (8 bytes) 18:50:40.879 DRIVER « [REQ] [SendData] [confirmation] 18:50:40.878 SERIAL Buffer := 0x0107001312000002fb (9 bytes) 18:50:40.878 SERIAL » [ACK] (0x06) 18:50:40.880 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.879 DRIVER ACK received from node for current transaction. RTT = 30.42 ms 18:50:40.915 SERIAL « 0x01 (1 bytes) 18:50:40.915 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.917 SERIAL « 0x0b000400070570061e01009b (12 bytes) 18:50:40.919 CNTRLR [Node 007] [~] [Configuration] 30: 0 => 0 18:50:40.921 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:40.917 SERIAL Buffer := 0x010b000400070570061e01009b (13 bytes) 18:50:40.920 SERIAL » [ACK] (0x06) 18:50:40.922 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.925 SERIAL » 0x010a00130703700528251389 (12 bytes) 18:50:40.923 CNTRLR » [Node 007] querying parameter #40 value... 18:50:40.921 DRIVER ACK already received, resolving transaction 18:50:40.922 DRIVER resuming send queue 18:50:40.924 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:40.925 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:40.930 SERIAL « [ACK] (0x06) 18:50:40.930 DRIVER ACK received from controller for current transaction 18:50:40.931 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.936 SERIAL « 0x01 (1 bytes) 18:50:40.936 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.938 SERIAL « 0x04011301e8 (5 bytes) 18:50:40.940 DRIVER « [RES] [SendData] [confirmation] 18:50:40.939 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:40.939 SERIAL » [ACK] (0x06) 18:50:40.941 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.953 SERIAL « 0x01 (1 bytes) 18:50:40.953 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.955 DRIVER « [REQ] [SendData] [confirmation] 18:50:40.954 SERIAL « 0x07001313000003fb (8 bytes) 18:50:40.955 SERIAL Buffer := 0x0107001313000003fb (9 bytes) 18:50:40.955 SERIAL » [ACK] (0x06) 18:50:40.956 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:40.956 DRIVER ACK received from node for current transaction. RTT = 31.23 ms 18:50:40.991 SERIAL « 0x01 (1 bytes) 18:50:40.992 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:40.994 CNTRLR [Node 007] [~] [Configuration] 40: 5 => 5 18:50:40.997 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:40.993 SERIAL « 0x0b00040007057006280105a8 (12 bytes) 18:50:40.993 SERIAL Buffer := 0x010b00040007057006280105a8 (13 bytes) 18:50:40.996 SERIAL » [ACK] (0x06) 18:50:40.998 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.002 SERIAL » 0x010a0013070370052a25148c (12 bytes) 18:50:40.999 CNTRLR » [Node 007] querying parameter #42 value... 18:50:40.997 DRIVER ACK already received, resolving transaction 18:50:40.998 DRIVER resuming send queue 18:50:41.002 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:41.004 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:41.011 SERIAL « [ACK] (0x06) 18:50:41.011 DRIVER ACK received from controller for current transaction 18:50:41.012 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.016 SERIAL « 0x0104 (2 bytes) 18:50:41.017 SERIAL [incomplete] Buffer := 0x0104 (2 bytes) 18:50:41.020 SERIAL « 0x011301e8 (4 bytes) 18:50:41.021 DRIVER « [RES] [SendData] [confirmation] 18:50:41.020 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:41.021 SERIAL » [ACK] (0x06) 18:50:41.022 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.032 SERIAL « 0x01 (1 bytes) 18:50:41.033 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.035 DRIVER « [REQ] [SendData] [confirmation] 18:50:41.034 SERIAL « 0x07001314000003fc (8 bytes) 18:50:41.034 SERIAL Buffer := 0x0107001314000003fc (9 bytes) 18:50:41.035 SERIAL » [ACK] (0x06) 18:50:41.036 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.035 DRIVER ACK received from node for current transaction. RTT = 33.18 ms 18:50:41.074 SERIAL « 0x01 (1 bytes) 18:50:41.075 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.078 CNTRLR [Node 007] [~] [Configuration] 42: 0 => 0 18:50:41.081 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:41.076 SERIAL « 0x0c000400070670062a020000a8 (13 bytes) 18:50:41.077 SERIAL Buffer := 0x010c000400070670062a020000a8 (14 bytes) 18:50:41.080 SERIAL » [ACK] (0x06) 18:50:41.082 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.083 CNTRLR » [Node 007] querying parameter #60 value... 18:50:41.081 DRIVER ACK already received, resolving transaction 18:50:41.082 DRIVER resuming send queue 18:50:41.084 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:41.085 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:41.085 SERIAL » 0x010a0013070370053c25159b (12 bytes) 18:50:41.092 SERIAL « [ACK] (0x06) 18:50:41.092 DRIVER ACK received from controller for current transaction 18:50:41.093 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.096 SERIAL « 0x01 (1 bytes) 18:50:41.096 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.099 DRIVER « [RES] [SendData] [confirmation] 18:50:41.097 SERIAL « 0x04011301e8 (5 bytes) 18:50:41.098 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:41.098 SERIAL » [ACK] (0x06) 18:50:41.099 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.112 SERIAL « 0x01 (1 bytes) 18:50:41.113 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.117 DRIVER « [REQ] [SendData] [confirmation] 18:50:41.115 SERIAL « 0x07001315000003fd (8 bytes) 18:50:41.115 SERIAL Buffer := 0x0107001315000003fd (9 bytes) 18:50:41.116 SERIAL » [ACK] (0x06) 18:50:41.118 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.117 DRIVER ACK received from node for current transaction. RTT = 32.50 ms 18:50:41.151 SERIAL « 0x01 (1 bytes) 18:50:41.152 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.157 CNTRLR [Node 007] [~] [Configuration] 60: 1 => 1 18:50:41.159 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:41.154 SERIAL « 0x0b000400070570063c0101b8 (12 bytes) 18:50:41.155 SERIAL Buffer := 0x010b000400070570063c0101b8 (13 bytes) 18:50:41.158 SERIAL » [ACK] (0x06) 18:50:41.161 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.162 CNTRLR » [Node 007] querying parameter #61 value... 18:50:41.159 DRIVER ACK already received, resolving transaction 18:50:41.160 DRIVER resuming send queue 18:50:41.164 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:41.166 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:41.165 SERIAL » 0x010a0013070370053d251699 (12 bytes) 18:50:41.172 SERIAL « [ACK] (0x06) 18:50:41.173 DRIVER ACK received from controller for current transaction 18:50:41.174 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.178 SERIAL « 0x01 (1 bytes) 18:50:41.179 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.182 DRIVER « [RES] [SendData] [confirmation] 18:50:41.180 SERIAL « 0x04011301e8 (5 bytes) 18:50:41.181 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:41.181 SERIAL » [ACK] (0x06) 18:50:41.183 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.194 SERIAL « 0x01 (1 bytes) 18:50:41.195 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.197 DRIVER « [REQ] [SendData] [confirmation] 18:50:41.196 SERIAL « 0x07001316000002ff (8 bytes) 18:50:41.197 SERIAL Buffer := 0x0107001316000002ff (9 bytes) 18:50:41.197 SERIAL » [ACK] (0x06) 18:50:41.199 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.198 DRIVER ACK received from node for current transaction. RTT = 33.14 ms 18:50:41.234 SERIAL « 0x01 (1 bytes) 18:50:41.235 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.237 CNTRLR [Node 007] [~] [Configuration] 61: 99 => 99 18:50:41.239 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:41.241 CNTRLR » [Node 007] querying parameter #65 value... 18:50:41.240 DRIVER ACK already received, resolving transaction 18:50:41.240 DRIVER resuming send queue 18:50:41.243 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:41.244 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:41.236 SERIAL « 0x0b000400070570063d0163db (12 bytes) 18:50:41.236 SERIAL Buffer := 0x010b000400070570063d0163db (13 bytes) 18:50:41.239 SERIAL » [ACK] (0x06) 18:50:41.241 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.243 SERIAL » 0x010a001307037005412517e4 (12 bytes) 18:50:41.248 SERIAL « [ACK] (0x06) 18:50:41.250 DRIVER ACK received from controller for current transaction 18:50:41.251 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.256 SERIAL « 0x01 (1 bytes) 18:50:41.257 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.259 DRIVER « [RES] [SendData] [confirmation] 18:50:41.258 SERIAL « 0x04011301e8 (5 bytes) 18:50:41.258 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:41.259 SERIAL » [ACK] (0x06) 18:50:41.260 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.271 SERIAL « 0x01 (1 bytes) 18:50:41.271 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.273 SERIAL « 0x07001317000002fe (8 bytes) 18:50:41.275 DRIVER « [REQ] [SendData] [confirmation] 18:50:41.274 SERIAL Buffer := 0x0107001317000002fe (9 bytes) 18:50:41.274 SERIAL » [ACK] (0x06) 18:50:41.276 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.275 DRIVER ACK received from node for current transaction. RTT = 31.97 ms 18:50:41.314 SERIAL « 0x01 (1 bytes) 18:50:41.315 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.317 CNTRLR [Node 007] [~] [Configuration] 65: 100 => 100 18:50:41.319 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:41.316 SERIAL « 0x0c0004000706700641020064a7 (13 bytes) 18:50:41.316 SERIAL Buffer := 0x010c0004000706700641020064a7 (14 bytes) 18:50:41.319 SERIAL » [ACK] (0x06) 18:50:41.321 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.324 SERIAL » 0x010a001307037005422518e8 (12 bytes) 18:50:41.321 CNTRLR » [Node 007] querying parameter #66 value... 18:50:41.320 DRIVER ACK already received, resolving transaction 18:50:41.320 DRIVER resuming send queue 18:50:41.324 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:41.325 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:41.330 SERIAL « [ACK] (0x06) 18:50:41.331 DRIVER ACK received from controller for current transaction 18:50:41.331 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.336 SERIAL « 0x01 (1 bytes) 18:50:41.336 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.341 DRIVER « [RES] [SendData] [confirmation] 18:50:41.339 SERIAL « 0x04011301e8 (5 bytes) 18:50:41.339 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:41.340 SERIAL » [ACK] (0x06) 18:50:41.341 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.351 SERIAL « 0x01 (1 bytes) 18:50:41.352 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.353 SERIAL « 0x07001318000003f0 (8 bytes) 18:50:41.356 DRIVER « [REQ] [SendData] [confirmation] 18:50:41.354 SERIAL Buffer := 0x0107001318000003f0 (9 bytes) 18:50:41.355 SERIAL » [ACK] (0x06) 18:50:41.357 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.356 DRIVER ACK received from node for current transaction. RTT = 32.23 ms 18:50:41.393 SERIAL « 0x01 (1 bytes) 18:50:41.394 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.396 SERIAL « 0x0c0004000706700642020003c3 (13 bytes) 18:50:41.398 CNTRLR [Node 007] [~] [Configuration] 66: 3 => 3 18:50:41.401 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:41.397 SERIAL Buffer := 0x010c0004000706700642020003c3 (14 bytes) 18:50:41.400 SERIAL » [ACK] (0x06) 18:50:41.402 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.406 SERIAL » 0x010a001307037005432519e8 (12 bytes) 18:50:41.403 CNTRLR » [Node 007] querying parameter #67 value... 18:50:41.401 DRIVER ACK already received, resolving transaction 18:50:41.402 DRIVER resuming send queue 18:50:41.405 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:41.406 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:41.413 SERIAL « [ACK] (0x06) 18:50:41.413 DRIVER ACK received from controller for current transaction 18:50:41.414 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.417 SERIAL « 0x01 (1 bytes) 18:50:41.418 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.421 DRIVER « [RES] [SendData] [confirmation] 18:50:41.419 SERIAL « 0x04011301e8 (5 bytes) 18:50:41.420 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:41.420 SERIAL » [ACK] (0x06) 18:50:41.422 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.434 SERIAL « 0x01 (1 bytes) 18:50:41.434 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.436 DRIVER « [REQ] [SendData] [confirmation] 18:50:41.437 DRIVER ACK received from node for current transaction. RTT = 30.98 ms 18:50:41.435 SERIAL « 0x07001319000002f0 (8 bytes) 18:50:41.436 SERIAL Buffer := 0x0107001319000002f0 (9 bytes) 18:50:41.436 SERIAL » [ACK] (0x06) 18:50:41.437 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.474 SERIAL « 0x01 (1 bytes) 18:50:41.475 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.477 CNTRLR [Node 007] [~] [Configuration] 67: 0 => 0 18:50:41.479 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:41.476 SERIAL « 0x0b00040007057006430100c6 (12 bytes) 18:50:41.476 SERIAL Buffer := 0x010b00040007057006430100c6 (13 bytes) 18:50:41.479 SERIAL » [ACK] (0x06) 18:50:41.481 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.483 SERIAL » 0x010a00130703700544251aec (12 bytes) 18:50:41.481 CNTRLR » [Node 007] querying parameter #68 value... 18:50:41.480 DRIVER ACK already received, resolving transaction 18:50:41.480 DRIVER resuming send queue 18:50:41.482 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:41.483 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:41.489 SERIAL « [ACK] (0x06) 18:50:41.490 DRIVER ACK received from controller for current transaction 18:50:41.491 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.494 SERIAL « 0x01 (1 bytes) 18:50:41.495 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.497 SERIAL « 0x04011301e8 (5 bytes) 18:50:41.499 DRIVER « [RES] [SendData] [confirmation] 18:50:41.498 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:41.498 SERIAL » [ACK] (0x06) 18:50:41.499 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.510 SERIAL « 0x01 (1 bytes) 18:50:41.511 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.513 DRIVER « [REQ] [SendData] [confirmation] 18:50:41.512 SERIAL « 0x0700131a000002f3 (8 bytes) 18:50:41.512 SERIAL Buffer := 0x010700131a000002f3 (9 bytes) 18:50:41.512 SERIAL » [ACK] (0x06) 18:50:41.514 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.513 DRIVER ACK received from node for current transaction. RTT = 30.61 ms 18:50:41.550 SERIAL « 0x01 (1 bytes) 18:50:41.550 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.554 CNTRLR [Node 007] [~] [Configuration] 68: 0 => 0 18:50:41.557 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:41.553 SERIAL « 0x0b00040007057006440100c1 (12 bytes) 18:50:41.553 SERIAL Buffer := 0x010b00040007057006440100c1 (13 bytes) 18:50:41.556 SERIAL » [ACK] (0x06) 18:50:41.558 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.558 CNTRLR » [Node 007] querying parameter #110 value... 18:50:41.557 DRIVER ACK already received, resolving transaction 18:50:41.557 DRIVER resuming send queue 18:50:41.560 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:41.561 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:41.561 SERIAL » 0x010a0013070370056e251bc7 (12 bytes) 18:50:41.566 SERIAL « [ACK] (0x06) 18:50:41.567 DRIVER ACK received from controller for current transaction 18:50:41.568 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.572 SERIAL « 0x01 (1 bytes) 18:50:41.573 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.575 DRIVER « [RES] [SendData] [confirmation] 18:50:41.574 SERIAL « 0x04011301e8 (5 bytes) 18:50:41.575 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:41.575 SERIAL » [ACK] (0x06) 18:50:41.576 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.588 SERIAL « 0x01 (1 bytes) 18:50:41.588 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.590 SERIAL « 0x0700131b000002f2 (8 bytes) 18:50:41.591 DRIVER « [REQ] [SendData] [confirmation] 18:50:41.590 SERIAL Buffer := 0x010700131b000002f2 (9 bytes) 18:50:41.591 SERIAL » [ACK] (0x06) 18:50:41.592 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.592 DRIVER ACK received from node for current transaction. RTT = 30.90 ms 18:50:41.630 SERIAL « 0x01 (1 bytes) 18:50:41.631 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.633 CNTRLR [Node 007] [~] [Configuration] 110: 32536 => 32536 18:50:41.636 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:41.631 SERIAL « 0x0c000400070670066e027f188b (13 bytes) 18:50:41.632 SERIAL Buffer := 0x010c000400070670066e027f188b (14 bytes) 18:50:41.635 SERIAL » [ACK] (0x06) 18:50:41.638 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.641 SERIAL » 0x010a00130703700578251cd6 (12 bytes) 18:50:41.639 CNTRLR » [Node 007] querying parameter #120 value... 18:50:41.637 DRIVER ACK already received, resolving transaction 18:50:41.637 DRIVER resuming send queue 18:50:41.640 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:41.641 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:41.647 SERIAL « [ACK] (0x06) 18:50:41.647 DRIVER ACK received from controller for current transaction 18:50:41.648 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.652 SERIAL « 0x01 (1 bytes) 18:50:41.653 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.655 DRIVER « [RES] [SendData] [confirmation] 18:50:41.654 SERIAL « 0x04011301e8 (5 bytes) 18:50:41.654 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:41.654 SERIAL » [ACK] (0x06) 18:50:41.655 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.669 SERIAL « 0x01 (1 bytes) 18:50:41.669 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.671 DRIVER « [REQ] [SendData] [confirmation] 18:50:41.670 SERIAL « 0x0700131c000002f5 (8 bytes) 18:50:41.670 SERIAL Buffer := 0x010700131c000002f5 (9 bytes) 18:50:41.671 SERIAL » [ACK] (0x06) 18:50:41.673 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.673 DRIVER ACK received from node for current transaction. RTT = 30.90 ms 18:50:41.707 SERIAL « 0x010b (2 bytes) 18:50:41.708 SERIAL [incomplete] Buffer := 0x010b (2 bytes) 18:50:41.711 CNTRLR [Node 007] [~] [Configuration] 120: 5 => 5 18:50:41.713 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:41.715 CNTRLR [Node 007] [~] [Configuration] interviewComplete: true [Endpoint 0] [internal] => true 18:50:41.709 SERIAL « 0x00040007057006780105f8 (11 bytes) 18:50:41.710 SERIAL Buffer := 0x010b00040007057006780105f8 (13 bytes) 18:50:41.712 SERIAL » [ACK] (0x06) 18:50:41.714 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.713 DRIVER ACK already received, resolving transaction 18:50:41.714 DRIVER resuming send queue 18:50:41.716 DRIVER The remaining 6 messages are for sleeping nodes, not sending anything! 18:50:41.747 CNTRLR [Node 007] AssociationCC: skipping interview because Multi Channel Association is supported... 18:50:41.753 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:41.754 SERIAL » 0x010a001307038e0201251d57 (12 bytes) 18:50:41.748 CNTRLR [Node 007] [~] [Association] interviewComplete: true = [Endpoint 0] [internal] > true 18:50:41.750 CNTRLR [Node 007] MultiChannelAssociationCC: doing a partial interview... 18:50:41.751 CNTRLR » [Node 007] querying association group #1... 18:50:41.758 SERIAL « [ACK] (0x06) 18:50:41.759 DRIVER ACK received from controller for current transaction 18:50:41.759 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.765 SERIAL « 0x01 (1 bytes) 18:50:41.766 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.768 DRIVER « [RES] [SendData] [confirmation] 18:50:41.767 SERIAL « 0x04011301e8 (5 bytes) 18:50:41.767 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:41.768 SERIAL » [ACK] (0x06) 18:50:41.768 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.781 SERIAL « 0x01 (1 bytes) 18:50:41.782 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.784 DRIVER « [REQ] [SendData] [confirmation] 18:50:41.783 SERIAL « 0x0700131d000002f4 (8 bytes) 18:50:41.783 SERIAL Buffer := 0x010700131d000002f4 (9 bytes) 18:50:41.784 SERIAL » [ACK] (0x06) 18:50:41.785 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.784 DRIVER ACK received from node for current transaction. RTT = 30.96 ms 18:50:41.987 SERIAL « 0x01 (1 bytes) 18:50:41.987 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:41.991 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:41.994 CNTRLR « [Node 007] received information for association group #1: maximum # of nodes: 1 currently assigned nodes: 1 currently assigned endpoints: 18:50:41.988 SERIAL « 0x0c00040007068e03010100017a (13 bytes) 18:50:41.989 SERIAL Buffer := 0x010c00040007068e03010100017a (14 bytes) 18:50:41.991 SERIAL » [ACK] (0x06) 18:50:41.993 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:41.997 SERIAL » 0x010a001307038e0202251e57 (12 bytes) 18:50:41.992 DRIVER ACK already received, resolving transaction 18:50:41.992 DRIVER resuming send queue 18:50:41.996 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:41.997 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:41.995 CNTRLR » [Node 007] querying association group #2... 18:50:42.001 SERIAL « [ACK] (0x06) 18:50:42.002 DRIVER ACK received from controller for current transaction 18:50:42.003 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:42.008 SERIAL « 0x01 (1 bytes) 18:50:42.009 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:42.011 DRIVER « [RES] [SendData] [confirmation] 18:50:42.010 SERIAL « 0x04011301e8 (5 bytes) 18:50:42.010 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:42.011 SERIAL » [ACK] (0x06) 18:50:42.011 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:42.024 SERIAL « 0x01 (1 bytes) 18:50:42.025 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:42.026 DRIVER « [REQ] [SendData] [confirmation] 18:50:42.025 SERIAL « 0x0700131e000002f7 (8 bytes) 18:50:42.026 SERIAL Buffer := 0x010700131e000002f7 (9 bytes) 18:50:42.026 SERIAL » [ACK] (0x06) 18:50:42.028 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:42.027 DRIVER ACK received from node for current transaction. RTT = 30.56 ms 18:50:42.229 SERIAL « 0x01 (1 bytes) 18:50:42.229 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:42.235 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:42.237 CNTRLR « [Node 007] received information for association group #2: maximum # of nodes: 16 currently assigned nodes: currently assigned endpoints: 18:50:42.235 DRIVER ACK already received, resolving transaction 18:50:42.236 DRIVER resuming send queue 18:50:42.239 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:42.239 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:42.233 SERIAL « 0x0b00040007058e030210006d (12 bytes) 18:50:42.233 SERIAL Buffer := 0x010b00040007058e030210006d (13 bytes) 18:50:42.234 SERIAL » [ACK] (0x06) 18:50:42.236 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:42.239 SERIAL » 0x010a001307038e0203251f57 (12 bytes) 18:50:42.237 CNTRLR » [Node 007] querying association group #3... 18:50:42.243 SERIAL « [ACK] (0x06) 18:50:42.243 DRIVER ACK received from controller for current transaction 18:50:42.244 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:42.249 SERIAL « 0x01 (1 bytes) 18:50:42.250 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:42.251 SERIAL « 0x04011301e8 (5 bytes) 18:50:42.252 DRIVER « [RES] [SendData] [confirmation] 18:50:42.252 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:42.252 SERIAL » [ACK] (0x06) 18:50:42.253 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:42.265 SERIAL « 0x01 (1 bytes) 18:50:42.265 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:42.267 SERIAL « 0x0700131f000002f6 (8 bytes) 18:50:42.268 DRIVER « [REQ] [SendData] [confirmation] 18:50:42.267 SERIAL Buffer := 0x010700131f000002f6 (9 bytes) 18:50:42.268 SERIAL » [ACK] (0x06) 18:50:42.269 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:42.268 DRIVER ACK received from node for current transaction. RTT = 29.43 ms 18:50:42.471 SERIAL « 0x01 (1 bytes) 18:50:42.471 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:42.475 SERIAL « 0x0b00040007058e030310006c (12 bytes) 18:50:42.477 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:42.479 CNTRLR « [Node 007] received information for association group #3: maximum # of nodes: 16 currently assigned nodes: currently assigned endpoints: 18:50:42.476 SERIAL Buffer := 0x010b00040007058e030310006c (13 bytes) 18:50:42.476 SERIAL » [ACK] (0x06) 18:50:42.478 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:42.481 SERIAL » 0x010a001307038e020425206f (12 bytes) 18:50:42.477 DRIVER ACK already received, resolving transaction 18:50:42.478 DRIVER resuming send queue 18:50:42.480 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:42.481 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:42.479 CNTRLR » [Node 007] querying association group #4... 18:50:42.485 SERIAL « [ACK] (0x06) 18:50:42.485 DRIVER ACK received from controller for current transaction 18:50:42.486 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:42.492 SERIAL « 0x01 (1 bytes) 18:50:42.492 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:42.494 DRIVER « [RES] [SendData] [confirmation] 18:50:42.493 SERIAL « 0x04011301e8 (5 bytes) 18:50:42.493 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:42.494 SERIAL » [ACK] (0x06) 18:50:42.495 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:42.507 SERIAL « 0x01 (1 bytes) 18:50:42.507 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:42.509 SERIAL « 0x07001320000002c9 (8 bytes) 18:50:42.510 DRIVER « [REQ] [SendData] [confirmation] 18:50:42.511 DRIVER ACK received from node for current transaction. RTT = 30.27 ms 18:50:42.510 SERIAL Buffer := 0x0107001320000002c9 (9 bytes) 18:50:42.510 SERIAL » [ACK] (0x06) 18:50:42.511 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:42.713 SERIAL « 0x01 (1 bytes) 18:50:42.713 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:42.717 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:42.719 CNTRLR « [Node 007] received information for association group #4: maximum # of nodes: 16 currently assigned nodes: currently assigned endpoints: 18:50:42.715 SERIAL « 0x0b00040007058e030410006b (12 bytes) 18:50:42.716 SERIAL Buffer := 0x010b00040007058e030410006b (13 bytes) 18:50:42.716 SERIAL » [ACK] (0x06) 18:50:42.719 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:42.721 SERIAL » 0x010a001307038e020525216f (12 bytes) 18:50:42.717 DRIVER ACK already received, resolving transaction 18:50:42.718 DRIVER resuming send queue 18:50:42.721 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:42.722 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:42.719 CNTRLR » [Node 007] querying association group #5... 18:50:42.725 SERIAL « [ACK] (0x06) 18:50:42.726 DRIVER ACK received from controller for current transaction 18:50:42.727 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:42.732 SERIAL « 0x01 (1 bytes) 18:50:42.733 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:42.736 DRIVER « [RES] [SendData] [confirmation] 18:50:42.735 SERIAL « 0x04011301e8 (5 bytes) 18:50:42.736 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:42.736 SERIAL » [ACK] (0x06) 18:50:42.737 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:42.748 SERIAL « 0x01 (1 bytes) 18:50:42.749 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:42.750 SERIAL « 0x07001321000002c8 (8 bytes) 18:50:42.752 DRIVER « [REQ] [SendData] [confirmation] 18:50:42.751 SERIAL Buffer := 0x0107001321000002c8 (9 bytes) 18:50:42.751 SERIAL » [ACK] (0x06) 18:50:42.753 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:42.752 DRIVER ACK received from node for current transaction. RTT = 30.84 ms 18:50:42.954 SERIAL « 0x01 (1 bytes) 18:50:42.955 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:42.957 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:42.959 CNTRLR « [Node 007] received information for association group #5: maximum # of nodes: 16 currently assigned nodes: currently assigned endpoints: 18:50:42.956 SERIAL « 0x0b00040007058e030510006a (12 bytes) 18:50:42.956 SERIAL Buffer := 0x010b00040007058e030510006a (13 bytes) 18:50:42.957 SERIAL » [ACK] (0x06) 18:50:42.959 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:42.958 DRIVER ACK already received, resolving transaction 18:50:42.958 DRIVER resuming send queue 18:50:42.962 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:42.966 DRIVER workOffSendQueue > skipping because a transaction is pending 18:50:42.960 CNTRLR » [Node 007] supports Z-Wave+, assigning ourselves to the Lifeline group... 18:50:42.964 SERIAL » 0x010d001307068e0101000100252268 (15 bytes) 18:50:42.970 SERIAL « [ACK] (0x06) 18:50:42.970 DRIVER ACK received from controller for current transaction 18:50:42.971 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:42.977 SERIAL « 0x01 (1 bytes) 18:50:42.977 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:42.979 DRIVER « [RES] [SendData] [confirmation] 18:50:42.978 SERIAL « 0x04011301e8 (5 bytes) 18:50:42.979 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:42.979 SERIAL » [ACK] (0x06) 18:50:42.980 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:42.994 SERIAL « 0x01 (1 bytes) 18:50:42.995 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:42.997 DRIVER « [REQ] [SendData] [final] 18:50:42.999 CNTRLR [Node 007] [~] [Multi Channel Association] interviewCo [Endpoint 0] [internal] mplete: true => true 18:50:42.995 SERIAL « 0x07001322000002cb (8 bytes) 18:50:42.996 SERIAL Buffer := 0x0107001322000002cb (9 bytes) 18:50:42.996 SERIAL » [ACK] (0x06) 18:50:42.998 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:42.997 DRIVER ACK already received, resolving transaction 18:50:42.998 DRIVER resuming send queue 18:50:43.000 DRIVER The remaining 6 messages are for sleeping nodes, not sending anything! 18:50:43.025 CNTRLR [Node 007] AssociationGroupInfoCC: doing a partial interview... 18:50:43.032 DRIVER workOffSendQueue > sending next message (SendData) to node 7... 18:50:43.033 SERIAL » 0x010c001307057104000800252399 (14 bytes) 18:50:43.027 CNTRLR [Node 007] [~] [Association Group Information] intervi [Endpoint 0] [internal] ewComplete: true => true 18:50:43.029 CNTRLR [Node 007] NotificationCC: doing a partial interview... 18:50:43.030 CNTRLR » [Node 007] querying notification status for Power Management... 18:50:43.037 SERIAL « [ACK] (0x06) 18:50:43.037 DRIVER ACK received from controller for current transaction 18:50:43.037 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:43.043 SERIAL « 0x01 (1 bytes) 18:50:43.044 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:43.045 SERIAL « 0x04011301e8 (5 bytes) 18:50:43.047 DRIVER « [RES] [SendData] [confirmation] 18:50:43.046 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:43.046 SERIAL » [ACK] (0x06) 18:50:43.047 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:43.059 SERIAL « 0x01 (1 bytes) 18:50:43.060 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:43.061 SERIAL « 0x07001323000002ca (8 bytes) 18:50:43.063 DRIVER « [REQ] [SendData] [confirmation] 18:50:43.062 SERIAL Buffer := 0x0107001323000002ca (9 bytes) 18:50:43.062 SERIAL » [ACK] (0x06) 18:50:43.064 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:43.063 DRIVER ACK received from node for current transaction. RTT = 30.42 ms 18:50:43.072 SERIAL « 0x01 (1 bytes) 18:50:43.072 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:43.074 SERIAL « 0x0f00040007097105000000ff08fe0087 (16 bytes) 18:50:43.076 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:43.081 CNTRLR [Node 007] [~] [Notification] Power Management[unknown]: 254 => 2 [Endpoint 0] 54 18:50:43.075 SERIAL Buffer := 0x010f00040007097105000000ff08fe0087 (17 bytes) 18:50:43.076 SERIAL » [ACK] (0x06) 18:50:43.078 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:43.077 DRIVER ACK already received, resolving transaction 18:50:43.077 DRIVER resuming send queue 18:50:43.084 DRIVER The remaining 6 messages are for sleeping nodes, not sending anything! 18:50:43.083 CNTRLR [Node 007] [~] [Notification] interviewComplete: true [Endpoint 0] [internal] => true 18:50:43.112 CNTRLR [Node 007] Interview stage completed: CommandClasses 18:50:43.115 DRIVER workOffSendQueue > sending next message (GetRoutingInfo) to node 7... 18:50:43.115 SERIAL » 0x01070080070000007f (9 bytes) 18:50:43.113 CNTRLR [Node 007] Interview stage completed: OverwriteConfig 18:50:43.113 CNTRLR » [Node 007] requesting node neighbors... 18:50:43.121 SERIAL « [ACK] (0x06) 18:50:43.121 DRIVER ACK received from controller for current transaction 18:50:43.122 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:43.123 SERIAL « 0x01 (1 bytes) 18:50:43.123 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:43.126 SERIAL « 0x200180a900000000000000000000000000000000000000000000000000000000f (33 bytes) 7 18:50:43.128 DRIVER « [RES] [GetRoutingInfo] [final] 18:50:43.130 CNTRLR « [Node 007] node neighbors received: 1, 4, 6, 8 18:50:43.127 SERIAL Buffer := 0x01200180a9000000000000000000000000000000000000000000000 (34 bytes) 00000000000f7 18:50:43.127 SERIAL » [ACK] (0x06) 18:50:43.130 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:43.128 DRIVER ACK already received, resolving transaction 18:50:43.129 DRIVER resuming send queue 18:50:43.135 DRIVER The remaining 6 messages are for sleeping nodes, not sending anything! 18:50:43.131 CNTRLR [Node 007] Interview stage completed: Neighbors 18:50:43.131 CNTRLR [Node 007] Interview completed 18:50:43.131 CNTRLR [Node 007] The node is ready to be used 18:50:43.132 CNTRLR All nodes are ready to be used 18:50:50.898 SERIAL « 0x01 (1 bytes) 18:50:50.899 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:50.904 CNTRLR [Node 005] [~] [Battery] level: 58 => 58 [Endpoint 0] 18:50:50.909 DRIVER handling application command request Battery (0x80) for node 5 18:50:50.901 SERIAL « 0x09000400050380033a4d (10 bytes) 18:50:50.901 SERIAL Buffer := 0x0109000400050380033a4d (11 bytes) 18:50:50.908 SERIAL » [ACK] (0x06) 18:50:50.910 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:50.907 CNTRLR [Node 005] [~] [Battery] isLow: false => false [Endpoint 0] 18:50:50.916 SERIAL « 0x01 (1 bytes) 18:50:50.917 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:50.918 SERIAL « 0x0c0004000506430301420708f8 (13 bytes) 18:50:50.920 CNTRLR [Node 005] [~] [Thermostat Setpoint] setpoint[1]: 16 => 18 [Endpoint 0] 18:50:50.922 DRIVER handling application command request Thermostat Setpoint (0x43) for node 5 18:50:50.918 SERIAL Buffer := 0x010c0004000506430301420708f8 (14 bytes) 18:50:50.922 SERIAL » [ACK] (0x06) 18:50:50.923 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:50.936 SERIAL « 0x01 (1 bytes) 18:50:50.937 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:50.938 SERIAL « 0x0a00040005044608007fc1 (11 bytes) 18:50:50.941 CNTRLR [Node 005] [~] [Climate Control Schedule] overrideType: 0 => 0 [Endpoint 0] 18:50:50.944 DRIVER handling application command request Climate Control Schedule (0x46) for node 5 18:50:50.939 SERIAL Buffer := 0x010a00040005044608007fc1 (12 bytes) 18:50:50.944 SERIAL » [ACK] (0x06) 18:50:50.945 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:50.943 CNTRLR [Node 005] [~] [Climate Control Schedule] overrideState: "Unused" [Endpoint 0] => "Unused" 18:50:50.957 SERIAL « 0x01 (1 bytes) 18:50:50.958 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:50.961 DRIVER handling application command request Wake Up (0x84) for node 5 18:50:50.962 CNTRLR « [Node 005] received wakeup notification 18:50:50.959 SERIAL « 0x080004000502840777 (9 bytes) 18:50:50.959 SERIAL Buffer := 0x01080004000502840777 (10 bytes) 18:50:50.960 SERIAL » [ACK] (0x06) 18:50:50.965 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:50.966 SERIAL » 0x010a00130503430200250581 (12 bytes) 18:50:50.966 DRIVER workOffSendQueue > sending next message (SendData) to node 5... 18:50:50.963 CNTRLR [Node 005] The node is now awake. 18:50:50.970 SERIAL « [ACK] (0x06) 18:50:50.971 DRIVER Unexpected ACK received 18:50:50.971 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:50.977 SERIAL « 0x01 (1 bytes) 18:50:50.978 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:50.980 DRIVER « [RES] [SendData] [confirmation] 18:50:50.979 SERIAL « 0x04011301e8 (5 bytes) 18:50:50.979 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:50.980 SERIAL » [ACK] (0x06) 18:50:50.980 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:50.994 SERIAL « 0x01 (1 bytes) 18:50:50.995 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:50.998 DRIVER « [REQ] [SendData] [confirmation] 18:50:50.996 SERIAL « 0x07001305000002ec (8 bytes) 18:50:50.997 SERIAL Buffer := 0x0107001305000002ec (9 bytes) 18:50:50.997 SERIAL » [ACK] (0x06) 18:50:50.999 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:50.999 DRIVER ACK received from node for current transaction. RTT = 32.44 ms 18:50:52.033 DRIVER The transaction timed out 18:50:52.034 CNTRLR Thermostat Setpoint: Interview failed: The transaction timed out 18:50:52.034 DRIVER resuming send queue 18:50:52.036 DRIVER The remaining 5 messages are for sleeping nodes, not sending anything! 18:50:52.071 CNTRLR [Node 005] BatteryCC: doing a partial interview... 18:50:52.074 DRIVER workOffSendQueue > sending next message (SendData) to node 5... 18:50:52.075 SERIAL » 0x0109001305028002252461 (11 bytes) 18:50:52.072 CNTRLR » [Node 005] querying battery status... 18:50:52.079 SERIAL « [ACK] (0x06) 18:50:52.080 DRIVER ACK received from controller for current transaction 18:50:52.080 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:52.086 SERIAL « 0x01 (1 bytes) 18:50:52.086 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:52.087 SERIAL « 0x04011301e8 (5 bytes) 18:50:52.089 DRIVER « [RES] [SendData] [confirmation] 18:50:52.088 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:52.089 SERIAL » [ACK] (0x06) 18:50:52.090 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:52.103 SERIAL « 0x01 (1 bytes) 18:50:52.103 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:52.106 DRIVER « [REQ] [SendData] [confirmation] 18:50:52.104 SERIAL « 0x07001324000003cc (8 bytes) 18:50:52.105 SERIAL Buffer := 0x0107001324000003cc (9 bytes) 18:50:52.105 SERIAL » [ACK] (0x06) 18:50:52.108 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:52.107 DRIVER ACK received from node for current transaction. RTT = 32.38 ms 18:50:52.113 SERIAL « 0x01 (1 bytes) 18:50:52.113 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:52.117 CNTRLR [Node 005] [~] [Battery] level: 58 => 58 [Endpoint 0] 18:50:52.121 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:52.115 SERIAL « 0x09000400050380033a4d (10 bytes) 18:50:52.115 SERIAL Buffer := 0x0109000400050380033a4d (11 bytes) 18:50:52.120 SERIAL » [ACK] (0x06) 18:50:52.123 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:52.119 CNTRLR [Node 005] [~] [Battery] isLow: false => false [Endpoint 0] 18:50:52.124 CNTRLR « [Node 005] received response for battery information: level: 58 18:50:52.125 CNTRLR [Node 005] [~] [Battery] interviewComplete: true => tr [Endpoint 0] [internal] ue 18:50:52.121 DRIVER ACK already received, resolving transaction 18:50:52.122 DRIVER resuming send queue 18:50:52.126 DRIVER The remaining 5 messages are for sleeping nodes, not sending anything! 18:50:52.163 CNTRLR [Node 005] WakeUpCC: doing a partial interview... 18:50:52.166 DRIVER workOffSendQueue > sending next message (SendData) to node 5... 18:50:52.167 SERIAL » 0x0109001305028405252563 (11 bytes) 18:50:52.164 CNTRLR » [Node 005] retrieving wakeup interval from the device... 18:50:52.172 SERIAL « [ACK] (0x06) 18:50:52.173 DRIVER ACK received from controller for current transaction 18:50:52.173 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:52.177 SERIAL « 0x01 (1 bytes) 18:50:52.177 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:52.180 SERIAL « 0x04011301e8 (5 bytes) 18:50:52.181 DRIVER « [RES] [SendData] [confirmation] 18:50:52.180 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:52.181 SERIAL » [ACK] (0x06) 18:50:52.182 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:52.195 SERIAL « 0x01 (1 bytes) 18:50:52.196 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:52.198 DRIVER « [REQ] [SendData] [confirmation] 18:50:52.197 SERIAL « 0x07001325000002cc (8 bytes) 18:50:52.197 SERIAL Buffer := 0x0107001325000002cc (9 bytes) 18:50:52.198 SERIAL » [ACK] (0x06) 18:50:52.200 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:52.199 DRIVER ACK received from node for current transaction. RTT = 32.24 ms 18:50:52.205 SERIAL « 0x01 (1 bytes) 18:50:52.205 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:52.209 CNTRLR [Node 005] [~] [Wake Up] wakeUpInterval: 300 => 300 [Endpoint 0] 18:50:52.213 DRIVER « [REQ] [ApplicationCommand] [final] 18:50:52.206 SERIAL « 0x0c0004000506840600012c015a (13 bytes) 18:50:52.207 SERIAL Buffer := 0x010c0004000506840600012c015a (14 bytes) 18:50:52.213 SERIAL » [ACK] (0x06) 18:50:52.215 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:52.211 CNTRLR [Node 005] [~] [Wake Up] controllerNodeId: 1 => 1 [Endpoint 0] 18:50:52.215 CNTRLR « [Node 005] received wakeup configuration: wakeup interval: 300 seconds controller node: 1 18:50:52.216 CNTRLR [Node 005] [~] [Wake Up] interviewComplete: true => tr [Endpoint 0] [internal] ue 18:50:52.214 DRIVER ACK already received, resolving transaction 18:50:52.214 DRIVER resuming send queue 18:50:52.217 DRIVER The remaining 5 messages are for sleeping nodes, not sending anything! 18:50:52.254 CNTRLR [Node 005] Interview stage completed: CommandClasses 18:50:52.257 DRIVER workOffSendQueue > sending next message (GetRoutingInfo) to node 5... 18:50:52.257 SERIAL » 0x01070080050000007d (9 bytes) 18:50:52.255 CNTRLR [Node 005] Interview stage completed: OverwriteConfig 18:50:52.256 CNTRLR » [Node 005] requesting node neighbors... 18:50:52.262 SERIAL « [ACK] (0x06) 18:50:52.263 DRIVER ACK received from controller for current transaction 18:50:52.263 SERIAL « 0x0601200180 (5 bytes) 18:50:52.264 SERIAL [incomplete] Buffer := 0x01200180 (4 bytes) 18:50:52.267 DRIVER « [RES] [GetRoutingInfo] [final] 18:50:52.269 CNTRLR « [Node 005] node neighbors received: 1 18:50:52.266 SERIAL « 0x01000000000000000000000000000000000000000000000000000000005f (30 bytes) 18:50:52.266 SERIAL Buffer := 0x0120018001000000000000000000000000000000000000000000000 (34 bytes) 000000000005f 18:50:52.267 SERIAL » [ACK] (0x06) 18:50:52.270 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:52.268 DRIVER ACK already received, resolving transaction 18:50:52.268 DRIVER resuming send queue 18:50:52.272 DRIVER The remaining 5 messages are for sleeping nodes, not sending anything! 18:50:52.270 CNTRLR [Node 005] Interview stage completed: Neighbors 18:50:52.301 CNTRLR [Node 005] Interview completed 18:50:53.303 CNTRLR » [Node 005] Sending node back to sleep... 18:50:53.305 DRIVER workOffSendQueue > sending next message (SendData) to node 5... 18:50:53.306 SERIAL » 0x010900130502840825266d (11 bytes) 18:50:53.310 SERIAL « [ACK] (0x06) 18:50:53.311 DRIVER ACK received from controller for current transaction 18:50:53.311 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:53.316 SERIAL « 0x01 (1 bytes) 18:50:53.317 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:53.319 DRIVER « [RES] [SendData] [confirmation] 18:50:53.318 SERIAL « 0x04011301e8 (5 bytes) 18:50:53.319 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:50:53.319 SERIAL » [ACK] (0x06) 18:50:53.320 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:53.333 SERIAL « 0x01 (1 bytes) 18:50:53.334 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:50:53.336 DRIVER « [REQ] [SendData] [final] 18:50:53.338 CNTRLR [Node 005] The node is now asleep. 18:50:53.335 SERIAL « 0x07001326000003ce (8 bytes) 18:50:53.335 SERIAL Buffer := 0x0107001326000003ce (9 bytes) 18:50:53.335 SERIAL » [ACK] (0x06) 18:50:53.337 SERIAL The receive buffer is empty, waiting for the next chunk... 18:50:53.336 DRIVER ACK already received, resolving transaction 18:50:53.337 DRIVER resuming send queue 18:50:53.340 DRIVER The remaining 5 messages are for sleeping nodes, not sending anything! 18:50:53.342 DRIVER The remaining 5 messages are for sleeping nodes, not sending anything! 18:50:53.339 CNTRLR [Node 005] Node asleep 18:51:09.746 DRIVER The remaining 6 messages are for sleeping nodes, not sending anything! 18:51:09.836 SERIAL « 0x01 (1 bytes) 18:51:09.837 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:09.841 CNTRLR [Node 002] [~] [Battery] level: 82 => 82 [Endpoint 0] 18:51:09.845 DRIVER handling application command request Battery (0x80) for node 2 18:51:09.840 SERIAL « 0x09000400020380035222 (10 bytes) 18:51:09.840 SERIAL Buffer := 0x0109000400020380035222 (11 bytes) 18:51:09.844 SERIAL » [ACK] (0x06) 18:51:09.848 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:09.843 CNTRLR [Node 002] [~] [Battery] isLow: false => false [Endpoint 0] 18:51:09.885 SERIAL « 0x01 (1 bytes) 18:51:09.886 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:09.888 CNTRLR [Node 002] [~] [Thermostat Setpoint] setpoint[1]: 20 => 21 [Endpoint 0] 18:51:09.890 DRIVER handling application command request Thermostat Setpoint (0x43) for node 2 18:51:09.887 SERIAL « 0x0c0004000206430301420834cc (13 bytes) 18:51:09.887 SERIAL Buffer := 0x010c0004000206430301420834cc (14 bytes) 18:51:09.889 SERIAL » [ACK] (0x06) 18:51:09.891 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:09.905 SERIAL « 0x01 (1 bytes) 18:51:09.906 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:09.909 CNTRLR [Node 002] [~] [Climate Control Schedule] overrideType: 0 => 0 [Endpoint 0] 18:51:09.912 DRIVER handling application command request Climate Control Schedule (0x46) for node 2 18:51:09.907 SERIAL « 0x0a00040002044608007fc6 (11 bytes) 18:51:09.908 SERIAL Buffer := 0x010a00040002044608007fc6 (12 bytes) 18:51:09.912 SERIAL » [ACK] (0x06) 18:51:09.913 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:09.911 CNTRLR [Node 002] [~] [Climate Control Schedule] overrideState: "Unused" [Endpoint 0] => "Unused" 18:51:09.927 SERIAL « 0x01080004000202840770 (10 bytes) 18:51:09.930 DRIVER handling application command request Wake Up (0x84) for node 2 18:51:09.931 CNTRLR « [Node 002] received wakeup notification 18:51:09.928 SERIAL Buffer := 0x01080004000202840770 (10 bytes) 18:51:09.929 SERIAL » [ACK] (0x06) 18:51:09.934 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:09.936 SERIAL » 0x010a00130203430200250685 (12 bytes) 18:51:09.935 DRIVER workOffSendQueue > sending next message (SendData) to node 2... 18:51:09.932 CNTRLR [Node 002] The node is now awake. 18:51:09.940 SERIAL « [ACK] (0x06) 18:51:09.941 DRIVER Unexpected ACK received 18:51:09.941 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:09.946 SERIAL « 0x01 (1 bytes) 18:51:09.946 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:09.949 DRIVER « [RES] [SendData] [confirmation] 18:51:09.948 SERIAL « 0x04011301e8 (5 bytes) 18:51:09.948 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:51:09.948 SERIAL » [ACK] (0x06) 18:51:09.949 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:09.963 SERIAL « 0x01 (1 bytes) 18:51:09.963 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:09.965 SERIAL « 0x07001306000003ee (8 bytes) 18:51:09.969 DRIVER « [REQ] [SendData] [confirmation] 18:51:09.968 SERIAL Buffer := 0x0107001306000003ee (9 bytes) 18:51:09.969 SERIAL » [ACK] (0x06) 18:51:09.971 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:09.970 DRIVER ACK received from node for current transaction. RTT = 34.27 ms 18:51:11.006 DRIVER The transaction timed out 18:51:11.007 CNTRLR Thermostat Setpoint: Interview failed: The transaction timed out 18:51:11.006 DRIVER resuming send queue 18:51:11.008 DRIVER The remaining 5 messages are for sleeping nodes, not sending anything! 18:51:11.046 CNTRLR [Node 002] BatteryCC: doing a partial interview... 18:51:11.048 DRIVER workOffSendQueue > sending next message (SendData) to node 2... 18:51:11.048 SERIAL » 0x0109001302028002252765 (11 bytes) 18:51:11.046 CNTRLR » [Node 002] querying battery status... 18:51:11.052 SERIAL « [ACK] (0x06) 18:51:11.053 DRIVER ACK received from controller for current transaction 18:51:11.054 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:11.059 SERIAL « 0x01 (1 bytes) 18:51:11.059 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:11.062 DRIVER « [RES] [SendData] [confirmation] 18:51:11.060 SERIAL « 0x04011301e8 (5 bytes) 18:51:11.061 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:51:11.061 SERIAL » [ACK] (0x06) 18:51:11.063 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:11.076 SERIAL « 0x01 (1 bytes) 18:51:11.077 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:11.079 DRIVER « [REQ] [SendData] [confirmation] 18:51:11.078 SERIAL « 0x07001327000002ce (8 bytes) 18:51:11.078 SERIAL Buffer := 0x0107001327000002ce (9 bytes) 18:51:11.078 SERIAL » [ACK] (0x06) 18:51:11.080 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:11.079 DRIVER ACK received from node for current transaction. RTT = 31.07 ms 18:51:11.086 SERIAL « 0x01 (1 bytes) 18:51:11.086 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:11.089 CNTRLR [Node 002] [~] [Battery] level: 82 => 82 [Endpoint 0] 18:51:11.093 DRIVER « [REQ] [ApplicationCommand] [final] 18:51:11.087 SERIAL « 0x09000400020380035222 (10 bytes) 18:51:11.087 SERIAL Buffer := 0x0109000400020380035222 (11 bytes) 18:51:11.092 SERIAL » [ACK] (0x06) 18:51:11.095 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:11.091 CNTRLR [Node 002] [~] [Battery] isLow: false => false [Endpoint 0] 18:51:11.096 CNTRLR « [Node 002] received response for battery information: level: 82 18:51:11.097 CNTRLR [Node 002] [~] [Battery] interviewComplete: true => tr [Endpoint 0] [internal] ue 18:51:11.094 DRIVER ACK already received, resolving transaction 18:51:11.094 DRIVER resuming send queue 18:51:11.099 DRIVER The remaining 5 messages are for sleeping nodes, not sending anything! 18:51:11.131 CNTRLR [Node 002] WakeUpCC: doing a partial interview... 18:51:11.133 DRIVER workOffSendQueue > sending next message (SendData) to node 2... 18:51:11.134 SERIAL » 0x0109001302028405252869 (11 bytes) 18:51:11.132 CNTRLR » [Node 002] retrieving wakeup interval from the device... 18:51:11.138 SERIAL « [ACK] (0x06) 18:51:11.138 DRIVER ACK received from controller for current transaction 18:51:11.139 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:11.144 SERIAL « 0x01 (1 bytes) 18:51:11.145 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:11.148 DRIVER « [RES] [SendData] [confirmation] 18:51:11.147 SERIAL « 0x04011301e8 (5 bytes) 18:51:11.147 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:51:11.147 SERIAL » [ACK] (0x06) 18:51:11.148 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:11.162 SERIAL « 0x01 (1 bytes) 18:51:11.163 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:11.167 DRIVER « [REQ] [SendData] [confirmation] 18:51:11.164 SERIAL « 0x07001328000002c1 (8 bytes) 18:51:11.165 SERIAL Buffer := 0x0107001328000002c1 (9 bytes) 18:51:11.166 SERIAL » [ACK] (0x06) 18:51:11.169 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:11.168 DRIVER ACK received from node for current transaction. RTT = 34.14 ms 18:51:11.172 SERIAL « 0x01 (1 bytes) 18:51:11.173 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:11.178 CNTRLR [Node 002] [~] [Wake Up] wakeUpInterval: 300 => 300 [Endpoint 0] 18:51:11.183 DRIVER « [REQ] [ApplicationCommand] [final] 18:51:11.175 SERIAL « 0x0c0004000206840600012c015d (13 bytes) 18:51:11.176 SERIAL Buffer := 0x010c0004000206840600012c015d (14 bytes) 18:51:11.182 SERIAL » [ACK] (0x06) 18:51:11.186 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:11.180 CNTRLR [Node 002] [~] [Wake Up] controllerNodeId: 1 => 1 [Endpoint 0] 18:51:11.186 CNTRLR « [Node 002] received wakeup configuration: wakeup interval: 300 seconds controller node: 1 18:51:11.187 CNTRLR [Node 002] [~] [Wake Up] interviewComplete: true => tr [Endpoint 0] [internal] ue 18:51:11.184 DRIVER ACK already received, resolving transaction 18:51:11.185 DRIVER resuming send queue 18:51:11.189 DRIVER The remaining 5 messages are for sleeping nodes, not sending anything! 18:51:11.220 CNTRLR [Node 002] Interview stage completed: CommandClasses 18:51:11.222 DRIVER workOffSendQueue > sending next message (GetRoutingInfo) to node 2... 18:51:11.223 SERIAL » 0x01070080020000007a (9 bytes) 18:51:11.221 CNTRLR [Node 002] Interview stage completed: OverwriteConfig 18:51:11.221 CNTRLR » [Node 002] requesting node neighbors... 18:51:11.230 SERIAL « [ACK] (0x06) 18:51:11.231 DRIVER ACK received from controller for current transaction 18:51:11.231 SERIAL « 0x06012001800100000000000000000000000000000000 (22 bytes) 18:51:11.232 SERIAL [incomplete] Buffer := 0x012001800100000000000000000000000000000000 (21 bytes) 18:51:11.234 DRIVER « [RES] [GetRoutingInfo] [final] 18:51:11.236 CNTRLR « [Node 002] node neighbors received: 1 18:51:11.233 SERIAL « 0x0000000000000000000000005f (13 bytes) 18:51:11.233 SERIAL Buffer := 0x0120018001000000000000000000000000000000000000000000000 (34 bytes) 000000000005f 18:51:11.234 SERIAL » [ACK] (0x06) 18:51:11.236 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:11.234 DRIVER ACK already received, resolving transaction 18:51:11.235 DRIVER resuming send queue 18:51:11.239 DRIVER The remaining 5 messages are for sleeping nodes, not sending anything! 18:51:11.236 CNTRLR [Node 002] Interview stage completed: Neighbors 18:51:11.237 CNTRLR [Node 002] Interview completed 18:51:12.239 CNTRLR » [Node 002] Sending node back to sleep... 18:51:12.241 DRIVER workOffSendQueue > sending next message (SendData) to node 2... 18:51:12.241 SERIAL » 0x0109001302028408252965 (11 bytes) 18:51:12.245 SERIAL « [ACK] (0x06) 18:51:12.249 DRIVER ACK received from controller for current transaction 18:51:12.250 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:12.252 SERIAL « 0x01 (1 bytes) 18:51:12.252 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:12.253 SERIAL « 0x04011301e8 (5 bytes) 18:51:12.255 DRIVER « [RES] [SendData] [confirmation] 18:51:12.254 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:51:12.255 SERIAL » [ACK] (0x06) 18:51:12.255 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:12.268 SERIAL « 0x01 (1 bytes) 18:51:12.269 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:12.270 SERIAL « 0x07001329000002c0 (8 bytes) 18:51:12.272 DRIVER « [REQ] [SendData] [final] 18:51:12.274 CNTRLR [Node 002] The node is now asleep. 18:51:12.271 SERIAL Buffer := 0x0107001329000002c0 (9 bytes) 18:51:12.271 SERIAL » [ACK] (0x06) 18:51:12.273 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:12.272 DRIVER ACK already received, resolving transaction 18:51:12.273 DRIVER resuming send queue 18:51:12.276 DRIVER The remaining 5 messages are for sleeping nodes, not sending anything! 18:51:12.276 DRIVER The remaining 5 messages are for sleeping nodes, not sending anything! 18:51:12.275 CNTRLR [Node 002] Node asleep 18:51:17.867 SERIAL « 0x01 (1 bytes) 18:51:17.868 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:17.871 CNTRLR [Node 006] [~] [Battery] level: 40 => 40 [Endpoint 0] 18:51:17.874 DRIVER handling application command request Battery (0x80) for node 6 18:51:17.869 SERIAL « 0x0900040006038003285c (10 bytes) 18:51:17.869 SERIAL Buffer := 0x010900040006038003285c (11 bytes) 18:51:17.874 SERIAL » [ACK] (0x06) 18:51:17.875 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:17.872 CNTRLR [Node 006] [~] [Battery] isLow: false => false [Endpoint 0] 18:51:17.889 SERIAL « 0x01 (1 bytes) 18:51:17.889 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:17.893 CNTRLR [Node 006] [~] [Thermostat Setpoint] setpoint[1]: 4 => 4 [Endpoint 0] 18:51:17.895 DRIVER handling application command request Thermostat Setpoint (0x43) for node 6 18:51:17.892 SERIAL « 0x0c000400060643030142019065 (13 bytes) 18:51:17.892 SERIAL Buffer := 0x010c000400060643030142019065 (14 bytes) 18:51:17.895 SERIAL » [ACK] (0x06) 18:51:17.896 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:17.906 SERIAL « 0x01 (1 bytes) 18:51:17.907 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:17.908 SERIAL « 0x0a00040006044608007fc2 (11 bytes) 18:51:17.910 CNTRLR [Node 006] [~] [Climate Control Schedule] overrideType: 0 => 0 [Endpoint 0] 18:51:17.917 DRIVER handling application command request Climate Control Schedule (0x46) for node 6 18:51:17.909 SERIAL Buffer := 0x010a00040006044608007fc2 (12 bytes) 18:51:17.916 SERIAL » [ACK] (0x06) 18:51:17.918 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:17.912 CNTRLR [Node 006] [~] [Climate Control Schedule] overrideState: "Unused" [Endpoint 0] => "Unused" 18:51:17.934 SERIAL « 0x01 (1 bytes) 18:51:17.935 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:17.940 DRIVER handling application command request Wake Up (0x84) for node 6 18:51:17.941 CNTRLR « [Node 006] received wakeup notification 18:51:17.938 SERIAL « 0x080004000602840774 (9 bytes) 18:51:17.938 SERIAL Buffer := 0x01080004000602840774 (10 bytes) 18:51:17.939 SERIAL » [ACK] (0x06) 18:51:17.943 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:17.946 SERIAL » 0x010a00130603430200250384 (12 bytes) 18:51:17.945 DRIVER workOffSendQueue > sending next message (SendData) to node 6... 18:51:17.942 CNTRLR [Node 006] The node is now awake. 18:51:17.952 SERIAL « [ACK] (0x06) 18:51:17.953 DRIVER Unexpected ACK received 18:51:17.953 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:17.957 SERIAL « 0x01 (1 bytes) 18:51:17.958 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:17.960 DRIVER « [RES] [SendData] [confirmation] 18:51:17.959 SERIAL « 0x04011301e8 (5 bytes) 18:51:17.959 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:51:17.960 SERIAL » [ACK] (0x06) 18:51:17.961 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:17.974 SERIAL « 0x01 (1 bytes) 18:51:17.975 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:17.977 DRIVER « [REQ] [SendData] [confirmation] 18:51:17.976 SERIAL « 0x07001303000002ea (8 bytes) 18:51:17.976 SERIAL Buffer := 0x0107001303000002ea (9 bytes) 18:51:17.977 SERIAL » [ACK] (0x06) 18:51:17.978 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:17.978 DRIVER ACK received from node for current transaction. RTT = 32.35 ms 18:51:19.011 DRIVER The transaction timed out 18:51:19.013 CNTRLR Thermostat Setpoint: Interview failed: The transaction timed out 18:51:19.012 DRIVER resuming send queue 18:51:19.016 DRIVER The remaining 4 messages are for sleeping nodes, not sending anything! 18:51:19.045 CNTRLR [Node 006] BatteryCC: doing a partial interview... 18:51:19.047 DRIVER workOffSendQueue > sending next message (SendData) to node 6... 18:51:19.047 SERIAL » 0x0109001306028002252a6c (11 bytes) 18:51:19.045 CNTRLR » [Node 006] querying battery status... 18:51:19.051 SERIAL « [ACK] (0x06) 18:51:19.052 DRIVER ACK received from controller for current transaction 18:51:19.052 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:19.058 SERIAL « 0x01 (1 bytes) 18:51:19.059 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:19.060 SERIAL « 0x04011301e8 (5 bytes) 18:51:19.062 DRIVER « [RES] [SendData] [confirmation] 18:51:19.061 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:51:19.061 SERIAL » [ACK] (0x06) 18:51:19.063 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:19.075 SERIAL « 0x01 (1 bytes) 18:51:19.076 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:19.078 DRIVER « [REQ] [SendData] [confirmation] 18:51:19.077 SERIAL « 0x0700132a000002c3 (8 bytes) 18:51:19.077 SERIAL Buffer := 0x010700132a000002c3 (9 bytes) 18:51:19.077 SERIAL » [ACK] (0x06) 18:51:19.079 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:19.079 DRIVER ACK received from node for current transaction. RTT = 31.55 ms 18:51:19.086 SERIAL « 0x01 (1 bytes) 18:51:19.087 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:19.091 CNTRLR [Node 006] [~] [Battery] level: 40 => 40 [Endpoint 0] 18:51:19.095 DRIVER « [REQ] [ApplicationCommand] [final] 18:51:19.089 SERIAL « 0x0900040006038003285c (10 bytes) 18:51:19.089 SERIAL Buffer := 0x010900040006038003285c (11 bytes) 18:51:19.094 SERIAL » [ACK] (0x06) 18:51:19.097 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:19.093 CNTRLR [Node 006] [~] [Battery] isLow: false => false [Endpoint 0] 18:51:19.097 CNTRLR « [Node 006] received response for battery information: level: 40 18:51:19.098 CNTRLR [Node 006] [~] [Battery] interviewComplete: true => tr [Endpoint 0] [internal] ue 18:51:19.095 DRIVER ACK already received, resolving transaction 18:51:19.096 DRIVER resuming send queue 18:51:19.101 DRIVER The remaining 4 messages are for sleeping nodes, not sending anything! 18:51:19.146 CNTRLR [Node 006] WakeUpCC: doing a partial interview... 18:51:19.148 DRIVER workOffSendQueue > sending next message (SendData) to node 6... 18:51:19.149 SERIAL » 0x0109001306028405252b6e (11 bytes) 18:51:19.147 CNTRLR » [Node 006] retrieving wakeup interval from the device... 18:51:19.154 SERIAL « [ACK] (0x06) 18:51:19.157 DRIVER ACK received from controller for current transaction 18:51:19.158 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:19.161 SERIAL « 0x01 (1 bytes) 18:51:19.162 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:19.166 SERIAL « 0x04011301e8 (5 bytes) 18:51:19.168 DRIVER « [RES] [SendData] [confirmation] 18:51:19.167 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:51:19.168 SERIAL » [ACK] (0x06) 18:51:19.169 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:19.177 SERIAL « 0x01 (1 bytes) 18:51:19.178 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:19.181 DRIVER « [REQ] [SendData] [confirmation] 18:51:19.179 SERIAL « 0x0700132b000002c2 (8 bytes) 18:51:19.180 SERIAL Buffer := 0x010700132b000002c2 (9 bytes) 18:51:19.180 SERIAL » [ACK] (0x06) 18:51:19.183 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:19.181 DRIVER ACK received from node for current transaction. RTT = 32.40 ms 18:51:19.188 SERIAL « 0x01 (1 bytes) 18:51:19.188 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:19.190 SERIAL « 0x0c0004000606840600012c0159 (13 bytes) 18:51:19.191 CNTRLR [Node 006] [~] [Wake Up] wakeUpInterval: 300 => 300 [Endpoint 0] 18:51:19.196 DRIVER « [REQ] [ApplicationCommand] [final] 18:51:19.190 SERIAL Buffer := 0x010c0004000606840600012c0159 (14 bytes) 18:51:19.195 SERIAL » [ACK] (0x06) 18:51:19.198 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:19.194 CNTRLR [Node 006] [~] [Wake Up] controllerNodeId: 1 => 1 [Endpoint 0] 18:51:19.199 CNTRLR « [Node 006] received wakeup configuration: wakeup interval: 300 seconds controller node: 1 18:51:19.199 CNTRLR [Node 006] [~] [Wake Up] interviewComplete: true => tr [Endpoint 0] [internal] ue 18:51:19.197 DRIVER ACK already received, resolving transaction 18:51:19.197 DRIVER resuming send queue 18:51:19.200 DRIVER The remaining 4 messages are for sleeping nodes, not sending anything! 18:51:19.233 CNTRLR [Node 006] Interview stage completed: CommandClasses 18:51:19.235 DRIVER workOffSendQueue > sending next message (GetRoutingInfo) to node 6... 18:51:19.236 SERIAL » 0x01070080060000007e (9 bytes) 18:51:19.234 CNTRLR [Node 006] Interview stage completed: OverwriteConfig 18:51:19.234 CNTRLR » [Node 006] requesting node neighbors... 18:51:19.241 SERIAL « [ACK] (0x06) 18:51:19.242 DRIVER ACK received from controller for current transaction 18:51:19.242 SERIAL « 0x0601200180 (5 bytes) 18:51:19.243 SERIAL [incomplete] Buffer := 0x01200180 (4 bytes) 18:51:19.246 DRIVER « [RES] [GetRoutingInfo] [final] 18:51:19.247 CNTRLR « [Node 006] node neighbors received: 1, 7 18:51:19.244 SERIAL « 0x41000000000000000000000000000000000000000000000000000000001f (30 bytes) 18:51:19.245 SERIAL Buffer := 0x0120018041000000000000000000000000000000000000000000000 (34 bytes) 000000000001f 18:51:19.245 SERIAL » [ACK] (0x06) 18:51:19.247 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:19.246 DRIVER ACK already received, resolving transaction 18:51:19.247 DRIVER resuming send queue 18:51:19.250 DRIVER The remaining 4 messages are for sleeping nodes, not sending anything! 18:51:19.248 CNTRLR [Node 006] Interview stage completed: Neighbors 18:51:19.248 CNTRLR [Node 006] Interview completed 18:51:20.249 CNTRLR » [Node 006] Sending node back to sleep... 18:51:20.252 DRIVER workOffSendQueue > sending next message (SendData) to node 6... 18:51:20.253 SERIAL » 0x0109001306028408252c64 (11 bytes) 18:51:20.257 SERIAL « [ACK] (0x06) 18:51:20.258 DRIVER ACK received from controller for current transaction 18:51:20.259 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:20.264 SERIAL « 0x01 (1 bytes) 18:51:20.265 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:20.267 DRIVER « [RES] [SendData] [confirmation] 18:51:20.266 SERIAL « 0x04011301e8 (5 bytes) 18:51:20.266 SERIAL Buffer := 0x0104011301e8 (6 bytes) 18:51:20.267 SERIAL » [ACK] (0x06) 18:51:20.268 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:20.281 SERIAL « 0x01 (1 bytes) 18:51:20.282 SERIAL [incomplete] Buffer := 0x01 (1 bytes) 18:51:20.284 DRIVER « [REQ] [SendData] [final] 18:51:20.286 CNTRLR [Node 006] The node is now asleep. 18:51:20.284 DRIVER ACK already received, resolving transaction 18:51:20.285 DRIVER resuming send queue 18:51:20.290 DRIVER The remaining 4 messages are for sleeping nodes, not sending anything! 18:51:20.290 DRIVER The remaining 4 messages are for sleeping nodes, not sending anything! 18:51:20.283 SERIAL « 0x0700132c000002c5 (8 bytes) 18:51:20.283 SERIAL Buffer := 0x010700132c000002c5 (9 bytes) 18:51:20.284 SERIAL » [ACK] (0x06) 18:51:20.286 SERIAL The receive buffer is empty, waiting for the next chunk... 18:51:20.289 CNTRLR [Node 006] Node asleep 18:51:29.913 DRIVER The remaining 5 messages are for sleeping nodes, not sending anything! 18:51:50.548 DRIVER destroying driver instance...