Failed
de.dim.whiteboard.graphql.emf.integration.test.VariableIntegrationTest.testSingleVariableWithInputList (from test.de.dim.whiteboard.graphql.emf.integration.test)
Failing for the past 1 build
(Since Failed )
Error Message
expected:<200> but was:<400>
Stacktrace
java.lang.AssertionError: expected:<200> but was:<400> at org.junit.Assert.fail(Assert.java:88) at org.junit.Assert.failNotEquals(Assert.java:834) at org.junit.Assert.assertEquals(Assert.java:645) at org.junit.Assert.assertEquals(Assert.java:631) at de.dim.whiteboard.graphql.emf.integration.test.VariableIntegrationTest.testSingleVariableWithInputList(VariableIntegrationTest.java:169) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:325) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:78) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:57) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268) at org.junit.runners.ParentRunner.run(ParentRunner.java:363) at org.mockito.internal.runners.JUnit45AndHigherRunnerImpl.run(JUnit45AndHigherRunnerImpl.java:37) at org.mockito.runners.MockitoJUnitRunner.run(MockitoJUnitRunner.java:62) at junit.framework.JUnit4TestAdapter.run(JUnit4TestAdapter.java:38) at junit.framework.TestSuite.runTest(TestSuite.java:252) at junit.framework.TestSuite.run(TestSuite.java:247) at aQute.junit.Activator.test(Activator.java:340) at aQute.junit.Activator.automatic(Activator.java:236) at aQute.junit.Activator.run(Activator.java:177) at aQute.launcher.Launcher.lambda$serviceChanged$0(Launcher.java:1377) at aQute.launcher.Launcher.run(Launcher.java:349) at aQute.launcher.Launcher.main(Launcher.java:149)
Standard Output
14:04:19.448 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting HttpClient@37095ded{STOPPED} 14:04:19.448 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - HttpClient@37095ded{STARTING} added {SslContextFactory@2eb79cbe[provider=null,keyStore=null,trustStore=null],AUTO} 14:04:19.448 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - HttpClient@37095ded{STARTING} added {QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STOPPED,8<=0<=200,i=0,q=0}[org.eclipse.jetty.util.thread.TryExecutor$$Lambda$1/2045036434@6e9a5ed8],AUTO} 14:04:19.449 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - HttpClient@37095ded{STARTING} added {org.eclipse.jetty.io.MappedByteBufferPool@43826ec,POJO} 14:04:19.449 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - HttpClient@37095ded{STARTING} added {ScheduledExecutorScheduler@7923f5b3{STOPPED},AUTO} 14:04:19.449 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - HttpClient@37095ded{STARTING} added {HttpClientTransportOverHTTP@6b63d445{STOPPED},AUTO} 14:04:19.449 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - HttpClient@37095ded{STARTING} added {org.eclipse.jetty.util.SocketAddressResolver$Async@7578e06a,POJO} 14:04:19.449 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting SslContextFactory@2eb79cbe[provider=null,keyStore=null,trustStore=null] 14:04:19.458 [main] DEBUG org.eclipse.jetty.util.ssl.SslContextFactory - Selected Protocols [TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] 14:04:19.459 [main] DEBUG org.eclipse.jetty.util.ssl.SslContextFactory - Selected Ciphers [TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA256, TLS_ECDH_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_DSS_WITH_AES_128_CBC_SHA256, TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDH_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDH_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_DSS_WITH_AES_128_GCM_SHA256, TLS_EMPTY_RENEGOTIATION_INFO_SCSV] of [TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA256, TLS_ECDH_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_DSS_WITH_AES_128_CBC_SHA256, TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_CBC_SHA, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA, TLS_ECDH_RSA_WITH_AES_128_CBC_SHA, TLS_DHE_RSA_WITH_AES_128_CBC_SHA, TLS_DHE_DSS_WITH_AES_128_CBC_SHA, TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDH_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDH_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_DSS_WITH_AES_128_GCM_SHA256, TLS_ECDHE_ECDSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDH_ECDSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDH_RSA_WITH_3DES_EDE_CBC_SHA, SSL_DHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_DHE_DSS_WITH_3DES_EDE_CBC_SHA, TLS_EMPTY_RENEGOTIATION_INFO_SCSV, TLS_DH_anon_WITH_AES_128_GCM_SHA256, TLS_DH_anon_WITH_AES_128_CBC_SHA256, TLS_ECDH_anon_WITH_AES_128_CBC_SHA, TLS_DH_anon_WITH_AES_128_CBC_SHA, TLS_ECDH_anon_WITH_3DES_EDE_CBC_SHA, SSL_DH_anon_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_DES_CBC_SHA, SSL_DHE_RSA_WITH_DES_CBC_SHA, SSL_DHE_DSS_WITH_DES_CBC_SHA, SSL_DH_anon_WITH_DES_CBC_SHA, SSL_RSA_EXPORT_WITH_DES40_CBC_SHA, SSL_DHE_RSA_EXPORT_WITH_DES40_CBC_SHA, SSL_DHE_DSS_EXPORT_WITH_DES40_CBC_SHA, SSL_DH_anon_EXPORT_WITH_DES40_CBC_SHA, TLS_RSA_WITH_NULL_SHA256, TLS_ECDHE_ECDSA_WITH_NULL_SHA, TLS_ECDHE_RSA_WITH_NULL_SHA, SSL_RSA_WITH_NULL_SHA, TLS_ECDH_ECDSA_WITH_NULL_SHA, TLS_ECDH_RSA_WITH_NULL_SHA, TLS_ECDH_anon_WITH_NULL_SHA, SSL_RSA_WITH_NULL_MD5, TLS_KRB5_WITH_3DES_EDE_CBC_SHA, TLS_KRB5_WITH_3DES_EDE_CBC_MD5, TLS_KRB5_WITH_DES_CBC_SHA, TLS_KRB5_WITH_DES_CBC_MD5, TLS_KRB5_EXPORT_WITH_DES_CBC_40_SHA, TLS_KRB5_EXPORT_WITH_DES_CBC_40_MD5] 14:04:19.459 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @12163ms SslContextFactory@2eb79cbe[provider=null,keyStore=null,trustStore=null] 14:04:19.459 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STOPPED,8<=0<=200,i=0,q=0}[org.eclipse.jetty.util.thread.TryExecutor$$Lambda$1/2045036434@6e9a5ed8] 14:04:19.459 [main] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@56da52a7{s=0/8,p=0} 14:04:19.459 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTING,8<=0<=200,i=0,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}] added {ReservedThreadExecutor@56da52a7{s=0/8,p=0},AUTO} 14:04:19.459 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting ReservedThreadExecutor@56da52a7{s=0/8,p=0} 14:04:19.459 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @12163ms ReservedThreadExecutor@56da52a7{s=0/8,p=0} 14:04:19.463 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @12167ms QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=7,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}] 14:04:19.463 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting ScheduledExecutorScheduler@7923f5b3{STOPPED} 14:04:19.463 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @12168ms ScheduledExecutorScheduler@7923f5b3{STARTED} 14:04:19.463 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting HttpClientTransportOverHTTP@6b63d445{STOPPED} 14:04:19.464 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - HttpClientTransportOverHTTP@6b63d445{STARTING} added {ClientSelectorManager@73877e19{STOPPED},AUTO} 14:04:19.464 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting ClientSelectorManager@73877e19{STOPPED} 14:04:19.464 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - EatWhatYouKill@7b208b45/SelectorProducer@5bfc257/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.464Z added {SelectorProducer@5bfc257,POJO} 14:04:19.464 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - EatWhatYouKill@7b208b45/SelectorProducer@5bfc257/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.464Z added {QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}],UNMANAGED} 14:04:19.464 [main] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@7b208b45/SelectorProducer@5bfc257/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.464Z created 14:04:19.464 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - ManagedSelector@34523d46{STOPPED} id=0 keys=-1 selected=-1 updates=0 added {EatWhatYouKill@7b208b45/SelectorProducer@5bfc257/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.464Z,MANAGED} 14:04:19.464 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - ClientSelectorManager@73877e19{STARTING} added {ManagedSelector@34523d46{STOPPED} id=0 keys=-1 selected=-1 updates=0,AUTO} 14:04:19.465 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - EatWhatYouKill@7cedfa63/SelectorProducer@3e83c18/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.465Z added {SelectorProducer@3e83c18,POJO} 14:04:19.465 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - EatWhatYouKill@7cedfa63/SelectorProducer@3e83c18/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.465Z added {QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}],UNMANAGED} 14:04:19.465 [main] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@7cedfa63/SelectorProducer@3e83c18/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.465Z created 14:04:19.465 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - ManagedSelector@783efb48{STOPPED} id=1 keys=-1 selected=-1 updates=0 added {EatWhatYouKill@7cedfa63/SelectorProducer@3e83c18/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.465Z,MANAGED} 14:04:19.465 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - ClientSelectorManager@73877e19{STARTING} added {ManagedSelector@783efb48{STOPPED} id=1 keys=-1 selected=-1 updates=0,AUTO} 14:04:19.465 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - EatWhatYouKill@5d235104/SelectorProducer@4e8e8621/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.465Z added {SelectorProducer@4e8e8621,POJO} 14:04:19.465 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - EatWhatYouKill@5d235104/SelectorProducer@4e8e8621/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.465Z added {QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}],UNMANAGED} 14:04:19.465 [main] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@5d235104/SelectorProducer@4e8e8621/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.465Z created 14:04:19.466 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - ManagedSelector@c446b14{STOPPED} id=2 keys=-1 selected=-1 updates=0 added {EatWhatYouKill@5d235104/SelectorProducer@4e8e8621/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.465Z,MANAGED} 14:04:19.466 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - ClientSelectorManager@73877e19{STARTING} added {ManagedSelector@c446b14{STOPPED} id=2 keys=-1 selected=-1 updates=0,AUTO} 14:04:19.466 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - EatWhatYouKill@3af356f/SelectorProducer@4443ef6f/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.466Z added {SelectorProducer@4443ef6f,POJO} 14:04:19.466 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - EatWhatYouKill@3af356f/SelectorProducer@4443ef6f/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.466Z added {QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}],UNMANAGED} 14:04:19.466 [main] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@3af356f/SelectorProducer@4443ef6f/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.466Z created 14:04:19.466 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - ManagedSelector@7e46d648{STOPPED} id=3 keys=-1 selected=-1 updates=0 added {EatWhatYouKill@3af356f/SelectorProducer@4443ef6f/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.466Z,MANAGED} 14:04:19.466 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - ClientSelectorManager@73877e19{STARTING} added {ManagedSelector@7e46d648{STOPPED} id=3 keys=-1 selected=-1 updates=0,AUTO} 14:04:19.466 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting ManagedSelector@34523d46{STOPPED} id=0 keys=-1 selected=-1 updates=0 14:04:19.466 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting EatWhatYouKill@7b208b45/SelectorProducer@5bfc257/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.466Z 14:04:19.467 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @12171ms EatWhatYouKill@7b208b45/SelectorProducer@5bfc257/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.467Z 14:04:19.467 [main] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - queue org.eclipse.jetty.io.ManagedSelector$$Lambda$24/455501890@51751e5f 14:04:19.467 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$Start@2b0b4d53 on ManagedSelector@34523d46{STARTING} id=0 keys=0 selected=0 updates=0 14:04:19.467 [HttpClient@37095ded-113] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.io.ManagedSelector$$Lambda$24/455501890@51751e5f 14:04:19.467 [HttpClient@37095ded-113] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@7b208b45/SelectorProducer@5bfc257/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=7,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.467Z tryProduce false 14:04:19.467 [HttpClient@37095ded-113] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1 14:04:19.467 [HttpClient@37095ded-113] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$Start@2b0b4d53 14:04:19.467 [HttpClient@37095ded-113] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0 14:04:19.467 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @12171ms ManagedSelector@34523d46{STARTED} id=0 keys=0 selected=0 updates=0 14:04:19.467 [HttpClient@37095ded-113] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6828c80 waiting with 0 keys 14:04:19.467 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting ManagedSelector@783efb48{STOPPED} id=1 keys=-1 selected=-1 updates=0 14:04:19.467 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting EatWhatYouKill@7cedfa63/SelectorProducer@3e83c18/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=7,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.467Z 14:04:19.468 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @12172ms EatWhatYouKill@7cedfa63/SelectorProducer@3e83c18/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=7,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.467Z 14:04:19.468 [main] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - queue org.eclipse.jetty.io.ManagedSelector$$Lambda$24/455501890@7068f7ca 14:04:19.468 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$Start@38548b19 on ManagedSelector@783efb48{STARTING} id=1 keys=0 selected=0 updates=0 14:04:19.468 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.io.ManagedSelector$$Lambda$24/455501890@7068f7ca 14:04:19.468 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@7cedfa63/SelectorProducer@3e83c18/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=6,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.468Z tryProduce false 14:04:19.468 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1 14:04:19.468 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$Start@38548b19 14:04:19.468 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0 14:04:19.468 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2f9fd565 waiting with 0 keys 14:04:19.468 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @12172ms ManagedSelector@783efb48{STARTED} id=1 keys=0 selected=0 updates=0 14:04:19.468 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting ManagedSelector@c446b14{STOPPED} id=2 keys=-1 selected=-1 updates=0 14:04:19.468 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting EatWhatYouKill@5d235104/SelectorProducer@4e8e8621/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=6,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.468Z 14:04:19.468 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @12172ms EatWhatYouKill@5d235104/SelectorProducer@4e8e8621/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=6,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.468Z 14:04:19.468 [main] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - queue org.eclipse.jetty.io.ManagedSelector$$Lambda$24/455501890@41aaedaa 14:04:19.468 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$Start@303a5119 on ManagedSelector@c446b14{STARTING} id=2 keys=0 selected=0 updates=0 14:04:19.468 [HttpClient@37095ded-115] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.io.ManagedSelector$$Lambda$24/455501890@41aaedaa 14:04:19.469 [HttpClient@37095ded-115] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@5d235104/SelectorProducer@4e8e8621/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=5,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.469Z tryProduce false 14:04:19.469 [HttpClient@37095ded-115] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1 14:04:19.469 [HttpClient@37095ded-115] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$Start@303a5119 14:04:19.469 [HttpClient@37095ded-115] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0 14:04:19.469 [HttpClient@37095ded-115] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@16a8ca40 waiting with 0 keys 14:04:19.469 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @12173ms ManagedSelector@c446b14{STARTED} id=2 keys=0 selected=0 updates=0 14:04:19.469 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting ManagedSelector@7e46d648{STOPPED} id=3 keys=-1 selected=-1 updates=0 14:04:19.469 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting EatWhatYouKill@3af356f/SelectorProducer@4443ef6f/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=5,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.469Z 14:04:19.469 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @12173ms EatWhatYouKill@3af356f/SelectorProducer@4443ef6f/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=5,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.469Z 14:04:19.469 [main] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - queue org.eclipse.jetty.io.ManagedSelector$$Lambda$24/455501890@75b3673 14:04:19.469 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$Start@1bcb79c2 on ManagedSelector@7e46d648{STARTING} id=3 keys=0 selected=0 updates=0 14:04:19.469 [HttpClient@37095ded-116] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.io.ManagedSelector$$Lambda$24/455501890@75b3673 14:04:19.470 [HttpClient@37095ded-116] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@3af356f/SelectorProducer@4443ef6f/IDLE/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=4,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.47Z tryProduce false 14:04:19.470 [HttpClient@37095ded-116] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1 14:04:19.470 [HttpClient@37095ded-116] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$Start@1bcb79c2 14:04:19.470 [HttpClient@37095ded-116] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0 14:04:19.470 [HttpClient@37095ded-116] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@4dcd67cc waiting with 0 keys 14:04:19.470 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @12174ms ManagedSelector@7e46d648{STARTED} id=3 keys=0 selected=0 updates=0 14:04:19.470 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @12174ms ClientSelectorManager@73877e19{STARTED} 14:04:19.470 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @12174ms HttpClientTransportOverHTTP@6b63d445{STARTED} 14:04:19.470 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @12174ms HttpClient@37095ded{STARTED} 14:04:19.478 [main] INFO graphql.servlet.AbstractGraphQLHttpServlet - Schema generation sucessfull 14:04:19.478 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - HttpClient@37095ded{STARTED} added {HttpDestination[http://localhost:8181]@963176,queue=0,pool=null,MANAGED} 14:04:19.478 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting HttpDestination[http://localhost:8181]@963176,queue=0,pool=null 14:04:19.478 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - HttpDestination[http://localhost:8181]@963176,queue=0,pool=DuplexConnectionPool@65004ff6[c=0/64,a=0,i=0] added {DuplexConnectionPool@65004ff6[c=0/64,a=0,i=0],POJO} 14:04:19.478 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @12183ms HttpDestination[http://localhost:8181]@963176,queue=0,pool=DuplexConnectionPool@65004ff6[c=0/64,a=0,i=0] 14:04:19.478 [main] DEBUG org.eclipse.jetty.client.HttpClient - Created HttpDestination[http://localhost:8181]@963176,queue=0,pool=DuplexConnectionPool@65004ff6[c=0/64,a=0,i=0] 14:04:19.479 [main] DEBUG org.eclipse.jetty.client.HttpDestination - Queued HttpRequest[POST /graphql HTTP/1.1]@4cafa9aa for HttpDestination[http://localhost:8181]@963176,queue=1,pool=DuplexConnectionPool@65004ff6[c=0/64,a=0,i=0] 14:04:19.479 [main] DEBUG org.eclipse.jetty.client.AbstractConnectionPool - tryCreate 0/64 connections 0/-1 pending 14:04:19.479 [main] DEBUG org.eclipse.jetty.client.AbstractConnectionPool - newConnection 1/64 connections 1/-1 pending 14:04:19.479 [main] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - queue org.eclipse.jetty.util.SocketAddressResolver$Async$$Lambda$128/438228197@562c877a 14:04:19.479 [HttpClient@37095ded-117] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.SocketAddressResolver$Async$$Lambda$128/438228197@562c877a 14:04:19.479 [HttpClient@37095ded-117] DEBUG org.eclipse.jetty.util.SocketAddressResolver - Resolved localhost in 0 ms 14:04:19.479 [HttpClient@37095ded-117] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change Connect@b88381d{java.nio.channels.SocketChannel[connection-pending remote=localhost/127.0.0.1:8181],{ssl.peer.port=8181, ssl.peer.host=localhost, http.destination=HttpDestination[http://localhost:8181]@963176,queue=1,pool=DuplexConnectionPool@65004ff6[c=1/64,a=0,i=0], http.connection.promise=org.eclipse.jetty.client.HttpClient$1$1@fb7bdb4, client.connector=HttpClient@37095ded{STARTED}}} on ManagedSelector@783efb48{STARTED} id=1 keys=0 selected=0 updates=0 14:04:19.479 [qtp352598575-43-acceptor-0@4a00d9cf-ServerConnector@3b5fad2d{HTTP/1.1,[http/1.1]}{0.0.0.0:8181}] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$Accept@520403fe on ManagedSelector@5f77d0f9{STARTED} id=3 keys=0 selected=0 updates=0 14:04:19.479 [HttpClient@37095ded-117] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@783efb48{STARTED} id=1 keys=0 selected=0 updates=1 14:04:19.479 [qtp352598575-43-acceptor-0@4a00d9cf-ServerConnector@3b5fad2d{HTTP/1.1,[http/1.1]}{0.0.0.0:8181}] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@5f77d0f9{STARTED} id=3 keys=0 selected=0 updates=1 14:04:19.479 [HttpClient@37095ded-117] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.SocketAddressResolver$Async$$Lambda$128/438228197@562c877a 14:04:19.480 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@c18337a woken with none selected 14:04:19.480 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2f9fd565 woken with none selected 14:04:19.480 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@c18337a woken up from select, 0/0/0 selected 14:04:19.480 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2f9fd565 woken up from select, 0/0/0 selected 14:04:19.480 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2f9fd565 processing 0 keys, 1 updates 14:04:19.480 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@c18337a processing 0 keys, 1 updates 14:04:19.480 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1 14:04:19.480 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1 14:04:19.480 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$Accept@520403fe 14:04:19.480 [qtp352598575-42] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - queue org.eclipse.jetty.io.ManagedSelector$Accept@520403fe 14:04:19.480 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - update Connect@b88381d{java.nio.channels.SocketChannel[connection-pending remote=localhost/127.0.0.1:8181],{ssl.peer.port=8181, ssl.peer.host=localhost, http.destination=HttpDestination[http://localhost:8181]@963176,queue=1,pool=DuplexConnectionPool@65004ff6[c=1/64,a=0,i=0], http.connection.promise=org.eclipse.jetty.client.HttpClient$1$1@fb7bdb4, client.connector=HttpClient@37095ded{STARTED}}} 14:04:19.480 [qtp352598575-44] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.io.ManagedSelector$Accept@520403fe 14:04:19.480 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0 14:04:19.480 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@c18337a waiting with 1 keys 14:04:19.481 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0 14:04:19.481 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2f9fd565 waiting with 1 keys 14:04:19.481 [qtp352598575-44] DEBUG org.eclipse.jetty.io.IdleTimeout - SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=1/60000}{io=0/0,kio=0,kro=0}-><null> idle timeout check, elapsed: 1 ms, remaining: 59999 ms 14:04:19.481 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2f9fd565 woken up from select, 1/1/1 selected 14:04:19.481 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2f9fd565 processing 1 keys, 0 updates 14:04:19.481 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - selected 8 sun.nio.ch.SelectionKeyImpl@21582ee6 Connect@b88381d{java.nio.channels.SocketChannel[connection-pending remote=localhost/127.0.0.1:8181],{ssl.peer.port=8181, ssl.peer.host=localhost, http.destination=HttpDestination[http://localhost:8181]@963176,queue=1,pool=DuplexConnectionPool@65004ff6[c=1/64,a=0,i=0], http.connection.promise=org.eclipse.jetty.client.HttpClient$1$1@fb7bdb4, client.connector=HttpClient@37095ded{STARTED}}} 14:04:19.481 [qtp352598575-44] DEBUG org.eclipse.jetty.server.HttpChannel - new HttpChannelOverHttp@2a6882ce{r=0,c=false,a=IDLE,uri=null,age=0} -> SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=1/60000}{io=0/0,kio=0,kro=0}-><null>,null,HttpChannelState@6657c9a2{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false} 14:04:19.481 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - Connected true java.nio.channels.SocketChannel[connected local=/127.0.0.1:44004 remote=localhost/127.0.0.1:8181] 14:04:19.481 [qtp352598575-44] DEBUG org.eclipse.jetty.server.HttpConnection - New HTTP Connection HttpConnection@2c75abf3::SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=1/60000}{io=0/0,kio=0,kro=0}-><null> 14:04:19.482 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - queue CreateEndPoint@2567be15{Connect@b88381d{java.nio.channels.SocketChannel[connected local=/127.0.0.1:44004 remote=localhost/127.0.0.1:8181],{ssl.peer.port=8181, ssl.peer.host=localhost, http.destination=HttpDestination[http://localhost:8181]@963176,queue=1,pool=DuplexConnectionPool@65004ff6[c=1/64,a=0,i=0], http.connection.promise=org.eclipse.jetty.client.HttpClient$1$1@fb7bdb4, client.connector=HttpClient@37095ded{STARTED}}},sun.nio.ch.SelectionKeyImpl@21582ee6} 14:04:19.482 [qtp352598575-44] DEBUG org.eclipse.jetty.io.AbstractEndPoint - onOpen SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=1/60000}{io=0/0,kio=0,kro=0}->HttpConnection@2c75abf3[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1d6daeab{s=START}]=>HttpChannelOverHttp@2a6882ce{r=0,c=false,a=IDLE,uri=null,age=0} 14:04:19.482 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 0 14:04:19.482 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0 14:04:19.482 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2f9fd565 waiting with 1 keys 14:04:19.482 [qtp352598575-44] DEBUG org.eclipse.jetty.io.AbstractConnection - onOpen HttpConnection@2c75abf3::SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=2/60000}{io=0/0,kio=0,kro=0}->HttpConnection@2c75abf3[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1d6daeab{s=START}]=>HttpChannelOverHttp@2a6882ce{r=0,c=false,a=IDLE,uri=null,age=0} 14:04:19.482 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run CreateEndPoint@2567be15{Connect@b88381d{java.nio.channels.SocketChannel[connected local=/127.0.0.1:44004 remote=localhost/127.0.0.1:8181],{ssl.peer.port=8181, ssl.peer.host=localhost, http.destination=HttpDestination[http://localhost:8181]@963176,queue=1,pool=DuplexConnectionPool@65004ff6[c=1/64,a=0,i=0], http.connection.promise=org.eclipse.jetty.client.HttpClient$1$1@fb7bdb4, client.connector=HttpClient@37095ded{STARTED}}},sun.nio.ch.SelectionKeyImpl@21582ee6} 14:04:19.482 [qtp352598575-44] DEBUG org.eclipse.jetty.io.AbstractConnection - fillInterested HttpConnection@2c75abf3::SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=2/60000}{io=0/0,kio=0,kro=0}->HttpConnection@2c75abf3[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1d6daeab{s=START}]=>HttpChannelOverHttp@2a6882ce{r=0,c=false,a=IDLE,uri=null,age=0} 14:04:19.482 [qtp352598575-44] DEBUG org.eclipse.jetty.io.FillInterest - interested FillInterest@73fd213a{AC.ReadCB@2c75abf3{HttpConnection@2c75abf3::SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=FI,flush=-,to=0/60000}{io=0/0,kio=0,kro=0}->HttpConnection@2c75abf3[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1d6daeab{s=START}]=>HttpChannelOverHttp@2a6882ce{r=0,c=false,a=IDLE,uri=null,age=0}}} 14:04:19.482 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.client.HttpClientTransport - Created HttpConnectionOverHTTP@2533af6::SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=-,flush=-,to=0/0}{io=0/0,kio=0,kro=8}-><null> 14:04:19.482 [qtp352598575-44] DEBUG org.eclipse.jetty.io.ChannelEndPoint - changeInterests p=false 0->1 for SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=FI,flush=-,to=0/60000}{io=0/1,kio=0,kro=0}->HttpConnection@2c75abf3[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1d6daeab{s=START}]=>HttpChannelOverHttp@2a6882ce{r=0,c=false,a=IDLE,uri=null,age=0} 14:04:19.482 [qtp352598575-44] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ChannelEndPoint$1@2b3ffc3e on ManagedSelector@5f77d0f9{STARTED} id=3 keys=1 selected=0 updates=0 14:04:19.482 [qtp352598575-44] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@5f77d0f9{STARTED} id=3 keys=1 selected=0 updates=1 14:04:19.482 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.io.AbstractEndPoint - onOpen SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=-,flush=-,to=0/0}{io=0/0,kio=0,kro=8}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=IDLE,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] 14:04:19.482 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@c18337a woken with none selected 14:04:19.482 [qtp352598575-44] DEBUG org.eclipse.jetty.io.ManagedSelector - Created SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=FI,flush=-,to=0/60000}{io=0/1,kio=0,kro=0}->HttpConnection@2c75abf3[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1d6daeab{s=START}]=>HttpChannelOverHttp@2a6882ce{r=0,c=false,a=IDLE,uri=null,age=0} 14:04:19.482 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@c18337a woken up from select, 0/0/1 selected 14:04:19.482 [qtp352598575-44] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$Accept@520403fe 14:04:19.482 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@c18337a processing 0 keys, 1 updates 14:04:19.482 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1 14:04:19.482 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ChannelEndPoint$1@2b3ffc3e 14:04:19.482 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.io.AbstractConnection - onOpen HttpConnectionOverHTTP@2533af6::SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=-,flush=-,to=0/0}{io=0/0,kio=0,kro=8}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=IDLE,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] 14:04:19.482 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 0 -> 1 on SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=FI,flush=-,to=0/60000}{io=1/1,kio=1,kro=0}->HttpConnection@2c75abf3[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1d6daeab{s=START}]=>HttpChannelOverHttp@2a6882ce{r=0,c=false,a=IDLE,uri=null,age=0} 14:04:19.483 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0 14:04:19.483 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@c18337a waiting with 1 keys 14:04:19.483 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.io.AbstractConnection - fillInterested HttpConnectionOverHTTP@2533af6::SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=-,flush=-,to=0/0}{io=0/0,kio=0,kro=8}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=IDLE,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] 14:04:19.483 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.io.FillInterest - interested FillInterest@2edb0cf2{AC.ReadCB@2533af6{HttpConnectionOverHTTP@2533af6::SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=FI,flush=-,to=0/0}{io=0/0,kio=0,kro=8}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=IDLE,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]}} 14:04:19.483 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.io.ChannelEndPoint - changeInterests p=false 0->1 for SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=FI,flush=-,to=0/0}{io=0/1,kio=0,kro=8}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=IDLE,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] 14:04:19.483 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ChannelEndPoint$1@2d8621b8 on ManagedSelector@783efb48{STARTED} id=1 keys=1 selected=0 updates=0 14:04:19.483 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@783efb48{STARTED} id=1 keys=1 selected=0 updates=1 14:04:19.483 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2f9fd565 woken with none selected 14:04:19.483 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2f9fd565 woken up from select, 0/0/1 selected 14:04:19.483 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2f9fd565 processing 0 keys, 1 updates 14:04:19.483 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1 14:04:19.483 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ChannelEndPoint$1@2d8621b8 14:04:19.483 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.client.AbstractConnectionPool - Connection 1/64 creation succeeded HttpConnectionOverHTTP@2533af6::SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=FI,flush=-,to=0/0}{io=0/1,kio=0,kro=8}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=IDLE,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] 14:04:19.484 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 0 -> 1 on SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=FI,flush=-,to=0/0}{io=1/1,kio=1,kro=8}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=IDLE,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] 14:04:19.484 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0 14:04:19.484 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2f9fd565 waiting with 1 keys 14:04:19.484 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.client.AbstractConnectionPool - Connection idle HttpConnectionOverHTTP@2533af6::SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=FI,flush=-,to=1/0}{io=1/1,kio=1,kro=8}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=IDLE,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] 14:04:19.484 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.client.AbstractConnectionPool - Connection active HttpConnectionOverHTTP@2533af6::SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=FI,flush=-,to=1/0}{io=1/1,kio=1,kro=8}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=IDLE,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] 14:04:19.484 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.client.HttpDestination - Processing exchange HttpExchange@4d99e21f req=PENDING/null@null res=PENDING/null@null on HttpConnectionOverHTTP@2533af6::SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=FI,flush=-,to=1/0}{io=1/1,kio=1,kro=8}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=IDLE,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] of HttpDestination[http://localhost:8181]@963176,queue=0,pool=DuplexConnectionPool@65004ff6[c=1/64,a=1,i=0] 14:04:19.485 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.client.HttpChannel - HttpExchange@4d99e21f req=PENDING/null@null res=PENDING/null@null associated true to HttpChannelOverHTTP@8ab5186(exchange=HttpExchange@4d99e21f req=PENDING/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=IDLE,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] 14:04:19.485 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.client.HttpSender - Request begin HttpRequest[POST /graphql HTTP/1.1]@4cafa9aa 14:04:19.485 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.client.HttpSender - Request headers HttpRequest[POST /graphql HTTP/1.1]@4cafa9aa Accept-Encoding: gzip User-Agent: Jetty/9.4.12.v20180830 Content-Type: application/json Host: localhost:8181 Content-Length: 307 14:04:19.485 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.client.HttpContent - Advanced content to last chunk java.nio.HeapByteBuffer[pos=0 lim=307 cap=307] 14:04:19.485 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.client.HttpSender - Generated headers (-1 bytes), chunk (-1 bytes), content (307 bytes) - NEED_HEADER/HttpGenerator@5636d93e{s=START} 14:04:19.485 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.http.HttpGenerator - generateHeaders POST{u=/graphql,HTTP/1.1,h=5,cl=307} last=true content=HeapByteBuffer@4315bc79[p=0,l=307,c=307,r=307]={<<<{\r\n "query": "qu...\r\n }\r\n }\r\n}>>>} 14:04:19.485 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.http.HttpGenerator - Accept-Encoding: gzip User-Agent: Jetty/9.4.12.v20180830 Content-Type: application/json Host: localhost:8181 Content-Length: 307 14:04:19.485 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.http.HttpGenerator - CONTENT_LENGTH 14:04:19.485 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.client.HttpSender - Generated headers (160 bytes), chunk (-1 bytes), content (307 bytes) - FLUSH/HttpGenerator@5636d93e{s=COMPLETING} 14:04:19.486 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@29c8f897{IDLE}->null [HeapByteBuffer@35df8475[p=0,l=160,c=4096,r=160]={<<<POST /graphql HTT...Length: 307\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@3b0c9bce[p=0,l=0,c=0,r=0]={<<<>>>},HeapByteBuffer@4315bc79[p=0,l=307,c=307,r=307]={<<<{\r\n "query": "qu...\r\n }\r\n }\r\n}>>>}] 14:04:19.486 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@29c8f897{WRITING}->null:IDLE-->WRITING 14:04:19.486 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@c18337a woken up from select, 1/1/1 selected 14:04:19.486 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@c18337a processing 1 keys, 0 updates 14:04:19.486 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ManagedSelector - selected 1 sun.nio.ch.SelectionKeyImpl@2dfdc89 SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=FI,flush=-,to=4/60000}{io=1/1,kio=1,kro=1}->HttpConnection@2c75abf3[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1d6daeab{s=START}]=>HttpChannelOverHttp@2a6882ce{r=0,c=false,a=IDLE,uri=null,age=0} 14:04:19.486 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.io.ChannelEndPoint - flushed 467 SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=FI,flush=W,to=3/0}{io=1/1,kio=1,kro=8}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=HttpExchange@4d99e21f req=PENDING/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@4da9def7(req=HEADERS,snd=SENDING,failure=null)[HttpGenerator@5636d93e{s=COMPLETING}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] 14:04:19.486 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ChannelEndPoint - onSelected 1->0 r=true w=false for SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=FI,flush=-,to=4/60000}{io=1/0,kio=1,kro=1}->HttpConnection@2c75abf3[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1d6daeab{s=START}]=>HttpChannelOverHttp@2a6882ce{r=0,c=false,a=IDLE,uri=null,age=0} 14:04:19.486 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=467 remaining=0 WriteFlusher@29c8f897{WRITING}->null 14:04:19.486 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@29c8f897{IDLE}->null:WRITING-->IDLE 14:04:19.486 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ChannelEndPoint - task CEP:SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=FI,flush=-,to=4/60000}{io=1/0,kio=1,kro=1}->HttpConnection@2c75abf3[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1d6daeab{s=START}]=>HttpChannelOverHttp@2a6882ce{r=0,c=false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING 14:04:19.486 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.client.HttpSender - Generated headers (-1 bytes), chunk (-1 bytes), content (-1 bytes) - DONE/HttpGenerator@5636d93e{s=END} 14:04:19.486 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.client.HttpSender - Request committed HttpRequest[POST /graphql HTTP/1.1]@4cafa9aa 14:04:19.486 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.client.HttpSender - Request content HttpRequest[POST /graphql HTTP/1.1]@4cafa9aa HeapByteBuffer@2bc36eab[p=0,l=307,c=307,r=307]={<<<{\r\n "query": "qu...\r\n }\r\n }\r\n}>>>} 14:04:19.487 [qtp352598575-42] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@1df8da7a{s=1/8,p=0} tryExecute EatWhatYouKill@295cf707/SelectorProducer@1130520d/PRODUCING/p=false/QueuedThreadPool[qtp352598575]@15043a2f{STARTED,8<=8<=200,i=2,q=0}[ReservedThreadExecutor@1df8da7a{s=1/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.486Z 14:04:19.487 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.client.HttpContent - Advanced content past last chunk 14:04:19.487 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.client.HttpSender - Content present false, last true, consumed true for HttpRequest[POST /graphql HTTP/1.1]@4cafa9aa 14:04:19.487 [HttpClient@37095ded-118] DEBUG org.eclipse.jetty.client.HttpSender - Genera ...[truncated 12331 chars]... SPATCHED,uri=//localhost:8181/graphql,age=2} parse HeapByteBuffer@80ca5de[p=160,l=467,c=8192,r=307]={POST /graphql HTT...Length: 307\r\n\r\n<<<{\r\n "query": "qu...\r\n }\r\n }\r\n}>>>a"\r\n }\r\n}"name",...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} {} 14:04:19.500 [qtp352598575-42] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=CONTENT HeapByteBuffer@80ca5de[p=160,l=467,c=8192,r=307]={POST /graphql HTT...Length: 307\r\n\r\n<<<{\r\n "query": "qu...\r\n }\r\n }\r\n}>>>a"\r\n }\r\n}"name",...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} 14:04:19.500 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@2a6882ce{r=1,c=false,a=DISPATCHED,uri=//localhost:8181/graphql,age=2} onContent Content@43e2d6b2{HeapByteBufferR@e2cbe57[p=160,l=467,c=8192,r=307]={POST /graphql HTT...Length: 307\r\n\r\n<<<{\r\n "query": "qu...\r\n }\r\n }\r\n}>>>a"\r\n }\r\n}"name",...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}} 14:04:19.500 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpInput - HttpInputOverHTTP@72eb108f[c=0,q=0,[0]=null,s=STREAM] addContent Content@43e2d6b2{HeapByteBufferR@e2cbe57[p=160,l=467,c=8192,r=307]={POST /graphql HTT...Length: 307\r\n\r\n<<<{\r\n "query": "qu...\r\n }\r\n }\r\n}>>>a"\r\n }\r\n}"name",...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}} 14:04:19.500 [qtp352598575-42] DEBUG org.eclipse.jetty.http.HttpParser - CONTENT --> END 14:04:19.501 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@2a6882ce{r=1,c=false,a=DISPATCHED,uri=//localhost:8181/graphql,age=3} onContentComplete 14:04:19.501 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@2a6882ce{r=1,c=false,a=DISPATCHED,uri=//localhost:8181/graphql,age=3} onRequestComplete 14:04:19.501 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpInput - HttpInputOverHTTP@72eb108f[c=0,q=1,[0]=EOF,s=STREAM] addContent EOF 14:04:19.501 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@2c75abf3::SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=4/60000}{io=0/0,kio=0,kro=1}->HttpConnection@2c75abf3[p=HttpParser{s=END,307 of 307},g=HttpGenerator@1d6daeab{s=START}]=>HttpChannelOverHttp@2a6882ce{r=1,c=false,a=DISPATCHED,uri=//localhost:8181/graphql,age=3} parsed false HttpParser{s=END,307 of 307} 14:04:19.501 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpInput - HttpInputOverHTTP@72eb108f[c=307,q=1,[0]=EOF,s=STREAM] read 307 from Content@43e2d6b2{HeapByteBufferR@e2cbe57[p=467,l=467,c=8192,r=0]={POST /graphql HTT...\r\n }\r\n }\r\n}<<<>>>a"\r\n }\r\n}"name",...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}} 14:04:19.501 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpConnection - releaseRequestBuffer HttpConnection@2c75abf3::SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=4/60000}{io=0/0,kio=0,kro=1}->HttpConnection@2c75abf3[p=HttpParser{s=END,307 of 307},g=HttpGenerator@1d6daeab{s=START}]=>HttpChannelOverHttp@2a6882ce{r=1,c=false,a=DISPATCHED,uri=//localhost:8181/graphql,age=3} 14:04:19.502 [qtp352598575-42] DEBUG graphql.GraphQL - Executing request. operation name: 'null'. query: 'query _($prod : ProductInput!){ VarService{ testVariable(prod : $prod) } }'. variables '{prod={id=test, name=name, description=desc, price=12, inputValueList=[test1, test2], active=true}}' 14:04:19.502 [qtp352598575-42] DEBUG graphql.GraphQL - Parsing query: 'query _($prod : ProductInput!){ VarService{ testVariable(prod : $prod) } }'... 14:04:19.503 [qtp352598575-42] DEBUG graphql.GraphQL - Validating query: 'query _($prod : ProductInput!){ VarService{ testVariable(prod : $prod) } }' 14:04:19.504 [qtp352598575-42] DEBUG graphql.GraphQL - Executing '1ca5803a-f385-4b5d-a9f4-8f10553d122d'. operation name: 'null'. query: 'query _($prod : ProductInput!){ VarService{ testVariable(prod : $prod) } }'. variables '{prod={id=test, name=name, description=desc, price=12, inputValueList=[test1, test2], active=true}}' 14:04:19.504 [qtp352598575-42] DEBUG graphql.execution.Execution - Executing '1ca5803a-f385-4b5d-a9f4-8f10553d122d' query operation: 'QUERY' using 'de.dim.whiteboard.graphql.emf.executation.EMFAsyncExecutionStrategy' execution strategy 14:04:19.504 [qtp352598575-42] DEBUG graphql.execution.ExecutionStrategy - '1ca5803a-f385-4b5d-a9f4-8f10553d122d' fetching field '/VarService' using data fetcher 'graphql.schema.StaticDataFetcher'... 14:04:19.504 [qtp352598575-42] DEBUG graphql.execution.ExecutionStrategy - '1ca5803a-f385-4b5d-a9f4-8f10553d122d' field '/VarService' fetch returned 'org.apache.felix.framework.BundleContextImpl$ServiceObjectsImpl' 14:04:19.504 [qtp352598575-42] DEBUG graphql.execution.ExecutionStrategy - '1ca5803a-f385-4b5d-a9f4-8f10553d122d' completing field '/VarService'... 14:04:19.507 [qtp352598575-42] ERROR graphql.GraphQL - Execution '1ca5803a-f385-4b5d-a9f4-8f10553d122d' threw exception when executing : query : 'query _($prod : ProductInput!){ VarService{ testVariable(prod : $prod) } }'. variables '{prod={id=test, name=name, description=desc, price=12, inputValueList=[test1, test2], active=true}}' java.util.concurrent.CompletionException: java.lang.ClassCastException: java.lang.String cannot be cast to java.util.List at java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:292) at java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:308) at java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:769) at java.util.concurrent.CompletableFuture.uniWhenCompleteStage(CompletableFuture.java:778) at java.util.concurrent.CompletableFuture.whenComplete(CompletableFuture.java:2140) at graphql.execution.Execution.executeOperation(Execution.java:174) at graphql.execution.Execution.execute(Execution.java:101) at graphql.GraphQL.execute(GraphQL.java:573) at graphql.GraphQL.parseValidateAndExecute(GraphQL.java:515) at graphql.GraphQL.executeAsync(GraphQL.java:489) at graphql.GraphQL.execute(GraphQL.java:420) at graphql.servlet.GraphQLQueryInvoker.query(GraphQLQueryInvoker.java:95) at graphql.servlet.GraphQLQueryInvoker.query(GraphQLQueryInvoker.java:91) at graphql.servlet.GraphQLQueryInvoker.query(GraphQLQueryInvoker.java:42) at graphql.servlet.AbstractGraphQLHttpServlet.query(AbstractGraphQLHttpServlet.java:302) at graphql.servlet.AbstractGraphQLHttpServlet.lambda$new$4(AbstractGraphQLHttpServlet.java:174) at graphql.servlet.AbstractGraphQLHttpServlet.doRequest(AbstractGraphQLHttpServlet.java:273) at graphql.servlet.AbstractGraphQLHttpServlet.doRequestAsync(AbstractGraphQLHttpServlet.java:264) at graphql.servlet.AbstractGraphQLHttpServlet.doPost(AbstractGraphQLHttpServlet.java:294) at org.gecko.whiteboard.graphql.servlet.OsgiGraphqlWhiteboard.doPost(OsgiGraphqlWhiteboard.java:158) at javax.servlet.http.HttpServlet.service(HttpServlet.java:707) at org.gecko.whiteboard.graphql.servlet.OsgiGraphqlWhiteboard.service(OsgiGraphqlWhiteboard.java:170) at javax.servlet.http.HttpServlet.service(HttpServlet.java:790) at org.apache.felix.http.base.internal.handler.ServletHandler.handle(ServletHandler.java:120) at org.apache.felix.http.base.internal.dispatch.InvocationChain.doFilter(InvocationChain.java:86) at org.apache.felix.http.base.internal.dispatch.Dispatcher$1.doFilter(Dispatcher.java:146) at org.apache.felix.http.base.internal.whiteboard.WhiteboardManager.invokePreprocessors(WhiteboardManager.java:1000) at org.apache.felix.http.base.internal.dispatch.Dispatcher.dispatch(Dispatcher.java:91) at org.apache.felix.http.base.internal.dispatch.DispatcherServlet.service(DispatcherServlet.java:49) at javax.servlet.http.HttpServlet.service(HttpServlet.java:790) at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:865) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:535) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1340) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1242) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:220) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) at org.eclipse.jetty.server.Server.handle(Server.java:503) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:364) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:305) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:132) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:765) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:683) at java.lang.Thread.run(Thread.java:745) Caused by: java.lang.ClassCastException: java.lang.String cannot be cast to java.util.List at de.dim.whiteboard.graphql.emf.executation.EMFValuesResolver.convertToEObject(EMFValuesResolver.java:341) at de.dim.whiteboard.graphql.emf.executation.EMFValuesResolver.coerceValueAst(EMFValuesResolver.java:282) at de.dim.whiteboard.graphql.emf.executation.EMFValuesResolver.getArgumentValues(EMFValuesResolver.java:156) at de.dim.whiteboard.graphql.emf.executation.EMFAsyncExecutionStrategy.fetchField(EMFAsyncExecutionStrategy.java:70) at graphql.execution.ExecutionStrategy.resolveFieldWithInfo(ExecutionStrategy.java:202) at graphql.execution.AsyncExecutionStrategy.execute(AsyncExecutionStrategy.java:70) at graphql.execution.ExecutionStrategy.completeValueForObject(ExecutionStrategy.java:636) at graphql.execution.ExecutionStrategy.completeValue(ExecutionStrategy.java:416) at graphql.execution.ExecutionStrategy.completeField(ExecutionStrategy.java:366) at graphql.execution.ExecutionStrategy.lambda$resolveFieldWithInfo$0(ExecutionStrategy.java:204) at java.util.concurrent.CompletableFuture.uniApply(CompletableFuture.java:602) at java.util.concurrent.CompletableFuture.uniApplyStage(CompletableFuture.java:614) at java.util.concurrent.CompletableFuture.thenApply(CompletableFuture.java:1983) at graphql.execution.ExecutionStrategy.resolveFieldWithInfo(ExecutionStrategy.java:203) at graphql.execution.AsyncExecutionStrategy.execute(AsyncExecutionStrategy.java:70) at graphql.execution.Execution.executeOperation(Execution.java:159) ... 51 common frames omitted 14:04:19.508 [qtp352598575-42] INFO graphql.servlet.AbstractGraphQLHttpServlet - Bad POST request: parsing failed java.lang.ClassCastException: java.lang.String cannot be cast to java.util.List at de.dim.whiteboard.graphql.emf.executation.EMFValuesResolver.convertToEObject(EMFValuesResolver.java:341) at de.dim.whiteboard.graphql.emf.executation.EMFValuesResolver.coerceValueAst(EMFValuesResolver.java:282) at de.dim.whiteboard.graphql.emf.executation.EMFValuesResolver.getArgumentValues(EMFValuesResolver.java:156) at de.dim.whiteboard.graphql.emf.executation.EMFAsyncExecutionStrategy.fetchField(EMFAsyncExecutionStrategy.java:70) at graphql.execution.ExecutionStrategy.resolveFieldWithInfo(ExecutionStrategy.java:202) at graphql.execution.AsyncExecutionStrategy.execute(AsyncExecutionStrategy.java:70) at graphql.execution.ExecutionStrategy.completeValueForObject(ExecutionStrategy.java:636) at graphql.execution.ExecutionStrategy.completeValue(ExecutionStrategy.java:416) at graphql.execution.ExecutionStrategy.completeField(ExecutionStrategy.java:366) at graphql.execution.ExecutionStrategy.lambda$resolveFieldWithInfo$0(ExecutionStrategy.java:204) at java.util.concurrent.CompletableFuture.uniApply(CompletableFuture.java:602) at java.util.concurrent.CompletableFuture.uniApplyStage(CompletableFuture.java:614) at java.util.concurrent.CompletableFuture.thenApply(CompletableFuture.java:1983) at graphql.execution.ExecutionStrategy.resolveFieldWithInfo(ExecutionStrategy.java:203) at graphql.execution.AsyncExecutionStrategy.execute(AsyncExecutionStrategy.java:70) at graphql.execution.Execution.executeOperation(Execution.java:159) at graphql.execution.Execution.execute(Execution.java:101) at graphql.GraphQL.execute(GraphQL.java:573) at graphql.GraphQL.parseValidateAndExecute(GraphQL.java:515) at graphql.GraphQL.executeAsync(GraphQL.java:489) at graphql.GraphQL.execute(GraphQL.java:420) at graphql.servlet.GraphQLQueryInvoker.query(GraphQLQueryInvoker.java:95) at graphql.servlet.GraphQLQueryInvoker.query(GraphQLQueryInvoker.java:91) at graphql.servlet.GraphQLQueryInvoker.query(GraphQLQueryInvoker.java:42) at graphql.servlet.AbstractGraphQLHttpServlet.query(AbstractGraphQLHttpServlet.java:302) at graphql.servlet.AbstractGraphQLHttpServlet.lambda$new$4(AbstractGraphQLHttpServlet.java:174) at graphql.servlet.AbstractGraphQLHttpServlet.doRequest(AbstractGraphQLHttpServlet.java:273) at graphql.servlet.AbstractGraphQLHttpServlet.doRequestAsync(AbstractGraphQLHttpServlet.java:264) at graphql.servlet.AbstractGraphQLHttpServlet.doPost(AbstractGraphQLHttpServlet.java:294) at org.gecko.whiteboard.graphql.servlet.OsgiGraphqlWhiteboard.doPost(OsgiGraphqlWhiteboard.java:158) at javax.servlet.http.HttpServlet.service(HttpServlet.java:707) at org.gecko.whiteboard.graphql.servlet.OsgiGraphqlWhiteboard.service(OsgiGraphqlWhiteboard.java:170) at javax.servlet.http.HttpServlet.service(HttpServlet.java:790) at org.apache.felix.http.base.internal.handler.ServletHandler.handle(ServletHandler.java:120) at org.apache.felix.http.base.internal.dispatch.InvocationChain.doFilter(InvocationChain.java:86) at org.apache.felix.http.base.internal.dispatch.Dispatcher$1.doFilter(Dispatcher.java:146) at org.apache.felix.http.base.internal.whiteboard.WhiteboardManager.invokePreprocessors(WhiteboardManager.java:1000) at org.apache.felix.http.base.internal.dispatch.Dispatcher.dispatch(Dispatcher.java:91) at org.apache.felix.http.base.internal.dispatch.DispatcherServlet.service(DispatcherServlet.java:49) at javax.servlet.http.HttpServlet.service(HttpServlet.java:790) at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:865) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:535) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1340) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1242) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:220) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) at org.eclipse.jetty.server.Server.handle(Server.java:503) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:364) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:305) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:132) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:765) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:683) at java.lang.Thread.run(Thread.java:745) 14:04:19.509 [qtp352598575-42] DEBUG org.eclipse.jetty.server.session - FinalSession=null old_session_manager=null this=org.eclipse.jetty.server.session.SessionHandler361268035==dftMaxIdleSec=0 14:04:19.509 [qtp352598575-42] DEBUG org.eclipse.jetty.server.Server - handled=true async=false committed=false on HttpChannelOverHttp@2a6882ce{r=1,c=false,a=DISPATCHED,uri=//localhost:8181/graphql,age=11} 14:04:19.509 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpChannelState - unhandle HttpChannelState@6657c9a2{s=DISPATCHED a=NOT_ASYNC i=true r=IDLE w=false} 14:04:19.509 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@2a6882ce{r=1,c=false,a=COMPLETING,uri=//localhost:8181/graphql,age=11} action COMPLETE 14:04:19.509 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpChannel - sendResponse info=null content=HeapByteBuffer@3b0c9bce[p=0,l=0,c=0,r=0]={<<<>>>} complete=true committing=true callback=Blocker@56c2ab1{null} 14:04:19.509 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpChannel - COMMIT for /graphql on HttpChannelOverHttp@2a6882ce{r=1,c=true,a=COMPLETING,uri=//localhost:8181/graphql,age=11} 400 null HTTP/1.1 Date: Wed, 10 Apr 2019 14:04:19 GMT Access-Control-Allow-Origin: * Access-Control-Allow-Headers: * Access-Control-Request-Method: * 14:04:19.509 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@2e2824c2[PROCESSING][i=HTTP/1.1{s=400,h=4,cl=-1},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@c68c999] generate: NEED_HEADER (null,[p=0,l=0,c=0,r=0],true)@START 14:04:19.509 [qtp352598575-42] DEBUG org.eclipse.jetty.http.HttpGenerator - generateHeaders HTTP/1.1{s=400,h=4,cl=-1} last=true content=HeapByteBuffer@3b0c9bce[p=0,l=0,c=0,r=0]={<<<>>>} 14:04:19.510 [qtp352598575-42] DEBUG org.eclipse.jetty.http.HttpGenerator - Date: Wed, 10 Apr 2019 14:04:19 GMT Access-Control-Allow-Origin: * Access-Control-Allow-Headers: * Access-Control-Request-Method: * 14:04:19.510 [qtp352598575-42] DEBUG org.eclipse.jetty.http.HttpGenerator - CONTENT_LENGTH 14:04:19.510 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@2e2824c2[PROCESSING][i=HTTP/1.1{s=400,h=4,cl=-1},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@c68c999] generate: FLUSH ([p=0,l=183,c=16384,r=183],[p=0,l=0,c=0,r=0],true)@COMPLETING 14:04:19.510 [qtp352598575-42] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@3d6c7c2b{IDLE}->null [HeapByteBuffer@725e9272[p=0,l=183,c=16384,r=183]={<<<HTTP/1.1 400 Bad ...t-Length: 0\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}] 14:04:19.510 [qtp352598575-42] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@3d6c7c2b{WRITING}->null:IDLE-->WRITING 14:04:19.510 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2f9fd565 woken up from select, 1/1/1 selected 14:04:19.510 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2f9fd565 processing 1 keys, 0 updates 14:04:19.510 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ChannelEndPoint - flushed 183 SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=-,flush=W,to=13/60000}{io=0/0,kio=0,kro=1}->HttpConnection@2c75abf3[p=HttpParser{s=END,307 of 307},g=HttpGenerator@1d6daeab{s=COMPLETING}]=>HttpChannelOverHttp@2a6882ce{r=1,c=true,a=COMPLETING,uri=//localhost:8181/graphql,age=12} 14:04:19.510 [qtp352598575-42] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=183 remaining=0 WriteFlusher@3d6c7c2b{WRITING}->null 14:04:19.510 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - selected 1 sun.nio.ch.SelectionKeyImpl@21582ee6 SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=FI,flush=-,to=24/0}{io=1/1,kio=1,kro=1}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=HttpExchange@4d99e21f req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] 14:04:19.510 [qtp352598575-42] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@3d6c7c2b{IDLE}->null:WRITING-->IDLE 14:04:19.510 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@2e2824c2[PROCESSING][i=HTTP/1.1{s=400,h=4,cl=-1},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@c68c999] generate: DONE ([p=183,l=183,c=16384,r=0],[p=0,l=0,c=0,r=0],true)@END 14:04:19.510 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ChannelEndPoint - onSelected 1->0 r=true w=false for SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=FI,flush=-,to=24/0}{io=1/0,kio=1,kro=1}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=HttpExchange@4d99e21f req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] 14:04:19.511 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpChannelState - onComplete HttpChannelState@6657c9a2{s=COMPLETING a=NOT_ASYNC i=false r=IDLE w=false} 14:04:19.511 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpChannel - COMPLETE for /graphql written=0 14:04:19.511 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpChannelState - recycle HttpChannelState@6657c9a2{s=COMPLETED a=NOT_ASYNC i=false r=IDLE w=false} 14:04:19.511 [qtp352598575-42] DEBUG org.eclipse.jetty.http.HttpParser - reset HttpParser{s=END,307 of 307} 14:04:19.511 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ChannelEndPoint - task CEP:SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=FI,flush=-,to=25/0}{io=1/0,kio=1,kro=1}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=HttpExchange@4d99e21f req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]:runFillable:BLOCKING 14:04:19.511 [qtp352598575-42] DEBUG org.eclipse.jetty.http.HttpParser - END --> START 14:04:19.511 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@2a6882ce{r=1,c=false,a=IDLE,uri=null,age=0} handle exit, result COMPLETE 14:04:19.511 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@56da52a7{s=0/8,p=0} tryExecute EatWhatYouKill@7cedfa63/SelectorProducer@3e83c18/PRODUCING/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=4,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.511Z 14:04:19.511 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=1/60000}{io=0/0,kio=0,kro=1}->HttpConnection@2c75abf3[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1d6daeab{s=START}]=>HttpChannelOverHttp@2a6882ce{r=1,c=false,a=IDLE,uri=null,age=0} 14:04:19.511 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@56da52a7{s=0/8,p=1} startReservedThread p=1 14:04:19.511 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - queue ReservedThreadExecutor@56da52a7{s=0/8,p=1}@606524dd 14:04:19.511 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=1/60000}{io=0/0,kio=0,kro=1}->HttpConnection@2c75abf3[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1d6daeab{s=START}]=>HttpChannelOverHttp@2a6882ce{r=1,c=false,a=IDLE,uri=null,age=0} 14:04:19.511 [HttpClient@37095ded-119] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run ReservedThreadExecutor@56da52a7{s=0/8,p=1}@606524dd 14:04:19.511 [HttpClient@37095ded-119] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@56da52a7{s=1/8,p=1}@606524dd started 14:04:19.511 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@2c75abf3::SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=1/60000}{io=0/0,kio=0,kro=1}->HttpConnection@2c75abf3[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1d6daeab{s=START}]=>HttpChannelOverHttp@2a6882ce{r=1,c=false,a=IDLE,uri=null,age=0} filled 0 HeapByteBuffer@80ca5de[p=0,l=0,c=8192,r=0]={<<<>>>POST /graphql HTT...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} 14:04:19.511 [HttpClient@37095ded-119] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@56da52a7{s=1/8,p=0}@606524dd waiting 14:04:19.511 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@7cedfa63/SelectorProducer@3e83c18/PRODUCING/p=false/QueuedThreadPool[HttpClient@37095ded]@2ca6546f{STARTED,8<=8<=200,i=3,q=0}[ReservedThreadExecutor@56da52a7{s=0/8,p=1}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:19.511Z m=PRODUCE_EXECUTE_CONSUME t=CEP:SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=FI,flush=-,to=25/0}{io=1/0,kio=1,kro=1}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=HttpExchange@4d99e21f req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]:runFillable:BLOCKING/BLOCKING 14:04:19.511 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@2c75abf3::SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=1/60000}{io=0/0,kio=0,kro=1}->HttpConnection@2c75abf3[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1d6daeab{s=START}]=>HttpChannelOverHttp@2a6882ce{r=1,c=false,a=IDLE,uri=null,age=0} parse HeapByteBuffer@80ca5de[p=0,l=0,c=8192,r=0]={<<<>>>POST /graphql HTT...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} {} 14:04:19.511 [qtp352598575-42] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=START HeapByteBuffer@80ca5de[p=0,l=0,c=8192,r=0]={<<<>>>POST /graphql HTT...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} 14:04:19.512 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - queue CEP:SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=FI,flush=-,to=25/0}{io=1/0,kio=1,kro=1}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=HttpExchange@4d99e21f req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]:runFillable:BLOCKING 14:04:19.512 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@2c75abf3::SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=1/60000}{io=0/0,kio=0,kro=1}->HttpConnection@2c75abf3[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1d6daeab{s=START}]=>HttpChannelOverHttp@2a6882ce{r=1,c=false,a=IDLE,uri=null,age=0} parsed false HttpParser{s=START,0 of -1} 14:04:19.512 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 0 14:04:19.512 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0 14:04:19.512 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpConnection - releaseRequestBuffer HttpConnection@2c75abf3::SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=2/60000}{io=0/0,kio=0,kro=1}->HttpConnection@2c75abf3[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1d6daeab{s=START}]=>HttpChannelOverHttp@2a6882ce{r=1,c=false,a=IDLE,uri=null,age=0} 14:04:19.512 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 1 -> 0 on SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=FI,flush=-,to=26/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=HttpExchange@4d99e21f req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] 14:04:19.512 [qtp352598575-42] DEBUG org.eclipse.jetty.io.AbstractConnection - fillInterested HttpConnection@2c75abf3::SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=2/60000}{io=0/0,kio=0,kro=1}->HttpConnection@2c75abf3[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1d6daeab{s=START}]=>HttpChannelOverHttp@2a6882ce{r=1,c=false,a=IDLE,uri=null,age=0} 14:04:19.512 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2f9fd565 waiting with 1 keys 14:04:19.512 [qtp352598575-42] DEBUG org.eclipse.jetty.io.FillInterest - interested FillInterest@73fd213a{AC.ReadCB@2c75abf3{HttpConnection@2c75abf3::SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=FI,flush=-,to=0/60000}{io=0/0,kio=0,kro=1}->HttpConnection@2c75abf3[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1d6daeab{s=START}]=>HttpChannelOverHttp@2a6882ce{r=1,c=false,a=IDLE,uri=null,age=0}}} 14:04:19.512 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run CEP:SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=FI,flush=-,to=26/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=HttpExchange@4d99e21f req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]:runFillable:BLOCKING 14:04:19.512 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ChannelEndPoint - changeInterests p=false 0->1 for SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=FI,flush=-,to=0/60000}{io=0/1,kio=0,kro=1}->HttpConnection@2c75abf3[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1d6daeab{s=START}]=>HttpChannelOverHttp@2a6882ce{r=1,c=false,a=IDLE,uri=null,age=0} 14:04:19.512 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ChannelEndPoint$1@2b3ffc3e on ManagedSelector@5f77d0f9{STARTED} id=3 keys=1 selected=0 updates=0 14:04:19.512 [qtp352598575-42] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@5f77d0f9{STARTED} id=3 keys=1 selected=0 updates=1 14:04:19.512 [qtp352598575-46] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@c18337a woken with none selected 14:04:19.512 [qtp352598575-46] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@c18337a woken up from select, 0/0/1 selected 14:04:19.513 [qtp352598575-46] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@c18337a processing 0 keys, 1 updates 14:04:19.513 [qtp352598575-42] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@2c75abf3::SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=FI,flush=-,to=0/60000}{io=0/1,kio=0,kro=1}->HttpConnection@2c75abf3[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1d6daeab{s=START}]=>HttpChannelOverHttp@2a6882ce{r=1,c=false,a=IDLE,uri=null,age=0} onFillable exit HttpChannelState@6657c9a2{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false} null 14:04:19.513 [qtp352598575-46] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1 14:04:19.512 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.io.FillInterest - fillable FillInterest@2edb0cf2{AC.ReadCB@2533af6{HttpConnectionOverHTTP@2533af6::SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=FI,flush=-,to=26/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=HttpExchange@4d99e21f req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]}} 14:04:19.513 [qtp352598575-42] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$$Lambda$24/455501890@6692b6c6 14:04:19.513 [qtp352598575-46] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ChannelEndPoint$1@2b3ffc3e 14:04:19.513 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=START DirectByteBuffer@697a89a8[p=0,l=0,c=16384,r=0]={<<<>>>\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} 14:04:19.513 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.client.HttpReceiver - Parsed false, remaining 0 HttpParser{s=START,0 of -1} 14:04:19.513 [qtp352598575-46] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 0 -> 1 on SocketChannelEndPoint@27a2c16f{/127.0.0.1:44004<->/127.0.0.1:8181,OPEN,fill=FI,flush=-,to=1/60000}{io=1/1,kio=1,kro=1}->HttpConnection@2c75abf3[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1d6daeab{s=START}]=>HttpChannelOverHttp@2a6882ce{r=1,c=false,a=IDLE,uri=null,age=0} 14:04:19.513 [qtp352598575-46] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0 14:04:19.513 [qtp352598575-46] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@c18337a waiting with 1 keys 14:04:19.513 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 183 SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=-,flush=-,to=27/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=HttpExchange@4d99e21f req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] 14:04:19.513 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.client.HttpReceiver - Read 183 bytes DirectByteBuffer@697a89a8[p=0,l=183,c=16384,r=183]={<<<HTTP/1.1 400 Bad ...t-Length: 0\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} from SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=-,flush=-,to=0/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=HttpExchange@4d99e21f req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] 14:04:19.513 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=START DirectByteBuffer@697a89a8[p=0,l=183,c=16384,r=183]={<<<HTTP/1.1 400 Bad ...t-Length: 0\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} 14:04:19.513 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - START --> RESPONSE_VERSION 14:04:19.513 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - RESPONSE_VERSION --> SPACE1 14:04:19.513 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - SPACE1 --> STATUS 14:04:19.513 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - STATUS --> SPACE2 14:04:19.513 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - SPACE2 --> REASON 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - REASON --> HEADER 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.client.HttpReceiver - Response begin HttpResponse[HTTP/1.1 400 Bad Request]@67bd6af7 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Date --> VALUE 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Date --> IN_VALUE 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Date --> FIELD 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - HEADER: --> IN_NAME 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Access-Control-Allow-Origin --> VALUE 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Access-Control-Allow-Origin --> IN_VALUE 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Access-Control-Allow-Origin --> FIELD 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - HEADER: --> IN_NAME 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Access-Control-Allow-Headers --> VALUE 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Access-Control-Allow-Headers --> IN_VALUE 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Access-Control-Allow-Headers --> FIELD 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - HEADER: --> IN_NAME 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Access-Control-Request-Method --> VALUE 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Access-Control-Request-Method --> IN_VALUE 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Access-Control-Request-Method --> FIELD 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Content-Length: 0 --> IN_VALUE 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Content-Length: 0 --> FIELD 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - HEADER --> END 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.client.HttpReceiver - Response headers HttpResponse[HTTP/1.1 400 Bad Request]@67bd6af7 Date: Wed, 10 Apr 2019 14:04:19 GMT Access-Control-Allow-Origin: * Access-Control-Allow-Headers: * Access-Control-Request-Method: * Content-Length: 0 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - reset HttpParser{s=END,0 of 0} 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - END --> START 14:04:19.514 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.client.HttpReceiver - Response success HttpResponse[HTTP/1.1 400 Bad Request]@67bd6af7 14:04:19.515 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.client.HttpExchange - Terminated response for HttpExchange@4d99e21f req=TERMINATED/null@null res=TERMINATED/null@null, result: Result[HttpRequest[POST /graphql HTTP/1.1]@4cafa9aa > HttpResponse[HTTP/1.1 400 Bad Request]@67bd6af7] null 14:04:19.515 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.client.HttpReceiver - Response complete HttpResponse[HTTP/1.1 400 Bad Request]@67bd6af7 14:04:19.515 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.client.HttpChannel - HttpExchange@4d99e21f req=TERMINATED/null@null res=TERMINATED/null@null disassociated true from HttpChannelOverHTTP@8ab5186(exchange=null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] 14:04:19.515 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.client.HttpDestination - Released HttpConnectionOverHTTP@2533af6::SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=-,flush=-,to=2/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] 14:04:19.515 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.client.AbstractConnectionPool - Connection idle HttpConnectionOverHTTP@2533af6::SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=-,flush=-,to=2/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] 14:04:19.515 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.client.HttpReceiver - Request/Response succeeded: Result[HttpRequest[POST /graphql HTTP/1.1]@4cafa9aa > HttpResponse[HTTP/1.1 400 Bad Request]@67bd6af7] null 14:04:19.515 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.client.HttpReceiver - Parsed false, remaining 0 HttpParser{s=START,0 of -1} 14:04:19.515 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=START DirectByteBuffer@697a89a8[p=183,l=183,c=16384,r=0]={HTTP/1.1 400 Bad ...t-Length: 0\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} 14:04:19.515 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.client.HttpReceiver - Parsed false, remaining 0 HttpParser{s=START,0 of -1} 14:04:19.516 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=-,flush=-,to=2/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] 14:04:19.516 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.client.HttpReceiver - Read 0 bytes DirectByteBuffer@697a89a8[p=0,l=0,c=16384,r=0]={<<<>>>HTTP/1.1 400 Bad ...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} from SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=-,flush=-,to=3/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] 14:04:19.516 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.io.AbstractConnection - fillInterested HttpConnectionOverHTTP@2533af6::SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=-,flush=-,to=3/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] 14:04:19.516 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.io.FillInterest - interested FillInterest@2edb0cf2{AC.ReadCB@2533af6{HttpConnectionOverHTTP@2533af6::SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=FI,flush=-,to=0/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]}} 14:04:19.516 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.io.ChannelEndPoint - changeInterests p=false 0->1 for SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=FI,flush=-,to=0/0}{io=0/1,kio=0,kro=1}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] 14:04:19.516 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ChannelEndPoint$1@2d8621b8 on ManagedSelector@783efb48{STARTED} id=1 keys=1 selected=0 updates=0 14:04:19.517 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@783efb48{STARTED} id=1 keys=1 selected=0 updates=1 14:04:19.517 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2f9fd565 woken with none selected 14:04:19.517 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2f9fd565 woken up from select, 0/0/1 selected 14:04:19.517 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2f9fd565 processing 0 keys, 1 updates 14:04:19.517 [HttpClient@37095ded-120] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran CEP:SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=FI,flush=-,to=1/0}{io=0/1,kio=0,kro=1}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]:runFillable:BLOCKING 14:04:19.517 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1 14:04:19.517 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ChannelEndPoint$1@2d8621b8 14:04:19.517 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 0 -> 1 on SocketChannelEndPoint@c94d4cc{localhost/127.0.0.1:8181<->/127.0.0.1:44004,OPEN,fill=FI,flush=-,to=1/0}{io=1/1,kio=1,kro=1}->HttpConnectionOverHTTP@2533af6(l:/127.0.0.1:44004 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@8ab5186(exchange=null)[send=HttpSenderOverHTTP@4da9def7(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@5636d93e{s=START}],recv=HttpReceiverOverHTTP@7cae5580(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] 14:04:19.517 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0 14:04:19.517 [HttpClient@37095ded-114] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2f9fd565 waiting with 1 keys 14:04:19.522 [main] INFO graphql.servlet.AbstractGraphQLHttpServlet - Schema generation sucessfull