By type
2021-11-05 12:37:17 600 DEBUG [org.eclipse.jetty.util.thread.ReservedThreadExecutor][dw-21] invocationID:{InvocationID} - ReservedThreadExecutor@14b8a751{s=1/1,p=0} tryExecute EatWhatYouKill@4fa8bebb/SelectorProducer@38ef1a0a/PRODUCING/p=false/InstrumentedQueuedThreadPool[dw]@374ccb9{STARTED,8<=8<=1024,i=4,r=1,q=0}[ReservedThreadExecutor@14b8a751{s=1/1,p=0}][pc=0,pic=0,pec=8,epc=3670]@2021-11-05T12:37:17.600866Z 2021-11-05 12:37:17 600 DEBUG [org.eclipse.jetty.util.thread.ReservedThreadExecutor][dw-21] invocationID:{InvocationID} - ReservedThreadExecutor@14b8a751{s=0/1,p=0}@7e58ad98 offer EatWhatYouKill@4fa8bebb/SelectorProducer@38ef1a0a/PRODUCING/p=false/InstrumentedQueuedThreadPool[dw]@374ccb9{STARTED,8<=8<=1024,i=4,r=1,q=0}[ReservedThreadExecutor@14b8a751{s=0/1,p=0}][pc=0,pic=0,pec=8,epc=3670]@2021-11-05T12:37:17.600946Z 2021-11-05 12:37:17 600 DEBUG [org.eclipse.jetty.server.HttpChannelState][dw-28] invocationID:{InvocationID} - completed HttpChannelState@18e28e42{s=HANDLING rs=COMPLETING os=COMPLETED is=IDLE awp=false se=false i=false al=1} 2021-11-05 12:37:17 600 DEBUG [org.eclipse.jetty.util.thread.ReservedThreadExecutor][dw-21] invocationID:{InvocationID} - ReservedThreadExecutor@14b8a751{s=0/1,p=1} startReservedThread p=1 2021-11-05 12:37:17 601 DEBUG [org.eclipse.jetty.util.thread.QueuedThreadPool][dw-21] invocationID:{InvocationID} - queue ReservedThreadExecutor@14b8a751{s=0/1,p=1}@38993970 startThread=0 2021-11-05 12:37:17 601 DEBUG [org.eclipse.jetty.util.thread.QueuedThreadPool][dw-26] invocationID:{InvocationID} - run ReservedThreadExecutor@14b8a751{s=0/1,p=1}@38993970 in InstrumentedQueuedThreadPool[dw]@374ccb9{STARTED,8<=8<=1024,i=3,r=1,q=0}[ReservedThreadExecutor@14b8a751{s=0/1,p=1}] 2021-11-05 12:37:17 601 DEBUG [org.eclipse.jetty.util.thread.ReservedThreadExecutor][dw-23] invocationID:{InvocationID} - ReservedThreadExecutor@14b8a751{s=0/1,p=1}@7e58ad98 task=EatWhatYouKill@4fa8bebb/SelectorProducer@38ef1a0a/IDLE/p=true/InstrumentedQueuedThreadPool[dw]@374ccb9{STARTED,8<=8<=1024,i=3,r=1,q=0}[ReservedThreadExecutor@14b8a751{s=0/1,p=1}][pc=0,pic=0,pec=8,epc=3670]@2021-11-05T12:37:17.601178Z 2021-11-05 12:37:17 601 DEBUG [org.eclipse.jetty.util.thread.ReservedThreadExecutor][dw-26] invocationID:{InvocationID} - ReservedThreadExecutor@14b8a751{s=1/1,p=1}@38993970 started 2021-11-05 12:37:17 601 DEBUG [org.eclipse.jetty.util.thread.ReservedThreadExecutor][dw-26] invocationID:{InvocationID} - ReservedThreadExecutor@14b8a751{s=1/1,p=0}@38993970 waiting 2021-11-05 12:37:17 601 DEBUG [org.eclipse.jetty.server.HttpChannelState][dw-28] invocationID:{InvocationID} - unhandle HttpChannelState@18e28e42{s=HANDLING rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=1} 2021-11-05 12:37:17 601 DEBUG [org.eclipse.jetty.util.thread.strategy.EatWhatYouKill][dw-23] invocationID:{InvocationID} - EatWhatYouKill@4fa8bebb/SelectorProducer@38ef1a0a/IDLE/p=true/InstrumentedQueuedThreadPool[dw]@374ccb9{STARTED,8<=8<=1024,i=3,r=1,q=0}[ReservedThreadExecutor@14b8a751{s=1/1,p=0}][pc=0,pic=0,pec=8,epc=3670]@2021-11-05T12:37:17.601322Z tryProduce true 2021-11-05 12:37:17 601 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-23] invocationID:{InvocationID} - updateable 0 2021-11-05 12:37:17 601 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-23] invocationID:{InvocationID} - updates 0 2021-11-05 12:37:17 601 DEBUG [org.eclipse.jetty.server.HttpChannelState][dw-28] invocationID:{InvocationID} - nextAction(false) TERMINATED HttpChannelState@18e28e42{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=1} 2021-11-05 12:37:17 601 DEBUG [org.eclipse.jetty.util.thread.strategy.EatWhatYouKill][dw-21] invocationID:{InvocationID} - EatWhatYouKill@4fa8bebb/SelectorProducer@38ef1a0a/IDLE/p=true/InstrumentedQueuedThreadPool[dw]@374ccb9{STARTED,8<=8<=1024,i=3,r=1,q=0}[ReservedThreadExecutor@14b8a751{s=0/1,p=1}][pc=0,pic=0,pec=8,epc=3670]@2021-11-05T12:37:17.601055Z m=EXECUTE_PRODUCE_CONSUME t=CEP:SocketChannelEndPoint@7fb431ad{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=FI,flush=-,to=1/30000}{io=1/0,kio=1,kro=1}->SslConnection@36800c26{NEED_UNWRAP,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=-,flush=-,to=41/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@5f01cfe1{s=END}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=1},r=1,c=true/true,a=IDLE,uri=https://10.233.65.254:9101/api/holmes-rule-mgmt/v1/healthcheck,age=17}:runFillable:BLOCKING/BLOCKING 2021-11-05 12:37:17 601 DEBUG [org.eclipse.jetty.server.HttpChannel][dw-28] invocationID:{InvocationID} - action TERMINATED HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=1},r=1,c=true/true,a=IDLE,uri=https://10.233.65.254:9101/api/holmes-rule-mgmt/v1/healthcheck,age=17} 2021-11-05 12:37:17 601 DEBUG [org.eclipse.jetty.server.HttpChannel][dw-28] invocationID:{InvocationID} - onCompleted for /api/holmes-rule-mgmt/v1/healthcheck written=4 2021-11-05 12:37:17 601 DEBUG [org.eclipse.jetty.io.ChannelEndPoint][dw-23] invocationID:{InvocationID} - Key interests updated 1 -> 0 on SocketChannelEndPoint@7fb431ad{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=FI,flush=-,to=2/30000}{io=0/0,kio=0,kro=1}->SslConnection@36800c26{NEED_UNWRAP,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=-,flush=-,to=41/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@5f01cfe1{s=END}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=1},r=1,c=true/true,a=IDLE,uri=https://10.233.65.254:9101/api/holmes-rule-mgmt/v1/healthcheck,age=17} 2021-11-05 12:37:17 601 DEBUG [org.eclipse.jetty.io.FillInterest][dw-21] invocationID:{InvocationID} - fillable FillInterest@4d03f148{SSLC.NBReadCB@36800c26{SslConnection@36800c26::SocketChannelEndPoint@7fb431ad{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=FI,flush=-,to=2/30000}{io=0/0,kio=0,kro=1}->SslConnection@36800c26{NEED_UNWRAP,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=-,flush=-,to=41/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@5f01cfe1{s=END}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=1},r=1,c=true/true,a=IDLE,uri=https://10.233.65.254:9101/api/holmes-rule-mgmt/v1/healthcheck,age=17}}} 2021-11-05 12:37:17 601 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-23] invocationID:{InvocationID} - Selector sun.nio.ch.EPollSelectorImpl@64310b50 waiting with 1 keys 2021-11-05 12:37:17 601 DEBUG [org.eclipse.jetty.io.ssl.SslConnection][dw-21] invocationID:{InvocationID} - >c.onFillable SslConnection@36800c26::SocketChannelEndPoint@7fb431ad{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=-,flush=-,to=2/30000}{io=0/0,kio=0,kro=1}->SslConnection@36800c26{NEED_UNWRAP,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=-,flush=-,to=41/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@5f01cfe1{s=END}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=1},r=1,c=true/true,a=IDLE,uri=https://10.233.65.254:9101/api/holmes-rule-mgmt/v1/healthcheck,age=17} 2021-11-05 12:37:17 601 DEBUG [org.eclipse.jetty.io.ssl.SslConnection][dw-21] invocationID:{InvocationID} - onFillable SslConnection@36800c26::SocketChannelEndPoint@7fb431ad{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=-,flush=-,to=2/30000}{io=0/0,kio=0,kro=1}->SslConnection@36800c26{NEED_UNWRAP,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=-,flush=-,to=41/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@5f01cfe1{s=END}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=1},r=1,c=true/true,a=IDLE,uri=https://10.233.65.254:9101/api/holmes-rule-mgmt/v1/healthcheck,age=17} 2021-11-05 12:37:17 601 DEBUG [org.eclipse.jetty.io.FillInterest][dw-21] invocationID:{InvocationID} - fillable FillInterest@17e88fd1{null} 2021-11-05 12:37:17 601 DEBUG [org.eclipse.jetty.io.FillInterest][dw-21] invocationID:{InvocationID} - FillInterest@17e88fd1{null} lost race null 2021-11-05 12:37:17 601 DEBUG [org.eclipse.jetty.io.ssl.SslConnection][dw-21] invocationID:{InvocationID} -
SslConnection@36800c26{NEED_UNWRAP,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=-,flush=-,to=42/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@5f01cfe1{s=END}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=1},r=1,c=true/true,a=IDLE,uri=https://10.233.65.254:9101/api/holmes-rule-mgmt/v1/healthcheck,age=17} 2021-11-05 12:37:17 602 DEBUG [org.eclipse.jetty.util.thread.ReservedThreadExecutor][dw-21] invocationID:{InvocationID} - ReservedThreadExecutor@14b8a751{s=1/1,p=0}@3fb18107 size 1 > capacity 2021-11-05 12:37:17 602 DEBUG [org.eclipse.jetty.util.thread.QueuedThreadPool][dw-21] invocationID:{InvocationID} - ran ReservedThreadExecutor@14b8a751{s=1/1,p=0}@3fb18107 in InstrumentedQueuedThreadPool[dw]@374ccb9{STARTED,8<=8<=1024,i=3,r=1,q=0}[ReservedThreadExecutor@14b8a751{s=1/1,p=0}] 2021-11-05 12:37:17 602 DEBUG [org.eclipse.jetty.server.HttpChannelState][dw-28] invocationID:{InvocationID} - recycle HttpChannelState@18e28e42{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=1} 2021-11-05 12:37:17 602 DEBUG [org.eclipse.jetty.http.HttpParser][dw-28] invocationID:{InvocationID} - close HttpParser{s=CONTENT,0 of -1} 2021-11-05 12:37:17 602 DEBUG [org.eclipse.jetty.http.HttpParser][dw-28] invocationID:{InvocationID} - CONTENT --> CLOSE 2021-11-05 12:37:17 602 DEBUG [org.eclipse.jetty.server.HttpChannel][dw-28] invocationID:{InvocationID} - !handle TERMINATED HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=1,c=false/false,a=IDLE,uri=null,age=0} 2021-11-05 12:37:17 603 DEBUG [org.eclipse.jetty.io.ssl.SslConnection][dw-28] invocationID:{InvocationID} - >fill SslConnection@36800c26::SocketChannelEndPoint@7fb431ad{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=-,flush=-,to=3/30000}{io=0/0,kio=0,kro=1}->SslConnection@36800c26{NEED_UNWRAP,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=-,flush=-,to=43/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5f01cfe1{s=START}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=1,c=false/false,a=IDLE,uri=null,age=0} 2021-11-05 12:37:17 603 DEBUG [org.eclipse.jetty.io.ssl.SslConnection][dw-28] invocationID:{InvocationID} - fill NEED_UNWRAP 2021-11-05 12:37:17 604 DEBUG [org.eclipse.jetty.io.ChannelEndPoint][dw-28] invocationID:{InvocationID} - filled 24 HeapByteBuffer@1e8bc23f[p=0,l=24,c=17408,r=24]={<<<\x17\x03\x03\x00\x13%,\xD4\xFc\xC9\x90\x027\xCc\xB9}\xE3\x17OT\xC8\xAcP\xC7>>>\xB4I\xE3h\x8eh\x88)\xF7...\x00\x00\x00\x00\x00\x00\x00} 2021-11-05 12:37:17 604 DEBUG [org.eclipse.jetty.io.ssl.SslConnection][dw-28] invocationID:{InvocationID} - net filled=24 2021-11-05 12:37:17 604 DEBUG [org.eclipse.jetty.io.ssl.SslConnection][dw-28] invocationID:{InvocationID} - unwrap net_filled=24 Status = CLOSED HandshakeStatus = NOT_HANDSHAKING bytesConsumed = 24 bytesProduced = 0 encryptedBuffer=[p=24,l=24,c=17408,r=0] unwrapBuffer=HeapByteBuffer@319c61b2[p=0,l=0,c=17408,r=0]={<<<>>>\x17\x03\x03\x00#\xD5\xC8\xD8F...\x00\x00\x00\x00\x00\x00\x00} appBuffer=HeapByteBuffer@319c61b2[p=0,l=0,c=17408,r=0]={<<<>>>\x17\x03\x03\x00#\xD5\xC8\xD8F...\x00\x00\x00\x00\x00\x00\x00} 10.233.65.23 - - [05/Nov/2021:12:37:17 +0000] "GET /api/holmes-rule-mgmt/v1/healthcheck HTTP/1.1" 200 4 "-" "kube-probe/1.19" 17 2021-11-05 12:37:17 605 DEBUG [org.eclipse.jetty.io.ssl.SslConnection][dw-28] invocationID:{InvocationID} - SslConnection@36800c26{NOT_HANDSHAKING,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=-,flush=-,to=44/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5f01cfe1{s=START}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=1,c=false/false,a=IDLE,uri=null,age=0} 2021-11-05 12:37:17 605 DEBUG [org.eclipse.jetty.http.HttpParser][dw-28] invocationID:{InvocationID} - atEOF HttpParser{s=CLOSE,0 of -1} 2021-11-05 12:37:17 605 DEBUG [org.eclipse.jetty.server.HttpConnection][dw-28] invocationID:{InvocationID} - HttpConnection@6df4baba::DecryptedEndPoint@4605edaa{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=-,flush=-,to=45/30000} filled -1 HeapByteBuffer@319c61b2[p=0,l=0,c=17408,r=0]={<<<>>>\x17\x03\x03\x00#\xD5\xC8\xD8F...\x00\x00\x00\x00\x00\x00\x00} 2021-11-05 12:37:17 605 DEBUG [org.eclipse.jetty.io.AbstractEndPoint][dw-28] invocationID:{InvocationID} - close DecryptedEndPoint@4605edaa{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=-,flush=-,to=45/30000} 2021-11-05 12:37:17 605 DEBUG [org.eclipse.jetty.io.AbstractEndPoint][dw-28] invocationID:{InvocationID} - close(null) DecryptedEndPoint@4605edaa{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=-,flush=-,to=45/30000} 2021-11-05 12:37:17 606 DEBUG [org.eclipse.jetty.io.ssl.SslConnection][dw-28] invocationID:{InvocationID} - shutdownOutput: SslConnection@36800c26::SocketChannelEndPoint@7fb431ad{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=-,flush=-,to=1/30000}{io=0/0,kio=0,kro=1}->SslConnection@36800c26{NOT_HANDSHAKING,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=/10.233.65.254:9101,r=/10.233.65.23:38464,CLOSED,fill=-,flush=-,to=46/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5f01cfe1{s=START}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=1,c=false/false,a=IDLE,uri=null,age=0} oshut=true, ishut=false 2021-11-05 12:37:17 607 DEBUG [org.eclipse.jetty.io.ssl.SslConnection][dw-28] invocationID:{InvocationID} - ensureFillInterested SslConnection@36800c26::SocketChannelEndPoint@7fb431ad{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=-,flush=-,to=2/30000}{io=0/0,kio=0,kro=1}->SslConnection@36800c26{NOT_HANDSHAKING,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=/10.233.65.254:9101,r=/10.233.65.23:38464,CLOSED,fill=-,flush=-,to=46/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5f01cfe1{s=START}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=1,c=false/false,a=IDLE,uri=null,age=0} 2021-11-05 12:37:17 608 DEBUG [org.eclipse.jetty.io.FillInterest][dw-28] invocationID:{InvocationID} - interested FillInterest@4d03f148{SSLC.NBReadCB@36800c26{SslConnection@36800c26::SocketChannelEndPoint@7fb431ad{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=FI,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->SslConnection@36800c26{NOT_HANDSHAKING,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=/10.233.65.254:9101,r=/10.233.65.23:38464,CLOSED,fill=-,flush=-,to=47/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5f01cfe1{s=START}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=1,c=false/false,a=IDLE,uri=null,age=0}}} 2021-11-05 12:37:17 608 DEBUG [org.eclipse.jetty.io.ChannelEndPoint][dw-28] invocationID:{InvocationID} - changeInterests p=false 0->1 for SocketChannelEndPoint@7fb431ad{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=FI,flush=-,to=0/30000}{io=0/1,kio=0,kro=1}->SslConnection@36800c26{NOT_HANDSHAKING,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=/10.233.65.254:9101,r=/10.233.65.23:38464,CLOSED,fill=-,flush=-,to=48/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5f01cfe1{s=START}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=1,c=false/false,a=IDLE,uri=null,age=0} 2021-11-05 12:37:17 608 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-28] invocationID:{InvocationID} - Queued change lazy=false org.eclipse.jetty.io.ChannelEndPoint$$Lambda$505/0x0000000100628840@7b5435f2 on ManagedSelector@6fbe4800{STARTED} id=0 keys=1 selected=0 updates=0 2021-11-05 12:37:17 608 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-28] invocationID:{InvocationID} - Wakeup on submit ManagedSelector@6fbe4800{STARTED} id=0 keys=1 selected=0 updates=1 2021-11-05 12:37:17 608 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-23] invocationID:{InvocationID} - Selector sun.nio.ch.EPollSelectorImpl@64310b50 woken with none selected 2021-11-05 12:37:17 608 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-23] invocationID:{InvocationID} - Selector sun.nio.ch.EPollSelectorImpl@64310b50 woken up from select, 0/0/1 selected 2021-11-05 12:37:17 609 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-23] invocationID:{InvocationID} - Selector sun.nio.ch.EPollSelectorImpl@64310b50 processing 0 keys, 1 updates 2021-11-05 12:37:17 609 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-23] invocationID:{InvocationID} - updateable 1 2021-11-05 12:37:17 609 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-23] invocationID:{InvocationID} - update org.eclipse.jetty.io.ChannelEndPoint$$Lambda$505/0x0000000100628840@7b5435f2 2021-11-05 12:37:17 609 DEBUG [org.eclipse.jetty.io.AbstractEndPoint][dw-28] invocationID:{InvocationID} - close SocketChannelEndPoint@7fb431ad{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=FI,flush=-,to=1/30000}{io=0/1,kio=0,kro=1}->SslConnection@36800c26{NOT_HANDSHAKING,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=/10.233.65.254:9101,r=/10.233.65.23:38464,CLOSED,fill=-,flush=-,to=49/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5f01cfe1{s=START}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=1,c=false/false,a=IDLE,uri=null,age=0} 2021-11-05 12:37:17 609 DEBUG [org.eclipse.jetty.io.ChannelEndPoint][dw-23] invocationID:{InvocationID} - Key interests updated 0 -> 1 on SocketChannelEndPoint@7fb431ad{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=FI,flush=-,to=1/30000}{io=1/1,kio=1,kro=1}->SslConnection@36800c26{NOT_HANDSHAKING,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=/10.233.65.254:9101,r=/10.233.65.23:38464,CLOSED,fill=-,flush=-,to=49/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5f01cfe1{s=START}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=1,c=false/false,a=IDLE,uri=null,age=0} 2021-11-05 12:37:17 609 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-23] invocationID:{InvocationID} - updates 0 2021-11-05 12:37:17 609 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-23] invocationID:{InvocationID} - Selector sun.nio.ch.EPollSelectorImpl@64310b50 waiting with 1 keys 2021-11-05 12:37:17 609 DEBUG [org.eclipse.jetty.io.AbstractEndPoint][dw-28] invocationID:{InvocationID} - close(null) SocketChannelEndPoint@7fb431ad{l=/10.233.65.254:9101,r=/10.233.65.23:38464,OSHUT,fill=FI,flush=-,to=1/30000}{io=1/1,kio=1,kro=1}->SslConnection@36800c26{NOT_HANDSHAKING,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=/10.233.65.254:9101,r=/10.233.65.23:38464,CLOSED,fill=-,flush=-,to=49/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5f01cfe1{s=START}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=1,c=false/false,a=IDLE,uri=null,age=0} 2021-11-05 12:37:17 609 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-23] invocationID:{InvocationID} - Selector sun.nio.ch.EPollSelectorImpl@64310b50 woken up from select, 1/1/1 selected 2021-11-05 12:37:17 609 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-23] invocationID:{InvocationID} - Selector sun.nio.ch.EPollSelectorImpl@64310b50 processing 1 keys, 0 updates 2021-11-05 12:37:17 609 DEBUG [org.eclipse.jetty.io.ChannelEndPoint][dw-28] invocationID:{InvocationID} - doClose SocketChannelEndPoint@7fb431ad{l=/10.233.65.254:9101,r=/10.233.65.23:38464,CLOSED,fill=FI,flush=-,to=1/30000}{io=1/1,kio=1,kro=1}->SslConnection@36800c26{NOT_HANDSHAKING,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=/10.233.65.254:9101,r=/10.233.65.23:38464,CLOSED,fill=-,flush=-,to=49/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5f01cfe1{s=START}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=1,c=false/false,a=IDLE,uri=null,age=0} 2021-11-05 12:37:17 609 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-23] invocationID:{InvocationID} - selected 1 channel=java.nio.channels.SocketChannel[connected oshut local=/10.233.65.254:9101 remote=/10.233.65.23:38464], selector=sun.nio.ch.EPollSelectorImpl@64310b50, interestOps=1, readyOps=1 SocketChannelEndPoint@7fb431ad{l=/10.233.65.254:9101,r=/10.233.65.23:38464,CLOSED,fill=FI,flush=-,to=2/30000}{io=1/1,kio=1,kro=1}->SslConnection@36800c26{NOT_HANDSHAKING,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=/10.233.65.254:9101,r=/10.233.65.23:38464,CLOSED,fill=-,flush=-,to=49/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5f01cfe1{s=START}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=1,c=false/false,a=IDLE,uri=null,age=0} 2021-11-05 12:37:17 609 DEBUG [org.eclipse.jetty.io.ChannelEndPoint][dw-23] invocationID:{InvocationID} - onSelected 1->0 r=true w=false for SocketChannelEndPoint@7fb431ad{l=0.0.0.0/0.0.0.0:9101,r=/10.233.65.23:38464,CLOSED,fill=FI,flush=-,to=2/30000}{io=1/0,kio=1,kro=1}->SslConnection@36800c26{NOT_HANDSHAKING,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=0.0.0.0/0.0.0.0:9101,r=/10.233.65.23:38464,CLOSED,fill=-,flush=-,to=49/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5f01cfe1{s=START}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=1,c=false/false,a=IDLE,uri=null,age=0} 2021-11-05 12:37:17 610 DEBUG [org.eclipse.jetty.io.ChannelEndPoint][dw-23] invocationID:{InvocationID} - task CEP:SocketChannelEndPoint@7fb431ad{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=FI,flush=-,to=2/30000}{io=1/0,kio=-1,kro=-1}->SslConnection@36800c26{NOT_HANDSHAKING,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=-,flush=-,to=50/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5f01cfe1{s=START}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=1,c=false/false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING 2021-11-05 12:37:17 610 DEBUG [org.eclipse.jetty.util.thread.ReservedThreadExecutor][dw-23] invocationID:{InvocationID} - ReservedThreadExecutor@14b8a751{s=1/1,p=0} tryExecute EatWhatYouKill@4fa8bebb/SelectorProducer@38ef1a0a/PRODUCING/p=false/InstrumentedQueuedThreadPool[dw]@374ccb9{STARTED,8<=8<=1024,i=4,r=1,q=0}[ReservedThreadExecutor@14b8a751{s=1/1,p=0}][pc=0,pic=0,pec=8,epc=3671]@2021-11-05T12:37:17.610177Z 2021-11-05 12:37:17 610 DEBUG [org.eclipse.jetty.io.FillInterest][dw-28] invocationID:{InvocationID} - onClose FillInterest@4d03f148{SSLC.NBReadCB@36800c26{SslConnection@36800c26::SocketChannelEndPoint@7fb431ad{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=FI,flush=-,to=2/30000}{io=1/0,kio=-1,kro=-1}->SslConnection@36800c26{NOT_HANDSHAKING,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=-,flush=-,to=50/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5f01cfe1{s=START}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=1,c=false/false,a=IDLE,uri=null,age=0}}} 2021-11-05 12:37:17 610 DEBUG [org.eclipse.jetty.util.thread.ReservedThreadExecutor][dw-23] invocationID:{InvocationID} - ReservedThreadExecutor@14b8a751{s=0/1,p=0}@38993970 offer EatWhatYouKill@4fa8bebb/SelectorProducer@38ef1a0a/PRODUCING/p=false/InstrumentedQueuedThreadPool[dw]@374ccb9{STARTED,8<=8<=1024,i=4,r=1,q=0}[ReservedThreadExecutor@14b8a751{s=0/1,p=0}][pc=0,pic=0,pec=8,epc=3671]@2021-11-05T12:37:17.610299Z 2021-11-05 12:37:17 610 DEBUG [org.eclipse.jetty.util.thread.ReservedThreadExecutor][dw-23] invocationID:{InvocationID} - ReservedThreadExecutor@14b8a751{s=0/1,p=1} startReservedThread p=1 2021-11-05 12:37:17 610 DEBUG [org.eclipse.jetty.util.thread.QueuedThreadPool][dw-23] invocationID:{InvocationID} - queue ReservedThreadExecutor@14b8a751{s=0/1,p=1}@63df08d6 startThread=0 2021-11-05 12:37:17 610 DEBUG [org.eclipse.jetty.util.thread.ReservedThreadExecutor][dw-26] invocationID:{InvocationID} - ReservedThreadExecutor@14b8a751{s=0/1,p=1}@38993970 task=EatWhatYouKill@4fa8bebb/SelectorProducer@38ef1a0a/IDLE/p=true/InstrumentedQueuedThreadPool[dw]@374ccb9{STARTED,8<=8<=1024,i=3,r=1,q=0}[ReservedThreadExecutor@14b8a751{s=0/1,p=1}][pc=0,pic=0,pec=8,epc=3671]@2021-11-05T12:37:17.610597Z 2021-11-05 12:37:17 610 DEBUG [org.eclipse.jetty.util.thread.strategy.EatWhatYouKill][dw-26] invocationID:{InvocationID} - EatWhatYouKill@4fa8bebb/SelectorProducer@38ef1a0a/IDLE/p=true/InstrumentedQueuedThreadPool[dw]@374ccb9{STARTED,8<=8<=1024,i=3,r=1,q=0}[ReservedThreadExecutor@14b8a751{s=0/1,p=1}][pc=0,pic=0,pec=8,epc=3671]@2021-11-05T12:37:17.610693Z tryProduce true 2021-11-05 12:37:17 610 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-26] invocationID:{InvocationID} - updateable 0 2021-11-05 12:37:17 610 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-26] invocationID:{InvocationID} - updates 0 2021-11-05 12:37:17 610 DEBUG [org.eclipse.jetty.util.thread.strategy.EatWhatYouKill][dw-23] invocationID:{InvocationID} - EatWhatYouKill@4fa8bebb/SelectorProducer@38ef1a0a/IDLE/p=true/InstrumentedQueuedThreadPool[dw]@374ccb9{STARTED,8<=8<=1024,i=3,r=1,q=0}[ReservedThreadExecutor@14b8a751{s=0/1,p=1}][pc=0,pic=0,pec=8,epc=3671]@2021-11-05T12:37:17.610514Z m=EXECUTE_PRODUCE_CONSUME t=CEP:SocketChannelEndPoint@7fb431ad{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=-,flush=-,to=3/30000}{io=1/0,kio=-1,kro=-1}->SslConnection@36800c26{NOT_HANDSHAKING,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=-,flush=-,to=50/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5f01cfe1{s=START}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=1,c=false/false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING/BLOCKING 2021-11-05 12:37:17 610 DEBUG [org.eclipse.jetty.io.FillInterest][dw-23] invocationID:{InvocationID} - fillable FillInterest@4d03f148{null} 2021-11-05 12:37:17 610 DEBUG [org.eclipse.jetty.io.FillInterest][dw-23] invocationID:{InvocationID} - FillInterest@4d03f148{null} lost race null 2021-11-05 12:37:17 611 DEBUG [org.eclipse.jetty.util.thread.ReservedThreadExecutor][dw-23] invocationID:{InvocationID} - ReservedThreadExecutor@14b8a751{s=1/1,p=1}@7e58ad98 waiting 2021-11-05 12:37:17 611 DEBUG [org.eclipse.jetty.io.ChannelEndPoint][dw-26] invocationID:{InvocationID} - Ignoring key update for cancelled key SocketChannelEndPoint@7fb431ad{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=-,flush=-,to=3/30000}{io=0/0,kio=-1,kro=-1}->SslConnection@36800c26{NOT_HANDSHAKING,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=-,flush=-,to=50/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5f01cfe1{s=START}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=1,c=false/false,a=IDLE,uri=null,age=0} 2021-11-05 12:37:17 611 DEBUG [org.eclipse.jetty.io.AbstractEndPoint][dw-26] invocationID:{InvocationID} - close SocketChannelEndPoint@7fb431ad{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=-,flush=-,to=3/30000}{io=0/0,kio=-1,kro=-1}->SslConnection@36800c26{NOT_HANDSHAKING,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=-,flush=-,to=51/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5f01cfe1{s=START}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=1,c=false/false,a=IDLE,uri=null,age=0} 2021-11-05 12:37:17 611 DEBUG [org.eclipse.jetty.io.AbstractEndPoint][dw-26] invocationID:{InvocationID} - close(null) SocketChannelEndPoint@7fb431ad{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=-,flush=-,to=3/30000}{io=0/0,kio=-1,kro=-1}->SslConnection@36800c26{NOT_HANDSHAKING,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=-,flush=-,to=51/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5f01cfe1{s=START}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=1,c=false/false,a=IDLE,uri=null,age=0} 2021-11-05 12:37:17 611 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-26] invocationID:{InvocationID} - Selector sun.nio.ch.EPollSelectorImpl@64310b50 waiting with 1 keys 2021-11-05 12:37:17 611 DEBUG [org.eclipse.jetty.io.ssl.SslConnection][dw-28] invocationID:{InvocationID} - onFillableFail SslConnection@36800c26::SocketChannelEndPoint@7fb431ad{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=-,flush=-,to=4/30000}{io=0/0,kio=-1,kro=-1}->SslConnection@36800c26{NOT_HANDSHAKING,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=-,flush=-,to=51/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5f01cfe1{s=START}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=1,c=false/false,a=IDLE,uri=null,age=0} 2021-11-05 12:37:17 611 DEBUG [org.eclipse.jetty.io.FillInterest][dw-28] invocationID:{InvocationID} - onFail FillInterest@17e88fd1{null} java.nio.channels.ClosedChannelException: null at org.eclipse.jetty.io.FillInterest.onClose(FillInterest.java:150) at org.eclipse.jetty.io.AbstractEndPoint.onClose(AbstractEndPoint.java:355) at org.eclipse.jetty.io.ChannelEndPoint.onClose(ChannelEndPoint.java:227) at org.eclipse.jetty.io.AbstractEndPoint.doOnClose(AbstractEndPoint.java:225) at org.eclipse.jetty.io.AbstractEndPoint.close(AbstractEndPoint.java:192) at org.eclipse.jetty.io.AbstractEndPoint.close(AbstractEndPoint.java:175) at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.doClose(SslConnection.java:1366) at org.eclipse.jetty.io.AbstractEndPoint.doOnClose(AbstractEndPoint.java:220) at org.eclipse.jetty.io.AbstractEndPoint.close(AbstractEndPoint.java:192) at org.eclipse.jetty.io.AbstractEndPoint.close(AbstractEndPoint.java:175) at org.eclipse.jetty.io.AbstractConnection.close(AbstractConnection.java:254) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:255) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:543) at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:398) at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:161) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:336) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:313) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:171) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:129) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:375) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938) at java.base/java.lang.Thread.run(Unknown Source) 2021-11-05 12:37:17 612 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-28] invocationID:{InvocationID} - Wakeup ManagedSelector@6fbe4800{STARTED} id=0 keys=0 selected=0 updates=0 2021-11-05 12:37:17 612 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-26] invocationID:{InvocationID} - Selector sun.nio.ch.EPollSelectorImpl@64310b50 woken with none selected 2021-11-05 12:37:17 612 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-26] invocationID:{InvocationID} - Selector sun.nio.ch.EPollSelectorImpl@64310b50 woken up from select, 0/0/0 selected 2021-11-05 12:37:17 612 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-26] invocationID:{InvocationID} - Selector sun.nio.ch.EPollSelectorImpl@64310b50 processing 0 keys, 0 updates 2021-11-05 12:37:17 612 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-26] invocationID:{InvocationID} - updateable 0 2021-11-05 12:37:17 612 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-26] invocationID:{InvocationID} - updates 0 2021-11-05 12:37:17 612 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-26] invocationID:{InvocationID} - Selector sun.nio.ch.EPollSelectorImpl@64310b50 waiting with 0 keys 2021-11-05 12:37:17 612 DEBUG [org.eclipse.jetty.util.thread.QueuedThreadPool][dw-28] invocationID:{InvocationID} - queue org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@4d75f2e1 startThread=0 2021-11-05 12:37:17 612 DEBUG [org.eclipse.jetty.io.FillInterest][dw-28] invocationID:{InvocationID} - onClose FillInterest@17e88fd1{null} 2021-11-05 12:37:17 612 DEBUG [org.eclipse.jetty.server.HttpConnection][dw-28] invocationID:{InvocationID} - HttpConnection@6df4baba::DecryptedEndPoint@4605edaa{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=-,flush=-,to=52/30000} parse HeapByteBuffer@319c61b2[p=0,l=0,c=17408,r=0]={<<<>>>\x17\x03\x03\x00#\xD5\xC8\xD8F...\x00\x00\x00\x00\x00\x00\x00} {} 2021-11-05 12:37:17 612 DEBUG [org.eclipse.jetty.http.HttpParser][dw-28] invocationID:{InvocationID} - parseNext s=CLOSE HeapByteBuffer@319c61b2[p=0,l=0,c=17408,r=0]={<<<>>>\x17\x03\x03\x00#\xD5\xC8\xD8F...\x00\x00\x00\x00\x00\x00\x00} 2021-11-05 12:37:17 612 DEBUG [org.eclipse.jetty.http.HttpParser][dw-28] invocationID:{InvocationID} - CLOSE --> CLOSED 2021-11-05 12:37:17 612 DEBUG [org.eclipse.jetty.server.HttpConnection][dw-28] invocationID:{InvocationID} - HttpConnection@6df4baba::DecryptedEndPoint@4605edaa{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=-,flush=-,to=52/30000} parsed false HttpParser{s=CLOSED,0 of -1} 2021-11-05 12:37:17 612 DEBUG [org.eclipse.jetty.server.HttpConnection][dw-28] invocationID:{InvocationID} - releaseRequestBuffer HttpConnection@6df4baba::DecryptedEndPoint@4605edaa{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=-,flush=-,to=52/30000} 2021-11-05 12:37:17 612 DEBUG [org.eclipse.jetty.io.AbstractEndPoint][dw-28] invocationID:{InvocationID} - shutdownOutput DecryptedEndPoint@4605edaa{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=-,flush=-,to=52/30000} 2021-11-05 12:37:17 612 DEBUG [org.eclipse.jetty.server.HttpConnection][dw-28] invocationID:{InvocationID} - HttpConnection@6df4baba::DecryptedEndPoint@4605edaa{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=-,flush=-,to=52/30000} onFillable exit HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0} null 2021-11-05 12:37:17 612 DEBUG [org.eclipse.jetty.io.ssl.SslConnection][dw-28] invocationID:{InvocationID} - SslConnection@36800c26{NOT_HANDSHAKING,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=-,flush=-,to=52/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@5f01cfe1{s=START}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=1,c=false/false,a=IDLE,uri=null,age=0} 2021-11-05 12:37:17 613 DEBUG [org.eclipse.jetty.util.thread.ReservedThreadExecutor][dw-28] invocationID:{InvocationID} - ReservedThreadExecutor@14b8a751{s=1/1,p=1}@152c5669 size 1 > capacity 2021-11-05 12:37:17 613 DEBUG [org.eclipse.jetty.util.thread.QueuedThreadPool][dw-28] invocationID:{InvocationID} - ran ReservedThreadExecutor@14b8a751{s=1/1,p=1}@152c5669 in InstrumentedQueuedThreadPool[dw]@374ccb9{STARTED,8<=8<=1024,i=2,r=1,q=0}[ReservedThreadExecutor@14b8a751{s=1/1,p=1}] 2021-11-05 12:37:17 613 DEBUG [org.eclipse.jetty.util.thread.QueuedThreadPool][dw-28] invocationID:{InvocationID} - run org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@4d75f2e1 in InstrumentedQueuedThreadPool[dw]@374ccb9{STARTED,8<=8<=1024,i=3,r=1,q=0}[ReservedThreadExecutor@14b8a751{s=1/1,p=1}] 2021-11-05 12:37:17 613 DEBUG [org.eclipse.jetty.io.ManagedSelector][dw-28] invocationID:{InvocationID} - Destroyed SocketChannelEndPoint@7fb431ad{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=-,flush=-,to=5/30000}{io=0/0,kio=-1,kro=-1}->SslConnection@36800c26{NOT_HANDSHAKING,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=-,flush=-,to=53/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@5f01cfe1{s=START}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=1,c=false/false,a=IDLE,uri=null,age=0} 2021-11-05 12:37:17 613 DEBUG [org.eclipse.jetty.io.AbstractConnection][dw-28] invocationID:{InvocationID} - onClose HttpConnection@6df4baba::DecryptedEndPoint@4605edaa{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=-,flush=-,to=53/30000} 2021-11-05 12:37:17 613 DEBUG [org.eclipse.jetty.io.AbstractConnection][dw-28] invocationID:{InvocationID} - onClose SslConnection@36800c26::SocketChannelEndPoint@7fb431ad{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=-,flush=-,to=6/30000}{io=0/0,kio=-1,kro=-1}->SslConnection@36800c26{NOT_HANDSHAKING,eio=-1/-1,di=-1,fill=IDLE,flush=IDLE}~>DecryptedEndPoint@4605edaa{l=0.0.0.0/0.0.0.0:9101,r=null,CLOSED,fill=-,flush=-,to=53/30000}=>HttpConnection@6df4baba[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@5f01cfe1{s=START}]=>HttpChannelOverHttp@63d69d20{s=HttpChannelState@18e28e42{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=1,c=false/false,a=IDLE,uri=null,age=0} 2021-11-05 12:37:17 613 DEBUG [org.eclipse.jetty.util.thread.QueuedThreadPool][dw-28] invocationID:{InvocationID} - ran org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@4d75f2e1 in InstrumentedQueuedThreadPool[dw]@374ccb9{STARTED,8<=8<=1024,i=3,r=1,q=0}[ReservedThreadExecutor@14b8a751{s=1/1,p=1}] 2021-11-05 12:37:17 611 DEBUG [org.eclipse.jetty.util.thread.QueuedThreadPool][dw-27] invocationID:{InvocationID} - run ReservedThreadExecutor@14b8a751{s=1/1,p=1}@63df08d6 in InstrumentedQueuedThreadPool[dw]@374ccb9{STARTED,8<=8<=1024,i=3,r=1,q=0}[ReservedThreadExecutor@14b8a751{s=1/1,p=1}] 2021-11-05 12:37:17 617 DEBUG [org.eclipse.jetty.util.thread.ReservedThreadExecutor][dw-27] invocationID:{InvocationID} - ReservedThreadExecutor@14b8a751{s=1/1,p=1}@63df08d6 size 1 > capacity 2021-11-05 12:37:17 617 DEBUG [org.eclipse.jetty.util.thread.QueuedThreadPool][dw-27] invocationID:{InvocationID} - ran ReservedThreadExecutor@14b8a751{s=1/1,p=0}@63df08d6 in InstrumentedQueuedThreadPool[dw]@374ccb9{STARTED,8<=8<=1024,i=4,r=1,q=0}[ReservedThreadExecutor@14b8a751{s=1/1,p=0}] 2021-11-05 12:37:19 261 TRACE [org.skife.jdbi.v2.DBI][RuleAllocatorTimer] invocationID:{InvocationID} - Handle [org.skife.jdbi.v2.BasicHandle@4129cfb9] obtained in 0 millis 2021-11-05 12:37:19 262 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - simple execute, handler=org.postgresql.jdbc.PgStatement$StatementResultHandler@79e490dc, maxRows=0, fetchSize=0, flags=16 2021-11-05 12:37:19 262 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - FE=> Bind(stmt=S_1,portal=null) 2021-11-05 12:37:19 262 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - FE=> Execute(portal=null,limit=0) 2021-11-05 12:37:19 262 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - FE=> Sync 2021-11-05 12:37:19 266 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - <=BE BindComplete [unnamed] 2021-11-05 12:37:19 266 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - <=BE DataRow(len=51) 2021-11-05 12:37:19 266 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - <=BE CommandStatus(SELECT 1) 2021-11-05 12:37:19 266 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - <=BE ReadyForQuery(I) 2021-11-05 12:37:19 266 TRACE [org.skife.jdbi.v2.DBI][RuleAllocatorTimer] invocationID:{InvocationID} - statement:[/* EngineEntityDao.getAllEntities */ SELECT * FROM ENGINE_ENTITY] took 4 millis 2021-11-05 12:37:19 266 TRACE [org.postgresql.jdbc.PgConnection][RuleAllocatorTimer] invocationID:{InvocationID} - getString columnIndex: 2 2021-11-05 12:37:19 266 TRACE [org.postgresql.jdbc.PgConnection][RuleAllocatorTimer] invocationID:{InvocationID} - getInt columnIndex: 3 2021-11-05 12:37:19 266 TRACE [org.postgresql.jdbc.PgConnection][RuleAllocatorTimer] invocationID:{InvocationID} - getLong columnIndex: 4 2021-11-05 12:37:19 267 TRACE [org.skife.jdbi.v2.DBI][RuleAllocatorTimer] invocationID:{InvocationID} - Handle [org.skife.jdbi.v2.BasicHandle@4129cfb9] released 2021-11-05 12:37:19 267 INFO [org.onap.holmes.rulemgt.RuleAllocator][RuleAllocatorTimer] invocationID:{InvocationID} - There are 1 engine instance(s) running currently. 2021-11-05 12:37:19 267 TRACE [org.skife.jdbi.v2.DBI][RuleAllocatorTimer] invocationID:{InvocationID} - Handle [org.skife.jdbi.v2.BasicHandle@2a384d61] obtained in 0 millis 2021-11-05 12:37:19 267 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - simple execute, handler=org.postgresql.jdbc.PgStatement$StatementResultHandler@5a3b917f, maxRows=0, fetchSize=0, flags=16 2021-11-05 12:37:19 267 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - FE=> Bind(stmt=S_2,portal=null) 2021-11-05 12:37:19 267 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - FE=> Execute(portal=null,limit=0) 2021-11-05 12:37:19 267 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - FE=> Sync 2021-11-05 12:37:19 268 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - <=BE BindComplete [unnamed] 2021-11-05 12:37:19 268 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - <=BE CommandStatus(SELECT 0) 2021-11-05 12:37:19 268 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - <=BE ReadyForQuery(I) 2021-11-05 12:37:19 268 TRACE [org.skife.jdbi.v2.DBI][RuleAllocatorTimer] invocationID:{InvocationID} - statement:[/* EngineEntityDao.getLegacyEngines */ SELECT DISTINCT(ENGINEINSTANCE) FROM APLUS_RULE] took 0 millis 2021-11-05 12:37:19 268 TRACE [org.skife.jdbi.v2.DBI][RuleAllocatorTimer] invocationID:{InvocationID} - Handle [org.skife.jdbi.v2.BasicHandle@2a384d61] released 2021-11-05 12:37:19 268 TRACE [org.skife.jdbi.v2.DBI][RuleAllocatorTimer] invocationID:{InvocationID} - Handle [org.skife.jdbi.v2.BasicHandle@f0db29b] obtained in 0 millis 2021-11-05 12:37:19 269 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - simple execute, handler=org.postgresql.jdbc.PgStatement$StatementResultHandler@2a85e952, maxRows=0, fetchSize=0, flags=16 2021-11-05 12:37:19 269 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - FE=> Bind(stmt=S_3,portal=null,$1=<1>,type=INT4) 2021-11-05 12:37:19 269 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - FE=> Execute(portal=null,limit=0) 2021-11-05 12:37:19 269 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - FE=> Sync 2021-11-05 12:37:19 270 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - <=BE BindComplete [unnamed] 2021-11-05 12:37:19 270 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - <=BE CommandStatus(SELECT 0) 2021-11-05 12:37:19 270 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - <=BE ReadyForQuery(I) 2021-11-05 12:37:19 270 TRACE [org.skife.jdbi.v2.DBI][RuleAllocatorTimer] invocationID:{InvocationID} - statement:[/* CorrelationRuleDao.queryRuleByEnable */ SELECT * FROM APLUS_RULE WHERE enable=?] took 0 millis 2021-11-05 12:37:19 270 TRACE [org.skife.jdbi.v2.DBI][RuleAllocatorTimer] invocationID:{InvocationID} - Handle [org.skife.jdbi.v2.BasicHandle@f0db29b] released 2021-11-05 12:37:19 271 TRACE [org.skife.jdbi.v2.DBI][RuleAllocatorTimer] invocationID:{InvocationID} - Handle [org.skife.jdbi.v2.BasicHandle@1ad814ce] obtained in 0 millis 2021-11-05 12:37:19 271 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - simple execute, handler=org.postgresql.jdbc.PgStatement$StatementResultHandler@2aba8c37, maxRows=0, fetchSize=0, flags=16 2021-11-05 12:37:19 271 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - FE=> Bind(stmt=S_4,portal=null,$1=<'holmes-engine-mgmt'>,type=VARCHAR) 2021-11-05 12:37:19 271 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - FE=> Execute(portal=null,limit=0) 2021-11-05 12:37:19 271 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - FE=> Sync 2021-11-05 12:37:19 273 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - <=BE BindComplete [unnamed] 2021-11-05 12:37:19 273 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - <=BE CommandStatus(SELECT 0) 2021-11-05 12:37:19 273 TRACE [org.postgresql.core.v3.QueryExecutorImpl][RuleAllocatorTimer] invocationID:{InvocationID} - <=BE ReadyForQuery(I) 2021-11-05 12:37:19 273 TRACE [org.skife.jdbi.v2.DBI][RuleAllocatorTimer] invocationID:{InvocationID} - statement:[/* CorrelationRuleDao.queryRuleByEngineInstance */ SELECT * FROM APLUS_RULE WHERE engineinstance=?] took 1 millis 2021-11-05 12:37:19 273 TRACE [org.skife.jdbi.v2.DBI][RuleAllocatorTimer] invocationID:{InvocationID} - Handle [org.skife.jdbi.v2.BasicHandle@1ad814ce] released