search

cdjackson / HABmin

A web admin console for openHAB home automation
http://www.cd-jackson.com/index.php/openhab/habmin
217 stars 51 forks source link

Missing device in Product Database (Manufacturer Id: 0x13c, Device Type: 0x1, Device ID: 0x12 ) #232

Closed rezethon closed 8 years ago

rezethon commented 9 years ago

Hello I have a Philio PAN04-1B Relay Switch that is missing from the database. Could you please add it to the database? I believe this xml has the correct data: http://www.pepper1.net/zwavedb/device/652/652-013c-0001-0012-03-03-5f-01-04.xml My current xml:

<node>
  <deviceClass>
    <basicDeviceClass>ROUTING_SLAVE</basicDeviceClass>
    <genericDeviceClass>BINARY_SWITCH</genericDeviceClass>
    <specificDeviceClass>POWER_SWITCH_BINARY</specificDeviceClass>
  </deviceClass>
  <homeId>0x184d892</homeId>
  <nodeId>6</nodeId>
  <version>4</version>
  <manufacturer>0x13c</manufacturer>
  <deviceId>0x12</deviceId>
  <deviceType>0x1</deviceType>
  <listening>true</listening>
  <frequentlyListening>false</frequentlyListening>
  <routing>true</routing>
  <supportedCommandClasses>
    <entry>
      <commandClass>CONFIGURATION</commandClass>
      <configurationCommandClass>
        <version>1</version>
        <instances>1</instances>
        <configParameters/>
      </configurationCommandClass>
    </entry>
    <entry>
      <commandClass>VERSION</commandClass>
      <versionCommandClass>
        <version>1</version>
        <instances>1</instances>
        <libraryType>LIB_SLAVE_ENHANCED</libraryType>
        <protocolVersion>3.95</protocolVersion>
        <applicationVersion>1.4</applicationVersion>
      </versionCommandClass>
    </entry>
    <entry>
      <commandClass>METER</commandClass>
      <meterCommandClass>
        <version>3</version>
        <instances>1</instances>
        <meterType>ELECTRIC</meterType>
        <meterScales>
          <meterScale>E_A</meterScale>
          <meterScale>E_Power_Factor</meterScale>
          <meterScale>E_W</meterScale>
          <meterScale>E_KWh</meterScale>
          <meterScale>E_V</meterScale>
        </meterScales>
        <canReset>true</canReset>
        <isGetSupported>true</isGetSupported>
        <isSupportRequestSupported>true</isSupportRequestSupported>
      </meterCommandClass>
    </entry>
    <entry>
      <commandClass>SWITCH_BINARY</commandClass>
      <binarySwitchCommandClass>
        <version>1</version>
        <instances>1</instances>
        <isGetSupported>true</isGetSupported>
      </binarySwitchCommandClass>
    </entry>
    <entry>
      <commandClass>SCENE_ACTIVATION</commandClass>
      <sceneActivationCommandClass>
        <version>1</version>
        <instances>1</instances>
      </sceneActivationCommandClass>
    </entry>
    <entry>
      <commandClass>MULTI_INSTANCE</commandClass>
      <multiInstanceCommandClass>
        <version>2</version>
        <instances>1</instances>
        <endpoints>
          <entry>
            <int>1</int>
            <endPoint>
              <deviceClass>
                <basicDeviceClass>ROUTING_SLAVE</basicDeviceClass>
                <genericDeviceClass>BINARY_SWITCH</genericDeviceClass>
                <specificDeviceClass>POWER_SWITCH_BINARY</specificDeviceClass>
              </deviceClass>
              <endpointId>1</endpointId>
              <supportedCommandClasses>
                <entry>
                  <commandClass>METER</commandClass>
                  <meterCommandClass>
                    <endpoint reference="../../../.."/>
                    <version>3</version>
                    <instances>1</instances>
                    <meterType>ELECTRIC</meterType>
                    <meterScales>
                      <meterScale>E_A</meterScale>
                      <meterScale>E_Power_Factor</meterScale>
                      <meterScale>E_W</meterScale>
                      <meterScale>E_KWh</meterScale>
                      <meterScale>E_V</meterScale>
                    </meterScales>
                    <canReset>true</canReset>
                    <isGetSupported>true</isGetSupported>
                    <isSupportRequestSupported>true</isSupportRequestSupported>
                  </meterCommandClass>
                </entry>
                <entry>
                  <commandClass>SWITCH_BINARY</commandClass>
                  <binarySwitchCommandClass>
                    <endpoint reference="../../../.."/>
                    <version>1</version>
                    <instances>1</instances>
                    <isGetSupported>true</isGetSupported>
                  </binarySwitchCommandClass>
                </entry>
                <entry>
                  <commandClass>BASIC</commandClass>
                  <basicCommandClass>
                    <endpoint reference="../../../.."/>
                    <version>0</version>
                    <instances>0</instances>
                    <isGetSupported>true</isGetSupported>
                  </basicCommandClass>
                </entry>
              </supportedCommandClasses>
            </endPoint>
          </entry>
          <entry>
            <int>2</int>
            <endPoint>
              <deviceClass>
                <basicDeviceClass>ROUTING_SLAVE</basicDeviceClass>
                <genericDeviceClass>BINARY_SWITCH</genericDeviceClass>
                <specificDeviceClass>POWER_SWITCH_BINARY</specificDeviceClass>
              </deviceClass>
              <endpointId>2</endpointId>
              <supportedCommandClasses>
                <entry>
                  <commandClass>METER</commandClass>
                  <meterCommandClass>
                    <endpoint reference="../../../.."/>
                    <version>3</version>
                    <instances>1</instances>
                    <meterType>ELECTRIC</meterType>
                    <meterScales>
                      <meterScale>E_A</meterScale>
                      <meterScale>E_Power_Factor</meterScale>
                      <meterScale>E_W</meterScale>
                      <meterScale>E_KWh</meterScale>
                      <meterScale>E_V</meterScale>
                    </meterScales>
                    <canReset>true</canReset>
                    <isGetSupported>true</isGetSupported>
                    <isSupportRequestSupported>true</isSupportRequestSupported>
                  </meterCommandClass>
                </entry>
                <entry>
                  <commandClass>SWITCH_BINARY</commandClass>
                  <binarySwitchCommandClass>
                    <endpoint reference="../../../.."/>
                    <version>1</version>
                    <instances>1</instances>
                    <isGetSupported>true</isGetSupported>
                  </binarySwitchCommandClass>
                </entry>
                <entry>
                  <commandClass>BASIC</commandClass>
                  <basicCommandClass>
                    <endpoint reference="../../../.."/>
                    <version>0</version>
                    <instances>0</instances>
                    <isGetSupported>true</isGetSupported>
                  </basicCommandClass>
                </entry>
              </supportedCommandClasses>
            </endPoint>
          </entry>
          <entry>
            <int>3</int>
            <endPoint>
              <deviceClass>
                <basicDeviceClass>ROUTING_SLAVE</basicDeviceClass>
                <genericDeviceClass>BINARY_SWITCH</genericDeviceClass>
                <specificDeviceClass>POWER_SWITCH_BINARY</specificDeviceClass>
              </deviceClass>
              <endpointId>3</endpointId>
              <supportedCommandClasses>
                <entry>
                  <commandClass>METER</commandClass>
                  <meterCommandClass>
                    <endpoint reference="../../../.."/>
                    <version>3</version>
                    <instances>1</instances>
                    <meterType>ELECTRIC</meterType>
                    <meterScales>
                      <meterScale>E_A</meterScale>
                      <meterScale>E_Power_Factor</meterScale>
                      <meterScale>E_W</meterScale>
                      <meterScale>E_KWh</meterScale>
                      <meterScale>E_V</meterScale>
                    </meterScales>
                    <canReset>true</canReset>
                    <isGetSupported>true</isGetSupported>
                    <isSupportRequestSupported>true</isSupportRequestSupported>
                  </meterCommandClass>
                </entry>
                <entry>
                  <commandClass>SWITCH_BINARY</commandClass>
                  <binarySwitchCommandClass>
                    <endpoint reference="../../../.."/>
                    <version>1</version>
                    <instances>1</instances>
                    <isGetSupported>true</isGetSupported>
                  </binarySwitchCommandClass>
                </entry>
                <entry>
                  <commandClass>BASIC</commandClass>
                  <basicCommandClass>
                    <endpoint reference="../../../.."/>
                    <version>0</version>
                    <instances>0</instances>
                    <isGetSupported>true</isGetSupported>
                  </basicCommandClass>
                </entry>
              </supportedCommandClasses>
            </endPoint>
          </entry>
        </endpoints>
        <endpointsAreTheSameDeviceClass>true</endpointsAreTheSameDeviceClass>
      </multiInstanceCommandClass>
    </entry>
    <entry>
      <commandClass>BASIC</commandClass>
      <basicCommandClass>
        <version>1</version>
        <instances>1</instances>
        <isGetSupported>true</isGetSupported>
      </basicCommandClass>
    </entry>
    <entry>
      <commandClass>MANUFACTURER_SPECIFIC</commandClass>
      <manufacturerSpecificCommandClass>
        <version>1</version>
        <instances>1</instances>
      </manufacturerSpecificCommandClass>
    </entry>
    <entry>
      <commandClass>ASSOCIATION</commandClass>
      <associationCommandClass>
        <version>1</version>
        <instances>1</instances>
        <configAssociations>
          <entry>
            <int>1</int>
            <associationGroup>
              <Index>1</Index>
              <Nodes>
                <int>1</int>
              </Nodes>
            </associationGroup>
          </entry>
          <entry>
            <int>2</int>
            <associationGroup>
              <Index>2</Index>
              <Nodes>
                <int>1</int>
              </Nodes>
            </associationGroup>
          </entry>
          <entry>
            <int>3</int>
            <associationGroup>
              <Index>3</Index>
              <Nodes>
                <int>1</int>
              </Nodes>
            </associationGroup>
          </entry>
        </configAssociations>
        <maxGroups>3</maxGroups>
      </associationCommandClass>
    </entry>
    <entry>
      <commandClass>ALARM</commandClass>
      <alarmCommandClass>
        <version>1</version>
        <instances>1</instances>
        <alarms/>
        <isGetSupported>true</isGetSupported>
      </alarmCommandClass>
    </entry>
    <entry>
      <commandClass>NO_OPERATION</commandClass>
      <noOperationCommandClass>
        <version>1</version>
        <instances>1</instances>
      </noOperationCommandClass>
    </entry>
  </supportedCommandClasses>
  <nodeNeighbors>
    <int>1</int>
    <int>5</int>
  </nodeNeighbors>
  <lastSent>2015-04-12 23:21:54.487 UTC</lastSent>
  <lastReceived>2015-04-12 23:21:54.522 UTC</lastReceived>
  <applicationUpdateReceived>true</applicationUpdateReceived>
</node>

Regards

rezethon commented 9 years ago

Looks good except parameter 10 should have values 1 or 2. When I try to set it to "Doesn't Exist" it tries to set it to 0. See below:

2015-04-15 22:21:01.461 [DEBUG] [.z.i.config.ZWaveConfiguration:1071]- doSet domain 'nodes/node6/parameters/configuration10' to '0'
2015-04-15 22:21:01.515 [DEBUG] [.z.i.config.ZWaveConfiguration:1136]- Set parameter index '10' to '0'
2015-04-15 22:21:01.515 [DEBUG] [ZWaveConfigurationCommandClass:182 ]- NODE 6: Creating new message for application command CONFIGURATIONCMD_SET
2015-04-15 22:21:01.516 [DEBUG] [o.b.z.i.protocol.SerialMessage:109 ]- NODE 6: Creating empty message of class = SendData (0x13), type = Request (0x00)
2015-04-15 22:21:01.516 [DEBUG] [b.z.i.protocol.ZWaveController:976 ]- Callback ID = 56
2015-04-15 22:21:01.516 [DEBUG] [WaveController$ZWaveSendThread:1249]- Took message from queue for sending. Queue length = 0
2015-04-15 22:21:01.516 [DEBUG] [b.z.i.protocol.ZWaveController:658 ]- Enqueueing message. Queue length = 1
2015-04-15 22:21:01.517 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 0C 00 13 06 05 70 04 0A 01 00 25 38 81 
2015-04-15 22:21:01.517 [DEBUG] [WaveController$ZWaveSendThread:1306]- NODE 6: Sending REQUEST Message = 01 0C 00 13 06 05 70 04 0A 01 00 25 38 81 
2015-04-15 22:21:01.517 [DEBUG] [ZWaveConfigurationCommandClass:154 ]- NODE 6: Creating new message for application command CONFIGURATIONCMD_GET
2015-04-15 22:21:01.518 [DEBUG] [o.b.z.i.protocol.SerialMessage:109 ]- NODE 6: Creating empty message of class = SendData (0x13), type = Request (0x00)
2015-04-15 22:21:01.518 [DEBUG] [b.z.i.protocol.ZWaveController:976 ]- Callback ID = 57
2015-04-15 22:21:01.519 [DEBUG] [b.z.i.protocol.ZWaveController:658 ]- Enqueueing message. Queue length = 1
2015-04-15 22:21:01.522 [DEBUG] [eController$ZWaveReceiveThread:1519]- Receive Message = 01 04 01 13 01 E8 
2015-04-15 22:21:01.523 [DEBUG] [eController$ZWaveReceiveThread:1443]- Receive queue ADD: Length=1
2015-04-15 22:21:01.523 [DEBUG] [b.z.i.protocol.ZWaveController:1201]- Receive queue TAKE: Length=0
2015-04-15 22:21:01.524 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 04 01 13 01 E8 
2015-04-15 22:21:01.524 [DEBUG] [b.z.i.protocol.ZWaveController:1202]- Process Message = 01 04 01 13 01 E8 
2015-04-15 22:21:01.525 [DEBUG] [b.z.i.protocol.ZWaveController:190 ]- Message: class = SendData (0x13), type = Response (0x01), payload = 01 
2015-04-15 22:21:01.525 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38  ]- NODE 6: Sent Data successfully placed on stack.
2015-04-15 22:21:01.538 [DEBUG] [eController$ZWaveReceiveThread:1519]- Receive Message = 01 05 00 13 38 00 D1 
2015-04-15 22:21:01.539 [DEBUG] [eController$ZWaveReceiveThread:1443]- Receive queue ADD: Length=1
2015-04-15 22:21:01.541 [DEBUG] [b.z.i.protocol.ZWaveController:1201]- Receive queue TAKE: Length=0
2015-04-15 22:21:01.541 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 07 00 13 38 00 00 00 D3 
2015-04-15 22:21:01.541 [DEBUG] [b.z.i.protocol.ZWaveController:1202]- Process Message = 01 07 00 13 38 00 00 00 D3 
2015-04-15 22:21:01.541 [DEBUG] [b.z.i.protocol.ZWaveController:190 ]- Message: class = SendData (0x13), type = Request (0x00), payload = 38 00 
2015-04-15 22:21:01.541 [DEBUG] [b.z.i.p.s.SendDataMessageClass:74  ]- NODE 6: SendData Request. CallBack ID = 56, Status = Transmission complete and ACK received(0)
2015-04-15 22:21:01.542 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:63  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 06 05 70 04 0A 01 00 
2015-04-15 22:21:01.542 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:64  ]- Recv message Message: class = SendData (0x13), type = Request (0x00), payload = 38 00 
2015-04-15 22:21:01.542 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:65  ]- Checking transaction complete: class=SendData, expected=SendData, cancelled=false
2015-04-15 22:21:01.542 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:68  ]-          transaction complete!
2015-04-15 22:21:01.542 [DEBUG] [b.z.i.protocol.ZWaveController:673 ]- Notifying event listeners: ZWaveTransactionCompletedEvent
2015-04-15 22:21:01.542 [DEBUG] [.z.internal.ZWaveActiveBinding:433 ]- ZwaveIncomingEvent
2015-04-15 22:21:01.542 [DEBUG] [WaveController$ZWaveSendThread:1365]- NODE 6: Response processed after 25ms/140ms.
2015-04-15 22:21:01.543 [DEBUG] [WaveController$ZWaveSendThread:1249]- Took message from queue for sending. Queue length = 0
2015-04-15 22:21:01.543 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 0A 00 13 06 03 70 05 0A 25 39 80 
2015-04-15 22:21:01.543 [DEBUG] [WaveController$ZWaveSendThread:1306]- NODE 6: Sending REQUEST Message = 01 0A 00 13 06 03 70 05 0A 25 39 80 
2015-04-15 22:21:01.548 [DEBUG] [eController$ZWaveReceiveThread:1519]- Receive Message = 01 04 01 13 01 E8 
2015-04-15 22:21:01.548 [DEBUG] [eController$ZWaveReceiveThread:1443]- Receive queue ADD: Length=1
2015-04-15 22:21:01.548 [DEBUG] [b.z.i.protocol.ZWaveController:1201]- Receive queue TAKE: Length=0
2015-04-15 22:21:01.548 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 04 01 13 01 E8 
2015-04-15 22:21:01.549 [DEBUG] [b.z.i.protocol.ZWaveController:1202]- Process Message = 01 04 01 13 01 E8 
2015-04-15 22:21:01.549 [DEBUG] [b.z.i.protocol.ZWaveController:190 ]- Message: class = SendData (0x13), type = Response (0x01), payload = 01 
2015-04-15 22:21:01.549 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38  ]- NODE 6: Sent Data successfully placed on stack.
2015-04-15 22:21:01.563 [DEBUG] [eController$ZWaveReceiveThread:1519]- Receive Message = 01 05 00 13 39 00 D0 
2015-04-15 22:21:01.564 [DEBUG] [eController$ZWaveReceiveThread:1443]- Receive queue ADD: Length=1
2015-04-15 22:21:01.564 [DEBUG] [b.z.i.protocol.ZWaveController:1201]- Receive queue TAKE: Length=0
2015-04-15 22:21:01.564 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 07 00 13 39 00 00 00 D2 
2015-04-15 22:21:01.564 [DEBUG] [b.z.i.protocol.ZWaveController:1202]- Process Message = 01 07 00 13 39 00 00 00 D2 
2015-04-15 22:21:01.565 [DEBUG] [b.z.i.protocol.ZWaveController:190 ]- Message: class = SendData (0x13), type = Request (0x00), payload = 39 00 
2015-04-15 22:21:01.565 [DEBUG] [b.z.i.p.s.SendDataMessageClass:74  ]- NODE 6: SendData Request. CallBack ID = 57, Status = Transmission complete and ACK received(0)
2015-04-15 22:21:01.565 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:63  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 06 03 70 05 0A 
2015-04-15 22:21:01.565 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:64  ]- Recv message Message: class = SendData (0x13), type = Request (0x00), payload = 39 00 
2015-04-15 22:21:01.565 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:65  ]- Checking transaction complete: class=SendData, expected=ApplicationCommandHandler, cancelled=false
2015-04-15 22:21:01.574 [DEBUG] [eController$ZWaveReceiveThread:1519]- Receive Message = 01 0B 00 04 00 06 05 70 06 0A 01 01 8F 
2015-04-15 22:21:01.574 [DEBUG] [eController$ZWaveReceiveThread:1443]- Receive queue ADD: Length=1
2015-04-15 22:21:01.574 [DEBUG] [b.z.i.protocol.ZWaveController:1201]- Receive queue TAKE: Length=0
2015-04-15 22:21:01.575 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 0B 00 04 00 06 05 70 06 0A 01 01 8F 
2015-04-15 22:21:01.575 [DEBUG] [b.z.i.protocol.ZWaveController:1202]- Process Message = 01 0B 00 04 00 06 05 70 06 0A 01 01 8F 
2015-04-15 22:21:01.575 [DEBUG] [b.z.i.protocol.ZWaveController:190 ]- Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 06 05 70 06 0A 01 01 
2015-04-15 22:21:01.575 [DEBUG] [ApplicationCommandMessageClass:38  ]- NODE 6: Application Command Request (ALIVE:DONE)
2015-04-15 22:21:01.575 [DEBUG] [ApplicationCommandMessageClass:56  ]- NODE 6: Incoming command class CONFIGURATION
2015-04-15 22:21:01.575 [DEBUG] [ZWaveConfigurationCommandClass:76  ]- NODE 6: Received Configuration Request
2015-04-15 22:21:01.575 [DEBUG] [ZWaveConfigurationCommandClass:115 ]- NODE 6: Node configuration report, parameter = 10, value = 1
2015-04-15 22:21:01.576 [DEBUG] [b.z.i.protocol.ZWaveController:673 ]- Notifying event listeners: ZWaveConfigurationParameterEvent
2015-04-15 22:21:01.576 [DEBUG] [.z.internal.ZWaveActiveBinding:433 ]- ZwaveIncomingEvent
2015-04-15 22:21:01.576 [DEBUG] [.z.internal.ZWaveActiveBinding:450 ]- NODE 6: Got a value event from Z-Wave network, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@26c84fe8
2015-04-15 22:21:01.576 [WARN ] [.z.internal.ZWaveActiveBinding:467 ]- NODE 6: No item bound for event, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ConfigurationParameter@26c84fe8, ignoring.
2015-04-15 22:21:01.579 [DEBUG] [.b.z.i.p.i.ZWaveNodeSerializer:107 ]- NODE 6: Serializing to file etc\zwave\node6.xml
2015-04-15 22:21:01.587 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:63  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 06 03 70 05 0A 
2015-04-15 22:21:01.587 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:64  ]- Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 06 05 70 06 0A 01 01 
2015-04-15 22:21:01.587 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:65  ]- Checking transaction complete: class=ApplicationCommandHandler, expected=ApplicationCommandHandler, cancelled=false
2015-04-15 22:21:01.587 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:68  ]-          transaction complete!
2015-04-15 22:21:01.587 [DEBUG] [b.z.i.protocol.ZWaveController:673 ]- Notifying event listeners: ZWaveTransactionCompletedEvent
rezethon commented 9 years ago

There is one last thing regarding that relay and I'm not sure if it is software or device firmware related. There are three groups that suppose to report relay status. Group one combines relay one and two while Groups 2 and 3 are for relay 1 and 2 respectively. When I query endpoints 1 and 2 manually I get correct data but when I flip the switches on the wall all events are being sent to endpoint 0! I don't really want to swamp the network with poll requests when device reports events just when they happen. Any help would be appreciated. See below for my trace logs when manually flipping both switches on and off:

2015-04-23 12:40:36.715 [TRACE] [veController$WatchDogTimerTask:1586]- Watchdog: Checking Serial threads
2015-04-23 12:40:41.421 [DEBUG] [eController$ZWaveReceiveThread:1519]- Receive Message = 01 09 00 04 00 04 03 25 03 FF 2C 
2015-04-23 12:40:41.426 [TRACE] [o.b.z.i.protocol.SerialMessage:136 ]- NODE 255: Creating new SerialMessage from buffer = 01 09 00 04 00 04 03 25 03 FF 2C 
2015-04-23 12:40:41.426 [TRACE] [o.b.z.i.protocol.SerialMessage:179 ]- Calculated checksum = 0x2C
2015-04-23 12:40:41.426 [TRACE] [o.b.z.i.protocol.SerialMessage:141 ]- NODE 255: Checksum matched
2015-04-23 12:40:41.431 [TRACE] [o.b.z.i.protocol.SerialMessage:153 ]- NODE 255: Message payload = 00 04 03 25 03 FF 
2015-04-23 12:40:41.431 [TRACE] [eController$ZWaveReceiveThread:1428]- Message is valid, sending ACK
2015-04-23 12:40:41.431 [TRACE] [eController$ZWaveReceiveThread:1413]- Response SENT
2015-04-23 12:40:41.431 [DEBUG] [eController$ZWaveReceiveThread:1443]- Receive queue ADD: Length=1
2015-04-23 12:40:41.431 [DEBUG] [b.z.i.protocol.ZWaveController:1201]- Receive queue TAKE: Length=0
2015-04-23 12:40:41.436 [TRACE] [o.b.z.i.protocol.SerialMessage:179 ]- Calculated checksum = 0x2C
2015-04-23 12:40:41.436 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 09 00 04 00 04 03 25 03 FF 2C 
2015-04-23 12:40:41.441 [DEBUG] [b.z.i.protocol.ZWaveController:1202]- Process Message = 01 09 00 04 00 04 03 25 03 FF 2C 
2015-04-23 12:40:41.441 [DEBUG] [b.z.i.protocol.ZWaveController:190 ]- Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 04 03 25 03 FF 
2015-04-23 12:40:41.441 [TRACE] [b.z.i.protocol.ZWaveController:210 ]- Incoming Message type = REQUEST
2015-04-23 12:40:41.446 [TRACE] [ApplicationCommandMessageClass:30  ]- Handle Message Application Command Request
2015-04-23 12:40:41.446 [DEBUG] [ApplicationCommandMessageClass:38  ]- NODE 4: Application Command Request (ALIVE:DONE)
2015-04-23 12:40:41.446 [DEBUG] [ApplicationCommandMessageClass:56  ]- NODE 4: Incoming command class SWITCH_BINARY
2015-04-23 12:40:41.446 [TRACE] [ApplicationCommandMessageClass:79  ]- NODE 4: Found Command Class SWITCH_BINARY, passing to handleApplicationCommandRequest
2015-04-23 12:40:41.446 [TRACE] [.ZWaveBinarySwitchCommandClass:78  ]- Handle Message Switch Binary Request
2015-04-23 12:40:41.451 [DEBUG] [.ZWaveBinarySwitchCommandClass:79  ]- Received Switch Binary Request for Node ID = 4
2015-04-23 12:40:41.451 [TRACE] [.ZWaveBinarySwitchCommandClass:93  ]- Process Switch Binary Report
2015-04-23 12:40:41.451 [DEBUG] [.ZWaveBinarySwitchCommandClass:115 ]- NODE 4: Switch Binary report, value = 0xFF
2015-04-23 12:40:41.451 [DEBUG] [b.z.i.protocol.ZWaveController:673 ]- Notifying event listeners: ZWaveCommandClassValueEvent
2015-04-23 12:40:41.451 [DEBUG] [.z.internal.ZWaveActiveBinding:433 ]- ZwaveIncomingEvent
2015-04-23 12:40:41.451 [DEBUG] [.z.internal.ZWaveActiveBinding:450 ]- NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = SWITCH_BINARY, value = 255
2015-04-23 12:40:41.456 [WARN ] [.z.internal.ZWaveActiveBinding:467 ]- NODE 4: No item bound for event, endpoint = 0, command class = SWITCH_BINARY, value = 255, ignoring.
2015-04-23 12:40:41.456 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:63  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 04 01 00 
2015-04-23 12:40:41.456 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:64  ]- Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 04 03 25 03 FF 
2015-04-23 12:40:41.456 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:65  ]- Checking transaction complete: class=ApplicationCommandHandler, expected=SendData, cancelled=false
2015-04-23 12:40:42.981 [DEBUG] [eController$ZWaveReceiveThread:1519]- Receive Message = 01 09 00 04 00 04 03 25 03 FF 2C 
2015-04-23 12:40:42.986 [TRACE] [o.b.z.i.protocol.SerialMessage:136 ]- NODE 255: Creating new SerialMessage from buffer = 01 09 00 04 00 04 03 25 03 FF 2C 
2015-04-23 12:40:42.986 [TRACE] [o.b.z.i.protocol.SerialMessage:179 ]- Calculated checksum = 0x2C
2015-04-23 12:40:42.986 [TRACE] [o.b.z.i.protocol.SerialMessage:141 ]- NODE 255: Checksum matched
2015-04-23 12:40:42.986 [TRACE] [o.b.z.i.protocol.SerialMessage:153 ]- NODE 255: Message payload = 00 04 03 25 03 FF 
2015-04-23 12:40:42.986 [TRACE] [eController$ZWaveReceiveThread:1428]- Message is valid, sending ACK
2015-04-23 12:40:42.986 [TRACE] [eController$ZWaveReceiveThread:1413]- Response SENT
2015-04-23 12:40:42.986 [DEBUG] [eController$ZWaveReceiveThread:1443]- Receive queue ADD: Length=1
2015-04-23 12:40:42.986 [DEBUG] [b.z.i.protocol.ZWaveController:1201]- Receive queue TAKE: Length=0
2015-04-23 12:40:42.986 [TRACE] [o.b.z.i.protocol.SerialMessage:179 ]- Calculated checksum = 0x2C
2015-04-23 12:40:42.991 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 09 00 04 00 04 03 25 03 FF 2C 
2015-04-23 12:40:42.991 [DEBUG] [b.z.i.protocol.ZWaveController:1202]- Process Message = 01 09 00 04 00 04 03 25 03 FF 2C 
2015-04-23 12:40:42.991 [DEBUG] [b.z.i.protocol.ZWaveController:190 ]- Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 04 03 25 03 FF 
2015-04-23 12:40:42.991 [TRACE] [b.z.i.protocol.ZWaveController:210 ]- Incoming Message type = REQUEST
2015-04-23 12:40:42.991 [TRACE] [ApplicationCommandMessageClass:30  ]- Handle Message Application Command Request
2015-04-23 12:40:42.991 [DEBUG] [ApplicationCommandMessageClass:38  ]- NODE 4: Application Command Request (ALIVE:DONE)
2015-04-23 12:40:42.996 [DEBUG] [ApplicationCommandMessageClass:56  ]- NODE 4: Incoming command class SWITCH_BINARY
2015-04-23 12:40:42.996 [TRACE] [ApplicationCommandMessageClass:79  ]- NODE 4: Found Command Class SWITCH_BINARY, passing to handleApplicationCommandRequest
2015-04-23 12:40:42.996 [TRACE] [.ZWaveBinarySwitchCommandClass:78  ]- Handle Message Switch Binary Request
2015-04-23 12:40:42.996 [DEBUG] [.ZWaveBinarySwitchCommandClass:79  ]- Received Switch Binary Request for Node ID = 4
2015-04-23 12:40:42.996 [TRACE] [.ZWaveBinarySwitchCommandClass:93  ]- Process Switch Binary Report
2015-04-23 12:40:42.996 [DEBUG] [.ZWaveBinarySwitchCommandClass:115 ]- NODE 4: Switch Binary report, value = 0xFF
2015-04-23 12:40:42.996 [DEBUG] [b.z.i.protocol.ZWaveController:673 ]- Notifying event listeners: ZWaveCommandClassValueEvent
2015-04-23 12:40:42.996 [DEBUG] [.z.internal.ZWaveActiveBinding:433 ]- ZwaveIncomingEvent
2015-04-23 12:40:42.996 [DEBUG] [.z.internal.ZWaveActiveBinding:450 ]- NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = SWITCH_BINARY, value = 255
2015-04-23 12:40:43.001 [WARN ] [.z.internal.ZWaveActiveBinding:467 ]- NODE 4: No item bound for event, endpoint = 0, command class = SWITCH_BINARY, value = 255, ignoring.
2015-04-23 12:40:43.001 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:63  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 04 01 00 
2015-04-23 12:40:43.001 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:64  ]- Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 04 03 25 03 FF 
2015-04-23 12:40:43.001 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:65  ]- Checking transaction complete: class=ApplicationCommandHandler, expected=SendData, cancelled=false
2015-04-23 12:40:43.907 [DEBUG] [eController$ZWaveReceiveThread:1519]- Receive Message = 01 14 00 04 00 04 0E 32 02 21 34 00 00 00 AF 00 00 00 00 00 00 6F 
2015-04-23 12:40:43.912 [TRACE] [o.b.z.i.protocol.SerialMessage:136 ]- NODE 255: Creating new SerialMessage from buffer = 01 14 00 04 00 04 0E 32 02 21 34 00 00 00 AF 00 00 00 00 00 00 6F 
2015-04-23 12:40:43.912 [TRACE] [o.b.z.i.protocol.SerialMessage:179 ]- Calculated checksum = 0x6F
2015-04-23 12:40:43.912 [TRACE] [o.b.z.i.protocol.SerialMessage:141 ]- NODE 255: Checksum matched
2015-04-23 12:40:43.917 [TRACE] [o.b.z.i.protocol.SerialMessage:153 ]- NODE 255: Message payload = 00 04 0E 32 02 21 34 00 00 00 AF 00 00 00 00 00 00 
2015-04-23 12:40:43.917 [TRACE] [eController$ZWaveReceiveThread:1428]- Message is valid, sending ACK
2015-04-23 12:40:43.917 [TRACE] [eController$ZWaveReceiveThread:1413]- Response SENT
2015-04-23 12:40:43.922 [DEBUG] [eController$ZWaveReceiveThread:1443]- Receive queue ADD: Length=1
2015-04-23 12:40:43.922 [DEBUG] [b.z.i.protocol.ZWaveController:1201]- Receive queue TAKE: Length=0
2015-04-23 12:40:43.922 [TRACE] [o.b.z.i.protocol.SerialMessage:179 ]- Calculated checksum = 0x6F
2015-04-23 12:40:43.927 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 14 00 04 00 04 0E 32 02 21 34 00 00 00 AF 00 00 00 00 00 00 6F 
2015-04-23 12:40:43.932 [DEBUG] [b.z.i.protocol.ZWaveController:1202]- Process Message = 01 14 00 04 00 04 0E 32 02 21 34 00 00 00 AF 00 00 00 00 00 00 6F 
2015-04-23 12:40:43.937 [DEBUG] [b.z.i.protocol.ZWaveController:190 ]- Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 04 0E 32 02 21 34 00 00 00 AF 00 00 00 00 00 00 
2015-04-23 12:40:43.937 [TRACE] [b.z.i.protocol.ZWaveController:210 ]- Incoming Message type = REQUEST
2015-04-23 12:40:43.942 [TRACE] [ApplicationCommandMessageClass:30  ]- Handle Message Application Command Request
2015-04-23 12:40:43.942 [DEBUG] [ApplicationCommandMessageClass:38  ]- NODE 4: Application Command Request (ALIVE:DONE)
2015-04-23 12:40:43.942 [DEBUG] [ApplicationCommandMessageClass:56  ]- NODE 4: Incoming command class METER
2015-04-23 12:40:43.947 [TRACE] [ApplicationCommandMessageClass:79  ]- NODE 4: Found Command Class METER, passing to handleApplicationCommandRequest
2015-04-23 12:40:43.947 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:109 ]- NODE 4: Received Meter Request
2015-04-23 12:40:43.947 [TRACE] [z.i.p.c.ZWaveMeterCommandClass:121 ]- NODE 4: Meter report received
2015-04-23 12:40:43.947 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:157 ]- NODE 4: Meter: Type=Electric(1), Scale=W(2), Value=17.5
2015-04-23 12:40:43.947 [DEBUG] [b.z.i.protocol.ZWaveController:673 ]- Notifying event listeners: ZWaveMeterValueEvent
2015-04-23 12:40:43.947 [DEBUG] [.z.internal.ZWaveActiveBinding:433 ]- ZwaveIncomingEvent
2015-04-23 12:40:43.952 [DEBUG] [.z.internal.ZWaveActiveBinding:450 ]- NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = METER, value = 17.5
2015-04-23 12:40:43.952 [WARN ] [.z.internal.ZWaveActiveBinding:467 ]- NODE 4: No item bound for event, endpoint = 0, command class = METER, value = 17.5, ignoring.
2015-04-23 12:40:43.952 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:63  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 04 01 00 
2015-04-23 12:40:43.952 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:64  ]- Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 04 0E 32 02 21 34 00 00 00 AF 00 00 00 00 00 00 
2015-04-23 12:40:43.952 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:65  ]- Checking transaction complete: class=ApplicationCommandHandler, expected=SendData, cancelled=false
2015-04-23 12:40:43.957 [DEBUG] [eController$ZWaveReceiveThread:1519]- Receive Message = 01 14 00 04 00 04 0E 32 02 21 34 00 00 00 5F 00 00 00 00 00 00 9F 
2015-04-23 12:40:43.962 [TRACE] [o.b.z.i.protocol.SerialMessage:136 ]- NODE 255: Creating new SerialMessage from buffer = 01 14 00 04 00 04 0E 32 02 21 34 00 00 00 5F 00 00 00 00 00 00 9F 
2015-04-23 12:40:43.962 [TRACE] [o.b.z.i.protocol.SerialMessage:179 ]- Calculated checksum = 0x9F
2015-04-23 12:40:43.962 [TRACE] [o.b.z.i.protocol.SerialMessage:141 ]- NODE 255: Checksum matched
2015-04-23 12:40:43.962 [TRACE] [o.b.z.i.protocol.SerialMessage:153 ]- NODE 255: Message payload = 00 04 0E 32 02 21 34 00 00 00 5F 00 00 00 00 00 00 
2015-04-23 12:40:43.962 [TRACE] [eController$ZWaveReceiveThread:1428]- Message is valid, sending ACK
2015-04-23 12:40:43.962 [TRACE] [eController$ZWaveReceiveThread:1413]- Response SENT
2015-04-23 12:40:43.962 [DEBUG] [eController$ZWaveReceiveThread:1443]- Receive queue ADD: Length=1
2015-04-23 12:40:43.962 [DEBUG] [b.z.i.protocol.ZWaveController:1201]- Receive queue TAKE: Length=0
2015-04-23 12:40:43.967 [TRACE] [o.b.z.i.protocol.SerialMessage:179 ]- Calculated checksum = 0x9F
2015-04-23 12:40:43.967 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 14 00 04 00 04 0E 32 02 21 34 00 00 00 5F 00 00 00 00 00 00 9F 
2015-04-23 12:40:43.967 [DEBUG] [b.z.i.protocol.ZWaveController:1202]- Process Message = 01 14 00 04 00 04 0E 32 02 21 34 00 00 00 5F 00 00 00 00 00 00 9F 
2015-04-23 12:40:43.972 [DEBUG] [b.z.i.protocol.ZWaveController:190 ]- Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 04 0E 32 02 21 34 00 00 00 5F 00 00 00 00 00 00 
2015-04-23 12:40:43.972 [TRACE] [b.z.i.protocol.ZWaveController:210 ]- Incoming Message type = REQUEST
2015-04-23 12:40:43.972 [TRACE] [ApplicationCommandMessageClass:30  ]- Handle Message Application Command Request
2015-04-23 12:40:43.972 [DEBUG] [ApplicationCommandMessageClass:38  ]- NODE 4: Application Command Request (ALIVE:DONE)
2015-04-23 12:40:43.972 [DEBUG] [ApplicationCommandMessageClass:56  ]- NODE 4: Incoming command class METER
2015-04-23 12:40:43.972 [TRACE] [ApplicationCommandMessageClass:79  ]- NODE 4: Found Command Class METER, passing to handleApplicationCommandRequest
2015-04-23 12:40:43.972 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:109 ]- NODE 4: Received Meter Request
2015-04-23 12:40:43.972 [TRACE] [z.i.p.c.ZWaveMeterCommandClass:121 ]- NODE 4: Meter report received
2015-04-23 12:40:43.977 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:157 ]- NODE 4: Meter: Type=Electric(1), Scale=W(2), Value=9.5
2015-04-23 12:40:43.977 [DEBUG] [b.z.i.protocol.ZWaveController:673 ]- Notifying event listeners: ZWaveMeterValueEvent
2015-04-23 12:40:43.977 [DEBUG] [.z.internal.ZWaveActiveBinding:433 ]- ZwaveIncomingEvent
2015-04-23 12:40:43.977 [DEBUG] [.z.internal.ZWaveActiveBinding:450 ]- NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = METER, value = 9.5
2015-04-23 12:40:43.977 [WARN ] [.z.internal.ZWaveActiveBinding:467 ]- NODE 4: No item bound for event, endpoint = 0, command class = METER, value = 9.5, ignoring.
2015-04-23 12:40:43.977 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:63  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 04 01 00 
2015-04-23 12:40:43.977 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:64  ]- Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 04 0E 32 02 21 34 00 00 00 5F 00 00 00 00 00 00 
2015-04-23 12:40:43.977 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:65  ]- Checking transaction complete: class=ApplicationCommandHandler, expected=SendData, cancelled=false
2015-04-23 12:40:46.717 [TRACE] [veController$WatchDogTimerTask:1586]- Watchdog: Checking Serial threads

2015-04-23 12:35:56.648 [TRACE] [veController$WatchDogTimerTask:1586]- Watchdog: Checking Serial threads
2015-04-23 12:36:00.639 [DEBUG] [eController$ZWaveReceiveThread:1519]- Receive Message = 01 09 00 04 00 04 03 25 03 00 D3 
2015-04-23 12:36:00.644 [TRACE] [o.b.z.i.protocol.SerialMessage:136 ]- NODE 255: Creating new SerialMessage from buffer = 01 09 00 04 00 04 03 25 03 00 D3 
2015-04-23 12:36:00.644 [TRACE] [o.b.z.i.protocol.SerialMessage:179 ]- Calculated checksum = 0xD3
2015-04-23 12:36:00.644 [TRACE] [o.b.z.i.protocol.SerialMessage:141 ]- NODE 255: Checksum matched
2015-04-23 12:36:00.644 [TRACE] [o.b.z.i.protocol.SerialMessage:153 ]- NODE 255: Message payload = 00 04 03 25 03 00 
2015-04-23 12:36:00.649 [TRACE] [eController$ZWaveReceiveThread:1428]- Message is valid, sending ACK
2015-04-23 12:36:00.649 [TRACE] [eController$ZWaveReceiveThread:1413]- Response SENT
2015-04-23 12:36:00.649 [DEBUG] [eController$ZWaveReceiveThread:1443]- Receive queue ADD: Length=1
2015-04-23 12:36:00.649 [DEBUG] [b.z.i.protocol.ZWaveController:1201]- Receive queue TAKE: Length=0
2015-04-23 12:36:00.649 [TRACE] [o.b.z.i.protocol.SerialMessage:179 ]- Calculated checksum = 0xD3
2015-04-23 12:36:00.654 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 09 00 04 00 04 03 25 03 00 D3 
2015-04-23 12:36:00.654 [DEBUG] [b.z.i.protocol.ZWaveController:1202]- Process Message = 01 09 00 04 00 04 03 25 03 00 D3 
2015-04-23 12:36:00.659 [DEBUG] [b.z.i.protocol.ZWaveController:190 ]- Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 04 03 25 03 00 
2015-04-23 12:36:00.659 [TRACE] [b.z.i.protocol.ZWaveController:210 ]- Incoming Message type = REQUEST
2015-04-23 12:36:00.659 [TRACE] [ApplicationCommandMessageClass:30  ]- Handle Message Application Command Request
2015-04-23 12:36:00.659 [DEBUG] [ApplicationCommandMessageClass:38  ]- NODE 4: Application Command Request (ALIVE:DONE)
2015-04-23 12:36:00.664 [DEBUG] [ApplicationCommandMessageClass:56  ]- NODE 4: Incoming command class SWITCH_BINARY
2015-04-23 12:36:00.664 [TRACE] [ApplicationCommandMessageClass:79  ]- NODE 4: Found Command Class SWITCH_BINARY, passing to handleApplicationCommandRequest
2015-04-23 12:36:00.664 [TRACE] [.ZWaveBinarySwitchCommandClass:78  ]- Handle Message Switch Binary Request
2015-04-23 12:36:00.664 [DEBUG] [.ZWaveBinarySwitchCommandClass:79  ]- Received Switch Binary Request for Node ID = 4
2015-04-23 12:36:00.664 [TRACE] [.ZWaveBinarySwitchCommandClass:93  ]- Process Switch Binary Report
2015-04-23 12:36:00.669 [DEBUG] [.ZWaveBinarySwitchCommandClass:115 ]- NODE 4: Switch Binary report, value = 0x00
2015-04-23 12:36:00.669 [DEBUG] [b.z.i.protocol.ZWaveController:673 ]- Notifying event listeners: ZWaveCommandClassValueEvent
2015-04-23 12:36:00.669 [DEBUG] [.z.internal.ZWaveActiveBinding:433 ]- ZwaveIncomingEvent
2015-04-23 12:36:00.669 [DEBUG] [.z.internal.ZWaveActiveBinding:450 ]- NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = SWITCH_BINARY, value = 0
2015-04-23 12:36:00.669 [WARN ] [.z.internal.ZWaveActiveBinding:467 ]- NODE 4: No item bound for event, endpoint = 0, command class = SWITCH_BINARY, value = 0, ignoring.
2015-04-23 12:36:00.674 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:63  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 04 01 00 
2015-04-23 12:36:00.674 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:64  ]- Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 04 03 25 03 00 
2015-04-23 12:36:00.674 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:65  ]- Checking transaction complete: class=ApplicationCommandHandler, expected=SendData, cancelled=false
2015-04-23 12:36:01.884 [DEBUG] [eController$ZWaveReceiveThread:1519]- Receive Message = 01 14 00 04 00 04 0E 32 02 21 34 00 00 00 00 00 00 00 00 00 00 C0 
2015-04-23 12:36:01.889 [TRACE] [o.b.z.i.protocol.SerialMessage:136 ]- NODE 255: Creating new SerialMessage from buffer = 01 14 00 04 00 04 0E 32 02 21 34 00 00 00 00 00 00 00 00 00 00 C0 
2015-04-23 12:36:01.889 [TRACE] [o.b.z.i.protocol.SerialMessage:179 ]- Calculated checksum = 0xC0
2015-04-23 12:36:01.889 [TRACE] [o.b.z.i.protocol.SerialMessage:141 ]- NODE 255: Checksum matched
2015-04-23 12:36:01.894 [TRACE] [o.b.z.i.protocol.SerialMessage:153 ]- NODE 255: Message payload = 00 04 0E 32 02 21 34 00 00 00 00 00 00 00 00 00 00 
2015-04-23 12:36:01.894 [TRACE] [eController$ZWaveReceiveThread:1428]- Message is valid, sending ACK
2015-04-23 12:36:01.894 [TRACE] [eController$ZWaveReceiveThread:1413]- Response SENT
2015-04-23 12:36:01.894 [DEBUG] [eController$ZWaveReceiveThread:1443]- Receive queue ADD: Length=1
2015-04-23 12:36:01.894 [DEBUG] [b.z.i.protocol.ZWaveController:1201]- Receive queue TAKE: Length=0
2015-04-23 12:36:01.894 [TRACE] [o.b.z.i.protocol.SerialMessage:179 ]- Calculated checksum = 0xC0
2015-04-23 12:36:01.899 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 14 00 04 00 04 0E 32 02 21 34 00 00 00 00 00 00 00 00 00 00 C0 
2015-04-23 12:36:01.904 [DEBUG] [b.z.i.protocol.ZWaveController:1202]- Process Message = 01 14 00 04 00 04 0E 32 02 21 34 00 00 00 00 00 00 00 00 00 00 C0 
2015-04-23 12:36:01.909 [DEBUG] [b.z.i.protocol.ZWaveController:190 ]- Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 04 0E 32 02 21 34 00 00 00 00 00 00 00 00 00 00 
2015-04-23 12:36:01.909 [TRACE] [b.z.i.protocol.ZWaveController:210 ]- Incoming Message type = REQUEST
2015-04-23 12:36:01.909 [TRACE] [ApplicationCommandMessageClass:30  ]- Handle Message Application Command Request
2015-04-23 12:36:01.909 [DEBUG] [ApplicationCommandMessageClass:38  ]- NODE 4: Application Command Request (ALIVE:DONE)
2015-04-23 12:36:01.909 [DEBUG] [ApplicationCommandMessageClass:56  ]- NODE 4: Incoming command class METER
2015-04-23 12:36:01.909 [TRACE] [ApplicationCommandMessageClass:79  ]- NODE 4: Found Command Class METER, passing to handleApplicationCommandRequest
2015-04-23 12:36:01.909 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:109 ]- NODE 4: Received Meter Request
2015-04-23 12:36:01.914 [TRACE] [z.i.p.c.ZWaveMeterCommandClass:121 ]- NODE 4: Meter report received
2015-04-23 12:36:01.914 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:157 ]- NODE 4: Meter: Type=Electric(1), Scale=W(2), Value=0E+1
2015-04-23 12:36:01.914 [DEBUG] [b.z.i.protocol.ZWaveController:673 ]- Notifying event listeners: ZWaveMeterValueEvent
2015-04-23 12:36:01.914 [DEBUG] [.z.internal.ZWaveActiveBinding:433 ]- ZwaveIncomingEvent
2015-04-23 12:36:01.914 [DEBUG] [.z.internal.ZWaveActiveBinding:450 ]- NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = METER, value = 0E+1
2015-04-23 12:36:01.914 [WARN ] [.z.internal.ZWaveActiveBinding:467 ]- NODE 4: No item bound for event, endpoint = 0, command class = METER, value = 0E+1, ignoring.
2015-04-23 12:36:01.914 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:63  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 04 01 00 
2015-04-23 12:36:01.914 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:64  ]- Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 04 0E 32 02 21 34 00 00 00 00 00 00 00 00 00 00 
2015-04-23 12:36:01.919 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:65  ]- Checking transaction complete: class=ApplicationCommandHandler, expected=SendData, cancelled=false
2015-04-23 12:36:02.519 [DEBUG] [eController$ZWaveReceiveThread:1519]- Receive Message = 01 09 00 04 00 04 03 25 03 00 D3 
2015-04-23 12:36:02.524 [TRACE] [o.b.z.i.protocol.SerialMessage:136 ]- NODE 255: Creating new SerialMessage from buffer = 01 09 00 04 00 04 03 25 03 00 D3 
2015-04-23 12:36:02.524 [TRACE] [o.b.z.i.protocol.SerialMessage:179 ]- Calculated checksum = 0xD3
2015-04-23 12:36:02.524 [TRACE] [o.b.z.i.protocol.SerialMessage:141 ]- NODE 255: Checksum matched
2015-04-23 12:36:02.529 [TRACE] [o.b.z.i.protocol.SerialMessage:153 ]- NODE 255: Message payload = 00 04 03 25 03 00 
2015-04-23 12:36:02.529 [TRACE] [eController$ZWaveReceiveThread:1428]- Message is valid, sending ACK
2015-04-23 12:36:02.529 [TRACE] [eController$ZWaveReceiveThread:1413]- Response SENT
2015-04-23 12:36:02.534 [DEBUG] [eController$ZWaveReceiveThread:1443]- Receive queue ADD: Length=1
2015-04-23 12:36:02.534 [DEBUG] [b.z.i.protocol.ZWaveController:1201]- Receive queue TAKE: Length=0
2015-04-23 12:36:02.534 [TRACE] [o.b.z.i.protocol.SerialMessage:179 ]- Calculated checksum = 0xD3
2015-04-23 12:36:02.539 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 09 00 04 00 04 03 25 03 00 D3 
2015-04-23 12:36:02.539 [DEBUG] [b.z.i.protocol.ZWaveController:1202]- Process Message = 01 09 00 04 00 04 03 25 03 00 D3 
2015-04-23 12:36:02.544 [DEBUG] [b.z.i.protocol.ZWaveController:190 ]- Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 04 03 25 03 00 
2015-04-23 12:36:02.544 [TRACE] [b.z.i.protocol.ZWaveController:210 ]- Incoming Message type = REQUEST
2015-04-23 12:36:02.544 [TRACE] [ApplicationCommandMessageClass:30  ]- Handle Message Application Command Request
2015-04-23 12:36:02.544 [DEBUG] [ApplicationCommandMessageClass:38  ]- NODE 4: Application Command Request (ALIVE:DONE)
2015-04-23 12:36:02.544 [DEBUG] [ApplicationCommandMessageClass:56  ]- NODE 4: Incoming command class SWITCH_BINARY
2015-04-23 12:36:02.544 [TRACE] [ApplicationCommandMessageClass:79  ]- NODE 4: Found Command Class SWITCH_BINARY, passing to handleApplicationCommandRequest
2015-04-23 12:36:02.544 [TRACE] [.ZWaveBinarySwitchCommandClass:78  ]- Handle Message Switch Binary Request
2015-04-23 12:36:02.544 [DEBUG] [.ZWaveBinarySwitchCommandClass:79  ]- Received Switch Binary Request for Node ID = 4
2015-04-23 12:36:02.549 [TRACE] [.ZWaveBinarySwitchCommandClass:93  ]- Process Switch Binary Report
2015-04-23 12:36:02.549 [DEBUG] [.ZWaveBinarySwitchCommandClass:115 ]- NODE 4: Switch Binary report, value = 0x00
2015-04-23 12:36:02.549 [DEBUG] [b.z.i.protocol.ZWaveController:673 ]- Notifying event listeners: ZWaveCommandClassValueEvent
2015-04-23 12:36:02.549 [DEBUG] [.z.internal.ZWaveActiveBinding:433 ]- ZwaveIncomingEvent
2015-04-23 12:36:02.549 [DEBUG] [.z.internal.ZWaveActiveBinding:450 ]- NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = SWITCH_BINARY, value = 0
2015-04-23 12:36:02.549 [WARN ] [.z.internal.ZWaveActiveBinding:467 ]- NODE 4: No item bound for event, endpoint = 0, command class = SWITCH_BINARY, value = 0, ignoring.
2015-04-23 12:36:02.549 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:63  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 04 01 00 
2015-04-23 12:36:02.554 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:64  ]- Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 04 03 25 03 00 
2015-04-23 12:36:02.554 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:65  ]- Checking transaction complete: class=ApplicationCommandHandler, expected=SendData, cancelled=false
2015-04-23 12:36:02.894 [DEBUG] [eController$ZWaveReceiveThread:1519]- Receive Message = 01 14 00 04 00 04 0E 32 02 21 34 00 00 00 00 00 00 00 00 00 00 C0 
2015-04-23 12:36:02.899 [TRACE] [o.b.z.i.protocol.SerialMessage:136 ]- NODE 255: Creating new SerialMessage from buffer = 01 14 00 04 00 04 0E 32 02 21 34 00 00 00 00 00 00 00 00 00 00 C0 
2015-04-23 12:36:02.899 [TRACE] [o.b.z.i.protocol.SerialMessage:179 ]- Calculated checksum = 0xC0
2015-04-23 12:36:02.899 [TRACE] [o.b.z.i.protocol.SerialMessage:141 ]- NODE 255: Checksum matched
2015-04-23 12:36:02.904 [TRACE] [o.b.z.i.protocol.SerialMessage:153 ]- NODE 255: Message payload = 00 04 0E 32 02 21 34 00 00 00 00 00 00 00 00 00 00 
2015-04-23 12:36:02.904 [TRACE] [eController$ZWaveReceiveThread:1428]- Message is valid, sending ACK
2015-04-23 12:36:02.909 [TRACE] [eController$ZWaveReceiveThread:1413]- Response SENT
2015-04-23 12:36:02.909 [DEBUG] [eController$ZWaveReceiveThread:1443]- Receive queue ADD: Length=1
2015-04-23 12:36:02.909 [DEBUG] [b.z.i.protocol.ZWaveController:1201]- Receive queue TAKE: Length=0
2015-04-23 12:36:02.909 [TRACE] [o.b.z.i.protocol.SerialMessage:179 ]- Calculated checksum = 0xC0
2015-04-23 12:36:02.914 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 14 00 04 00 04 0E 32 02 21 34 00 00 00 00 00 00 00 00 00 00 C0 
2015-04-23 12:36:02.919 [DEBUG] [b.z.i.protocol.ZWaveController:1202]- Process Message = 01 14 00 04 00 04 0E 32 02 21 34 00 00 00 00 00 00 00 00 00 00 C0 
2015-04-23 12:36:02.924 [DEBUG] [b.z.i.protocol.ZWaveController:190 ]- Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 04 0E 32 02 21 34 00 00 00 00 00 00 00 00 00 00 
2015-04-23 12:36:02.924 [TRACE] [b.z.i.protocol.ZWaveController:210 ]- Incoming Message type = REQUEST
2015-04-23 12:36:02.924 [TRACE] [ApplicationCommandMessageClass:30  ]- Handle Message Application Command Request
2015-04-23 12:36:02.924 [DEBUG] [ApplicationCommandMessageClass:38  ]- NODE 4: Application Command Request (ALIVE:DONE)
2015-04-23 12:36:02.924 [DEBUG] [ApplicationCommandMessageClass:56  ]- NODE 4: Incoming command class METER
2015-04-23 12:36:02.924 [TRACE] [ApplicationCommandMessageClass:79  ]- NODE 4: Found Command Class METER, passing to handleApplicationCommandRequest
2015-04-23 12:36:02.929 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:109 ]- NODE 4: Received Meter Request
2015-04-23 12:36:02.929 [TRACE] [z.i.p.c.ZWaveMeterCommandClass:121 ]- NODE 4: Meter report received
2015-04-23 12:36:02.929 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:157 ]- NODE 4: Meter: Type=Electric(1), Scale=W(2), Value=0E+1
2015-04-23 12:36:02.929 [DEBUG] [b.z.i.protocol.ZWaveController:673 ]- Notifying event listeners: ZWaveMeterValueEvent
2015-04-23 12:36:02.929 [DEBUG] [.z.internal.ZWaveActiveBinding:433 ]- ZwaveIncomingEvent
2015-04-23 12:36:02.934 [DEBUG] [.z.internal.ZWaveActiveBinding:450 ]- NODE 4: Got a value event from Z-Wave network, endpoint = 0, command class = METER, value = 0E+1
2015-04-23 12:36:02.934 [WARN ] [.z.internal.ZWaveActiveBinding:467 ]- NODE 4: No item bound for event, endpoint = 0, command class = METER, value = 0E+1, ignoring.
2015-04-23 12:36:02.934 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:63  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 04 01 00 
2015-04-23 12:36:02.939 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:64  ]- Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 04 0E 32 02 21 34 00 00 00 00 00 00 00 00 00 00 
2015-04-23 12:36:02.939 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:65  ]- Checking transaction complete: class=ApplicationCommandHandler, expected=SendData, cancelled=false
2015-04-23 12:36:06.650 [TRACE] [veController$WatchDogTimerTask:1586]- Watchdog: Checking Serial threads