20:18:49.847 [INFO ] [smarthome.event.ItemCommandEvent ] - Item 'RosieCommand' received command start
20:18:49.844 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@196441b{IDLE}:WRITING-->IDLE
20:18:49.853 [DEBUG] [ab.binding.neato.handler.NeatoHandler] - Ok - will handle command for CHANNEL_COMMAND
20:18:49.858 [DEBUG] [g.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@1fe912e[PROCESSING][i=null,cb=Blocker@2aa8f3{null}] generate: DONE (null,[p=153,l=153,c=32768,r=0],false)@COMMITTED
20:18:49.864 [INFO ] [ab.binding.neato.handler.NeatoHandler] - Will get STATE for Robot Rosie
20:18:49.872 [DEBUG] [org.eclipse.jetty.server.Server ] - handled=true async=false committed=false on HttpChannelOverHttp@dbc8ad{r=9,c=false,a=DISPATCHED,uri=//192.168.0.149:8080/rest/items/RosieCommand}
20:18:49.868 [DEBUG] [org.eclipse.jetty.server.HttpChannel ] - sendResponse info=null content=DirectByteBuffer@1743fa4[p=0,l=141,c=32768,r=141]={<<<event: message\nda...mStateEvent"}\n\n>>>ateEvent"}\n\n"}\n\nn...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} complete=false committing=false callback=Blocker@2aa8f3{null}
20:18:49.878 [DEBUG] [eclipse.jetty.server.HttpChannelState] - HttpChannelState@1ca6a08{s=DISPATCHED a=NOT_ASYNC i=true r=NONE/false w=false} unhandle DISPATCHED
20:18:49.882 [DEBUG] [.eclipse.jetty.client.HttpDestination] - Queued HttpRequest[POST /vendors/neato/robots/OPS38417-D43639149577/messages HTTP/1.1]@1c3304c for HttpDestination[https://nucleo.neatocloud.com:4443]@1e07351,queue=1,pool=DuplexConnectionPool[c=1/64,a=0,i=1]
20:18:49.881 [DEBUG] [g.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@1fe912e[PROCESSING][i=null,cb=Blocker@2aa8f3{null}] generate: FLUSH (null,[p=0,l=141,c=32768,r=141],false)@COMMITTED
20:18:49.885 [DEBUG] [org.eclipse.jetty.server.HttpChannel ] - HttpChannelOverHttp@dbc8ad{r=9,c=false,a=COMPLETING,uri=//192.168.0.149:8080/rest/items/RosieCommand} action COMPLETE
20:18:49.895 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - write: WriteFlusher@196441b{IDLE} [HeapByteBuffer@129cbb5[p=0,l=6,c=1024,r=6]={<<<\r\n8D\r\n>>>\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},DirectByteBuffer@1743fa4[p=0,l=141,c=32768,r=141]={<<<event: message\nda...mStateEvent"}\n\n>>>ateEvent"}\n\n"}\n\nn...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}]
20:18:49.896 [DEBUG] [pse.jetty.client.DuplexConnectionPool] - Connection active HttpConnectionOverHTTP@6c822(l:/192.168.0.149:41362 <-> r:nucleo.neatocloud.com/54.84.102.60:4443,closed=false)[HttpChannelOverHTTP@2504fb(exchange=null)[send=HttpSenderOverHTTP@1163b4(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@3be479{s=START}],recv=HttpReceiverOverHTTP@ba7a44(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]]
20:18:49.901 [DEBUG] [org.eclipse.jetty.server.HttpChannel ] - sendResponse info=null content=HeapByteBuffer@80b0[p=0,l=0,c=0,r=0]={<<<>>>} complete=true committing=true callback=Blocker@5a45b1{null}
20:18:49.903 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@196441b{WRITING}:IDLE-->WRITING
20:18:49.918 [DEBUG] [.eclipse.jetty.client.HttpDestination] - Processing exchange HttpExchange@a75d5d req=PENDING/null@null res=PENDING/null@null on HttpConnectionOverHTTP@6c822(l:/192.168.0.149:41362 <-> r:nucleo.neatocloud.com/54.84.102.60:4443,closed=false)[HttpChannelOverHTTP@2504fb(exchange=null)[send=HttpSenderOverHTTP@1163b4(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@3be479{s=START}],recv=HttpReceiverOverHTTP@ba7a44(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]] of HttpDestination[https://nucleo.neatocloud.com:4443]@1e07351,queue=0,pool=DuplexConnectionPool[c=1/64,a=1,i=0]
20:18:49.930 [DEBUG] [org.eclipse.jetty.server.HttpChannel ] - COMMIT for /rest/items/RosieCommand on HttpChannelOverHttp@dbc8ad{r=9,c=true,a=COMPLETING,uri=//192.168.0.149:8080/rest/items/RosieCommand}
200 OK HTTP/1.1
Content-Type: text/plain
20:18:49.936 [DEBUG] [org.eclipse.jetty.client.HttpChannel ] - HttpExchange@a75d5d req=PENDING/null@null res=PENDING/null@null associated true to HttpChannelOverHTTP@2504fb(exchange=HttpExchange@a75d5d req=PENDING/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@1163b4(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@3be479{s=START}],recv=HttpReceiverOverHTTP@ba7a44(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
20:18:49.933 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint ] - flushed 147 SelectChannelEndPoint@1d0ea2f{/192.168.0.219:41114<->8080,Open,in,out,-,W,101/30000,HttpConnection@1e9ec49}{io=0/0,kio=0,kro=1}
20:18:49.945 [DEBUG] [org.eclipse.jetty.client.HttpSender ] - Request begin HttpRequest[POST /vendors/neato/robots/OPS38417-D43639149577/messages HTTP/1.1]@1c3304c
20:18:49.941 [DEBUG] [g.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@188bec5[PROCESSING][i=HTTP/1.1{s=200,h=1},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@146c61b] generate: NEED_HEADER (null,[p=0,l=0,c=0,r=0],true)@START
20:18:49.947 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - Flushed=true 6/6+1 WriteFlusher@196441b{WRITING}
20:18:49.952 [DEBUG] [org.eclipse.jetty.client.HttpSender ] - Request headers HttpRequest[POST /vendors/neato/robots/OPS38417-D43639149577/messages HTTP/1.1]@1c3304c
Accept-Encoding: gzip
User-Agent: Jetty/9.3.21.v20170918
Authorization: NEATOAPP a185c8b58925218f20b4d31e40ac444c3fa033a01c0a48068b07089473b741b3
Accept: application/vnd.neato.nucleo.v1
Date: Thu, 30 Aug 2018 00:18:49 GMT
Content-Type: text/html; charset=ISO-8859-1
Host: nucleo.neatocloud.com:4443
20:18:49.959 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@196441b{IDLE}:WRITING-->IDLE
20:18:49.957 [DEBUG] [g.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@188bec5[PROCESSING][i=HTTP/1.1{s=200,h=1},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@146c61b] generate: FLUSH ([p=0,l=97,c=8192,r=97],[p=0,l=0,c=0,r=0],true)@COMPLETING
20:18:49.967 [DEBUG] [lient.util.InputStreamContentProvider] - Read 40 bytes from java.io.ByteArrayInputStream@209732
20:18:49.974 [DEBUG] [g.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@1fe912e[PROCESSING][i=null,cb=Blocker@2aa8f3{null}] generate: DONE (null,[p=141,l=141,c=32768,r=0],false)@COMMITTED
20:18:49.971 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - write: WriteFlusher@18767d0{IDLE} [HeapByteBuffer@1c18c9f[p=0,l=97,c=8192,r=97]={<<<HTTP/1.1 200 OK\r\n....v20170918)\r\n\r\n>>>-Length: 5\r\nOrigi...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}]
20:18:49.976 [DEBUG] [lient.util.InputStreamContentProvider] - Read -1 bytes from java.io.ByteArrayInputStream@209732
20:18:49.982 [DEBUG] [org.eclipse.jetty.server.HttpChannel ] - sendResponse info=null content=DirectByteBuffer@1743fa4[p=0,l=152,c=32768,r=152]={<<<event: message\nda...mStateEvent"}\n\n>>>\n"}\n\nnt"}\n\n"NONE\\...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} complete=false committing=false callback=Blocker@2aa8f3{null}
20:18:49.990 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@18767d0{WRITING}:IDLE-->WRITING
20:18:49.986 [DEBUG] [org.eclipse.jetty.client.HttpContent ] - Advanced content to last chunk java.nio.HeapByteBuffer[pos=0 lim=40 cap=4096]
20:18:49.995 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint ] - flushed 97 SelectChannelEndPoint@1ffd4cc{/192.168.0.177:49694<->8080,Open,in,out,-,W,785/30000,HttpConnection@1c4576d}{io=0/0,kio=0,kro=1}
20:18:49.992 [DEBUG] [g.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@1fe912e[PROCESSING][i=null,cb=Blocker@2aa8f3{null}] generate: FLUSH (null,[p=0,l=152,c=32768,r=152],false)@COMMITTED
20:18:49.998 [DEBUG] [org.eclipse.jetty.client.HttpSender ] - Generated headers (-1 bytes), chunk (-1 bytes), content (40 bytes) - NEED_HEADER/HttpGenerator@3be479{s=START}
20:18:50.004 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - write: WriteFlusher@196441b{IDLE} [HeapByteBuffer@129cbb5[p=0,l=6,c=1024,r=6]={<<<\r\n98\r\n>>>\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},DirectByteBuffer@1743fa4[p=0,l=152,c=32768,r=152]={<<<event: message\nda...mStateEvent"}\n\n>>>\n"}\n\nnt"}\n\n"NONE\\...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}]
20:18:50.000 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - Flushed=true 97/97+0 WriteFlusher@18767d0{WRITING}
20:18:50.011 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@196441b{WRITING}:IDLE-->WRITING
20:18:50.019 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint ] - flushed 158 SelectChannelEndPoint@1d0ea2f{/192.168.0.219:41114<->8080,Open,in,out,-,W,72/30000,HttpConnection@1e9ec49}{io=0/0,kio=0,kro=1}
20:18:50.006 [DEBUG] [org.eclipse.jetty.client.HttpSender ] - Generated headers (396 bytes), chunk (-1 bytes), content (40 bytes) - FLUSH/HttpGenerator@3be479{s=COMPLETING}
20:18:50.030 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - Flushed=true 6/6+1 WriteFlusher@196441b{WRITING}
20:18:50.034 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - write: WriteFlusher@1bf917{IDLE} [HeapByteBuffer@12cbf30[p=0,l=396,c=4096,r=396]={<<<POST /vendors/nea...-Length: 40\r\n\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@1b17539[p=0,l=40,c=4096,r=40]={<<<{"reqId": "abc","...etRobotState" }>>>\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}]
20:18:50.039 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@196441b{IDLE}:WRITING-->IDLE
20:18:50.014 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@18767d0{IDLE}:WRITING-->IDLE
20:18:50.045 [DEBUG] [g.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@1fe912e[PROCESSING][i=null,cb=Blocker@2aa8f3{null}] generate: DONE (null,[p=152,l=152,c=32768,r=0],false)@COMMITTED
20:18:50.043 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@1bf917{WRITING}:IDLE-->WRITING
20:18:50.047 [DEBUG] [g.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@188bec5[PROCESSING][i=HTTP/1.1{s=200,h=1},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@146c61b] generate: DONE ([p=97,l=97,c=8192,r=0],[p=0,l=0,c=0,r=0],true)@END
20:18:50.056 [DEBUG] [rg.eclipse.jetty.io.ssl.SslConnection] - flush b[396]=504F5354202F76656E646F72732F6E6561746F2F726F626F74...2034300D0A0D0A SslConnection@f6bd74{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnectionOverHTTP@6c822(l:/192.168.0.149:41362 <-> r:nucleo.neatocloud.com/54.84.102.60:4443,closed=false)[HttpChannelOverHTTP@2504fb(exchange=HttpExchange@a75d5d req=PENDING/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@1163b4(req=HEADERS,snd=SENDING,failure=null)[HttpGenerator@3be479{s=COMPLETING}],recv=HttpReceiverOverHTTP@ba7a44(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]]
20:18:50.055 [DEBUG] [org.eclipse.jetty.server.HttpChannel ] - sendResponse info=null content=DirectByteBuffer@1743fa4[p=0,l=144,c=32768,r=144]={<<<event: message\nda...mStateEvent"}\n\n>>>vent"}\n\n\n"}\n\nnt"}...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} complete=false committing=false callback=Blocker@2aa8f3{null}
20:18:50.063 [DEBUG] [rg.eclipse.jetty.io.ssl.SslConnection] - flush b[40]=7B227265714964223A2022616263222C22636D64223A202267...7461746522207D SslConnection@f6bd74{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> HttpConnectionOverHTTP@6c822(l:/192.168.0.149:41362 <-> r:nucleo.neatocloud.com/54.84.102.60:4443,closed=false)[HttpChannelOverHTTP@2504fb(exchange=HttpExchange@a75d5d req=PENDING/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@1163b4(req=HEADERS,snd=SENDING,failure=null)[HttpGenerator@3be479{s=COMPLETING}],recv=HttpReceiverOverHTTP@ba7a44(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]]
20:18:50.059 [DEBUG] [org.eclipse.jetty.server.HttpChannel ] - COMPLETE for /rest/items/RosieCommand written=0
20:18:50.067 [DEBUG] [g.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@1fe912e[PROCESSING][i=null,cb=Blocker@2aa8f3{null}] generate: FLUSH (null,[p=0,l=144,c=32768,r=144],false)@COMMITTED
20:18:50.071 [INFO ] [org.pcap4j.core.Pcaps ] - 8 NIF(s) found.
20:18:50.077 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - write: WriteFlusher@196441b{IDLE} [HeapByteBuffer@129cbb5[p=0,l=6,c=1024,r=6]={<<<\r\n90\r\n>>>\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},DirectByteBuffer@1743fa4[p=0,l=144,c=32768,r=144]={<<<event: message\nda...mStateEvent"}\n\n>>>vent"}\n\n\n"}\n\nnt"}...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}]
20:18:50.075 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - reset HttpParser{s=END,5 of 5}
20:18:50.072 [DEBUG] [rg.eclipse.jetty.io.ssl.SslConnection] - wrap Status = OK HandshakeStatus = NOT_HANDSHAKING bytesConsumed = 436 bytesProduced = 465 SslConnection@f6bd74{NOT_HANDSHAKING,eio=-1/465,di=-1} -> HttpConnectionOverHTTP@6c822(l:/192.168.0.149:41362 <-> r:nucleo.neatocloud.com/54.84.102.60:4443,closed=false)[HttpChannelOverHTTP@2504fb(exchange=HttpExchange@a75d5d req=PENDING/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@1163b4(req=HEADERS,snd=SENDING,failure=null)[HttpGenerator@3be479{s=COMPLETING}],recv=HttpReceiverOverHTTP@ba7a44(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]]
20:18:50.085 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - END --> START
20:18:50.081 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@196441b{WRITING}:IDLE-->WRITING
20:18:50.088 [DEBUG] [rg.eclipse.jetty.io.ssl.SslConnection] - wrap OK b[465]=17030301CC00000000000000095327FB8CC2535F73C3679E61...3C7242B0299589 SslConnection@f6bd74{NOT_HANDSHAKING,eio=-1/465,di=-1} -> HttpConnectionOverHTTP@6c822(l:/192.168.0.149:41362 <-> r:nucleo.neatocloud.com/54.84.102.60:4443,closed=false)[HttpChannelOverHTTP@2504fb(exchange=HttpExchange@a75d5d req=PENDING/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@1163b4(req=HEADERS,snd=SENDING,failure=null)[HttpGenerator@3be479{s=COMPLETING}],recv=HttpReceiverOverHTTP@ba7a44(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]]
20:18:50.094 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint ] - flushed 150 SelectChannelEndPoint@1d0ea2f{/192.168.0.219:41114<->8080,Open,in,out,-,W,64/30000,HttpConnection@1e9ec49}{io=0/0,kio=0,kro=1}
20:18:50.091 [DEBUG] [org.eclipse.jetty.server.HttpChannel ] - HttpChannelOverHttp@dbc8ad{r=9,c=false,a=IDLE,uri=null} handle exit, result COMPLETE
20:18:50.099 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - Flushed=true 6/6+1 WriteFlusher@196441b{WRITING}
20:18:50.098 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint ] - flushed 465 SelectChannelEndPoint@188bf94{nucleo.neatocloud.com/54.84.102.60:4443<->41362,Open,in,out,FI,-,955/0,SslConnection@f6bd74}{io=1/1,kio=1,kro=1}
20:18:50.105 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@196441b{IDLE}:WRITING-->IDLE
20:18:50.104 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint ] - filled 0 SelectChannelEndPoint@1ffd4cc{/192.168.0.177:49694<->8080,Open,in,out,-,-,103/30000,HttpConnection@1c4576d}{io=0/0,kio=0,kro=1}
20:18:50.112 [DEBUG] [g.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@1fe912e[PROCESSING][i=null,cb=Blocker@2aa8f3{null}] generate: DONE (null,[p=144,l=144,c=32768,r=0],false)@COMMITTED
20:18:50.109 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - Flushed=true 396/396+1 WriteFlusher@1bf917{WRITING}
20:18:50.116 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint ] - filled 0 SelectChannelEndPoint@1ffd4cc{/192.168.0.177:49694<->8080,Open,in,out,-,-,116/30000,HttpConnection@1c4576d}{io=0/0,kio=0,kro=1}