2018-08-04 15:40:12.742 Always, OpenZwave Version 1.5.0 Starting Up 2018-08-04 15:40:12.742 Info, Setting Up Provided Network Key for Secure Communications 2018-08-04 15:40:12.748 Info, Manufacturer_Specific.xml file Revision is 12 2018-08-04 15:40:12.750 Info, Error in Product Config file at line 3 - missing Revision attribute 2018-08-04 15:40:12.784 Info, Error in Product Config file at line 7 - missing Revision attribute 2018-08-04 15:40:12.784 Info, Error in Product Config file at line 7 - missing Revision attribute 2018-08-04 15:40:12.784 Info, Error in Product Config file at line 7 - missing Revision attribute 2018-08-04 15:40:12.785 Info, Error in Product Config file at line 5 - missing Revision attribute 2018-08-04 15:40:12.785 Info, Error in Product Config file at line 5 - missing Revision attribute 2018-08-04 15:40:12.785 Info, Error in Product Config file at line 6 - missing Revision attribute 2018-08-04 15:40:12.785 Info, Error in Product Config file at line 6 - missing Revision attribute 2018-08-04 15:40:12.785 Info, Error in Product Config file at line 5 - missing Revision attribute 2018-08-04 15:40:12.785 Info, Error in Product Config file at line 5 - missing Revision attribute 2018-08-04 15:40:12.785 Info, Error in Product Config file at line 5 - missing Revision attribute 2018-08-04 15:40:12.785 Info, Error in Product Config file at line 5 - missing Revision attribute 2018-08-04 15:40:12.786 Info, Error in Product Config file at line 5 - missing Revision attribute 2018-08-04 15:40:12.786 Info, Error in Product Config file at line 5 - missing Revision attribute 2018-08-04 15:40:12.789 Info, Error in Product Config file at line 2 - missing Revision attribute 2018-08-04 15:40:12.789 Info, Error in Product Config file at line 2 - missing Revision attribute 2018-08-04 15:40:12.790 Info, Error in Product Config file at line 2 - missing Revision attribute 2018-08-04 15:40:12.797 Info, Error in Product Config file at line 6 - missing Revision attribute 2018-08-04 15:40:12.842 Info, Error in Product Config file at line 7 - missing Revision attribute 2018-08-04 15:40:12.844 Info, Error in Product Config file at line 2 - missing Revision attribute 2018-08-04 15:40:12.844 Info, Error in Product Config file at line 2 - missing Revision attribute 2018-08-04 15:40:12.845 Info, Error in Product Config file at line 2 - missing Revision attribute 2018-08-04 15:40:12.846 Info, Error in Product Config file at line 2 - missing Revision attribute 2018-08-04 15:40:12.847 Info, Error in Product Config file at line 2 - missing Revision attribute 2018-08-04 15:40:12.848 Info, Error in Product Config file at line 2 - missing Revision attribute 2018-08-04 15:40:12.848 Info, Error in Product Config file at line 2 - missing Revision attribute 2018-08-04 15:40:12.848 Info, Error in Product Config file at line 8 - missing Revision attribute 2018-08-04 15:40:12.848 Info, Error in Product Config file at line 3 - missing Revision attribute 2018-08-04 15:40:12.849 Info, Error in Product Config file at line 2 - missing Revision attribute 2018-08-04 15:40:12.849 Info, Error in Product Config file at line 2 - missing Revision attribute 2018-08-04 15:40:12.849 Info, Error in Product Config file at line 2 - missing Revision attribute 2018-08-04 15:40:12.850 Info, Error in Product Config file at line 3 - missing Revision attribute 2018-08-04 15:40:12.850 Info, Error in Product Config file at line 3 - missing Revision attribute 2018-08-04 15:40:12.850 Info, Error in Product Config file at line 3 - missing Revision attribute 2018-08-04 15:40:12.850 Info, Error in Product Config file at line 3 - missing Revision attribute 2018-08-04 15:40:12.851 Info, Product name collision: VRF01-1LZ Multilevel Scene Switch - 1.5A Fan type 1001 id 209 manufacturerid 1d, collides with VRF01-1LZ Quiet Fan Speed Control, type 1001 id 209 manufacturerid 1d 2018-08-04 15:40:12.851 Info, Product name collision: VRCS2-MRZ 2-Button Scene Controller with Switches type 1102 id 243 manufacturerid 1d, collides with VRCS2-MRZ 2-Button Scene Controller with Switches, type 1102 id 243 manufacturerid 1d 2018-08-04 15:40:12.851 Info, Product name collision: DZPD3-1LW Plug-In Dimming Lamp Module type 1902 id 334 manufacturerid 1d, collides with DZPD3-1LW Plug-In Lamp Module, type 1902 id 334 manufacturerid 1d 2018-08-04 15:40:12.853 Info, Error in Product Config file at line 6 - missing Revision attribute 2018-08-04 15:40:12.861 Info, Error in Product Config file at line 7 - missing Revision attribute 2018-08-04 15:40:12.862 Info, Error in Product Config file at line 7 - missing Revision attribute 2018-08-04 15:40:12.863 Info, Error in Product Config file at line 7 - missing Revision attribute 2018-08-04 15:40:12.863 Info, Error in Product Config file at line 6 - missing Revision attribute 2018-08-04 15:40:12.864 Info, Error in Product Config file at line 6 - missing Revision attribute 2018-08-04 15:40:12.864 Info, Error in Product Config file at line 6 - missing Revision attribute 2018-08-04 15:40:12.884 Info, Error in Product Config file at line 2 - missing Revision attribute 2018-08-04 15:40:12.884 Info, Error in Product Config file at line 2 - missing Revision attribute 2018-08-04 15:40:12.885 Info, Error in Product Config file at line 2 - missing Revision attribute 2018-08-04 15:40:12.885 Info, Error in Product Config file at line 1 - missing Revision attribute 2018-08-04 15:40:12.886 Info, Error in Product Config file at line 3 - missing Revision attribute 2018-08-04 15:40:12.895 Info, Error in Product Config file at line 3 - missing Revision attribute 2018-08-04 15:40:12.901 Info, Error in Product Config file at line 6 - missing Revision attribute 2018-08-04 15:40:12.903 Info, Error in Product Config file at line 2 - missing Revision attribute 2018-08-04 15:40:12.904 Info, Error in Product Config file at line 2 - missing Revision attribute 2018-08-04 15:40:12.904 Info, Queuing Lookup on mfs.db.openzwave.com for Node 0 2018-08-04 15:40:12.904 Info, Opening controller /dev/ttyACM0 2018-08-04 15:40:12.904 Info, Trying to open serial port /dev/ttyACM0 (attempt 1) 2018-08-04 15:40:12.904 Info, mgr, Added driver for controller /dev/ttyACM0 2018-08-04 15:40:12.905 Info, Starting DNSThread 2018-08-04 15:40:12.905 Info, LookupTxT Checking mfs.db.openzwave.com 2018-08-04 15:40:12.905 Info, Lookup for mfs.db.openzwave.com returned 12 2018-08-04 15:40:12.907 Info, Serial port /dev/ttyACM0 opened (attempt 1) 2018-08-04 15:40:12.907 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9 2018-08-04 15:40:12.907 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc 2018-08-04 15:40:12.907 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9 2018-08-04 15:40:12.907 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb 2018-08-04 15:40:12.907 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa 2018-08-04 15:40:12.910 Info, ManufacturerSpecificDB Initialized 2018-08-04 15:40:12.910 Detail, Notification: ManufacturerSpecificDB Ready 2018-08-04 15:40:12.910 Detail, 2018-08-04 15:40:12.910 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9 2018-08-04 15:40:12.914 Detail, contrlr, Received: 0x01, 0x10, 0x01, 0x15, 0x5a, 0x2d, 0x57, 0x61, 0x76, 0x65, 0x20, 0x34, 0x2e, 0x30, 0x35, 0x00, 0x01, 0x97 2018-08-04 15:40:12.914 Detail, 2018-08-04 15:40:12.914 Info, contrlr, Received reply to FUNC_ID_ZW_GET_VERSION: 2018-08-04 15:40:12.914 Info, contrlr, Static Controller library, version Z-Wave 4.05 2018-08-04 15:40:12.914 Detail, Node045, Expected reply was received 2018-08-04 15:40:12.914 Detail, Node045, Message transaction complete 2018-08-04 15:40:12.914 Detail, 2018-08-04 15:40:12.914 Detail, contrlr, Removing current message 2018-08-04 15:40:12.914 Detail, 2018-08-04 15:40:12.914 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc 2018-08-04 15:40:12.917 Detail, contrlr, Received: 0x01, 0x08, 0x01, 0x20, 0xfa, 0x30, 0x26, 0x08, 0x01, 0x33 2018-08-04 15:40:12.917 Detail, 2018-08-04 15:40:12.917 Info, contrlr, Received reply to FUNC_ID_ZW_MEMORY_GET_ID. Home ID = 0xfa302608. Our node ID = 1 2018-08-04 15:40:12.917 Detail, Node048, Expected reply was received 2018-08-04 15:40:12.917 Detail, Node048, Message transaction complete 2018-08-04 15:40:12.917 Detail, 2018-08-04 15:40:12.918 Detail, contrlr, Removing current message 2018-08-04 15:40:12.918 Detail, 2018-08-04 15:40:12.918 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9 2018-08-04 15:40:12.922 Detail, contrlr, Received: 0x01, 0x04, 0x01, 0x05, 0x28, 0xd7 2018-08-04 15:40:12.922 Detail, 2018-08-04 15:40:12.922 Info, contrlr, Received reply to FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 2018-08-04 15:40:12.922 Info, contrlr, There is no SUC ID Server (SIS) in this network. 2018-08-04 15:40:12.922 Info, contrlr, The PC controller is a primary controller. 2018-08-04 15:40:12.922 Detail, Node215, Expected reply was received 2018-08-04 15:40:12.922 Detail, Node215, Message transaction complete 2018-08-04 15:40:12.922 Detail, 2018-08-04 15:40:12.922 Detail, contrlr, Removing current message 2018-08-04 15:40:12.922 Detail, 2018-08-04 15:40:12.922 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb 2018-08-04 15:40:12.927 Detail, contrlr, Received: 0x01, 0x2b, 0x01, 0x07, 0x05, 0x05, 0x01, 0x15, 0x04, 0x00, 0x00, 0x01, 0xfe, 0x83, 0xff, 0x88, 0xcf, 0x1f, 0x00, 0x00, 0xfb, 0x9f, 0x7d, 0xa0, 0x67, 0x00, 0x80, 0x80, 0x00, 0x80, 0x86, 0x00, 0x00, 0x00, 0xe8, 0x73, 0x00, 0x00, 0x0e, 0x00, 0x00, 0x40, 0x1a, 0x00, 0x0e 2018-08-04 15:40:12.927 Detail, 2018-08-04 15:40:12.927 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_GET_CAPABILITIES 2018-08-04 15:40:12.927 Info, contrlr, Serial API Version: 5.5 2018-08-04 15:40:12.927 Info, contrlr, Manufacturer ID: 0x0115 2018-08-04 15:40:12.927 Info, contrlr, Product Type: 0x0400 2018-08-04 15:40:12.927 Info, contrlr, Product ID: 0x0001 2018-08-04 15:40:12.927 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7 2018-08-04 15:40:12.927 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe 2018-08-04 15:40:12.927 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97 2018-08-04 15:40:12.927 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x08, 0x00, 0x03, 0x01, 0x02, 0x01, 0x01, 0x5e, 0xa9 2018-08-04 15:40:12.927 Detail, Node005, Expected reply was received 2018-08-04 15:40:12.927 Detail, Node005, Message transaction complete 2018-08-04 15:40:12.927 Detail, 2018-08-04 15:40:12.927 Detail, contrlr, Removing current message 2018-08-04 15:40:12.927 Detail, 2018-08-04 15:40:12.927 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x56) - FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa 2018-08-04 15:40:12.931 Detail, contrlr, Received: 0x01, 0x04, 0x01, 0x56, 0x00, 0xac 2018-08-04 15:40:12.931 Detail, 2018-08-04 15:40:12.931 Info, contrlr, Received reply to GET_SUC_NODE_ID. Node ID = 0 2018-08-04 15:40:12.931 Info, Controller Does not Support SUC - Cannot Setup Controller as SUC Node 2018-08-04 15:40:12.931 Detail, Node172, Expected reply was received 2018-08-04 15:40:12.931 Detail, Node172, Message transaction complete 2018-08-04 15:40:12.931 Detail, 2018-08-04 15:40:12.931 Detail, contrlr, Removing current message 2018-08-04 15:40:12.931 Detail, 2018-08-04 15:40:12.931 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x1c) - FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7 2018-08-04 15:40:12.990 Detail, contrlr, Received: 0x01, 0x25, 0x01, 0x1c, 0x01, 0x20, 0x67, 0xfa, 0x06, 0x37, 0x20, 0x53, 0x9f, 0x80, 0x19, 0x0f, 0x7e, 0x95, 0x0b, 0xe0, 0x31, 0x72, 0x15, 0x28, 0xc4, 0x5d, 0xfa, 0xb3, 0x6f, 0x3d, 0x5a, 0x28, 0x54, 0x4a, 0x51, 0xce, 0xbf, 0x37, 0xb7 2018-08-04 15:40:12.990 Detail, 2018-08-04 15:40:12.990 Info, contrlr, Received reply to FUNC_ID_ZW_GET_RANDOM: true 2018-08-04 15:40:12.990 Detail, Node032, Expected reply was received 2018-08-04 15:40:12.990 Detail, Node032, Message transaction complete 2018-08-04 15:40:12.990 Detail, 2018-08-04 15:40:12.990 Detail, contrlr, Removing current message 2018-08-04 15:40:12.991 Detail, 2018-08-04 15:40:12.991 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x02) - FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe 2018-08-04 15:40:13.090 Detail, contrlr, Received: 0x01, 0x25, 0x01, 0x02, 0x05, 0x00, 0x1d, 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, 0x05, 0x00, 0xc5 2018-08-04 15:40:13.090 Detail, 2018-08-04 15:40:13.090 Info, mgr, Driver with Home ID of 0xfa302608 is now ready. 2018-08-04 15:40:13.090 Info, 2018-08-04 15:40:13.093 Info, Loading Localization File /usr/local/etc/openzwave/Localization.xml 2018-08-04 15:40:13.094 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_GET_INIT_DATA: 2018-08-04 15:40:13.094 Info, contrlr, Node 001 - Known 2018-08-04 15:40:13.094 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=1 2018-08-04 15:40:13.094 Detail, Node001, QueryStage_CacheLoad 2018-08-04 15:40:13.094 Info, Node001, Loading Cache for node 1: Manufacturer=Z-Wave.Me, Product=ZME_UZB1 USB Stick 2018-08-04 15:40:13.094 Info, Node001, Node Identity Codes: 0115:0400:0001 2018-08-04 15:40:13.094 Detail, Node001, QueryStage_Associations 2018-08-04 15:40:13.094 Detail, Node001, QueryStage_Neighbors 2018-08-04 15:40:13.094 Detail, contrlr, Requesting routing info (neighbor list) for Node 1 2018-08-04 15:40:13.094 Detail, Node001, Queuing (Command) Get Routing Info (Node=1): 0x01, 0x07, 0x00, 0x80, 0x01, 0x00, 0x00, 0x03, 0x7a 2018-08-04 15:40:13.094 Detail, Node001, Queuing (Query) Query Stage Complete (Neighbors) 2018-08-04 15:40:13.094 Detail, Expected reply was received 2018-08-04 15:40:13.094 Detail, Message transaction complete 2018-08-04 15:40:13.094 Detail, 2018-08-04 15:40:13.094 Detail, contrlr, Removing current message 2018-08-04 15:40:13.094 Detail, Node001, Notification: DriverReady 2018-08-04 15:40:13.094 Detail, Node001, Notification: NodeAdded 2018-08-04 15:40:13.094 Detail, Node001, Notification: NodeProtocolInfo 2018-08-04 15:40:13.097 Detail, Node001, Notification: EssentialNodeQueriesComplete 2018-08-04 15:40:13.097 Detail, Node001, Notification: ValueAdded 2018-08-04 15:40:13.097 Detail, Node001, Notification: ValueAdded 2018-08-04 15:40:13.097 Detail, Node001, Notification: ValueAdded 2018-08-04 15:40:13.097 Detail, Node001, Notification: ValueAdded 2018-08-04 15:40:13.097 Detail, Node001, Notification: ValueAdded 2018-08-04 15:40:13.097 Detail, Node001, Notification: ValueAdded 2018-08-04 15:40:13.097 Detail, Node001, Notification: NodeNaming 2018-08-04 15:40:13.097 Detail, 2018-08-04 15:40:13.097 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x06) - FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97 2018-08-04 15:40:13.100 Detail, contrlr, Received: 0x01, 0x05, 0x01, 0x06, 0x64, 0x0f, 0x96 2018-08-04 15:40:13.100 Detail, 2018-08-04 15:40:13.100 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_SET_TIMEOUTS 2018-08-04 15:40:13.100 Detail, Node015, Expected reply was received 2018-08-04 15:40:13.100 Detail, Node015, Message transaction complete 2018-08-04 15:40:13.100 Detail, 2018-08-04 15:40:13.100 Detail, contrlr, Removing current message 2018-08-04 15:40:13.100 Detail, 2018-08-04 15:40:13.100 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x00) - FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x08, 0x00, 0x03, 0x01, 0x02, 0x01, 0x01, 0x5e, 0xa9 2018-08-04 15:40:13.102 Detail, contrlr, Removing current message 2018-08-04 15:40:13.102 Detail, 2018-08-04 15:40:13.102 Info, Node001, Sending (Command) message (Callback ID=0x00, Expected Reply=0x80) - Get Routing Info (Node=1): 0x01, 0x07, 0x00, 0x80, 0x01, 0x00, 0x00, 0x03, 0x7a 2018-08-04 15:40:13.107 Detail, Node001, Received: 0x01, 0x20, 0x01, 0x80, 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, 0x00, 0x5e 2018-08-04 15:40:13.108 Detail, 2018-08-04 15:40:13.108 Info, Node001, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO 2018-08-04 15:40:13.108 Info, Node001, Neighbors of this node are: 2018-08-04 15:40:13.108 Info, Node001, (none reported) 2018-08-04 15:40:13.108 Detail, Expected reply was received 2018-08-04 15:40:13.108 Detail, Message transaction complete 2018-08-04 15:40:13.108 Detail, 2018-08-04 15:40:13.108 Detail, Node001, Removing current message 2018-08-04 15:40:13.108 Detail, Node001, Query Stage Complete (Neighbors) 2018-08-04 15:40:13.108 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1 2018-08-04 15:40:13.108 Detail, Node001, QueryStage_Session 2018-08-04 15:40:13.108 Detail, Node001, QueryStage_Dynamic 2018-08-04 15:40:13.108 Detail, Node001, QueryStage_Configuration 2018-08-04 15:40:13.108 Detail, Node001, QueryStage_Complete 2018-08-04 15:40:13.108 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0 2018-08-04 15:40:13.108 Warning, CheckCompletedNodeQueries all=1, deadFound=0 sleepingOnly=1 2018-08-04 15:40:13.108 Info, Node query processing complete. 2018-08-04 15:40:13.108 Detail, Node001, Notification: NodeQueriesComplete 2018-08-04 15:40:13.108 Detail, contrlr, Notification: AllNodesQueried