SUC/SIS doesn't work with OZW
SUC/SIS doesn't work with OZW. There for, adding the inclusion controller doesn't work either, it automatically becomes a secondary controller to the zwave network. Below is a snipet out of the zwave-log, beginning at the resetting of the controller through adding the aeon-labs mini mote
2013-10-10 22:29:20.056 contrlr, Sending (Command) message (Callback ID=0x01, Expected Reply=0x42) - Reset controller and erase all node information: 0x01, 0x04, 0x00, 0x42, 0x01, 0xb8
2013-10-10 22:29:20.911 ***************************************************************************
2013-10-10 22:29:20.911 ********************* Cumulative Network Statistics *********************
2013-10-10 22:29:20.911 *** General
2013-10-10 22:29:20.911 Driver run time: . . . 0 days, 0 hours, 1 minutes
2013-10-10 22:29:20.911 Frames processed: . . . . . . . . . . . . . . . . . . . . 10
2013-10-10 22:29:20.911 Total messages successfully received: . . . . . . . . . . 10
2013-10-10 22:29:20.912 Total Messages successfully sent: . . . . . . . . . . . . 11
2013-10-10 22:29:20.912 ACKs received from controller: . . . . . . . . . . . . . 11
2013-10-10 22:29:20.912 *** Errors
2013-10-10 22:29:20.912 Unsolicited messages received while waiting for ACK: . . 0
2013-10-10 22:29:20.912 Reads aborted due to timeouts: . . . . . . . . . . . . . 0
2013-10-10 22:29:20.912 Bad checksum errors: . . . . . . . . . . . . . . . . . . 0
2013-10-10 22:29:20.912 CANs received from controller: . . . . . . . . . . . . . 0
2013-10-10 22:29:20.912 NAKs received from controller: . . . . . . . . . . . . . 0
2013-10-10 22:29:20.912 Out of frame data flow errors: . . . . . . . . . . . . . 0
2013-10-10 22:29:20.912 Messages retransmitted: . . . . . . . . . . . . . . . . . 0
2013-10-10 22:29:20.912 Messages dropped and not delivered: . . . . . . . . . . . 0
2013-10-10 22:29:20.912 ***************************************************************************
<0xb64ffb40>
05 10/10/13 22:29:22.918 ZWInterface::OnNotification() : Node Removed nodeId = 1 <0xb64ffb40>
2013-10-10 22:29:22.918 mgr, Driver for controller /dev/ttyUSB0 removed
2013-10-10 22:29:22.918 mgr, Added driver for controller /dev/ttyUSB0
2013-10-10 22:29:22.918 Opening controller /dev/ttyUSB0
2013-10-10 22:29:22.918 Trying to open serial port /dev/ttyUSB0 (attempt 1)
2013-10-10 22:29:22.931 Serial port /dev/ttyUSB0 opened (attempt 1)
2013-10-10 22:29:22.932 contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2013-10-10 22:29:22.936 contrlr, Received reply to FUNC_ID_ZW_GET_VERSION:
2013-10-10 22:29:22.936 contrlr, Static Controller library, version Z-Wave 2.78
2013-10-10 22:29:22.936 contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2013-10-10 22:29:22.940 contrlr, Received reply to FUNC_ID_ZW_MEMORY_GET_ID. Home ID = 0x014cfbba. Our node ID = 1
2013-10-10 22:29:22.940 contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2013-10-10 22:29:22.943 contrlr, Received reply to FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES:
2013-10-10 22:29:22.943 contrlr, There is no SUC ID Server (SIS) in this network.
2013-10-10 22:29:22.943 contrlr, The PC controller is a primary controller.
2013-10-10 22:29:22.943 contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2013-10-10 22:29:22.950 contrlr, Received reply to FUNC_ID_SERIAL_API_GET_CAPABILITIES
2013-10-10 22:29:22.950 contrlr, Serial API Version: 3.7
2013-10-10 22:29:22.950 contrlr, Manufacturer ID: 0x0086
2013-10-10 22:29:22.950 contrlr, Product Type: 0x0002
2013-10-10 22:29:22.950 contrlr, Product ID: 0x0001
2013-10-10 22:29:22.951 contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x56) - FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2013-10-10 22:29:22.954 contrlr, Received reply to GET_SUC_NODE_ID. Node ID = 0
2013-10-10 22:29:22.954 No SUC, so we become SUC
2013-10-10 22:29:22.954 contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x1c) - FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2013-10-10 22:29:23.009 contrlr, Received reply to FUNC_ID_ZW_GET_RANDOM: true
2013-10-10 22:29:23.009 contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x02) - FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2013-10-10 22:29:23.060 mgr, Driver with Home ID of 0x014cfbba is now ready.
2013-10-10 22:29:23.060
2013-10-10 22:29:23.061 contrlr, Received reply to FUNC_ID_SERIAL_API_GET_INIT_DATA:
2013-10-10 22:29:23.061 contrlr, Node 001 - Known
05 10/10/13 22:29:23.419 ZWInterface::OnNotification() : Node Added nodeId = 1 <0xb50ffb40>
05 10/10/13 22:29:23.419 ZWInterface::OnNotification() : Value Added Home 0x014cfbba Node 1 Genre 0 Class 32 Instance 1 Index 0 Type 1, label = Basic
<0xb50ffb40>
2013-10-10 22:29:23.419 mgr, Manager::WriteConfig completed for driver with home ID of 0x014cfbba
2013-10-10 22:29:23.420 contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x06) - FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
2013-10-10 22:29:23.423 contrlr, Received reply to FUNC_ID_SERIAL_API_SET_TIMEOUTS
2013-10-10 22:29:23.423 contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x00) - FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x07, 0x00, 0x03, 0x01, 0x02, 0x01, 0x00, 0xf9
2013-10-10 22:29:23.427 Node001, Sending (Command) message (Callback ID=0x00, Expected Reply=0x52) - Enable SUC (Node=1): 0x01, 0x06, 0x00, 0x52, 0x01, 0x00, 0x01, 0xab
2013-10-10 22:29:23.430 Node001, Received reply to Enable SUC.
2013-10-10 22:30:03.430 Node001, Received reply to Enable SUC.
2013-10-10 22:30:43.431 Node001, Received reply to Enable SUC.
2013-10-10 22:31:23.605
2013-10-10 22:31:23.605 Dumping queued log messages
2013-10-10 22:31:23.605
2013-10-10 22:29:23.427 b50ffb40
2013-10-10 22:29:23.427 b50ffb40 Sending (Command) message (Callback ID=0x00, Expected Reply=0x52) - Enable SUC (Node=1): 0x01, 0x06, 0x00, 0x52, 0x01, 0x00, 0x01, 0xab
2013-10-10 22:29:23.430 b50ffb40 Received: 0x01, 0x04, 0x01, 0x52, 0x01, 0xa9
2013-10-10 22:29:23.430 b50ffb40
2013-10-10 22:29:23.430 b50ffb40 Received reply to Enable SUC.
2013-10-10 22:29:23.430 b50ffb40 IsExpectedReply: m_expectedNodeId = 1 m_expectedReply = 52
2013-10-10 22:30:03.427 b50ffb40
2013-10-10 22:30:03.427 b50ffb40 Sending (Command) message (Attempt 2, Callback ID=0x00, Expected Reply=0x52) - Enable SUC (Node=1): 0x01, 0x06, 0x00, 0x52, 0x01, 0x00, 0x01, 0xab
2013-10-10 22:30:03.430 b50ffb40 Received: 0x01, 0x04, 0x01, 0x52, 0x01, 0xa9
2013-10-10 22:30:03.430 b50ffb40
2013-10-10 22:30:03.430 b50ffb40 Received reply to Enable SUC.
2013-10-10 22:30:03.430 b50ffb40 IsExpectedReply: m_expectedNodeId = 1 m_expectedReply = 52
2013-10-10 22:30:43.428 b50ffb40
2013-10-10 22:30:43.428 b50ffb40 Sending (Command) message (Attempt 3, Callback ID=0x00, Expected Reply=0x52) - Enable SUC (Node=1): 0x01, 0x06, 0x00, 0x52, 0x01, 0x00, 0x01, 0xab
2013-10-10 22:30:43.431 b50ffb40 Received: 0x01, 0x04, 0x01, 0x52, 0x01, 0xa9
2013-10-10 22:30:43.431 b50ffb40
2013-10-10 22:30:43.431 b50ffb40 Received reply to Enable SUC.
2013-10-10 22:30:43.431 b50ffb40 IsExpectedReply: m_expectedNodeId = 1 m_expectedReply = 52
2013-10-10 22:31:23.605 b50ffb40 ERROR: Dropping command, expected response not received after 3 attempt(s)
2013-10-10 22:31:23.605 b50ffb40
2013-10-10 22:31:23.605 b50ffb40 Dumping queued log messages
2013-10-10 22:31:23.605 b50ffb40
2013-10-10 22:31:23.606
2013-10-10 22:31:23.606 End of queued log message dump
2013-10-10 22:31:23.606
2013-10-10 22:31:23.607 Node001, Sending (Command) message (Callback ID=0x00, Expected Reply=0x54) - Set SUC node ID (Node=1): 0x01, 0x07, 0x00, 0x54, 0x01, 0x01, 0x00, 0x01, 0xad
2013-10-10 22:31:23.614 Node001, Received reply to SET_SUC_NODE_ID.
05 10/10/13 22:31:32.419 void ClientSocket::Disconnect() on this socket: 0x9e9c430 (m_Socket: 6) <0xb6e83700>
05 10/10/13 22:31:32.422 Socket::ReceiveData 0x9ea1eb0 failed, bytes left -1 start: 80000 1: 0 1b: 0 2: 0 2b: 0 m_Socket: 4 Event Dev #59 <0xb6e83700>
05 10/10/13 22:31:32.422 Socket::ReceiveString2 ReceiveData failed m_Socket: -1 Event Dev #59 <0xb6e83700>
01 10/10/13 22:31:32.422 Lost connection device: 59 <0xb6e83700>
05 10/10/13 22:31:32.422 Handshake failed. Retrying in 5 seconds <0xb6e83700>
05 10/10/13 22:31:45.449 Note: Device manager has attached a device of type 1820 that this has no custom event handler for. It will not fire events. <0xb6e83700>
05 10/10/13 22:31:45.449 Note: Device manager has attached a device of type 1820 that this has no custom handler for. This is normal for IR. <0xb6e83700>
05 10/10/13 22:31:45.449 Creating child 90 <0xb6e83700>
05 10/10/13 22:31:45.449 Note: Device manager has attached a device of type 1922 that this has no custom event handler for. It will not fire events. <0xb6e83700>
05 10/10/13 22:31:45.449 Note: Device manager has attached a device of type 1922 that this has no custom handler for. This is normal for IR. <0xb6e83700>
05 10/10/13 22:31:45.449 Creating child 91 <0xb6e83700>
05 10/10/13 22:31:45.449 Note: Device manager has attached a device of type 1945 that this has no custom event handler for. It will not fire events. <0xb6e83700>
05 10/10/13 22:31:45.449 Note: Device manager has attached a device of type 1945 that this has no custom handler for. This is normal for IR. <0xb6e83700>
01 10/10/13 22:31:45.518 TranslateSerialUSB pci0000:00/0000:00:13.1+3 result /dev/ttyUSB0 <0xb6e83700>
2013-10-10 22:32:03.612 Node001, Received reply to SET_SUC_NODE_ID.
2013-10-10 22:32:43.613 Node001, Received reply to SET_SUC_NODE_ID.
2013-10-10 22:33:23.610
2013-10-10 22:33:23.611 Dumping queued log messages
2013-10-10 22:33:23.611
2013-10-10 22:31:23.607 b50ffb40
2013-10-10 22:31:23.607 b50ffb40 Sending (Command) message (Callback ID=0x00, Expected Reply=0x54) - Set SUC node ID (Node=1): 0x01, 0x07, 0x00, 0x54, 0x01, 0x01, 0x00, 0x01, 0xad
2013-10-10 22:31:23.614 b50ffb40 Received: 0x01, 0x04, 0x01, 0x54, 0x01, 0xaf
2013-10-10 22:31:23.614 b50ffb40
2013-10-10 22:31:23.614 b50ffb40 Received reply to SET_SUC_NODE_ID.
2013-10-10 22:31:23.615 b50ffb40 IsExpectedReply: m_expectedNodeId = 1 m_expectedReply = 54
2013-10-10 22:32:03.608 b50ffb40
2013-10-10 22:32:03.608 b50ffb40 Sending (Command) message (Attempt 2, Callback ID=0x00, Expected Reply=0x54) - Set SUC node ID (Node=1): 0x01, 0x07, 0x00, 0x54, 0x01, 0x01, 0x00, 0x01, 0xad
2013-10-10 22:32:03.612 b50ffb40 Received: 0x01, 0x04, 0x01, 0x54, 0x01, 0xaf
2013-10-10 22:32:03.612 b50ffb40
2013-10-10 22:32:03.612 b50ffb40 Received reply to SET_SUC_NODE_ID.
2013-10-10 22:32:03.612 b50ffb40 IsExpectedReply: m_expectedNodeId = 1 m_expectedReply = 54
2013-10-10 22:32:43.609 b50ffb40
2013-10-10 22:32:43.609 b50ffb40 Sending (Command) message (Attempt 3, Callback ID=0x00, Expected Reply=0x54) - Set SUC node ID (Node=1): 0x01, 0x07, 0x00, 0x54, 0x01, 0x01, 0x00, 0x01, 0xad
2013-10-10 22:32:43.613 b50ffb40 Received: 0x01, 0x04, 0x01, 0x54, 0x01, 0xaf
2013-10-10 22:32:43.613 b50ffb40
2013-10-10 22:32:43.613 b50ffb40 Received reply to SET_SUC_NODE_ID.
2013-10-10 22:32:43.613 b50ffb40 IsExpectedReply: m_expectedNodeId = 1 m_expectedReply = 54
2013-10-10 22:33:23.610 b50ffb40 ERROR: Dropping command, expected response not received after 3 attempt(s)
2013-10-10 22:33:23.610 b50ffb40
2013-10-10 22:33:23.611 b50ffb40 Dumping queued log messages
2013-10-10 22:33:23.611 b50ffb40
2013-10-10 22:33:23.612
2013-10-10 22:33:23.612 End of queued log message dump
2013-10-10 22:33:23.612
2013-10-10 22:33:23.612 Node001, Sending (Command) message (Callback ID=0x00, Expected Reply=0x80) - Get Routing Info (Node=1): 0x01, 0x07, 0x00, 0x80, 0x01, 0x00, 0x00, 0x03, 0x7a
2013-10-10 22:33:23.618 Node001, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
2013-10-10 22:33:23.619 Node001, Neighbors of this node are:
2013-10-10 22:33:23.619 Node001, (none reported)
2013-10-10 22:33:23.619 CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2013-10-10 22:33:23.619 CheckCompletedNodeQueries all=1, deadFound=0 sleepingOnly=1
2013-10-10 22:33:23.619 Node query processing complete.
2013-10-10 22:35:15.008 contrlr, Sending (Command) message (Callback ID=0x02, Expected Reply=0x4a) - AddDevice: 0x01, 0x05, 0x00, 0x4a, 0x01, 0x02, 0xb3
2013-10-10 22:35:15.012 contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2013-10-10 22:35:15.013 contrlr, ADD_NODE_STATUS_LEARN_READY
2013-10-10 22:35:15.013 WriteNextMsg Controller nothing to do
2013-10-10 22:35:21.781 ADD_NODE_STATUS_NODE_FOUND
2013-10-10 22:35:22.206 FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2013-10-10 22:35:22.206 ADD_NODE_STATUS_ADDING_CONTROLLER
2013-10-10 22:35:22.207 Adding controller ID 2
2013-10-10 22:35:22.286 FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2013-10-10 22:35:22.286 ADD_NODE_STATUS_PROTOCOL_DONE
2013-10-10 22:35:22.287 contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x4a) - Add Node Stop: 0x01, 0x04, 0x00, 0x4a, 0x05, 0xb4
2013-10-10 22:35:22.367 contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:
2013-10-10 22:35:22.367 contrlr, ADD_NODE_STATUS_DONE
05 10/10/13 22:35:22.368 ZWInterface::OnNotification() : Node Added nodeId = 2 <0xb50ffb40>
2013-10-10 22:35:22.368 Node002, Sending (Query) message (Callback ID=0x00, Expected Reply=0x41) - Get Node Protocol Info (Node=2): 0x01, 0x04, 0x00, 0x41, 0x02, 0xb8
2013-10-10 22:35:22.372 Node002, Received reply to FUNC_ID_ZW_GET_NODE_PROTOCOL_INFO
2013-10-10 22:35:22.372 Node002, Protocol Info for Node 2:
2013-10-10 22:35:22.372 Node002, Listening = false
2013-10-10 22:35:22.373 Node002, Frequent = false
2013-10-10 22:35:22.373 Node002, Beaming = true
2013-10-10 22:35:22.373 Node002, Routing = false
2013-10-10 22:35:22.373 Node002, Max Baud Rate = 40000
2013-10-10 22:35:22.373 Node002, Version = 3
2013-10-10 22:35:22.373 Node002, Security = false
2013-10-10 22:35:22.380 Node002, Basic device class (0x01) - Controller
2013-10-10 22:35:22.380 Node002, Generic device Class (0x01) - Remote Controller
2013-10-10 22:35:22.381 Node002, Specific device class (0x01) - Portable Remote Controller
2013-10-10 22:35:22.381 Node002, COMMAND_CLASS_BASIC is not mapped
2013-10-10 22:35:22.381 Node002, Mandatory Command Classes for Node 2:
2013-10-10 22:35:22.381 Node002, COMMAND_CLASS_WAKE_UP
2013-10-10 22:35:22.381 Node002, Mandatory Command Classes controlled by Node 2:
2013-10-10 22:35:22.381 Node002, COMMAND_CLASS_BASIC
05 10/10/13 22:35:22.382 ZWInterface::OnNotification() : Value Added Home 0x014cfbba Node 2 Genre 0 Class 32 Instance 1 Index 0 Type 1, label = Basic
<0xb50ffb40>
2013-10-10 22:35:22.382 mgr, Manager::WriteConfig completed for driver with home ID of 0x014cfbba
2013-10-10 22:35:22.383 Node002, NoOperation::Set - Routing=true
2013-10-10 22:35:22.383 Node002, Sending (NoOp) message (Callback ID=0x03, Expected Reply=0x13) - NoOperation_Set (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x00, 0x00, 0x25, 0x03, 0xc3
2013-10-10 22:35:22.400 Node002, Request RTT 17 Average Request RTT 17
2013-10-10 22:35:22.401 Node002, Sending (Query) message (Callback ID=0x00, Expected Reply=0x49) - Request Node Info (Node=2): 0x01, 0x04, 0x00, 0x60, 0x02, 0x99
2013-10-10 22:35:22.406 Node002, FUNC_ID_ZW_REQUEST_NODE_INFO Request successful.
2013-10-10 22:35:22.496 Node002, UPDATE_STATE_NODE_INFO_RECEIVED from node 2
2013-10-10 22:35:22.496 Node002, Optional command classes for node 2:
2013-10-10 22:35:22.497 Node002, COMMAND_CLASS_VERSION
2013-10-10 22:35:22.497 Node002, COMMAND_CLASS_MANUFACTURER_SPECIFIC
2013-10-10 22:35:22.497 Node002, COMMAND_CLASS_ASSOCIATION
2013-10-10 22:35:22.497 Node002, COMMAND_CLASS_CONFIGURATION
2013-10-10 22:35:22.497 Node002, COMMAND_CLASS_ASSOCIATION_COMMAND_CONFIGURATION
2013-10-10 22:35:22.497 Node002, Optional command classes controlled by node 2:
2013-10-10 22:35:22.497 Node002, COMMAND_CLASS_SCENE_ACTIVATION
2013-10-10 22:35:22.497 Node002, COMMAND_CLASS_SWITCH_MULTILEVEL
05 10/10/13 22:35:22.498 ZWInterface::OnNotification() : Value Added Home 0x014cfbba Node 2 Genre 3 Class 134 Instance 1 Index 0 Type 7, label = Library Version
<0xb50ffb40>
2013-10-10 22:35:22.502 mgr, Manager::WriteConfig completed for driver with home ID of 0x014cfbba
05 10/10/13 22:35:22.502 ZWInterface::OnNotification() : Value Added Home 0x014cfbba Node 2 Genre 3 Class 134 Instance 1 Index 1 Type 7, label = Protocol Version
<0xb50ffb40>
2013-10-10 22:35:22.503 mgr, Manager::WriteConfig completed for driver with home ID of 0x014cfbba
05 10/10/13 22:35:22.504 ZWInterface::OnNotification() : Value Added Home 0x014cfbba Node 2 Genre 3 Class 134 Instance 1 Index 2 Type 7, label = Application Version
<0xb50ffb40>
2013-10-10 22:35:22.504 mgr, Manager::WriteConfig completed for driver with home ID of 0x014cfbba
05 10/10/13 22:35:22.505 ZWInterface::OnNotification() : Value Added Home 0x014cfbba Node 2 Genre 3 Class 155 Instance 1 Index 0 Type 1, label = Max Command Length
<0xb50ffb40>
2013-10-10 22:35:22.505 mgr, Manager::WriteConfig completed for driver with home ID of 0x014cfbba
05 10/10/13 22:35:22.506 ZWInterface::OnNotification() : Value Added Home 0x014cfbba Node 2 Genre 3 Class 155 Instance 1 Index 1 Type 0, label = Commands are Values
<0xb50ffb40>
2013-10-10 22:35:22.506 mgr, Manager::WriteConfig completed for driver with home ID of 0x014cfbba
05 10/10/13 22:35:22.507 ZWInterface::OnNotification() : Value Added Home 0x014cfbba Node 2 Genre 3 Class 155 Instance 1 Index 2 Type 0, label = Commands are Configurable
<0xb50ffb40>
2013-10-10 22:35:22.507 mgr, Manager::WriteConfig completed for driver with home ID of 0x014cfbba
05 10/10/13 22:35:22.508 ZWInterface::OnNotification() : Value Added Home 0x014cfbba Node 2 Genre 3 Class 155 Instance 1 Index 3 Type 1, label = Free Commands
<0xb50ffb40>
2013-10-10 22:35:22.508 mgr, Manager::WriteConfig completed for driver with home ID of 0x014cfbba
05 10/10/13 22:35:22.509 ZWInterface::OnNotification() : Value Added Home 0x014cfbba Node 2 Genre 3 Class 155 Instance 1 Index 4 Type 1, label = Max Commands
<0xb50ffb40>
2013-10-10 22:35:22.509 mgr, Manager::WriteConfig completed for driver with home ID of 0x014cfbba
05 10/10/13 22:35:22.510 ZWInterface::OnNotification() : Value Added Home 0x014cfbba Node 2 Genre 1 Class 38 Instance 1 Index 0 Type 1, label = Level
<0xb50ffb40>
2013-10-10 22:35:22.511 mgr, Manager::WriteConfig completed for driver with home ID of 0x014cfbba
05 10/10/13 22:35:22.511 ZWInterface::OnNotification() : Value Added Home 0x014cfbba Node 2 Genre 1 Class 38 Instance 1 Index 1 Type 8, label = Bright
<0xb50ffb40>
2013-10-10 22:35:22.512 mgr, Manager::WriteConfig completed for driver with home ID of 0x014cfbba
05 10/10/13 22:35:22.512 ZWInterface::OnNotification() : Value Added Home 0x014cfbba Node 2 Genre 1 Class 38 Instance 1 Index 2 Type 8, label = Dim
<0xb50ffb40>
2013-10-10 22:35:22.513 mgr, Manager::WriteConfig completed for driver with home ID of 0x014cfbba
05 10/10/13 22:35:22.513 ZWInterface::OnNotification() : Value Added Home 0x014cfbba Node 2 Genre 3 Class 38 Instance 1 Index 3 Type 0, label = Ignore Start Level
<0xb50ffb40>
2013-10-10 22:35:22.514 mgr, Manager::WriteConfig completed for driver with home ID of 0x014cfbba
05 10/10/13 22:35:22.514 ZWInterface::OnNotification() : Value Added Home 0x014cfbba Node 2 Genre 3 Class 38 Instance 1 Index 4 Type 1, label = Start Level
<0xb50ffb40>
2013-10-10 22:35:22.515 mgr, Manager::WriteConfig completed for driver with home ID of 0x014cfbba
2013-10-10 22:35:22.515 Node002, Sending (Query) message (Callback ID=0x04, Expected Reply=0x04) - ManufacturerSpecificCmd_Get (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x72, 0x04, 0x25, 0x04, 0xb2
2013-10-10 22:35:22.532 Node002, Request RTT 16 Average Request RTT 16
2013-10-10 22:35:22.541 Node002, Response RTT 25 Average Response RTT 25
2013-10-10 22:35:22.552 Node002, Opening config param file /etc/openzwave/config/aeon_labs/minimote.xml
2013-10-10 22:35:22.554 Missing default boolean value from xml configuration: node 2, class 0x70, instance 1, index 241
2013-10-10 22:35:22.554 Missing default boolean value from xml configuration: node 2, class 0x70, instance 1, index 242
2013-10-10 22:35:22.554 Missing default boolean value from xml configuration: node 2, class 0x70, instance 1, index 243
2013-10-10 22:35:22.554 Missing default boolean value from xml configuration: node 2, class 0x70, instance 1, index 244
2013-10-10 22:35:22.554 Node002, RemoveCommandClass - Removed support for COMMAND_CLASS_VERSION
2013-10-10 22:35:22.555 Node002, Received manufacturer specific report from node 2: Manufacturer=Aeon Labs, Product=Minimote
05 10/10/13 22:35:22.555 ZWInterface::OnNotification() : Value Added Home 0x014cfbba Node 2 Genre 2 Class 112 Instance 1 Index 241 Type 0, label = Button #1 Programmable
<0xb50ffb40>
2013-10-10 22:35:22.556 mgr, Manager::WriteConfig completed for driver with home ID of 0x014cfbba
05 10/10/13 22:35:22.556 ZWInterface::OnNotification() : Value Added Home 0x014cfbba Node 2 Genre 2 Class 112 Instance 1 Index 242 Type 0, label = Button #2 Programmable
<0xb50ffb40>
2013-10-10 22:35:22.557 mgr, Manager::WriteConfig completed for driver with home ID of 0x014cfbba
05 10/10/13 22:35:22.558 ZWInterface::OnNotification() : Value Added Home 0x014cfbba Node 2 Genre 2 Class 112 Instance 1 Index 243 Type 0, label = Button #3 Programmable
<0xb50ffb40>
2013-10-10 22:35:22.558 mgr, Manager::WriteConfig completed for driver with home ID of 0x014cfbba
05 10/10/13 22:35:22.559 ZWInterface::OnNotification() : Value Added Home 0x014cfbba Node 2 Genre 2 Class 112 Instance 1 Index 244 Type 0, label = Button #4 Programmable
<0xb50ffb40>
2013-10-10 22:35:22.559 mgr, Manager::WriteConfig completed for driver with home ID of 0x014cfbba
05 10/10/13 22:35:22.560 ZWInterface::OnNotification() : Value Removed Home 0x014cfbba Node 2 Genre 3 Class 134 Instance 1 Index 0 Type 7
<0xb50ffb40>
05 10/10/13 22:35:22.560 ZWInterface::OnNotification() : Value Removed Home 0x014cfbba Node 2 Genre 3 Class 134 Instance 1 Index 1 Type 7
<0xb50ffb40>
05 10/10/13 22:35:22.560 ZWInterface::OnNotification() : Value Removed Home 0x014cfbba Node 2 Genre 3 Class 134 Instance 1 Index 2 Type 7
<0xb50ffb40>
2013-10-10 22:35:22.560 Node002, Essential node queries are complete
2013-10-10 22:35:22.560 Node002, Sending (Query) message (Callback ID=0x05, Expected Reply=0x04) - Get Association Groupings (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x85, 0x05, 0x25, 0x05, 0x45
2013-10-10 22:35:22.577 Node002, Request RTT 17 Average Request RTT 16
2013-10-10 22:35:29.381 Node002, Received Wakeup Notification from node 2
2013-10-10 22:35:31.381 Node002, Received Wakeup Notification from node 2
2013-10-10 22:35:33.381 Node002, Received Wakeup Notification from node 2
2013-10-10 22:36:02.660 Node002, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2013-10-10 22:36:02.660 Node002, Node 2 has been marked as asleep
2013-10-10 22:36:02.660 Node002, Node not responding - moving message to Wake-Up queue: Get Association Groupings (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x85, 0x05, 0x25, 0x06, 0x46
2013-10-10 22:36:02.660 Node002, Node not responding - moving QueryStageComplete command to Wake-Up queue
2013-10-10 22:36:45.806 Node002, Node 2 has been marked as awake
2013-10-10 22:36:45.806 Node002, Sending (WakeUp) message (Attempt 3, Callback ID=0x07, Expected Reply=0x04) - Get Association Groupings (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x85, 0x05, 0x25, 0x07, 0x47
2013-10-10 22:36:45.891 Node002, Request RTT 84 Average Request RTT 50
05 10/10/13 22:37:08.285 void ClientSocket::Disconnect() on this socket: 0x9e9c430 (m_Socket: 6) <0xb6e83700>
05 10/10/13 22:37:08.286 Socket::ReceiveData 0x9ea1eb0 failed, bytes left 0 start: 230000 1: 0 1b: 0 2: 0 2b: 0 m_Socket: 4 Event Dev #59 <0xb6e83700>
05 10/10/13 22:37:08.286 Socket::ReceiveString2 ReceiveData failed m_Socket: -1 Event Dev #59 <0xb6e83700>
01 10/10/13 22:37:08.286 Lost connection device: 59 <0xb6e83700>
05 10/10/13 22:37:08.286 Handshake failed. Retrying in 5 seconds <0xb6e83700>
05 10/10/13 22:37:20.311 Note: Device manager has attached a device of type 1820 that this has no custom event handler for. It will not fire events. <0xb6e83700>
05 10/10/13 22:37:20.311 Note: Device manager has attached a device of type 1820 that this has no custom handler for. This is normal for IR. <0xb6e83700>
05 10/10/13 22:37:20.311 Creating child 90 <0xb6e83700>
05 10/10/13 22:37:20.311 Note: Device manager has attached a device of type 1922 that this has no custom event handler for. It will not fire events. <0xb6e83700>
05 10/10/13 22:37:20.311 Note: Device manager has attached a device of type 1922 that this has no custom handler for. This is normal for IR. <0xb6e83700>
05 10/10/13 22:37:20.311 Creating child 91 <0xb6e83700>
05 10/10/13 22:37:20.311 Note: Device manager has attached a device of type 1945 that this has no custom event handler for. It will not fire events. <0xb6e83700>
05 10/10/13 22:37:20.311 Note: Device manager has attached a device of type 1945 that this has no custom handler for. This is normal for IR. <0xb6e83700>
01 10/10/13 22:37:20.405 TranslateSerialUSB pci0000:00/0000:00:13.1+3 result /dev/ttyUSB0 <0xb6e83700>
05 10/10/13 22:37:20.405 -> DEVICE NOT FOUND! Adding new device! <0xb6e83700>
05 10/10/13 22:37:20.405 ZWave::GetDevice() No device found for id 2 <0xb6e83700>
05 10/10/13 22:37:20.405 -> NOT FOUND! Adding new device! <0xb6e83700>
05 10/10/13 22:37:20.405 -> ZWave_Controller <0xb6e83700>
05 10/10/13 22:37:20.405 Adding device for node: 2 to parent device 59 <0xb6e83700>
05 10/10/13 22:37:20.458 ZWave::GetDevice() No device found for id 2 <0xb6e83700>
05 10/10/13 22:37:20.458 -> Has been added since last reload - reload required! <0xb6e83700>
05 10/10/13 22:37:20.459 ZWave::GetDevice() No device found for id 2 <0xb6e83700>
05 10/10/13 22:37:20.459 -> Has been added since last reload - reload required! <0xb6e83700>
05 10/10/13 22:37:20.459 ZWave::GetDevice() No device found for id 2 <0xb6e83700>
05 10/10/13 22:37:20.459 -> Has been added since last reload - reload required! <0xb6e83700>
05 10/10/13 22:37:20.459 ZWave::GetDevice() No device found for id 2 <0xb6e83700>
05 10/10/13 22:37:20.459 -> Has been added since last reload - reload required! <0xb6e83700>
05 10/10/13 22:37:20.459 ZWave::GetDevice() No device found for id 2 <0xb6e83700>
05 10/10/13 22:37:20.459 -> Has been added since last reload - reload required! <0xb6e83700>
05 10/10/13 22:37:20.459 ZWave::GetDevice() No device found for id 2 <0xb6e83700>
05 10/10/13 22:37:20.459 -> Has been added since last reload - reload required! <0xb6e83700>
05 10/10/13 22:37:20.459 ZWave::GetDevice() No device found for id 2 <0xb6e83700>
05 10/10/13 22:37:20.459 -> Has been added since last reload - reload required! <0xb6e83700>
05 10/10/13 22:37:20.459 ZWave::GetDevice() No device found for id 2 <0xb6e83700>
05 10/10/13 22:37:20.459 -> Has been added since last reload - reload required! <0xb6e83700>
05 10/10/13 22:37:20.459 ZWave::GetDevice() No device found for id 2 <0xb6e83700>
05 10/10/13 22:37:20.459 -> Has been added since last reload - reload required! <0xb6e83700>
05 10/10/13 22:37:20.459 ZWave::GetDevice() No device found for id 2 <0xb6e83700>
05 10/10/13 22:37:20.459 -> Has been added since last reload - reload required! <0xb6e83700>
05 10/10/13 22:37:20.459 ZWave::GetDevice() No device found for id 2 <0xb6e83700>
05 10/10/13 22:37:20.459 -> Has been added since last reload - reload required! <0xb6e83700>
05 10/10/13 22:37:20.459 ZWave::GetDevice() No device found for id 2 <0xb6e83700>
05 10/10/13 22:37:20.459 -> Has been added since last reload - reload required! <0xb6e83700>
05 10/10/13 22:37:20.459 ZWave::GetDevice() No device found for id 2 <0xb6e83700>
05 10/10/13 22:37:20.459 -> Has been added since last reload - reload required! <0xb6e83700>
05 10/10/13 22:37:20.459 ZWave::GetDevice() No device found for id 2 <0xb6e83700>
05 10/10/13 22:37:20.459 -> Has been added since last reload - reload required! <0xb6e83700>
2013-10-10 22:37:25.807
2013-10-10 22:37:25.807 Dumping queued log messages
2013-10-10 22:37:25.807
2013-10-10 22:36:45.806 b50ffb40
2013-10-10 22:36:45.806 b50ffb40 Sending (WakeUp) message (Attempt 3, Callback ID=0x07, Expected Reply=0x04) - Get Association Groupings (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x85, 0x05, 0x25, 0x07, 0x47
2013-10-10 22:36:45.813 b50ffb40 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2013-10-10 22:36:45.813 b50ffb40 ZW_SEND_DATA delivered to Z-Wave stack
2013-10-10 22:36:45.891 b50ffb40 Received: 0x01, 0x05, 0x00, 0x13, 0x07, 0x00, 0xee
2013-10-10 22:36:45.891 b50ffb40 ZW_SEND_DATA Request with callback ID 0x07 received (expected 0x07)
2013-10-10 22:36:45.891 b50ffb40 Request RTT 84 Average Request RTT 50
2013-10-10 22:37:25.807 b50ffb40 ERROR: Dropping command, expected response not received after 3 attempt(s)
2013-10-10 22:37:25.807 b50ffb40
2013-10-10 22:37:25.807 b50ffb40 Dumping queued log messages
2013-10-10 22:37:25.807 b50ffb40
2013-10-10 22:37:25.808
2013-10-10 22:37:25.808 End of queued log message dump
2013-10-10 22:37:25.808
2013-10-10 22:37:25.808 Node002, Number of association groups reported for node 2 is 4.
2013-10-10 22:37:25.809 Node002, Get Associations for group 1 of node 2
2013-10-10 22:37:25.809 Node002, Sending (Send) message (Callback ID=0x08, Expected Reply=0x04) - Get Associations (Node=2): 0x01, 0x0a, 0x00, 0x13, 0x02, 0x03, 0x85, 0x02, 0x01, 0x25, 0x08, 0x4c
2013-10-10 22:37:25.971 Node002, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2013-10-10 22:37:25.971 Node002, Node 2 has been marked as asleep
2013-10-10 22:37:25.971 Node002, Node not responding - moving message to Wake-Up queue: Get Associations (Node=2): 0x01, 0x0a, 0x00, 0x13, 0x02, 0x03, 0x85, 0x02, 0x01, 0x25, 0x08, 0x4c
2013-10-10 22:37:25.971 Node002, Node not responding - moving QueryStageComplete command to Wake-Up queue
05 10/10/13 22:37:34.613 void ClientSocket::Disconnect() on this socket: 0x9e9c430 (m_Socket: 6) <0xb6e83700>
05 10/10/13 22:37:34.618 Socket::ReceiveData 0x9ea1eb0 failed, bytes left -1 start: 250000 1: 0 1b: 0 2: 0 2b: 0 m_Socket: 4 Event Dev #59 <0xb6e83700>
05 10/10/13 22:37:34.618 Socket::ReceiveString2 ReceiveData failed m_Socket: -1 Event Dev #59 <0xb6e83700>
01 10/10/13 22:37:34.618 Lost connection device: 59 <0xb6e83700>
05 10/10/13 22:37:34.618 Handshake failed. Retrying in 5 seconds <0xb6e83700>
05 10/10/13 22:37:46.642 Note: Device manager has attached a device of type 1820 that this has no custom event handler for. It will not fire events. <0xb6e83700>
05 10/10/13 22:37:46.642 Note: Device manager has attached a device of type 1820 that this has no custom handler for. This is normal for IR. <0xb6e83700>
05 10/10/13 22:37:46.642 Creating child 90 <0xb6e83700>
05 10/10/13 22:37:46.642 Note: Device manager has attached a device of type 1922 that this has no custom event handler for. It will not fire events. <0xb6e83700>
05 10/10/13 22:37:46.642 Note: Device manager has attached a device of type 1922 that this has no custom handler for. This is normal for IR. <0xb6e83700>
05 10/10/13 22:37:46.643 Creating child 91 <0xb6e83700>
05 10/10/13 22:37:46.643 Note: Device manager has attached a device of type 1945 that this has no custom event handler for. It will not fire events. <0xb6e83700>
05 10/10/13 22:37:46.643 Note: Device manager has attached a device of type 1945 that this has no custom handler for. This is normal for IR. <0xb6e83700>
05 10/10/13 22:37:46.643 Creating child 92 <0xb6e83700>
05 10/10/13 22:37:46.643 Note: Device manager has attached a device of type 1945 that this has no custom event handler for. It will not fire events. <0xb6e83700>
05 10/10/13 22:37:46.643 Note: Device manager has attached a device of type 1945 that this has no custom handler for. This is normal for IR. <0xb6e83700>
01 10/10/13 22:37:46.708 TranslateSerialUSB pci0000:00/0000:00:13.1+3 result /dev/ttyUSB0 <0xb6e83700>