![[Jenkins]](/jenkins/static/ed43705e/images/svgs/logo.svg){kind=logo}
Failed
de.dim.whiteboard.graphql.emf.integration.test.VariableIntegrationTest.testSingleVariableListMultipleValues (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.testSingleVariableListMultipleValues(VariableIntegrationTest.java:374)
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:08.711 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting HttpClient@47747fb9{STOPPED}
14:04:08.711 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - HttpClient@47747fb9{STARTING} added {SslContextFactory@20312893[provider=null,keyStore=null,trustStore=null],AUTO}
14:04:08.711 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - HttpClient@47747fb9{STARTING} added {QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STOPPED,8<=0<=200,i=0,q=0}[org.eclipse.jetty.util.thread.TryExecutor$$Lambda$1/2045036434@6e9a5ed8],AUTO}
14:04:08.713 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - HttpClient@47747fb9{STARTING} added {org.eclipse.jetty.io.MappedByteBufferPool@1a6f5124,POJO}
14:04:08.713 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - HttpClient@47747fb9{STARTING} added {ScheduledExecutorScheduler@1edb61b1{STOPPED},AUTO}
14:04:08.713 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - HttpClient@47747fb9{STARTING} added {HttpClientTransportOverHTTP@ec2bf82{STOPPED},AUTO}
14:04:08.714 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - HttpClient@47747fb9{STARTING} added {org.eclipse.jetty.util.SocketAddressResolver$Async@29539e36,POJO}
14:04:08.720 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting SslContextFactory@20312893[provider=null,keyStore=null,trustStore=null]
14:04:08.899 [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:08.899 [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:08.899 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @1603ms SslContextFactory@20312893[provider=null,keyStore=null,trustStore=null]
14:04:08.899 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STOPPED,8<=0<=200,i=0,q=0}[org.eclipse.jetty.util.thread.TryExecutor$$Lambda$1/2045036434@6e9a5ed8]
14:04:08.899 [main] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}
14:04:08.899 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTING,8<=0<=200,i=0,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}] added {ReservedThreadExecutor@13e9f2e2{s=0/8,p=0},AUTO}
14:04:08.899 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}
14:04:08.900 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @1604ms ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}
14:04:08.903 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @1607ms QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=7,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}]
14:04:08.903 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting ScheduledExecutorScheduler@1edb61b1{STOPPED}
14:04:08.903 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @1608ms ScheduledExecutorScheduler@1edb61b1{STARTED}
14:04:08.903 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting HttpClientTransportOverHTTP@ec2bf82{STOPPED}
14:04:08.904 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - HttpClientTransportOverHTTP@ec2bf82{STARTING} added {ClientSelectorManager@7a356a0d{STOPPED},AUTO}
14:04:08.904 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting ClientSelectorManager@7a356a0d{STOPPED}
14:04:08.904 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - EatWhatYouKill@c827db/SelectorProducer@377c68c6/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.904Z added {SelectorProducer@377c68c6,POJO}
14:04:08.905 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - EatWhatYouKill@c827db/SelectorProducer@377c68c6/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.905Z added {QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}],UNMANAGED}
14:04:08.905 [main] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@c827db/SelectorProducer@377c68c6/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.905Z created
14:04:08.905 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - ManagedSelector@538cd0f2{STOPPED} id=0 keys=-1 selected=-1 updates=0 added {EatWhatYouKill@c827db/SelectorProducer@377c68c6/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.905Z,MANAGED}
14:04:08.906 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - ClientSelectorManager@7a356a0d{STARTING} added {ManagedSelector@538cd0f2{STOPPED} id=0 keys=-1 selected=-1 updates=0,AUTO}
14:04:08.906 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - EatWhatYouKill@238ad8c/SelectorProducer@430fa4ef/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.906Z added {SelectorProducer@430fa4ef,POJO}
14:04:08.906 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - EatWhatYouKill@238ad8c/SelectorProducer@430fa4ef/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.906Z added {QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}],UNMANAGED}
14:04:08.907 [main] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@238ad8c/SelectorProducer@430fa4ef/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.907Z created
14:04:08.907 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - ManagedSelector@1761de10{STOPPED} id=1 keys=-1 selected=-1 updates=0 added {EatWhatYouKill@238ad8c/SelectorProducer@430fa4ef/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.907Z,MANAGED}
14:04:08.907 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - ClientSelectorManager@7a356a0d{STARTING} added {ManagedSelector@1761de10{STOPPED} id=1 keys=-1 selected=-1 updates=0,AUTO}
14:04:08.908 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - EatWhatYouKill@22df874e/SelectorProducer@654c1a54/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.907Z added {SelectorProducer@654c1a54,POJO}
14:04:08.908 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - EatWhatYouKill@22df874e/SelectorProducer@654c1a54/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.908Z added {QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}],UNMANAGED}
14:04:08.908 [main] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@22df874e/SelectorProducer@654c1a54/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.908Z created
14:04:08.909 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - ManagedSelector@5bdaf2ce{STOPPED} id=2 keys=-1 selected=-1 updates=0 added {EatWhatYouKill@22df874e/SelectorProducer@654c1a54/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.909Z,MANAGED}
14:04:08.909 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - ClientSelectorManager@7a356a0d{STARTING} added {ManagedSelector@5bdaf2ce{STOPPED} id=2 keys=-1 selected=-1 updates=0,AUTO}
14:04:08.910 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - EatWhatYouKill@42d236fb/SelectorProducer@1ce93c18/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.909Z added {SelectorProducer@1ce93c18,POJO}
14:04:08.910 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - EatWhatYouKill@42d236fb/SelectorProducer@1ce93c18/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.91Z added {QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}],UNMANAGED}
14:04:08.911 [main] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@42d236fb/SelectorProducer@1ce93c18/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.91Z created
14:04:08.911 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - ManagedSelector@19f21b6b{STOPPED} id=3 keys=-1 selected=-1 updates=0 added {EatWhatYouKill@42d236fb/SelectorProducer@1ce93c18/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.911Z,MANAGED}
14:04:08.911 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - ClientSelectorManager@7a356a0d{STARTING} added {ManagedSelector@19f21b6b{STOPPED} id=3 keys=-1 selected=-1 updates=0,AUTO}
14:04:08.911 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting ManagedSelector@538cd0f2{STOPPED} id=0 keys=-1 selected=-1 updates=0
14:04:08.911 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting EatWhatYouKill@c827db/SelectorProducer@377c68c6/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.911Z
14:04:08.912 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @1616ms EatWhatYouKill@c827db/SelectorProducer@377c68c6/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=8,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.912Z
14:04:08.912 [main] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - queue org.eclipse.jetty.io.ManagedSelector$$Lambda$24/455501890@1532c619
14:04:08.912 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$Start@46044faa on ManagedSelector@538cd0f2{STARTING} id=0 keys=0 selected=0 updates=0
14:04:08.912 [HttpClient@47747fb9-94] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.io.ManagedSelector$$Lambda$24/455501890@1532c619
14:04:08.912 [HttpClient@47747fb9-94] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@c827db/SelectorProducer@377c68c6/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=7,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.912Z tryProduce false
14:04:08.912 [HttpClient@47747fb9-94] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
14:04:08.913 [HttpClient@47747fb9-94] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$Start@46044faa
14:04:08.913 [HttpClient@47747fb9-94] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
14:04:08.913 [HttpClient@47747fb9-94] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@4113d126 waiting with 0 keys
14:04:08.913 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @1617ms ManagedSelector@538cd0f2{STARTED} id=0 keys=0 selected=0 updates=0
14:04:08.913 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting ManagedSelector@1761de10{STOPPED} id=1 keys=-1 selected=-1 updates=0
14:04:08.913 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting EatWhatYouKill@238ad8c/SelectorProducer@430fa4ef/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=7,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.913Z
14:04:08.914 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @1618ms EatWhatYouKill@238ad8c/SelectorProducer@430fa4ef/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=7,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.914Z
14:04:08.914 [main] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - queue org.eclipse.jetty.io.ManagedSelector$$Lambda$24/455501890@1358b28e
14:04:08.914 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.io.ManagedSelector$$Lambda$24/455501890@1358b28e
14:04:08.914 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@238ad8c/SelectorProducer@430fa4ef/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=6,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.914Z tryProduce false
14:04:08.914 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 0
14:04:08.914 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$Start@1a78dacd on ManagedSelector@1761de10{STARTING} id=1 keys=0 selected=0 updates=0
14:04:08.914 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
14:04:08.914 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@50d5d41d waiting with 0 keys
14:04:08.914 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@1761de10{STARTING} id=1 keys=0 selected=0 updates=1
14:04:08.915 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@50d5d41d woken with none selected
14:04:08.915 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@50d5d41d woken up from select, 0/0/0 selected
14:04:08.915 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@50d5d41d processing 0 keys, 1 updates
14:04:08.915 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
14:04:08.915 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$Start@1a78dacd
14:04:08.915 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
14:04:08.915 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@50d5d41d waiting with 0 keys
14:04:08.916 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @1621ms ManagedSelector@1761de10{STARTED} id=1 keys=0 selected=0 updates=0
14:04:08.917 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting ManagedSelector@5bdaf2ce{STOPPED} id=2 keys=-1 selected=-1 updates=0
14:04:08.917 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting EatWhatYouKill@22df874e/SelectorProducer@654c1a54/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=6,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.917Z
14:04:08.918 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @1622ms EatWhatYouKill@22df874e/SelectorProducer@654c1a54/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=6,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.918Z
14:04:08.918 [main] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - queue org.eclipse.jetty.io.ManagedSelector$$Lambda$24/455501890@19f9d595
14:04:08.918 [HttpClient@47747fb9-96] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.io.ManagedSelector$$Lambda$24/455501890@19f9d595
14:04:08.918 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$Start@7de4a01f on ManagedSelector@5bdaf2ce{STARTING} id=2 keys=0 selected=0 updates=0
14:04:08.919 [HttpClient@47747fb9-96] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@22df874e/SelectorProducer@654c1a54/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=5,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.919Z tryProduce false
14:04:08.919 [HttpClient@47747fb9-96] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
14:04:08.919 [HttpClient@47747fb9-96] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$Start@7de4a01f
14:04:08.919 [HttpClient@47747fb9-96] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
14:04:08.919 [HttpClient@47747fb9-96] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2e0b6ab7 waiting with 0 keys
14:04:08.919 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @1623ms ManagedSelector@5bdaf2ce{STARTED} id=2 keys=0 selected=0 updates=0
14:04:08.919 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting ManagedSelector@19f21b6b{STOPPED} id=3 keys=-1 selected=-1 updates=0
14:04:08.920 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting EatWhatYouKill@42d236fb/SelectorProducer@1ce93c18/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=5,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.92Z
14:04:08.920 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @1624ms EatWhatYouKill@42d236fb/SelectorProducer@1ce93c18/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=5,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.92Z
14:04:08.921 [main] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - queue org.eclipse.jetty.io.ManagedSelector$$Lambda$24/455501890@2bfeb1ef
14:04:08.921 [HttpClient@47747fb9-97] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.io.ManagedSelector$$Lambda$24/455501890@2bfeb1ef
14:04:08.921 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$Start@778ca8ef on ManagedSelector@19f21b6b{STARTING} id=3 keys=0 selected=0 updates=0
14:04:08.921 [HttpClient@47747fb9-97] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@42d236fb/SelectorProducer@1ce93c18/IDLE/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=4,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:08.921Z tryProduce false
14:04:08.921 [HttpClient@47747fb9-97] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
14:04:08.921 [HttpClient@47747fb9-97] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$Start@778ca8ef
14:04:08.921 [HttpClient@47747fb9-97] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
14:04:08.921 [HttpClient@47747fb9-97] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@eab0535 waiting with 0 keys
14:04:08.921 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @1626ms ManagedSelector@19f21b6b{STARTED} id=3 keys=0 selected=0 updates=0
14:04:08.921 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @1626ms ClientSelectorManager@7a356a0d{STARTED}
14:04:08.921 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @1626ms HttpClientTransportOverHTTP@ec2bf82{STARTED}
14:04:08.922 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @1626ms HttpClient@47747fb9{STARTED}
14:04:08.938 [main] INFO graphql.servlet.AbstractGraphQLHttpServlet - Schema generation sucessfull
14:04:08.945 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - HttpClient@47747fb9{STARTED} added {HttpDestination[http://localhost:8181]@65f00478,queue=0,pool=null,MANAGED}
14:04:08.945 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - starting HttpDestination[http://localhost:8181]@65f00478,queue=0,pool=null
14:04:08.947 [main] DEBUG org.eclipse.jetty.util.component.ContainerLifeCycle - HttpDestination[http://localhost:8181]@65f00478,queue=0,pool=DuplexConnectionPool@7d151a[c=0/64,a=0,i=0] added {DuplexConnectionPool@7d151a[c=0/64,a=0,i=0],POJO}
14:04:08.947 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STARTED @1651ms HttpDestination[http://localhost:8181]@65f00478,queue=0,pool=DuplexConnectionPool@7d151a[c=0/64,a=0,i=0]
14:04:08.947 [main] DEBUG org.eclipse.jetty.client.HttpClient - Created HttpDestination[http://localhost:8181]@65f00478,queue=0,pool=DuplexConnectionPool@7d151a[c=0/64,a=0,i=0]
14:04:08.949 [main] DEBUG org.eclipse.jetty.client.HttpDestination - Queued HttpRequest[POST /graphql HTTP/1.1]@4248ed58 for HttpDestination[http://localhost:8181]@65f00478,queue=1,pool=DuplexConnectionPool@7d151a[c=0/64,a=0,i=0]
14:04:08.949 [main] DEBUG org.eclipse.jetty.client.AbstractConnectionPool - tryCreate 0/64 connections 0/-1 pending
14:04:08.949 [main] DEBUG org.eclipse.jetty.client.AbstractConnectionPool - newConnection 1/64 connections 1/-1 pending
14:04:08.951 [main] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - queue org.eclipse.jetty.util.SocketAddressResolver$Async$$Lambda$128/438228197@1efdcd5
14:04:08.951 [HttpClient@47747fb9-98] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.SocketAddressResolver$Async$$Lambda$128/438228197@1efdcd5
14:04:08.952 [HttpClient@47747fb9-98] DEBUG org.eclipse.jetty.util.SocketAddressResolver - Resolved localhost in 0 ms
14:04:08.963 [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@58fc688e on ManagedSelector@3224a577{STARTED} id=1 keys=0 selected=0 updates=0
14:04:08.963 [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@3224a577{STARTED} id=1 keys=0 selected=0 updates=1
14:04:08.963 [HttpClient@47747fb9-98] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change Connect@1b2f54b{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]@65f00478,queue=1,pool=DuplexConnectionPool@7d151a[c=1/64,a=0,i=0], http.connection.promise=org.eclipse.jetty.client.HttpClient$1$1@196ec512, client.connector=HttpClient@47747fb9{STARTED}}} on ManagedSelector@1761de10{STARTED} id=1 keys=0 selected=0 updates=0
14:04:08.963 [qtp352598575-40] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5c45e47b woken with none selected
14:04:08.963 [HttpClient@47747fb9-98] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@1761de10{STARTED} id=1 keys=0 selected=0 updates=1
14:04:08.963 [qtp352598575-40] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5c45e47b woken up from select, 0/0/0 selected
14:04:08.963 [HttpClient@47747fb9-98] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.SocketAddressResolver$Async$$Lambda$128/438228197@1efdcd5
14:04:08.963 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@50d5d41d woken with none selected
14:04:08.963 [qtp352598575-40] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5c45e47b processing 0 keys, 1 updates
14:04:08.963 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@50d5d41d woken up from select, 0/0/0 selected
14:04:08.963 [qtp352598575-40] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
14:04:08.963 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@50d5d41d processing 0 keys, 1 updates
14:04:08.963 [qtp352598575-40] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$Accept@58fc688e
14:04:08.963 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
14:04:08.963 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - update Connect@1b2f54b{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]@65f00478,queue=1,pool=DuplexConnectionPool@7d151a[c=1/64,a=0,i=0], http.connection.promise=org.eclipse.jetty.client.HttpClient$1$1@196ec512, client.connector=HttpClient@47747fb9{STARTED}}}
14:04:08.963 [qtp352598575-40] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - queue org.eclipse.jetty.io.ManagedSelector$Accept@58fc688e
14:04:08.963 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
14:04:08.963 [qtp352598575-40] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
14:04:08.963 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@50d5d41d waiting with 1 keys
14:04:08.963 [qtp352598575-44] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.io.ManagedSelector$Accept@58fc688e
14:04:08.964 [qtp352598575-40] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5c45e47b waiting with 1 keys
14:04:08.964 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@50d5d41d woken up from select, 1/1/1 selected
14:04:08.964 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@50d5d41d processing 1 keys, 0 updates
14:04:08.964 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - selected 8 sun.nio.ch.SelectionKeyImpl@5e123077 Connect@1b2f54b{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]@65f00478,queue=1,pool=DuplexConnectionPool@7d151a[c=1/64,a=0,i=0], http.connection.promise=org.eclipse.jetty.client.HttpClient$1$1@196ec512, client.connector=HttpClient@47747fb9{STARTED}}}
14:04:08.964 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Connected true java.nio.channels.SocketChannel[connected local=/127.0.0.1:43966 remote=localhost/127.0.0.1:8181]
14:04:08.964 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - queue CreateEndPoint@5a2dbf6d{Connect@1b2f54b{java.nio.channels.SocketChannel[connected local=/127.0.0.1:43966 remote=localhost/127.0.0.1:8181],{ssl.peer.port=8181, ssl.peer.host=localhost, http.destination=HttpDestination[http://localhost:8181]@65f00478,queue=1,pool=DuplexConnectionPool@7d151a[c=1/64,a=0,i=0], http.connection.promise=org.eclipse.jetty.client.HttpClient$1$1@196ec512, client.connector=HttpClient@47747fb9{STARTED}}},sun.nio.ch.SelectionKeyImpl@5e123077}
14:04:08.964 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 0
14:04:08.964 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
14:04:08.964 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@50d5d41d waiting with 1 keys
14:04:08.965 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run CreateEndPoint@5a2dbf6d{Connect@1b2f54b{java.nio.channels.SocketChannel[connected local=/127.0.0.1:43966 remote=localhost/127.0.0.1:8181],{ssl.peer.port=8181, ssl.peer.host=localhost, http.destination=HttpDestination[http://localhost:8181]@65f00478,queue=1,pool=DuplexConnectionPool@7d151a[c=1/64,a=0,i=0], http.connection.promise=org.eclipse.jetty.client.HttpClient$1$1@196ec512, client.connector=HttpClient@47747fb9{STARTED}}},sun.nio.ch.SelectionKeyImpl@5e123077}
14:04:08.969 [qtp352598575-44] DEBUG org.eclipse.jetty.io.IdleTimeout - SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=4/60000}{io=0/0,kio=0,kro=0}-><null> idle timeout check, elapsed: 4 ms, remaining: 59996 ms
14:04:08.979 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.client.HttpClientTransport - Created HttpConnectionOverHTTP@4cb4a45c::SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=-,flush=-,to=14/0}{io=0/0,kio=0,kro=8}-><null>
14:04:08.980 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.io.AbstractEndPoint - onOpen SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=-,flush=-,to=14/0}{io=0/0,kio=0,kro=8}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=IDLE,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:08.980 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.io.AbstractConnection - onOpen HttpConnectionOverHTTP@4cb4a45c::SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=-,flush=-,to=15/0}{io=0/0,kio=0,kro=8}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=IDLE,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:08.980 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.io.AbstractConnection - fillInterested HttpConnectionOverHTTP@4cb4a45c::SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=-,flush=-,to=15/0}{io=0/0,kio=0,kro=8}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=IDLE,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:08.981 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.io.FillInterest - interested FillInterest@24290e4e{AC.ReadCB@4cb4a45c{HttpConnectionOverHTTP@4cb4a45c::SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=FI,flush=-,to=0/0}{io=0/0,kio=0,kro=8}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=IDLE,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]}}
14:04:08.981 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.io.ChannelEndPoint - changeInterests p=false 0->1 for SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=FI,flush=-,to=1/0}{io=0/1,kio=0,kro=8}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=IDLE,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:08.982 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ChannelEndPoint$1@311f57ff on ManagedSelector@1761de10{STARTED} id=1 keys=1 selected=0 updates=0
14:04:08.982 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@1761de10{STARTED} id=1 keys=1 selected=0 updates=1
14:04:08.982 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@50d5d41d woken with none selected
14:04:08.982 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@50d5d41d woken up from select, 0/0/1 selected
14:04:08.982 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@50d5d41d processing 0 keys, 1 updates
14:04:08.982 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
14:04:08.982 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ChannelEndPoint$1@311f57ff
14:04:08.982 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.client.AbstractConnectionPool - Connection 1/64 creation succeeded HttpConnectionOverHTTP@4cb4a45c::SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=FI,flush=-,to=2/0}{io=0/1,kio=0,kro=8}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=IDLE,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:08.982 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 0 -> 1 on SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=FI,flush=-,to=2/0}{io=1/1,kio=1,kro=8}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=IDLE,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:08.982 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
14:04:08.982 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.client.AbstractConnectionPool - Connection idle HttpConnectionOverHTTP@4cb4a45c::SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=FI,flush=-,to=2/0}{io=1/1,kio=1,kro=8}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=IDLE,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:08.982 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@50d5d41d waiting with 1 keys
14:04:08.983 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.client.AbstractConnectionPool - Connection active HttpConnectionOverHTTP@4cb4a45c::SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=FI,flush=-,to=2/0}{io=1/1,kio=1,kro=8}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=IDLE,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:08.983 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.client.HttpDestination - Processing exchange HttpExchange@7d9a8308 req=PENDING/null@null res=PENDING/null@null on HttpConnectionOverHTTP@4cb4a45c::SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=FI,flush=-,to=3/0}{io=1/1,kio=1,kro=8}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=IDLE,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] of HttpDestination[http://localhost:8181]@65f00478,queue=0,pool=DuplexConnectionPool@7d151a[c=1/64,a=1,i=0]
14:04:08.984 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.client.HttpChannel - HttpExchange@7d9a8308 req=PENDING/null@null res=PENDING/null@null associated true to HttpChannelOverHTTP@5a163034(exchange=HttpExchange@7d9a8308 req=PENDING/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=IDLE,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:08.984 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.client.HttpSender - Request begin HttpRequest[POST /graphql HTTP/1.1]@4248ed58
14:04:08.985 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.client.HttpSender - Request headers HttpRequest[POST /graphql HTTP/1.1]@4248ed58
Accept-Encoding: gzip
User-Agent: Jetty/9.4.12.v20180830
Content-Type: application/json
Host: localhost:8181
Content-Length: 543
14:04:08.987 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.client.HttpContent - Advanced content to last chunk java.nio.HeapByteBuffer[pos=0 lim=543 cap=543]
14:04:08.988 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.client.HttpSender - Generated headers (-1 bytes), chunk (-1 bytes), content (543 bytes) - NEED_HEADER/HttpGenerator@7357f3df{s=START}
14:04:08.991 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.http.HttpGenerator - generateHeaders POST{u=/graphql,HTTP/1.1,h=5,cl=543} last=true content=HeapByteBuffer@2f6f3624[p=0,l=543,c=543,r=543]={<<<{\r\n "query": "qu...\n }]\r\n }\r\n}>>>}
14:04:08.991 [HttpClient@47747fb9-99] 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: 543
14:04:08.991 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.http.HttpGenerator - CONTENT_LENGTH
14:04:08.991 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.client.HttpSender - Generated headers (160 bytes), chunk (-1 bytes), content (543 bytes) - FLUSH/HttpGenerator@7357f3df{s=COMPLETING}
14:04:08.991 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@3a9fee16{IDLE}->null [HeapByteBuffer@4e7da331[p=0,l=160,c=4096,r=160]={<<<POST /graphql HTT...Length: 543\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@2f6f3624[p=0,l=543,c=543,r=543]={<<<{\r\n "query": "qu...\n }]\r\n }\r\n}>>>}]
14:04:08.991 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@3a9fee16{WRITING}->null:IDLE-->WRITING
14:04:08.992 [qtp352598575-44] DEBUG org.eclipse.jetty.server.HttpChannel - new HttpChannelOverHttp@31c4399f{r=0,c=false,a=IDLE,uri=null,age=0} -> SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=27/60000}{io=0/0,kio=0,kro=0}-><null>,null,HttpChannelState@484a903f{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false}
14:04:08.992 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.io.ChannelEndPoint - flushed 703 SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=FI,flush=W,to=12/0}{io=1/1,kio=1,kro=8}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=HttpExchange@7d9a8308 req=PENDING/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@18d1be5a(req=HEADERS,snd=SENDING,failure=null)[HttpGenerator@7357f3df{s=COMPLETING}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:08.992 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=703 remaining=0 WriteFlusher@3a9fee16{WRITING}->null
14:04:08.992 [qtp352598575-44] DEBUG org.eclipse.jetty.server.HttpConnection - New HTTP Connection HttpConnection@7ce686cc::SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=27/60000}{io=0/0,kio=0,kro=0}-><null>
14:04:08.992 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@3a9fee16{IDLE}->null:WRITING-->IDLE
14:04:08.993 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.client.HttpSender - Generated headers (-1 bytes), chunk (-1 bytes), content (-1 bytes) - DONE/HttpGenerator@7357f3df{s=END}
14:04:08.993 [qtp352598575-44] DEBUG org.eclipse.jetty.io.AbstractEndPoint - onOpen SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=27/60000}{io=0/0,kio=0,kro=0}->HttpConnection@7ce686cc[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1b7e8b07{s=START}]=>HttpChannelOverHttp@31c4399f{r=0,c=false,a=IDLE,uri=null,age=0}
14:04:08.993 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.client.HttpSender - Request committed HttpRequest[POST /graphql HTTP/1.1]@4248ed58
14:04:08.993 [qtp352598575-44] DEBUG org.eclipse.jetty.io.AbstractConnection - onOpen HttpConnection@7ce686cc::SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=28/60000}{io=0/0,kio=0,kro=0}->HttpConnection@7ce686cc[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1b7e8b07{s=START}]=>HttpChannelOverHttp@31c4399f{r=0,c=false,a=IDLE,uri=null,age=0}
14:04:08.993 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.client.HttpSender - Request content HttpRequest[POST /graphql HTTP/1.1]@4248ed58
HeapByteBuffer@6c973177[p=0,l=543,c=543,r=543]={<<<{\r\n "query": "qu...\n }]\r\n }\r\n}>>>}
14:04:08.993 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.client.HttpContent - Advanced content past last chunk
14:04:08.993 [qtp352598575-44] DEBUG org.eclipse.jetty.io.AbstractConnection - fillInterested HttpConnection@7ce686cc::SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=28/60000}{io=0/0,kio=0,kro=0}->HttpConnection@7ce686cc[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1b7e8b07{s=START}]=>HttpChannelOverHttp@31c4399f{r=0,c=false,a=IDLE,uri=null,age=0}
14:04:08.993 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.client.HttpSender - Content present false, last true, consumed true for HttpRequest[POST /graphql HTTP/1.1]@4248ed58
14:04:08.993 [qtp352598575-44] DEBUG org.eclipse.jetty.io.FillInterest - interested FillInterest@be64136{AC.ReadCB@7ce686cc{HttpConnection@7ce686cc::SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=FI,flush=-,to=0/60000}{io=0/0,kio=0,kro=0}->HttpConnection@7ce686cc[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1b7e8b07{s=START}]=>HttpChannelOverHttp@31c4399f{r=0,c=false,a=IDLE,uri=null,age=0}}}
14:04:08.993 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.client.HttpSender - Generated content (0 bytes) - DONE/HttpGenerator@7357f3df{s=END}
14:04:08.993 [qtp352598575-44] DEBUG org.eclipse.jetty.io.ChannelEndPoint - changeInterests p=false 0->1 for SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=FI,flush=-,to=0/60000}{io=0/1,kio=0,kro=0}->HttpConnection@7ce686cc[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1b7e8b07{s=START}]=>HttpChannelOverHttp@31c4399f{r=0,c=false,a=IDLE,uri=null,age=0}
14:04:08.993 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.client.HttpSender - Request success HttpRequest[POST /graphql HTTP/1.1]@4248ed58
14:04:08.993 [qtp352598575-44] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ChannelEndPoint$1@1731f31d on ManagedSelector@3224a577{STARTED} id=1 keys=1 selected=0 updates=0
14:04:08.993 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.client.HttpExchange - Terminated request for HttpExchange@7d9a8308 req=TERMINATED/null@null res=PENDING/null@null, result: null
14:04:08.993 [qtp352598575-44] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@3224a577{STARTED} id=1 keys=1 selected=0 updates=1
14:04:08.993 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.client.HttpSender - Terminating request HttpRequest[POST /graphql HTTP/1.1]@4248ed58
14:04:08.994 [qtp352598575-40] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5c45e47b woken with none selected
14:04:08.994 [qtp352598575-40] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5c45e47b woken up from select, 0/0/1 selected
14:04:08.994 [qtp352598575-40] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5c45e47b processing 0 keys, 1 updates
14:04:08.994 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.io.ManagedSelector - Created SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=FI,flush=-,to=2/0}{io=1/1,kio=1,kro=8}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=HttpExchange@7d9a8308 req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:08.994 [qtp352598575-40] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
14:04:08.994 [qtp352598575-40] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ChannelEndPoint$1@1731f31d
14:04:08.994 [HttpClient@47747fb9-99] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran CreateEndPoint@5a2dbf6d{Connect@1b2f54b{java.nio.channels.SocketChannel[connected local=/127.0.0.1:43966 remote=localhost/127.0.0.1:8181],{ssl.peer.port=8181, ssl.peer.host=localhost, http.destination=HttpDestination[http://localhost:8181]@65f00478,queue=0,pool=DuplexConnectionPool@7d151a[c=1/64,a=1,i=0], http.connection.promise=org.eclipse.jetty.client.HttpClient$1$1@196ec512, client.connector=HttpClient@47747fb9{STARTED}}},sun.nio.ch.SelectionKeyImpl@5e123077}
14:04:08.994 [qtp352598575-40] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 0 -> 1 on SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=FI,flush=-,to=1/60000}{io=1/1,kio=1,kro=0}->HttpConnection@7ce686cc[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1b7e8b07{s
...[truncated 14595 chars]...
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:09.016 [qtp352598575-46] DEBUG org.eclipse.jetty.http.HttpParser - CONTENT --> END
14:04:09.016 [qtp352598575-46] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@31c4399f{r=1,c=false,a=DISPATCHED,uri=//localhost:8181/graphql,age=13} onContentComplete
14:04:09.016 [qtp352598575-46] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@31c4399f{r=1,c=false,a=DISPATCHED,uri=//localhost:8181/graphql,age=13} onRequestComplete
14:04:09.016 [qtp352598575-46] DEBUG org.eclipse.jetty.server.HttpInput - HttpInputOverHTTP@26447a39[c=0,q=1,[0]=EOF,s=STREAM] addContent EOF
14:04:09.016 [qtp352598575-46] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@7ce686cc::SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=17/60000}{io=0/0,kio=0,kro=1}->HttpConnection@7ce686cc[p=HttpParser{s=END,543 of 543},g=HttpGenerator@1b7e8b07{s=START}]=>HttpChannelOverHttp@31c4399f{r=1,c=false,a=DISPATCHED,uri=//localhost:8181/graphql,age=13} parsed false HttpParser{s=END,543 of 543}
14:04:09.017 [qtp352598575-46] DEBUG org.eclipse.jetty.server.HttpInput - HttpInputOverHTTP@26447a39[c=543,q=1,[0]=EOF,s=STREAM] read 543 from Content@67acde4e{HeapByteBufferR@3aebc8ad[p=703,l=703,c=8192,r=0]={POST /graphql HTT...\n }]\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:09.017 [qtp352598575-46] DEBUG org.eclipse.jetty.server.HttpConnection - releaseRequestBuffer HttpConnection@7ce686cc::SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=18/60000}{io=0/0,kio=0,kro=1}->HttpConnection@7ce686cc[p=HttpParser{s=END,543 of 543},g=HttpGenerator@1b7e8b07{s=START}]=>HttpChannelOverHttp@31c4399f{r=1,c=false,a=DISPATCHED,uri=//localhost:8181/graphql,age=14}
14:04:09.203 [qtp352598575-46] DEBUG graphql.GraphQL - Executing request. operation name: 'null'. query: 'query _($prods : [ProductInput]!){
ListVarService{
testVariables(prods : $prods)
}
}'. variables '{prods=[{id=test, name=name, description=desc, price=12, active=true}, {id=test, name=name, description=desc, price=12, active=true}, {id=test, name=name, description=desc, price=12, active=true}]}'
14:04:09.206 [qtp352598575-46] DEBUG graphql.GraphQL - Parsing query: 'query _($prods : [ProductInput]!){
ListVarService{
testVariables(prods : $prods)
}
}'...
14:04:09.270 [qtp352598575-46] DEBUG graphql.GraphQL - Validating query: 'query _($prods : [ProductInput]!){
ListVarService{
testVariables(prods : $prods)
}
}'
14:04:09.281 [qtp352598575-46] DEBUG graphql.GraphQL - Executing '4beed6ae-b8e3-4a5a-903c-44a8e1d711a5'. operation name: 'null'. query: 'query _($prods : [ProductInput]!){
ListVarService{
testVariables(prods : $prods)
}
}'. variables '{prods=[{id=test, name=name, description=desc, price=12, active=true}, {id=test, name=name, description=desc, price=12, active=true}, {id=test, name=name, description=desc, price=12, active=true}]}'
14:04:09.287 [qtp352598575-46] DEBUG graphql.execution.Execution - Executing '4beed6ae-b8e3-4a5a-903c-44a8e1d711a5' query operation: 'QUERY' using 'de.dim.whiteboard.graphql.emf.executation.EMFAsyncExecutionStrategy' execution strategy
14:04:09.292 [qtp352598575-46] DEBUG graphql.execution.ExecutionStrategy - '4beed6ae-b8e3-4a5a-903c-44a8e1d711a5' fetching field '/ListVarService' using data fetcher 'graphql.schema.StaticDataFetcher'...
14:04:09.292 [qtp352598575-46] DEBUG graphql.execution.ExecutionStrategy - '4beed6ae-b8e3-4a5a-903c-44a8e1d711a5' field '/ListVarService' fetch returned 'org.apache.felix.framework.BundleContextImpl$ServiceObjectsImpl'
14:04:09.298 [qtp352598575-46] DEBUG graphql.execution.ExecutionStrategy - '4beed6ae-b8e3-4a5a-903c-44a8e1d711a5' completing field '/ListVarService'...
14:04:09.308 [qtp352598575-46] ERROR graphql.GraphQL - Execution '4beed6ae-b8e3-4a5a-903c-44a8e1d711a5' threw exception when executing : query : 'query _($prods : [ProductInput]!){
ListVarService{
testVariables(prods : $prods)
}
}'. variables '{prods=[{id=test, name=name, description=desc, price=12, active=true}, {id=test, name=name, description=desc, price=12, active=true}, {id=test, name=name, description=desc, price=12, 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.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.lambda$coerceValueAst$0(EMFValuesResolver.java:280)
at java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:193)
at java.util.ArrayList$ArrayListSpliterator.forEachRemaining(ArrayList.java:1374)
at java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:481)
at java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:471)
at java.util.stream.ReduceOps$ReduceOp.evaluateSequential(ReduceOps.java:708)
at java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234)
at java.util.stream.ReferencePipeline.collect(ReferencePipeline.java:499)
at de.dim.whiteboard.graphql.emf.executation.EMFValuesResolver.coerceValueAst(EMFValuesResolver.java:280)
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)
... 47 common frames omitted
14:04:09.309 [qtp352598575-46] 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.lambda$coerceValueAst$0(EMFValuesResolver.java:280)
at java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:193)
at java.util.ArrayList$ArrayListSpliterator.forEachRemaining(ArrayList.java:1374)
at java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:481)
at java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:471)
at java.util.stream.ReduceOps$ReduceOp.evaluateSequential(ReduceOps.java:708)
at java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234)
at java.util.stream.ReferencePipeline.collect(ReferencePipeline.java:499)
at de.dim.whiteboard.graphql.emf.executation.EMFValuesResolver.coerceValueAst(EMFValuesResolver.java:280)
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.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:09.311 [qtp352598575-46] DEBUG org.eclipse.jetty.server.session - FinalSession=null old_session_manager=null this=org.eclipse.jetty.server.session.SessionHandler361268035==dftMaxIdleSec=0
14:04:09.311 [qtp352598575-46] DEBUG org.eclipse.jetty.server.Server - handled=true async=false committed=false on HttpChannelOverHttp@31c4399f{r=1,c=false,a=DISPATCHED,uri=//localhost:8181/graphql,age=308}
14:04:09.311 [qtp352598575-46] DEBUG org.eclipse.jetty.server.HttpChannelState - unhandle HttpChannelState@484a903f{s=DISPATCHED a=NOT_ASYNC i=true r=IDLE w=false}
14:04:09.311 [qtp352598575-46] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@31c4399f{r=1,c=false,a=COMPLETING,uri=//localhost:8181/graphql,age=308} action COMPLETE
14:04:09.312 [qtp352598575-46] 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@335df55c{null}
14:04:09.312 [qtp352598575-46] DEBUG org.eclipse.jetty.server.HttpChannel - COMMIT for /graphql on HttpChannelOverHttp@31c4399f{r=1,c=true,a=COMPLETING,uri=//localhost:8181/graphql,age=309}
400 null HTTP/1.1
Date: Wed, 10 Apr 2019 14:04:09 GMT
Access-Control-Allow-Origin: *
Access-Control-Allow-Headers: *
Access-Control-Request-Method: *
14:04:09.313 [qtp352598575-46] DEBUG org.eclipse.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@4fd13984[PROCESSING][i=HTTP/1.1{s=400,h=4,cl=-1},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@5110fa4b] generate: NEED_HEADER (null,[p=0,l=0,c=0,r=0],true)@START
14:04:09.313 [qtp352598575-46] 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:09.313 [qtp352598575-46] DEBUG org.eclipse.jetty.http.HttpGenerator - Date: Wed, 10 Apr 2019 14:04:09 GMT
Access-Control-Allow-Origin: *
Access-Control-Allow-Headers: *
Access-Control-Request-Method: *
14:04:09.314 [qtp352598575-46] DEBUG org.eclipse.jetty.http.HttpGenerator - CONTENT_LENGTH
14:04:09.314 [qtp352598575-46] DEBUG org.eclipse.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@4fd13984[PROCESSING][i=HTTP/1.1{s=400,h=4,cl=-1},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@5110fa4b] generate: FLUSH ([p=0,l=183,c=16384,r=183],[p=0,l=0,c=0,r=0],true)@COMPLETING
14:04:09.314 [qtp352598575-46] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@64b2bd70{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:09.314 [qtp352598575-46] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@64b2bd70{WRITING}->null:IDLE-->WRITING
14:04:09.314 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@50d5d41d woken up from select, 1/1/1 selected
14:04:09.314 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@50d5d41d processing 1 keys, 0 updates
14:04:09.314 [qtp352598575-46] DEBUG org.eclipse.jetty.io.ChannelEndPoint - flushed 183 SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=-,flush=W,to=315/60000}{io=0/0,kio=0,kro=1}->HttpConnection@7ce686cc[p=HttpParser{s=END,543 of 543},g=HttpGenerator@1b7e8b07{s=COMPLETING}]=>HttpChannelOverHttp@31c4399f{r=1,c=true,a=COMPLETING,uri=//localhost:8181/graphql,age=311}
14:04:09.314 [qtp352598575-46] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=183 remaining=0 WriteFlusher@64b2bd70{WRITING}->null
14:04:09.314 [qtp352598575-46] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@64b2bd70{IDLE}->null:WRITING-->IDLE
14:04:09.314 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - selected 1 sun.nio.ch.SelectionKeyImpl@5e123077 SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=FI,flush=-,to=322/0}{io=1/1,kio=1,kro=1}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=HttpExchange@7d9a8308 req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:09.314 [qtp352598575-46] DEBUG org.eclipse.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@4fd13984[PROCESSING][i=HTTP/1.1{s=400,h=4,cl=-1},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@5110fa4b] generate: DONE ([p=183,l=183,c=16384,r=0],[p=0,l=0,c=0,r=0],true)@END
14:04:09.315 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ChannelEndPoint - onSelected 1->0 r=true w=false for SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=FI,flush=-,to=322/0}{io=1/0,kio=1,kro=1}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=HttpExchange@7d9a8308 req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:09.315 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ChannelEndPoint - task CEP:SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=FI,flush=-,to=323/0}{io=1/0,kio=1,kro=1}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=HttpExchange@7d9a8308 req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]:runFillable:BLOCKING
14:04:09.315 [qtp352598575-46] DEBUG org.eclipse.jetty.server.HttpChannelState - onComplete HttpChannelState@484a903f{s=COMPLETING a=NOT_ASYNC i=false r=IDLE w=false}
14:04:09.315 [qtp352598575-46] DEBUG org.eclipse.jetty.server.HttpChannel - COMPLETE for /graphql written=0
14:04:09.315 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@13e9f2e2{s=0/8,p=0} tryExecute EatWhatYouKill@238ad8c/SelectorProducer@430fa4ef/PRODUCING/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=4,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:09.315Z
14:04:09.315 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@13e9f2e2{s=0/8,p=1} startReservedThread p=1
14:04:09.316 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - queue ReservedThreadExecutor@13e9f2e2{s=0/8,p=1}@1f2f3a4c
14:04:09.316 [HttpClient@47747fb9-100] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run ReservedThreadExecutor@13e9f2e2{s=0/8,p=1}@1f2f3a4c
14:04:09.316 [HttpClient@47747fb9-100] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@13e9f2e2{s=1/8,p=1}@1f2f3a4c started
14:04:09.316 [HttpClient@47747fb9-100] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@13e9f2e2{s=1/8,p=0}@1f2f3a4c waiting
14:04:09.316 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@238ad8c/SelectorProducer@430fa4ef/PRODUCING/p=false/QueuedThreadPool[HttpClient@47747fb9]@70eecdc2{STARTED,8<=8<=200,i=3,q=0}[ReservedThreadExecutor@13e9f2e2{s=0/8,p=1}][pc=0,pic=0,pec=0,epc=0]@2019-04-10T14:04:09.316Z m=PRODUCE_EXECUTE_CONSUME t=CEP:SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=FI,flush=-,to=324/0}{io=1/0,kio=1,kro=1}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=HttpExchange@7d9a8308 req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]:runFillable:BLOCKING/BLOCKING
14:04:09.316 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - queue CEP:SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=FI,flush=-,to=324/0}{io=1/0,kio=1,kro=1}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=HttpExchange@7d9a8308 req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]:runFillable:BLOCKING
14:04:09.317 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 0
14:04:09.317 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
14:04:09.317 [qtp352598575-46] DEBUG org.eclipse.jetty.server.HttpChannelState - recycle HttpChannelState@484a903f{s=COMPLETED a=NOT_ASYNC i=false r=IDLE w=false}
14:04:09.317 [qtp352598575-46] DEBUG org.eclipse.jetty.http.HttpParser - reset HttpParser{s=END,543 of 543}
14:04:09.317 [qtp352598575-46] DEBUG org.eclipse.jetty.http.HttpParser - END --> START
14:04:09.317 [qtp352598575-46] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@31c4399f{r=1,c=false,a=IDLE,uri=null,age=0} handle exit, result COMPLETE
14:04:09.317 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 1 -> 0 on SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=FI,flush=-,to=325/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=HttpExchange@7d9a8308 req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:09.317 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@50d5d41d waiting with 1 keys
14:04:09.317 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run CEP:SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=FI,flush=-,to=325/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=HttpExchange@7d9a8308 req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]:runFillable:BLOCKING
14:04:09.317 [qtp352598575-46] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=3/60000}{io=0/0,kio=0,kro=1}->HttpConnection@7ce686cc[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1b7e8b07{s=START}]=>HttpChannelOverHttp@31c4399f{r=1,c=false,a=IDLE,uri=null,age=0}
14:04:09.318 [qtp352598575-46] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=3/60000}{io=0/0,kio=0,kro=1}->HttpConnection@7ce686cc[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1b7e8b07{s=START}]=>HttpChannelOverHttp@31c4399f{r=1,c=false,a=IDLE,uri=null,age=0}
14:04:09.318 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.io.FillInterest - fillable FillInterest@24290e4e{AC.ReadCB@4cb4a45c{HttpConnectionOverHTTP@4cb4a45c::SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=FI,flush=-,to=325/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=HttpExchange@7d9a8308 req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]}}
14:04:09.318 [qtp352598575-46] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@7ce686cc::SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=4/60000}{io=0/0,kio=0,kro=1}->HttpConnection@7ce686cc[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1b7e8b07{s=START}]=>HttpChannelOverHttp@31c4399f{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:09.318 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=START DirectByteBuffer@281140c5[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:09.318 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.client.HttpReceiver - Parsed false, remaining 0 HttpParser{s=START,0 of -1}
14:04:09.318 [qtp352598575-46] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@7ce686cc::SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=4/60000}{io=0/0,kio=0,kro=1}->HttpConnection@7ce686cc[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1b7e8b07{s=START}]=>HttpChannelOverHttp@31c4399f{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:09.318 [qtp352598575-46] 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:09.318 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 183 SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=-,flush=-,to=326/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=HttpExchange@7d9a8308 req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:09.319 [qtp352598575-46] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@7ce686cc::SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=4/60000}{io=0/0,kio=0,kro=1}->HttpConnection@7ce686cc[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1b7e8b07{s=START}]=>HttpChannelOverHttp@31c4399f{r=1,c=false,a=IDLE,uri=null,age=0} parsed false HttpParser{s=START,0 of -1}
14:04:09.319 [qtp352598575-46] DEBUG org.eclipse.jetty.server.HttpConnection - releaseRequestBuffer HttpConnection@7ce686cc::SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=5/60000}{io=0/0,kio=0,kro=1}->HttpConnection@7ce686cc[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1b7e8b07{s=START}]=>HttpChannelOverHttp@31c4399f{r=1,c=false,a=IDLE,uri=null,age=0}
14:04:09.319 [qtp352598575-46] DEBUG org.eclipse.jetty.io.AbstractConnection - fillInterested HttpConnection@7ce686cc::SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=-,flush=-,to=5/60000}{io=0/0,kio=0,kro=1}->HttpConnection@7ce686cc[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1b7e8b07{s=START}]=>HttpChannelOverHttp@31c4399f{r=1,c=false,a=IDLE,uri=null,age=0}
14:04:09.319 [qtp352598575-46] DEBUG org.eclipse.jetty.io.FillInterest - interested FillInterest@be64136{AC.ReadCB@7ce686cc{HttpConnection@7ce686cc::SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=FI,flush=-,to=0/60000}{io=0/0,kio=0,kro=1}->HttpConnection@7ce686cc[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1b7e8b07{s=START}]=>HttpChannelOverHttp@31c4399f{r=1,c=false,a=IDLE,uri=null,age=0}}}
14:04:09.320 [qtp352598575-46] DEBUG org.eclipse.jetty.io.ChannelEndPoint - changeInterests p=false 0->1 for SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=FI,flush=-,to=0/60000}{io=0/1,kio=0,kro=1}->HttpConnection@7ce686cc[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1b7e8b07{s=START}]=>HttpChannelOverHttp@31c4399f{r=1,c=false,a=IDLE,uri=null,age=0}
14:04:09.320 [qtp352598575-46] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ChannelEndPoint$1@1731f31d on ManagedSelector@3224a577{STARTED} id=1 keys=1 selected=0 updates=0
14:04:09.320 [qtp352598575-46] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@3224a577{STARTED} id=1 keys=1 selected=0 updates=1
14:04:09.320 [qtp352598575-40] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5c45e47b woken with none selected
14:04:09.320 [qtp352598575-40] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5c45e47b woken up from select, 0/0/1 selected
14:04:09.320 [qtp352598575-40] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5c45e47b processing 0 keys, 1 updates
14:04:09.320 [qtp352598575-40] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
14:04:09.320 [qtp352598575-40] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ChannelEndPoint$1@1731f31d
14:04:09.320 [qtp352598575-46] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@7ce686cc::SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=FI,flush=-,to=1/60000}{io=0/1,kio=0,kro=1}->HttpConnection@7ce686cc[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1b7e8b07{s=START}]=>HttpChannelOverHttp@31c4399f{r=1,c=false,a=IDLE,uri=null,age=0} onFillable exit HttpChannelState@484a903f{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false} null
14:04:09.320 [qtp352598575-40] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 0 -> 1 on SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=FI,flush=-,to=1/60000}{io=1/1,kio=1,kro=1}->HttpConnection@7ce686cc[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1b7e8b07{s=START}]=>HttpChannelOverHttp@31c4399f{r=1,c=false,a=IDLE,uri=null,age=0}
14:04:09.320 [qtp352598575-46] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran CEP:SocketChannelEndPoint@79cca6d4{/127.0.0.1:43966<->/127.0.0.1:8181,OPEN,fill=FI,flush=-,to=1/60000}{io=1/1,kio=1,kro=1}->HttpConnection@7ce686cc[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1b7e8b07{s=START}]=>HttpChannelOverHttp@31c4399f{r=1,c=false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING
14:04:09.319 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.client.HttpReceiver - Read 183 bytes DirectByteBuffer@281140c5[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@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=-,flush=-,to=0/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=HttpExchange@7d9a8308 req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:09.323 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=START DirectByteBuffer@281140c5[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:09.323 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - START --> RESPONSE_VERSION
14:04:09.323 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - RESPONSE_VERSION --> SPACE1
14:04:09.323 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - SPACE1 --> STATUS
14:04:09.323 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - STATUS --> SPACE2
14:04:09.323 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - SPACE2 --> REASON
14:04:09.323 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - REASON --> HEADER
14:04:09.323 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.client.HttpReceiver - Response begin HttpResponse[HTTP/1.1 400 Bad Request]@26d6bbb7
14:04:09.324 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Date --> VALUE
14:04:09.324 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Date --> IN_VALUE
14:04:09.324 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Date --> FIELD
14:04:09.324 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - HEADER: --> IN_NAME
14:04:09.324 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Access-Control-Allow-Origin --> VALUE
14:04:09.324 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Access-Control-Allow-Origin --> IN_VALUE
14:04:09.324 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Access-Control-Allow-Origin --> FIELD
14:04:09.324 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - HEADER: --> IN_NAME
14:04:09.324 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Access-Control-Allow-Headers --> VALUE
14:04:09.324 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Access-Control-Allow-Headers --> IN_VALUE
14:04:09.324 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Access-Control-Allow-Headers --> FIELD
14:04:09.325 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - HEADER: --> IN_NAME
14:04:09.325 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Access-Control-Request-Method --> VALUE
14:04:09.325 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Access-Control-Request-Method --> IN_VALUE
14:04:09.325 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Access-Control-Request-Method --> FIELD
14:04:09.325 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Content-Length: 0 --> IN_VALUE
14:04:09.325 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Content-Length: 0 --> FIELD
14:04:09.325 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - HEADER --> END
14:04:09.325 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.client.HttpReceiver - Response headers HttpResponse[HTTP/1.1 400 Bad Request]@26d6bbb7
Date: Wed, 10 Apr 2019 14:04:09 GMT
Access-Control-Allow-Origin: *
Access-Control-Allow-Headers: *
Access-Control-Request-Method: *
Content-Length: 0
14:04:09.320 [qtp352598575-40] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
14:04:09.325 [qtp352598575-40] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5c45e47b waiting with 1 keys
14:04:09.327 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - reset HttpParser{s=END,0 of 0}
14:04:09.327 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - END --> START
14:04:09.327 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.client.HttpReceiver - Response success HttpResponse[HTTP/1.1 400 Bad Request]@26d6bbb7
14:04:09.328 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.client.HttpExchange - Terminated response for HttpExchange@7d9a8308 req=TERMINATED/null@null res=TERMINATED/null@null, result: Result[HttpRequest[POST /graphql HTTP/1.1]@4248ed58 > HttpResponse[HTTP/1.1 400 Bad Request]@26d6bbb7] null
14:04:09.328 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.client.HttpReceiver - Response complete HttpResponse[HTTP/1.1 400 Bad Request]@26d6bbb7
14:04:09.328 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.client.HttpChannel - HttpExchange@7d9a8308 req=TERMINATED/null@null res=TERMINATED/null@null disassociated true from HttpChannelOverHTTP@5a163034(exchange=null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:09.329 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.client.HttpDestination - Released HttpConnectionOverHTTP@4cb4a45c::SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=-,flush=-,to=9/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:09.329 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.client.AbstractConnectionPool - Connection idle HttpConnectionOverHTTP@4cb4a45c::SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=-,flush=-,to=10/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:09.329 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.client.HttpReceiver - Request/Response succeeded: Result[HttpRequest[POST /graphql HTTP/1.1]@4248ed58 > HttpResponse[HTTP/1.1 400 Bad Request]@26d6bbb7] null
14:04:09.330 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.client.HttpReceiver - Parsed false, remaining 0 HttpParser{s=START,0 of -1}
14:04:09.330 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=START DirectByteBuffer@281140c5[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:09.330 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.client.HttpReceiver - Parsed false, remaining 0 HttpParser{s=START,0 of -1}
14:04:09.330 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=-,flush=-,to=11/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:09.331 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.client.HttpReceiver - Read 0 bytes DirectByteBuffer@281140c5[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@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=-,flush=-,to=12/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:09.331 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.io.AbstractConnection - fillInterested HttpConnectionOverHTTP@4cb4a45c::SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=-,flush=-,to=12/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:09.332 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.io.FillInterest - interested FillInterest@24290e4e{AC.ReadCB@4cb4a45c{HttpConnectionOverHTTP@4cb4a45c::SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=FI,flush=-,to=0/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]}}
14:04:09.332 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.io.ChannelEndPoint - changeInterests p=false 0->1 for SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=FI,flush=-,to=1/0}{io=0/1,kio=0,kro=1}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:09.332 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ChannelEndPoint$1@311f57ff on ManagedSelector@1761de10{STARTED} id=1 keys=1 selected=0 updates=0
14:04:09.332 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@1761de10{STARTED} id=1 keys=1 selected=0 updates=1
14:04:09.332 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@50d5d41d woken with none selected
14:04:09.332 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@50d5d41d woken up from select, 0/0/1 selected
14:04:09.332 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@50d5d41d processing 0 keys, 1 updates
14:04:09.332 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
14:04:09.332 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ChannelEndPoint$1@311f57ff
14:04:09.333 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 0 -> 1 on SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=FI,flush=-,to=1/0}{io=1/1,kio=1,kro=1}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
14:04:09.333 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
14:04:09.333 [HttpClient@47747fb9-95] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@50d5d41d waiting with 1 keys
14:04:09.333 [HttpClient@47747fb9-101] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran CEP:SocketChannelEndPoint@1c755415{localhost/127.0.0.1:8181<->/127.0.0.1:43966,OPEN,fill=FI,flush=-,to=2/0}{io=1/1,kio=1,kro=1}->HttpConnectionOverHTTP@4cb4a45c(l:/127.0.0.1:43966 <-> r:localhost/127.0.0.1:8181,closed=false)=>HttpChannelOverHTTP@5a163034(exchange=null)[send=HttpSenderOverHTTP@18d1be5a(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7357f3df{s=START}],recv=HttpReceiverOverHTTP@4f550000(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]:runFillable:BLOCKING
14:04:09.343 [main] INFO graphql.servlet.AbstractGraphQLHttpServlet - Schema generation sucessfull