NetBeans: JPDA Listening Start... JPDA Address: dixie:36272 Port:36272 cd /home/trycoon/NetBeansProjects/JavaApplication1/ZWave_test; JAVA_HOME=/usr/lib/jvm/java-8-oracle /home/trycoon/netbeans-8.1/java/maven/bin/mvn "-Dexec.args=-Xdebug -Xrunjdwp:transport=dt_socket,server=n,address=36272 -Djava.library.path=/usr/lib/jni -Dgnu.io.rxtx.SerialPorts=/dev/ttyACM0 -classpath %classpath com.test.WZWaveTest" -Dexec.executable=/usr/lib/jvm/java-8-oracle/bin/java -Djpda.listen=true -Djpda.address=36272 org.codehaus.mojo:exec-maven-plugin:1.2.1:exec Running NetBeans Compile On Save execution. Phase execution is skipped and output directories of dependency projects (with Compile on Save turned on) will be used instead of their jar artifacts. Scanning for projects... ------------------------------------------------------------------------ Building ZWave_test 1.0-SNAPSHOT ------------------------------------------------------------------------ --- exec-maven-plugin:1.2.1:exec (default-cli) @ ZWave_test --- 2016-08-17 23:27:14,542 [main] DEBUG io.netty.util.internal.logging.InternalLoggerFactory - Using SLF4J as the default logging framework 2016-08-17 23:27:14,553 [main] DEBUG io.netty.util.internal.PlatformDependent0 - java.nio.Buffer.address: available 2016-08-17 23:27:14,554 [main] DEBUG io.netty.util.internal.PlatformDependent0 - sun.misc.Unsafe.theUnsafe: available 2016-08-17 23:27:14,554 [main] DEBUG io.netty.util.internal.PlatformDependent0 - sun.misc.Unsafe.copyMemory: available 2016-08-17 23:27:14,555 [main] DEBUG io.netty.util.internal.PlatformDependent0 - java.nio.Bits.unaligned: true 2016-08-17 23:27:14,630 [main] DEBUG io.netty.util.internal.PlatformDependent - UID: 1000 2016-08-17 23:27:14,631 [main] DEBUG io.netty.util.internal.PlatformDependent - Java version: 8 2016-08-17 23:27:14,631 [main] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.noUnsafe: false 2016-08-17 23:27:14,631 [main] DEBUG io.netty.util.internal.PlatformDependent - sun.misc.Unsafe: available 2016-08-17 23:27:14,631 [main] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.noJavassist: false 2016-08-17 23:27:14,633 [main] DEBUG io.netty.util.internal.PlatformDependent - Javassist: unavailable 2016-08-17 23:27:14,633 [main] DEBUG io.netty.util.internal.PlatformDependent - You don't have Javassist in your class path or you don't have enough permission to load dynamically generated classes. Please check the configuration for better performance. 2016-08-17 23:27:14,633 [main] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.tmpdir: /tmp (java.io.tmpdir) 2016-08-17 23:27:14,633 [main] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.bitMode: 64 (sun.arch.data.model) 2016-08-17 23:27:14,634 [main] DEBUG io.netty.util.internal.PlatformDependent - -Dio.netty.noPreferDirect: false =================== Start =================== 2016-08-17 23:27:14,671 [main] DEBUG io.netty.util.internal.ThreadLocalRandom - -Dio.netty.initialSeedUniquifier: 0x37245f01934c4309 2016-08-17 23:27:14,682 [main] DEBUG io.netty.channel.ChannelOutboundBuffer - -Dio.netty.threadLocalDirectBufferSize: 65536 2016-08-17 23:27:14,684 [main] DEBUG io.netty.util.Recycler - -Dio.netty.recycler.maxCapacity.default: 262144 2016-08-17 23:27:14,703 [main] DEBUG io.netty.buffer.ByteBufUtil - -Dio.netty.allocator.type: unpooled Stable Library ========================================= Native lib Version = RXTX-2.2pre2 Java lib Version = RXTX-2.1-7 WARNING: RXTX Version mismatch Jar version = RXTX-2.1-7 native lib Version = RXTX-2.2pre2 RXTX Warning: Removing stale lock file. /var/lock/LCK..ttyACM0 2016-08-17 23:27:14,777 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - write: ZW_VERSION 2016-08-17 23:27:14,778 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - No transaction detected, sending data frame: ZW_VERSION 2016-08-17 23:27:14,784 [pool-2-thread-1] DEBUG io.netty.util.ResourceLeakDetector - -Dio.netty.leakDetectionLevel: simple 2016-08-17 23:27:14,787 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameEncoder - SENT ZW_VERSION: 0x01 0x03 0x00 0x15 0xE9 2016-08-17 23:27:14,790 [pool-2-thread-1] DEBUG io.netty.util.internal.Cleaner0 - java.nio.ByteBuffer.cleaner(): available 2016-08-17 23:27:14,790 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - Detected data frame write: ZW_VERSION 2016-08-17 23:27:14,793 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - *** Data frame transaction started for ZW_VERSION 2016-08-17 23:27:14,794 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - write: ZW_MEMORY_GET_ID 2016-08-17 23:27:14,795 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - Queueing data frame: ZW_MEMORY_GET_ID; current queue size: 0 2016-08-17 23:27:14,796 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - write: ZW_INIT_DATA 2016-08-17 23:27:14,796 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - Queueing data frame: ZW_INIT_DATA; current queue size: 1 2016-08-17 23:27:14,799 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - RCVD: 0x06 0x01 0x10 0x01 0x15 0x5A 0x2D 0x57 0x61 0x76 0x65 0x20 0x33 0x2E 0x39 0x39 0x00 0x01 0x95 2016-08-17 23:27:14,802 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - Done processing received data: [ACK, ZW_VERSION[Z-Wave 3.99]] 2016-08-17 23:27:14,803 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - Received frame within transaction context: ACK 2016-08-17 23:27:14,803 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.frame.transaction.RequestResponseTransaction - Received ACK as expected 2016-08-17 23:27:14,803 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveChannelInboundHandler - Sending ACK for received data frame: ZW_VERSION[Z-Wave 3.99] 2016-08-17 23:27:14,803 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - write: ACK 2016-08-17 23:27:14,803 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameEncoder - SENT ACK: 0x06 2016-08-17 23:27:14,803 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - Received frame within transaction context: ZW_VERSION[Z-Wave 3.99] 2016-08-17 23:27:14,803 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.frame.transaction.RequestResponseTransaction - Received expected message response 2016-08-17 23:27:14,803 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - *** Data frame transaction completed with final frame: ZW_VERSION[Z-Wave 3.99] 2016-08-17 23:27:14,803 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - 2016-08-17 23:27:14,804 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - Detected data frame transaction completion 2016-08-17 23:27:14,804 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - Sending next queued data frame: ZW_MEMORY_GET_ID 2016-08-17 23:27:14,804 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameEncoder - SENT ZW_MEMORY_GET_ID: 0x01 0x03 0x00 0x20 0xDC 2016-08-17 23:27:14,804 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - Detected data frame write: ZW_MEMORY_GET_ID 2016-08-17 23:27:14,804 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - *** Data frame transaction started for ZW_MEMORY_GET_ID 2016-08-17 23:27:14,806 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - RCVD: 0x06 2016-08-17 23:27:14,807 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - Done processing received data: [ACK] 2016-08-17 23:27:14,807 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - Received frame within transaction context: ACK 2016-08-17 23:27:14,807 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.frame.transaction.RequestResponseTransaction - Received ACK as expected 2016-08-17 23:27:14,808 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - RCVD: 0x01 0x08 0x01 0x20 0xF9 0x67 0xAE 0x64 0x01 0x83 2016-08-17 23:27:14,809 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - Done processing received data: [ZW_MEMORY_GET_ID[-20892,1]] 2016-08-17 23:27:14,809 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveChannelInboundHandler - Sending ACK for received data frame: ZW_MEMORY_GET_ID[-20892,1] 2016-08-17 23:27:14,810 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - write: ACK 2016-08-17 23:27:14,810 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameEncoder - SENT ACK: 0x06 2016-08-17 23:27:14,810 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - Received frame within transaction context: ZW_MEMORY_GET_ID[-20892,1] 2016-08-17 23:27:14,810 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.frame.transaction.RequestResponseTransaction - Received expected message response 2016-08-17 23:27:14,810 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - *** Data frame transaction completed with final frame: ZW_MEMORY_GET_ID[-20892,1] 2016-08-17 23:27:14,810 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - ControllerInfo received: libraryVersion=Z-Wave 3.99, homeId=-20892, nodeId=1 2016-08-17 23:27:14,811 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - Detected data frame transaction completion 2016-08-17 23:27:14,811 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - Sending next queued data frame: ZW_INIT_DATA 2016-08-17 23:27:14,811 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameEncoder - SENT ZW_INIT_DATA: 0x01 0x03 0x00 0x02 0xFE 2016-08-17 23:27:14,812 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - Detected data frame write: ZW_INIT_DATA 2016-08-17 23:27:14,812 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - *** Data frame transaction started for ZW_INIT_DATA 2016-08-17 23:27:14,813 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - RCVD: 0x06 2016-08-17 23:27:14,815 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - Done processing received data: [ACK] 2016-08-17 23:27:14,817 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - Received frame within transaction context: ACK 2016-08-17 23:27:14,817 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.frame.transaction.RequestResponseTransaction - Received ACK as expected 2016-08-17 23:27:14,904 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - RCVD: 0x01 0x25 0x01 0x02 0x05 0x00 0x1D 0x01 0x00 0x00 0x00 0x00 0x00 0x00 2016-08-17 23:27:14,905 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - Done processing received data: [] 2016-08-17 23:27:14,905 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - RCVD: 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 2016-08-17 23:27:14,909 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - Done processing received data: [] 2016-08-17 23:27:14,909 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - RCVD: 0x00 0x00 0x00 0x00 0x00 0x00 0x05 0x00 0xC5 2016-08-17 23:27:14,910 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - Done processing received data: [ZW_INIT_DATA: nodes 0x01 ] 2016-08-17 23:27:14,910 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveChannelInboundHandler - Sending ACK for received data frame: ZW_INIT_DATA: nodes 0x01 2016-08-17 23:27:14,910 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - write: ACK 2016-08-17 23:27:14,910 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameEncoder - SENT ACK: 0x06 2016-08-17 23:27:14,911 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - Received frame within transaction context: ZW_INIT_DATA: nodes 0x01 2016-08-17 23:27:14,911 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.frame.transaction.RequestResponseTransaction - Received expected message response 2016-08-17 23:27:14,911 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - *** Data frame transaction completed with final frame: ZW_INIT_DATA: nodes 0x01 2016-08-17 23:27:14,911 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - 2016-08-17 23:27:14,911 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveChannelInboundHandler - Node 1 found; requesting protocol info 2016-08-17 23:27:14,912 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - write: ZW_GetNodeProtocolInfo[0x01] 2016-08-17 23:27:14,912 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - Queueing data frame: ZW_GetNodeProtocolInfo[0x01]; current queue size: 0 2016-08-17 23:27:14,912 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - Detected data frame transaction completion 2016-08-17 23:27:14,912 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - Sending next queued data frame: ZW_GetNodeProtocolInfo[0x01] 2016-08-17 23:27:14,912 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameEncoder - SENT ZW_GetNodeProtocolInfo[0x01]: 0x01 0x04 0x00 0x41 0x01 0xBB 2016-08-17 23:27:14,912 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - Detected data frame write: ZW_GetNodeProtocolInfo[0x01] 2016-08-17 23:27:14,913 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - *** Data frame transaction started for ZW_GetNodeProtocolInfo[0x01] 2016-08-17 23:27:14,916 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - RCVD: 0x06 2016-08-17 23:27:14,917 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - Done processing received data: [ACK] 2016-08-17 23:27:14,917 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - Received frame within transaction context: ACK 2016-08-17 23:27:14,917 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.frame.transaction.RequestResponseTransaction - Received ACK as expected 2016-08-17 23:27:14,917 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - RCVD: 0x01 0x09 0x01 0x41 0x93 0x16 0x01 0x02 0x02 0x01 0x33 2016-08-17 23:27:14,917 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - Done processing received data: [ZW_GetNodeProtocolInfo[0x00](Basic=0x02,Generic=0x02,Specific=0x01)] 2016-08-17 23:27:14,918 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveChannelInboundHandler - Sending ACK for received data frame: ZW_GetNodeProtocolInfo[0x00](Basic=0x02,Generic=0x02,Specific=0x01) 2016-08-17 23:27:14,918 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - write: ACK 2016-08-17 23:27:14,918 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameEncoder - SENT ACK: 0x06 2016-08-17 23:27:14,918 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - Received frame within transaction context: ZW_GetNodeProtocolInfo[0x00](Basic=0x02,Generic=0x02,Specific=0x01) 2016-08-17 23:27:14,918 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.frame.transaction.RequestResponseTransaction - Received expected message response 2016-08-17 23:27:14,918 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - *** Data frame transaction completed with final frame: ZW_GetNodeProtocolInfo[0x00](Basic=0x02,Generic=0x02,Specific=0x01) 2016-08-17 23:27:14,918 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - 2016-08-17 23:27:14,918 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.controller.netty.NettyZWaveController - Received protocol info for node 1 2016-08-17 23:27:14,923 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.node.specific.PCController - Node 1 changing to state: NodeInfo 2016-08-17 23:27:14,924 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.node.specific.PCController - Node 1 changing to state: Started Z-Wave node added: 1 2016-08-17 23:27:14,925 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.controller.netty.NettyZWaveController - Created node [1]: com.whizzosoftware.wzwave.node.specific.PCController@5e9e1db8 2016-08-17 23:27:14,925 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - Detected data frame transaction completion 2016-08-17 23:27:14,925 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - No pending data frames to send =================== Enter node adding state =================== 2016-08-17 23:27:19,933 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - write: com.whizzosoftware.wzwave.frame.AddNodeToNetwork@35013197 2016-08-17 23:27:19,933 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - No transaction detected, sending data frame: com.whizzosoftware.wzwave.frame.AddNodeToNetwork@35013197 2016-08-17 23:27:19,933 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameEncoder - SENT com.whizzosoftware.wzwave.frame.AddNodeToNetwork@35013197: 0x01 0x05 0x00 0x4A 0x01 0x01 0xB0 2016-08-17 23:27:19,934 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - Detected data frame write: com.whizzosoftware.wzwave.frame.AddNodeToNetwork@35013197 2016-08-17 23:27:19,937 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - *** Data frame transaction started for com.whizzosoftware.wzwave.frame.AddNodeToNetwork@35013197 2016-08-17 23:27:19,937 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - RCVD: 0x06 0x01 0x07 0x00 0x4A 0x01 0x01 0x00 0x00 0xB2 2016-08-17 23:27:19,937 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - Done processing received data: [ACK, com.whizzosoftware.wzwave.frame.AddNodeToNetwork@512cf7c2] 2016-08-17 23:27:19,938 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - Received frame within transaction context: ACK 2016-08-17 23:27:19,938 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.frame.transaction.RequestRequestTransaction - Received ACK as expected 2016-08-17 23:27:19,938 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveChannelInboundHandler - Sending ACK for received data frame: com.whizzosoftware.wzwave.frame.AddNodeToNetwork@512cf7c2 2016-08-17 23:27:19,938 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - write: ACK 2016-08-17 23:27:19,938 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameEncoder - SENT ACK: 0x06 2016-08-17 23:27:19,939 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - Received frame within transaction context: com.whizzosoftware.wzwave.frame.AddNodeToNetwork@512cf7c2 2016-08-17 23:27:19,939 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.frame.transaction.RequestRequestTransaction - Received expected message response 2016-08-17 23:27:19,939 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - *** Data frame transaction completed with final frame: com.whizzosoftware.wzwave.frame.AddNodeToNetwork@512cf7c2 2016-08-17 23:27:19,939 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - =================== onZWaveInclusionStarted =================== 2016-08-17 23:27:19,940 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - Detected data frame transaction completion 2016-08-17 23:27:19,941 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - No pending data frames to send =================== Exit node adding state =================== 2016-08-17 23:27:49,981 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - write: com.whizzosoftware.wzwave.frame.AddNodeToNetwork@21ddefdc 2016-08-17 23:27:49,982 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - No transaction detected, sending data frame: com.whizzosoftware.wzwave.frame.AddNodeToNetwork@21ddefdc 2016-08-17 23:27:49,982 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameEncoder - SENT com.whizzosoftware.wzwave.frame.AddNodeToNetwork@21ddefdc: 0x01 0x05 0x00 0x4A 0x05 0x02 0xB7 2016-08-17 23:27:49,982 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - Detected data frame write: com.whizzosoftware.wzwave.frame.AddNodeToNetwork@21ddefdc 2016-08-17 23:27:49,982 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - *** Data frame transaction started for com.whizzosoftware.wzwave.frame.AddNodeToNetwork@21ddefdc 2016-08-17 23:27:49,983 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - RCVD: 0x06 2016-08-17 23:27:49,984 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - Done processing received data: [ACK] 2016-08-17 23:27:49,984 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - Received frame within transaction context: ACK 2016-08-17 23:27:49,984 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.frame.transaction.RequestRequestTransaction - Received ACK as expected 2016-08-17 23:27:50,133 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - RCVD: 0x01 0x07 0x00 0x4A 2016-08-17 23:27:50,133 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - Done processing received data: [] 2016-08-17 23:27:50,134 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - RCVD: 0x02 0x06 0x00 0x00 0xB6 2016-08-17 23:27:50,134 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - Done processing received data: [com.whizzosoftware.wzwave.frame.AddNodeToNetwork@12f3edf2] 2016-08-17 23:27:50,134 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveChannelInboundHandler - Sending ACK for received data frame: com.whizzosoftware.wzwave.frame.AddNodeToNetwork@12f3edf2 2016-08-17 23:27:50,135 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - write: ACK 2016-08-17 23:27:50,135 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameEncoder - SENT ACK: 0x06 2016-08-17 23:27:50,135 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - Received frame within transaction context: com.whizzosoftware.wzwave.frame.AddNodeToNetwork@12f3edf2 2016-08-17 23:27:50,135 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.frame.transaction.RequestRequestTransaction - Received expected message response 2016-08-17 23:27:50,136 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - *** Data frame transaction completed with final frame: com.whizzosoftware.wzwave.frame.AddNodeToNetwork@12f3edf2 2016-08-17 23:27:50,136 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - =================== onZWaveInclusionStopped =================== 2016-08-17 23:27:50,136 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - Detected data frame transaction completion 2016-08-17 23:27:50,136 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - No pending data frames to send =================== Set factory default =================== 2016-08-17 23:28:00,150 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - write: com.whizzosoftware.wzwave.frame.SetDefault@109f87c5 2016-08-17 23:28:00,150 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - No transaction detected, sending data frame: com.whizzosoftware.wzwave.frame.SetDefault@109f87c5 2016-08-17 23:28:00,151 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameEncoder - SENT com.whizzosoftware.wzwave.frame.SetDefault@109f87c5: 0x01 0x03 0x00 0x42 0xBE 2016-08-17 23:28:00,151 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - Detected data frame write: com.whizzosoftware.wzwave.frame.SetDefault@109f87c5 2016-08-17 23:28:00,151 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - *** Data frame transaction started for com.whizzosoftware.wzwave.frame.SetDefault@109f87c5 2016-08-17 23:28:00,152 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - RCVD: 0x06 2016-08-17 23:28:00,152 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - Done processing received data: [ACK] 2016-08-17 23:28:00,152 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - Received frame within transaction context: ACK 2016-08-17 23:28:00,152 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.frame.transaction.RequestRequestTransaction - Received ACK as expected 2016-08-17 23:28:02,392 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - RCVD: 0x01 0x04 0x00 0x42 0x05 0xBC 2016-08-17 23:28:02,393 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.codec.ZWaveFrameDecoder - Done processing received data: [com.whizzosoftware.wzwave.frame.SetDefault@263be0f3] 2016-08-17 23:28:02,393 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveChannelInboundHandler - Sending ACK for received data frame: com.whizzosoftware.wzwave.frame.SetDefault@263be0f3 2016-08-17 23:28:02,393 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - write: ACK 2016-08-17 23:28:02,393 [pool-2-thread-1] DEBUG com.whizzosoftware.wzwave.codec.ZWaveFrameEncoder - SENT ACK: 0x06 2016-08-17 23:28:02,394 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - Received frame within transaction context: com.whizzosoftware.wzwave.frame.SetDefault@263be0f3 2016-08-17 23:28:02,394 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.frame.transaction.RequestRequestTransaction - Received expected message response 2016-08-17 23:28:02,394 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - *** Data frame transaction completed with final frame: com.whizzosoftware.wzwave.frame.SetDefault@263be0f3 2016-08-17 23:28:02,394 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveDataFrameTransactionInboundHandler - 2016-08-17 23:28:02,394 [pool-2-thread-1] INFO com.whizzosoftware.wzwave.controller.netty.NettyZWaveController - Z-Wave controller has been reset to factory default 2016-08-17 23:28:02,394 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - Detected data frame transaction completion 2016-08-17 23:28:02,395 [pool-2-thread-1] TRACE com.whizzosoftware.wzwave.channel.ZWaveQueuedOutboundHandler - No pending data frames to send =================== Stop ===================