Problems with Jeelink (no devices)

sp_ky · December 28, 2017, 9:32am

Hey there,

i got a problem with me jeelink. It worked fine under FHEM but in OH2 there are some issues.
Jeelink on dev/ttyUSB0. Binding 2.2.0 installed. Jeelink is online, but not able to find any device.

Raspberry2 with openhabian (installed 2 days ago)

configured the serial-driver on cmd in openhab:

feature:install openhab-transport-serial

Greetings

vbier · December 28, 2017, 7:09pm

If you are on 2.2.0, there is no need to install anything in the console. Simply open paper ui and install the binding from there.
Are there any errors in the log? Please enable TRACE logging from the openHAB console, start the discovery again and paste the relevant part of the log?

sp_ky · December 30, 2017, 4:01pm

So I have to delete the entries i made?

2017-12-30 16:58:45.206 [DEBUG] [.eclipse.jetty.server.HttpConnection] - HttpConnection@713f44[SelectChannelEndPoint@1f272d9{/192.168.0.15:55389<->8080,Open,in,out,-,-,0/30000,HttpConnection@713f44}{io=0/0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator@11b191e{s=START},c=HttpChannelOverHttp@1420b27{r=10,c=false,a=IDLE,uri=null}] filled 454 HeapByteBuffer@19551e8[p=0,l=454,c=8192,r=454]={<<<POST /rest/discov...gId":"jeelink"}>>>\n\r\n6c-aaac-b6e280...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}

2017-12-30 16:58:45.208 [DEBUG] [.eclipse.jetty.server.HttpConnection] - HttpConnection@713f44[SelectChannelEndPoint@1f272d9{/192.168.0.15:55389<->8080,Open,in,out,-,-,2/30000,HttpConnection@713f44}{io=0/0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator@11b191e{s=START},c=HttpChannelOverHttp@1420b27{r=10,c=false,a=IDLE,uri=null}] parse HeapByteBuffer@19551e8[p=0,l=454,c=8192,r=454]={<<<POST /rest/discov...gId":"jeelink"}>>>\n\r\n6c-aaac-b6e280...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} {}

2017-12-30 16:58:45.209 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - parseNext s=START HeapByteBuffer@19551e8[p=0,l=454,c=8192,r=454]={<<<POST /rest/discov...gId":"jeelink"}>>>\n\r\n6c-aaac-b6e280...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}

2017-12-30 16:58:45.250 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - REQUEST for //192.168.0.5:8080/rest/discovery/bindings/jeelink/scan on HttpChannelOverHttp@1420b27{r=11,c=false,a=IDLE,uri=//192.168.0.5:8080/rest/discovery/bindings/jeelink/scan}

POST //192.168.0.5:8080/rest/discovery/bindings/jeelink/scan HTTP/1.1

2017-12-30 16:58:45.253 [DEBUG] [.eclipse.jetty.server.HttpConnection] - HttpConnection@713f44[SelectChannelEndPoint@1f272d9{/192.168.0.15:55389<->8080,Open,in,out,-,-,48/30000,HttpConnection@713f44}{io=0/0,kio=0,kro=1}][p=HttpParser{s=CONTENT,0 of 23},g=HttpGenerator@11b191e{s=START},c=HttpChannelOverHttp@1420b27{r=11,c=false,a=IDLE,uri=//192.168.0.5:8080/rest/discovery/bindings/jeelink/scan}] parsed true HttpParser{s=CONTENT,0 of 23}

2017-12-30 16:58:45.254 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@1420b27{r=11,c=false,a=IDLE,uri=//192.168.0.5:8080/rest/discovery/bindings/jeelink/scan} handle //192.168.0.5:8080/rest/discovery/bindings/jeelink/scan 

2017-12-30 16:58:45.257 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@1420b27{r=11,c=false,a=DISPATCHED,uri=//192.168.0.5:8080/rest/discovery/bindings/jeelink/scan} action DISPATCH

2017-12-30 16:58:45.259 [DEBUG] [org.eclipse.jetty.server.Server     ] - REQUEST POST /rest/discovery/bindings/jeelink/scan on HttpChannelOverHttp@1420b27{r=11,c=false,a=DISPATCHED,uri=//192.168.0.5:8080/rest/discovery/bindings/jeelink/scan}

2017-12-30 16:58:45.260 [DEBUG] [ax.web.service.spi.model.ServerModel] - Matching [/rest/discovery/bindings/jeelink/scan]...

2017-12-30 16:58:45.262 [DEBUG] [ax.web.service.spi.model.ServerModel] - Path [/rest/discovery/bindings/jeelink/scan] matched to {pattern=/rest/.*,model=ServletModel{id=org.ops4j.pax.web.service.spi.model.ServletModel-12,name=org.ops4j.pax.web.service.spi.model.ServletModel-12,urlPatterns=[/rest/*],alias=/rest,servlet=com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge@477812,initParams={},context=ContextModel{id=org.ops4j.pax.web.service.spi.model.ContextModel-11,name=,httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [15], contextID=default],contextParams={},virtualHosts={},connectors={}}}}

2017-12-30 16:58:45.263 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery/bindings/jeelink/scan @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [15], contextID=default]}

2017-12-30 16:58:45.265 [DEBUG] [.jetty.server.handler.ContextHandler] - context=||/rest/discovery/bindings/jeelink/scan @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [15], contextID=default]}

2017-12-30 16:58:45.271 [DEBUG] [eclipse.jetty.servlet.ServletHandler] - servlet |/rest|/discovery/bindings/jeelink/scan -> org.ops4j.pax.web.service.spi.model.ServletModel-12@b9f03e59==com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge,jsp=null,order=-1,inst=true

2017-12-30 16:58:45.272 [DEBUG] [ce.jetty.internal.HttpServiceContext] - Handling request for [/rest/discovery/bindings/jeelink/scan] using http context [DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [15], contextID=default]]

2017-12-30 16:58:45.290 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - COMMIT for /rest/discovery/bindings/jeelink/scan on HttpChannelOverHttp@1420b27{r=11,c=true,a=DISPATCHED,uri=//192.168.0.5:8080/rest/discovery/bindings/jeelink/scan}

2017-12-30 16:58:45.304 [DEBUG] [org.eclipse.jetty.server.Server     ] - handled=true async=false committed=true on HttpChannelOverHttp@1420b27{r=11,c=true,a=DISPATCHED,uri=//192.168.0.5:8080/rest/discovery/bindings/jeelink/scan}

2017-12-30 16:58:45.306 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@1420b27{r=11,c=true,a=COMPLETING,uri=//192.168.0.5:8080/rest/discovery/bindings/jeelink/scan} action COMPLETE

2017-12-30 16:58:45.307 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - COMPLETE for /rest/discovery/bindings/jeelink/scan written=2

2017-12-30 16:58:45.308 [DEBUG] [.eclipse.jetty.server.HttpConnection] - unconsumed input HttpConnection@713f44[SelectChannelEndPoint@1f272d9{/192.168.0.15:55389<->8080,Open,in,out,-,-,11/30000,HttpConnection@713f44}{io=0/0,kio=0,kro=1}][p=HttpParser{s=CONTENT,0 of 23},g=HttpGenerator@11b191e{s=END},c=HttpChannelOverHttp@1420b27{r=11,c=true,a=COMPLETED,uri=//192.168.0.5:8080/rest/discovery/bindings/jeelink/scan}]

2017-12-30 16:58:45.310 [DEBUG] [.eclipse.jetty.server.HttpConnection] - HttpConnection@713f44[SelectChannelEndPoint@1f272d9{/192.168.0.15:55389<->8080,Open,in,out,-,-,13/30000,HttpConnection@713f44}{io=0/0,kio=0,kro=1}][p=HttpParser{s=CONTENT,0 of 23},g=HttpGenerator@11b191e{s=END},c=HttpChannelOverHttp@1420b27{r=11,c=true,a=COMPLETED,uri=//192.168.0.5:8080/rest/discovery/bindings/jeelink/scan}] parse HeapByteBuffer@19551e8[p=431,l=454,c=8192,r=23]={POST /rest/discov... keep-alive\r\n\r\n<<<{"bindingId":"jeelink"}>>>\n\r\n6c-aaac-b6e280...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} {}

2017-12-30 16:58:45.311 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - parseNext s=CONTENT HeapByteBuffer@19551e8[p=431,l=454,c=8192,r=23]={POST /rest/discov... keep-alive\r\n\r\n<<<{"bindingId":"jeelink"}>>>\n\r\n6c-aaac-b6e280...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}

2017-12-30 16:58:45.313 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@1420b27{r=11,c=true,a=COMPLETED,uri=//192.168.0.5:8080/rest/discovery/bindings/jeelink/scan} content Content@1649377{HeapByteBufferR@59a2b3[p=431,l=454,c=8192,r=23]={POST /rest/discov... keep-alive\r\n\r\n<<<{"bindingId":"jeelink"}>>>\n\r\n6c-aaac-b6e280...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}}

2017-12-30 16:58:45.315 [DEBUG] [org.eclipse.jetty.server.HttpInput  ] - HttpInputOverHTTP@fe4a8b[c=0,q=1,[0]=Content@1649377{HeapByteBufferR@59a2b3[p=431,l=454,c=8192,r=23]={POST /rest/discov... keep-alive\r\n\r\n<<<{"bindingId":"jeelink"}>>>\n\r\n6c-aaac-b6e280...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}},s=STREAM] addContent Content@1649377{HeapByteBufferR@59a2b3[p=431,l=454,c=8192,r=23]={POST /rest/discov... keep-alive\r\n\r\n<<<{"bindingId":"jeelink"}>>>\n\r\n6c-aaac-b6e280...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}}

2017-12-30 16:58:45.317 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@1420b27{r=11,c=true,a=COMPLETED,uri=//192.168.0.5:8080/rest/discovery/bindings/jeelink/scan} onContentComplete

2017-12-30 16:58:45.318 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@1420b27{r=11,c=true,a=COMPLETED,uri=//192.168.0.5:8080/rest/discovery/bindings/jeelink/scan} onRequestComplete

2017-12-30 16:58:45.319 [DEBUG] [org.eclipse.jetty.server.HttpInput  ] - HttpInputOverHTTP@fe4a8b[c=0,q=2,[0]=Content@1649377{HeapByteBufferR@59a2b3[p=431,l=454,c=8192,r=23]={POST /rest/discov... keep-alive\r\n\r\n<<<{"bindingId":"jeelink"}>>>\n\r\n6c-aaac-b6e280...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}},s=STREAM] addContent EOF

2017-12-30 16:58:45.321 [DEBUG] [.eclipse.jetty.server.HttpConnection] - HttpConnection@713f44[SelectChannelEndPoint@1f272d9{/192.168.0.15:55389<->8080,Open,in,out,-,-,24/30000,HttpConnection@713f44}{io=0/0,kio=0,kro=1}][p=HttpParser{s=END,23 of 23},g=HttpGenerator@11b191e{s=END},c=HttpChannelOverHttp@1420b27{r=11,c=true,a=COMPLETED,uri=//192.168.0.5:8080/rest/discovery/bindings/jeelink/scan}] parsed false HttpParser{s=END,23 of 23}

2017-12-30 16:58:45.323 [DEBUG] [.eclipse.jetty.server.HttpConnection] - releaseRequestBuffer HttpConnection@713f44[SelectChannelEndPoint@1f272d9{/192.168.0.15:55389<->8080,Open,in,out,-,-,25/30000,HttpConnection@713f44}{io=0/0,kio=0,kro=1}][p=HttpParser{s=END,23 of 23},g=HttpGenerator@11b191e{s=END},c=HttpChannelOverHttp@1420b27{r=11,c=true,a=COMPLETED,uri=//192.168.0.5:8080/rest/discovery/bindings/jeelink/scan}]

2017-12-30 16:58:45.324 [DEBUG] [org.eclipse.jetty.server.HttpInput  ] - HttpInputOverHTTP@fe4a8b[c=23,q=1,[0]=EOF,s=STREAM] consumed Content@1649377{HeapByteBufferR@59a2b3[p=454,l=454,c=8192,r=0]={POST /rest/discov...gId":"jeelink"}<<<>>>\n\r\n6c-aaac-b6e280...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}

vbier · December 31, 2017, 9:07am

I am not sure if you have to revert the changes, but there is no need to do that any more. Most likely they will do no harm.

Connect to the openHAB console and type:

log:set TRACE org.openhab.binding.jeelink

to enable TRACE logging for the jeelink binding. Restart the discovery and paste the relevant part of the log.

sp_ky · December 31, 2017, 9:14am

didnt know, what is interessting for you and there is a lot of Logfile

    2017-12-31 10:20:02.139 [TRACE] [jersey.process.internal.RequestScope] - [DEBUG] Released scope instance Instance{id=3e66b9cd-5199-47be-8f78-4fe9333e2b01, referenceCounter=0, store size=0} on thread qtp12448296-13818

2017-12-31 10:20:03.393 [TRACE] [jersey.process.internal.RequestScope] - [DEBUG] Released scope instance Instance{id=6906f574-94ff-4efd-af8e-44440ac3cf35, referenceCounter=0, store size=0} on thread qtp12448296-14416

2017-12-31 10:20:04.673 [TRACE] [.www.protocol.http.HttpURLConnection] - ProxySelector Request for http://192.168.0.28/api/6ANnFcoQhXvEuGc1mW3ks3GXrAQF-Qr3ZdHMvuhr

2017-12-31 10:20:04.890 [TRACE] [jersey.process.internal.RequestScope] - [DEBUG] Released scope instance Instance{id=97560ad4-aa02-48a1-b054-c48849b107a8, referenceCounter=0, store size=0} on thread qtp12448296-14418

2017-12-31 10:20:05.114 [TRACE] [jersey.process.internal.RequestScope] - [DEBUG] Released scope instance Instance{id=98cad2fa-3dc3-4665-bf13-3bd121392b98, referenceCounter=0, store size=0} on thread qtp12448296-13825

2017-12-31 10:20:05.189 [TRACE] [jersey.process.internal.RequestScope] - [DEBUG] Released scope instance Instance{id=29354028-6026-4c45-8c3c-e6d9faa6dac0, referenceCounter=0, store size=0} on thread qtp12448296-8553

2017-12-31 10:20:05.220 [TRACE] [jersey.process.internal.RequestScope] - [DEBUG] Released scope instance Instance{id=6d417c13-fe7e-43c8-a3e9-09f83e3baba7, referenceCounter=0, store size=0} on thread qtp12448296-13818

2017-12-31 10:20:05.319 [TRACE] [jersey.process.internal.RequestScope] - [DEBUG] Released scope instance Instance{id=d9ea7377-7e54-42ab-a008-a38088b393a5, referenceCounter=0, store size=0} on thread qtp12448296-14418

2017-12-31 10:20:17.793 [TRACE] [.www.protocol.http.HttpURLConnection] - ProxySelector Re

vbier · December 31, 2017, 10:05am

I need /var/log/openhab2/openhab.log, this has to contain messages like

2017-12-31 11:08:09.756 [DEBUG] [nal.discovery.SensorDiscoveryService] - discovery started for bridge jeelink:jeelinkUsb:ab739591
2017-12-31 11:08:09.801 [DEBUG] [nal.discovery.SensorDiscoveryService] - discovery for bridge jeelink:jeelinkUsb:ab739591 found already known sensor id 1-160-236
2017-12-31 11:08:09.855 [DEBUG] [nal.discovery.SensorDiscoveryService] - discovery started for bridge jeelink:jeelinkUsb:b4d4ffb4
2017-12-31 11:08:09.900 [DEBUG] [nal.discovery.SensorDiscoveryService] - discovery for bridge jeelink:jeelinkUsb:b4d4ffb4 found already known sensor id 30
2017-12-31 11:08:09.953 [DEBUG] [nal.discovery.SensorDiscoveryService] - discovery for bridge jeelink:jeelinkUsb:b4d4ffb4 found unknown sensor of type jeelink:lacrosse with id 52
2017-12-31 11:08:10.017 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing ‘jeelink:lacrosse:52’ to inbox.
2017-12-31 11:08:10.080 [DEBUG] [nal.discovery.SensorDiscoveryService] - discovery for bridge jeelink:jeelinkUsb:b4d4ffb4 found unknown sensor of type jeelink:lacrosse with id 63
2017-12-31 11:08:10.137 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing ‘jeelink:lacrosse:63’ to inbox.
2017-12-31 11:08:10.177 [DEBUG] [nal.discovery.SensorDiscoveryService] - discovery for bridge jeelink:jeelinkUsb:b4d4ffb4 found unknown sensor of type jeelink:lacrosse with id 53
2017-12-31 11:08:10.251 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing ‘jeelink:lacrosse:53’ to inbox.
2017-12-31 11:08:10.291 [DEBUG] [nal.discovery.SensorDiscoveryService] - discovery for bridge jeelink:jeelinkUsb:b4d4ffb4 found unknown sensor of type jeelink:lacrosse with id 20
2017-12-31 11:08:10.362 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing ‘jeelink:lacrosse:20’ to inbox.
2017-12-31 11:08:10.404 [DEBUG] [nal.discovery.SensorDiscoveryService] - discovery for bridge jeelink:jeelinkUsb:b4d4ffb4 found unknown sensor of type jeelink:lacrosse with id 1
2017-12-31 11:08:10.488 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing ‘jeelink:lacrosse:1’ to inbox.
2017-12-31 11:08:10.527 [DEBUG] [nal.discovery.SensorDiscoveryService] - discovery for bridge jeelink:jeelinkUsb:b4d4ffb4 found unknown sensor of type jeelink:lacrosse with id 4
2017-12-31 11:08:10.599 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing ‘jeelink:lacrosse:4’ to inbox.
2017-12-31 11:08:10.639 [DEBUG] [nal.discovery.SensorDiscoveryService] - discovery for bridge jeelink:jeelinkUsb:b4d4ffb4 found unknown sensor of type jeelink:lacrosse with id 7
2017-12-31 11:08:10.718 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing ‘jeelink:lacrosse:7’ to inbox.
2017-12-31 11:08:10.774 [DEBUG] [nal.discovery.SensorDiscoveryService] - discovery for bridge jeelink:jeelinkUsb:b4d4ffb4 found unknown sensor of type jeelink:lacrosse with id 8
2017-12-31 11:08:10.777 [TRACE] [connection.AbstractJeeLinkConnection] - Read line from port /dev/ttyJeelink: OK 9 38 1 4 203 41
2017-12-31 11:08:10.834 [DEBUG] [nal.discovery.SensorDiscoveryService] - discovery for bridge jeelink:jeelinkUsb:b4d4ffb4 found unknown sensor of type jeelink:lacrosse with id 45
2017-12-31 11:08:10.844 [TRACE] [.LaCrosseTemperatureReadingConverter] - Creating reading from: OK 9 38 1 4 203 41
2017-12-31 11:08:10.899 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing ‘jeelink:lacrosse:45’ to inbox.
2017-12-31 11:08:10.968 [DEBUG] [nal.discovery.SensorDiscoveryService] - discovery for bridge jeelink:jeelinkUsb:b4d4ffb4 found unknown sensor of type jeelink:lacrosse with id 43
2017-12-31 11:08:10.927 [DEBUG] [nal.discovery.SensorDiscoveryService] - discovery for bridge jeelink:jeelinkUsb:b4d4ffb4 found already known sensor id 38
2017-12-31 11:08:11.039 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing ‘jeelink:lacrosse:43’ to inbox.
2017-12-31 11:08:11.102 [DEBUG] [nal.discovery.SensorDiscoveryService] - discovery for bridge jeelink:jeelinkUsb:b4d4ffb4 found unknown sensor of type jeelink:lacrosse with id 22
2017-12-31 11:08:11.081 [TRACE] [connection.AbstractJeeLinkConnection] - Read line from port /dev/ttyJeelink: OK 9 58 1 4 204 42
2017-12-31 11:08:11.249 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing ‘jeelink:lacrosse:22’ to inbox.
2017-12-31 11:08:11.276 [TRACE] [.LaCrosseTemperatureReadingConverter] - Creating reading from: OK 9 58 1 4 204 42
2017-12-31 11:08:11.320 [DEBUG] [nal.discovery.SensorDiscoveryService] - discovery for bridge jeelink:jeelinkUsb:b4d4ffb4 found already known sensor id 38
2017-12-31 11:08:11.336 [DEBUG] [nal.discovery.SensorDiscoveryService] - discovery for bridge jeelink:jeelinkUsb:b4d4ffb4 found already known sensor id 58
2017-12-31 11:08:11.378 [DEBUG] [nal.discovery.SensorDiscoveryService] - discovery for bridge jeelink:jeelinkUsb:b4d4ffb4 found unknown sensor of type jeelink:lacrosse with id 39
2017-12-31 11:08:11.459 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing ‘jeelink:lacrosse:39’ to inbox.
2017-12-31 11:08:11.516 [DEBUG] [nal.discovery.SensorDiscoveryService] - discovery for bridge jeelink:jeelinkUsb:b4d4ffb4 found already known sensor id 58
2017-12-31 11:08:12.006 [TRACE] [connection.AbstractJeeLinkConnection] - Read line from port /dev/ttyJeelink: OK 9 30 1 4 194 44
2017-12-31 11:08:12.025 [TRACE] [.LaCrosseTemperatureReadingConverter] - Creating reading from: OK 9 30 1 4 194 44
2017-12-31 11:08:12.044 [DEBUG] [nal.discovery.SensorDiscoveryService] - discovery for bridge jeelink:jeelinkUsb:b4d4ffb4 found already known sensor id 30

Easiest is to tail the log before starting discovery and then copy all new messages here.

sp_ky · December 31, 2017, 12:31pm

oh damn, i accidently renamed the log file and now its empty… i’ll fix this first…

sp_ky · January 2, 2018, 8:15am

there isn’t something like the logs you’ve posted. (Log Level configured)
Only Entrys with Jeelink are:

2018-01-02 09:10:09.903 [DEBUG] [.eclipse.jetty.server.HttpConnection] - HttpConnection@15ba376[SelectChannelEndPoint@d1c216{/192.168.0.15:50468<->8080,Open,in,out,-,-,67/30000,HttpConnection@15ba376}{io=0/0,kio=0,kro=1}][p=HttpParser{s=CONTENT,0 of 23},g=HttpGenerator@8c440f{s=START},c=HttpChannelOverHttp@dcbe8c{r=1,c=false,a=IDLE,uri=//192.168.0.5:8080/rest/discovery/bindings/jeelink/scan}] parsed true HttpParser{s=CONTENT,0 of 23}
2018-01-02 09:10:09.905 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@dcbe8c{r=1,c=false,a=IDLE,uri=//192.168.0.5:8080/rest/discovery/bindings/jeelink/scan} handle //192.168.0.5:8080/rest/discovery/bindings/jeelink/scan 
2018-01-02 09:10:09.907 [DEBUG] [clipse.jetty.server.HttpChannelState] - HttpChannelState@1a88871{s=IDLE a=NOT_ASYNC i=true r=NONE/false w=false} handling IDLE
2018-01-02 09:10:09.909 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@dcbe8c{r=1,c=false,a=DISPATCHED,uri=//192.168.0.5:8080/rest/discovery/bindings/jeelink/scan} action DISPATCH
2018-01-02 09:10:09.911 [DEBUG] [org.eclipse.jetty.server.Server     ] - REQUEST POST /rest/discovery/bindings/jeelink/scan on HttpChannelOverHttp@dcbe8c{r=1,c=false,a=DISPATCHED,uri=//192.168.0.5:8080/rest/discovery/bindings/jeelink/scan}
2018-01-02 09:10:09.913 [DEBUG] [ax.web.service.spi.model.ServerModel] - Matching [/rest/discovery/bindings/jeelink/scan]...
2018-01-02 09:10:09.915 [DEBUG] [ax.web.service.spi.model.ServerModel] - Path [/rest/discovery/bindings/jeelink/scan] matched to {pattern=/rest/.*,model=ServletModel{id=org.ops4j.pax.web.service.spi.model.ServletModel-12,name=org.ops4j.pax.web.service.spi.model.ServletModel-12,urlPatterns=[/rest/*],alias=/rest,servlet=com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge@1bb5e07,initParams={},context=ContextModel{id=org.ops4j.pax.web.service.spi.model.ContextModel-11,name=,httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [15], contextID=default],contextParams={},virtualHosts={},connectors={}}}}
2018-01-02 09:10:09.917 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/discovery/bindings/jeelink/scan @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [15], contextID=default]}
2018-01-02 09:10:09.919 [DEBUG] [.jetty.server.handler.ContextHandler] - context=||/rest/discovery/bindings/jeelink/scan @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [15], contextID=default]}
2018-01-02 09:10:09.921 [DEBUG] [org.eclipse.jetty.server.session    ] - sessionManager=org.ops4j.pax.web.service.jetty.internal.LateInvalidatingHashSessionManager@fe67a1
2018-01-02 09:10:09.922 [DEBUG] [org.eclipse.jetty.server.session    ] - session=null
2018-01-02 09:10:09.925 [DEBUG] [eclipse.jetty.servlet.ServletHandler] - servlet |/rest|/discovery/bindings/jeelink/scan -> org.ops4j.pax.web.service.spi.model.ServletModel-12@b9f03e59==com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge,jsp=null,order=-1,inst=true
2018-01-02 09:10:09.926 [DEBUG] [ce.jetty.internal.HttpServiceContext] - Handling request for [/rest/discovery/bindings/jeelink/scan] using http context [DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [15], contextID=default]]
2018-01-02 09:10:09.928 [DEBUG] [y.internal.HttpServiceServletHandler] - handling request org.ops4j.pax.web.service.jetty.internal.HttpServiceRequestWrapper@16f2a7d, org.ops4j.pax.web.service.jetty.internal.HttpServiceResponseWrapper@344536
2018-01-02 09:10:09.930 [DEBUG] [eclipse.jetty.servlet.ServletHandler] - chain=null
2018-01-02 09:10:09.939 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - sendResponse info=null content=HeapByteBuffer@5e91af[p=0,l=2,c=8192,r=2]={<<<30>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} complete=true committing=true callback=Blocker@120225f{null}
2018-01-02 09:10:09.940 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - COMMIT for /rest/discovery/bindings/jeelink/scan on HttpChannelOverHttp@dcbe8c{r=1,c=true,a=DISPATCHED,uri=//192.168.0.5:8080/rest/discovery/bindings/jeelink/scan}

vbier · January 2, 2018, 2:31pm

Is this /var/log/openhab2/openhab.log?

What are your configured logging levels? Enter

log:list

into the openHAB console and paste the output, please.

sp_ky · January 2, 2018, 2:33pm

Logger                                             │ Level
───────────────────────────────────────────────────┼──────
ROOT                                               │ TRACE
javax.jmdns                                        │ ERROR
org.apache.karaf.jaas.modules.audit                │ INFO
org.apache.karaf.kar.internal.KarServiceImpl       │ ERROR
org.apache.karaf.shell.support                     │ OFF
org.eclipse.smarthome                              │ INFO
org.jupnp                                          │ ERROR
org.openhab                                        │ INFO
org.openhab.binding.jeelink                        │ TRACE
org.ops4j.pax.url.mvn.internal.AetherBasedResolver │ ERROR
org.ops4j.pax.web.pax-web-runtime                  │ OFF
smarthome.event                                    │ INFO
smarthome.event.InboxUpdatedEvent                  │ ERROR
smarthome.event.ItemAddedEvent                     │ ERROR
smarthome.event.ItemRemovedEvent                   │ ERROR
smarthome.event.ItemStateEvent                     │ ERROR
smarthome.event.ThingAddedEvent                    │ ERROR
smarthome.event.ThingRemovedEvent                  │ ERROR
smarthome.event.ThingStatusInfoEvent               │ ERROR

vbier · January 2, 2018, 3:19pm

log levels look nearly like mine, but your log file looks completely different. Can you please edit the jeelink stick thing (change the init command either to be empty, or “0a v”) and look if that produces log output?

Can you also please post the version of the jeelink binding. Enter

bundle:list|grep -i jeeLink

into the openHAB console.

sp_ky · January 3, 2018, 9:43am

there are only this entries in log, when setting initaion by Jeelink

2018-01-03 10:39:53.277 [DEBUG] [org.eclipse.jetty.server.HttpInput  ] - HttpInputOverHTTP@1cbf216[c=68,q=1,[0]=EOF,s=STREAM] consumed Content@1669fc1{HeapByteBufferR@edc9e9[p=494,l=494,c=8192,r=0]={PUT /rest/things/...mmands":"0a v"}<<<>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}}
2018-01-03 10:39:53.278 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_VALUE --> HEADER_IN_VALUE
2018-01-03 10:39:53.278 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2018-01-03 10:39:53.279 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2018-01-03 10:39:53.279 [DEBUG] [org.eclipse.jetty.server.HttpInput  ] - HttpInputOverHTTP@1cbf216[c=68,q=0,[0]=null,s=STREAM] consumed EOF
2018-01-03 10:39:53.280 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2018-01-03 10:39:53.281 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2018-01-03 10:39:53.281 [DEBUG] [e.internal.ReaderInterceptorExecutor] - Message body reader (class org.eclipse.smarthome.io.rest.core.internal.GsonProvider) is trying to close the entity input stream. Not closing.
2018-01-03 10:39:53.281 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - sendResponse info=null content=HeapByteBuffer@19d24d3[p=0,l=784,c=8192,r=784]={<<<[{"author":"Andre...tton Binding"}]>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} complete=true committing=true callback=Blocker@b23428{null}
2018-01-03 10:39:53.282 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> END
2018-01-03 10:39:53.283 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - REQUEST for //192.168.0.5:8080/rest/things on HttpChannelOverHttp@c5fec6{r=3,c=false,a=IDLE,uri=//192.168.0.5:8080/rest/things}

Output Bundle:

openhab> bundle:list|grep -i jeeLink
232 │ Active │ 80 │ 2.2.0 │ JeeLink Binding

vbier · January 3, 2018, 11:03am

I do not understand why this looks so different on your installation. There should be jeelink log entries. Did you change the init command using the paper UI and also applied the changes? I have no idea why the log does not show any jeelink related info.

sp_ky · January 3, 2018, 11:34am

Thank you for your support, only thing i can tell is, that i used this tutorial

maybe i have to undo something?

except of the binding, i installed it in PaperUI

vbier · January 3, 2018, 11:47am

I just had a quick look at the blog post. Most of the steps there are not needed anymore, but should do no harm. Are you sure you have the correct usb device? If you have more than one usb device /dev/ttyUSB0 might be wrong. If I remember correctly, the stick will show online as soon as a device can be opened.

But regardless, you should see logging output. What kind of output do you get if you restart openHAB? Are there any jeelink related messages?

sp_ky · January 3, 2018, 11:51am

i dont know changed but now it works…

vbier · January 3, 2018, 12:07pm

Problems solved by restart. That is the Windows way.

sp_ky · January 3, 2018, 12:08pm

sure it is Thank you very much

sp_ky · January 3, 2018, 12:30pm

but there is still a problem, the temperature is wrong, it only shows 7°C…

vbier · January 3, 2018, 12:43pm

Without log messages there is not much i can do.

Are your sensors supported by the sketch? You can see compatible sensors here: https://wiki.fhem.de/wiki/JeeLink#Unterst.C3.BCtzte_Sensoren_und_Aktoren_incl._Wetterstation_WS_1600