2017-07-13 16:23:04.895 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - parseNext s=CONTENT HeapByteBuffer@baf65e[p=444,l=804,c=8192,r=360]={POST /rest/things...ip, deflate\r\n\r\n<<<{"UID":"innogysma...rthome:bridge"}>>>\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}
2017-07-13 16:23:04.897 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@17a716b{r=1,c=false,a=DISPATCHED,uri=/rest/things} content java.nio.HeapByteBufferR[pos=444 lim=804 cap=8192]
2017-07-13 16:23:04.898 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - parseNext s=CONTENT HeapByteBuffer@baf65e[p=804,l=804,c=8192,r=0]={POST /rest/things...rthome:bridge"}<<<>>>\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}
2017-07-13 16:23:04.899 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - CONTENT --> END
2017-07-13 16:23:04.900 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@17a716b{r=1,c=false,a=DISPATCHED,uri=/rest/things} messageComplete
2017-07-13 16:23:04.901 [DEBUG] [org.eclipse.jetty.server.HttpInput  ] - HttpInputOverHTTP@68b93a EOF
2017-07-13 16:23:04.903 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 SelectChannelEndPoint@73ea53{/192.168.178.29:49922<->8080,Open,in,out,-,-,63/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-07-13 16:23:04.906 [DEBUG] [.eclipse.jetty.server.HttpConnection] - HttpConnection@9af173[FILLING,SelectChannelEndPoint@73ea53{/192.168.178.29:49922<->8080,Open,in,out,-,-,65/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=END,360 of 360},g=HttpGenerator{s=START},c=HttpChannelOverHttp@17a716b{r=1,c=false,a=DISPATCHED,uri=/rest/things}] filled 0
2017-07-13 16:23:04.907 [DEBUG] [org.eclipse.jetty.server.HttpInput  ] - HttpInputOverHTTP@68b93a eof EOF
2017-07-13 16:23:04.909 [DEBUG] [org.eclipse.jetty.server.HttpInput  ] - HttpInputOverHTTP@68b93a eof EOF
2017-07-13 16:23:04.910 [DEBUG] [org.eclipse.jetty.server.HttpInput  ] - HttpInputOverHTTP@68b93a eof EOF
2017-07-13 16:23:04.912 [DEBUG] [e.internal.ReaderInterceptorExecutor] - Message body reader (class org.eclipse.smarthome.io.rest.core.internal.GsonProvider) is trying to close the entity input stream. Not closing.
2017-07-13 16:23:04.953 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@19641fe[PROCESSING][i=null,cb=Blocker@4986af{null}] generate: FLUSH (null,[p=0,l=431,c=32768,r=431],false)@COMMITTED
2017-07-13 16:23:04.955 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - write: WriteFlusher@9a9616{IDLE} [HeapByteBuffer@86f151[p=0,l=7,c=1024,r=7]={<<<\r\n1AF\r\n>>>\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},HeapByteBuffer@1baf3b1[p=0,l=431,c=32768,r=431]={<<<event: message\nda...gAddedEvent"}\n\n>>>\\",\\"configuratio...ngs/windcentral}]
2017-07-13 16:23:04.956 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@9a9616{WRITING}:IDLE-->WRITING
2017-07-13 16:23:04.958 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 438 SelectChannelEndPoint@13d5392{/192.168.178.29:49691<->8080,Open,in,out,-,W,1609/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-07-13 16:23:04.960 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@9a9616{IDLE}:WRITING-->IDLE
2017-07-13 16:23:04.961 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@19641fe[PROCESSING][i=null,cb=Blocker@4986af{null}] generate: DONE (null,[p=431,l=431,c=32768,r=0],false)@COMMITTED
2017-07-13 16:23:04.978 [DEBUG] [org.openhab.binding.innogysmarthome ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=301, service.bundleid=190, service.scope=singleton} - org.openhab.binding.innogysmarthome
2017-07-13 16:23:05.025 [DEBUG] [marthome.handler.InnogyBridgeHandler] - Initializing innogy SmartHome BridgeHandler...
2017-07-13 16:23:05.030 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@19641fe[PROCESSING][i=null,cb=Blocker@4986af{null}] generate: FLUSH (null,[p=0,l=183,c=32768,r=183],false)@COMMITTED
2017-07-13 16:23:05.030 [DEBUG] [marthome.handler.InnogyBridgeHandler] - Config [clientId=24635749, clientSecret=no secret, redirectUrl=https://www.ollie.in/smarthome-austria-token/, authCode=4a1d501d1051435898ad095f95d9c45f, accessToken=, refreshToken=]
2017-07-13 16:23:05.033 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - write: WriteFlusher@9a9616{IDLE} [HeapByteBuffer@86f151[p=0,l=6,c=1024,r=6]={<<<\r\nB7\r\n>>>\n\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},HeapByteBuffer@1baf3b1[p=0,l=183,c=32768,r=183]={<<<event: message\nda...usInfoEvent"}\n\n>>>095f95d9c45f\\",\\"...ngs/windcentral}]
2017-07-13 16:23:05.035 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@9a9616{WRITING}:IDLE-->WRITING
2017-07-13 16:23:05.035 [INFO ] [marthome.handler.InnogyBridgeHandler] - Initializing innogy SmartHome client...
2017-07-13 16:23:05.037 [DEBUG] [n.ollie.innogysmarthome.InnogyClient] - Trying to get access token
2017-07-13 16:23:05.039 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 189 SelectChannelEndPoint@13d5392{/192.168.178.29:49691<->8080,Open,in,out,-,W,79/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-07-13 16:23:05.041 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@9a9616{IDLE}:WRITING-->IDLE
2017-07-13 16:23:05.042 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@19641fe[PROCESSING][i=null,cb=Blocker@4986af{null}] generate: DONE (null,[p=183,l=183,c=32768,r=0],false)@COMMITTED
2017-07-13 16:23:05.042 [INFO ] [google.api.client.http.HttpTransport] - -------------- REQUEST  --------------
POST https://api.services-smarthome.de/AUTH/token
Accept-Encoding: gzip
Authorization: <Not Logged>
User-Agent: Google-HTTP-Java-Client/1.21.0 (gzip)
Content-Type: application/x-www-form-urlencoded; charset=UTF-8
Content-Length: 136

2017-07-13 16:23:05.043 [INFO ] [google.api.client.http.HttpTransport] - curl -v --compressed -X POST -H 'Accept-Encoding: gzip' -H 'Authorization: <Not Logged>' -H 'User-Agent: Google-HTTP-Java-Client/1.21.0 (gzip)' -H 'Content-Type: application/x-www-form-urlencoded; charset=UTF-8' -d '@-' -- 'https://api.services-smarthome.de/AUTH/token' << $$$
2017-07-13 16:23:05.048 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@19641fe[PROCESSING][i=null,cb=Blocker@4986af{null}] generate: FLUSH (null,[p=0,l=256,c=32768,r=256],false)@COMMITTED
2017-07-13 16:23:05.050 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - write: WriteFlusher@9a9616{IDLE} [HeapByteBuffer@86f151[p=0,l=7,c=1024,r=7]={<<<\r\n100\r\n>>>\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},HeapByteBuffer@1baf3b1[p=0,l=256,c=32768,r=256]={<<<event: message\nda...hangedEvent"}\n\n>>>"websocketidletim...ngs/windcentral}]
2017-07-13 16:23:05.052 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@9a9616{WRITING}:IDLE-->WRITING
2017-07-13 16:23:05.057 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 263 SelectChannelEndPoint@13d5392{/192.168.178.29:49691<->8080,Open,in,out,-,W,16/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-07-13 16:23:05.057 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@1dd9a9d[PROCESSING][i=ResponseInfo{HTTP/1.1 201 Created,354,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@474675] generate: NEED_HEADER (null,[p=0,l=354,c=8192,r=354],true)@START
2017-07-13 16:23:05.059 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@9a9616{IDLE}:WRITING-->IDLE
2017-07-13 16:23:05.059 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@1dd9a9d[PROCESSING][i=ResponseInfo{HTTP/1.1 201 Created,354,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@474675] generate: FLUSH ([p=0,l=147,c=8192,r=147],[p=0,l=354,c=8192,r=354],true)@COMPLETING
2017-07-13 16:23:05.061 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@19641fe[PROCESSING][i=null,cb=Blocker@4986af{null}] generate: DONE (null,[p=256,l=256,c=32768,r=0],false)@COMMITTED
2017-07-13 16:23:05.062 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - write: WriteFlusher@19954d7{IDLE} [HeapByteBuffer@1e5a53d[p=0,l=147,c=8192,r=147]={<<<HTTP/1.1 201 Crea....v20160908)\r\n\r\n>>>on/json, text/pla...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00},HeapByteBuffer@ebbc4f[p=0,l=354,c=8192,r=354]={<<<{"statusInfo":{"s...,"channels":[]}>>>\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}]
2017-07-13 16:23:05.064 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@19954d7{WRITING}:IDLE-->WRITING
2017-07-13 16:23:05.067 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 501 SelectChannelEndPoint@73ea53{/192.168.178.29:49922<->8080,Open,in,out,-,W,227/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-07-13 16:23:05.070 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@19954d7{IDLE}:WRITING-->IDLE
2017-07-13 16:23:05.072 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@1dd9a9d[PROCESSING][i=ResponseInfo{HTTP/1.1 201 Created,354,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@474675] generate: DONE ([p=147,l=147,c=8192,r=0],[p=354,l=354,c=8192,r=0],true)@END
2017-07-13 16:23:05.076 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/things @ o.e.j.s.h.ContextHandler@8f35cf{/static,null,AVAILABLE}
2017-07-13 16:23:05.078 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/things @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.core.audio_0.9.0.b5 [99], contextID=default]}
2017-07-13 16:23:05.080 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/things @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui_0.9.0.b5 [136], contextID=default]}
2017-07-13 16:23:05.081 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/things @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.icon_0.9.0.b5 [137], contextID=default]}
2017-07-13 16:23:05.083 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/things @ HttpServiceContext{httpContext=org.jupnp.transport.impl.osgi.DisableAuthenticationHttpContext@1eeacc2}
2017-07-13 16:23:05.085 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/things @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.ui.dashboard_2.1.0 [170], contextID=default]}
2017-07-13 16:23:05.086 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/things @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.basic_0.9.0.b5 [176], contextID=default]}
2017-07-13 16:23:05.088 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/things @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.paper_0.9.0.b5 [177], contextID=default]}
2017-07-13 16:23:05.090 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/things @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.ui.habpanel_2.1.0 [179], contextID=default]}
2017-07-13 16:23:05.092 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/things @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.core_2.1.0 [166], contextID=default]}
2017-07-13 16:23:05.093 [DEBUG] [org.eclipse.jetty.server.Server     ] - RESPONSE /rest/things  201 handled=true
2017-07-13 16:23:05.095 [DEBUG] [clipse.jetty.server.HttpChannelState] - HttpChannelState@d3e64c{s=DISPATCHED i=true a=null} unhandle DISPATCHED
2017-07-13 16:23:05.097 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - reset HttpParser{s=END,360 of 360}
2017-07-13 16:23:05.099 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - END --> START
2017-07-13 16:23:05.101 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@17a716b{r=1,c=false,a=IDLE,uri=} handle exit, result COMPLETE
2017-07-13 16:23:05.103 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 SelectChannelEndPoint@73ea53{/192.168.178.29:49922<->8080,Open,in,out,-,-,34/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-07-13 16:23:05.106 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 SelectChannelEndPoint@73ea53{/192.168.178.29:49922<->8080,Open,in,out,-,-,36/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-07-13 16:23:05.108 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - parseNext s=START HeapByteBuffer@1b36d3b[p=0,l=0,c=8192,r=0]={<<<>>>GET /rest/inbox H...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2017-07-13 16:23:05.111 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - fillInterested HttpConnection@9af173[FILLING,SelectChannelEndPoint@73ea53{/192.168.178.29:49922<->8080,Open,in,out,-,-,41/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@17a716b{r=1,c=false,a=IDLE,uri=}]
2017-07-13 16:23:05.114 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILLING-->FILLING_FILL_INTERESTED HttpConnection@9af173[FILLING_FILL_INTERESTED,SelectChannelEndPoint@73ea53{/192.168.178.29:49922<->8080,Open,in,out,-,-,44/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@17a716b{r=1,c=false,a=IDLE,uri=}]
2017-07-13 16:23:05.117 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILLING_FILL_INTERESTED-->FILL_INTERESTED HttpConnection@9af173[FILL_INTERESTED,SelectChannelEndPoint@73ea53{/192.168.178.29:49922<->8080,Open,in,out,-,-,47/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@17a716b{r=1,c=false,a=IDLE,uri=}]
2017-07-13 16:23:05.120 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Local interests updating 0 -> 1 for SelectChannelEndPoint@73ea53{/192.168.178.29:49922<->8080,Open,in,out,R,-,0/30000,HttpConnection}{io=1,kio=0,kro=1}
2017-07-13 16:23:05.121 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Queued change org.eclipse.jetty.io.SelectChannelEndPoint$1@1b2a341
2017-07-13 16:23:05.123 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop woken up from select, 0/1 selected
2017-07-13 16:23:05.124 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Running change org.eclipse.jetty.io.SelectChannelEndPoint$1@1b2a341
2017-07-13 16:23:05.126 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Key interests updated 0 -> 1 on SelectChannelEndPoint@73ea53{/192.168.178.29:49922<->8080,Open,in,out,R,-,6/30000,HttpConnection}{io=1,kio=1,kro=1}
2017-07-13 16:23:05.127 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop waiting on select
2017-07-13 16:23:05.244 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop woken up from select, 1/1 selected
2017-07-13 16:23:05.246 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Key interests updated 1 -> 0 on SelectChannelEndPoint@73ea53{/192.168.178.29:49922<->8080,Open,in,out,R,-,126/30000,HttpConnection}{io=1,kio=0,kro=1}
2017-07-13 16:23:05.247 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Local interests updating 1 -> 0 for SelectChannelEndPoint@73ea53{/192.168.178.29:49922<->8080,Open,in,out,R,-,128/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-07-13 16:23:05.249 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Queued change org.eclipse.jetty.io.SelectorManager$ManagedSelector$Accept@1e3d414
2017-07-13 16:23:05.250 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Queued change org.eclipse.jetty.io.SelectChannelEndPoint$1@1b2a341
2017-07-13 16:23:05.250 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop woken up from select, 0/1 selected
2017-07-13 16:23:05.251 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Running change org.eclipse.jetty.io.SelectorManager$ManagedSelector$Accept@1e3d414
2017-07-13 16:23:05.252 [DEBUG] [rg.eclipse.jetty.io.AbstractEndPoint] - onOpen SelectChannelEndPoint@11a4c0e{/192.168.178.29:49923<->8080,Open,in,out,-,-,0/30000,null}{io=0,kio=0,kro=0}
2017-07-13 16:23:05.253 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILL_INTERESTED-->FILLING HttpConnection@9af173[FILLING,SelectChannelEndPoint@73ea53{/192.168.178.29:49922<->8080,Open,in,out,-,-,132/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@17a716b{r=1,c=false,a=IDLE,uri=}]
2017-07-13 16:23:05.253 [DEBUG] [org.eclipse.jetty.io.IdleTimeout    ] - SelectChannelEndPoint@11a4c0e{/192.168.178.29:49923<->8080,Open,in,out,-,-,1/30000,null}{io=0,kio=0,kro=0} idle timeout check, elapsed: 1 ms, remaining: 29999 ms
2017-07-13 16:23:05.255 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - new HttpChannelOverHttp@161a855{r=0,c=false,a=IDLE,uri=} -> SelectChannelEndPoint@11a4c0e{/192.168.178.29:49923<->8080,Open,in,out,-,-,3/30000,null}{io=0,kio=0,kro=0},null,HttpChannelState@15658a5{s=IDLE i=true a=null}
2017-07-13 16:23:05.256 [DEBUG] [.eclipse.jetty.server.HttpConnection] - HttpConnection@9af173[FILLING,SelectChannelEndPoint@73ea53{/192.168.178.29:49922<->8080,Open,in,out,-,-,135/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@17a716b{r=1,c=false,a=IDLE,uri=}] onFillable HttpChannelState@d3e64c{s=IDLE i=true a=null}
2017-07-13 16:23:05.258 [DEBUG] [.eclipse.jetty.server.HttpConnection] - New HTTP Connection HttpConnection@1c38172[IDLE,SelectChannelEndPoint@11a4c0e{/192.168.178.29:49923<->8080,Open,in,out,-,-,4/30000,null}{io=0,kio=0,kro=0}][p=HttpParser{s=START,0 of 0},g=HttpGenerator{s=START},c=HttpChannelOverHttp@161a855{r=0,c=false,a=IDLE,uri=}]
2017-07-13 16:23:05.258 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 358 SelectChannelEndPoint@73ea53{/192.168.178.29:49922<->8080,Open,in,out,-,-,138/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-07-13 16:23:05.260 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - parseNext s=START HeapByteBuffer@1cd6cab[p=0,l=358,c=8192,r=358]={<<<GET /rest/binding...ip, deflate\r\n\r\n>>>ncoding: gzip, de...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2017-07-13 16:23:05.261 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - START --> SPACE1
2017-07-13 16:23:05.262 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - onOpen HttpConnection@1c38172[IDLE,SelectChannelEndPoint@11a4c0e{/192.168.178.29:49923<->8080,Open,in,out,-,-,8/30000,HttpConnection}{io=0,kio=0,kro=0}][p=HttpParser{s=START,0 of 0},g=HttpGenerator{s=START},c=HttpChannelOverHttp@161a855{r=0,c=false,a=IDLE,uri=}]
2017-07-13 16:23:05.263 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - SPACE1 --> URI
2017-07-13 16:23:05.264 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - URI --> SPACE2
2017-07-13 16:23:05.265 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - SPACE2 --> REQUEST_VERSION
2017-07-13 16:23:05.265 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - fillInterested HttpConnection@1c38172[IDLE,SelectChannelEndPoint@11a4c0e{/192.168.178.29:49923<->8080,Open,in,out,-,-,12/30000,HttpConnection}{io=0,kio=0,kro=0}][p=HttpParser{s=START,0 of 0},g=HttpGenerator{s=START},c=HttpChannelOverHttp@161a855{r=0,c=false,a=IDLE,uri=}]
2017-07-13 16:23:05.266 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - REQUEST_VERSION --> HEADER
2017-07-13 16:23:05.268 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2017-07-13 16:23:05.269 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2017-07-13 16:23:05.269 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - IDLE-->FILL_INTERESTED HttpConnection@1c38172[FILL_INTERESTED,SelectChannelEndPoint@11a4c0e{/192.168.178.29:49923<->8080,Open,in,out,-,-,16/30000,HttpConnection}{io=0,kio=0,kro=0}][p=HttpParser{s=START,0 of 0},g=HttpGenerator{s=START},c=HttpChannelOverHttp@161a855{r=0,c=false,a=IDLE,uri=}]
2017-07-13 16:23:05.270 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2017-07-13 16:23:05.271 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Local interests updating 0 -> 1 for SelectChannelEndPoint@11a4c0e{/192.168.178.29:49923<->8080,Open,in,out,R,-,0/30000,HttpConnection}{io=1,kio=0,kro=0}
2017-07-13 16:23:05.271 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Running change org.eclipse.jetty.io.SelectChannelEndPoint$1@1b2a341
2017-07-13 16:23:05.271 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2017-07-13 16:23:05.273 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Queued change org.eclipse.jetty.io.SelectChannelEndPoint$1@bad578
2017-07-13 16:23:05.274 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2017-07-13 16:23:05.275 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2017-07-13 16:23:05.275 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Created SelectChannelEndPoint@11a4c0e{/192.168.178.29:49923<->8080,Open,in,out,R,-,4/30000,HttpConnection}{io=1,kio=0,kro=0}
2017-07-13 16:23:05.276 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2017-07-13 16:23:05.277 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2017-07-13 16:23:05.277 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop waiting on select
2017-07-13 16:23:05.276 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Running change org.eclipse.jetty.io.SelectChannelEndPoint$1@bad578
2017-07-13 16:23:05.279 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2017-07-13 16:23:05.280 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2017-07-13 16:23:05.281 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_VALUE
2017-07-13 16:23:05.281 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Key interests updated 0 -> 1 on SelectChannelEndPoint@11a4c0e{/192.168.178.29:49923<->8080,Open,in,out,R,-,10/30000,HttpConnection}{io=1,kio=1,kro=0}
2017-07-13 16:23:05.282 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_VALUE --> HEADER_IN_VALUE
2017-07-13 16:23:05.284 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2017-07-13 16:23:05.284 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop waiting on select
2017-07-13 16:23:05.285 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2017-07-13 16:23:05.285 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop woken up from select, 1/2 selected
2017-07-13 16:23:05.286 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2017-07-13 16:23:05.288 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Key interests updated 1 -> 0 on SelectChannelEndPoint@11a4c0e{/192.168.178.29:49923<->8080,Open,in,out,R,-,17/30000,HttpConnection}{io=1,kio=0,kro=1}
2017-07-13 16:23:05.288 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> END
2017-07-13 16:23:05.290 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@17a716b{r=2,c=false,a=IDLE,uri=/rest/bindings} messageComplete
2017-07-13 16:23:05.290 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Local interests updating 1 -> 0 for SelectChannelEndPoint@11a4c0e{/192.168.178.29:49923<->8080,Open,in,out,R,-,19/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-07-13 16:23:05.291 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Queued change org.eclipse.jetty.io.SelectChannelEndPoint$1@bad578
2017-07-13 16:23:05.291 [DEBUG] [org.eclipse.jetty.server.HttpInput  ] - HttpInputOverHTTP@68b93a EOF
2017-07-13 16:23:05.293 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@17a716b{r=2,c=false,a=IDLE,uri=/rest/bindings} handle enter
2017-07-13 16:23:05.294 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILL_INTERESTED-->FILLING HttpConnection@1c38172[FILLING,SelectChannelEndPoint@11a4c0e{/192.168.178.29:49923<->8080,Open,in,out,-,-,23/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of 0},g=HttpGenerator{s=START},c=HttpChannelOverHttp@161a855{r=0,c=false,a=IDLE,uri=}]
2017-07-13 16:23:05.295 [DEBUG] [clipse.jetty.server.HttpChannelState] - HttpChannelState@d3e64c{s=IDLE i=true a=null} handling IDLE
2017-07-13 16:23:05.296 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Running change org.eclipse.jetty.io.SelectChannelEndPoint$1@bad578
2017-07-13 16:23:05.297 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@17a716b{r=2,c=false,a=DISPATCHED,uri=/rest/bindings} action REQUEST_DISPATCH
2017-07-13 16:23:05.298 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop waiting on select
2017-07-13 16:23:05.299 [DEBUG] [org.eclipse.jetty.server.Server     ] - REQUEST GET /rest/bindings on HttpChannelOverHttp@17a716b{r=2,c=false,a=DISPATCHED,uri=/rest/bindings}
2017-07-13 16:23:05.300 [DEBUG] [ax.web.service.spi.model.ServerModel] - Matching [/rest/bindings]...
2017-07-13 16:23:05.301 [DEBUG] [ax.web.service.spi.model.ServerModel] - Path [/rest/bindings] matched to {pattern=/rest/.*,model=ServletModel{id=org.ops4j.pax.web.service.spi.model.ServletModel-4,name=org.ops4j.pax.web.service.spi.model.ServletModel-4,urlPatterns=[/rest/*],alias=/rest,servlet=com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge@18f2b74,initParams={},context=ContextModel{id=org.ops4j.pax.web.service.spi.model.ContextModel-3,name=,httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [9], contextID=default],contextParams={},virtualHosts={},connectors={}}}}
2017-07-13 16:23:05.303 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/bindings @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [9], contextID=default]}
2017-07-13 16:23:05.304 [DEBUG] [.jetty.server.handler.ContextHandler] - context=||/rest/bindings @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [9], contextID=default]}
2017-07-13 16:23:05.305 [DEBUG] [org.eclipse.jetty.server.session    ] - sessionManager=org.ops4j.pax.web.service.jetty.internal.LateInvalidatingHashSessionManager@15ba85c
2017-07-13 16:23:05.306 [DEBUG] [org.eclipse.jetty.server.session    ] - session=null
2017-07-13 16:23:05.308 [DEBUG] [eclipse.jetty.servlet.ServletHandler] - servlet |/rest|/bindings -> org.ops4j.pax.web.service.spi.model.ServletModel-4@378be0fc==com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge,-1,true
2017-07-13 16:23:05.309 [DEBUG] [ce.jetty.internal.HttpServiceContext] - Handling request for [/rest/bindings] using http context [DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [9], contextID=default]]
2017-07-13 16:23:05.310 [DEBUG] [y.internal.HttpServiceServletHandler] - handling request org.ops4j.pax.web.service.jetty.internal.HttpServiceRequestWrapper@5b754a, org.ops4j.pax.web.service.jetty.internal.HttpServiceResponseWrapper@9c83f1
2017-07-13 16:23:05.311 [DEBUG] [eclipse.jetty.servlet.ServletHandler] - chain=null
2017-07-13 16:23:05.311 [DEBUG] [.eclipse.jetty.server.HttpConnection] - HttpConnection@1c38172[FILLING,SelectChannelEndPoint@11a4c0e{/192.168.178.29:49923<->8080,Open,in,out,-,-,38/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of 0},g=HttpGenerator{s=START},c=HttpChannelOverHttp@161a855{r=0,c=false,a=IDLE,uri=}] onFillable HttpChannelState@15658a5{s=IDLE i=true a=null}
2017-07-13 16:23:05.315 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 356 SelectChannelEndPoint@11a4c0e{/192.168.178.29:49923<->8080,Open,in,out,-,-,45/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-07-13 16:23:05.318 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - parseNext s=START HeapByteBuffer@187eb13[p=0,l=356,c=8192,r=356]={<<<GET /rest/things ...ip, deflate\r\n\r\n>>>\n\r\ncoding: gzip, ...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2017-07-13 16:23:05.320 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - START --> SPACE1
2017-07-13 16:23:05.323 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - SPACE1 --> URI
2017-07-13 16:23:05.325 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - URI --> SPACE2
2017-07-13 16:23:05.327 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - SPACE2 --> REQUEST_VERSION
2017-07-13 16:23:05.328 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - REQUEST_VERSION --> HEADER
2017-07-13 16:23:05.329 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_VALUE
2017-07-13 16:23:05.330 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_VALUE --> HEADER_IN_VALUE
2017-07-13 16:23:05.333 [DEBUG] [.httpclient.params.DefaultHttpParams] - Set parameter http.socket.timeout = 5000
2017-07-13 16:23:05.334 [DEBUG] [.httpclient.params.DefaultHttpParams] - Set parameter http.method.retry-handler = org.apache.commons.httpclient.DefaultHttpMethodRetryHandler@d3a584
2017-07-13 16:23:05.336 [DEBUG] [he.commons.httpclient.HttpConnection] - Open connection to 192.168.178.28:80
2017-07-13 16:23:05.339 [DEBUG] [httpclient.wire.header              ] - >> "GET /web/getcurrent HTTP/1.1[\r][\n]"
2017-07-13 16:23:05.340 [DEBUG] [he.commons.httpclient.HttpMethodBase] - Adding Host request header
2017-07-13 16:23:05.341 [DEBUG] [httpclient.wire.header              ] - >> "User-Agent: Jakarta Commons-HttpClient/3.1[\r][\n]"
2017-07-13 16:23:05.342 [DEBUG] [httpclient.wire.header              ] - >> "Host: 192.168.178.28[\r][\n]"
2017-07-13 16:23:05.343 [DEBUG] [httpclient.wire.header              ] - >> "[\r][\n]"
2017-07-13 16:23:05.357 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@1dd9a9d[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,509,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@18e7685] generate: NEED_HEADER (null,[p=0,l=509,c=8192,r=509],true)@START
2017-07-13 16:23:05.357 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2017-07-13 16:23:05.358 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2017-07-13 16:23:05.359 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2017-07-13 16:23:05.360 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_VALUE
2017-07-13 16:23:05.360 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@1dd9a9d[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,509,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@18e7685] generate: FLUSH ([p=0,l=142,c=8192,r=142],[p=0,l=509,c=8192,r=509],true)@COMPLETING
2017-07-13 16:23:05.360 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_VALUE --> HEADER_IN_VALUE
2017-07-13 16:23:05.361 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - write: WriteFlusher@19954d7{IDLE} [HeapByteBuffer@1a0a992[p=0,l=142,c=8192,r=142]={<<<HTTP/1.1 200 OK\r\n....v20160908)\r\n\r\n>>>\nUser-Agent: Mozi...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00},HeapByteBuffer@15916e1[p=0,l=509,c=8192,r=509]={<<<[{"author":"Olive...work Binding"}]>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}]
2017-07-13 16:23:05.362 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@19954d7{WRITING}:IDLE-->WRITING
2017-07-13 16:23:05.365 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 651 SelectChannelEndPoint@73ea53{/192.168.178.29:49922<->8080,Open,in,out,-,W,105/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-07-13 16:23:05.368 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@19954d7{IDLE}:WRITING-->IDLE
2017-07-13 16:23:05.361 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2017-07-13 16:23:05.369 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_VALUE
2017-07-13 16:23:05.370 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_VALUE --> HEADER_IN_VALUE
2017-07-13 16:23:05.370 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@1dd9a9d[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,509,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@18e7685] generate: DONE ([p=142,l=142,c=8192,r=0],[p=509,l=509,c=8192,r=0],true)@END
2017-07-13 16:23:05.371 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2017-07-13 16:23:05.372 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_VALUE
2017-07-13 16:23:05.373 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_VALUE --> HEADER_IN_VALUE
2017-07-13 16:23:05.373 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/bindings @ o.e.j.s.h.ContextHandler@8f35cf{/static,null,AVAILABLE}
2017-07-13 16:23:05.374 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/bindings @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.core.audio_0.9.0.b5 [99], contextID=default]}
2017-07-13 16:23:05.375 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2017-07-13 16:23:05.375 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/bindings @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui_0.9.0.b5 [136], contextID=default]}
2017-07-13 16:23:05.376 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/bindings @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.icon_0.9.0.b5 [137], contextID=default]}
2017-07-13 16:23:05.376 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_VALUE
2017-07-13 16:23:05.378 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/bindings @ HttpServiceContext{httpContext=org.jupnp.transport.impl.osgi.DisableAuthenticationHttpContext@1eeacc2}
2017-07-13 16:23:05.379 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_VALUE --> HEADER_IN_VALUE
2017-07-13 16:23:05.380 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/bindings @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.ui.dashboard_2.1.0 [170], contextID=default]}
2017-07-13 16:23:05.383 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2017-07-13 16:23:05.384 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2017-07-13 16:23:05.385 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2017-07-13 16:23:05.386 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> END
2017-07-13 16:23:05.388 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@161a855{r=1,c=false,a=IDLE,uri=/rest/things} messageComplete
2017-07-13 16:23:05.388 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/bindings @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.basic_0.9.0.b5 [176], contextID=default]}
2017-07-13 16:23:05.389 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/bindings @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.paper_0.9.0.b5 [177], contextID=default]}
2017-07-13 16:23:05.401 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/bindings @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.ui.habpanel_2.1.0 [179], contextID=default]}
2017-07-13 16:23:05.402 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/bindings @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.core_2.1.0 [166], contextID=default]}
2017-07-13 16:23:05.403 [DEBUG] [org.eclipse.jetty.server.Server     ] - RESPONSE /rest/bindings  200 handled=true
2017-07-13 16:23:05.404 [DEBUG] [clipse.jetty.server.HttpChannelState] - HttpChannelState@d3e64c{s=DISPATCHED i=true a=null} unhandle DISPATCHED
2017-07-13 16:23:05.401 [DEBUG] [org.eclipse.jetty.server.HttpInput  ] - HttpInputOverHTTP@9bc8b EOF
2017-07-13 16:23:05.405 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - reset HttpParser{s=END,0 of -1}
2017-07-13 16:23:05.407 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - END --> START
2017-07-13 16:23:05.408 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@17a716b{r=2,c=false,a=IDLE,uri=} handle exit, result COMPLETE
2017-07-13 16:23:05.410 [DEBUG] [httpclient.wire.header              ] - << "HTTP/1.1 200 OK[\r][\n]"
2017-07-13 16:23:05.410 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 SelectChannelEndPoint@73ea53{/192.168.178.29:49922<->8080,Open,in,out,-,-,43/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-07-13 16:23:05.412 [DEBUG] [httpclient.wire.header              ] - << "HTTP/1.1 200 OK[\r][\n]"
2017-07-13 16:23:05.413 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 SelectChannelEndPoint@73ea53{/192.168.178.29:49922<->8080,Open,in,out,-,-,45/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-07-13 16:23:05.414 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - parseNext s=START HeapByteBuffer@1e5a53d[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 201 Crea...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2017-07-13 16:23:05.416 [DEBUG] [httpclient.wire.header              ] - << "Transfer-Encoding: chunked[\r][\n]"
2017-07-13 16:23:05.418 [DEBUG] [httpclient.wire.header              ] - << "Date: Thu, 13 Jul 2017 14:23:04 GMT[\r][\n]"
2017-07-13 16:23:05.417 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - fillInterested HttpConnection@9af173[FILLING,SelectChannelEndPoint@73ea53{/192.168.178.29:49922<->8080,Open,in,out,-,-,48/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@17a716b{r=2,c=false,a=IDLE,uri=}]
2017-07-13 16:23:05.421 [DEBUG] [httpclient.wire.header              ] - << "Content-Type: text/xml[\r][\n]"
2017-07-13 16:23:05.418 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@161a855{r=1,c=false,a=IDLE,uri=/rest/things} handle enter
2017-07-13 16:23:05.423 [DEBUG] [clipse.jetty.server.HttpChannelState] - HttpChannelState@15658a5{s=IDLE i=true a=null} handling IDLE
2017-07-13 16:23:05.424 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@161a855{r=1,c=false,a=DISPATCHED,uri=/rest/things} action REQUEST_DISPATCH
2017-07-13 16:23:05.426 [DEBUG] [org.eclipse.jetty.server.Server     ] - REQUEST GET /rest/things on HttpChannelOverHttp@161a855{r=1,c=false,a=DISPATCHED,uri=/rest/things}
2017-07-13 16:23:05.426 [DEBUG] [ax.web.service.spi.model.ServerModel] - Matching [/rest/things]...
2017-07-13 16:23:05.427 [DEBUG] [ax.web.service.spi.model.ServerModel] - Path [/rest/things] matched to {pattern=/rest/.*,model=ServletModel{id=org.ops4j.pax.web.service.spi.model.ServletModel-4,name=org.ops4j.pax.web.service.spi.model.ServletModel-4,urlPatterns=[/rest/*],alias=/rest,servlet=com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge@18f2b74,initParams={},context=ContextModel{id=org.ops4j.pax.web.service.spi.model.ContextModel-3,name=,httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [9], contextID=default],contextParams={},virtualHosts={},connectors={}}}}
2017-07-13 16:23:05.429 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/things @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [9], contextID=default]}
2017-07-13 16:23:05.430 [DEBUG] [.jetty.server.handler.ContextHandler] - context=||/rest/things @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [9], contextID=default]}
2017-07-13 16:23:05.431 [DEBUG] [org.eclipse.jetty.server.session    ] - sessionManager=org.ops4j.pax.web.service.jetty.internal.LateInvalidatingHashSessionManager@15ba85c
2017-07-13 16:23:05.432 [DEBUG] [org.eclipse.jetty.server.session    ] - session=null
2017-07-13 16:23:05.433 [DEBUG] [eclipse.jetty.servlet.ServletHandler] - servlet |/rest|/things -> org.ops4j.pax.web.service.spi.model.ServletModel-4@378be0fc==com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge,-1,true
2017-07-13 16:23:05.434 [DEBUG] [ce.jetty.internal.HttpServiceContext] - Handling request for [/rest/things] using http context [DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.1.201602281253 [9], contextID=default]]
2017-07-13 16:23:05.435 [DEBUG] [y.internal.HttpServiceServletHandler] - handling request org.ops4j.pax.web.service.jetty.internal.HttpServiceRequestWrapper@1837241, org.ops4j.pax.web.service.jetty.internal.HttpServiceResponseWrapper@81b24a
2017-07-13 16:23:05.436 [DEBUG] [eclipse.jetty.servlet.ServletHandler] - chain=null
2017-07-13 16:23:05.422 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILLING-->FILLING_FILL_INTERESTED HttpConnection@9af173[FILLING_FILL_INTERESTED,SelectChannelEndPoint@73ea53{/192.168.178.29:49922<->8080,Open,in,out,-,-,53/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@17a716b{r=2,c=false,a=IDLE,uri=}]
2017-07-13 16:23:05.428 [DEBUG] [httpclient.wire.header              ] - << "Server: TwistedWeb/16.4.0[\r][\n]"
2017-07-13 16:23:05.439 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILLING_FILL_INTERESTED-->FILL_INTERESTED HttpConnection@9af173[FILL_INTERESTED,SelectChannelEndPoint@73ea53{/192.168.178.29:49922<->8080,Open,in,out,-,-,71/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@17a716b{r=2,c=false,a=IDLE,uri=}]
2017-07-13 16:23:05.440 [DEBUG] [httpclient.wire.header              ] - << "Set-Cookie: TWISTED_SESSION=53f9a92f130f584f1c605673e0abf7c34885daf0653ba4463995bba212a2946b; Path=/[\r][\n]"
2017-07-13 16:23:05.441 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Local interests updating 0 -> 1 for SelectChannelEndPoint@73ea53{/192.168.178.29:49922<->8080,Open,in,out,R,-,1/30000,HttpConnection}{io=1,kio=0,kro=1}
2017-07-13 16:23:05.442 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Queued change org.eclipse.jetty.io.SelectChannelEndPoint$1@1b2a341
2017-07-13 16:23:05.442 [DEBUG] [httpclient.wire.header              ] - << "[\r][\n]"
2017-07-13 16:23:05.443 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop woken up from select, 0/1 selected
2017-07-13 16:23:05.445 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Running change org.eclipse.jetty.io.SelectChannelEndPoint$1@1b2a341
2017-07-13 16:23:05.446 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Key interests updated 0 -> 1 on SelectChannelEndPoint@73ea53{/192.168.178.29:49922<->8080,Open,in,out,R,-,6/30000,HttpConnection}{io=1,kio=1,kro=1}
2017-07-13 16:23:05.448 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop waiting on select
2017-07-13 16:23:05.451 [DEBUG] [he.commons.httpclient.HttpMethodBase] - Cookie accepted: "$Version=0; TWISTED_SESSION=53f9a92f130f584f1c605673e0abf7c34885daf0653ba4463995bba212a2946b; $Path=/"
2017-07-13 16:23:05.454 [DEBUG] [httpclient.wire.content             ] - << "6"
2017-07-13 16:23:05.456 [DEBUG] [httpclient.wire.content             ] - << "8"
2017-07-13 16:23:05.458 [DEBUG] [httpclient.wire.content             ] - << "7"
2017-07-13 16:23:05.460 [DEBUG] [httpclient.wire.content             ] - << "[\r]"
2017-07-13 16:23:05.462 [DEBUG] [httpclient.wire.content             ] - << "[\n]"
2017-07-13 16:23:05.464 [DEBUG] [httpclient.wire.content             ] - << "<?xml version="1.0" encoding="UTF-8"?>[\n]"
2017-07-13 16:23:05.465 [DEBUG] [httpclient.wire.content             ] - << "<e2currentserviceinformation>[\n]"
2017-07-13 16:23:05.467 [DEBUG] [httpclient.wire.content             ] - << "[0x9]<e2service>[\n]"
2017-07-13 16:23:05.469 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9]<e2servicereference></e2servicereference>[\n]"
2017-07-13 16:23:05.471 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9]<e2servicename></e2servicename>[\n]"
2017-07-13 16:23:05.473 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9]<e2providername></e2providername>[\n]"
2017-07-13 16:23:05.475 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9]<e2videowidth>0</e2videowidth>[\n]"
2017-07-13 16:23:05.477 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9]<e2videoheight>0</e2videoheight>[\n]"
2017-07-13 16:23:05.479 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9]<e2servicevideosize>0x0</e2servicevideosize>[\n]"
2017-07-13 16:23:05.481 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9]<e2iswidescreen>[\n]"
2017-07-13 16:23:05.483 [DEBUG] [httpclient.wire.content             ] - << "0[0x9][0x9]</e2iswidescreen>[\n]"
2017-07-13 16:23:05.485 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9]<e2apid>0</e2apid>[\n]"
2017-07-13 16:23:05.487 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9]<e2vpid>0</e2vpid>[\n]"
2017-07-13 16:23:05.489 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9]<e2pcrpid>0</e2pcrpid>[\n]"
2017-07-13 16:23:05.491 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9]<e2pmtpid>0</e2pmtpid>[\n]"
2017-07-13 16:23:05.493 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9]<e2txtpid>N/A</e2txtpid>[\n]"
2017-07-13 16:23:05.495 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9]<e2tsid>0</e2tsid>[\n]"
2017-07-13 16:23:05.496 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9]<e2onid>0</e2onid>[\n]"
2017-07-13 16:23:05.499 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9]<e2sid>0</e2sid>[\n]"
2017-07-13 16:23:05.501 [DEBUG] [httpclient.wire.content             ] - << "[0x9]</e2service>[\n]"
2017-07-13 16:23:05.503 [DEBUG] [httpclient.wire.content             ] - << "[0x9]<e2eventlist>[\n]"
2017-07-13 16:23:05.505 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9]<e2event>[\n]"
2017-07-13 16:23:05.507 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventservicereference></e2eventservicereference>[\n]"
2017-07-13 16:23:05.509 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventservicename></e2eventservicename>[\n]"
2017-07-13 16:23:05.511 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventprovidername></e2eventprovidername>[\n]"
2017-07-13 16:23:05.513 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventid>0</e2eventid>[\n]"
2017-07-13 16:23:05.515 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventname></e2eventname>[\n]"
2017-07-13 16:23:05.511 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@1b8b63[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,356,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@88f0db] generate: NEED_HEADER (null,[p=0,l=356,c=8192,r=356],true)@START
2017-07-13 16:23:05.552 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventtitle></e2eventtitle>[\n]"
2017-07-13 16:23:05.555 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@1b8b63[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,356,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@88f0db] generate: FLUSH ([p=0,l=142,c=8192,r=142],[p=0,l=356,c=8192,r=356],true)@COMPLETING
2017-07-13 16:23:05.556 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - write: WriteFlusher@8f2e64{IDLE} [HeapByteBuffer@baf65e[p=0,l=142,c=8192,r=142]={<<<HTTP/1.1 200 OK\r\n....v20160908)\r\n\r\n>>>rigin: http://ope...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00},HeapByteBuffer@13201a2[p=0,l=356,c=8192,r=356]={<<<[{"statusInfo":{"..."channels":[]}]>>>\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}]
2017-07-13 16:23:05.557 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventdescription></e2eventdescription>[\n]"
2017-07-13 16:23:05.559 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventstart>0</e2eventstart>[\n]"
2017-07-13 16:23:05.562 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventduration>0</e2eventduration>[\n]"
2017-07-13 16:23:05.564 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@8f2e64{WRITING}:IDLE-->WRITING
2017-07-13 16:23:05.565 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventremaining>0</e2eventremaining>[\n]"
2017-07-13 16:23:05.566 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 498 SelectChannelEndPoint@11a4c0e{/192.168.178.29:49923<->8080,Open,in,out,-,W,247/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-07-13 16:23:05.567 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@8f2e64{IDLE}:WRITING-->IDLE
2017-07-13 16:23:05.568 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventcurrenttime>0</e2eventcurrenttime>[\n]"
2017-07-13 16:23:05.569 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@1b8b63[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,356,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@88f0db] generate: DONE ([p=142,l=142,c=8192,r=0],[p=356,l=356,c=8192,r=0],true)@END
2017-07-13 16:23:05.571 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventdescriptionextended></e2eventdescriptionextended>[\n]"
2017-07-13 16:23:05.572 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/things @ o.e.j.s.h.ContextHandler@8f35cf{/static,null,AVAILABLE}
2017-07-13 16:23:05.573 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/things @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.core.audio_0.9.0.b5 [99], contextID=default]}
2017-07-13 16:23:05.574 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9]</e2event>[\n]"
2017-07-13 16:23:05.577 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9]<e2event>[\n]"
2017-07-13 16:23:05.574 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/things @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui_0.9.0.b5 [136], contextID=default]}
2017-07-13 16:23:05.578 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventservicereference></e2eventservicereference>[\n]"
2017-07-13 16:23:05.579 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/things @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.icon_0.9.0.b5 [137], contextID=default]}
2017-07-13 16:23:05.585 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/things @ HttpServiceContext{httpContext=org.jupnp.transport.impl.osgi.DisableAuthenticationHttpContext@1eeacc2}
2017-07-13 16:23:05.588 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventservicename></e2eventservicename>[\n]"
2017-07-13 16:23:05.591 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/things @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.ui.dashboard_2.1.0 [170], contextID=default]}
2017-07-13 16:23:05.594 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/things @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.basic_0.9.0.b5 [176], contextID=default]}
2017-07-13 16:23:05.597 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/things @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.paper_0.9.0.b5 [177], contextID=default]}
2017-07-13 16:23:05.600 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventprovidername></e2eventprovidername>[\n]"
2017-07-13 16:23:05.605 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/things @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.ui.habpanel_2.1.0 [179], contextID=default]}
2017-07-13 16:23:05.606 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/things @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.core_2.1.0 [166], contextID=default]}
2017-07-13 16:23:05.607 [DEBUG] [org.eclipse.jetty.server.Server     ] - RESPONSE /rest/things  200 handled=true
2017-07-13 16:23:05.607 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventid>0</e2eventid>[\n]"
2017-07-13 16:23:05.614 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventname></e2eventname>[\n]"
2017-07-13 16:23:05.614 [DEBUG] [clipse.jetty.server.HttpChannelState] - HttpChannelState@15658a5{s=DISPATCHED i=true a=null} unhandle DISPATCHED
2017-07-13 16:23:05.615 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventtitle></e2eventtitle>[\n]"
2017-07-13 16:23:05.615 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventdescription></e2eventdescription>[\n]"
2017-07-13 16:23:05.616 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - reset HttpParser{s=END,0 of 0}
2017-07-13 16:23:05.616 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventstart>0</e2eventstart>[\n]"
2017-07-13 16:23:05.620 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - END --> START
2017-07-13 16:23:05.621 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventduration>0</e2eventduration>[\n]"
2017-07-13 16:23:05.621 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@161a855{r=1,c=false,a=IDLE,uri=} handle exit, result COMPLETE
2017-07-13 16:23:05.622 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventremaining>0</e2eventremaining>[\n]"
2017-07-13 16:23:05.626 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 SelectChannelEndPoint@11a4c0e{/192.168.178.29:49923<->8080,Open,in,out,-,-,55/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-07-13 16:23:05.630 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 SelectChannelEndPoint@11a4c0e{/192.168.178.29:49923<->8080,Open,in,out,-,-,62/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-07-13 16:23:05.630 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventcurrenttime>0</e2eventcurrenttime>[\n]"
2017-07-13 16:23:05.631 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - parseNext s=START HeapByteBuffer@1b36d3b[p=0,l=0,c=8192,r=0]={<<<>>>GET /rest/inbox H...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2017-07-13 16:23:05.644 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9][0x9]<e2eventdescriptionextended></e2eventdescriptionextended>[\n]"
2017-07-13 16:23:05.646 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - fillInterested HttpConnection@1c38172[FILLING,SelectChannelEndPoint@11a4c0e{/192.168.178.29:49923<->8080,Open,in,out,-,-,77/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@161a855{r=1,c=false,a=IDLE,uri=}]
2017-07-13 16:23:05.647 [DEBUG] [httpclient.wire.content             ] - << "[0x9][0x9]</e2event>[\n]"
2017-07-13 16:23:05.648 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILLING-->FILLING_FILL_INTERESTED HttpConnection@1c38172[FILLING_FILL_INTERESTED,SelectChannelEndPoint@11a4c0e{/192.168.178.29:49923<->8080,Open,in,out,-,-,80/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@161a855{r=1,c=false,a=IDLE,uri=}]
2017-07-13 16:23:05.651 [DEBUG] [httpclient.wire.content             ] - << "[0x9]</e2eventlist>[\n]"
2017-07-13 16:23:05.651 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILLING_FILL_INTERESTED-->FILL_INTERESTED HttpConnection@1c38172[FILL_INTERESTED,SelectChannelEndPoint@11a4c0e{/192.168.178.29:49923<->8080,Open,in,out,-,-,83/30000,HttpConnection}{io=0,kio=0,kro=1}][p=HttpParser{s=START,0 of -1},g=HttpGenerator{s=START},c=HttpChannelOverHttp@161a855{r=1,c=false,a=IDLE,uri=}]
2017-07-13 16:23:05.653 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Local interests updating 0 -> 1 for SelectChannelEndPoint@11a4c0e{/192.168.178.29:49923<->8080,Open,in,out,R,-,0/30000,HttpConnection}{io=1,kio=0,kro=1}
2017-07-13 16:23:05.654 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Queued change org.eclipse.jetty.io.SelectChannelEndPoint$1@bad578
2017-07-13 16:23:05.654 [DEBUG] [httpclient.wire.content             ] - << "</e2currentserviceinformation>[\n]"
2017-07-13 16:23:05.656 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop woken up from select, 0/2 selected
2017-07-13 16:23:05.662 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Running change org.eclipse.jetty.io.SelectChannelEndPoint$1@bad578
2017-07-13 16:23:05.663 [DEBUG] [httpclient.wire.content             ] - << "[\r]"
2017-07-13 16:23:05.664 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Key interests updated 0 -> 1 on SelectChannelEndPoint@11a4c0e{/192.168.178.29:49923<->8080,Open,in,out,R,-,11/30000,HttpConnection}{io=1,kio=1,kro=1}
2017-07-13 16:23:05.665 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop waiting on select
2017-07-13 16:23:05.666 [DEBUG] [httpclient.wire.content             ] - << "[\n]"
2017-07-13 16:23:05.669 [DEBUG] [httpclient.wire.content             ] - << "0"
2017-07-13 16:23:05.671 [DEBUG] [httpclient.wire.content             ] - << "[\r]"
2017-07-13 16:23:05.674 [DEBUG] [httpclient.wire.content             ] - << "[\n]"
2017-07-13 16:23:05.677 [DEBUG] [httpclient.wire.content             ] - << "[\r]"
2017-07-13 16:23:05.680 [DEBUG] [httpclient.wire.content             ] - << "[\n]"
2017-07-13 16:23:05.682 [DEBUG] [httpclient.wire.header              ] - << "[\r][\n]"
2017-07-13 16:23:05.685 [DEBUG] [he.commons.httpclient.HttpMethodBase] - Resorting to protocol version default close connection policy
2017-07-13 16:23:05.687 [DEBUG] [he.commons.httpclient.HttpMethodBase] - Should NOT close connection, using HTTP/1.1
2017-07-13 16:23:05.690 [DEBUG] [he.commons.httpclient.HttpConnection] - Releasing connection back to connection manager.
2017-07-13 16:23:05.701 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@19641fe[PROCESSING][i=null,cb=Blocker@4986af{null}] generate: FLUSH (null,[p=0,l=140,c=32768,r=140],false)@COMMITTED
2017-07-13 16:23:05.703 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - write: WriteFlusher@9a9616{IDLE} [HeapByteBuffer@86f151[p=0,l=6,c=1024,r=6]={<<<\r\n8C\r\n>>>\n\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},HeapByteBuffer@1baf3b1[p=0,l=140,c=32768,r=140]={<<<event: message\nda...mStateEvent"}\n\n>>>etail\\":\\"NONE\\"}...ngs/windcentral}]
2017-07-13 16:23:05.704 [DEBUG] [io.socket.client.Manager            ] - writing packet io.socket.parser.Packet@781933
2017-07-13 16:23:05.705 [DEBUG] [io.socket.parser.Parser             ] - encoding packet io.socket.parser.Packet@781933
2017-07-13 16:23:05.706 [DEBUG] [io.socket.parser.Parser             ] - encoded io.socket.parser.Packet@781933 as 2["itemupdate",{"itemName":"XT_Sendung","itemStatus":""}]
2017-07-13 16:23:05.707 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@9a9616{WRITING}:IDLE-->WRITING
2017-07-13 16:23:05.707 [DEBUG] [io.socket.engineio.client.Socket    ] - flushing 1 packets in socket
2017-07-13 16:23:05.712 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 146 SelectChannelEndPoint@13d5392{/192.168.178.29:49691<->8080,Open,in,out,-,W,650/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-07-13 16:23:05.714 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@9a9616{IDLE}:WRITING-->IDLE
2017-07-13 16:23:05.715 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@19641fe[PROCESSING][i=null,cb=Blocker@4986af{null}] generate: DONE (null,[p=140,l=140,c=32768,r=0],false)@COMMITTED
2017-07-13 16:23:05.898 [DEBUG] [.www.protocol.http.HttpURLConnection] - sun.net.www.MessageHeader@230d9d9 pairs: {POST /AUTH/token HTTP/1.1: null}{Accept-Encoding: gzip}{Authorization: Basic MjQ2MzU3NDk6bm8gc2VjcmV0}{User-Agent: Google-HTTP-Java-Client/1.21.0 (gzip)}{Content-Type: application/x-www-form-urlencoded; charset=UTF-8}{Host: api.services-smarthome.de}{Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2}{Connection: keep-alive}{Content-Length: 136}
2017-07-13 16:23:05.903 [INFO ] [google.api.client.http.HttpTransport] - Total: 136 bytes
2017-07-13 16:23:05.905 [INFO ] [google.api.client.http.HttpTransport] - code=4a1d501d1051435898ad095f95d9c45f&grant_type=authorization_code&redirect_uri=https%3A%2F%2Fwww.ollie.in%2Fsmarthome-austria-token%2F
2017-07-13 16:23:05.992 [DEBUG] [.www.protocol.http.HttpURLConnection] - sun.net.www.MessageHeader@ae742412 pairs: {null: HTTP/1.1 403 Forbidden}{Cache-Control: no-cache}{Pragma: no-cache}{Content-Type: application/json; charset=utf-8}{Expires: -1}{Public-Key-Pins: pin-sha256="EQoZq8WwHEuZYMMbORo4O7mryR+o+pig1+lq+Zwvi9Y="; pin-sha256="9n0izTnSRF+W4W4JTq51avSXkWhQB8duS2bxVLfzXsY="; max-age=86400}{Strict-Transport-Security: max-age=31536000}{X-XSS-Protection: 1; mode=block}{X-Content-Type-Options: nosniff}{X-Frame-Options: SAMEORIGIN}{Date: Thu, 13 Jul 2017 14:22:57 GMT}{Content-Length: 93}
2017-07-13 16:23:05.998 [INFO ] [google.api.client.http.HttpTransport] - -------------- RESPONSE --------------
HTTP/1.1 403 Forbidden
X-Frame-Options: SAMEORIGIN
Strict-Transport-Security: max-age=31536000
Cache-Control: no-cache
X-Content-Type-Options: nosniff
Expires: -1
Pragma: no-cache
Content-Length: 93
X-XSS-Protection: 1; mode=block
Public-Key-Pins: pin-sha256="EQoZq8WwHEuZYMMbORo4O7mryR+o+pig1+lq+Zwvi9Y="; pin-sha256="9n0izTnSRF+W4W4JTq51avSXkWhQB8duS2bxVLfzXsY="; max-age=86400
Date: Thu, 13 Jul 2017 14:22:57 GMT
Content-Type: application/json; charset=utf-8

2017-07-13 16:23:06.002 [INFO ] [google.api.client.http.HttpTransport] - Total: 93 bytes
2017-07-13 16:23:06.003 [INFO ] [google.api.client.http.HttpTransport] - {"errorcode":2002,"description":"Invalid token request","messages":["Reason: invalid_grant"]}
2017-07-13 16:23:06.005 [ERROR] [marthome.handler.InnogyBridgeHandler] - Error fetching access tokens. Invalid authcode! Please generate a new one.
2017-07-13 16:23:06.025 [DEBUG] [marthome.handler.InnogyBridgeHandler] - Disposing innogy SmartHome bridge handler 'SHC'
2017-07-13 16:23:06.026 [INFO ] [n.ollie.innogysmarthome.InnogyClient] - Uninitializing innogy SmartHome Session...
2017-07-13 16:23:06.029 [INFO ] [google.api.client.http.HttpTransport] - -------------- REQUEST  --------------
GET https://api.services-smarthome.de/API/1.0/uninitialize
Accept-Encoding: gzip
User-Agent: Google-HTTP-Java-Client/1.21.0 (gzip)

2017-07-13 16:23:06.029 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@19641fe[PROCESSING][i=null,cb=Blocker@4986af{null}] generate: FLUSH (null,[p=0,l=683,c=32768,r=683],false)@COMMITTED
2017-07-13 16:23:06.030 [INFO ] [google.api.client.http.HttpTransport] - curl -v --compressed -H 'Accept-Encoding: gzip' -H 'User-Agent: Google-HTTP-Java-Client/1.21.0 (gzip)' -- 'https://api.services-smarthome.de/API/1.0/uninitialize'
2017-07-13 16:23:06.032 [DEBUG] [.www.protocol.http.HttpURLConnection] - sun.net.www.MessageHeader@a16b26 pairs: {GET /API/1.0/uninitialize HTTP/1.1: null}{Accept-Encoding: gzip}{User-Agent: Google-HTTP-Java-Client/1.21.0 (gzip)}{Host: api.services-smarthome.de}{Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2}{Connection: keep-alive}
2017-07-13 16:23:06.033 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - write: WriteFlusher@9a9616{IDLE} [HeapByteBuffer@86f151[p=0,l=7,c=1024,r=7]={<<<\r\n2AB\r\n>>>\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},HeapByteBuffer@1baf3b1[p=0,l=683,c=32768,r=683]={<<<event: message\nda...pdatedEvent"}\n\n>>>"type":"ThingUpda...ngs/windcentral}]
2017-07-13 16:23:06.036 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@9a9616{WRITING}:IDLE-->WRITING
2017-07-13 16:23:06.039 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 690 SelectChannelEndPoint@13d5392{/192.168.178.29:49691<->8080,Open,in,out,-,W,325/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-07-13 16:23:06.041 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@9a9616{IDLE}:WRITING-->IDLE
2017-07-13 16:23:06.043 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@19641fe[PROCESSING][i=null,cb=Blocker@4986af{null}] generate: DONE (null,[p=683,l=683,c=32768,r=0],false)@COMMITTED
2017-07-13 16:23:06.047 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@19641fe[PROCESSING][i=null,cb=Blocker@4986af{null}] generate: FLUSH (null,[p=0,l=258,c=32768,r=258],false)@COMMITTED
2017-07-13 16:23:06.049 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - write: WriteFlusher@9a9616{IDLE} [HeapByteBuffer@86f151[p=0,l=7,c=1024,r=7]={<<<\r\n102\r\n>>>\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},HeapByteBuffer@1baf3b1[p=0,l=258,c=32768,r=258]={<<<event: message\nda...usInfoEvent"}\n\n>>>properties\\":{},\\...ngs/windcentral}]
2017-07-13 16:23:06.051 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@9a9616{WRITING}:IDLE-->WRITING
2017-07-13 16:23:06.053 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 265 SelectChannelEndPoint@13d5392{/192.168.178.29:49691<->8080,Open,in,out,-,W,12/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-07-13 16:23:06.055 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@9a9616{IDLE}:WRITING-->IDLE
2017-07-13 16:23:06.057 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@19641fe[PROCESSING][i=null,cb=Blocker@4986af{null}] generate: DONE (null,[p=258,l=258,c=32768,r=0],false)@COMMITTED
2017-07-13 16:23:06.061 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@19641fe[PROCESSING][i=null,cb=Blocker@4986af{null}] generate: FLUSH (null,[p=0,l=330,c=32768,r=330],false)@COMMITTED
2017-07-13 16:23:06.063 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - write: WriteFlusher@9a9616{IDLE} [HeapByteBuffer@86f151[p=0,l=7,c=1024,r=7]={<<<\r\n14A\r\n>>>\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},HeapByteBuffer@1baf3b1[p=0,l=330,c=32768,r=330]={<<<event: message\nda...hangedEvent"}\n\n>>>\\"innogysmarthome...ngs/windcentral}]
2017-07-13 16:23:06.065 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@9a9616{WRITING}:IDLE-->WRITING
2017-07-13 16:23:06.068 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 337 SelectChannelEndPoint@13d5392{/192.168.178.29:49691<->8080,Open,in,out,-,W,13/30000,HttpConnection}{io=0,kio=0,kro=1}
2017-07-13 16:23:06.070 [DEBUG] [org.eclipse.jetty.io.WriteFlusher   ] - update WriteFlusher@9a9616{IDLE}:WRITING-->IDLE
2017-07-13 16:23:06.072 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@19641fe[PROCESSING][i=null,cb=Blocker@4986af{null}] generate: DONE (null,[p=330,l=330,c=32768,r=0],false)@COMMITTED
2017-07-13 16:23:06.103 [DEBUG] [.www.protocol.http.HttpURLConnection] - sun.net.www.MessageHeader@7b7d0011 pairs: {null: HTTP/1.1 403 Forbidden}{Cache-Control: no-cache}{Pragma: no-cache}{Content-Type: application/json; charset=utf-8}{Expires: -1}{Public-Key-Pins: pin-sha256="EQoZq8WwHEuZYMMbORo4O7mryR+o+pig1+lq+Zwvi9Y="; pin-sha256="9n0izTnSRF+W4W4JTq51avSXkWhQB8duS2bxVLfzXsY="; max-age=86400}{Strict-Transport-Security: max-age=31536000}{X-XSS-Protection: 1; mode=block}{X-Content-Type-Options: nosniff}{Date: Thu, 13 Jul 2017 14:22:57 GMT}{Content-Length: 4}
2017-07-13 16:23:06.109 [INFO ] [google.api.client.http.HttpTransport] - -------------- RESPONSE --------------
HTTP/1.1 403 Forbidden
Strict-Transport-Security: max-age=31536000
Cache-Control: no-cache
X-Content-Type-Options: nosniff
Expires: -1
Pragma: no-cache
Content-Length: 4
X-XSS-Protection: 1; mode=block
Public-Key-Pins: pin-sha256="EQoZq8WwHEuZYMMbORo4O7mryR+o+pig1+lq+Zwvi9Y="; pin-sha256="9n0izTnSRF+W4W4JTq51avSXkWhQB8duS2bxVLfzXsY="; max-age=86400
Date: Thu, 13 Jul 2017 14:22:57 GMT
Content-Type: application/json; charset=utf-8

2017-07-13 16:23:06.109 [DEBUG] [n.ollie.innogysmarthome.InnogyClient] - [1] Statuscode is NOT OK: 403
2017-07-13 16:23:06.118 [INFO ] [marthome.handler.InnogyBridgeHandler] - innogy SmartHome bridge handler shut down.
2017-07-13 16:23:06.119 [ERROR] [marthome.handler.InnogyBridgeHandler] - Error initializing innogy SmartHome client.