Hue bridge scanning doesn't work anymore

Since a few OpenhHAB2 snapshots the scanning for hue bridges doesn’t work anymore. No bridges are found.
Also manually adding doesn’t work. There is an error “Authentication failed - remove user name from configuration to generate a new one.” but the user name field is already empty.

Regards, Norbert

It works well for me.
Can you delete all your bridges and start the discovery? Can you provide logs from the hue binding?

Here are the logs after a new discovery:

2016-02-02 10:14:17.305 [WARN ] [javax.jmdns.impl.DNSIncoming        ] - DNSIncoming() dump dns[query,192.168.178.20:5353, length=443, id=0x0, flags=0x400:aa, questions=3, authorities=5 questions:
    [AllRecords@412518049 type: TYPE_ANY index 255, class: CLASS_IN index 1-unique, name: _hap._tcp.local.]
    [AllRecords@605395199 type: TYPE_ANY index 255, class: CLASS_IN index 1-unique, name: Philips-hue.local.]
    [AllRecords@1057964040 type: TYPE_ANY index 255, class: CLASS_IN index 1-unique, name: Philips hue - 2066A5._hap._tcp.local.] authorities:
    [IPv4Address@384960976 type: TYPE_A index 1, class: CLASS_IN index 1, name: Philips-hue.local. ttl: '119/120' address: '192.168.178.20']
    [Pointer@989668897 type: TYPE_PTR index 12, class: CLASS_IN index 1, name: _hap._tcp.local. ttl: '4499/4500' alias: 'Philips hue - 2066A5._hap._tcp.local.']
    [Text@849839073 type: TYPE_TXT index 16, class: CLASS_IN index 1, name: Philips hue - 2066A5._hap._tcp.local. ttl: '4499/4500' text: 'c#=1ff=1id=00:...']
    [Service@2003667645 type: TYPE_SRV index 33, class: CLASS_IN index 1, name: Philips hue - 2066A5._hap._tcp.local. ttl: '120/120' server: 'Philips-hue.local.:8080']
    [IPv6Address@1901568265 type: TYPE_AAAA index 28, class: CLASS_IN index 1, name: Philips-hue.local. ttl: '120/120' address: 'fe80:0:0:0:217:88ff:fe20:66a5']]
    question:      [AllRecords@412518049 type: TYPE_ANY index 255, class: CLASS_IN index 1-unique, name: _hap._tcp.local.]
    question:      [AllRecords@605395199 type: TYPE_ANY index 255, class: CLASS_IN index 1-unique, name: Philips-hue.local.]
    question:      [AllRecords@1057964040 type: TYPE_ANY index 255, class: CLASS_IN index 1-unique, name: Philips hue - 2066A5._hap._tcp.local.]
    authoritative: [IPv4Address@384960976 type: TYPE_A index 1, class: CLASS_IN index 1, name: Philips-hue.local. ttl: '119/120' address: '192.168.178.20']
    authoritative: [Pointer@989668897 type: TYPE_PTR index 12, class: CLASS_IN index 1, name: _hap._tcp.local. ttl: '4499/4500' alias: 'Philips hue - 2066A5._hap._tcp.local.']
    authoritative: [Text@849839073 type: TYPE_TXT index 16, class: CLASS_IN index 1, name: Philips hue - 2066A5._hap._tcp.local. ttl: '4499/4500' text: 'c#=1ff=1id=00:...']
    authoritative: [Service@2003667645 type: TYPE_SRV index 33, class: CLASS_IN index 1, name: Philips hue - 2066A5._hap._tcp.local. ttl: '119/120' server: 'Philips-hue.local.:8080']
    authoritative: [IPv6Address@1901568265 type: TYPE_AAAA index 28, class: CLASS_IN index 1, name: Philips-hue.local. ttl: '119/120' address: 'fe80:0:0:0:217:88ff:fe20:66a5']
   0: 0000040000030000 00050000045f6861 70045f746370056c 6f63616c0000ff80     ........ ....._ha p._tcp.l ocal....
  20: 010b5068696c6970 732d687565056c6f 63616c0000ff8001 145068696c697073     ..Philip s-hue.lo cal..... .Philips
  40: 20687565202d2032 3036364135045f68 6170045f74637005 6c6f63616c0000ff     .hue.-.2 066A5._h ap._tcp. local...
  60: 80010b5068696c69 70732d687565056c 6f63616c00000100 01000000780004c0     ...Phili ps-hue.l ocal.... ....x...
  80: a8b214045f686170 045f746370056c6f 63616c00000c0001 0000119400261450     ...._hap ._tcp.lo cal..... .....&.P
  a0: 68696c6970732068 7565202d20323036 364135045f686170 045f746370056c6f     hilips.h ue.-.206 6A5._hap ._tcp.lo
  c0: 63616c0014506869 6c69707320687565 202d203230363641 35045f686170045f     cal..Phi lips.hue .-.2066A 5._hap._
  e0: 746370056c6f6361 6c00001000010000 1194003f0463233d 310466663d311469     tcp.loca l....... ...?.c#= 1.ff=1.i
 100: 643d30303a31373a 38383a32303a3636 3a4135096d643d42 5342303032067076     d=00:17: 88:20:66 :A5.md=B SB002.pv
 120: 3d312e300473663d 300473233d310463 693d32145068696c 6970732068756520     =1.0.sf= 0.s#=1.c i=2.Phil ips.hue.
 140: 2d20323036364135 045f686170045f74 6370056c6f63616c 0000210001000000     -.2066A5 ._hap._t cp.local ..!.....
 160: 780019000000001f 900b5068696c6970 732d687565056c6f 63616c000b506869     x....... ..Philip s-hue.lo cal..Phi
 180: 6c6970732d687565 056c6f63616c0000 1c00010000007800 10fe800000000000     lips-hue .local.. ......x. ........
 1a0: 00021788fffe2066 a500000000000000 0000000000000000 000000               .......f ........ ........ ...

 exception 
java.io.IOException: Received a message with the wrong length.
at javax.jmdns.impl.DNSIncoming.<init>(DNSIncoming.java:256)[160:org.openhab.jmdns:3.4.2]
at javax.jmdns.impl.SocketListener.run(SocketListener.java:50)[160:org.openhab.jmdns:3.4.2]
2016-02-02 10:14:17.305 [WARN ] [javax.jmdns.impl.DNSIncoming        ] - DNSIncoming() dump dns[query,192.168.178.20:5353, length=443, id=0x0, flags=0x400:aa, questions=3, authorities=5 questions:
    [AllRecords@102437858 type: TYPE_ANY index 255, class: CLASS_IN index 1-unique, name: _hap._tcp.local.]
    [AllRecords@791410797 type: TYPE_ANY index 255, class: CLASS_IN index 1-unique, name: Philips-hue.local.]
    [AllRecords@758560426 type: TYPE_ANY index 255, class: CLASS_IN index 1-unique, name: Philips hue - 2066A5._hap._tcp.local.] authorities:
    [IPv4Address@281639765 type: TYPE_A index 1, class: CLASS_IN index 1, name: Philips-hue.local. ttl: '120/120' address: '192.168.178.20']
    [Pointer@1318317741 type: TYPE_PTR index 12, class: CLASS_IN index 1, name: _hap._tcp.local. ttl: '4500/4500' alias: 'Philips hue - 2066A5._hap._tcp.local.']
    [Text@1040949122 type: TYPE_TXT index 16, class: CLASS_IN index 1, name: Philips hue - 2066A5._hap._tcp.local. ttl: '4500/4500' text: 'c#=1ff=1id=00:...']
    [Service@647036175 type: TYPE_SRV index 33, class: CLASS_IN index 1, name: Philips hue - 2066A5._hap._tcp.local. ttl: '120/120' server: 'Philips-hue.local.:8080']
    [IPv6Address@2102933851 type: TYPE_AAAA index 28, class: CLASS_IN index 1, name: Philips-hue.local. ttl: '120/120' address: 'fe80:0:0:0:217:88ff:fe20:66a5']]
    question:      [AllRecords@102437858 type: TYPE_ANY index 255, class: CLASS_IN index 1-unique, name: _hap._tcp.local.]
    question:      [AllRecords@791410797 type: TYPE_ANY index 255, class: CLASS_IN index 1-unique, name: Philips-hue.local.]
    question:      [AllRecords@758560426 type: TYPE_ANY index 255, class: CLASS_IN index 1-unique, name: Philips hue - 2066A5._hap._tcp.local.]
    authoritative: [IPv4Address@281639765 type: TYPE_A index 1, class: CLASS_IN index 1, name: Philips-hue.local. ttl: '119/120' address: '192.168.178.20']
    authoritative: [Pointer@1318317741 type: TYPE_PTR index 12, class: CLASS_IN index 1, name: _hap._tcp.local. ttl: '4499/4500' alias: 'Philips hue - 2066A5._hap._tcp.local.']
    authoritative: [Text@1040949122 type: TYPE_TXT index 16, class: CLASS_IN index 1, name: Philips hue - 2066A5._hap._tcp.local. ttl: '4499/4500' text: 'c#=1ff=1id=00:...']
    authoritative: [Service@647036175 type: TYPE_SRV index 33, class: CLASS_IN index 1, name: Philips hue - 2066A5._hap._tcp.local. ttl: '119/120' server: 'Philips-hue.local.:8080']
    authoritative: [IPv6Address@2102933851 type: TYPE_AAAA index 28, class: CLASS_IN index 1, name: Philips-hue.local. ttl: '119/120' address: 'fe80:0:0:0:217:88ff:fe20:66a5']
   0: 0000040000030000 00050000045f6861 70045f746370056c 6f63616c0000ff80     ........ ....._ha p._tcp.l ocal....
  20: 010b5068696c6970 732d687565056c6f 63616c0000ff8001 145068696c697073     ..Philip s-hue.lo cal..... .Philips
  40: 20687565202d2032 3036364135045f68 6170045f74637005 6c6f63616c0000ff     .hue.-.2 066A5._h ap._tcp. local...
  60: 80010b5068696c69 70732d687565056c 6f63616c00000100 01000000780004c0     ...Phili ps-hue.l ocal.... ....x...
  80: a8b214045f686170 045f746370056c6f 63616c00000c0001 0000119400261450     ...._hap ._tcp.lo cal..... .....&.P
  a0: 68696c6970732068 7565202d20323036 364135045f686170 045f746370056c6f     hilips.h ue.-.206 6A5._hap ._tcp.lo
  c0: 63616c0014506869 6c69707320687565 202d203230363641 35045f686170045f     cal..Phi lips.hue .-.2066A 5._hap._
  e0: 746370056c6f6361 6c00001000010000 1194003f0463233d 310466663d311469     tcp.loca l....... ...?.c#= 1.ff=1.i
 100: 643d30303a31373a 38383a32303a3636 3a4135096d643d42 5342303032067076     d=00:17: 88:20:66 :A5.md=B SB002.pv
 120: 3d312e300473663d 300473233d310463 693d32145068696c 6970732068756520     =1.0.sf= 0.s#=1.c i=2.Phil ips.hue.
 140: 2d20323036364135 045f686170045f74 6370056c6f63616c 0000210001000000     -.2066A5 ._hap._t cp.local ..!.....
 160: 780019000000001f 900b5068696c6970 732d687565056c6f 63616c000b506869     x....... ..Philip s-hue.lo cal..Phi
 180: 6c6970732d687565 056c6f63616c0000 1c00010000007800 10fe800000000000     lips-hue .local.. ......x. ........
 1a0: 00021788fffe2066 a500000000000000 0000000000000000 000000               .......f ........ ........ ...

     exception 
    java.io.IOException: Received a message with the wrong length.
    at javax.jmdns.impl.DNSIncoming.<init>(DNSIncoming.java:256)[160:org.openhab.jmdns:3.4.2]
    at javax.jmdns.impl.SocketListener.run(SocketListener.java:50)[160:org.openhab.jmdns:3.4.2]
2016-02-02 10:14:17.306 [WARN ] [javax.jmdns.impl.SocketListener     ] - SocketListener(NexusH.local.).run() exception 
java.io.IOException: DNSIncoming corrupted message
    at javax.jmdns.impl.DNSIncoming.<init>(DNSIncoming.java:261)[160:org.openhab.jmdns:3.4.2]
    at javax.jmdns.impl.SocketListener.run(SocketListener.java:50)[160:org.openhab.jmdns:3.4.2]
Caused by: java.io.IOException: Received a message with the wrong length.
    at javax.jmdns.impl.DNSIncoming.<init>(DNSIncoming.java:256)[160:org.openhab.jmdns:3.4.2]
    ... 1 more
2016-02-02 10:14:17.307 [WARN ] [javax.jmdns.impl.SocketListener     ] - SocketListener(NexusH.local.).run() exception 
java.io.IOException: DNSIncoming corrupted message
    at javax.jmdns.impl.DNSIncoming.<init>(DNSIncoming.java:261)[160:org.openhab.jmdns:3.4.2]
    at javax.jmdns.impl.SocketListener.run(SocketListener.java:50)[160:org.openhab.jmdns:3.4.2]
Caused by: java.io.IOException: Received a message with the wrong length.
    at javax.jmdns.impl.DNSIncoming.<init>(DNSIncoming.java:256)[160:org.openhab.jmdns:3.4.2]
    ... 1 more

Note that Hue discovery works through UPnP, so just ignore all jMDNS log messages.
You will have to adapt the logging to get debug info from the Hue binding (see here about how to use log:set).

I have set the debug option to hue and discovered again. These are the only hue lines in the log:

2016-02-02 16:12:05.423 [DEBUG] [org.eclipse.smarthome.binding.hue   ] - BundleEvent STARTING - org.eclipse.smarthome.binding.hue
2016-02-02 16:12:05.442 [DEBUG] [org.eclipse.smarthome.binding.hue   ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={component.name=org.eclipse.smarthome.binding.hue.internal.factory.HueThingHandlerFactory, component.id=103, service.id=206, service.bundleid=143, service.scope=bundle} - org.eclipse.smarthome.binding.hue
2016-02-02 16:12:05.444 [DEBUG] [org.eclipse.smarthome.binding.hue   ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.UpnpDiscoveryParticipant}={component.name=org.eclipse.smarthome.binding.hue.discovery.bridge, component.id=104, service.id=207, service.bundleid=143, service.scope=bundle} - org.eclipse.smarthome.binding.hue
2016-02-02 16:12:05.445 [DEBUG] [org.eclipse.smarthome.binding.hue   ] - BundleEvent STARTED - org.eclipse.smarthome.binding.hue

Btw why has the hue binding the version 0.8.0 and not 2.0.0 or 1.9.0?

c:\openHAB2\runtime\karaf\system\org\eclipse\smarthome\binding\org.eclipse.smarthome.binding.hue\0.8.0-SNAPSHOT\org.eclipse.smarthome.binding.hue-0.8.0-SNAPSHOT.jar

It all works smoothly for me (tried on beta2):
Doing a

smarthome:discovery start hue

on the console, I then find in the log:

11:54:02.969 [INFO ] [smarthome.event.InboxUpdatedEvent   ] - Discovery Result with UID 'hue:bridge:001788142732' has been updated.
11:54:02.996 [INFO ] [smarthome.event.InboxUpdatedEvent   ] - Discovery Result with UID 'hue:bridge:001788141b41' has been updated.

Because it is a binding from ESH, which currently has the version 0.8.0.

Thank you Kai for your support. I found the culprit. The latest Fritz Box 7490 update seems to have an error in multicast forwarding. With wireshark I saw only ssdp-notify messages from the hue, but no reaction on m-search. Now I have connected the hue and the openhab-computer to a separate switch and the hue is discovered immediately.

Great, I am glad that it is working now!
But a bug in the 7490 does not sound good - is this a general bug for all FritzBox users with the latest firmware?

Don’t know, it was a labor version. The newest 6.51 released today, is working again.

1 Like