ZIP reference_listener not responding


#1

Hi

I have been trying to manage zwave device with the help of steps given at http://zwavepublic.com/developer

reference client works fine, where I am able to addnode, list, removenode and send commands to my zwave devices succesfully

but when I try to listen the commands sent from a device to controller through reference_listener
by sending
./reference_listener -l fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96

I get

PSK not configured - using default
Listening on fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 port 41230

And it just stucks there and does not show any status sent from devices

When I run logger I get the following

10089584 update flag 1 129
10093584 DHCP Timeout
10093584 Sending DISCOVER
10093584 DHCP pass completed
10093584 update flag 1 129
10094701 ApplicationCommandHandler 29->1 class 0x5b cmd 0x03 size 5
10094701 Sending Unsolicited to IP app...
tcpip_ipv6_output: nbr cache entry stale moving to delay
10097999 ApplicationCommandHandler 29->1 class 0x5b cmd 0x03 size 5
10097999 Sending Unsolicited to IP app...
10101585 DHCP Timeout
10101585 Sending DISCOVER
10101585 DHCP pass completed
10101585 update flag 1 129
10103209 Sending waiting
tcpip_ipv6_output: nbr cache entry stale moving to delay
10117587 DHCP Timeout
10117587 Sending DISCOVER
10117588 DHCP pass completed
10117588 update flag 1 129
10118862 ApplicationCommandHandler 29->1 class 0x5b cmd 0x03 size 5
10118864 Sending Unsolicited to IP app...
10119588 DHCP Timeout
10119588 Sending DISCOVER
10119588 DHCP pass completed
10119589 update flag 1 129
10119962 ApplicationCommandHandler 29->1 class 0x5b cmd 0x03 size 5
10119962 Sending Unsolicited to IP app...
10123588 DHCP Timeout
10123588 Sending DISCOVER
10221607 DHCP pass completed
10221607 update flag 1 129
10223087 Sending first attempt
10223087 DTLS: DTLS read failed
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10223088 
10223088 queue_send_done to node 29 queue 1
tcpip_ipv6_output: nbr cache entry stale moving to delay
10223088 DTLS: Closing DTLS connection
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10223089 
10223212 Sending waiting
10223212 New client session allocated 
10223213 Sending first attempt
10223214 DTLS: DTLS read failed
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10223214 
10223214 DTLS Classic node session package
10223214 queue_send_done to node 29 queue 1
10223215 Sending first attempt
10223218 DTLS: DTLS read failed
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10223218 
10223218 DTLS Classic node session package
10223218 queue_send_done to node 29 queue 1
10224294 Sending first attempt
10224294 DTLS: DTLS read failed
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10224294 
10224294 DTLS Classic node session package
10224294 queue_send_done to node 29 queue 1
10224295 Sending first attempt
10224295 DTLS: DTLS read failed
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10224295 
10224295 DTLS Classic node session package
10224295 queue_send_done to node 29 queue 1
10226374 Sending first attempt
10226374 DTLS: DTLS read failed
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10226374 
10226374 DTLS Classic node session package
10226374 queue_send_done to node 29 queue 1
10226374 Sending first attempt
10226375 DTLS: DTLS read failed
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10226375 
10226375 DTLS Classic node session package
10226375 queue_send_done to node 29 queue 1
tcpip_ipv6_output: nbr cache entry stale moving to delay
10230454 Sending first attempt
10230454 DTLS: DTLS read failed
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10230454 
10230454 DTLS Classic node session package
10230454 queue_send_done to node 29 queue 1
10230455 Sending first attempt
10230455 DTLS: DTLS read failed
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10230455 
10230455 DTLS Classic node session package
10230455 queue_send_done to node 29 queue 1
10237610 DHCP Timeout
10237610 Sending DISCOVER
10237610 DHCP pass completed
10237610 update flag 1 129
10238614 Sending first attempt
10238615 DTLS: DTLS read failed
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10238615 
10238615 DTLS Classic node session package
10238615 queue_send_done to node 29 queue 1
10238615 Sending first attempt
10238615 DTLS: DTLS read failed
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10238615 
10238615 DTLS Classic node session package
10238615 queue_send_done to node 29 queue 1
10239612 DHCP Timeout
10239612 Sending DISCOVER
10239612 DHCP pass completed
10239612 update flag 1 129
10243613 DHCP Timeout
10243613 Sending DISCOVER
10243613 DHCP pass completed
10243613 update flag 1 129
10251616 DHCP Timeout
10251620 Sending DISCOVER
10251620 DHCP pass completed
10251620 update flag 1 129
10255254 Sending first attempt
10255254 DTLS: DTLS read failed
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10255255 
10255255 DTLS Classic node session package
10255255 queue_send_done to node 29 queue 1
10255255 Sending first attempt
10255255 DTLS: DTLS read failed
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10255255 
10255255 DTLS Classic node session package
10255256 queue_send_done to node 29 queue 1
10267620 DHCP Timeout
10267620 Sending DISCOVER
10267620 DHCP pass completed
10267620 update flag 1 129
10269620 DHCP Timeout
10269620 Sending DISCOVER
10269620 DHCP pass completed
10269620 update flag 1 129
10273621 DHCP Timeout
10273621 Sending DISCOVER
10273621 DHCP pass completed
10273621 update flag 1 129
10281624 DHCP Timeout
10281624 Sending DISCOVER
10281624 DHCP pass completed
10281624 update flag 1 129
10283213 Sending waiting
10287894 Sending first attempt
10287895 DTLS: DTLS read failed
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10287895 
tcpip_ipv6_output: nbr cache entry stale moving to delay
10287896 DTLS Classic node session package
10287896 queue_send_done to node 29 queue 1
10287897 Sending first attempt
10287897 DTLS: DTLS read failed
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10287897 
10287898 DTLS Classic node session package
10287898 queue_send_done to node 29 queue 1
tcpip_ipv6_output: nbr cache entry stale moving to delay
10297625 DHCP Timeout
10297625 Sending DISCOVER
10297625 DHCP pass completed
10297625 update flag 1 129
10299625 DHCP Timeout
10299625 Sending DISCOVER
10299625 DHCP pass completed
10299625 update flag 1 129
10303625 DHCP Timeout
10303625 Sending DISCOVER
10303625 DHCP pass completed
10303625 update flag 1 129
10311627 DHCP Timeout
10311627 Sending DISCOVER
10311627 DHCP pass completed
10311627 update flag 1 129
10316023 Sending first attempt
10316023 DTLS: DTLS read failed
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10316023 
10316023 DTLS Classic node session package
10316023 queue_send_done to node 29 queue 1
10323030 Sending first attempt
10323030 DTLS: DTLS read failed
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10323030 
10323030 DTLS Classic node session package
10323030 queue_send_done to node 29 queue 1
10327627 DHCP Timeout
10327628 Sending DISCOVER
10327628 DHCP pass completed
10327628 update flag 1 129
10329628 DHCP Timeout
10329628 Sending DISCOVER
10329628 DHCP pass completed
10329629 update flag 1 129
10333628 DHCP Timeout
10333628 Sending DISCOVER
10333628 DHCP pass completed
10333629 update flag 1 129
10341629 DHCP Timeout
10341629 Sending DISCOVER
10341630 DHCP pass completed
10341630 update flag 1 129
10343213 Sending waiting
10350614 Sending first attempt
10350615 DTLS: DTLS read failed
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10350615 
tcpip_ipv6_output: nbr cache entry stale moving to delay
10350615 DTLS Classic node session package
10350615 queue_send_done to node 29 queue 1
10350616 Sending first attempt
10350616 DTLS: DTLS read failed
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10350616 
10350616 DTLS Classic node session package
10350616 queue_send_done to node 29 queue 1
10352231 Sending udp ping to ZIP client
10352731 Client seems to be offline, we will not send more waiting messages for him
tcpip_ipv6_output: nbr cache entry stale moving to delay
10357631 DHCP Timeout
10357632 Sending DISCOVER
10357632 DHCP pass completed
10357632 update flag 1 129
10359632 DHCP Timeout
10359632 Sending DISCOVER
10359632 DHCP pass completed
10359633 update flag 1 129
10363632 DHCP Timeout
10363632 Sending DISCOVER
10363633 DHCP pass completed
10363633 update flag 1 129
10371632 DHCP Timeout
10371632 Sending DISCOVER
10371633 DHCP pass completed
10371633 update flag 1 129
10387633 DHCP Timeout
10387634 Sending DISCOVER
10387634 DHCP pass completed
10387634 update flag 1 129
10389634 DHCP Timeout
10389635 Sending DISCOVER
10389635 DHCP pass completed
10389635 update flag 1 129
10393635 DHCP Timeout
10393635 Sending DISCOVER
10393635 DHCP pass completed
10393635 update flag 1 129
10401635 DHCP Timeout
10401636 Sending DISCOVER
10401636 DHCP pass completed
10401636 update flag 1 129
10410616 DTLS: Session timeout
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10410616 
10410617 DTLS: Closing DTLS connection
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10410617 
10412055 Sending first attempt
10412055 New server session allocated 
10412055 DTLS: DTLS read failed
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10412055 
10412055 DTLS Classic node session package
10412055 queue_send_done to node 29 queue 1
10412056 Sending first attempt
10412056 DTLS: DTLS read failed
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10412056 
10412056 DTLS Classic node session package
10412056 queue_send_done to node 29 queue 1
tcpip_ipv6_output: nbr cache entry stale moving to delay
10417636 DHCP Timeout
10417636 Sending DISCOVER
10417636 DHCP pass completed
10417637 update flag 1 129
10419636 DHCP Timeout
10419637 Sending DISCOVER
10419637 DHCP pass completed
10419637 update flag 1 129
10423638 DHCP Timeout
10423639 Sending DISCOVER
10423639 DHCP pass completed
10423639 update flag 1 129
10431640 DHCP Timeout
10431640 Sending DISCOVER
10431640 DHCP pass completed
10431640 update flag 1 129
10447641 DHCP Timeout
10447642 Sending DISCOVER
10447642 DHCP pass completed
10447642 update flag 1 129
10449642 DHCP Timeout
10449643 Sending DISCOVER
10449643 DHCP pass completed
10449643 update flag 1 129
10453644 DHCP Timeout
10453645 Sending DISCOVER
10453645 DHCP pass completed
10453645 update flag 1 129
10461648 DHCP Timeout
10461648 Sending DISCOVER
10461648 DHCP pass completed
10461648 update flag 1 129
10472056 DTLS: Session timeout
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10472057 
10472057 DTLS: Closing DTLS connection
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10472057 
10473495 Sending first attempt
10473495 New server session allocated 
10473495 DTLS: DTLS read failed
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10473496 
10473496 DTLS Classic node session package
10473496 queue_send_done to node 29 queue 1
10473496 Sending first attempt
10473496 DTLS: DTLS read failed
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
10473496 
10473496 DTLS Classic node session package
10473496 queue_send_done to node 29 queue 1
10477654 DHCP Timeout
10477654 Sending DISCOVER
10477654 DHCP pass completed
10477654 update flag 1 129
tcpip_ipv6_output: nbr cache entry stale moving to delay

Also sometimes when I try to recreate it, I see different port number with same ipv6 address, I tried changing to those port number by adding -o but still no luck

However it did work once magically with same reference_listener command shown above. but only for 2 minutes and again it went quite.

I tried same with two devices with two different clean images

  1. Fibaro Motion Sensor
  2. Aeotec Wallmote

Below is my config file
ZipUnsolicitedDestinationIp6 = fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96
ZipUnsolicitedDestinationPort = 41230
#ZipUnsolicitedDestination2Ip6 = fd00:a622:3f5d:4590:ba27:ebff:fed3:5eb5
#ZipUnsolicitedDestination2Port = 41231
#SerialLog = /tmp/ziprouter.serlog
ZipCaCert=/usr/local/etc/Portal.ca_x509.pem
ZipCert=/usr/local/etc/ZIPR.x509_1024.pem
ZipPrivKey=/usr/local/etc/ZIPR.key_1024.pem
#Eepromfile=/etc/eeprom.dat
#TunScript=/usr/local/etc/zipgateway.tun
ZipLanGw6 = fd00:a622:3f5d:4590::1234
#ZipPortal=192.168.1.1
#ZipPortalPort=44123
#ZipTunPrefix = 2000::
#ZipTunIp6PrefixLength = 128
#ZipManufacturerID=0
#ZipHardwareVersion=1
#ZipProductID=1
#ZipProductType=1
#ZipMBPort=41230
#ZipMBDestinationIp6=
#ZipMBMode=1
ZipPSK=123456789012345678901234567890AA
#ExtraClasses= 0x43 0x75
ZipSerialAPIPortName=/dev/ttyACM0
ZipLanIp6 = fd00:a622:3f5d:4590::3
ZipPanIp6 = fd00:ab45:754f:c15d::1
ExtraClasses = 0x8F 0x85 0x59 0x5A 0xF100

Also in about every 30 min my wallmote blinks yellow light and in logger I see

11601875 DHCP pass completed
11601875 update flag 1 129
11604571 ApplicationCommandHandler 29->1 class 0x84 cmd 0x07 size 2
11604571 MB_STATE_WAKEUP
11604571 Node was last awake at 11125 the clock is 11604 the interval should be 4080 
11604571 Wakeup notification received ahead of time
11604571 Sending 1->29, class: 0x84, cmd: 0x5
11604572 Sending with scheme 255
11604613 We now have 2 frames in the mailbox - sending
11604613 zw.scheme: 255
fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96
11604613 Sending 1->29, class: 0x0, cmd: 0x2
11604614 Sending with scheme 255
11604641 unsolicited_senddata_callback status 0
11604641 New client session allocated 
tcpip_ipv6_output: nbr cache entry stale moving to delay
11604641 Done sending mailbox item status is 0
11604641 We now have 1 frames in the mailbox - sending
11604641 zw.scheme: 255
fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96
11604642 Sending 1->29, class: 0x0, cmd: 0x2
11604643 Sending with scheme 255
11604652 Sending first attempt
11604652 Reque from node_queue
11604652 queue_send_done to node 29 queue 1
11604653 Frame put in mailbox
11604653 We now have 2 frames in the mailbox src port 41230 dst port 41230 3745
11604669 unsolicited_senddata_callback status 0
11604669 Done sending mailbox item status is 0
11604669 We now have 1 frames in the mailbox - sending
11604669 Dropping packet received on Z-Wave port: Not ZIP encapped
11604669 Done sending mailbox item status is 255
11607643 DTLS: Session timeout
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
11607644 
11607644 DTLS: Closing DTLS connection
lipaddr: fd00:ab45:754f:c15d::001d lport: 41230
ripaddr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96 rport: 41230
11607644 
11607669 No more info for node 29 putting to sleep
11607669 Sending 1->29, class: 0x84, cmd: 0x8
11607670 Sending with scheme 255
tcpip_ipv6_output: nbr cache entry stale moving to delay
11617878 DHCP Timeout

I believe there is some ipv6 port mismatch hapening between config file and zip

Request somebody to help

Thanks in Advance


#2

my network configuration

ifconfig
br-lan    Link encap:Ethernet  
          inet6 addr: fd00:a622:3f5d:4590:e2c2:58b0:54e8:4c96/64 Scope:Global
          inet6 addr: fe80::8735:efbf:e8a5:6876/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:3462 errors:0 dropped:0 overruns:0 frame:0
          TX packets:2093 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:680394 (664.4 KiB)  TX bytes:351485 (343.2 KiB)

br-lan:avahi Link encap:Ethernet 
          inet addr:169.254.10.120  Bcast:169.254.255.255  Mask:255.255.0.0
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1

eth0      Link encap:Ethernet  
          UP BROADCAST MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:0 (0.0 B)

lo        Link encap:Local Loopback  
          inet addr:127.0.0.1  Mask:255.0.0.0
          inet6 addr: ::1/128 Scope:Host
          UP LOOPBACK RUNNING  MTU:65536  Metric:1
          RX packets:4 errors:0 dropped:0 overruns:0 frame:0
          TX packets:4 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1 
          RX bytes:416 (416.0 B)  TX bytes:416 (416.0 B)

tap0      Link encap:Ethernet  
          inet addr:169.254.27.232  Bcast:169.254.255.255  Mask:255.255.0.0
          inet6 addr: fe80::7f24:9568:dee4:60e1/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:3483 errors:0 dropped:0 overruns:0 frame:0
          TX packets:8640 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:735404 (718.1 KiB)  TX bytes:766228 (748.2 KiB)

wlan0     Link encap:Ethernet  
          inet addr:192.168.0.106  Bcast:192.168.0.255  Mask:255.255.255.0
          inet6 addr: fe80::1533:f635:8f99:f935/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:4590 errors:0 dropped:0 overruns:0 frame:0
          TX packets:2640 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:615352 (600.9 KiB)  TX bytes:471482 (460.4 KiB)

#3

Hi,

Do you see any DTLS handshake or any IPV6 packets on wireshark at all?