Logging started 2011-09-12 12:45:17 2011-09-12 12:45:17:466 Added driver for controller /dev/ttyUSB0 2011-09-12 12:45:17:466 Opening controller /dev/ttyUSB0 2011-09-12 12:45:17:466 Open serial port /dev/ttyUSB0 2011-09-12 12:45:17:476 Queuing command: FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9 2011-09-12 12:45:17:476 Queuing command: FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc 2011-09-12 12:45:17:476 Queuing command: FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9 2011-09-12 12:45:17:476 Queuing command: FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb 2011-09-12 12:45:17:476 Queuing command: FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe 2011-09-12 12:45:17:572 Sending command (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9 2011-09-12 12:45:17:580 ACK received CallbackId 0x00 Reply 0x15 2011-09-12 12:45:17:580 Received: 0x01, 0x10, 0x01, 0x15, 0x5a, 0x2d, 0x57, 0x61, 0x76, 0x65, 0x20, 0x32, 0x2e, 0x37, 0x38, 0x00, 0x01, 0x9b 2011-09-12 12:45:17:580 Received reply to FUNC_ID_ZW_GET_VERSION: 2011-09-12 12:45:17:580 Static Controller library, version Z-Wave 2.78 2011-09-12 12:45:17:580 Expected reply was received 2011-09-12 12:45:17:580 Message transaction complete 2011-09-12 12:45:17:581 RemoveMsg 5 2011-09-12 12:45:17:581 Sending command (Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc 2011-09-12 12:45:17:585 ACK received CallbackId 0x00 Reply 0x20 2011-09-12 12:45:17:585 Received: 0x01, 0x08, 0x01, 0x20, 0x01, 0x4c, 0xdf, 0xbf, 0x01, 0xfa 2011-09-12 12:45:17:585 Received reply to FUNC_ID_ZW_MEMORY_GET_ID. Home ID = 0x014cdfbf. Our node ID = 1 2011-09-12 12:45:17:585 Expected reply was received 2011-09-12 12:45:17:585 Message transaction complete 2011-09-12 12:45:17:586 RemoveMsg 4 2011-09-12 12:45:17:586 Sending command (Callback ID=0x00, Expected Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9 2011-09-12 12:45:17:593 ACK received CallbackId 0x00 Reply 0x05 2011-09-12 12:45:17:593 Received: 0x01, 0x04, 0x01, 0x05, 0x1c, 0xe3 2011-09-12 12:45:17:593 Received reply to FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 2011-09-12 12:45:17:593 There is a SUC ID Server (SIS) in this network. 2011-09-12 12:45:17:593 The PC controller is an inclusion static update controller (SUC) and was the original primary before the SIS was added. 2011-09-12 12:45:17:593 Expected reply was received 2011-09-12 12:45:17:594 Message transaction complete 2011-09-12 12:45:17:594 RemoveMsg 3 2011-09-12 12:45:17:594 Sending command (Callback ID=0x00, Expected Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb 2011-09-12 12:45:17:601 ACK received CallbackId 0x00 Reply 0x07 2011-09-12 12:45:17:601 Received: 0x01, 0x2b, 0x01, 0x07, 0x03, 0x07, 0x00, 0x86, 0x00, 0x02, 0x00, 0x01, 0xfe, 0x80, 0xfe, 0x88, 0x0f, 0x00, 0x00, 0x00, 0xfb, 0x97, 0x7f, 0x82, 0x07, 0x00, 0x00, 0x80, 0x00, 0x80, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0xc2 2011-09-12 12:45:17:601 Received reply to FUNC_ID_SERIAL_API_GET_CAPABILITIES 2011-09-12 12:45:17:601 Application Version: 3 2011-09-12 12:45:17:601 Application Revision: 7 2011-09-12 12:45:17:601 Manufacturer ID: 0x0086 2011-09-12 12:45:17:602 Product Type: 0x0002 2011-09-12 12:45:17:602 Product ID: 0x0001 2011-09-12 12:45:17:602 Expected reply was received 2011-09-12 12:45:17:602 Message transaction complete 2011-09-12 12:45:17:602 RemoveMsg 2 2011-09-12 12:45:17:602 Sending command (Callback ID=0x00, Expected Reply=0x02) - FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe 2011-09-12 12:45:17:605 ACK received CallbackId 0x00 Reply 0x02 2011-09-12 12:45:17:652 Received: 0x01, 0x25, 0x01, 0x02, 0x05, 0x08, 0x1d, 0xed, 0x07, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x21 2011-09-12 12:45:17:652 Driver with Home ID of 0x014cdfbf is now ready. 2011-09-12 12:45:17:658 Missing default byte value from xml configuration: node 1, class 0x20, instance 1, index 0 2011-09-12 12:45:17:658 Missing default byte value from xml configuration: node 4, class 0x20, instance 1, index 0 2011-09-12 12:45:17:658 Missing default byte value from xml configuration: node 6, class 0x20, instance 1, index 0 2011-09-12 12:45:17:658 Missing default integer value from xml configuration: node 6, class 0x84, instance 1, index 0 2011-09-12 12:45:17:659 Missing default byte value from xml configuration: node 7, class 0x20, instance 1, index 0 2011-09-12 12:45:17:659 Missing default byte value from xml configuration: node 10, class 0x20, instance 1, index 0 2011-09-12 12:45:17:659 Missing default byte value from xml configuration: node 11, class 0x26, instance 1, index 4 2011-09-12 12:45:17:659 Missing default byte value from xml configuration: node 11, class 0x26, instance 1, index 5 2011-09-12 12:45:17:660 Received reply to FUNC_ID_SERIAL_API_GET_INIT_DATA: 2011-09-12 12:45:17:660 Node 1 - Known 2011-09-12 12:45:17:660 Node 3 - Known 2011-09-12 12:45:17:660 Node 4 - Known 2011-09-12 12:45:17:660 Node 6 - Known 2011-09-12 12:45:17:660 Node 7 - Known 2011-09-12 12:45:17:660 Node 8 - Known 2011-09-12 12:45:17:660 Node 9 - Known 2011-09-12 12:45:17:660 Node 10 - Known 2011-09-12 12:45:17:660 Node 11 - Known 2011-09-12 12:45:17:660 Expected reply was received 2011-09-12 12:45:17:660 Message transaction complete 2011-09-12 12:45:17:660 RemoveMsg 1 2011-09-12 12:45:19:677 AdvanceQueries node 6 WakeUp m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:19:677 Node 6: QueryStage_WakeUp 2011-09-12 12:45:19:677 Queuing command: WakeUpCmd_IntervalGet (Node=6): 0x01, 0x09, 0x00, 0x13, 0x06, 0x02, 0x84, 0x05, 0x05, 0x01, 0x64 2011-09-12 12:45:19:677 AdvanceQueries exit node 6 WakeUp m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 1 2011-09-12 12:45:19:776 Sending command (Callback ID=0x01, Expected Reply=0x04) - WakeUpCmd_IntervalGet (Node=6): 0x01, 0x09, 0x00, 0x13, 0x06, 0x02, 0x84, 0x05, 0x05, 0x01, 0x64 2011-09-12 12:45:19:780 ACK received CallbackId 0x01 Reply 0x04 2011-09-12 12:45:19:785 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:19:785 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:19:881 Received: 0x01, 0x05, 0x00, 0x13, 0x01, 0x01, 0xe9 2011-09-12 12:45:19:882 ZW_SEND_DATA Request with callback ID 0x01 received (expected 0x01) 2011-09-12 12:45:19:882 Error: ZW_SEND_DATA failed. 2011-09-12 12:45:19:882 Node 6 has been marked as asleep 2011-09-12 12:45:19:882 Node not responding - moving message to Wake-Up queue: WakeUpCmd_IntervalGet (Node=6): 0x01, 0x09, 0x00, 0x13, 0x06, 0x02, 0x84, 0x05, 0x05, 0x01, 0x64 2011-09-12 12:45:19:882 AdvanceQueries node 1 Associations m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:19:882 Node 1: QueryStage_Associations 2011-09-12 12:45:19:882 Node 1: QueryStage_Neighbors 2011-09-12 12:45:19:882 Requesting routing info (neighbor list) for Node 1 2011-09-12 12:45:19:882 Queuing command: Get Routing Info (Node=1): 0x01, 0x06, 0x00, 0x80, 0x01, 0x01, 0x01, 0x78 2011-09-12 12:45:19:882 AdvanceQueries exit node 1 Neighbors m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 1 2011-09-12 12:45:19:980 Sending command (Callback ID=0x00, Expected Reply=0x80) - Get Routing Info (Node=1): 0x01, 0x06, 0x00, 0x80, 0x01, 0x01, 0x01, 0x78 2011-09-12 12:45:19:984 ACK received CallbackId 0x00 Reply 0x80 2011-09-12 12:45:19:989 Received: 0x01, 0x20, 0x01, 0x80, 0xe8, 0x07, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0xb1 2011-09-12 12:45:19:989 Received reply to FUNC_ID_ZW_GET_ROUTING_INFO 2011-09-12 12:45:19:989 Neighbors of this node are: 2011-09-12 12:45:19:989 Node 4 2011-09-12 12:45:19:989 Node 6 2011-09-12 12:45:19:990 Node 7 2011-09-12 12:45:19:990 Node 8 2011-09-12 12:45:19:990 Node 9 2011-09-12 12:45:19:990 Node 10 2011-09-12 12:45:19:990 Node 11 2011-09-12 12:45:19:990 Expected reply was received 2011-09-12 12:45:19:990 Message transaction complete 2011-09-12 12:45:19:990 RemoveMsg 1 2011-09-12 12:45:19:990 bMoreForThisNode = 0 2011-09-12 12:45:19:990 QueryStageComplete node 1 Neighbors Neighbors completed 1 2011-09-12 12:45:19:990 QueryStageComplete Session m_queryPending 0 m_queryRetries 0 2011-09-12 12:45:19:990 AdvanceQueries node 1 Session m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:19:990 Node 1: QueryStage_Session 2011-09-12 12:45:19:990 Node 1: QueryStage_Dynamic 2011-09-12 12:45:19:990 Node 1: QueryStage_Configuration 2011-09-12 12:45:19:990 Node 1: QueryStage_Complete 2011-09-12 12:45:20:088 AdvanceQueries node 3 Associations m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:20:088 Node 3: QueryStage_Associations 2011-09-12 12:45:20:088 Node 3: QueryStage_Neighbors 2011-09-12 12:45:20:089 Requesting routing info (neighbor list) for Node 3 2011-09-12 12:45:20:089 Queuing command: Get Routing Info (Node=3): 0x01, 0x06, 0x00, 0x80, 0x03, 0x01, 0x01, 0x7a 2011-09-12 12:45:20:089 AdvanceQueries exit node 3 Neighbors m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 1 2011-09-12 12:45:20:188 Sending command (Callback ID=0x00, Expected Reply=0x80) - Get Routing Info (Node=3): 0x01, 0x06, 0x00, 0x80, 0x03, 0x01, 0x01, 0x7a 2011-09-12 12:45:20:193 ACK received CallbackId 0x00 Reply 0x80 2011-09-12 12:45:20:194 Received: 0x01, 0x20, 0x01, 0x80, 0x88, 0x05, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0xd3 2011-09-12 12:45:20:194 Received reply to FUNC_ID_ZW_GET_ROUTING_INFO 2011-09-12 12:45:20:194 Neighbors of this node are: 2011-09-12 12:45:20:194 Node 4 2011-09-12 12:45:20:194 Node 8 2011-09-12 12:45:20:194 Node 9 2011-09-12 12:45:20:194 Node 11 2011-09-12 12:45:20:194 Expected reply was received 2011-09-12 12:45:20:194 Message transaction complete 2011-09-12 12:45:20:194 RemoveMsg 1 2011-09-12 12:45:20:194 bMoreForThisNode = 0 2011-09-12 12:45:20:194 QueryStageComplete node 3 Neighbors Neighbors completed 1 2011-09-12 12:45:20:194 QueryStageComplete Session m_queryPending 0 m_queryRetries 0 2011-09-12 12:45:20:194 AdvanceQueries node 3 Session m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:20:194 Node 3: QueryStage_Session 2011-09-12 12:45:20:194 Queuing command: SwitchAllCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x27, 0x02, 0x05, 0x02, 0xc6 2011-09-12 12:45:20:194 AdvanceQueries exit node 3 Session m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 1 2011-09-12 12:45:20:292 Sending command (Callback ID=0x02, Expected Reply=0x04) - SwitchAllCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x27, 0x02, 0x05, 0x02, 0xc6 2011-09-12 12:45:20:297 ACK received CallbackId 0x02 Reply 0x04 2011-09-12 12:45:20:302 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:20:302 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:20:372 Received: 0x01, 0x05, 0x00, 0x13, 0x02, 0x00, 0xeb 2011-09-12 12:45:20:372 ZW_SEND_DATA Request with callback ID 0x02 received (expected 0x02) 2011-09-12 12:45:20:440 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x03, 0x03, 0x27, 0x03, 0x00, 0xd6 2011-09-12 12:45:20:440 Received SwitchAll report from node 3: Disabled 2011-09-12 12:45:20:440 Expected reply and command class was received 2011-09-12 12:45:20:441 Message transaction complete 2011-09-12 12:45:20:441 RemoveMsg 1 2011-09-12 12:45:20:441 bMoreForThisNode = 0 2011-09-12 12:45:20:441 QueryStageComplete node 3 Session Session completed 1 2011-09-12 12:45:20:441 QueryStageComplete Dynamic m_queryPending 0 m_queryRetries 0 2011-09-12 12:45:20:441 AdvanceQueries node 3 Dynamic m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:20:441 Node 3: QueryStage_Dynamic 2011-09-12 12:45:20:441 Queuing command: BasicCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x20, 0x02, 0x05, 0x03, 0xc0 2011-09-12 12:45:20:441 Queuing command: SwitchBinaryCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x25, 0x02, 0x05, 0x04, 0xc2 2011-09-12 12:45:20:441 AdvanceQueries exit node 3 Dynamic m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 1 2011-09-12 12:45:20:540 Sending command (Callback ID=0x03, Expected Reply=0x04) - BasicCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x20, 0x02, 0x05, 0x03, 0xc0 2011-09-12 12:45:20:544 ACK received CallbackId 0x03 Reply 0x04 2011-09-12 12:45:20:549 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:20:549 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:21:448 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x03, 0x03, 0x20, 0x03, 0x00, 0xd1 2011-09-12 12:45:21:448 Received Basic report from node 3: level=0 2011-09-12 12:45:21:449 Expected reply and command class was received 2011-09-12 12:45:21:848 Received: 0x01, 0x05, 0x00, 0x13, 0x03, 0x01, 0xeb 2011-09-12 12:45:21:848 ZW_SEND_DATA Request with callback ID 0x03 received (expected 0x03) 2011-09-12 12:45:21:848 Error: ZW_SEND_DATA failed. 2011-09-12 12:45:21:849 Sending command (Callback ID=0x03, Expected Reply=0x04) - BasicCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x20, 0x02, 0x05, 0x03, 0xc0 2011-09-12 12:45:21:853 ACK received CallbackId 0x03 Reply 0x04 2011-09-12 12:45:21:876 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:21:876 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:22:656 Received: 0x01, 0x05, 0x00, 0x13, 0x03, 0x00, 0xea 2011-09-12 12:45:22:656 ZW_SEND_DATA Request with callback ID 0x03 received (expected 0x03) 2011-09-12 12:45:22:692 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x03, 0x03, 0x20, 0x03, 0x00, 0xd1 2011-09-12 12:45:22:692 Received Basic report from node 3: level=0 2011-09-12 12:45:22:692 Expected reply and command class was received 2011-09-12 12:45:22:692 Message transaction complete 2011-09-12 12:45:22:693 RemoveMsg 2 2011-09-12 12:45:22:693 bMoreForThisNode = 1 2011-09-12 12:45:22:695 Sending command (Callback ID=0x04, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x25, 0x02, 0x05, 0x04, 0xc2 2011-09-12 12:45:22:701 ACK received CallbackId 0x04 Reply 0x04 2011-09-12 12:45:22:701 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:22:701 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:22:868 Received: 0x01, 0x05, 0x00, 0x13, 0x04, 0x00, 0xed 2011-09-12 12:45:22:868 ZW_SEND_DATA Request with callback ID 0x04 received (expected 0x04) 2011-09-12 12:45:22:908 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x03, 0x03, 0x25, 0x03, 0x00, 0xd4 2011-09-12 12:45:22:908 Received SwitchBinary report from node 3: level=Off 2011-09-12 12:45:22:908 Expected reply and command class was received 2011-09-12 12:45:22:909 Message transaction complete 2011-09-12 12:45:22:909 RemoveMsg 1 2011-09-12 12:45:22:909 bMoreForThisNode = 0 2011-09-12 12:45:22:909 QueryStageComplete node 3 Dynamic Dynamic completed 1 2011-09-12 12:45:22:909 QueryStageComplete Configuration m_queryPending 0 m_queryRetries 0 2011-09-12 12:45:22:909 AdvanceQueries node 3 Configuration m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:22:909 Node 3: QueryStage_Configuration 2011-09-12 12:45:22:909 Node 3: QueryStage_Complete 2011-09-12 12:45:23:008 AdvanceQueries node 4 Associations m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:23:008 Node 4: QueryStage_Associations 2011-09-12 12:45:23:008 Number of association groups reported for node 4 is 1. 2011-09-12 12:45:23:008 Get Associations for group 1 of node 4 2011-09-12 12:45:23:008 Queuing command: Get Associations (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x85, 0x02, 0x01, 0x05, 0x05, 0x67 2011-09-12 12:45:23:008 AdvanceQueries exit node 4 Associations m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 1 2011-09-12 12:45:23:108 Sending command (Callback ID=0x05, Expected Reply=0x04) - Get Associations (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x85, 0x02, 0x01, 0x05, 0x05, 0x67 2011-09-12 12:45:23:112 ACK received CallbackId 0x05 Reply 0x04 2011-09-12 12:45:23:117 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:23:117 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:24:516 Received: 0x01, 0x05, 0x00, 0x13, 0x05, 0x01, 0xed 2011-09-12 12:45:24:516 ZW_SEND_DATA Request with callback ID 0x05 received (expected 0x05) 2011-09-12 12:45:24:516 Error: ZW_SEND_DATA failed. 2011-09-12 12:45:24:516 Node 4 has been marked as asleep 2011-09-12 12:45:24:517 Node not responding - moving message to Wake-Up queue: Get Associations (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x85, 0x02, 0x01, 0x05, 0x05, 0x67 2011-09-12 12:45:24:517 AdvanceQueries node 7 Associations m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:24:517 Node 7: QueryStage_Associations 2011-09-12 12:45:24:517 Number of association groups reported for node 7 is 2. 2011-09-12 12:45:24:517 Get Associations for group 1 of node 7 2011-09-12 12:45:24:517 Queuing command: Get Associations (Node=7): 0x01, 0x0a, 0x00, 0x13, 0x07, 0x03, 0x85, 0x02, 0x01, 0x05, 0x06, 0x67 2011-09-12 12:45:24:517 AdvanceQueries exit node 7 Associations m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 1 2011-09-12 12:45:24:616 Sending command (Callback ID=0x06, Expected Reply=0x04) - Get Associations (Node=7): 0x01, 0x0a, 0x00, 0x13, 0x07, 0x03, 0x85, 0x02, 0x01, 0x05, 0x06, 0x67 2011-09-12 12:45:24:620 ACK received CallbackId 0x06 Reply 0x04 2011-09-12 12:45:24:625 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:24:625 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:24:780 Received: 0x01, 0x05, 0x00, 0x13, 0x06, 0x01, 0xee 2011-09-12 12:45:24:780 ZW_SEND_DATA Request with callback ID 0x06 received (expected 0x06) 2011-09-12 12:45:24:780 Error: ZW_SEND_DATA failed. 2011-09-12 12:45:24:780 Node 7 has been marked as asleep 2011-09-12 12:45:24:780 Node not responding - moving message to Wake-Up queue: Get Associations (Node=7): 0x01, 0x0a, 0x00, 0x13, 0x07, 0x03, 0x85, 0x02, 0x01, 0x05, 0x06, 0x67 2011-09-12 12:45:24:781 AdvanceQueries node 8 Associations m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:24:781 Node 8: QueryStage_Associations 2011-09-12 12:45:24:781 Node 8: QueryStage_Neighbors 2011-09-12 12:45:24:781 Requesting routing info (neighbor list) for Node 8 2011-09-12 12:45:24:781 Queuing command: Get Routing Info (Node=8): 0x01, 0x06, 0x00, 0x80, 0x08, 0x01, 0x01, 0x71 2011-09-12 12:45:24:781 AdvanceQueries exit node 8 Neighbors m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 1 2011-09-12 12:45:24:880 Sending command (Callback ID=0x00, Expected Reply=0x80) - Get Routing Info (Node=8): 0x01, 0x06, 0x00, 0x80, 0x08, 0x01, 0x01, 0x71 2011-09-12 12:45:24:884 ACK received CallbackId 0x00 Reply 0x80 2011-09-12 12:45:24:889 Received: 0x01, 0x20, 0x01, 0x80, 0x05, 0x02, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x59 2011-09-12 12:45:24:889 Received reply to FUNC_ID_ZW_GET_ROUTING_INFO 2011-09-12 12:45:24:889 Neighbors of this node are: 2011-09-12 12:45:24:889 Node 1 2011-09-12 12:45:24:889 Node 3 2011-09-12 12:45:24:889 Node 10 2011-09-12 12:45:24:890 Expected reply was received 2011-09-12 12:45:24:890 Message transaction complete 2011-09-12 12:45:24:890 RemoveMsg 1 2011-09-12 12:45:24:890 bMoreForThisNode = 0 2011-09-12 12:45:24:890 QueryStageComplete node 8 Neighbors Neighbors completed 1 2011-09-12 12:45:24:890 QueryStageComplete Session m_queryPending 0 m_queryRetries 0 2011-09-12 12:45:24:890 AdvanceQueries node 8 Session m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:24:890 Node 8: QueryStage_Session 2011-09-12 12:45:24:890 Queuing command: SwitchAllCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x27, 0x02, 0x05, 0x07, 0xc8 2011-09-12 12:45:24:890 AdvanceQueries exit node 8 Session m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 1 2011-09-12 12:45:24:988 Sending command (Callback ID=0x07, Expected Reply=0x04) - SwitchAllCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x27, 0x02, 0x05, 0x07, 0xc8 2011-09-12 12:45:24:992 ACK received CallbackId 0x07 Reply 0x04 2011-09-12 12:45:24:997 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:24:997 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:25:428 Received: 0x01, 0x05, 0x00, 0x13, 0x07, 0x00, 0xee 2011-09-12 12:45:25:428 ZW_SEND_DATA Request with callback ID 0x07 received (expected 0x07) 2011-09-12 12:45:25:476 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x08, 0x03, 0x27, 0x03, 0xff, 0x22 2011-09-12 12:45:25:476 Received SwitchAll report from node 8: On and Off Enabled 2011-09-12 12:45:25:476 Expected reply and command class was received 2011-09-12 12:45:25:476 Message transaction complete 2011-09-12 12:45:25:477 RemoveMsg 1 2011-09-12 12:45:25:477 bMoreForThisNode = 0 2011-09-12 12:45:25:477 QueryStageComplete node 8 Session Session completed 1 2011-09-12 12:45:25:477 QueryStageComplete Dynamic m_queryPending 0 m_queryRetries 0 2011-09-12 12:45:25:477 AdvanceQueries node 8 Dynamic m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:25:477 Node 8: QueryStage_Dynamic 2011-09-12 12:45:25:477 Queuing command: BasicCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x20, 0x02, 0x05, 0x08, 0xc0 2011-09-12 12:45:25:477 Queuing command: SwitchBinaryCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x25, 0x02, 0x05, 0x09, 0xc4 2011-09-12 12:45:25:477 AdvanceQueries exit node 8 Dynamic m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 1 2011-09-12 12:45:25:576 Sending command (Callback ID=0x08, Expected Reply=0x04) - BasicCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x20, 0x02, 0x05, 0x08, 0xc0 2011-09-12 12:45:25:581 ACK received CallbackId 0x08 Reply 0x04 2011-09-12 12:45:25:581 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:25:581 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:25:756 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x08, 0x03, 0x20, 0x03, 0x00, 0xda 2011-09-12 12:45:25:756 Received Basic report from node 8: level=0 2011-09-12 12:45:25:756 Expected reply and command class was received 2011-09-12 12:45:26:760 Received: 0x01, 0x05, 0x00, 0x13, 0x08, 0x00, 0xe1 2011-09-12 12:45:26:760 ZW_SEND_DATA Request with callback ID 0x08 received (expected 0x08) 2011-09-12 12:45:26:760 ZW_SEND_DATA was successful, removing command 2011-09-12 12:45:26:760 RemoveMsg 2 2011-09-12 12:45:26:761 bMoreForThisNode = 1 2011-09-12 12:45:26:761 Sending command (Callback ID=0x09, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x25, 0x02, 0x05, 0x09, 0xc4 2011-09-12 12:45:26:765 ACK received CallbackId 0x09 Reply 0x04 2011-09-12 12:45:26:765 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:26:765 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:27:256 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x08, 0x03, 0x25, 0x03, 0x00, 0xdf 2011-09-12 12:45:27:256 Received SwitchBinary report from node 8: level=Off 2011-09-12 12:45:27:256 Expected reply and command class was received 2011-09-12 12:45:27:580 Received: 0x01, 0x05, 0x00, 0x13, 0x09, 0x00, 0xe0 2011-09-12 12:45:27:580 ZW_SEND_DATA Request with callback ID 0x09 received (expected 0x09) 2011-09-12 12:45:27:581 ZW_SEND_DATA was successful, removing command 2011-09-12 12:45:27:581 RemoveMsg 1 2011-09-12 12:45:27:581 bMoreForThisNode = 0 2011-09-12 12:45:27:581 QueryStageComplete node 8 Dynamic Dynamic completed 1 2011-09-12 12:45:27:581 QueryStageComplete Configuration m_queryPending 0 m_queryRetries 0 2011-09-12 12:45:27:581 AdvanceQueries node 8 Configuration m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:27:581 Node 8: QueryStage_Configuration 2011-09-12 12:45:27:581 Node 8: QueryStage_Complete 2011-09-12 12:45:27:680 AdvanceQueries node 9 Associations m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:27:680 Node 9: QueryStage_Associations 2011-09-12 12:45:27:680 Node 9: QueryStage_Neighbors 2011-09-12 12:45:27:680 Requesting routing info (neighbor list) for Node 9 2011-09-12 12:45:27:680 Queuing command: Get Routing Info (Node=9): 0x01, 0x06, 0x00, 0x80, 0x09, 0x01, 0x01, 0x70 2011-09-12 12:45:27:681 AdvanceQueries exit node 9 Neighbors m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 1 2011-09-12 12:45:27:780 Sending command (Callback ID=0x00, Expected Reply=0x80) - Get Routing Info (Node=9): 0x01, 0x06, 0x00, 0x80, 0x09, 0x01, 0x01, 0x70 2011-09-12 12:45:27:784 ACK received CallbackId 0x00 Reply 0x80 2011-09-12 12:45:27:789 Received: 0x01, 0x20, 0x01, 0x80, 0x05, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x5b 2011-09-12 12:45:27:789 Received reply to FUNC_ID_ZW_GET_ROUTING_INFO 2011-09-12 12:45:27:789 Neighbors of this node are: 2011-09-12 12:45:27:789 Node 1 2011-09-12 12:45:27:789 Node 3 2011-09-12 12:45:27:789 Expected reply was received 2011-09-12 12:45:27:790 Message transaction complete 2011-09-12 12:45:27:790 RemoveMsg 1 2011-09-12 12:45:27:790 bMoreForThisNode = 0 2011-09-12 12:45:27:790 QueryStageComplete node 9 Neighbors Neighbors completed 1 2011-09-12 12:45:27:790 QueryStageComplete Session m_queryPending 0 m_queryRetries 0 2011-09-12 12:45:27:790 AdvanceQueries node 9 Session m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:27:790 Node 9: QueryStage_Session 2011-09-12 12:45:27:790 Queuing command: SwitchAllCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x27, 0x02, 0x05, 0x0a, 0xc4 2011-09-12 12:45:27:790 AdvanceQueries exit node 9 Session m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 1 2011-09-12 12:45:27:888 Sending command (Callback ID=0x0a, Expected Reply=0x04) - SwitchAllCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x27, 0x02, 0x05, 0x0a, 0xc4 2011-09-12 12:45:27:892 ACK received CallbackId 0x0a Reply 0x04 2011-09-12 12:45:27:897 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:27:897 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:28:268 Received: 0x01, 0x05, 0x00, 0x13, 0x0a, 0x00, 0xe3 2011-09-12 12:45:28:268 ZW_SEND_DATA Request with callback ID 0x0a received (expected 0x0a) 2011-09-12 12:45:33:468 Timeout 2011-09-12 12:45:33:468 Resending message (attempt 1) 2011-09-12 12:45:33:568 Sending command (Callback ID=0x0a, Expected Reply=0x04) - SwitchAllCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x27, 0x02, 0x05, 0x0a, 0xc4 2011-09-12 12:45:33:572 ACK received CallbackId 0x0a Reply 0x04 2011-09-12 12:45:33:576 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:33:576 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:34:904 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x09, 0x03, 0x27, 0x03, 0xff, 0x23 2011-09-12 12:45:34:905 Received SwitchAll report from node 9: On and Off Enabled 2011-09-12 12:45:34:905 Expected reply and command class was received 2011-09-12 12:45:35:056 Received: 0x01, 0x05, 0x00, 0x13, 0x0a, 0x01, 0xe2 2011-09-12 12:45:35:056 ZW_SEND_DATA Request with callback ID 0x0a received (expected 0x0a) 2011-09-12 12:45:35:056 Error: ZW_SEND_DATA failed. 2011-09-12 12:45:35:056 Sending command (Callback ID=0x0a, Expected Reply=0x04) - SwitchAllCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x27, 0x02, 0x05, 0x0a, 0xc4 2011-09-12 12:45:35:060 ACK received CallbackId 0x0a Reply 0x04 2011-09-12 12:45:35:064 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:35:064 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:36:416 Received: 0x01, 0x05, 0x00, 0x13, 0x0a, 0x01, 0xe2 2011-09-12 12:45:36:416 ZW_SEND_DATA Request with callback ID 0x0a received (expected 0x0a) 2011-09-12 12:45:36:416 Error: ZW_SEND_DATA failed. 2011-09-12 12:45:36:416 Removing message after three tries 2011-09-12 12:45:36:417 RemoveMsg 1 2011-09-12 12:45:36:417 bMoreForThisNode = 0 2011-09-12 12:45:36:417 QueryStageComplete node 9 Session Session completed 1 2011-09-12 12:45:36:417 QueryStageComplete Dynamic m_queryPending 0 m_queryRetries 0 2011-09-12 12:45:36:417 AdvanceQueries node 9 Dynamic m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:36:417 Node 9: QueryStage_Dynamic 2011-09-12 12:45:36:417 Queuing command: BasicCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x20, 0x02, 0x05, 0x0b, 0xc2 2011-09-12 12:45:36:417 Queuing command: SwitchBinaryCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x25, 0x02, 0x05, 0x0c, 0xc0 2011-09-12 12:45:36:417 AdvanceQueries exit node 9 Dynamic m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 1 2011-09-12 12:45:36:516 Sending command (Callback ID=0x0b, Expected Reply=0x04) - BasicCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x20, 0x02, 0x05, 0x0b, 0xc2 2011-09-12 12:45:36:520 ACK received CallbackId 0x0b Reply 0x04 2011-09-12 12:45:36:524 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:36:524 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:37:800 Received: 0x01, 0x05, 0x00, 0x13, 0x0b, 0x01, 0xe3 2011-09-12 12:45:37:800 ZW_SEND_DATA Request with callback ID 0x0b received (expected 0x0b) 2011-09-12 12:45:37:800 Error: ZW_SEND_DATA failed. 2011-09-12 12:45:37:801 Sending command (Callback ID=0x0b, Expected Reply=0x04) - BasicCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x20, 0x02, 0x05, 0x0b, 0xc2 2011-09-12 12:45:37:804 ACK received CallbackId 0x0b Reply 0x04 2011-09-12 12:45:37:808 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:37:808 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:38:192 Received: 0x01, 0x05, 0x00, 0x13, 0x0b, 0x00, 0xe2 2011-09-12 12:45:38:192 ZW_SEND_DATA Request with callback ID 0x0b received (expected 0x0b) 2011-09-12 12:45:38:311 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x09, 0x03, 0x20, 0x03, 0x00, 0xdb 2011-09-12 12:45:38:311 Received Basic report from node 9: level=0 2011-09-12 12:45:38:311 Expected reply and command class was received 2011-09-12 12:45:38:311 Message transaction complete 2011-09-12 12:45:38:311 RemoveMsg 2 2011-09-12 12:45:38:311 bMoreForThisNode = 1 2011-09-12 12:45:38:314 Sending command (Callback ID=0x0c, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x25, 0x02, 0x05, 0x0c, 0xc0 2011-09-12 12:45:38:320 ACK received CallbackId 0x0c Reply 0x04 2011-09-12 12:45:38:320 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:38:320 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:39:048 Received: 0x01, 0x05, 0x00, 0x13, 0x0c, 0x00, 0xe5 2011-09-12 12:45:39:048 ZW_SEND_DATA Request with callback ID 0x0c received (expected 0x0c) 2011-09-12 12:45:39:088 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x09, 0x03, 0x25, 0x03, 0x00, 0xde 2011-09-12 12:45:39:088 Received SwitchBinary report from node 9: level=Off 2011-09-12 12:45:39:088 Expected reply and command class was received 2011-09-12 12:45:39:089 Message transaction complete 2011-09-12 12:45:39:089 RemoveMsg 1 2011-09-12 12:45:39:089 bMoreForThisNode = 0 2011-09-12 12:45:39:089 QueryStageComplete node 9 Dynamic Dynamic completed 1 2011-09-12 12:45:39:089 QueryStageComplete Configuration m_queryPending 0 m_queryRetries 0 2011-09-12 12:45:39:089 AdvanceQueries node 9 Configuration m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:39:089 Node 9: QueryStage_Configuration 2011-09-12 12:45:39:089 Node 9: QueryStage_Complete 2011-09-12 12:45:39:188 AdvanceQueries node 10 Associations m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:39:188 Node 10: QueryStage_Associations 2011-09-12 12:45:39:188 Node 10: QueryStage_Neighbors 2011-09-12 12:45:39:188 Requesting routing info (neighbor list) for Node 10 2011-09-12 12:45:39:188 Queuing command: Get Routing Info (Node=10): 0x01, 0x06, 0x00, 0x80, 0x0a, 0x01, 0x01, 0x73 2011-09-12 12:45:39:189 AdvanceQueries exit node 10 Neighbors m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 1 2011-09-12 12:45:39:288 Sending command (Callback ID=0x00, Expected Reply=0x80) - Get Routing Info (Node=10): 0x01, 0x06, 0x00, 0x80, 0x0a, 0x01, 0x01, 0x73 2011-09-12 12:45:39:296 ACK received CallbackId 0x00 Reply 0x80 2011-09-12 12:45:39:296 Received: 0x01, 0x20, 0x01, 0x80, 0x81, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0xdf 2011-09-12 12:45:39:296 Received reply to FUNC_ID_ZW_GET_ROUTING_INFO 2011-09-12 12:45:39:296 Neighbors of this node are: 2011-09-12 12:45:39:297 Node 1 2011-09-12 12:45:39:297 Node 8 2011-09-12 12:45:39:297 Expected reply was received 2011-09-12 12:45:39:297 Message transaction complete 2011-09-12 12:45:39:297 RemoveMsg 1 2011-09-12 12:45:39:297 bMoreForThisNode = 0 2011-09-12 12:45:39:297 QueryStageComplete node 10 Neighbors Neighbors completed 1 2011-09-12 12:45:39:297 QueryStageComplete Session m_queryPending 0 m_queryRetries 0 2011-09-12 12:45:39:297 AdvanceQueries node 10 Session m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:39:297 Node 10: QueryStage_Session 2011-09-12 12:45:39:297 Queuing command: WakeUpCmd_IntervalGet (Node=10): 0x01, 0x09, 0x00, 0x13, 0x0a, 0x02, 0x84, 0x05, 0x05, 0x0d, 0x64 2011-09-12 12:45:39:297 AdvanceQueries exit node 10 Session m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 1 2011-09-12 12:45:39:392 Sending command (Callback ID=0x0d, Expected Reply=0x04) - WakeUpCmd_IntervalGet (Node=10): 0x01, 0x09, 0x00, 0x13, 0x0a, 0x02, 0x84, 0x05, 0x05, 0x0d, 0x64 2011-09-12 12:45:39:396 ACK received CallbackId 0x0d Reply 0x04 2011-09-12 12:45:39:400 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:39:400 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:41:416 Received: 0x01, 0x05, 0x00, 0x13, 0x0d, 0x01, 0xe5 2011-09-12 12:45:41:416 ZW_SEND_DATA Request with callback ID 0x0d received (expected 0x0d) 2011-09-12 12:45:41:416 Error: ZW_SEND_DATA failed. 2011-09-12 12:45:41:416 Node 10 has been marked as asleep 2011-09-12 12:45:41:417 Node not responding - moving message to Wake-Up queue: WakeUpCmd_IntervalGet (Node=10): 0x01, 0x09, 0x00, 0x13, 0x0a, 0x02, 0x84, 0x05, 0x05, 0x0d, 0x64 2011-09-12 12:45:41:417 AdvanceQueries node 11 Associations m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:41:417 Node 11: QueryStage_Associations 2011-09-12 12:45:41:417 Number of association groups reported for node 11 is 3. 2011-09-12 12:45:41:417 Get Associations for group 1 of node 11 2011-09-12 12:45:41:417 Queuing command: Get Associations (Node=11): 0x01, 0x0a, 0x00, 0x13, 0x0b, 0x03, 0x85, 0x02, 0x01, 0x05, 0x0e, 0x63 2011-09-12 12:45:41:417 AdvanceQueries exit node 11 Associations m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 1 2011-09-12 12:45:41:516 Sending command (Callback ID=0x0e, Expected Reply=0x04) - Get Associations (Node=11): 0x01, 0x0a, 0x00, 0x13, 0x0b, 0x03, 0x85, 0x02, 0x01, 0x05, 0x0e, 0x63 2011-09-12 12:45:41:520 ACK received CallbackId 0x0e Reply 0x04 2011-09-12 12:45:41:525 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:41:525 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:41:536 Received: 0x01, 0x05, 0x00, 0x13, 0x0e, 0x00, 0xe7 2011-09-12 12:45:41:536 ZW_SEND_DATA Request with callback ID 0x0e received (expected 0x0e) 2011-09-12 12:45:41:545 Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x0b, 0x05, 0x85, 0x03, 0x01, 0x08, 0x00, 0x71 2011-09-12 12:45:41:545 Received Association report from node 11, group 1, containing 0 associations 2011-09-12 12:45:41:545 Get Associations for group 2 of node 11 2011-09-12 12:45:41:545 Queuing command: Get Associations (Node=11): 0x01, 0x0a, 0x00, 0x13, 0x0b, 0x03, 0x85, 0x02, 0x02, 0x05, 0x0f, 0x61 2011-09-12 12:45:41:545 Expected reply and command class was received 2011-09-12 12:45:41:546 Message transaction complete 2011-09-12 12:45:41:546 RemoveMsg 2 2011-09-12 12:45:41:546 bMoreForThisNode = 1 2011-09-12 12:45:41:546 Sending command (Callback ID=0x0f, Expected Reply=0x04) - Get Associations (Node=11): 0x01, 0x0a, 0x00, 0x13, 0x0b, 0x03, 0x85, 0x02, 0x02, 0x05, 0x0f, 0x61 2011-09-12 12:45:41:549 ACK received CallbackId 0x0f Reply 0x04 2011-09-12 12:45:41:552 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:41:552 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:41:566 Received: 0x01, 0x05, 0x00, 0x13, 0x0f, 0x00, 0xe6 2011-09-12 12:45:41:566 ZW_SEND_DATA Request with callback ID 0x0f received (expected 0x0f) 2011-09-12 12:45:41:708 Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x0b, 0x05, 0x85, 0x03, 0x02, 0x08, 0x00, 0x72 2011-09-12 12:45:41:708 Received Association report from node 11, group 2, containing 0 associations 2011-09-12 12:45:41:708 Get Associations for group 3 of node 11 2011-09-12 12:45:41:709 Queuing command: Get Associations (Node=11): 0x01, 0x0a, 0x00, 0x13, 0x0b, 0x03, 0x85, 0x02, 0x03, 0x05, 0x10, 0x7f 2011-09-12 12:45:41:709 Expected reply and command class was received 2011-09-12 12:45:41:709 Message transaction complete 2011-09-12 12:45:41:709 RemoveMsg 2 2011-09-12 12:45:41:709 bMoreForThisNode = 1 2011-09-12 12:45:41:709 Sending command (Callback ID=0x10, Expected Reply=0x04) - Get Associations (Node=11): 0x01, 0x0a, 0x00, 0x13, 0x0b, 0x03, 0x85, 0x02, 0x03, 0x05, 0x10, 0x7f 2011-09-12 12:45:41:712 ACK received CallbackId 0x10 Reply 0x04 2011-09-12 12:45:41:716 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:41:716 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:41:816 Received: 0x01, 0x05, 0x00, 0x13, 0x10, 0x00, 0xf9 2011-09-12 12:45:41:816 ZW_SEND_DATA Request with callback ID 0x10 received (expected 0x10) 2011-09-12 12:45:41:825 Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x0b, 0x05, 0x85, 0x03, 0x03, 0x08, 0x00, 0x73 2011-09-12 12:45:41:825 Received Association report from node 11, group 3, containing 0 associations 2011-09-12 12:45:41:825 Querying associations for node 11 is complete. 2011-09-12 12:45:41:825 Adding the controller to group 1 (Group 1) of node 11 2011-09-12 12:45:41:826 Association::Set - Adding node 1 to group 1 of node 11 2011-09-12 12:45:41:826 Queuing command: Association Set (Node=11): 0x01, 0x0b, 0x00, 0x13, 0x0b, 0x04, 0x85, 0x01, 0x01, 0x01, 0x05, 0x11, 0x78 2011-09-12 12:45:41:826 Expected reply and command class was received 2011-09-12 12:45:41:826 Message transaction complete 2011-09-12 12:45:41:826 RemoveMsg 2 2011-09-12 12:45:41:826 bMoreForThisNode = 1 2011-09-12 12:45:41:826 Sending command (Callback ID=0x10, Expected Reply=0x04) - Get Associations (Node=11): 0x01, 0x0a, 0x00, 0x13, 0x0b, 0x03, 0x85, 0x02, 0x03, 0x05, 0x10, 0x7f 2011-09-12 12:45:41:829 ACK received CallbackId 0x10 Reply 0x04 2011-09-12 12:45:41:832 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:41:832 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:41:844 Received: 0x01, 0x05, 0x00, 0x13, 0x10, 0x00, 0xf9 2011-09-12 12:45:41:845 ZW_SEND_DATA Request with callback ID 0x10 received (expected 0x10) 2011-09-12 12:45:41:940 Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x0b, 0x05, 0x85, 0x03, 0x03, 0x08, 0x00, 0x73 2011-09-12 12:45:41:940 Received Association report from node 11, group 3, containing 0 associations 2011-09-12 12:45:41:940 Expected reply and command class was received 2011-09-12 12:45:41:941 Message transaction complete 2011-09-12 12:45:41:941 RemoveMsg 1 2011-09-12 12:45:41:941 bMoreForThisNode = 0 2011-09-12 12:45:41:941 QueryStageComplete node 11 Associations Associations completed 1 2011-09-12 12:45:41:941 QueryStageComplete Neighbors m_queryPending 0 m_queryRetries 0 2011-09-12 12:45:41:941 AdvanceQueries node 11 Neighbors m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:41:941 Node 11: QueryStage_Neighbors 2011-09-12 12:45:41:941 Requesting routing info (neighbor list) for Node 11 2011-09-12 12:45:41:941 Queuing command: Get Routing Info (Node=11): 0x01, 0x06, 0x00, 0x80, 0x0b, 0x01, 0x01, 0x72 2011-09-12 12:45:41:941 AdvanceQueries exit node 11 Neighbors m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 1 2011-09-12 12:45:42:040 Sending command (Callback ID=0x00, Expected Reply=0x80) - Get Routing Info (Node=11): 0x01, 0x06, 0x00, 0x80, 0x0b, 0x01, 0x01, 0x72 2011-09-12 12:45:42:044 ACK received CallbackId 0x00 Reply 0x80 2011-09-12 12:45:42:049 Received: 0x01, 0x20, 0x01, 0x80, 0x0d, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x53 2011-09-12 12:45:42:049 Received reply to FUNC_ID_ZW_GET_ROUTING_INFO 2011-09-12 12:45:42:049 Neighbors of this node are: 2011-09-12 12:45:42:050 Node 1 2011-09-12 12:45:42:050 Node 3 2011-09-12 12:45:42:050 Node 4 2011-09-12 12:45:42:050 Expected reply was received 2011-09-12 12:45:42:050 Message transaction complete 2011-09-12 12:45:42:050 RemoveMsg 1 2011-09-12 12:45:42:050 bMoreForThisNode = 0 2011-09-12 12:45:42:050 QueryStageComplete node 11 Neighbors Neighbors completed 1 2011-09-12 12:45:42:050 QueryStageComplete Session m_queryPending 0 m_queryRetries 0 2011-09-12 12:45:42:050 AdvanceQueries node 11 Session m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:42:050 Node 11: QueryStage_Session 2011-09-12 12:45:42:050 Queuing command: SwitchAllCmd_Get (Node=11): 0x01, 0x09, 0x00, 0x13, 0x0b, 0x02, 0x27, 0x02, 0x05, 0x12, 0xde 2011-09-12 12:45:42:050 Queuing command: ProtectionCmd_Get (Node=11): 0x01, 0x09, 0x00, 0x13, 0x0b, 0x02, 0x75, 0x02, 0x05, 0x13, 0x8d 2011-09-12 12:45:42:050 Queuing command: NodeNamingCmd_Get (Node=11): 0x01, 0x09, 0x00, 0x13, 0x0b, 0x02, 0x77, 0x02, 0x05, 0x14, 0x88 2011-09-12 12:45:42:050 Queuing command: NodeNamingCmd_LocationGet (Node=11): 0x01, 0x09, 0x00, 0x13, 0x0b, 0x02, 0x77, 0x05, 0x05, 0x15, 0x8e 2011-09-12 12:45:42:050 AdvanceQueries exit node 11 Session m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 1 2011-09-12 12:45:42:148 Sending command (Callback ID=0x12, Expected Reply=0x04) - SwitchAllCmd_Get (Node=11): 0x01, 0x09, 0x00, 0x13, 0x0b, 0x02, 0x27, 0x02, 0x05, 0x12, 0xde 2011-09-12 12:45:42:152 ACK received CallbackId 0x12 Reply 0x04 2011-09-12 12:45:42:157 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:42:157 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:42:196 Received: 0x01, 0x05, 0x00, 0x13, 0x12, 0x00, 0xfb 2011-09-12 12:45:42:196 ZW_SEND_DATA Request with callback ID 0x12 received (expected 0x12) 2011-09-12 12:45:42:201 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x0b, 0x03, 0x27, 0x03, 0x01, 0xdf 2011-09-12 12:45:42:201 Received SwitchAll report from node 11: Off Enabled 2011-09-12 12:45:42:201 Expected reply and command class was received 2011-09-12 12:45:42:201 Message transaction complete 2011-09-12 12:45:42:201 RemoveMsg 4 2011-09-12 12:45:42:201 bMoreForThisNode = 1 2011-09-12 12:45:42:201 Sending command (Callback ID=0x13, Expected Reply=0x04) - ProtectionCmd_Get (Node=11): 0x01, 0x09, 0x00, 0x13, 0x0b, 0x02, 0x75, 0x02, 0x05, 0x13, 0x8d 2011-09-12 12:45:42:204 ACK received CallbackId 0x13 Reply 0x04 2011-09-12 12:45:42:209 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:42:209 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:42:221 Received: 0x01, 0x05, 0x00, 0x13, 0x13, 0x00, 0xfa 2011-09-12 12:45:42:221 ZW_SEND_DATA Request with callback ID 0x13 received (expected 0x13) 2011-09-12 12:45:42:229 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x0b, 0x03, 0x75, 0x03, 0x00, 0x8c 2011-09-12 12:45:42:229 Received a Protection report from node 11: Unprotected 2011-09-12 12:45:42:229 Expected reply and command class was received 2011-09-12 12:45:42:229 Message transaction complete 2011-09-12 12:45:42:230 RemoveMsg 3 2011-09-12 12:45:42:230 bMoreForThisNode = 1 2011-09-12 12:45:42:230 Sending command (Callback ID=0x14, Expected Reply=0x04) - NodeNamingCmd_Get (Node=11): 0x01, 0x09, 0x00, 0x13, 0x0b, 0x02, 0x77, 0x02, 0x05, 0x14, 0x88 2011-09-12 12:45:42:232 ACK received CallbackId 0x14 Reply 0x04 2011-09-12 12:45:42:236 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:42:236 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:42:249 Received: 0x01, 0x05, 0x00, 0x13, 0x14, 0x00, 0xfd 2011-09-12 12:45:42:249 ZW_SEND_DATA Request with callback ID 0x14 received (expected 0x14) 2011-09-12 12:45:42:261 Received: 0x01, 0x19, 0x00, 0x04, 0x00, 0x0b, 0x13, 0x77, 0x03, 0x00, 0x00, 0x02, 0x04, 0x06, 0x08, 0x0a, 0x0c, 0x0e, 0x01, 0x03, 0x05, 0x07, 0x09, 0x0b, 0x0d, 0x0f, 0x8e 2011-09-12 12:45:42:261 Received the name of Node 11: . 2011-09-12 12:45:42:261 Expected reply and command class was received 2011-09-12 12:45:42:261 Message transaction complete 2011-09-12 12:45:42:262 RemoveMsg 2 2011-09-12 12:45:42:262 bMoreForThisNode = 1 2011-09-12 12:45:42:262 Sending command (Callback ID=0x15, Expected Reply=0x04) - NodeNamingCmd_LocationGet (Node=11): 0x01, 0x09, 0x00, 0x13, 0x0b, 0x02, 0x77, 0x05, 0x05, 0x15, 0x8e 2011-09-12 12:45:42:265 ACK received CallbackId 0x15 Reply 0x04 2011-09-12 12:45:42:268 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:42:268 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:42:368 Received: 0x01, 0x05, 0x00, 0x13, 0x15, 0x00, 0xfc 2011-09-12 12:45:42:368 ZW_SEND_DATA Request with callback ID 0x15 received (expected 0x15) 2011-09-12 12:45:42:377 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x0b, 0x03, 0x77, 0x06, 0x00, 0x8b 2011-09-12 12:45:42:377 Received the location of Node 11: . 2011-09-12 12:45:42:377 Expected reply and command class was received 2011-09-12 12:45:42:377 Message transaction complete 2011-09-12 12:45:42:378 RemoveMsg 1 2011-09-12 12:45:42:378 bMoreForThisNode = 0 2011-09-12 12:45:42:378 QueryStageComplete node 11 Session Session completed 1 2011-09-12 12:45:42:378 QueryStageComplete Dynamic m_queryPending 0 m_queryRetries 0 2011-09-12 12:45:42:378 AdvanceQueries node 11 Dynamic m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:42:378 Node 11: QueryStage_Dynamic 2011-09-12 12:45:42:378 Queuing command: BasicCmd_Get (Node=11): 0x01, 0x09, 0x00, 0x13, 0x0b, 0x02, 0x20, 0x02, 0x05, 0x16, 0xdd 2011-09-12 12:45:42:378 Queuing command: SwitchMultilevelCmd_Get (Node=11): 0x01, 0x09, 0x00, 0x13, 0x0b, 0x02, 0x26, 0x02, 0x05, 0x17, 0xda 2011-09-12 12:45:42:378 Queuing command: IndicatorCmd_Get (Node=11): 0x01, 0x09, 0x00, 0x13, 0x0b, 0x02, 0x87, 0x02, 0x05, 0x18, 0x74 2011-09-12 12:45:42:378 AdvanceQueries exit node 11 Dynamic m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 1 2011-09-12 12:45:42:476 Sending command (Callback ID=0x16, Expected Reply=0x04) - BasicCmd_Get (Node=11): 0x01, 0x09, 0x00, 0x13, 0x0b, 0x02, 0x20, 0x02, 0x05, 0x16, 0xdd 2011-09-12 12:45:42:480 ACK received CallbackId 0x16 Reply 0x04 2011-09-12 12:45:42:485 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:42:485 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:42:505 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x0b, 0x03, 0x20, 0x03, 0x00, 0xd9 2011-09-12 12:45:42:505 Received Basic report from node 11: level=0 2011-09-12 12:45:42:505 Expected reply and command class was received 2011-09-12 12:45:42:580 Received: 0x01, 0x05, 0x00, 0x13, 0x16, 0x00, 0xff 2011-09-12 12:45:42:580 ZW_SEND_DATA Request with callback ID 0x16 received (expected 0x16) 2011-09-12 12:45:42:580 ZW_SEND_DATA was successful, removing command 2011-09-12 12:45:42:581 RemoveMsg 3 2011-09-12 12:45:42:581 bMoreForThisNode = 1 2011-09-12 12:45:42:581 Sending command (Callback ID=0x17, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=11): 0x01, 0x09, 0x00, 0x13, 0x0b, 0x02, 0x26, 0x02, 0x05, 0x17, 0xda 2011-09-12 12:45:42:584 ACK received CallbackId 0x17 Reply 0x04 2011-09-12 12:45:42:588 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:42:588 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:42:592 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x0b, 0x03, 0x20, 0x03, 0x00, 0xd9 2011-09-12 12:45:42:592 Received Basic report from node 11: level=0 2011-09-12 12:45:42:636 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x0b, 0x03, 0x20, 0x03, 0x00, 0xd9 2011-09-12 12:45:42:636 Received Basic report from node 11: level=0 2011-09-12 12:45:42:688 Received: 0x01, 0x05, 0x00, 0x13, 0x17, 0x00, 0xfe 2011-09-12 12:45:42:688 ZW_SEND_DATA Request with callback ID 0x17 received (expected 0x17) 2011-09-12 12:45:42:697 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x0b, 0x03, 0x26, 0x03, 0x00, 0xdf 2011-09-12 12:45:42:697 Received SwitchMultiLevel report from node 11: level=0 2011-09-12 12:45:42:697 Expected reply and command class was received 2011-09-12 12:45:42:697 Message transaction complete 2011-09-12 12:45:42:698 RemoveMsg 2 2011-09-12 12:45:42:698 bMoreForThisNode = 1 2011-09-12 12:45:42:698 Sending command (Callback ID=0x18, Expected Reply=0x04) - IndicatorCmd_Get (Node=11): 0x01, 0x09, 0x00, 0x13, 0x0b, 0x02, 0x87, 0x02, 0x05, 0x18, 0x74 2011-09-12 12:45:42:701 ACK received CallbackId 0x18 Reply 0x04 2011-09-12 12:45:42:704 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:42:704 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:42:784 Received: 0x01, 0x05, 0x00, 0x13, 0x18, 0x00, 0xf1 2011-09-12 12:45:42:784 ZW_SEND_DATA Request with callback ID 0x18 received (expected 0x18) 2011-09-12 12:45:42:789 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x0b, 0x03, 0x87, 0x03, 0x00, 0x7e 2011-09-12 12:45:42:789 Received an Indicator report from node 11: Indicator=0 2011-09-12 12:45:42:789 Expected reply and command class was received 2011-09-12 12:45:42:789 Message transaction complete 2011-09-12 12:45:42:789 RemoveMsg 1 2011-09-12 12:45:42:790 bMoreForThisNode = 0 2011-09-12 12:45:42:790 QueryStageComplete node 11 Dynamic Dynamic completed 1 2011-09-12 12:45:42:790 QueryStageComplete Configuration m_queryPending 0 m_queryRetries 0 2011-09-12 12:45:42:790 AdvanceQueries node 11 Configuration m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:45:42:790 Node 11: QueryStage_Configuration 2011-09-12 12:45:42:790 Node 11: QueryStage_Complete 2011-09-12 12:45:42:888 Node query processing complete except for sleeping nodes. 2011-09-12 12:45:42:996 Manager::WriteConfig completed for driver with home ID of 0x014cdfbf 2011-09-12 12:45:47:476 Polling node 3: COMMAND_CLASS_BASIC index = 0 instance = 1 (send queue has 0 messages) 2011-09-12 12:45:47:476 Queuing command: BasicCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x20, 0x02, 0x05, 0x19, 0xda 2011-09-12 12:45:47:572 Sending command (Callback ID=0x19, Expected Reply=0x04) - BasicCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x20, 0x02, 0x05, 0x19, 0xda 2011-09-12 12:45:47:578 ACK received CallbackId 0x19 Reply 0x04 2011-09-12 12:45:47:578 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:45:47:578 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:45:47:632 Received: 0x01, 0x05, 0x00, 0x13, 0x19, 0x00, 0xf0 2011-09-12 12:45:47:632 ZW_SEND_DATA Request with callback ID 0x19 received (expected 0x19) 2011-09-12 12:45:47:708 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x03, 0x03, 0x20, 0x03, 0x00, 0xd1 2011-09-12 12:45:47:708 Received Basic report from node 3: level=0 2011-09-12 12:45:47:708 Expected reply and command class was received 2011-09-12 12:45:47:708 Message transaction complete 2011-09-12 12:45:47:709 RemoveMsg 1 2011-09-12 12:47:02:476 Polling node 8: COMMAND_CLASS_BASIC index = 0 instance = 1 (send queue has 0 messages) 2011-09-12 12:47:02:476 Queuing command: BasicCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x20, 0x02, 0x05, 0x1a, 0xd2 2011-09-12 12:47:02:576 Sending command (Callback ID=0x1a, Expected Reply=0x04) - BasicCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x20, 0x02, 0x05, 0x1a, 0xd2 2011-09-12 12:47:02:581 ACK received CallbackId 0x1a Reply 0x04 2011-09-12 12:47:02:585 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:47:02:585 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:47:04:032 Received: 0x01, 0x05, 0x00, 0x13, 0x1a, 0x01, 0xf2 2011-09-12 12:47:04:032 ZW_SEND_DATA Request with callback ID 0x1a received (expected 0x1a) 2011-09-12 12:47:04:032 Error: ZW_SEND_DATA failed. 2011-09-12 12:47:04:033 Sending command (Callback ID=0x1a, Expected Reply=0x04) - BasicCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x20, 0x02, 0x05, 0x1a, 0xd2 2011-09-12 12:47:04:037 ACK received CallbackId 0x1a Reply 0x04 2011-09-12 12:47:04:041 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:47:04:041 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:47:05:152 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x08, 0x03, 0x20, 0x03, 0x00, 0xda 2011-09-12 12:47:05:152 Received Basic report from node 8: level=0 2011-09-12 12:47:05:153 Expected reply and command class was received 2011-09-12 12:47:05:400 Received: 0x01, 0x05, 0x00, 0x13, 0x1a, 0x01, 0xf2 2011-09-12 12:47:05:400 ZW_SEND_DATA Request with callback ID 0x1a received (expected 0x1a) 2011-09-12 12:47:05:400 Error: ZW_SEND_DATA failed. 2011-09-12 12:47:05:401 Sending command (Callback ID=0x1a, Expected Reply=0x04) - BasicCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x20, 0x02, 0x05, 0x1a, 0xd2 2011-09-12 12:47:05:405 ACK received CallbackId 0x1a Reply 0x04 2011-09-12 12:47:05:405 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:47:05:405 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:47:06:632 Received: 0x01, 0x05, 0x00, 0x13, 0x1a, 0x00, 0xf3 2011-09-12 12:47:06:632 ZW_SEND_DATA Request with callback ID 0x1a received (expected 0x1a) 2011-09-12 12:47:06:653 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x08, 0x03, 0x20, 0x03, 0x00, 0xda 2011-09-12 12:47:06:653 Received Basic report from node 8: level=0 2011-09-12 12:47:06:653 Expected reply and command class was received 2011-09-12 12:47:06:653 Message transaction complete 2011-09-12 12:47:06:654 RemoveMsg 1 2011-09-12 12:48:17:477 Polling node 9: COMMAND_CLASS_BASIC index = 0 instance = 1 (send queue has 0 messages) 2011-09-12 12:48:17:477 Queuing command: BasicCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x20, 0x02, 0x05, 0x1b, 0xd2 2011-09-12 12:48:17:576 Sending command (Callback ID=0x1b, Expected Reply=0x04) - BasicCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x20, 0x02, 0x05, 0x1b, 0xd2 2011-09-12 12:48:17:582 ACK received CallbackId 0x1b Reply 0x04 2011-09-12 12:48:17:582 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:48:17:582 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:48:17:968 Received: 0x01, 0x05, 0x00, 0x13, 0x1b, 0x00, 0xf2 2011-09-12 12:48:17:968 ZW_SEND_DATA Request with callback ID 0x1b received (expected 0x1b) 2011-09-12 12:48:18:040 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x09, 0x03, 0x20, 0x03, 0x00, 0xdb 2011-09-12 12:48:18:040 Received Basic report from node 9: level=0 2011-09-12 12:48:18:040 Expected reply and command class was received 2011-09-12 12:48:18:040 Message transaction complete 2011-09-12 12:48:18:041 RemoveMsg 1 2011-09-12 12:49:32:477 Polling node 11: COMMAND_CLASS_BASIC index = 0 instance = 1 (send queue has 0 messages) 2011-09-12 12:49:32:477 Queuing command: BasicCmd_Get (Node=11): 0x01, 0x09, 0x00, 0x13, 0x0b, 0x02, 0x20, 0x02, 0x05, 0x1c, 0xd7 2011-09-12 12:49:32:576 Sending command (Callback ID=0x1c, Expected Reply=0x04) - BasicCmd_Get (Node=11): 0x01, 0x09, 0x00, 0x13, 0x0b, 0x02, 0x20, 0x02, 0x05, 0x1c, 0xd7 2011-09-12 12:49:32:582 ACK received CallbackId 0x1c Reply 0x04 2011-09-12 12:49:32:582 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:49:32:582 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:49:32:596 Received: 0x01, 0x05, 0x00, 0x13, 0x1c, 0x00, 0xf5 2011-09-12 12:49:32:596 ZW_SEND_DATA Request with callback ID 0x1c received (expected 0x1c) 2011-09-12 12:49:32:606 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x0b, 0x03, 0x20, 0x03, 0x00, 0xd9 2011-09-12 12:49:32:606 Received Basic report from node 11: level=0 2011-09-12 12:49:32:606 Expected reply and command class was received 2011-09-12 12:49:32:606 Message transaction complete 2011-09-12 12:49:32:606 RemoveMsg 1 2011-09-12 12:50:47:477 Polling node 3: COMMAND_CLASS_BASIC index = 0 instance = 1 (send queue has 0 messages) 2011-09-12 12:50:47:477 Queuing command: BasicCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x20, 0x02, 0x05, 0x1d, 0xde 2011-09-12 12:50:47:576 Sending command (Callback ID=0x1d, Expected Reply=0x04) - BasicCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x20, 0x02, 0x05, 0x1d, 0xde 2011-09-12 12:50:47:581 ACK received CallbackId 0x1d Reply 0x04 2011-09-12 12:50:47:585 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:50:47:585 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:50:47:740 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x03, 0x03, 0x20, 0x03, 0x00, 0xd1 2011-09-12 12:50:47:740 Received Basic report from node 3: level=0 2011-09-12 12:50:47:740 Expected reply and command class was received 2011-09-12 12:50:48:768 Received: 0x01, 0x05, 0x00, 0x13, 0x1d, 0x01, 0xf5 2011-09-12 12:50:48:768 ZW_SEND_DATA Request with callback ID 0x1d received (expected 0x1d) 2011-09-12 12:50:48:768 Error: ZW_SEND_DATA failed. 2011-09-12 12:50:48:768 Sending command (Callback ID=0x1d, Expected Reply=0x04) - BasicCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x20, 0x02, 0x05, 0x1d, 0xde 2011-09-12 12:50:48:772 ACK received CallbackId 0x1d Reply 0x04 2011-09-12 12:50:48:796 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:50:48:796 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:50:50:092 Received: 0x01, 0x05, 0x00, 0x13, 0x1d, 0x01, 0xf5 2011-09-12 12:50:50:092 ZW_SEND_DATA Request with callback ID 0x1d received (expected 0x1d) 2011-09-12 12:50:50:092 Error: ZW_SEND_DATA failed. 2011-09-12 12:50:50:093 Sending command (Callback ID=0x1d, Expected Reply=0x04) - BasicCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x20, 0x02, 0x05, 0x1d, 0xde 2011-09-12 12:50:50:096 ACK received CallbackId 0x1d Reply 0x04 2011-09-12 12:50:50:121 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:50:50:121 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:50:50:180 Received: 0x01, 0x05, 0x00, 0x13, 0x1d, 0x00, 0xf4 2011-09-12 12:50:50:180 ZW_SEND_DATA Request with callback ID 0x1d received (expected 0x1d) 2011-09-12 12:50:50:220 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x03, 0x03, 0x20, 0x03, 0x00, 0xd1 2011-09-12 12:50:50:220 Received Basic report from node 3: level=0 2011-09-12 12:50:50:220 Expected reply and command class was received 2011-09-12 12:50:50:221 Message transaction complete 2011-09-12 12:50:50:221 RemoveMsg 1 2011-09-12 12:51:01:896 Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x07, 0x02, 0x84, 0x07, 0x75 2011-09-12 12:51:01:896 Received Wakeup Notification from node 7 2011-09-12 12:51:01:896 Node 7 has been marked as awake 2011-09-12 12:51:01:897 Queuing command: Get Associations (Node=7): 0x01, 0x0a, 0x00, 0x13, 0x07, 0x03, 0x85, 0x02, 0x01, 0x05, 0x06, 0x67 2011-09-12 12:51:01:897 Sending command (Callback ID=0x06, Expected Reply=0x04) - Get Associations (Node=7): 0x01, 0x0a, 0x00, 0x13, 0x07, 0x03, 0x85, 0x02, 0x01, 0x05, 0x06, 0x67 2011-09-12 12:51:01:901 ACK received CallbackId 0x06 Reply 0x04 2011-09-12 12:51:01:905 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:51:01:905 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:51:01:928 Received: 0x01, 0x05, 0x00, 0x13, 0x06, 0x00, 0xef 2011-09-12 12:51:01:928 ZW_SEND_DATA Request with callback ID 0x06 received (expected 0x06) 2011-09-12 12:51:01:937 Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x07, 0x05, 0x85, 0x03, 0x01, 0x01, 0x00, 0x74 2011-09-12 12:51:01:937 Received Association report from node 7, group 1, containing 0 associations 2011-09-12 12:51:01:937 Get Associations for group 2 of node 7 2011-09-12 12:51:01:937 Queuing command: Get Associations (Node=7): 0x01, 0x0a, 0x00, 0x13, 0x07, 0x03, 0x85, 0x02, 0x02, 0x05, 0x1e, 0x7c 2011-09-12 12:51:01:937 Expected reply and command class was received 2011-09-12 12:51:01:938 Message transaction complete 2011-09-12 12:51:01:938 RemoveMsg 2 2011-09-12 12:51:01:938 bMoreForThisNode = 1 2011-09-12 12:51:01:938 Sending command (Callback ID=0x1e, Expected Reply=0x04) - Get Associations (Node=7): 0x01, 0x0a, 0x00, 0x13, 0x07, 0x03, 0x85, 0x02, 0x02, 0x05, 0x1e, 0x7c 2011-09-12 12:51:01:945 ACK received CallbackId 0x1e Reply 0x04 2011-09-12 12:51:01:945 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:51:01:945 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:51:01:956 Received: 0x01, 0x05, 0x00, 0x13, 0x1e, 0x00, 0xf7 2011-09-12 12:51:01:956 ZW_SEND_DATA Request with callback ID 0x1e received (expected 0x1e) 2011-09-12 12:51:01:965 Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x07, 0x06, 0x85, 0x03, 0x02, 0x03, 0x00, 0x01, 0x70 2011-09-12 12:51:01:965 Received Association report from node 7, group 2, containing 1 associations 2011-09-12 12:51:01:965 The group contains: 2011-09-12 12:51:01:965 Node 1 2011-09-12 12:51:01:965 Querying associations for node 7 is complete. 2011-09-12 12:51:01:966 Adding the controller to group 1 (Reports) of node 7 2011-09-12 12:51:01:966 Association::Set - Adding node 1 to group 1 of node 7 2011-09-12 12:51:01:966 Queuing command: Association Set (Node=7): 0x01, 0x0b, 0x00, 0x13, 0x07, 0x04, 0x85, 0x01, 0x01, 0x01, 0x05, 0x1f, 0x7a 2011-09-12 12:51:01:966 Expected reply and command class was received 2011-09-12 12:51:01:966 Message transaction complete 2011-09-12 12:51:01:966 RemoveMsg 2 2011-09-12 12:51:01:966 bMoreForThisNode = 1 2011-09-12 12:51:01:966 Sending command (Callback ID=0x1e, Expected Reply=0x04) - Get Associations (Node=7): 0x01, 0x0a, 0x00, 0x13, 0x07, 0x03, 0x85, 0x02, 0x02, 0x05, 0x1e, 0x7c 2011-09-12 12:51:01:973 ACK received CallbackId 0x1e Reply 0x04 2011-09-12 12:51:01:973 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:51:01:973 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:51:01:985 Received: 0x01, 0x05, 0x00, 0x13, 0x1e, 0x00, 0xf7 2011-09-12 12:51:01:985 ZW_SEND_DATA Request with callback ID 0x1e received (expected 0x1e) 2011-09-12 12:51:01:997 Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x07, 0x06, 0x85, 0x03, 0x02, 0x03, 0x00, 0x01, 0x70 2011-09-12 12:51:01:997 Received Association report from node 7, group 2, containing 1 associations 2011-09-12 12:51:01:997 The group contains: 2011-09-12 12:51:01:997 Node 1 2011-09-12 12:51:01:997 Expected reply and command class was received 2011-09-12 12:51:01:997 Message transaction complete 2011-09-12 12:51:01:998 RemoveMsg 1 2011-09-12 12:51:01:998 bMoreForThisNode = 0 2011-09-12 12:51:01:998 QueryStageComplete node 7 Associations Associations completed 1 2011-09-12 12:51:01:998 QueryStageComplete Neighbors m_queryPending 0 m_queryRetries 0 2011-09-12 12:51:01:998 AdvanceQueries node 7 Neighbors m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:51:01:998 Node 7: QueryStage_Neighbors 2011-09-12 12:51:01:998 Requesting routing info (neighbor list) for Node 7 2011-09-12 12:51:01:998 Queuing command: Get Routing Info (Node=7): 0x01, 0x06, 0x00, 0x80, 0x07, 0x01, 0x01, 0x7e 2011-09-12 12:51:01:998 AdvanceQueries exit node 7 Neighbors m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 1 2011-09-12 12:51:02:096 Sending command (Callback ID=0x00, Expected Reply=0x80) - Get Routing Info (Node=7): 0x01, 0x06, 0x00, 0x80, 0x07, 0x01, 0x01, 0x7e 2011-09-12 12:51:02:101 ACK received CallbackId 0x00 Reply 0x80 2011-09-12 12:51:02:105 Received: 0x01, 0x20, 0x01, 0x80, 0x01, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x5f 2011-09-12 12:51:02:105 Received reply to FUNC_ID_ZW_GET_ROUTING_INFO 2011-09-12 12:51:02:105 Neighbors of this node are: 2011-09-12 12:51:02:105 Node 1 2011-09-12 12:51:02:105 Expected reply was received 2011-09-12 12:51:02:106 Message transaction complete 2011-09-12 12:51:02:106 RemoveMsg 1 2011-09-12 12:51:02:106 bMoreForThisNode = 0 2011-09-12 12:51:02:106 QueryStageComplete node 7 Neighbors Neighbors completed 1 2011-09-12 12:51:02:106 QueryStageComplete Session m_queryPending 0 m_queryRetries 0 2011-09-12 12:51:02:106 AdvanceQueries node 7 Session m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:51:02:106 Node 7: QueryStage_Session 2011-09-12 12:51:02:106 Queuing command: WakeUpCmd_IntervalGet (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x84, 0x05, 0x05, 0x20, 0x44 2011-09-12 12:51:02:106 AdvanceQueries exit node 7 Session m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 1 2011-09-12 12:51:02:204 Sending command (Callback ID=0x20, Expected Reply=0x04) - WakeUpCmd_IntervalGet (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x84, 0x05, 0x05, 0x20, 0x44 2011-09-12 12:51:02:209 ACK received CallbackId 0x20 Reply 0x04 2011-09-12 12:51:02:209 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:51:02:209 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:51:02:224 Received: 0x01, 0x05, 0x00, 0x13, 0x20, 0x00, 0xc9 2011-09-12 12:51:02:224 ZW_SEND_DATA Request with callback ID 0x20 received (expected 0x20) 2011-09-12 12:51:02:233 Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x07, 0x06, 0x84, 0x06, 0x00, 0x0e, 0x10, 0x01, 0x6b 2011-09-12 12:51:02:233 Received Wakeup Interval report from node 7: Interval=3600, Target Node=1 2011-09-12 12:51:02:233 Expected reply and command class was received 2011-09-12 12:51:02:233 Message transaction complete 2011-09-12 12:51:02:233 RemoveMsg 1 2011-09-12 12:51:02:234 bMoreForThisNode = 0 2011-09-12 12:51:02:234 QueryStageComplete node 7 Session Session completed 1 2011-09-12 12:51:02:234 QueryStageComplete Dynamic m_queryPending 0 m_queryRetries 0 2011-09-12 12:51:02:234 AdvanceQueries node 7 Dynamic m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:51:02:234 Node 7: QueryStage_Dynamic 2011-09-12 12:51:02:234 Queuing command: SensorMultilevelCmd_Get (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x31, 0x04, 0x05, 0x21, 0xf1 2011-09-12 12:51:02:234 Queuing command: MultiChannel Encapsulated (instance=2): SensorMultilevelCmd_Get (Node=7): 0x01, 0x0d, 0x00, 0x13, 0x07, 0x06, 0x60, 0x0d, 0x00, 0x02, 0x31, 0x04, 0x05, 0x22, 0x9d 2011-09-12 12:51:02:234 Queuing command: BatteryCmd_Get (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x80, 0x02, 0x05, 0x23, 0x44 2011-09-12 12:51:02:234 AdvanceQueries exit node 7 Dynamic m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 1 2011-09-12 12:51:02:332 Sending command (Callback ID=0x21, Expected Reply=0x04) - SensorMultilevelCmd_Get (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x31, 0x04, 0x05, 0x21, 0xf1 2011-09-12 12:51:02:337 ACK received CallbackId 0x21 Reply 0x04 2011-09-12 12:51:02:337 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:51:02:337 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:51:02:352 Received: 0x01, 0x05, 0x00, 0x13, 0x21, 0x00, 0xc8 2011-09-12 12:51:02:352 ZW_SEND_DATA Request with callback ID 0x21 received (expected 0x21) 2011-09-12 12:51:03:040 Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x07, 0x06, 0x31, 0x05, 0x01, 0x22, 0x00, 0xe2, 0x03 2011-09-12 12:51:03:040 Received SensorMultiLevel report from node 7, instance 1: value=22,6C 2011-09-12 12:51:03:041 Expected reply and command class was received 2011-09-12 12:51:03:041 Message transaction complete 2011-09-12 12:51:03:041 RemoveMsg 3 2011-09-12 12:51:03:041 bMoreForThisNode = 1 2011-09-12 12:51:03:041 Sending command (Callback ID=0x22, Expected Reply=0x04) - MultiChannel Encapsulated (instance=2): SensorMultilevelCmd_Get (Node=7): 0x01, 0x0d, 0x00, 0x13, 0x07, 0x06, 0x60, 0x0d, 0x00, 0x02, 0x31, 0x04, 0x05, 0x22, 0x9d 2011-09-12 12:51:03:045 ACK received CallbackId 0x22 Reply 0x04 2011-09-12 12:51:03:049 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:51:03:049 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:51:03:064 Received: 0x01, 0x05, 0x00, 0x13, 0x22, 0x00, 0xcb 2011-09-12 12:51:03:064 ZW_SEND_DATA Request with callback ID 0x22 received (expected 0x22) 2011-09-12 12:51:03:652 Received: 0x01, 0x0f, 0x00, 0x04, 0x00, 0x07, 0x09, 0x60, 0x0d, 0x02, 0x00, 0x31, 0x05, 0x05, 0x01, 0x4f, 0xea 2011-09-12 12:51:03:652 Received a MultiChannelEncap from node 7, endpoint 2 for Command Class COMMAND_CLASS_SENSOR_MULTILEVEL 2011-09-12 12:51:03:653 Received SensorMultiLevel report from node 7, instance 2: value=79% 2011-09-12 12:51:03:653 Expected reply and command class was received 2011-09-12 12:51:03:653 Message transaction complete 2011-09-12 12:51:03:653 RemoveMsg 2 2011-09-12 12:51:03:653 bMoreForThisNode = 1 2011-09-12 12:51:03:653 Sending command (Callback ID=0x23, Expected Reply=0x04) - BatteryCmd_Get (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x80, 0x02, 0x05, 0x23, 0x44 2011-09-12 12:51:03:657 ACK received CallbackId 0x23 Reply 0x04 2011-09-12 12:51:03:661 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:51:03:661 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:51:03:676 Received: 0x01, 0x05, 0x00, 0x13, 0x23, 0x00, 0xca 2011-09-12 12:51:03:676 ZW_SEND_DATA Request with callback ID 0x23 received (expected 0x23) 2011-09-12 12:51:05:384 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x07, 0x03, 0x80, 0x03, 0x64, 0x11 2011-09-12 12:51:05:384 Received Battery report from node 7: level=100 2011-09-12 12:51:05:384 Expected reply and command class was received 2011-09-12 12:51:05:385 Message transaction complete 2011-09-12 12:51:05:385 RemoveMsg 1 2011-09-12 12:51:05:385 bMoreForThisNode = 0 2011-09-12 12:51:05:385 QueryStageComplete node 7 Dynamic Dynamic completed 1 2011-09-12 12:51:05:385 QueryStageComplete Configuration m_queryPending 0 m_queryRetries 0 2011-09-12 12:51:05:385 AdvanceQueries node 7 Configuration m_queryStageCompleted 0 m_queryRetries 0 m_queryPending 0 2011-09-12 12:51:05:385 Node 7: QueryStage_Configuration 2011-09-12 12:51:05:385 Node 7: QueryStage_Complete 2011-09-12 12:52:02:478 Polling node 8: COMMAND_CLASS_BASIC index = 0 instance = 1 (send queue has 0 messages) 2011-09-12 12:52:02:478 Queuing command: BasicCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x20, 0x02, 0x05, 0x24, 0xec 2011-09-12 12:52:02:576 Sending command (Callback ID=0x24, Expected Reply=0x04) - BasicCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x20, 0x02, 0x05, 0x24, 0xec 2011-09-12 12:52:02:581 ACK received CallbackId 0x24 Reply 0x04 2011-09-12 12:52:02:585 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:52:02:585 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:52:02:892 Received: 0x01, 0x05, 0x00, 0x13, 0x24, 0x00, 0xcd 2011-09-12 12:52:02:892 ZW_SEND_DATA Request with callback ID 0x24 received (expected 0x24) 2011-09-12 12:52:08:092 Timeout 2011-09-12 12:52:08:092 Resending message (attempt 1) 2011-09-12 12:52:08:192 Sending command (Callback ID=0x24, Expected Reply=0x04) - BasicCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x20, 0x02, 0x05, 0x24, 0xec 2011-09-12 12:52:08:197 ACK received CallbackId 0x24 Reply 0x04 2011-09-12 12:52:08:201 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:52:08:201 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:52:08:700 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x08, 0x03, 0x20, 0x03, 0x00, 0xda 2011-09-12 12:52:08:700 Received Basic report from node 8: level=0 2011-09-12 12:52:08:701 Expected reply and command class was received 2011-09-12 12:52:09:236 Received: 0x01, 0x05, 0x00, 0x13, 0x24, 0x00, 0xcd 2011-09-12 12:52:09:236 ZW_SEND_DATA Request with callback ID 0x24 received (expected 0x24) 2011-09-12 12:52:09:236 ZW_SEND_DATA was successful, removing command 2011-09-12 12:52:09:236 RemoveMsg 1 2011-09-12 12:53:17:478 Polling node 9: COMMAND_CLASS_BASIC index = 0 instance = 1 (send queue has 0 messages) 2011-09-12 12:53:17:478 Queuing command: BasicCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x20, 0x02, 0x05, 0x25, 0xec 2011-09-12 12:53:17:576 Sending command (Callback ID=0x25, Expected Reply=0x04) - BasicCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x20, 0x02, 0x05, 0x25, 0xec 2011-09-12 12:53:17:583 ACK received CallbackId 0x25 Reply 0x04 2011-09-12 12:53:17:583 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:53:17:583 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:53:18:056 Received: 0x01, 0x05, 0x00, 0x13, 0x25, 0x00, 0xcc 2011-09-12 12:53:18:056 ZW_SEND_DATA Request with callback ID 0x25 received (expected 0x25) 2011-09-12 12:53:23:256 Timeout 2011-09-12 12:53:23:256 Resending message (attempt 1) 2011-09-12 12:53:23:356 Sending command (Callback ID=0x25, Expected Reply=0x04) - BasicCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x20, 0x02, 0x05, 0x25, 0xec 2011-09-12 12:53:23:361 ACK received CallbackId 0x25 Reply 0x04 2011-09-12 12:53:23:365 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:53:23:365 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:53:23:680 Received: 0x01, 0x05, 0x00, 0x13, 0x25, 0x00, 0xcc 2011-09-12 12:53:23:680 ZW_SEND_DATA Request with callback ID 0x25 received (expected 0x25) 2011-09-12 12:53:28:881 Timeout 2011-09-12 12:53:28:881 Resending message (attempt 2) 2011-09-12 12:53:28:980 Sending command (Callback ID=0x25, Expected Reply=0x04) - BasicCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x20, 0x02, 0x05, 0x25, 0xec 2011-09-12 12:53:28:985 ACK received CallbackId 0x25 Reply 0x04 2011-09-12 12:53:28:989 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:53:28:989 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:53:29:316 Received: 0x01, 0x05, 0x00, 0x13, 0x25, 0x00, 0xcc 2011-09-12 12:53:29:316 ZW_SEND_DATA Request with callback ID 0x25 received (expected 0x25) 2011-09-12 12:53:29:540 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x09, 0x03, 0x20, 0x03, 0x00, 0xdb 2011-09-12 12:53:29:540 Received Basic report from node 9: level=0 2011-09-12 12:53:29:540 Expected reply and command class was received 2011-09-12 12:53:29:541 Message transaction complete 2011-09-12 12:53:29:541 RemoveMsg 1 2011-09-12 12:54:32:478 Polling node 11: COMMAND_CLASS_BASIC index = 0 instance = 1 (send queue has 0 messages) 2011-09-12 12:54:32:479 Queuing command: BasicCmd_Get (Node=11): 0x01, 0x09, 0x00, 0x13, 0x0b, 0x02, 0x20, 0x02, 0x05, 0x26, 0xed 2011-09-12 12:54:32:576 Sending command (Callback ID=0x26, Expected Reply=0x04) - BasicCmd_Get (Node=11): 0x01, 0x09, 0x00, 0x13, 0x0b, 0x02, 0x20, 0x02, 0x05, 0x26, 0xed 2011-09-12 12:54:32:582 ACK received CallbackId 0x26 Reply 0x04 2011-09-12 12:54:32:582 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:54:32:582 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:54:32:684 Received: 0x01, 0x05, 0x00, 0x13, 0x26, 0x00, 0xcf 2011-09-12 12:54:32:684 ZW_SEND_DATA Request with callback ID 0x26 received (expected 0x26) 2011-09-12 12:54:32:692 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x0b, 0x03, 0x20, 0x03, 0x00, 0xd9 2011-09-12 12:54:32:692 Received Basic report from node 11: level=0 2011-09-12 12:54:32:692 Expected reply and command class was received 2011-09-12 12:54:32:693 Message transaction complete 2011-09-12 12:54:32:693 RemoveMsg 1 2011-09-12 12:55:47:479 Polling node 3: COMMAND_CLASS_BASIC index = 0 instance = 1 (send queue has 0 messages) 2011-09-12 12:55:47:479 Queuing command: BasicCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x20, 0x02, 0x05, 0x27, 0xe4 2011-09-12 12:55:47:576 Sending command (Callback ID=0x27, Expected Reply=0x04) - BasicCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x20, 0x02, 0x05, 0x27, 0xe4 2011-09-12 12:55:47:581 ACK received CallbackId 0x27 Reply 0x04 2011-09-12 12:55:47:585 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:55:47:585 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:55:47:644 Received: 0x01, 0x05, 0x00, 0x13, 0x27, 0x00, 0xce 2011-09-12 12:55:47:644 ZW_SEND_DATA Request with callback ID 0x27 received (expected 0x27) 2011-09-12 12:55:47:756 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x03, 0x03, 0x20, 0x03, 0x00, 0xd1 2011-09-12 12:55:47:756 Received Basic report from node 3: level=0 2011-09-12 12:55:47:756 Expected reply and command class was received 2011-09-12 12:55:47:756 Message transaction complete 2011-09-12 12:55:47:757 RemoveMsg 1 2011-09-12 12:57:02:479 Polling node 8: COMMAND_CLASS_BASIC index = 0 instance = 1 (send queue has 0 messages) 2011-09-12 12:57:02:479 Queuing command: BasicCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x20, 0x02, 0x05, 0x28, 0xe0 2011-09-12 12:57:02:576 Sending command (Callback ID=0x28, Expected Reply=0x04) - BasicCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x20, 0x02, 0x05, 0x28, 0xe0 2011-09-12 12:57:02:582 ACK received CallbackId 0x28 Reply 0x04 2011-09-12 12:57:02:582 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:57:02:582 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:57:03:100 Received: 0x01, 0x05, 0x00, 0x13, 0x28, 0x00, 0xc1 2011-09-12 12:57:03:100 ZW_SEND_DATA Request with callback ID 0x28 received (expected 0x28) 2011-09-12 12:57:08:300 Timeout 2011-09-12 12:57:08:301 Resending message (attempt 1) 2011-09-12 12:57:08:400 Sending command (Callback ID=0x28, Expected Reply=0x04) - BasicCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x20, 0x02, 0x05, 0x28, 0xe0 2011-09-12 12:57:08:406 ACK received CallbackId 0x28 Reply 0x04 2011-09-12 12:57:08:406 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:57:08:406 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:57:08:772 Received: 0x01, 0x05, 0x00, 0x13, 0x28, 0x00, 0xc1 2011-09-12 12:57:08:772 ZW_SEND_DATA Request with callback ID 0x28 received (expected 0x28) 2011-09-12 12:57:13:974 Timeout 2011-09-12 12:57:13:974 Resending message (attempt 2) 2011-09-12 12:57:14:072 Sending command (Callback ID=0x28, Expected Reply=0x04) - BasicCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x20, 0x02, 0x05, 0x28, 0xe0 2011-09-12 12:57:14:076 ACK received CallbackId 0x28 Reply 0x04 2011-09-12 12:57:14:080 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:57:14:080 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:57:14:520 Received: 0x01, 0x05, 0x00, 0x13, 0x28, 0x00, 0xc1 2011-09-12 12:57:14:520 ZW_SEND_DATA Request with callback ID 0x28 received (expected 0x28) 2011-09-12 12:57:19:720 ERROR: Dropping command, expected response not received after three attempts 2011-09-12 12:57:19:720 RemoveMsg 1 2011-09-12 12:58:17:483 Polling node 9: COMMAND_CLASS_BASIC index = 0 instance = 1 (send queue has 0 messages) 2011-09-12 12:58:17:483 Queuing command: BasicCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x20, 0x02, 0x05, 0x29, 0xe0 2011-09-12 12:58:17:581 Sending command (Callback ID=0x29, Expected Reply=0x04) - BasicCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x20, 0x02, 0x05, 0x29, 0xe0 2011-09-12 12:58:17:585 ACK received CallbackId 0x29 Reply 0x04 2011-09-12 12:58:17:592 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:58:17:592 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:58:18:000 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x09, 0x03, 0x20, 0x03, 0x00, 0xdb 2011-09-12 12:58:18:000 Received Basic report from node 9: level=0 2011-09-12 12:58:18:001 Expected reply and command class was received 2011-09-12 12:58:18:528 Received: 0x01, 0x05, 0x00, 0x13, 0x29, 0x00, 0xc0 2011-09-12 12:58:18:528 ZW_SEND_DATA Request with callback ID 0x29 received (expected 0x29) 2011-09-12 12:58:18:528 ZW_SEND_DATA was successful, removing command 2011-09-12 12:58:18:528 RemoveMsg 1 2011-09-12 12:58:18:756 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x09, 0x03, 0x20, 0x03, 0x00, 0xdb 2011-09-12 12:58:18:756 Received Basic report from node 9: level=0 2011-09-12 12:59:32:484 Polling node 11: COMMAND_CLASS_BASIC index = 0 instance = 1 (send queue has 0 messages) 2011-09-12 12:59:32:484 Queuing command: BasicCmd_Get (Node=11): 0x01, 0x09, 0x00, 0x13, 0x0b, 0x02, 0x20, 0x02, 0x05, 0x2a, 0xe1 2011-09-12 12:59:32:581 Sending command (Callback ID=0x2a, Expected Reply=0x04) - BasicCmd_Get (Node=11): 0x01, 0x09, 0x00, 0x13, 0x0b, 0x02, 0x20, 0x02, 0x05, 0x2a, 0xe1 2011-09-12 12:59:32:585 ACK received CallbackId 0x2a Reply 0x04 2011-09-12 12:59:32:592 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 12:59:32:592 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 12:59:32:604 Received: 0x01, 0x05, 0x00, 0x13, 0x2a, 0x00, 0xc3 2011-09-12 12:59:32:604 ZW_SEND_DATA Request with callback ID 0x2a received (expected 0x2a) 2011-09-12 12:59:32:611 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x0b, 0x03, 0x20, 0x03, 0x00, 0xd9 2011-09-12 12:59:32:611 Received Basic report from node 11: level=0 2011-09-12 12:59:32:611 Expected reply and command class was received 2011-09-12 12:59:32:611 Message transaction complete 2011-09-12 12:59:32:611 RemoveMsg 1 2011-09-12 13:00:47:484 Polling node 3: COMMAND_CLASS_BASIC index = 0 instance = 1 (send queue has 0 messages) 2011-09-12 13:00:47:484 Queuing command: BasicCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x20, 0x02, 0x05, 0x2b, 0xe8 2011-09-12 13:00:47:581 Sending command (Callback ID=0x2b, Expected Reply=0x04) - BasicCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x20, 0x02, 0x05, 0x2b, 0xe8 2011-09-12 13:00:47:585 ACK received CallbackId 0x2b Reply 0x04 2011-09-12 13:00:47:589 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2011-09-12 13:00:47:589 ZW_SEND_DATA delivered to Z-Wave stack 2011-09-12 13:00:47:624 Received: 0x01, 0x05, 0x00, 0x13, 0x2b, 0x00, 0xc2 2011-09-12 13:00:47:624 ZW_SEND_DATA Request with callback ID 0x2b received (expected 0x2b) 2011-09-12 13:00:47:664 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x03, 0x03, 0x20, 0x03, 0x00, 0xd1 2011-09-12 13:00:47:664 Received Basic report from node 3: level=0 2011-09-12 13:00:47:664 Expected reply and command class was received 2011-09-12 13:00:47:665 Message transaction complete 2011-09-12 13:00:47:665 RemoveMsg 1