openHAB 2.0 binding
OpenHAB
534
Posts
88
Posters
479.5k
Views
99
Watching
-
Thanks, the new version is working nice. only your link didn't work for me, i used this one:
https://github.com/tobof/openhab2-addons/blob/MySensors/addons/binding/org.openhab.binding.mysensors/target/org.openhab.binding.mysensors-2.0.0-SNAPSHOT.jar?raw=true -
Ok, here is the corrected jar file: LINK
The things now should update.
I've tested it with the current snapshot on two systems / mysensors environments. I've not tested it against the curren testing (beta4). It should work, but it may throw exceptions on startup.
At the moment please only update if you expierence problems. I would like to ensure the binding is now working.
@TimO
Thank you for that new jar file.
Gateway is "online" in PaperUI
Sensors are "Uninitialised"
PaperUI saysStatus: UNINITIALIZED - HANDLER_INITIALIZING_ERROR org.eclipse.smarthome.core.thing.Bridge.getHandler()Lorg/eclipse/smarthome/core/thing/binding/BridgeHandler;
I have added these sensors manually to thing file:Bridge mysensors:bridge-eth:gateway [ ipAddress="127.0.0.1", tcpPort=5003, sendDelay=200, skipStartupCheck=true ] { humidity bathHum01 [ nodeId="1", childId="0" ] temperature bathTemp01 [ nodeId="1", childId="1" ] }Here is log file from OH2
22:06:55.465 [DEBUG] [io.socket.engineio.client.Socket ] - writing ping packet - expecting pong within 60000ms 22:06:55.466 [DEBUG] [io.socket.engineio.client.Socket ] - flushing 1 packets in socket 22:06:55.509 [DEBUG] [io.socket.engineio.client.Socket ] - socket received: type 'pong', data 'null' 22:07:04.487 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 22:07:07.682 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SelectChannelEndPoint@310200{/192.168.1.9:60520<->8080,Open,in,out,-,-,30000/30000,HttpConnection}{io=0,kio=0,kro=1} idle timeout check, elapsed: 30000 ms, remaining: 0 ms 22:07:07.683 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SelectChannelEndPoint@310200{/192.168.1.9:60520<->8080,Open,in,out,-,-,30002/30000,HttpConnection}{io=0,kio=0,kro=1} idle timeout expired 22:07:07.684 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - ignored: WriteFlusher@82249d{IDLE} java.util.concurrent.TimeoutException: Idle timeout expired: 30000/30000 ms 22:07:07.685 [DEBUG] [rg.eclipse.jetty.io.AbstractEndPoint] - Ignored idle endpoint SelectChannelEndPoint@310200{/192.168.1.9:60520<->8080,Open,in,out,-,-,30003/30000,HttpConnection}{io=0,kio=0,kro=1} 22:07:09.977 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,29439/30000,SslConnection}{io=0,kio=0,kro=1} idle timeout check, elapsed: 29439 ms, remaining: 561 ms 22:07:10.540 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,30001/30000,SslConnection}{io=0,kio=0,kro=1} idle timeout check, elapsed: 30001 ms, remaining: -1 ms 22:07:10.541 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,30002/30000,SslConnection}{io=0,kio=0,kro=1} idle timeout expired 22:07:10.541 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - ignored: WriteFlusher@f4107c{IDLE} java.util.concurrent.TimeoutException: Idle timeout expired: 30001/30000 ms 22:07:10.542 [DEBUG] [rg.eclipse.jetty.io.AbstractEndPoint] - Ignored idle endpoint SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,30004/30000,SslConnection}{io=0,kio=0,kro=1} 22:07:10.884 [DEBUG] [e.internal.WriterInterceptorExecutor] - Message body writer (class com.eclipsesource.jaxrs.provider.gson.GsonProvider) is trying to close the entity output stream. Not closing. 22:07:10.886 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@ad3ff7[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,1051,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@b60904] generate: NEED_HEADER (null,[p=0,l=1051,c=8192,r=1051],true)@START 22:07:10.887 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@ad3ff7[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,1051,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@b60904] generate: FLUSH ([p=0,l=106,c=8192,r=106],[p=0,l=1051,c=8192,r=1051],true)@COMPLETING 22:07:10.889 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - write: WriteFlusher@18a5ae6{IDLE} [HeapByteBuffer@107873[p=0,l=106,c=8192,r=106]={<<<HTTP/1.1 200 OK\r\n....v20151106)\r\n\r\n>>> chunked\r\nServer:...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00},HeapByteBuffer@6612b4[p=0,l=1051,c=8192,r=1051]={<<<{"id":"0000","tit..."widgets":[]}]}>>>\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}] 22:07:10.892 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@18a5ae6{WRITING}:IDLE-->WRITING 22:07:10.895 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,W,30371/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,353/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=END,0 of -1},g=HttpGenerator{s=COMPLETING},c=HttpChannelOverHttp@d19aaf{r=38,c=true,a=DISPATCHED,uri=/rest/sitemaps/_default/0000}] flush enter [java.nio.HeapByteBuffer[pos=0 lim=106 cap=8192], java.nio.HeapByteBuffer[pos=0 lim=1051 cap=8192]] 22:07:10.901 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/1186,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,W,30377/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,358/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=END,0 of -1},g=HttpGenerator{s=COMPLETING},c=HttpChannelOverHttp@d19aaf{r=38,c=true,a=DISPATCHED,uri=/rest/sitemaps/_default/0000}] wrap Status = OK HandshakeStatus = NOT_HANDSHAKING bytesConsumed = 1157 bytesProduced = 1186 22:07:10.903 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,W,30379/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,361/30000,SslConnection}{io=0,kio=0,kro=1} OK HeapByteBuffer@eb6978[p=0,l=1186,c=17408,r=1186]={<<<\x17\x03\x03\x04\x9d\x00\x00\x00\x00\x00\x00\x00&\xFe3\xE3|...\xFe$\xE1\xF9J\xE06\xE9\xE96_=\x9a\xAa\x0c>>>\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} 22:07:10.906 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 1186 SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,363/30000,SslConnection}{io=0,kio=0,kro=1} 22:07:10.908 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/0,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,W,30384/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,1/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=END,0 of -1},g=HttpGenerator{s=COMPLETING},c=HttpChannelOverHttp@d19aaf{r=38,c=true,a=DISPATCHED,uri=/rest/sitemaps/_default/0000}] flush exit, consumed 1157 22:07:10.910 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@18a5ae6{IDLE}:WRITING-->IDLE 22:07:10.912 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@ad3ff7[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,1051,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@b60904] generate: DONE ([p=106,l=106,c=8192,r=0],[p=1051,l=1051,c=8192,r=0],true)@END 22:07:10.915 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ o.e.j.s.h.ContextHandler@1f3fece{/static,null,AVAILABLE} 22:07:10.916 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.core.audio_0.9.0.201611041332 [100], contextID=default]} 22:07:10.917 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui_0.9.0.201611041332 [137], contextID=default]} 22:07:10.918 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.icon_0.9.0.201611041332 [138], contextID=default]} 22:07:10.919 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=org.jupnp.transport.impl.osgi.DisableAuthenticationHttpContext@14870c1} 22:07:10.919 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.basic_0.9.0.201611041332 [186], contextID=default]} 22:07:10.920 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.classic_0.9.0.201611041332 [187], contextID=default]} 22:07:10.921 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.paper_0.9.0.201611041332 [188], contextID=default]} 22:07:10.921 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.io.rest.docs_2.0.0.201611062131 [189], contextID=default]} 22:07:10.922 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.ui.dashboard_2.0.0.201611062131 [192], contextID=default]} 22:07:10.923 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.core_2.0.0.201611062131 [168], contextID=default]} 22:07:10.924 [DEBUG] [org.eclipse.jetty.server.Server ] - RESPONSE /rest/sitemaps/_default/0000 200 handled=true 22:07:10.925 [DEBUG] [clipse.jetty.server.HttpChannelState] - HttpChannelState@6e53d6{s=DISPATCHED i=true a=null} unhandle DISPATCHED 22:07:10.926 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - reset HttpParser{s=END,0 of -1} 22:07:10.926 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - END --> START 22:07:10.927 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@d19aaf{r=38,c=false,a=IDLE,uri=} handle exit, result COMPLETE 22:07:10.929 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,30405/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,22/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=38,c=false,a=IDLE,uri=}] fill enter 22:07:10.931 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,23/30000,SslConnection}{io=0,kio=0,kro=1} 22:07:10.933 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=0/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,30409/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,25/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=38,c=false,a=IDLE,uri=}] filled 0 encrypted bytes 22:07:10.935 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=0/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,30411/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,27/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=38,c=false,a=IDLE,uri=}] unwrap Status = BUFFER_UNDERFLOW HandshakeStatus = NOT_HANDSHAKING bytesConsumed = 0 bytesProduced = 0 22:07:10.937 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,30413/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,29/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=38,c=false,a=IDLE,uri=}] fill exit 22:07:10.939 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,30415/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,32/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=38,c=false,a=IDLE,uri=}] fill enter 22:07:10.941 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,33/30000,SslConnection}{io=0,kio=0,kro=1} 22:07:10.942 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=0/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,30419/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,35/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=38,c=false,a=IDLE,uri=}] filled 0 encrypted bytes 22:07:10.945 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=0/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,30421/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,37/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=38,c=false,a=IDLE,uri=}] unwrap Status = BUFFER_UNDERFLOW HandshakeStatus = NOT_HANDSHAKING bytesConsumed = 0 bytesProduced = 0 22:07:10.947 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,30423/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,39/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=38,c=false,a=IDLE,uri=}] fill exit 22:07:10.948 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - parseNext s=START HeapByteBuffer@1ac887b[p=0,l=0,c=17408,r=0]={<<<>>>GET /rest/sitemap...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} 22:07:10.949 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - fillInterested HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,30426/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,42/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=38,c=false,a=IDLE,uri=}] 22:07:10.951 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILLING-->FILLING_FILL_INTERESTED HttpConnection@12c6b19[FILLING_FILL_INTERESTED,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,30427/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,44/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=38,c=false,a=IDLE,uri=}] 22:07:10.953 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILLING_FILL_INTERESTED-->FILL_INTERESTED HttpConnection@12c6b19[FILL_INTERESTED,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,30429/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,46/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=38,c=false,a=IDLE,uri=}] 22:07:10.955 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - fillInterested SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILL_INTERESTED,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,R,-,0/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,48/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=38,c=false,a=IDLE,uri=}] 22:07:10.958 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - IDLE-->FILL_INTERESTED SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILL_INTERESTED,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,R,-,3/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,50/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=38,c=false,a=IDLE,uri=}] 22:07:10.959 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Local interests updating 0 -> 1 for SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,R,-,0/30000,SslConnection}{io=1,kio=0,kro=1} 22:07:10.960 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Queued change org.eclipse.jetty.io.SelectChannelEndPoint$1@1d2164a 22:07:10.961 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop woken up from select, 0/1 selected 22:07:10.961 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Running change org.eclipse.jetty.io.SelectChannelEndPoint$1@1d2164a 22:07:10.962 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Key interests updated 0 -> 1 on SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,R,-,3/30000,SslConnection}{io=1,kio=1,kro=1} 22:07:10.962 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop waiting on select 22:07:11.035 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop woken up from select, 1/1 selected 22:07:11.036 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Key interests updated 1 -> 0 on SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,R,-,78/30000,SslConnection}{io=1,kio=0,kro=1} 22:07:11.036 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Local interests updating 1 -> 0 for SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,R,-,78/30000,SslConnection}{io=0,kio=0,kro=1} 22:07:11.037 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Queued change org.eclipse.jetty.io.SelectChannelEndPoint$1@1d2164a 22:07:11.038 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILL_INTERESTED-->FILLING SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILL_INTERESTED,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,R,-,83/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,79/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=38,c=false,a=IDLE,uri=}] 22:07:11.038 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - onFillable enter DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,R,-,84/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,80/30000,SslConnection}{io=0,kio=0,kro=1} 22:07:11.039 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILL_INTERESTED-->FILLING HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,85/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,81/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=38,c=false,a=IDLE,uri=}] 22:07:11.040 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - onFillable exit DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,86/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,82/30000,SslConnection}{io=0,kio=0,kro=1} 22:07:11.041 [DEBUG] [.eclipse.jetty.server.HttpConnection] - HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,86/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,82/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=38,c=false,a=IDLE,uri=}] onFillable HttpChannelState@6e53d6{s=IDLE i=true a=null} 22:07:11.041 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILLING-->IDLE SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,87/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,83/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=38,c=false,a=IDLE,uri=}] 22:07:11.042 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,87/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,83/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=38,c=false,a=IDLE,uri=}] fill enter 22:07:11.042 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Running change org.eclipse.jetty.io.SelectChannelEndPoint$1@1d2164a 22:07:11.042 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop waiting on select 22:07:11.042 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 473 SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,84/30000,SslConnection}{io=0,kio=0,kro=1} 22:07:11.043 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=473/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,89/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,1/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=38,c=false,a=IDLE,uri=}] filled 473 encrypted bytes 22:07:11.045 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=0/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,91/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,3/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=38,c=false,a=IDLE,uri=}] unwrap Status = OK HandshakeStatus = NOT_HANDSHAKING bytesConsumed = 473 bytesProduced = 444 22:07:11.046 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,92/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,4/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=38,c=false,a=IDLE,uri=}] fill exit 22:07:11.047 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - parseNext s=START HeapByteBuffer@c998ba[p=0,l=444,c=17408,r=444]={<<<GET /rest/sitemap...rwin/16.1.0\r\n\r\n>>>.0 CFNetwork/808....\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} 22:07:11.047 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - START --> SPACE1 22:07:11.047 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - SPACE1 --> URI 22:07:11.048 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - URI --> SPACE2 22:07:11.048 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - SPACE2 --> REQUEST_VERSION 22:07:11.048 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - REQUEST_VERSION --> HEADER 22:07:11.048 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.049 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.049 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_NAME 22:07:11.049 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_NAME --> HEADER_VALUE 22:07:11.049 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_VALUE --> HEADER_IN_VALUE 22:07:11.050 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.050 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.050 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.050 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.050 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.050 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.051 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.051 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.051 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.051 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.051 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.052 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_NAME 22:07:11.052 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_NAME --> HEADER_VALUE 22:07:11.052 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_VALUE --> HEADER_IN_VALUE 22:07:11.052 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.052 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.053 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.053 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.053 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.053 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> END 22:07:11.054 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=/rest/sitemaps/_default/0000} messageComplete 22:07:11.054 [DEBUG] [org.eclipse.jetty.server.HttpInput ] - HttpInputOverHTTP@15ca2 EOF 22:07:11.055 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=/rest/sitemaps/_default/0000} handle enter 22:07:11.055 [DEBUG] [clipse.jetty.server.HttpChannelState] - HttpChannelState@6e53d6{s=IDLE i=true a=null} handling IDLE 22:07:11.056 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@d19aaf{r=39,c=false,a=DISPATCHED,uri=/rest/sitemaps/_default/0000} action REQUEST_DISPATCH 22:07:11.056 [DEBUG] [org.eclipse.jetty.server.Server ] - REQUEST GET /rest/sitemaps/_default/0000 on HttpChannelOverHttp@d19aaf{r=39,c=false,a=DISPATCHED,uri=/rest/sitemaps/_default/0000} 22:07:11.057 [DEBUG] [ax.web.service.spi.model.ServerModel] - Matching [/rest/sitemaps/_default/0000]... 22:07:11.057 [DEBUG] [ax.web.service.spi.model.ServerModel] - Path [/rest/sitemaps/_default/0000] matched to {pattern=/rest/.*,model=ServletModel{id=org.ops4j.pax.web.service.spi.model.ServletModel-2,name=org.ops4j.pax.web.service.spi.model.ServletModel-2,urlPatterns=[/rest/*],alias=/rest,servlet=com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge@1efe36d,initParams={},context=ContextModel{id=org.ops4j.pax.web.service.spi.model.ContextModel-1,name=,httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [12], contextID=default],contextParams={},virtualHosts={},connectors={}}}} 22:07:11.057 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [12], contextID=default]} 22:07:11.058 [DEBUG] [.jetty.server.handler.ContextHandler] - context=||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [12], contextID=default]} 22:07:11.058 [DEBUG] [org.eclipse.jetty.server.session ] - sessionManager=org.ops4j.pax.web.service.jetty.internal.LateInvalidatingHashSessionManager@1a5ce14 22:07:11.059 [DEBUG] [org.eclipse.jetty.server.session ] - session=null 22:07:11.059 [DEBUG] [eclipse.jetty.servlet.ServletHandler] - servlet |/rest|/sitemaps/_default/0000 -> org.ops4j.pax.web.service.spi.model.ServletModel-2@378be0fa==com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge,-1,true 22:07:11.059 [DEBUG] [ce.jetty.internal.HttpServiceContext] - Handling request for [/rest/sitemaps/_default/0000] using http context [DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [12], contextID=default]] 22:07:11.060 [DEBUG] [y.internal.HttpServiceServletHandler] - handling request org.ops4j.pax.web.service.jetty.internal.HttpServiceRequestWrapper@5ce2b2, org.ops4j.pax.web.service.jetty.internal.HttpServiceResponseWrapper@97896c 22:07:11.060 [DEBUG] [eclipse.jetty.servlet.ServletHandler] - chain=null 22:07:11.073 [DEBUG] [e.internal.WriterInterceptorExecutor] - Message body writer (class com.eclipsesource.jaxrs.provider.gson.GsonProvider) is trying to close the entity output stream. Not closing. -
22:07:11.075 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@ad3ff7[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,1051,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@1ccbd33] generate: NEED_HEADER (null,[p=0,l=1051,c=8192,r=1051],true)@START 22:07:11.075 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@ad3ff7[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,1051,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@1ccbd33] generate: FLUSH ([p=0,l=106,c=8192,r=106],[p=0,l=1051,c=8192,r=1051],true)@COMPLETING 22:07:11.076 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - write: WriteFlusher@18a5ae6{IDLE} [HeapByteBuffer@107873[p=0,l=106,c=8192,r=106]={<<<HTTP/1.1 200 OK\r\n....v20151106)\r\n\r\n>>> chunked\r\nServer:...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00},HeapByteBuffer@193feb4[p=0,l=1051,c=8192,r=1051]={<<<{"id":"0000","tit..."widgets":[]}]}>>>\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}] 22:07:11.076 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@18a5ae6{WRITING}:IDLE-->WRITING 22:07:11.077 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,W,123/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,35/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=END,0 of -1},g=HttpGenerator{s=COMPLETING},c=HttpChannelOverHttp@d19aaf{r=39,c=true,a=DISPATCHED,uri=/rest/sitemaps/_default/0000}] flush enter [java.nio.HeapByteBuffer[pos=0 lim=106 cap=8192], java.nio.HeapByteBuffer[pos=0 lim=1051 cap=8192]] 22:07:11.102 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/1186,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,W,147/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,60/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=END,0 of -1},g=HttpGenerator{s=COMPLETING},c=HttpChannelOverHttp@d19aaf{r=39,c=true,a=DISPATCHED,uri=/rest/sitemaps/_default/0000}] wrap Status = OK HandshakeStatus = NOT_HANDSHAKING bytesConsumed = 1157 bytesProduced = 1186 22:07:11.103 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,W,148/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,61/30000,SslConnection}{io=0,kio=0,kro=1} OK HeapByteBuffer@eb6978[p=0,l=1186,c=17408,r=1186]={<<<\x17\x03\x03\x04\x9d\x00\x00\x00\x00\x00\x00\x00']\x1a(\xA4...\x94_\xF6\x11\x13PZf&0^\x1a\xC5c\x85>>>\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} 22:07:11.104 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 1186 SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,61/30000,SslConnection}{io=0,kio=0,kro=1} 22:07:11.105 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/0,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,W,150/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,0/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=END,0 of -1},g=HttpGenerator{s=COMPLETING},c=HttpChannelOverHttp@d19aaf{r=39,c=true,a=DISPATCHED,uri=/rest/sitemaps/_default/0000}] flush exit, consumed 1157 22:07:11.105 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@18a5ae6{IDLE}:WRITING-->IDLE 22:07:11.106 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@ad3ff7[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,1051,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@1ccbd33] generate: DONE ([p=106,l=106,c=8192,r=0],[p=1051,l=1051,c=8192,r=0],true)@END 22:07:11.107 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ o.e.j.s.h.ContextHandler@1f3fece{/static,null,AVAILABLE} 22:07:11.107 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.core.audio_0.9.0.201611041332 [100], contextID=default]} 22:07:11.108 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui_0.9.0.201611041332 [137], contextID=default]} 22:07:11.108 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.icon_0.9.0.201611041332 [138], contextID=default]} 22:07:11.108 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=org.jupnp.transport.impl.osgi.DisableAuthenticationHttpContext@14870c1} 22:07:11.109 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.basic_0.9.0.201611041332 [186], contextID=default]} 22:07:11.109 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.classic_0.9.0.201611041332 [187], contextID=default]} 22:07:11.110 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.paper_0.9.0.201611041332 [188], contextID=default]} 22:07:11.110 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.io.rest.docs_2.0.0.201611062131 [189], contextID=default]} 22:07:11.110 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.ui.dashboard_2.0.0.201611062131 [192], contextID=default]} 22:07:11.111 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.core_2.0.0.201611062131 [168], contextID=default]} 22:07:11.111 [DEBUG] [org.eclipse.jetty.server.Server ] - RESPONSE /rest/sitemaps/_default/0000 200 handled=true 22:07:11.111 [DEBUG] [clipse.jetty.server.HttpChannelState] - HttpChannelState@6e53d6{s=DISPATCHED i=true a=null} unhandle DISPATCHED 22:07:11.112 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - reset HttpParser{s=END,0 of -1} 22:07:11.112 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - END --> START 22:07:11.112 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=} handle exit, result COMPLETE 22:07:11.114 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,159/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,9/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] fill enter 22:07:11.114 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,10/30000,SslConnection}{io=0,kio=0,kro=1} 22:07:11.115 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=0/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,161/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,11/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] filled 0 encrypted bytes 22:07:11.116 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=0/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,162/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,12/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] unwrap Status = BUFFER_UNDERFLOW HandshakeStatus = NOT_HANDSHAKING bytesConsumed = 0 bytesProduced = 0 22:07:11.118 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,163/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,13/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] fill exit 22:07:11.119 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,164/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,14/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] fill enter 22:07:11.119 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,15/30000,SslConnection}{io=0,kio=0,kro=1} 22:07:11.120 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=0/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,166/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,16/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] filled 0 encrypted bytes 22:07:11.121 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=0/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,167/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,17/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] unwrap Status = BUFFER_UNDERFLOW HandshakeStatus = NOT_HANDSHAKING bytesConsumed = 0 bytesProduced = 0 22:07:11.122 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,168/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,18/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] fill exit 22:07:11.123 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - parseNext s=START HeapByteBuffer@138a3c8[p=0,l=0,c=17408,r=0]={<<<>>>GET /rest/sitemap...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} 22:07:11.124 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - fillInterested HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,169/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,19/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] 22:07:11.125 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILLING-->FILLING_FILL_INTERESTED HttpConnection@12c6b19[FILLING_FILL_INTERESTED,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,170/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,20/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] 22:07:11.126 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILLING_FILL_INTERESTED-->FILL_INTERESTED HttpConnection@12c6b19[FILL_INTERESTED,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,171/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,21/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] 22:07:11.127 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - fillInterested SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILL_INTERESTED,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,R,-,0/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,22/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] 22:07:11.128 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - IDLE-->FILL_INTERESTED SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILL_INTERESTED,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,R,-,1/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,24/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] 22:07:11.129 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Local interests updating 0 -> 1 for SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,R,-,0/30000,SslConnection}{io=1,kio=0,kro=1} 22:07:11.129 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Queued change org.eclipse.jetty.io.SelectChannelEndPoint$1@1d2164a 22:07:11.129 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop woken up from select, 0/1 selected 22:07:11.130 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Running change org.eclipse.jetty.io.SelectChannelEndPoint$1@1d2164a 22:07:11.130 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Key interests updated 0 -> 1 on SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,R,-,2/30000,SslConnection}{io=1,kio=1,kro=1} 22:07:11.131 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop waiting on select 22:07:11.238 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop woken up from select, 1/1 selected 22:07:11.238 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Key interests updated 1 -> 0 on SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,R,-,110/30000,SslConnection}{io=1,kio=0,kro=1} 22:07:11.239 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Local interests updating 1 -> 0 for SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,R,-,111/30000,SslConnection}{io=0,kio=0,kro=1} 22:07:11.239 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Queued change org.eclipse.jetty.io.SelectChannelEndPoint$1@1d2164a 22:07:11.240 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILL_INTERESTED-->FILLING SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILL_INTERESTED,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,R,-,114/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,112/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] 22:07:11.241 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - onFillable enter DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,R,-,115/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,113/30000,SslConnection}{io=0,kio=0,kro=1} 22:07:11.242 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILL_INTERESTED-->FILLING HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,115/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,114/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] 22:07:11.243 [DEBUG] [.eclipse.jetty.server.HttpConnection] - HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,117/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,115/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] onFillable HttpChannelState@6e53d6{s=IDLE i=true a=null} 22:07:11.244 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,118/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,116/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] fill enter 22:07:11.245 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 511 SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,117/30000,SslConnection}{io=0,kio=0,kro=1} 22:07:11.246 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=511/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,120/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,1/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] filled 511 encrypted bytes 22:07:11.248 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=0/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,121/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,3/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] unwrap Status = OK HandshakeStatus = NOT_HANDSHAKING bytesConsumed = 511 bytesProduced = 482 22:07:11.249 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,122/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,4/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] fill exit 22:07:11.249 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - parseNext s=START HeapByteBuffer@1ac887b[p=0,l=482,c=17408,r=482]={<<<GET /rest/sitemap...rwin/16.1.0\r\n\r\n>>>in/16.1.0\r\n\r\n\xE0\xBc\xB1\xF2...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} 22:07:11.250 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - START --> SPACE1 22:07:11.250 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - SPACE1 --> URI 22:07:11.250 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - URI --> SPACE2 22:07:11.250 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - SPACE2 --> REQUEST_VERSION 22:07:11.250 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - REQUEST_VERSION --> HEADER 22:07:11.251 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.251 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.251 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_NAME 22:07:11.251 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - onFillable exit DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,123/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,5/30000,SslConnection}{io=0,kio=0,kro=1} 22:07:11.251 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_NAME --> HEADER_VALUE 22:07:11.252 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_VALUE --> HEADER_IN_VALUE 22:07:11.252 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.252 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.252 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.252 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.252 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.253 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.253 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.253 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.253 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.254 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_NAME 22:07:11.254 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_NAME --> HEADER_VALUE 22:07:11.254 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_VALUE --> HEADER_IN_VALUE 22:07:11.254 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.254 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_NAME 22:07:11.255 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_NAME --> HEADER_VALUE 22:07:11.255 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_VALUE --> HEADER_IN_VALUE 22:07:11.255 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.255 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.255 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.255 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.256 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.256 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.256 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.256 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> END 22:07:11.257 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@d19aaf{r=40,c=false,a=IDLE,uri=/rest/sitemaps/_default/0000} messageComplete 22:07:11.257 [DEBUG] [org.eclipse.jetty.server.HttpInput ] - HttpInputOverHTTP@15ca2 EOF 22:07:11.257 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILLING-->IDLE SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,127/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,9/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=HEADER_IN_VALUE,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=40,c=false,a=IDLE,uri=/rest/sitemaps/_default/0000}] 22:07:11.257 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@d19aaf{r=40,c=false,a=IDLE,uri=/rest/sitemaps/_default/0000} handle enter 22:07:11.258 [DEBUG] [clipse.jetty.server.HttpChannelState] - HttpChannelState@6e53d6{s=IDLE i=true a=null} handling IDLE 22:07:11.258 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Running change org.eclipse.jetty.io.SelectChannelEndPoint$1@1d2164a 22:07:11.258 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@d19aaf{r=40,c=false,a=DISPATCHED,uri=/rest/sitemaps/_default/0000} action REQUEST_DISPATCH 22:07:11.259 [DEBUG] [org.eclipse.jetty.server.Server ] - REQUEST GET /rest/sitemaps/_default/0000 on HttpChannelOverHttp@d19aaf{r=40,c=false,a=DISPATCHED,uri=/rest/sitemaps/_default/0000} 22:07:11.260 [DEBUG] [ax.web.service.spi.model.ServerModel] - Matching [/rest/sitemaps/_default/0000]... 22:07:11.260 [DEBUG] [ax.web.service.spi.model.ServerModel] - Path [/rest/sitemaps/_default/0000] matched to {pattern=/rest/.*,model=ServletModel{id=org.ops4j.pax.web.service.spi.model.ServletModel-2,name=org.ops4j.pax.web.service.spi.model.ServletModel-2,urlPatterns=[/rest/*],alias=/rest,servlet=com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge@1efe36d,initParams={},context=ContextModel{id=org.ops4j.pax.web.service.spi.model.ContextModel-1,name=,httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [12], contextID=default],contextParams={},virtualHosts={},connectors={}}}} 22:07:11.260 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [12], contextID=default]} 22:07:11.261 [DEBUG] [.jetty.server.handler.ContextHandler] - context=||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [12], contextID=default]} 22:07:11.261 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop waiting on select 22:07:11.261 [DEBUG] [org.eclipse.jetty.server.session ] - sessionManager=org.ops4j.pax.web.service.jetty.internal.LateInvalidatingHashSessionManager@1a5ce14 22:07:11.261 [DEBUG] [org.eclipse.jetty.server.session ] - session=null 22:07:11.262 [DEBUG] [eclipse.jetty.servlet.ServletHandler] - servlet |/rest|/sitemaps/_default/0000 -> org.ops4j.pax.web.service.spi.model.ServletModel-2@378be0fa==com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge,-1,true 22:07:11.262 [DEBUG] [ce.jetty.internal.HttpServiceContext] - Handling request for [/rest/sitemaps/_default/0000] using http context [DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [12], contextID=default]] 22:07:11.262 [DEBUG] [y.internal.HttpServiceServletHandler] - handling request org.ops4j.pax.web.service.jetty.internal.HttpServiceRequestWrapper@fee5d4, org.ops4j.pax.web.service.jetty.internal.HttpServiceResponseWrapper@1d0961 22:07:11.263 [DEBUG] [eclipse.jetty.servlet.ServletHandler] - chain=null 22:07:12.534 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SelectChannelEndPoint@dd0653{/192.168.1.9:60519<->8080,Open,in,out,-,-,30000/30000,HttpConnection}{io=0,kio=0,kro=1} idle timeout check, elapsed: 30000 ms, remaining: 0 ms 22:07:12.534 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SelectChannelEndPoint@dd0653{/192.168.1.9:60519<->8080,Open,in,out,-,-,30001/30000,HttpConnection}{io=0,kio=0,kro=1} idle timeout expired 22:07:12.535 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - ignored: WriteFlusher@aca643{IDLE} java.util.concurrent.TimeoutException: Idle timeout expired: 30000/30000 ms 22:07:12.535 [DEBUG] [rg.eclipse.jetty.io.AbstractEndPoint] - Ignored idle endpoint SelectChannelEndPoint@dd0653{/192.168.1.9:60519<->8080,Open,in,out,-,-,30002/30000,HttpConnection}{io=0,kio=0,kro=1} 22:07:14.488 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 22:07:19.729 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SelectChannelEndPoint@17b31db{/192.168.1.9:60535<->8080,Open,in,out,-,-,30000/30000,HttpConnection}{io=0,kio=0,kro=1} idle timeout check, elapsed: 30000 ms, remaining: 0 ms 22:07:19.730 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SelectChannelEndPoint@17b31db{/192.168.1.9:60535<->8080,Open,in,out,-,-,30001/30000,HttpConnection}{io=0,kio=0,kro=1} idle timeout expired 22:07:19.730 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - ignored: WriteFlusher@14d7529{IDLE} java.util.concurrent.TimeoutException: Idle timeout expired: 30000/30000 ms 22:07:19.731 [DEBUG] [rg.eclipse.jetty.io.AbstractEndPoint] - Ignored idle endpoint SelectChannelEndPoint@17b31db{/192.168.1.9:60535<->8080,Open,in,out,-,-,30003/30000,HttpConnection}{io=0,kio=0,kro=1} 22:07:20.626 [DEBUG] [io.socket.engineio.client.Socket ] - writing ping packet - expecting pong within 60000ms 22:07:20.626 [DEBUG] [io.socket.engineio.client.Socket ] - flushing 1 packets in socket 22:07:20.669 [DEBUG] [io.socket.engineio.client.Socket ] - socket received: type 'pong', data 'null' 22:07:21.383 [DEBUG] [rg.quartz.core.QuartzSchedulerThread] - batch acquisition of 0 triggers 22:07:24.489 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skippedRepeated again and again
Log at startup would be of any interest ?
There is some progress though: NEW CONNECTION when starting OH2!!! :)mysgw: Starting gateway... mysgw: Protocol version - 2.0.1-beta mysgw: MCO:BGN:INIT GW,CP=RNNG---,VER=2.0.1-beta mysgw: TSF:LRT:OK mysgw: TSM:INIT mysgw: TSM:INIT:TSP OK mysgw: TSM:INIT:GW MODE mysgw: TSM:READY mysgw: Listening for connections on 0.0.0.0:5003 mysgw: MCO:REG:NOT NEEDED mysgw: MCO:BGN:STP mysgw: MCO:BGN:INIT OK,ID=0,PAR=0,DIS=0,REG=1 mysgw: TSF:MSG:READ,1-1-0,s=255,c=3,t=26,pt=1,l=1,sg=0:2 mysgw: TSF:MSG:SEND,0-0-1-1,s=255,c=3,t=27,pt=1,l=1,sg=0,ft=0,st=OK:1 mysgw: TSF:MSG:READ,1-1-0,s=255,c=3,t=15,pt=6,l=2,sg=0:0100 mysgw: TSF:MSG:SEND,0-0-1-1,s=255,c=3,t=15,pt=6,l=2,sg=0,ft=0,st=OK:0100 mysgw: TSF:MSG:READ,1-1-0,s=255,c=0,t=17,pt=0,l=5,sg=0:2.0.0 mysgw: TSF:MSG:READ,1-1-0,s=255,c=3,t=6,pt=1,l=1,sg=0:0 mysgw: TSF:MSG:READ,1-1-0,s=255,c=3,t=11,pt=0,l=22,sg=0:TemperatureAndHumidity mysgw: TSF:MSG:READ,1-1-0,s=255,c=3,t=12,pt=0,l=3,sg=0:1.1 mysgw: TSF:MSG:READ,1-1-0,s=0,c=0,t=7,pt=0,l=0,sg=0: mysgw: TSF:MSG:READ,1-1-0,s=1,c=0,t=6,pt=0,l=0,sg=0: mysgw: TSF:MSG:READ,1-1-0,s=255,c=3,t=26,pt=1,l=1,sg=0:2 mysgw: TSF:MSG:SEND,0-0-1-1,s=255,c=3,t=27,pt=1,l=1,sg=0,ft=0,st=OK:1 mysgw: TSF:MSG:READ,1-1-0,s=1,c=1,t=0,pt=7,l=5,sg=0:19.9 mysgw: TSF:MSG:READ,1-1-0,s=0,c=1,t=1,pt=7,l=5,sg=0:61.2 mysgw: New connection from 127.0.0.1 mysgw: Client 0 connected mysgw: TSF:MSG:READ,1-1-0,s=0,c=1,t=1,pt=7,l=5,sg=0:61.3Thank you so much for your time
-
22:07:11.075 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@ad3ff7[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,1051,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@1ccbd33] generate: NEED_HEADER (null,[p=0,l=1051,c=8192,r=1051],true)@START 22:07:11.075 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@ad3ff7[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,1051,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@1ccbd33] generate: FLUSH ([p=0,l=106,c=8192,r=106],[p=0,l=1051,c=8192,r=1051],true)@COMPLETING 22:07:11.076 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - write: WriteFlusher@18a5ae6{IDLE} [HeapByteBuffer@107873[p=0,l=106,c=8192,r=106]={<<<HTTP/1.1 200 OK\r\n....v20151106)\r\n\r\n>>> chunked\r\nServer:...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00},HeapByteBuffer@193feb4[p=0,l=1051,c=8192,r=1051]={<<<{"id":"0000","tit..."widgets":[]}]}>>>\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}] 22:07:11.076 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@18a5ae6{WRITING}:IDLE-->WRITING 22:07:11.077 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,W,123/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,35/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=END,0 of -1},g=HttpGenerator{s=COMPLETING},c=HttpChannelOverHttp@d19aaf{r=39,c=true,a=DISPATCHED,uri=/rest/sitemaps/_default/0000}] flush enter [java.nio.HeapByteBuffer[pos=0 lim=106 cap=8192], java.nio.HeapByteBuffer[pos=0 lim=1051 cap=8192]] 22:07:11.102 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/1186,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,W,147/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,60/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=END,0 of -1},g=HttpGenerator{s=COMPLETING},c=HttpChannelOverHttp@d19aaf{r=39,c=true,a=DISPATCHED,uri=/rest/sitemaps/_default/0000}] wrap Status = OK HandshakeStatus = NOT_HANDSHAKING bytesConsumed = 1157 bytesProduced = 1186 22:07:11.103 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,W,148/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,61/30000,SslConnection}{io=0,kio=0,kro=1} OK HeapByteBuffer@eb6978[p=0,l=1186,c=17408,r=1186]={<<<\x17\x03\x03\x04\x9d\x00\x00\x00\x00\x00\x00\x00']\x1a(\xA4...\x94_\xF6\x11\x13PZf&0^\x1a\xC5c\x85>>>\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} 22:07:11.104 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 1186 SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,61/30000,SslConnection}{io=0,kio=0,kro=1} 22:07:11.105 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/0,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,W,150/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,0/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=END,0 of -1},g=HttpGenerator{s=COMPLETING},c=HttpChannelOverHttp@d19aaf{r=39,c=true,a=DISPATCHED,uri=/rest/sitemaps/_default/0000}] flush exit, consumed 1157 22:07:11.105 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@18a5ae6{IDLE}:WRITING-->IDLE 22:07:11.106 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@ad3ff7[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,1051,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@1ccbd33] generate: DONE ([p=106,l=106,c=8192,r=0],[p=1051,l=1051,c=8192,r=0],true)@END 22:07:11.107 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ o.e.j.s.h.ContextHandler@1f3fece{/static,null,AVAILABLE} 22:07:11.107 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.core.audio_0.9.0.201611041332 [100], contextID=default]} 22:07:11.108 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui_0.9.0.201611041332 [137], contextID=default]} 22:07:11.108 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.icon_0.9.0.201611041332 [138], contextID=default]} 22:07:11.108 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=org.jupnp.transport.impl.osgi.DisableAuthenticationHttpContext@14870c1} 22:07:11.109 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.basic_0.9.0.201611041332 [186], contextID=default]} 22:07:11.109 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.classic_0.9.0.201611041332 [187], contextID=default]} 22:07:11.110 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.paper_0.9.0.201611041332 [188], contextID=default]} 22:07:11.110 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.io.rest.docs_2.0.0.201611062131 [189], contextID=default]} 22:07:11.110 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.ui.dashboard_2.0.0.201611062131 [192], contextID=default]} 22:07:11.111 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.core_2.0.0.201611062131 [168], contextID=default]} 22:07:11.111 [DEBUG] [org.eclipse.jetty.server.Server ] - RESPONSE /rest/sitemaps/_default/0000 200 handled=true 22:07:11.111 [DEBUG] [clipse.jetty.server.HttpChannelState] - HttpChannelState@6e53d6{s=DISPATCHED i=true a=null} unhandle DISPATCHED 22:07:11.112 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - reset HttpParser{s=END,0 of -1} 22:07:11.112 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - END --> START 22:07:11.112 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=} handle exit, result COMPLETE 22:07:11.114 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,159/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,9/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] fill enter 22:07:11.114 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,10/30000,SslConnection}{io=0,kio=0,kro=1} 22:07:11.115 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=0/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,161/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,11/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] filled 0 encrypted bytes 22:07:11.116 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=0/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,162/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,12/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] unwrap Status = BUFFER_UNDERFLOW HandshakeStatus = NOT_HANDSHAKING bytesConsumed = 0 bytesProduced = 0 22:07:11.118 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,163/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,13/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] fill exit 22:07:11.119 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,164/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,14/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] fill enter 22:07:11.119 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,15/30000,SslConnection}{io=0,kio=0,kro=1} 22:07:11.120 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=0/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,166/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,16/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] filled 0 encrypted bytes 22:07:11.121 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=0/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,167/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,17/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] unwrap Status = BUFFER_UNDERFLOW HandshakeStatus = NOT_HANDSHAKING bytesConsumed = 0 bytesProduced = 0 22:07:11.122 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,168/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,18/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] fill exit 22:07:11.123 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - parseNext s=START HeapByteBuffer@138a3c8[p=0,l=0,c=17408,r=0]={<<<>>>GET /rest/sitemap...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} 22:07:11.124 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - fillInterested HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,169/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,19/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] 22:07:11.125 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILLING-->FILLING_FILL_INTERESTED HttpConnection@12c6b19[FILLING_FILL_INTERESTED,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,170/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,20/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] 22:07:11.126 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILLING_FILL_INTERESTED-->FILL_INTERESTED HttpConnection@12c6b19[FILL_INTERESTED,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,171/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,21/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] 22:07:11.127 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - fillInterested SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILL_INTERESTED,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,R,-,0/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,22/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] 22:07:11.128 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - IDLE-->FILL_INTERESTED SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILL_INTERESTED,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,R,-,1/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,24/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] 22:07:11.129 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Local interests updating 0 -> 1 for SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,R,-,0/30000,SslConnection}{io=1,kio=0,kro=1} 22:07:11.129 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Queued change org.eclipse.jetty.io.SelectChannelEndPoint$1@1d2164a 22:07:11.129 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop woken up from select, 0/1 selected 22:07:11.130 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Running change org.eclipse.jetty.io.SelectChannelEndPoint$1@1d2164a 22:07:11.130 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Key interests updated 0 -> 1 on SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,R,-,2/30000,SslConnection}{io=1,kio=1,kro=1} 22:07:11.131 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop waiting on select 22:07:11.238 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop woken up from select, 1/1 selected 22:07:11.238 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Key interests updated 1 -> 0 on SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,R,-,110/30000,SslConnection}{io=1,kio=0,kro=1} 22:07:11.239 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Local interests updating 1 -> 0 for SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,R,-,111/30000,SslConnection}{io=0,kio=0,kro=1} 22:07:11.239 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Queued change org.eclipse.jetty.io.SelectChannelEndPoint$1@1d2164a 22:07:11.240 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILL_INTERESTED-->FILLING SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILL_INTERESTED,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,R,-,114/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,112/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] 22:07:11.241 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - onFillable enter DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,R,-,115/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,113/30000,SslConnection}{io=0,kio=0,kro=1} 22:07:11.242 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILL_INTERESTED-->FILLING HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,115/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,114/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] 22:07:11.243 [DEBUG] [.eclipse.jetty.server.HttpConnection] - HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,117/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,115/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] onFillable HttpChannelState@6e53d6{s=IDLE i=true a=null} 22:07:11.244 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,118/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,116/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] fill enter 22:07:11.245 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 511 SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,117/30000,SslConnection}{io=0,kio=0,kro=1} 22:07:11.246 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=511/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,120/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,1/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] filled 511 encrypted bytes 22:07:11.248 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=0/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,121/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,3/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] unwrap Status = OK HandshakeStatus = NOT_HANDSHAKING bytesConsumed = 511 bytesProduced = 482 22:07:11.249 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,122/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,4/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=39,c=false,a=IDLE,uri=}] fill exit 22:07:11.249 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - parseNext s=START HeapByteBuffer@1ac887b[p=0,l=482,c=17408,r=482]={<<<GET /rest/sitemap...rwin/16.1.0\r\n\r\n>>>in/16.1.0\r\n\r\n\xE0\xBc\xB1\xF2...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} 22:07:11.250 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - START --> SPACE1 22:07:11.250 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - SPACE1 --> URI 22:07:11.250 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - URI --> SPACE2 22:07:11.250 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - SPACE2 --> REQUEST_VERSION 22:07:11.250 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - REQUEST_VERSION --> HEADER 22:07:11.251 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.251 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.251 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_NAME 22:07:11.251 [DEBUG] [g.eclipse.jetty.io.ssl.SslConnection] - onFillable exit DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,123/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,5/30000,SslConnection}{io=0,kio=0,kro=1} 22:07:11.251 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_NAME --> HEADER_VALUE 22:07:11.252 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_VALUE --> HEADER_IN_VALUE 22:07:11.252 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.252 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.252 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.252 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.252 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.253 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.253 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.253 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.253 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.254 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_NAME 22:07:11.254 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_NAME --> HEADER_VALUE 22:07:11.254 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_VALUE --> HEADER_IN_VALUE 22:07:11.254 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.254 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_NAME 22:07:11.255 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_NAME --> HEADER_VALUE 22:07:11.255 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_VALUE --> HEADER_IN_VALUE 22:07:11.255 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.255 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.255 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.255 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.256 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.256 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> HEADER_IN_VALUE 22:07:11.256 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER_IN_VALUE --> HEADER 22:07:11.256 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> END 22:07:11.257 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@d19aaf{r=40,c=false,a=IDLE,uri=/rest/sitemaps/_default/0000} messageComplete 22:07:11.257 [DEBUG] [org.eclipse.jetty.server.HttpInput ] - HttpInputOverHTTP@15ca2 EOF 22:07:11.257 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILLING-->IDLE SslConnection@140f96f{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnection@12c6b19[FILLING,DecryptedEndPoint@47d536{/192.168.1.24:55097<->8443,Open,in,out,-,-,127/30000,HttpConnection}->SelectChannelEndPoint@c36456{/192.168.1.24:55097<->8443,Open,in,out,-,-,9/30000,SslConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=HEADER_IN_VALUE,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@d19aaf{r=40,c=false,a=IDLE,uri=/rest/sitemaps/_default/0000}] 22:07:11.257 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@d19aaf{r=40,c=false,a=IDLE,uri=/rest/sitemaps/_default/0000} handle enter 22:07:11.258 [DEBUG] [clipse.jetty.server.HttpChannelState] - HttpChannelState@6e53d6{s=IDLE i=true a=null} handling IDLE 22:07:11.258 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Running change org.eclipse.jetty.io.SelectChannelEndPoint$1@1d2164a 22:07:11.258 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@d19aaf{r=40,c=false,a=DISPATCHED,uri=/rest/sitemaps/_default/0000} action REQUEST_DISPATCH 22:07:11.259 [DEBUG] [org.eclipse.jetty.server.Server ] - REQUEST GET /rest/sitemaps/_default/0000 on HttpChannelOverHttp@d19aaf{r=40,c=false,a=DISPATCHED,uri=/rest/sitemaps/_default/0000} 22:07:11.260 [DEBUG] [ax.web.service.spi.model.ServerModel] - Matching [/rest/sitemaps/_default/0000]... 22:07:11.260 [DEBUG] [ax.web.service.spi.model.ServerModel] - Path [/rest/sitemaps/_default/0000] matched to {pattern=/rest/.*,model=ServletModel{id=org.ops4j.pax.web.service.spi.model.ServletModel-2,name=org.ops4j.pax.web.service.spi.model.ServletModel-2,urlPatterns=[/rest/*],alias=/rest,servlet=com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge@1efe36d,initParams={},context=ContextModel{id=org.ops4j.pax.web.service.spi.model.ContextModel-1,name=,httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [12], contextID=default],contextParams={},virtualHosts={},connectors={}}}} 22:07:11.260 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [12], contextID=default]} 22:07:11.261 [DEBUG] [.jetty.server.handler.ContextHandler] - context=||/rest/sitemaps/_default/0000 @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [12], contextID=default]} 22:07:11.261 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop waiting on select 22:07:11.261 [DEBUG] [org.eclipse.jetty.server.session ] - sessionManager=org.ops4j.pax.web.service.jetty.internal.LateInvalidatingHashSessionManager@1a5ce14 22:07:11.261 [DEBUG] [org.eclipse.jetty.server.session ] - session=null 22:07:11.262 [DEBUG] [eclipse.jetty.servlet.ServletHandler] - servlet |/rest|/sitemaps/_default/0000 -> org.ops4j.pax.web.service.spi.model.ServletModel-2@378be0fa==com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge,-1,true 22:07:11.262 [DEBUG] [ce.jetty.internal.HttpServiceContext] - Handling request for [/rest/sitemaps/_default/0000] using http context [DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [12], contextID=default]] 22:07:11.262 [DEBUG] [y.internal.HttpServiceServletHandler] - handling request org.ops4j.pax.web.service.jetty.internal.HttpServiceRequestWrapper@fee5d4, org.ops4j.pax.web.service.jetty.internal.HttpServiceResponseWrapper@1d0961 22:07:11.263 [DEBUG] [eclipse.jetty.servlet.ServletHandler] - chain=null 22:07:12.534 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SelectChannelEndPoint@dd0653{/192.168.1.9:60519<->8080,Open,in,out,-,-,30000/30000,HttpConnection}{io=0,kio=0,kro=1} idle timeout check, elapsed: 30000 ms, remaining: 0 ms 22:07:12.534 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SelectChannelEndPoint@dd0653{/192.168.1.9:60519<->8080,Open,in,out,-,-,30001/30000,HttpConnection}{io=0,kio=0,kro=1} idle timeout expired 22:07:12.535 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - ignored: WriteFlusher@aca643{IDLE} java.util.concurrent.TimeoutException: Idle timeout expired: 30000/30000 ms 22:07:12.535 [DEBUG] [rg.eclipse.jetty.io.AbstractEndPoint] - Ignored idle endpoint SelectChannelEndPoint@dd0653{/192.168.1.9:60519<->8080,Open,in,out,-,-,30002/30000,HttpConnection}{io=0,kio=0,kro=1} 22:07:14.488 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 22:07:19.729 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SelectChannelEndPoint@17b31db{/192.168.1.9:60535<->8080,Open,in,out,-,-,30000/30000,HttpConnection}{io=0,kio=0,kro=1} idle timeout check, elapsed: 30000 ms, remaining: 0 ms 22:07:19.730 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SelectChannelEndPoint@17b31db{/192.168.1.9:60535<->8080,Open,in,out,-,-,30001/30000,HttpConnection}{io=0,kio=0,kro=1} idle timeout expired 22:07:19.730 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - ignored: WriteFlusher@14d7529{IDLE} java.util.concurrent.TimeoutException: Idle timeout expired: 30000/30000 ms 22:07:19.731 [DEBUG] [rg.eclipse.jetty.io.AbstractEndPoint] - Ignored idle endpoint SelectChannelEndPoint@17b31db{/192.168.1.9:60535<->8080,Open,in,out,-,-,30003/30000,HttpConnection}{io=0,kio=0,kro=1} 22:07:20.626 [DEBUG] [io.socket.engineio.client.Socket ] - writing ping packet - expecting pong within 60000ms 22:07:20.626 [DEBUG] [io.socket.engineio.client.Socket ] - flushing 1 packets in socket 22:07:20.669 [DEBUG] [io.socket.engineio.client.Socket ] - socket received: type 'pong', data 'null' 22:07:21.383 [DEBUG] [rg.quartz.core.QuartzSchedulerThread] - batch acquisition of 0 triggers 22:07:24.489 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skippedRepeated again and again
Log at startup would be of any interest ?
There is some progress though: NEW CONNECTION when starting OH2!!! :)mysgw: Starting gateway... mysgw: Protocol version - 2.0.1-beta mysgw: MCO:BGN:INIT GW,CP=RNNG---,VER=2.0.1-beta mysgw: TSF:LRT:OK mysgw: TSM:INIT mysgw: TSM:INIT:TSP OK mysgw: TSM:INIT:GW MODE mysgw: TSM:READY mysgw: Listening for connections on 0.0.0.0:5003 mysgw: MCO:REG:NOT NEEDED mysgw: MCO:BGN:STP mysgw: MCO:BGN:INIT OK,ID=0,PAR=0,DIS=0,REG=1 mysgw: TSF:MSG:READ,1-1-0,s=255,c=3,t=26,pt=1,l=1,sg=0:2 mysgw: TSF:MSG:SEND,0-0-1-1,s=255,c=3,t=27,pt=1,l=1,sg=0,ft=0,st=OK:1 mysgw: TSF:MSG:READ,1-1-0,s=255,c=3,t=15,pt=6,l=2,sg=0:0100 mysgw: TSF:MSG:SEND,0-0-1-1,s=255,c=3,t=15,pt=6,l=2,sg=0,ft=0,st=OK:0100 mysgw: TSF:MSG:READ,1-1-0,s=255,c=0,t=17,pt=0,l=5,sg=0:2.0.0 mysgw: TSF:MSG:READ,1-1-0,s=255,c=3,t=6,pt=1,l=1,sg=0:0 mysgw: TSF:MSG:READ,1-1-0,s=255,c=3,t=11,pt=0,l=22,sg=0:TemperatureAndHumidity mysgw: TSF:MSG:READ,1-1-0,s=255,c=3,t=12,pt=0,l=3,sg=0:1.1 mysgw: TSF:MSG:READ,1-1-0,s=0,c=0,t=7,pt=0,l=0,sg=0: mysgw: TSF:MSG:READ,1-1-0,s=1,c=0,t=6,pt=0,l=0,sg=0: mysgw: TSF:MSG:READ,1-1-0,s=255,c=3,t=26,pt=1,l=1,sg=0:2 mysgw: TSF:MSG:SEND,0-0-1-1,s=255,c=3,t=27,pt=1,l=1,sg=0,ft=0,st=OK:1 mysgw: TSF:MSG:READ,1-1-0,s=1,c=1,t=0,pt=7,l=5,sg=0:19.9 mysgw: TSF:MSG:READ,1-1-0,s=0,c=1,t=1,pt=7,l=5,sg=0:61.2 mysgw: New connection from 127.0.0.1 mysgw: Client 0 connected mysgw: TSF:MSG:READ,1-1-0,s=0,c=1,t=1,pt=7,l=5,sg=0:61.3Thank you so much for your time
-
@TimO Can you say anything regarding the autoupdate=false option yet? It would be useful to separate the UI user action from the status displayed by the UI. Rollershutters for example can show the percentage of how much the shutters are down. This conflicts with the autoupdate=true behaviour which sets the value to 0 as soon as the UP button is pushed.
-
-
-
@Jic I'm glad it worked out finally, thank you for your feedback!
@ben999 Please do so and ensure you install the latest snapshot of OH2.
At least I think we found the guilty one:
"The methods ThingHandler.bridgeHandlerInitialized() and ThingHandler.bridgeHandlerDisposed() are removed. This breaks the API."
PR LINKThe most annoying part is: I don't see a way to keep up backward compatibility with the current testing release (beta4).
-
@TimO Can you say anything regarding the autoupdate=false option yet? It would be useful to separate the UI user action from the status displayed by the UI. Rollershutters for example can show the percentage of how much the shutters are down. This conflicts with the autoupdate=true behaviour which sets the value to 0 as soon as the UP button is pushed.
@undee I'm not able to find a hint, that it is already implemented in OH2, do you have more information for me?
Defining:
Switch light01 "light01" { autoupdate=false, channel="mysensors:light:gateway:light01:status" }Returns:
[.e.s.m.i.i.GenericItemProvider:385 ] - Couldn't find ItemFactory for item 'channel' of type 'false'Does not look like OH2 already knows what it means.
-
@undee I'm not able to find a hint, that it is already implemented in OH2, do you have more information for me?
Defining:
Switch light01 "light01" { autoupdate=false, channel="mysensors:light:gateway:light01:status" }Returns:
[.e.s.m.i.i.GenericItemProvider:385 ] - Couldn't find ItemFactory for item 'channel' of type 'false'Does not look like OH2 already knows what it means.
@TimO The MQTT binding might be helpful. It is possible to implement the following behaviour with MQTT when autoupdate is set to false:
When you touch a switch, the defined message is published to an MQTT topic, but the button status remains unchanged. Then, for example, the device processes the message and publishes a status message which then causes the update to the visible status of the button.
The same can be implemented with shutters. When you push the button to move the shutters up, with autoupdate=true the icon is immediately set to reflect 0%. With autoupdate=false, you can show the current position by updating the position while it is in motion.
I can't do this with the MySensors binding yet because pushing a button always updates the status automatically.
-
@Jic I'm glad it worked out finally, thank you for your feedback!
@ben999 Please do so and ensure you install the latest snapshot of OH2.
At least I think we found the guilty one:
"The methods ThingHandler.bridgeHandlerInitialized() and ThingHandler.bridgeHandlerDisposed() are removed. This breaks the API."
PR LINKThe most annoying part is: I don't see a way to keep up backward compatibility with the current testing release (beta4).
@TimO
:crown: you deserve that one :)
All working fine, no trouble
Very clean OH2 log file
Happy man i am!
Thanks again for time, effort and availability
Please let me know if i can help in any waypi@raspberrypi:/usr/share/openhab2 $ ./start.sh Launching the openHAB runtime... __ _____ ____ ____ ____ ___ ____ / / / / | / __ ) / __ \/ __ \/ _ \/ __ \/ /_/ / /| | / __ | / /_/ / /_/ / __/ / / / __ / ___ |/ /_/ / \____/ .___/\___/_/ /_/_/ /_/_/ |_/_____/ /_/ 2.0.0-SNAPSHOT Build #609 Hit '<tab>' for a list of available commands and '[cmd] --help' for help on a specific command. Hit '<ctrl-d>' or type 'system:shutdown' or 'logout' to shutdown openHAB. openhab> log:set DEBUG org.openhab.binding.mysensors openhab> log:tail 21:42:00.387 [DEBUG] [org.openhab.binding.mysensors ] - BundleEvent STARTING - org.openhab.binding.mysensors 21:42:00.403 [DEBUG] [org.openhab.binding.mysensors ] - BundleEvent STARTED - org.openhab.binding.mysensors 21:42:00.523 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={component.name=org.openhab.binding.mysensors.internal.factory.MySensorsHandlerFactory, component.id=0, service.id=106, service.bundleid=10, service.scope=bundle} - org.openhab.binding.mysensors 21:42:14.666 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'home.things' 21:42:14.994 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'mysensors:bridge-eth:gateway' has been added. 21:42:15.002 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'mysensors:humidity:gateway:bathHum01' has been added. 21:42:15.006 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'mysensors:temperature:gateway:bathTemp01' has been added. 21:42:15.433 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=230, service.bundleid=10, service.scope=singleton} - org.openhab.binding.mysensors 21:42:15.451 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:bridge-eth:gateway' changed from UNINITIALIZED to INITIALIZING 21:42:15.453 [DEBUG] [ernal.handler.MySensorsBridgeHandler] - Initialization of the MySensors bridge 21:42:15.464 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:humidity:gateway:bathHum01' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR) 21:42:15.474 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:temperature:gateway:bathTemp01' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR) 21:42:15.507 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Set skip check on startup to: false 21:42:15.508 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Enabling connection watchdog 21:42:15.517 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Connecting to IP bridge [127.0.0.1:5003] 21:42:15.532 [DEBUG] [ternal.factory.MySensorsCacheFactory] - Cache file: given_ids.cached exist. 21:42:15.534 [DEBUG] [ternal.factory.MySensorsCacheFactory] - Cache (given_ids) content: [1] 21:42:15.540 [DEBUG] [ernal.handler.MySensorsBridgeHandler] - Initialization of the MySensors bridge DONE! 21:42:15.541 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;14;Gateway startup complete. 21:42:15.548 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;14;Gateway startup complete. 21:42:15.552 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_humidity_gateway_bathHum01_hum' has been added. 21:42:15.554 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;0;0;18;2.0.1-beta 21:42:15.556 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'mysensors_humidity_gateway_bathHum01_hum-mysensors:humidity:gateway:bathHum01:hum' has been added. 21:42:15.555 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;0;0;18;2.0.1-beta 21:42:15.559 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_humidity_gateway_bathHum01_lastupdate' has been added. 21:42:15.562 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'mysensors_humidity_gateway_bathHum01_lastupdate-mysensors:humidity:gateway:bathHum01:lastupdate' has been added. 21:42:15.565 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_temperature_gateway_bathTemp01_lastupdate' has been added. 21:42:15.569 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'mysensors_temperature_gateway_bathTemp01_lastupdate-mysensors:temperature:gateway:bathTemp01:lastupdate' has been added. 21:42:15.571 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_temperature_gateway_bathTemp01_temp' has been added. 21:42:15.573 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'mysensors_temperature_gateway_bathTemp01_temp-mysensors:temperature:gateway:bathTemp01:temp' has been added. 21:42:15.729 [DEBUG] [rs.internal.protocol.MySensorsWriter] - Sending to MySensors: 0;0;3;0;2; 21:42:15.743 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;2;2.0.1-beta 21:42:15.744 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Good,Gateway is up and running! (Ver:2.0.1-beta) 21:42:15.745 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;2;2.0.1-beta 21:42:15.749 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:bridge-eth:gateway' changed from INITIALIZING to ONLINE 21:42:15.759 [INFO ] [al.protocol.ip.MySensorsIpConnection] - Successfully connected to MySensors Bridge. 21:42:15.761 [WARN ] [al.protocol.ip.MySensorsIpConnection] - Network Sanity Checker thread disabled from bridge configuration 21:42:15.788 [DEBUG] [ternal.handler.MySensorsThingHandler] - Configuration: node 1, chiledId: 1, revertState: false 21:42:15.789 [DEBUG] [ternal.handler.MySensorsThingHandler] - Event listener for node 1-1 not registered yet, registering... 21:42:15.789 [DEBUG] [ternal.handler.MySensorsThingHandler] - Configuration: node 1, chiledId: 0, revertState: false 21:42:15.790 [DEBUG] [ternal.handler.MySensorsThingHandler] - Event listener for node 1-0 not registered yet, registering... 21:42:15.794 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:temperature:gateway:bathTemp01' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING 21:42:15.796 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:humidity:gateway:bathHum01' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING 21:42:15.797 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:temperature:gateway:bathTemp01' changed from INITIALIZING to ONLINE 21:42:15.799 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:humidity:gateway:bathHum01' changed from INITIALIZING to ONLINE 21:42:17.115 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app 21:42:17.453 [INFO ] [assic.internal.servlet.WebAppServlet] - Started Classic UI at /classicui/app 21:42:17.949 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /ui 21:42:18.125 [INFO ] [.dashboard.internal.DashboardService] - Started dashboard at /start 21:42:25.762 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:42:35.763 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:42:45.765 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:42:55.766 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:43:05.768 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:43:13.988 [DEBUG] [rs.internal.protocol.MySensorsReader] - 1;0;1;0;1;62.5 21:43:14.005 [DEBUG] [ternal.handler.MySensorsThingHandler] - Setting last update for node 1 to 2016-11-23T21:43:13.995+0000 21:43:14.010 [DEBUG] [ternal.handler.MySensorsThingHandler] - Setting last update for node 1 to 2016-11-23T21:43:14.006+0000 21:43:14.016 [DEBUG] [rs.internal.protocol.MySensorsReader] - 1;0;1;0;1;62.5 21:43:14.022 [INFO ] [marthome.event.ItemStateChangedEvent] - mysensors_temperature_gateway_bathTemp01_lastupdate changed from NULL to 2016-11-23T21:43:13.995+0000 21:43:14.037 [INFO ] [marthome.event.ItemStateChangedEvent] - mysensors_humidity_gateway_bathHum01_lastupdate changed from NULL to 2016-11-23T21:43:14.006+0000 21:43:14.040 [INFO ] [marthome.event.ItemStateChangedEvent] - mysensors_humidity_gateway_bathHum01_hum changed from NULL to 62.5 21:43:15.769 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:43:25.771 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:43:35.772 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:43:45.775 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:43:55.778 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:44:05.779 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:44:15.780 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:44:20.330 [DEBUG] [rs.internal.protocol.MySensorsReader] - 1;1;1;0;0;18.9 21:44:20.340 [DEBUG] [ternal.handler.MySensorsThingHandler] - Setting last update for node 1 to 2016-11-23T21:44:20.333+0000 21:44:20.348 [DEBUG] [ternal.handler.MySensorsThingHandler] - Setting last update for node 1 to 2016-11-23T21:44:20.342+0000 21:44:20.349 [DEBUG] [rs.internal.protocol.MySensorsReader] - 1;1;1;0;0;18.9 21:44:20.352 [DEBUG] [rs.internal.protocol.MySensorsReader] - 1;0;1;0;1;62.0 21:44:20.362 [DEBUG] [rs.internal.protocol.MySensorsReader] - 1;0;1;0;1;62.0 21:44:20.364 [INFO ] [marthome.event.ItemStateChangedEvent] - mysensors_temperature_gateway_bathTemp01_lastupdate changed from 2016-11-23T21:43:13.995+0000 to 2016-11-23T21:44:20.333+0000 21:44:20.370 [INFO ] [marthome.event.ItemStateChangedEvent] - mysensors_temperature_gateway_bathTemp01_temp changed from NULL to 18.9 21:44:20.381 [INFO ] [marthome.event.ItemStateChangedEvent] - mysensors_humidity_gateway_bathHum01_lastupdate changed from 2016-11-23T21:43:14.006+0000 to 2016-11-23T21:44:20.342+0000 21:44:20.387 [INFO ] [marthome.event.ItemStateChangedEvent] - mysensors_humidity_gateway_bathHum01_hum changed from 62.5 to 62.0 21:44:25.783 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:44:35.785 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:44:45.786 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:44:55.788 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:45:05.789 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:45:15.792 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped -
@TimO
:crown: you deserve that one :)
All working fine, no trouble
Very clean OH2 log file
Happy man i am!
Thanks again for time, effort and availability
Please let me know if i can help in any waypi@raspberrypi:/usr/share/openhab2 $ ./start.sh Launching the openHAB runtime... __ _____ ____ ____ ____ ___ ____ / / / / | / __ ) / __ \/ __ \/ _ \/ __ \/ /_/ / /| | / __ | / /_/ / /_/ / __/ / / / __ / ___ |/ /_/ / \____/ .___/\___/_/ /_/_/ /_/_/ |_/_____/ /_/ 2.0.0-SNAPSHOT Build #609 Hit '<tab>' for a list of available commands and '[cmd] --help' for help on a specific command. Hit '<ctrl-d>' or type 'system:shutdown' or 'logout' to shutdown openHAB. openhab> log:set DEBUG org.openhab.binding.mysensors openhab> log:tail 21:42:00.387 [DEBUG] [org.openhab.binding.mysensors ] - BundleEvent STARTING - org.openhab.binding.mysensors 21:42:00.403 [DEBUG] [org.openhab.binding.mysensors ] - BundleEvent STARTED - org.openhab.binding.mysensors 21:42:00.523 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={component.name=org.openhab.binding.mysensors.internal.factory.MySensorsHandlerFactory, component.id=0, service.id=106, service.bundleid=10, service.scope=bundle} - org.openhab.binding.mysensors 21:42:14.666 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'home.things' 21:42:14.994 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'mysensors:bridge-eth:gateway' has been added. 21:42:15.002 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'mysensors:humidity:gateway:bathHum01' has been added. 21:42:15.006 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing 'mysensors:temperature:gateway:bathTemp01' has been added. 21:42:15.433 [DEBUG] [org.openhab.binding.mysensors ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=230, service.bundleid=10, service.scope=singleton} - org.openhab.binding.mysensors 21:42:15.451 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:bridge-eth:gateway' changed from UNINITIALIZED to INITIALIZING 21:42:15.453 [DEBUG] [ernal.handler.MySensorsBridgeHandler] - Initialization of the MySensors bridge 21:42:15.464 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:humidity:gateway:bathHum01' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR) 21:42:15.474 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:temperature:gateway:bathTemp01' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR) 21:42:15.507 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Set skip check on startup to: false 21:42:15.508 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Enabling connection watchdog 21:42:15.517 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Connecting to IP bridge [127.0.0.1:5003] 21:42:15.532 [DEBUG] [ternal.factory.MySensorsCacheFactory] - Cache file: given_ids.cached exist. 21:42:15.534 [DEBUG] [ternal.factory.MySensorsCacheFactory] - Cache (given_ids) content: [1] 21:42:15.540 [DEBUG] [ernal.handler.MySensorsBridgeHandler] - Initialization of the MySensors bridge DONE! 21:42:15.541 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;14;Gateway startup complete. 21:42:15.548 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;14;Gateway startup complete. 21:42:15.552 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_humidity_gateway_bathHum01_hum' has been added. 21:42:15.554 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;0;0;18;2.0.1-beta 21:42:15.556 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'mysensors_humidity_gateway_bathHum01_hum-mysensors:humidity:gateway:bathHum01:hum' has been added. 21:42:15.555 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;0;0;18;2.0.1-beta 21:42:15.559 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_humidity_gateway_bathHum01_lastupdate' has been added. 21:42:15.562 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'mysensors_humidity_gateway_bathHum01_lastupdate-mysensors:humidity:gateway:bathHum01:lastupdate' has been added. 21:42:15.565 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_temperature_gateway_bathTemp01_lastupdate' has been added. 21:42:15.569 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'mysensors_temperature_gateway_bathTemp01_lastupdate-mysensors:temperature:gateway:bathTemp01:lastupdate' has been added. 21:42:15.571 [INFO ] [smarthome.event.ItemAddedEvent ] - Item 'mysensors_temperature_gateway_bathTemp01_temp' has been added. 21:42:15.573 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'mysensors_temperature_gateway_bathTemp01_temp-mysensors:temperature:gateway:bathTemp01:temp' has been added. 21:42:15.729 [DEBUG] [rs.internal.protocol.MySensorsWriter] - Sending to MySensors: 0;0;3;0;2; 21:42:15.743 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;2;2.0.1-beta 21:42:15.744 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Good,Gateway is up and running! (Ver:2.0.1-beta) 21:42:15.745 [DEBUG] [rs.internal.protocol.MySensorsReader] - 0;255;3;0;2;2.0.1-beta 21:42:15.749 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:bridge-eth:gateway' changed from INITIALIZING to ONLINE 21:42:15.759 [INFO ] [al.protocol.ip.MySensorsIpConnection] - Successfully connected to MySensors Bridge. 21:42:15.761 [WARN ] [al.protocol.ip.MySensorsIpConnection] - Network Sanity Checker thread disabled from bridge configuration 21:42:15.788 [DEBUG] [ternal.handler.MySensorsThingHandler] - Configuration: node 1, chiledId: 1, revertState: false 21:42:15.789 [DEBUG] [ternal.handler.MySensorsThingHandler] - Event listener for node 1-1 not registered yet, registering... 21:42:15.789 [DEBUG] [ternal.handler.MySensorsThingHandler] - Configuration: node 1, chiledId: 0, revertState: false 21:42:15.790 [DEBUG] [ternal.handler.MySensorsThingHandler] - Event listener for node 1-0 not registered yet, registering... 21:42:15.794 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:temperature:gateway:bathTemp01' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING 21:42:15.796 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:humidity:gateway:bathHum01' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING 21:42:15.797 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:temperature:gateway:bathTemp01' changed from INITIALIZING to ONLINE 21:42:15.799 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:humidity:gateway:bathHum01' changed from INITIALIZING to ONLINE 21:42:17.115 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app 21:42:17.453 [INFO ] [assic.internal.servlet.WebAppServlet] - Started Classic UI at /classicui/app 21:42:17.949 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /ui 21:42:18.125 [INFO ] [.dashboard.internal.DashboardService] - Started dashboard at /start 21:42:25.762 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:42:35.763 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:42:45.765 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:42:55.766 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:43:05.768 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:43:13.988 [DEBUG] [rs.internal.protocol.MySensorsReader] - 1;0;1;0;1;62.5 21:43:14.005 [DEBUG] [ternal.handler.MySensorsThingHandler] - Setting last update for node 1 to 2016-11-23T21:43:13.995+0000 21:43:14.010 [DEBUG] [ternal.handler.MySensorsThingHandler] - Setting last update for node 1 to 2016-11-23T21:43:14.006+0000 21:43:14.016 [DEBUG] [rs.internal.protocol.MySensorsReader] - 1;0;1;0;1;62.5 21:43:14.022 [INFO ] [marthome.event.ItemStateChangedEvent] - mysensors_temperature_gateway_bathTemp01_lastupdate changed from NULL to 2016-11-23T21:43:13.995+0000 21:43:14.037 [INFO ] [marthome.event.ItemStateChangedEvent] - mysensors_humidity_gateway_bathHum01_lastupdate changed from NULL to 2016-11-23T21:43:14.006+0000 21:43:14.040 [INFO ] [marthome.event.ItemStateChangedEvent] - mysensors_humidity_gateway_bathHum01_hum changed from NULL to 62.5 21:43:15.769 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:43:25.771 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:43:35.772 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:43:45.775 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:43:55.778 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:44:05.779 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:44:15.780 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:44:20.330 [DEBUG] [rs.internal.protocol.MySensorsReader] - 1;1;1;0;0;18.9 21:44:20.340 [DEBUG] [ternal.handler.MySensorsThingHandler] - Setting last update for node 1 to 2016-11-23T21:44:20.333+0000 21:44:20.348 [DEBUG] [ternal.handler.MySensorsThingHandler] - Setting last update for node 1 to 2016-11-23T21:44:20.342+0000 21:44:20.349 [DEBUG] [rs.internal.protocol.MySensorsReader] - 1;1;1;0;0;18.9 21:44:20.352 [DEBUG] [rs.internal.protocol.MySensorsReader] - 1;0;1;0;1;62.0 21:44:20.362 [DEBUG] [rs.internal.protocol.MySensorsReader] - 1;0;1;0;1;62.0 21:44:20.364 [INFO ] [marthome.event.ItemStateChangedEvent] - mysensors_temperature_gateway_bathTemp01_lastupdate changed from 2016-11-23T21:43:13.995+0000 to 2016-11-23T21:44:20.333+0000 21:44:20.370 [INFO ] [marthome.event.ItemStateChangedEvent] - mysensors_temperature_gateway_bathTemp01_temp changed from NULL to 18.9 21:44:20.381 [INFO ] [marthome.event.ItemStateChangedEvent] - mysensors_humidity_gateway_bathHum01_lastupdate changed from 2016-11-23T21:43:14.006+0000 to 2016-11-23T21:44:20.342+0000 21:44:20.387 [INFO ] [marthome.event.ItemStateChangedEvent] - mysensors_humidity_gateway_bathHum01_hum changed from 62.5 to 62.0 21:44:25.783 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:44:35.785 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:44:45.786 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:44:55.788 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:45:05.789 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped 21:45:15.792 [DEBUG] [al.protocol.ip.MySensorsIpConnection] - Bridge is connected, connection skipped -
@ben999 Thank you for your kind words and for your patience! :-)
I'm glad it is working now. As it is quiet for a day now, I suppose the problem is solved.
@TimO thanks to you. My Sensor seems working right since a week. :)
But I have noticed a beahaviour that I think it's anomal.
I have a Door Sensor, their lastupdate attribute upate their value almost each 10 minuts approximately. I think it should be updated when the state of the tripped value changes, or not?Also, if the tripped value change quickly, the lastupdate attribute not change.
I'm triying that the lastupdate value is the last time the door was open or close, but I don't find the way to do that. Is this a bug? I have reviewed my skecht and I don't see any posible error in it.
Thanks in advance
-
@Jic Do you have a corresponding log for me?
My last update value only updates if a update is received. If I don't get an update for hours the last update variable won't change.
Maybe you receive a not tripped from the motion sensor? That will update the last update variable.
-
@Jic Do you have a corresponding log for me?
My last update value only updates if a update is received. If I don't get an update for hours the last update variable won't change.
Maybe you receive a not tripped from the motion sensor? That will update the last update variable.
@TimO right now, I have only one Door sensor.
my logs are2016-12-01 23:02:43.047 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped 2016-12-01 23:02:53.049 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped 2016-12-01 23:02:53.909 [DEBUG] [rs.internal.protocol.MySensorsReader] - 10;1;1;0;16;1 2016-12-01 23:02:53.923 [DEBUG] [ternal.handler.MySensorsThingHandler] - Setting last update for node 10 to 2016-12-01T23:02:53.913+0100 2016-12-01 23:02:53.990 [DEBUG] [rs.internal.protocol.MySensorsReader] - 10;255;3;0;22;100788 2016-12-01 23:03:03.051 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped 2016-12-01 23:03:13.053 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped 2016-12-01 23:07:03.111 [DEBUG] [col.serial.MySensorsSerialConnection] - Bridge is connected, connection skipped 2016-12-01 23:07:05.190 [DEBUG] [rs.internal.protocol.MySensorsReader] - 10;255;3;0;21;0 2016-12-01 23:07:05.203 [DEBUG] [ternal.handler.MySensorsThingHandler] - Setting last update for node 10 to 2016-12-01T23:07:05.193+0100 ==> events.log <== 2016-12-01 23:07:05.229 [ItemStateChangedEvent ] - mysensors_door_gateway_Door_10_1_lastupdate changed from 2016-12-01T23:02:53.913+0100 to 2016-12-01T23:07:05.193+0100 2016-12-01 23:07:05.264 [ItemStateChangedEvent ] - Cancela01time changed from 2016-12-01T23:02:53.913+0100 to 2016-12-01T23:07:05.193+0100You can see last event is at 23:07, however in the following snapshot the time is 11:17
No more events, but the lastupdate change
-
@Jic Do you have a corresponding log for me?
My last update value only updates if a update is received. If I don't get an update for hours the last update variable won't change.
Maybe you receive a not tripped from the motion sensor? That will update the last update variable.
@TimO ok, I have discovered that my "tail" command doesn't work rigth, sorry
My logs are
2016-12-01 23:02:53.990 [DEBUG] [rs.internal.protocol.MySensorsReader] - 10;255;3;0;22;100788 2016-12-01 23:07:05.190 [DEBUG] [rs.internal.protocol.MySensorsReader] - 10;255;3;0;21;0 2016-12-01 23:17:05.248 [DEBUG] [rs.internal.protocol.MySensorsReader] - 10;255;3;0;21;0 2016-12-01 23:27:05.326 [DEBUG] [rs.internal.protocol.MySensorsReader] - 10;255;3;0;21;0 2016-12-01 23:37:05.409 [DEBUG] [rs.internal.protocol.MySensorsReader] - 10;255;3;0;21;0 2016-12-01 23:47:05.486 [DEBUG] [rs.internal.protocol.MySensorsReader] - 10;255;3;0;21;0 2016-12-01 23:57:05.562 [DEBUG] [rs.internal.protocol.MySensorsReader] - 10;255;3;0;21;0 2016-12-02 00:07:05.639 [DEBUG] [rs.internal.protocol.MySensorsReader] - 10;255;3;0;21;0I don't understand why am I reciving this message?
-
@TimO ok, I have discovered that my "tail" command doesn't work rigth, sorry
My logs are
2016-12-01 23:02:53.990 [DEBUG] [rs.internal.protocol.MySensorsReader] - 10;255;3;0;22;100788 2016-12-01 23:07:05.190 [DEBUG] [rs.internal.protocol.MySensorsReader] - 10;255;3;0;21;0 2016-12-01 23:17:05.248 [DEBUG] [rs.internal.protocol.MySensorsReader] - 10;255;3;0;21;0 2016-12-01 23:27:05.326 [DEBUG] [rs.internal.protocol.MySensorsReader] - 10;255;3;0;21;0 2016-12-01 23:37:05.409 [DEBUG] [rs.internal.protocol.MySensorsReader] - 10;255;3;0;21;0 2016-12-01 23:47:05.486 [DEBUG] [rs.internal.protocol.MySensorsReader] - 10;255;3;0;21;0 2016-12-01 23:57:05.562 [DEBUG] [rs.internal.protocol.MySensorsReader] - 10;255;3;0;21;0 2016-12-02 00:07:05.639 [DEBUG] [rs.internal.protocol.MySensorsReader] - 10;255;3;0;21;0I don't understand why am I reciving this message?
@Jic According to the serial API this is a I_DISCOVER_RESPONSE, but why is it sent? This seeems to update the lastUpdate channel.
I see it this way: "lastUpdate" is the last time the node has sent something, so everything is working as expected. :D
-
@Jic According to the serial API this is a I_DISCOVER_RESPONSE, but why is it sent? This seeems to update the lastUpdate channel.
I see it this way: "lastUpdate" is the last time the node has sent something, so everything is working as expected. :D
-
@bentrik said:
I managed to find an addons directory in /usr/share/openhab2/addons, but I can´t find a way to copy the .jar file there, as I get permission denied both through WinSCP in sudo mode and terminal ssh. The folder has permission 0775.
That is the correct directory if you installed OH2 via .deb. Seems like you are using a user in WinSCP that is not allowed to write to the directory above. To circumvent this:
- Copy the binding jar to /tmp.
- Login via ssh and execute:
sudo cp /tmp/org.openhab.binding.mysensors-2.0.0-SNAPSHOT.jar /usr/share/openhab2/addons/and
sudo chmod openhab.openhab /usr/share/openhab2/addons/org.openhab.binding.mysensors-2.0.0-SNAPSHOT.jarOH2 is started/stopped/restarted with
service openhab2 start/stop/restartTo log into the karaf console do:
ssh openhab@localhost -p 8101Password is: habopen
Follow the guide/wiki from:
In the console enter feature:install openhab-transport-serial@TimO said:
@bentrik said:
I managed to find an addons directory in /usr/share/openhab2/addons, but I can´t find a way to copy the .jar file there, as I get permission denied both through WinSCP in sudo mode and terminal ssh. The folder has permission 0775.
That is the correct directory if you installed OH2 via .deb. Seems like you are using a user in WinSCP that is not allowed to write to the directory above. To circumvent this:
- Copy the binding jar to /tmp.
- Login via ssh and execute:
sudo cp /tmp/org.openhab.binding.mysensors-2.0.0-SNAPSHOT.jar /usr/share/openhab2/addons/and
sudo chmod openhab.openhab /usr/share/openhab2/addons/org.openhab.binding.mysensors-2.0.0-SNAPSHOT.jarOH2 is started/stopped/restarted with
service openhab2 start/stop/restartTo log into the karaf console do:
ssh openhab@localhost -p 8101Password is: habopen
Follow the guide/wiki from:
In the console enter feature:install openhab-transport-serialI have installed openhab with the openhabian image. I could start the mysensors gateway too.
I followed the github-openhab2 installation instructions.
To this pointIn the console enter feature:install openhab-transport-serialI read a lot of posts on this thread but on get this error:
openhab> install openhab-transport-serial Bundle IDs: Error executing command: Error installing bundles: Unable to install bundle openhab-transport-serialthe chmod line posted above doesn't work neither. chmod does not accept openhab.openhab
I dont have any clue how to install the binding for openhab2 ... why isn't that possible from paperui :-(
