jetty.project icon indicating copy to clipboard operation
jetty.project copied to clipboard

Published 20 hours ago • verified publisher icon jetty

Cleanup of 12.1.x CI build output

Open gregw opened this issue 4 months ago • 12 comments

Jetty version(s) 12.1.x

Jetty Environment all

Java version/vendor (use: java -version) 24

OS type/version linux

Description

This is a meta issue. All strange, redundant, debug output reported in the log of https://jenkins.webtide.net/blue/organizations/jenkins/jetty.project/detail/jetty-12.1.x/679/pipeline/10/ These are probably just verbosity issues, but should be looked at just to check:

  • [x] System err
Running org.eclipse.jetty.http.HttpGeneratorServerTest.testKeepAliveWithFlush()
HTTP/1.1 200 OK
Content-Type: text/plain
  • [x] debug output
Running org.eclipse.jetty.http.HuffmanTest.testDecodemultipart/byteranges
([5] spec=D.6.1dtext/xml; charset=iso-8859-1
)
multipart/form-data
application/json; charset=utf-8
...
Running org.eclipse.jetty.http.HuffmanTest.testDecode([6] spec=D.6.1l)
multipart/byteranges
text/xml; charset=iso-8859-1
multipart/form-data
application/json; charset=utf-8
...
Running org.eclipse.jetty.http.MimeTypesTest.testMimeTypesGetBaseType([3] text/html, text/html)
multipart/byteranges
text/xml; charset=iso-8859-1
multipart/form-data
...
Running org.eclipse.jetty.http.MimeTypesTest.testMimeTypesGetBaseType([4] text/html;charset=utf-8, text/html)
multipart/byteranges
text/xml; charset=iso-8859-1
multipart/form-data
...
Running org.eclipse.jetty.http.HttpParserTest.testIPv6HostPort([6] oejh.HttpParserTest$Scenario[eol=CRLF, eolChunk=LF, c=RFC9110)
multipart/byteranges
text/xml; charset=iso-8859-1
...
Running org.eclipse.jetty.http.MimeTypesTest.testMimeTypesGetBaseType([8] null, null)
multipart/byteranges
text/xml; charset=iso-8859-1
multipart/form-data
...
  • [x] Debug output
Running org.eclipse.jetty.http.HttpParserTest.testIPv6HostPort([23] oejh.HttpParserTest$Scenario[eol=LF, eolChunk=CRLF, c=RFC2616_LEGACY)
decoded: value = '€'
Running org.eclipse.jetty.http.HttpParserTest.testIPv6HostPort([24] oejh.HttpParserTest$Scenario[eol=LF, eolChunk=LF, c=RFC2616_LEGACY)
Running org.eclipse.jetty.http.HuffmanTest.testEncode8859Only([2])
decoded: value = 'ÿ'
  • [x] Stack in log
Running org.eclipse.jetty.http.HttpParserTest.testNoValue([3] oejh.HttpParserTest$Scenario[eol=LF, eolChunk=CRLF, c=STRICT)
org.eclipse.jetty.http.BadMessageException: 400: LF line terminator in header
	at [email protected]/org.eclipse.jetty.http.HttpParser.checkViolation(HttpParser.java:373)
	at [email protected]/org.eclipse.jetty.http.HttpParser.parseLine(HttpParser.java:748)
	at [email protected]/org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:1700)
	at [email protected]/org.eclipse.jetty.http.HttpParserTest.parseAll(HttpParserTest.java:87)
...
Running org.eclipse.jetty.http.HttpParserTest.testNoValue([4] oejh.HttpParserTest$Scenario[eol=LF, eolChunk=LF, c=STRICT)
org.eclipse.jetty.http.BadMessageException: 400: LF line terminator in header
	at [email protected]/org.eclipse.jetty.http.HttpParser.checkViolation(HttpParser.java:373)
	at [email protected]/org.eclipse.jetty.http.HttpParser.parseLine(HttpParser.java:748)
	at [email protected]/org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:1700)
...
  • [ ] Assertion failure but test passed?
Running org.eclipse.jetty.server.RequestLogTest.testResponseThenChangeStatusAndHeaders()
2025-05-30 11:59:18.344:INFO :oejs.Server:ForkJoinPool-1-worker-2: jetty-12.1.0-SNAPSHOT; built: 2025-05-30T11:55:24.742Z; git: 2547cb0a09fab3b0e45112206d57115072f8e838; jvm 24.0.1+9
2025-05-30 11:59:18.345:INFO :oejs.AbstractConnector:ForkJoinPool-1-worker-2: Started oejs.ServerConnector@30a2bd0c{HTTP/1.1, (http/1.1)}{0.0.0.0:41777}
2025-05-30 11:59:18.346:INFO :oejs.Server:ForkJoinPool-1-worker-2: Started oejs.Server@61b85b64{STARTING}[12.1.0-SNAPSHOT,sto=0] @28968ms
2025-05-30 11:59:18.348:WARN :oejut.SerializedInvoker:qtp299524348-8964: Serialized invocation error
org.opentest4j.AssertionFailedError: Expected java.lang.UnsupportedOperationException to be thrown, but nothing was thrown.
	at org.junit.jupiter.api.AssertionFailureBuilder.build(AssertionFailureBuilder.java:152)
	at org.junit.jupiter.api.AssertThrows.assertThrows(AssertThrows.java:73)
	at org.junit.jupiter.api.AssertThrows.assertThrows(AssertThrows.java:35)
	at org.junit.jupiter.api.Assertions.assertThrows(Assertions.java:3128)
	at [email protected]/org.eclipse.jetty.server.RequestLogTest$1.lambda$handle$0(RequestLogTest.java:330)
	at [email protected]/org.eclipse.jetty.util.Callback$6.completed(Callback.java:290)
	at [email protected]/org.eclipse.jetty.util.Callback$Nested.succeeded(Callback.java:641)
	at [email protected]/org.eclipse.jetty.util.thread.SerializedInvoker$Link.run(SerializedInvoker.java:274)
	at [email protected]/org.eclipse.jetty.util.thread.SerializedInvoker.run(SerializedInvoker.java:174)
	at [email protected]/org.eclipse.jetty.server.internal.HttpChannelState$ChannelResponse.succeeded(HttpChannelState.java:1358)
...
  • [x] Debug dump
Running org.eclipse.jetty.server.handler.ThreadLimitHandlerTest.testLimit()
2025-05-30 11:59:57.033:INFO :oejs.Server:ForkJoinPool-1-worker-2: jetty-12.1.0-SNAPSHOT; built: 2025-05-30T11:55:24.742Z; git: 2547cb0a09fab3b0e45112206d57115072f8e838; jvm 24.0.1+9
2025-05-30 11:59:57.034:INFO :oejsh.ThreadLimitHandler:ForkJoinPool-1-worker-2: ThreadLimitHandler enable=true limit=4
2025-05-30 11:59:57.034:INFO :oejs.AbstractConnector:ForkJoinPool-1-worker-2: Started oejs.LocalConnector@b913425{HTTP/1.1, (http/1.1)}
2025-05-30 11:59:57.034:INFO :oejs.AbstractConnector:ForkJoinPool-1-worker-2: Started oejs.ServerConnector@4f540ac4{HTTP/1.1, (http/1.1)}{0.0.0.0:44569}
2025-05-30 11:59:57.034:INFO :oejs.Server:ForkJoinPool-1-worker-2: Started oejs.Server@8312761{STARTING}[12.1.0-SNAPSHOT,sto=0] @67656ms
oejsh.StatisticsHandler@390a6123{STARTED} - STARTED
+= oejsh.StatisticsHandlerTest$@46180991{STARTED} - STARTED
+> requestStats: oejus.CounterStatistic@613e73a{c=0,m=1,t=1}
+> requestTimeStats: oejus.SampleStatistic@6a28552d{count=1,max=1000565870,mean=1000565870.000000,total=1000565870,variance=0.000000,stddev=0.000000}
+> handleStats: oejus.CounterStatistic@23cc0c7e{c=0,m=1,t=1}
+> handleTimeStats: oejus.SampleStatistic@334a2d44{count=1,max=1000493917,mean=1000493917.000000,total=1000493917,variance=0.000000,stddev=0.000000}
+> failures: 0
+> handlingFailures: 0
+> 1xxResponses: 0
+> 2xxResponses: 1
+> 3xxResponses: 0
+> 4xxResponses: 0
+> 5xxResponses: 0
+> bytesRead: 0
+> bytesWritten: 0
  • [ ] Stack trace
Running org.eclipse.jetty.server.handler.StatisticsHandlerTest.testInvalidContentLengthRecordsStatus500()
2025-05-30 11:59:59.263:WARN :oejshg.GzipHandler:ForkJoinPool-1-worker-2: minGzipSize of 16 is inefficient for short content, break even is size 23
2025-05-30 11:59:59.264:INFO :oejs.Server:ForkJoinPool-1-worker-3: jetty-12.1.0-SNAPSHOT; built: 2025-05-30T11:55:24.742Z; git: 2547cb0a09fab3b0e45112206d57115072f8e838; jvm 24.0.1+9
2025-05-30 11:59:59.264:INFO :oejs.Server:ForkJoinPool-1-worker-2: jetty-12.1.0-SNAPSHOT; built: 2025-05-30T11:55:24.742Z; git: 2547cb0a09fab3b0e45112206d57115072f8e838; jvm 24.0.1+9
2025-05-30 11:59:59.264:INFO :oejs.AbstractConnector:ForkJoinPool-1-worker-3: Started oejs.LocalConnector@421d6018{HTTP/1.1, (http/1.1)}
2025-05-30 11:59:59.264:INFO :oejs.Server:ForkJoinPool-1-worker-3: Started oejs.Server@7b11cf13{STARTING}[12.1.0-SNAPSHOT,sto=0] @69887ms
2025-05-30 11:59:59.265:WARN :oejs.Response:qtp1156458427-21000: writeError: status=500, message=java.io.IOException: content-length 1 != 0 written, response=oejsi.HttpChannelState$ErrorResponse@234e23d2{500,GET@2d89d9cb http://localhost/ HTTP/1.1}
java.io.IOException: content-length 1 != 0 written
	at [email protected]/org.eclipse.jetty.server.internal.HttpChannelState$ChannelCallback.succeeded(HttpChannelState.java:1566)
	at [email protected]/org.eclipse.jetty.server.handler.StatisticsHandlerTest$4.handle(StatisticsHandlerTest.java:351)
	at [email protected]/org.eclipse.jetty.server.Handler$Wrapper.handle(Handler.java:794)
	at [email protected]/org.eclipse.jetty.server.handler.EventsHandler.handle(EventsHandler.java:81)
	at [email protected]/org.eclipse.jetty.server.Handler$Wrapper.handle(Handler.java:794)
...
  • [x] Request text in log
Running org.eclipse.jetty.compression.CompressionDecoderSourceTest.testDecodeTextOneByteAtATime([2] class org.eclipse.jetty.compression.gzip.GzipCompression, texts/long.txt)
2025-05-30 12:03:14.759:INFO :oejs.Server:ForkJoinPool-1-worker-4: jetty-12.1.0-SNAPSHOT; built: 2025-05-30T11:55:24.742Z; git: 2547cb0a09fab3b0e45112206d57115072f8e838; jvm 24.0.1+9
2025-05-30 12:03:14.794:INFO :oejs.AbstractConnector:ForkJoinPool-1-worker-4: Started oejs.ServerConnector@3e054dc0{HTTP/1.1, (http/1.1)}{0.0.0.0:38955}
2025-05-30 12:03:14.800:INFO :oejs.Server:ForkJoinPool-1-worker-4: Started oejs.Server@378fc4b9{STARTING}[12.1.0-SNAPSHOT,sto=0] @1474ms
[2025-05-30T12:03:14.921+0000][info][gc] GC(0) Pause Young (Normal) (G1 Evacuation Pause) 213M->18M(4100M) 11.385ms
  HTTP/1.1 200 OK
  Server: Jetty(12.1.0-SNAPSHOT)
  Date: Fri, 30 May 2025 12:03:14 GMT
  Vary: Accept-Encoding
  Content-Type: text/plain;charset=utf-8
  Content-Encoding: br
  Transfer-Encoding: chunked
...
Running org.eclipse.jetty.compression.CompressionHandlerTest.testCompressPathConfig([2] br, texts/logo.svg, image/svg+xml, /path/to/logo.svg, true)
2025-05-30 12:03:15.009:INFO :oejs.Server:ForkJoinPool-1-worker-4: jetty-12.1.0-SNAPSHOT; built: 2025-05-30T11:55:24.742Z; git: 2547cb0a09fab3b0e45112206d57115072f8e838; jvm 24.0.1+9
2025-05-30 12:03:15.010:INFO :oejs.AbstractConnector:ForkJoinPool-1-worker-4: Started oejs.ServerConnector@6c7085ff{HTTP/1.1, (http/1.1)}{0.0.0.0:37445}
2025-05-30 12:03:15.011:INFO :oejs.Server:ForkJoinPool-1-worker-4: Started oejs.Server@42ff6505{STARTING}[12.1.0-SNAPSHOT,sto=0] @1685ms
  HTTP/1.1 200 OK
  Server: Jetty(12.1.0-SNAPSHOT)
...
Running org.eclipse.jetty.compression.CompressionEncoderSinkTest.testEncodeTextSmallReadBuffers([3] class org.eclipse.jetty.compression.gzip.GzipCompression, texts/logo.svg)
  HTTP/1.1 200 OK
  Server: Jetty(12.1.0-SNAPSHOT)
  Date: Fri, 30 May 2025 12:03:15 GMT
...
Running org.eclipse.jetty.compression.CompressionHandlerTest.testCompressPathConfig([4] br, images/logo.png, image/png, /images/logo.png, false)
2025-05-30 12:03:16.167:INFO :oejs.Server:ForkJoinPool-1-worker-4: jetty-12.1.0-SNAPSHOT; built: 2025-05-30T11:55:24.742Z; git: 2547cb0a09fab3b0e45112206d57115072f8e838; jvm 24.0.1+9
2025-05-30 12:03:16.169:INFO :oejs.AbstractConnector:ForkJoinPool-1-worker-4: Started oejs.ServerConnector@32dad322{HTTP/1.1, (http/1.1)}{0.0.0.0:45127}
2025-05-30 12:03:16.170:INFO :oejs.Server:ForkJoinPool-1-worker-4: Started oejs.Server@4d852b8{STARTING}[12.1.0-SNAPSHOT,sto=0] @2844ms
  HTTP/1.1 200 OK
  Server: Jetty(12.1.0-SNAPSHOT)
...
  • [x] Do we really need these INFO logs:
2025-05-30 12:08:59.512:INFO :oejs.AbstractConnector:ForkJoinPool-1-worker-2: Started oejs.LocalConnector@44d61ef3{HTTP/1.1, (http/1.1)}
2025-05-30 12:08:59.512:INFO :oejs.Server:ForkJoinPool-1-worker-2: Started oejs.Server@9d0da90{STARTING}[12.1.0-SNAPSHOT,sto=0] @1486ms
2025-05-30 12:08:59.512:INFO :oejs.Server:ForkJoinPool-1-worker-3: jetty-12.1.0-SNAPSHOT; built: 2025-05-30T11:55:24.742Z; git: 2547cb0a09fab3b0e45112206d57115072f8e838; jvm 24.0.1+9
2025-05-30 12:08:59.521:INFO :oejs.Server:ForkJoinPool-1-worker-2: Stopped oejs.Server@9d0da90{STOPPING}[12.1.0-SNAPSHOT,sto=0]
  • [ ] exceptions in log
Running RequestNanoTimesTest.testRequestNanoTimes() [6] FCGI
2025-05-30 12:09:50.652:INFO :oejs.Server:ForkJoinPool-1-worker-2: jetty-12.1.0-SNAPSHOT; built: 2025-05-30T11:55:24.742Z; git: 2547cb0a09fab3b0e45112206d57115072f8e838; jvm 24.0.1+9
2025-05-30 12:09:50.654:INFO :oejs.AbstractConnector:ForkJoinPool-1-worker-2: Started oejs.ServerConnector@ab04b48{fcgi/1.0, (fcgi/1.0)}{0.0.0.0:35145}
2025-05-30 12:09:50.654:INFO :oejs.Server:ForkJoinPool-1-worker-2: Started oejs.Server@354a79f4{STARTING}[12.1.0-SNAPSHOT,sto=0] @35399ms
2025-05-30 12:09:50.656:WARN :oejusS.config:ForkJoinPool-1-worker-2: Trusting all certificates configured for oejus.SslContextFactory$Client@1bd79742[provider=null,keyStore=null,trustStore=null]
2025-05-30 12:09:50.656:WARN :oejusS.config:ForkJoinPool-1-worker-2: No Client EndPointIdentificationAlgorithm configured for oejus.SslContextFactory$Client@1bd79742[provider=null,keyStore=null,trustStore=null]
2025-05-30 12:09:50.687:WARN :oejs.Response:server-8147: writeError: status=500, message=java.io.IOException: java.util.concurrent.TimeoutException: Idle timeout expired: 1000/1000 ms, response=oejsi.HttpChannelState$ErrorResponse@5ae10472{500,POST@5bb8b6aa http://localhost:42915/ HTTP/1.1}
java.io.IOException: java.util.concurrent.TimeoutException: Idle timeout expired: 1000/1000 ms
	at org.eclipse.jetty.util.IO.rethrow(IO.java:451)
	at org.eclipse.jetty.test.client.transport.ThreadStarvationTest$2.handle(ThreadStarvationTest.java:130)
	at org.eclipse.jetty.server.Server.handle(Server.java:182)
	at org.eclipse.jetty.server.internal.HttpChannelState$HandlerInvoker.run(HttpChannelState.java:681)
	at org.eclipse.jetty.server.internal.HttpConnection.onFillable(HttpConnection.java:411)
	at org.eclipse.jetty.server.internal.HttpConnection$FillableCallback.succeeded(HttpConnection.java:1819)
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:99)
	at org.eclipse.jetty.io.SelectableChannelEndPoint$1.run(SelectableChannelEndPoint.java:54)
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:982)
	at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.doRunJob(QueuedThreadPool.java:1212)
	at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1167)
	at java.base/java.lang.Thread.run(Thread.java:1447)
Suppressed: 
	|org.eclipse.jetty.util.StaticException: Unconsumed request content
Caused by: 
java.util.concurrent.TimeoutException: Idle timeout expired: 1000/1000 ms
	at org.eclipse.jetty.io.IdleTimeout.checkIdleTimeout(IdleTimeout.java:167)
	at org.eclipse.jetty.io.IdleTimeout.idleCheck(IdleTimeout.java:113)
	at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:545)
	at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:328)
	at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:309)
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1095)
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:619)
	at java.base/java.lang.Thread.run(Thread.java:1447)
  • [ ] Exception in logs
Running HttpClientDemandTest.testDemandInTwoChunks() [1] HTTP
2025-05-30 12:09:52.924:INFO :oejs.Server:ForkJoinPool-1-worker-8: jetty-12.1.0-SNAPSHOT; built: 2025-05-30T11:55:24.742Z; git: 2547cb0a09fab3b0e45112206d57115072f8e838; jvm 24.0.1+9
2025-05-30 12:09:52.925:INFO :oejs.AbstractConnector:ForkJoinPool-1-worker-8: Started oejs.ServerConnector@4957bb6a{HTTP/1.1, (http/1.1)}{0.0.0.0:44129}
2025-05-30 12:09:52.925:INFO :oejs.Server:ForkJoinPool-1-worker-8: Started oejs.Server@173dc708{STARTING}[12.1.0-SNAPSHOT,sto=0] @37670ms
2025-05-30 12:09:52.926:WARN :oejusS.config:ForkJoinPool-1-worker-8: Trusting all certificates configured for oejus.SslContextFactory$Client@3615e7e1[provider=null,keyStore=null,trustStore=null]
2025-05-30 12:09:52.926:WARN :oejusS.config:ForkJoinPool-1-worker-8: No Client EndPointIdentificationAlgorithm configured for oejus.SslContextFactory$Client@3615e7e1[provider=null,keyStore=null,trustStore=null]
2025-05-30 12:09:52.965:INFO :oejtct.HttpClientStreamTest:server-6091: Accumulated 5300 chunks totalling 7063485 bytes
2025-05-30 12:09:53.013:INFO :oejtct.HttpClientStreamTest:server-6097: Accumulated 5400 chunks totalling 7272930 bytes
2025-05-30 12:09:53.050:WARN :oejs.Response:server-8834: writeError: status=500, message=java.io.IOException: java.util.concurrent.TimeoutException: Idle timeout expired: 1000/1000 ms, response=oejsi.HttpChannelState$ErrorResponse@6e392179{500,POST@7165f8ed https://localhost:44649/ HTTP/1.1}
java.io.IOException: java.util.concurrent.TimeoutException: Idle timeout expired: 1000/1000 ms
	at org.eclipse.jetty.util.IO.rethrow(IO.java:451)
	at org.eclipse.jetty.test.client.transport.ThreadStarvationTest$2.handle(ThreadStarvationTest.java:130)
	at org.eclipse.jetty.server.Server.handle(Server.java:182)
	at org.eclipse.jetty.server.internal.HttpChannelState$HandlerInvoker.run(HttpChannelState.java:681)
	at org.eclipse.jetty.server.internal.HttpConnection.onFillable(HttpConnection.java:411)
...
Suppressed: 
	|org.eclipse.jetty.util.StaticException: Unconsumed request content
Caused by: 
java.util.concurrent.TimeoutException: Idle timeout expired: 1000/1000 ms
	at org.eclipse.jetty.io.IdleTimeout.checkIdleTimeout(IdleTimeout.java:167)
	at org.eclipse.jetty.io.IdleTimeout.idleCheck(IdleTimeout.java:113)
	at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:545)
	at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:328)
...
  • [ ] Exception in log
Running RoundRobinConnectionPoolTest.testMultiplexWithMaxUsage() [3] H2C
2025-05-30 12:09:55.309:INFO :oejs.Server:ForkJoinPool-1-worker-2: jetty-12.1.0-SNAPSHOT; built: 2025-05-30T11:55:24.742Z; git: 2547cb0a09fab3b0e45112206d57115072f8e838; jvm 24.0.1+9
2025-05-30 12:09:55.309:INFO :oejs.AbstractConnector:ForkJoinPool-1-worker-2: Started oejs.ServerConnector@6b782248{h2c, (h2c)}{0.0.0.0:38001}
2025-05-30 12:09:55.309:INFO :oejs.Server:ForkJoinPool-1-worker-2: Started oejs.Server@3cc7ec4e{STARTING}[12.1.0-SNAPSHOT,sto=0] @40055ms
2025-05-30 12:09:55.311:WARN :oejusS.config:ForkJoinPool-1-worker-2: Trusting all certificates configured for oejus.SslContextFactory$Client@71d343d9[provider=null,keyStore=null,trustStore=null]
2025-05-30 12:09:55.311:WARN :oejusS.config:ForkJoinPool-1-worker-2: No Client EndPointIdentificationAlgorithm configured for oejus.SslContextFactory$Client@71d343d9[provider=null,keyStore=null,trustStore=null]
2025-05-30 12:09:55.350:WARN :oejs.Response:server-9451: writeError: status=500, message=java.io.IOException: java.util.concurrent.TimeoutException: Idle timeout 1000 ms elapsed, response=oejsi.HttpChannelState$ErrorResponse@148cba2a{500,POST@56717360 http://localhost:39941/ HTTP/2.0}
java.io.IOException: java.util.concurrent.TimeoutException: Idle timeout 1000 ms elapsed
	at org.eclipse.jetty.util.IO.rethrow(IO.java:451)
	at org.eclipse.jetty.test.client.transport.ThreadStarvationTest$2.handle(ThreadStarvationTest.java:130)
	at org.eclipse.jetty.server.Server.handle(Server.java:182)
	at org.eclipse.jetty.server.internal.HttpChannelState$HandlerInvoker.run(HttpChannelState.java:681)
	at org.eclipse.jetty.util.thread.Invocable$ReadyTask.run(Invocable.java:178)
	at org.eclipse.jetty.http2.server.internal.HttpStreamOverHTTP2$1.run(HttpStreamOverHTTP2.java:136)
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:982)
	at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.doRunJob(QueuedThreadPool.java:1212)
	at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1167)
	at java.base/java.lang.Thread.run(Thread.java:1447)
Suppressed: 
	|org.eclipse.jetty.util.StaticException: Unconsumed request content
Caused by: 
java.util.concurrent.TimeoutException: Idle timeout 1000 ms elapsed
	at org.eclipse.jetty.http2.HTTP2Session$StreamTimeouts.onExpired(HTTP2Session.java:2744)
...
  • [ ] Exception in log
Running ServerTimeoutsTest.testIdleTimeoutWithDemand() [1] HTTP, false
...
2025-05-30 12:09:56.762:WARN :oejs.Response:server-9809: writeError: status=500, message=java.io.IOException: java.util.concurrent.TimeoutException: Idle timeout 1000 ms elapsed, response=oejsi.HttpChannelState$ErrorResponse@5f653d0e{500,POST@1a67a886 https://localhost:34147/ HTTP/2.0}
java.io.IOException: java.util.concurrent.TimeoutException: Idle timeout 1000 ms elapsed
	at org.eclipse.jetty.util.IO.rethrow(IO.java:451)
	at org.eclipse.jetty.test.client.transport.ThreadStarvationTest$2.handle(ThreadStarvationTest.java:130)
	at org.eclipse.jetty.server.Server.handle(Server.java:182)
...
  • [ ] Exception in log
Running TLSHandshakeFailureTest.testTLSUnwrapAbruptSSLEngineClose() [1] HTTPS
...
2025-05-30 12:10:00.576:WARN :oejs.Response:server-10536: writeError: status=500, message=java.io.IOException: java.util.concurrent.TimeoutException: Idle timeout expired: 1000/1000 ms, response=oejsi.HttpChannelState$ErrorResponse@25bdd55{500,POST@75574596 http://localhost:42515/ HTTP/1.1}
java.io.IOException: java.util.concurrent.TimeoutException: Idle timeout expired: 1000/1000 ms
	at org.eclipse.jetty.util.IO.rethrow(IO.java:451)
	at org.eclipse.jetty.test.client.transport.ThreadStarvationTest$2.handle(ThreadStarvationTest.java:130)
...
  • [ ] Exception in Log
Running HttpClientStreamTest.testMultiplexedConnectionsForcibleStop() [5] H3_QUICHE
...
2025-05-30 12:10:19.370:WARN :oejs.Response:server-13451: writeError: status=500, message=org.eclipse.jetty.http3.RetryableStreamException, response=oejsi.HttpChannelState$ErrorResponse@55034330{500,GET@587c9d15 https://localhost:42091/ HTTP/3.0}
org.eclipse.jetty.http3.RetryableStreamException
	at org.eclipse.jetty.http3.HTTP3Session.onGoAway(HTTP3Session.java:535)
	at org.eclipse.jetty.http3.HTTP3Session$FrameListener.onGoAway(HTTP3Session.java:876)
...
  • [ ] exception in log
[INFO] Running org.eclipse.jetty.websocket.tests.client.ClientConnectTest
2025-05-30 12:13:30.187:WARN :oejs.Response:qtp868352784-723: writeError: status=500, message=org.eclipse.jetty.websocket.core.exception.WebSocketException: not upgraded: selected a protocol not present in offered protocols, response=oejsi.HttpChannelState$ChannelResponse@6ed82ec5{0,GET@601fdd6e http://localhost:41559/filterPath HTTP/1.1}
org.eclipse.jetty.websocket.core.exception.WebSocketException: not upgraded: selected a protocol not present in offered protocols
	at org.eclipse.jetty.websocket.core.server.internal.AbstractHandshaker.upgradeRequest(AbstractHandshaker.java:80)
	at org.eclipse.jetty.websocket.core.server.internal.HandshakerSelector.upgradeRequest(HandshakerSelector.java:45)
	at org.eclipse.jetty.websocket.core.server.WebSocketMappings.upgrade(WebSocketMappings.java:296)
...
  • [x] Dump in log
Running org.eclipse.jetty.ee11.servlet.security.ConstraintTest.testUncoveredMethod()
2025-05-30 12:13:48.561:INFO :oejs.Server:ForkJoinPool-1-worker-1: Started oejs.Server@7c5cc26f{STARTING}[12.1.0-SNAPSHOT,sto=0] @2101ms
oeje11ss.ConstraintSecurityHandler@64100a23{STARTED} - STARTED
+- knownAuthenticatorFactories size=1
|  +> org.eclipse.jetty.security.DefaultAuthenticatorFactory@5e8f094d
+= oeje11s.ServletHandler@12136d35{STARTED} - STARTED
|  +> listeners oeje11s.ServletHandler@12136d35{STARTED} size=0
...
  • [ ] Debug output
Running org.eclipse.jetty.ee11.servlet.AsyncServletTest.testForwardAsyncDispatch([1] false, false, name=orig&one=1, orig)
...
REQUEST >>> TestServletA /ctx/forwarder/info?name=orig&one=1
name=[orig]
FORWARD >>> TestServletB /ctx/target?name=forward&two=2
name=[forward, orig]
FORWARD <<< TestServletB /ctx/target?name=forward&two=2
REQUEST <<< TestServletA /ctx/forwarder/info?name=orig&one=1
ASYNC >>> TestServletA /ctx/forwarder/info?name=orig&one=1
name=[orig]
  • [ ] debug in log
Running org.eclipse.jetty.ee11.servlet.FormTest.testMaxFormContentSizeExceeded([3] -1, null, true, 200)
REQUEST >>> FwdServlet /ctx/fwd/info?start=10000&dispatch=100
FORWARD >>> PathServlet /ctx/path1?forward=true
wrapped REQ
initial
startAsync
...
Running org.eclipse.jetty.ee11.servlet.AsyncServletTest.testFwdWrapStartDispatch()
REQUEST >>> FwdServlet /ctx/fwd/info?wrap=true&start=1000&dispatch=100
  • [ ] debug in log
Running org.eclipse.jetty.ee11.servlet.IncludedServletTest.testTopWithIncludedHeader()
null=[HTTP/1.1 200 OK]
main-page-key=[main-page-value]
Server=[Jetty(12.1.0-SNAPSHOT)]
included-page-key=[included-page-value]
Content-Length=[65]
Date=[Fri, 30 May 2025 12:13:55 GMT]

<h3> This is the included page
<h2> Hello, this is the top page.
  • [x] Assertion failure test passes!!!
Running org.eclipse.jetty.ee11.servlet.RequestURITest.testGetRequestURIHTTP10([49] /hello%1Bworld, /hello%1Bworld, null)
2025-05-30 12:13:57.617:INFO :oejsh.ContextHandler:ForkJoinPool-1-worker-3: Started oeje11s.ServletContextHandler@6ff24755{ROOT,/,b=null,a=AVAILABLE,h=oeje11s.ServletHandler@1a19920b{STARTED}}
2025-05-30 12:13:57.617:INFO :oejes.ServletContextHandler:ForkJoinPool-1-worker-3: Started oeje11s.ServletContextHandler@6ff24755{ROOT,/,b=null,a=AVAILABLE,h=oeje11s.ServletHandler@1a19920b{STARTED}}
2025-05-30 12:13:57.618:INFO :oejs.AbstractConnector:ForkJoinPool-1-worker-3: Started oejs.LocalConnector@2a08a046{HTTP/1.1, (http/1.1)}
2025-05-30 12:13:57.618:INFO :oejs.Server:ForkJoinPool-1-worker-3: Started oejs.Server@7bb1dcd6{STARTING}[12.1.0-SNAPSHOT,sto=0] @11158ms
2025-05-30 12:13:57.619:INFO :oejs.Server:ForkJoinPool-1-worker-3: Stopped oejs.Server@7bb1dcd6{STOPPING}[12.1.0-SNAPSHOT,sto=0]
2025-05-30 12:13:57.618:WARN :oejes.ServletChannel:qtp1036417886-7638: /servlet/
java.lang.AssertionError: |Expected: is "5"|     but: was null
	at org.hamcrest.MatcherAssert.assertThat(MatcherAssert.java:20)
	at org.hamcrest.MatcherAssert.assertThat(MatcherAssert.java:6)
	at [email protected]/org.eclipse.jetty.ee11.servlet.ResponseTest.lambda$testSetContentLengthAfterCommit$0(ResponseTest.java:293)
	at [email protected]/org.eclipse.jetty.ee11.servlet.ResponseTest$4.doGet(ResponseTest.java:361)
	at [email protected]/jakarta.servlet.http.HttpServlet.service(HttpServlet.java:633)
  • [ ] stack in log
[INFO] Running org.eclipse.jetty.ee11.proxy.ProxyServletLoadTest
2025-05-30 12:14:55.893:WARN :oejes.ServletChannel:server-189: /
jakarta.servlet.ServletException: Expected Test Exception
	at [email protected]/org.eclipse.jetty.ee11.proxy.ProxyServletFailureTest$5.doGet(ProxyServletFailureTest.java:413)
	at [email protected]/jakarta.servlet.http.HttpServlet.service(HttpServlet.java:633)
	at [email protected]/jakarta.servlet.http.HttpServlet.service(HttpServlet.java:723)
...
2025-05-30 12:14:56.099:WARN :oejes.ServletChannel:server-379: /
jakarta.servlet.ServletException: Expected Test Exception
	at [email protected]/org.eclipse.jetty.ee11.proxy.ProxyServletFailureTest$5.doGet(ProxyServletFailureTest.java:413)
	at [email protected]/jakarta.servlet.http.HttpServlet.service(HttpServlet.java:633)
	at [email protected]/jakarta.servlet.http.HttpServlet.service(HttpServlet.java:723)
  • [ ] Exception in log
Running org.eclipse.jetty.ee11.proxy.ProxyServletTest.testWrongContentLength([2] class org.eclipse.jetty.ee11.proxy.AsyncProxyServlet)
...
2025-05-30 12:16:04.482:WARN :oejs.Response:server-5493: writeError: status=500, message=java.io.IOException: written 8 < 9 content-length, response=oejsi.HttpChannelState$ErrorResponse@4beb796{500,GET@a794971 http://localhost:45043/ HTTP/1.1}
java.io.IOException: written 8 < 9 content-length
	at [email protected]/org.eclipse.jetty.server.internal.HttpChannelState$ChannelResponse.write(HttpChannelState.java:1299)
	at [email protected]/org.eclipse.jetty.server.Response$Wrapper.write(Response.java:836)
	at [email protected]/org.eclipse.jetty.server.handler.ContextResponse.write(ContextResponse.java:56)
	at org.eclipse.jetty.ee11.servlet.ServletContextResponse.write(ServletContextResponse.java:225)
...
  • [ ] IllegalStateException in test
Running org.eclipse.jetty.ee9.nested.SessionHandlerTest.testCreateSession()
...
2025-05-30 12:20:04.441:WARN :oejen.HttpChannel:qtp643709738-66: /ctx/path/info
java.lang.IllegalStateException: s=HANDLING rs=COMPLETED os=ABORTED is=IDLE awp=false se=false i=false al=0
	at [email protected]/org.eclipse.jetty.ee9.nested.HttpChannelState.completed(HttpChannelState.java:1021)
	at [email protected]/org.eclipse.jetty.util.Callback$4.failed(Callback.java:210)
	at [email protected]/org.eclipse.jetty.ee9.nested.HttpOutput.complete(HttpOutput.java:515)
...
  • [ ] Strange warn text:
Running org.eclipse.jetty.ee9.nested.ErrorHandlerTest.testComplexCauseMessageNoAcceptHeader([1] /jsonmessage/)
...
2025-05-30 12:20:05.361:WARN :oejen.HttpChannel:qtp343021951-554: /jsonmessage/: jakarta.servlet.ServletException: java.lang.RuntimeException: "}, "glossary": {
 "title": "example"
 }
 {"
  • [ ] Response in log
Running org.eclipse.jetty.ee9.nested.ErrorHandlerTest.test404UnAccept()
...
-------------
HTTP/1.1 404 Not Found
Server: Jetty(12.1.0-SNAPSHOT)
Date: Fri, 30 May 2025 12:20:05 GMT
Cache-Control: must-revalidate,no-cache,no-store
Content-Length: 0
...
Running org.eclipse.jetty.ee9.nested.ContextHandlerTest.testGetResource()
...
response: HTTP/1.1 500 Server Error
Server: Jetty(12.1.0-SNAPSHOT)
Date: Fri, 30 May 2025 12:20:05 GMT
Cache-Control: must-revalidate,no-cache,no-store
Content-Type: text/html;charset=utf-8
Content-Length: 4374
  • [ ] Exception in log
Running org.eclipse.jetty.ee9.nested.NcsaRequestLogTest.testOKErrorHandler([4] customNCSA, oeje9n.NcsaRequestLogTest$ServletExceptionHandler, /sex, "GET /sex HTTP/1.0" 500)
...
2025-05-30 12:20:07.109:WARN :oejen.HttpChannel:qtp408455334-1862: /sex
jakarta.servlet.ServletException: expected
	at [email protected]/org.eclipse.jetty.ee9.nested.NcsaRequestLogTest$ServletExceptionHandler.handle(NcsaRequestLogTest.java:635)
	at [email protected]/org.eclipse.jetty.ee9.nested.NcsaRequestLogTest$3.handle(NcsaRequestLogTest.java:453)
	at [email protected]/org.eclipse.jetty.ee9.nested.HandlerWrapper.handle(HandlerWrapper.java:124)
	at [email protected]/org.eclipse.jetty.ee9.nested.ScopedHandler.nextHandle(ScopedHandler.java:195)
  • [ ] Exception in log
Running org.eclipse.jetty.ee9.nested.MultiPartParserTest.testMultiPartFormDataParserLegacyAllowBase64([34] browser-capture-sjis-charset-form-android-chrome.raw, null, expecting.multipart.count=3)
...
2025-05-30 12:20:07.126:WARN :oejen.HttpChannel:qtp1286929487-1897: /rtex
java.lang.RuntimeException: expected
	at [email protected]/org.eclipse.jetty.ee9.nested.NcsaRequestLogTest$RuntimeExceptionHandler.handle(NcsaRequestLogTest.java:667)
	at [email protected]/org.eclipse.jetty.ee9.nested.NcsaRequestLogTest$3.handle(NcsaRequestLogTest.java:453)
	at [email protected]/org.eclipse.jetty.ee9.nested.HandlerWrapper.handle(HandlerWrapper.java:124)
...
Running org.eclipse.jetty.ee9.nested.MultiPartParserTest.testMultiPartFormDataParserLegacyAllowBase64([43] browser-capture-form-fileupload-android-firefox.raw, null, expecting.multipart.count=2)
...
2025-05-30 12:20:07.194:WARN :oejen.HttpChannelState:qtp1485215605-1916: onError not handled due to invalid requestState s=HANDLING rs=COMPLETED os=ABORTED is=IDLE awp=false se=false i=true al=0
org.eclipse.jetty.http.BadMessageException: 488: null
	at [email protected]/org.eclipse.jetty.ee9.nested.NcsaRequestLogTest$AbortHandler.handle(NcsaRequestLogTest.java:685)
	at [email protected]/org.eclipse.jetty.ee9.nested.NcsaRequestLogTest$3.handle(NcsaRequestLogTest.java:453)
	at [email protected]/org.eclipse.jetty.ee9.nested.HandlerWrapper.handle(HandlerWrapper.java:124)
...
  • [x] IllegalStateExceptio
Running org.eclipse.jetty.ee9.nested.NcsaRequestLogTest.testAsyncComplete([9] customNCSA, oeje9n.NcsaRequestLogTest$AbortHandler, /bad, "GET /bad HTTP/1.0" 488)
...
2025-05-30 12:20:07.700:WARN :oejut.QueuedThreadPool:qtp2014510596-2119: Job failed
java.lang.IllegalStateException: s=WOKEN rs=COMPLETED os=ABORTED is=IDLE awp=false se=false i=false al=0
	at [email protected]/org.eclipse.jetty.ee9.nested.HttpChannelState.complete(HttpChannelState.java:754)
	at [email protected]/org.eclipse.jetty.ee9.nested.AsyncContextState.complete(AsyncContextState.java:67)
	at [email protected]/org.eclipse.jetty.ee9.nested.NcsaRequestLogTest$5.lambda$handle$0(NcsaRequestLogTest.java:549)
	at [email protected]/org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:982)
	at [email protected]/org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.doRunJob(QueuedThreadPool.java:1212)
	at [email protected]/org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1167)
	at java.base/java.lang.Thread.run(Thread.java:1447)
...
Running org.eclipse.jetty.ee9.nested.NcsaRequestLogTest.testAsyncComplete([10] customNCSA, oeje9n.NcsaRequestLogTest$AbortPartialHandler, /bad, "GET /bad HTTP/1.0" 200)
...
2025-05-30 12:20:07.704:WARN :oejut.QueuedThreadPool:qtp1903557879-2146: Job failed
java.lang.IllegalStateException: s=WOKEN rs=COMPLETED os=ABORTED is=IDLE awp=false se=false i=false al=0
	at [email protected]/org.eclipse.jetty.ee9.nested.HttpChannelState.complete(HttpChannelState.java:754)
	at [email protected]/org.eclipse.jetty.ee9.nested.AsyncContextState.complete(AsyncContextState.java:67)
	at [email protected]/org.eclipse.jetty.ee9.nested.NcsaRequestLogTest$5.lambda$handle$0(NcsaRequestLogTest.java:549)
	at [email protected]/org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:982)
	at [email protected]/org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.doRunJob(QueuedThreadPool.java:1212)
	at [email protected]/org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1167)
	at java.base/java.lang.Thread.run(Thread.java:1447)
  • [ ] Exception in log
Running org.eclipse.jetty.ee9.nested.RequestTest.testEncodedFormUnknownMethod()
...
2025-05-30 12:20:08.003:WARN :oejen.HttpChannel:qtp1208292584-2488: /sex
jakarta.servlet.ServletException: expected
	at [email protected]/org.eclipse.jetty.ee9.nested.NcsaRequestLogTest$ServletExceptionHandler.handle(NcsaRequestLogTest.java:635)
	at [email protected]/org.eclipse.jetty.ee9.nested.NcsaRequestLogTest$4.handle(NcsaRequestLogTest.java:482)
...
Running org.eclipse.jetty.ee9.nested.ResponseTest.testServletOutputStream([4] print(double) - NaN)
...
2025-05-30 12:20:08.220:WARN :oejen.HttpChannel:qtp431909371-2581: /rtex
java.lang.RuntimeException: expected
	at [email protected]/org.eclipse.jetty.ee9.nested.NcsaRequestLogTest$RuntimeExceptionHandler.handle(NcsaRequestLogTest.java:667)
	at [email protected]/org.eclipse.jetty.ee9.nested.NcsaRequestLogTest$4.handle(NcsaRequestLogTest.java:482)
	at [email protected]/org.eclipse.jetty.ee9.nested.HandlerWrapper.handle(HandlerWrapper.java:124)
	at [email protected]/org.eclipse.jetty.ee9.nested.ScopedHandler.nextHandle(ScopedHandler.java:195)
...
  • [ ] IllegalStateException
Running org.eclipse.jetty.ee9.servlet.AsyncServletTest.testStartOnTimeoutDispatch()
...
2025-05-30 12:21:31.492:WARN :oejen.HttpChannel:qtp1325766923-38: /ctx/path4/info
java.lang.IllegalStateException: s=HANDLING rs=COMPLETED os=ABORTED is=UNREADY awp=false se=false i=false al=1
	at [email protected]/org.eclipse.jetty.ee9.nested.HttpChannelState.completed(HttpChannelState.java:1021)
	at [email protected]/org.eclipse.jetty.util.Callback$4.failed(Callback.java:210)
	at [email protected]/org.eclipse.jetty.ee9.nested.HttpOutput.complete(HttpOutput.java:515)
...
  • [ ] Debug in log
Running org.eclipse.jetty.ee9.servlet.ErrorPageTest.testErrorOverridesMimeTypeAndCharset()
...
HTTP/1.1 595 595
Server: Jetty(12.1.0-SNAPSHOT)
Date: Fri, 30 May 2025 12:21:33 GMT
Cache-Control: must-revalidate,no-cache,no-store
Content-Length: 301
Connection: close

DISPATCH: ERROR
ERROR_PAGE: /595
ERROR_MESSAGE: 595
ERROR_CODE: 595
ERROR_EXCEPTION: null
ERROR_EXCEPTION_TYPE: null
ERROR_SERVLET: org.eclipse.jetty.ee9.servlet.ErrorPageTest$ErrorContentTypeCharsetWriterInitializedServlet-88334ac
ERROR_REQUEST_URI: /error-mime-charset-writer/
getParameterMap()= {}
  • [ ] Exception in log
Running org.eclipse.jetty.ee9.servlet.GzipHandlerInputTest.testReadGzippedInput([27] 8515, true)
...
2025-05-30 12:21:35.320:WARN :oejen.HttpChannel:qtp1338225462-3045: /exception-servlet
org.eclipse.jetty.ee9.servlet.ErrorPageTest$TestServletException: org.eclipse.jetty.ee9.servlet.ErrorPageTest$TestException: error page invoked
	at [email protected]/org.eclipse.jetty.ee9.servlet.ErrorPageTest$ExceptionServlet.doGet(ErrorPageTest.java:887)
	at [email protected]/jakarta.servlet.http.HttpServlet.service(HttpServlet.java:500)
	at [email protected]/jakarta.servlet.http.HttpServlet.service(HttpServlet.java:587)
	at [email protected]/org.eclipse.jetty.ee9.servlet.ServletHolder.handle(ServletHolder.java:766)
...
  • [x] Dump in log
Running org.eclipse.jetty.ee10.servlet.security.ConstraintTest.testUncoveredMethod()
...
2025-05-30 12:25:04.057:INFO :oejs.Server:ForkJoinPool-1-worker-1: Started oejs.Server@bf7f76f{STARTING}[12.1.0-SNAPSHOT,sto=0] @2045ms
oeje10ss.ConstraintSecurityHandler@6163ee9d{STARTED} - STARTED
+- knownAuthenticatorFactories size=1
|  +> org.eclipse.jetty.security.DefaultAuthenticatorFactory@41d9b7b7
+= oeje10s.ServletHandler@2fb46562{STARTED} - STARTED
|  +> listeners oeje10s.ServletHandler@2fb46562{STARTED} size=0
|  +> filters oeje10s.ServletHandler@2fb46562{STARTED} size=0
|  +> filterMappings oeje10s.ServletHandler@2fb46562{STARTED} size=0
...
  • [ ] Rejected Execution
Running org.eclipse.jetty.ee9.proxy.ProxyServletTest.testResponseHeadersAreNotRemoved([3] class org.eclipse.jetty.ee9.proxy.AsyncMiddleManServlet)
...
2025-05-30 12:29:46.235:WARN :oejuts.AdaptiveExecutionStrategy:proxy-4198: Execute failed
java.util.concurrent.RejectedExecutionException: oeji.SocketChannelEndPoint@7b4fa62a[{l=/127.0.0.1:33806,r=localhost/127.0.0.1:44479,OPEN,fill=FI,flush=-,to=5000/30000}{io=1/0,kio=1,kro=1}]->[HttpConnectionOverHTTP@75884733(l:/127.0.0.1:33806 <-> r:localhost/127.0.0.1:44479,closed=false)=>oejcti.HttpChannelOverHTTP@71b7cd5a(exchange=null)[send=oejcti.HttpSenderOverHTTP@34b54d08(req=QUEUED,failure=null)[oejh.HttpGenerator@324dc342{s=START}],recv=oejcti.HttpReceiverOverHTTP@5cbbbe7(ex=null,rsp=IDLE,failure=null)[oejh.HttpParser{s=START,0 of -1}]]]:runFillable:BLOCKING
	at [email protected]/org.eclipse.jetty.util.thread.QueuedThreadPool.execute(QueuedThreadPool.java:783)
	at [email protected]/org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.execute(AdaptiveExecutionStrategy.java:540)
	at [email protected]/org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.consumeTask(AdaptiveExecutionStrategy.java:442)
	at [email protected]/org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.tryProduce(AdaptiveExecutionStrategy.java:297)
	at [email protected]/org.eclipse.jetty.util.thread.strategy.AdaptiveExecutionStrategy.run(AdaptiveExecutionStrategy.java:202)
	at [email protected]/org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:312)
	at [email protected]/org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:982)
	at [email protected]/org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.doRunJob(QueuedThreadPool.java:1212)
	at [email protected]/org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1167)
	at java.base/java.lang.Thread.run(Thread.java:1447)
  • [x] Build warnings
[WARNING] Couldn't load group class 'external' in Surefire|Failsafe plugin. The group class is ignored!
[WARNING] Couldn't load group class 'large-disk-resource' in Surefire|Failsafe plugin. The group class is ignored!
[WARNING] Couldn't load group class 'stress' in Surefire|Failsafe plugin. The group class is ignored!
[WARNING] Couldn't load group class 'slow' in Surefire|Failsafe plugin. The group class is ignored!
[WARNING] Couldn't load group class 'flaky' in Surefire|Failsafe plugin. The group class is ignored!

[INFO] Running org.eclipse.jetty.ee11.osgi.test.TestJettyOSGiAnnotationParser
[2025-05-30T12:44:21.688+0000][info][gc] GC(0) Pause Young (Normal) (G1 Evacuation Pause) 205M->8M(4100M) 6.952ms
2025-05-30 12:44:21.691:INFO :oope.ExamJavaRunner:main: ExamJavaRunner completed successfully

WARNING: A terminally deprecated method in sun.misc.Unsafe has been called
WARNING: sun.misc.Unsafe::staticFieldOffset has been called by org.eclipse.osgi.internal.url.MultiplexingFactory (file:/home/jenkins/agent/workspace/jetty.project_jetty-12.1.x/.repository/org/eclipse/platform/org.eclipse.osgi/3.23.0/org.eclipse.osgi-3.23.0.jar)
WARNING: Please consider reporting this to the maintainers of class org.eclipse.osgi.internal.url.MultiplexingFactory
WARNING: sun.misc.Unsafe::staticFieldOffset will be removed in a future release

2025-05-30 12:44:22.606:WARN :oopumic.MavenConfigurationImpl:main: Location /root/.m2/settings-security.xml is not accessible (incorrect value for option "security")
2025-05-30 12:44:22.776:WARN :oopumic.MavenConfigurationImpl:main: Location /root/.m2/settings-security.xml is not accessible (incorrect value for option "security")
2025-05-30 12:44:22.786:WARN :oopumic.MavenConfigurationImpl:main: Location /root/.m2/settings-security.xml is not accessible (incorrect value for option "security")
  • [x] DEBUG left on
org.ops4j.pax.swissbox.extender [org.ops4j.pax.swissbox.extender.BundleWatcher] DEBUG : Creating bundle watcher with scanner [org.ops4j.pax.swissbox.extender.BundleManifestScanner@44292202]...
org.ops4j.pax.swissbox.extender [org.ops4j.pax.swissbox.extender.BundleWatcher] DEBUG : Scanning bundle [org.eclipse.osgi]
org.ops4j.pax.swissbox.extender [org.ops4j.pax.swissbox.extender.BundleWatcher] DEBUG : Scanning bundle [org.ops4j.pax.exam]
...
org.apache.aries.spifly.dynamic.bundle [org.apache.aries.spifly.BaseActivator] DEBUG : Bundle Considered for SPI providers: org.eclipse.osgi
org.apache.aries.spifly.dynamic.bundle [org.apache.aries.spifly.BaseActivator] DEBUG : No provided SPI services. Skipping bundle: org.eclipse.osgi
org.apache.aries.spifly.dynamic.bundle [org.apache.aries.spifly.BaseActivator] DEBUG : Bundle Considered for SPI providers: org.ops4j.pax.exam
...
  • [x] OSGi Error
org.eclipse.osgi.services [Events.Framework] ERROR : FrameworkEvent ERROR
org.osgi.framework.BundleException: Could not resolve module: org.eclipse.osgi.services [37]
  Unresolved requirement: Require-Bundle: org.osgi.service.http.whiteboard; bundle-version="[1.1.0,2.0.0)"; visibility:="reexport"
  Unresolved requirement: Require-Bundle: org.eclipse.equinox.http.service.api; bundle-version="[1.2.0,2.0.0)"; visibility:="reexport"
    -> Bundle-SymbolicName: org.eclipse.equinox.http.service.api; bundle-version="1.2.2.v20231218-2126"
       org.eclipse.equinox.http.service.api [38]
         Unresolved requirement: Import-Package: javax.servlet; version="[2.1.0,5.0.0)"

	at org.eclipse.osgi.container.Module.start(Module.java:495)
	at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel$2.run(ModuleContainer.java:2111)
	at org.eclipse.osgi.internal.framework.EquinoxContainerAdaptor$1$1.execute(EquinoxContainerAdaptor.java:146)
	at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:2102)
	at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:2044)
	at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.doContainerStartLevel(ModuleContainer.java:2004)
	at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1916)
	at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1)
	at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230)
	at org.eclipse.osgi.framework.eventmgr.EventManager$EventThread.run(EventManager.java:341)
  • [ ] Exception in log
Running org.eclipse.jetty.ee11.cdi.tests.EmbeddedWeldTest.testServletContext([4] CdiSpiDecorator+Listener)
...
2025-05-30 12:45:08.806:WARN :oejec.CdiServletContainerInitializer:ForkJoinPool-1-worker-2: Unable to decorate org.eclipse.jetty.ee11.cdi.tests.MyContextListener@5a0b357e
java.lang.reflect.InvocationTargetException
	at java.base/jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:119)
	at java.base/java.lang.reflect.Method.invoke(Method.java:565)
	at org.eclipse.jetty.ee11.cdi.CdiSpiDecorator$Decorated.<init>(CdiSpiDecorator.java:209)
	at org.eclipse.jetty.ee11.cdi.CdiSpiDecorator.decorate(CdiSpiDecorator.java:171)
...
2025-05-30 12:45:08.941:WARN :oejec.CdiServletContainerInitializer:ForkJoinPool-1-worker-2: Unable to decorate org.eclipse.jetty.ee11.cdi.tests.MyContextListener@f8e38a5
java.lang.reflect.InvocationTargetException
	at java.base/jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:119)
	at java.base/java.lang.reflect.Method.invoke(Method.java:565)
...
Caused by: 
java.lang.IllegalStateException: Unable to access CDI
	at jakarta.enterprise.inject.spi.CDI.lambda$getCDIProvider$0(CDI.java:97)
	at java.base/java.util.Optional.orElseThrow(Optional.java:403)
	at jakarta.enterprise.inject.spi.CDI.getCDIProvider(CDI.java:97)
	at jakarta.enterprise.inject.spi.CDI.current(CDI.java:64)
  • [ ] Exception in log
Running RequestReaderTest.testChannelStateSucceeded() [1] HTTP
...
2025-05-30 12:45:16.893:WARN :oejes.ServletChannelState:qtp702492308-43: onError not handled due to invalid requestState s=HANDLING rs=COMPLETED os=ABORTED is=IDLE awp=false se=false i=false al=1
org.eclipse.jetty.io.EofException
	at org.eclipse.jetty.http3.server.internal.HttpStreamOverHTTP3.onFailure(HttpStreamOverHTTP3.java:564)
	at org.eclipse.jetty.http3.server.internal.ServerHTTP3StreamConnection.onFailure(ServerHTTP3StreamConnection.java:93)
	at org.eclipse.jetty.http3.server.HTTP3ServerConnectionFactory$HTTP3StreamListener.onFailure(HTTP3ServerConnectionFactory.java:170)
	at org.eclipse.jetty.http3.server.internal.HTTP3StreamServer.notifyFailure(HTTP3StreamServer.java:131)
	at org.eclipse.jetty.http3.HTTP3Stream.onFailure(HTTP3Stream.java:321)
	at org.eclipse.jetty.http3.HTTP3Session.onStreamFailure(HTTP3Session.java:548)
...
  • [ ] NoClassDefFound logged
Running org.eclipse.jetty.ee11.test.HttpInputIntegrationTest.testOne([49] TEST c=LocalClient, m=ASYNC_DISPATCHED, delayDispatch=true delayInFrame=true content-length:-1 expect=200 read=0 content:[]\n)
...
java.lang.NoClassDefFoundError: Intentional.Failure
	at org.eclipse.jetty.ee11.test.DeploymentErrorInitializer.onStartup(DeploymentErrorInitializer.java:32)
	at org.eclipse.jetty.ee11.servlet.ServletContainerInitializerHolder.doStart(ServletContainerInitializerHolder.java:156)
	at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:93)
...
  • [ ] Unreadable session exception
Running org.eclipse.jetty.ee11.session.jdbc.JDBCSessionDataStoreTest.testExistsDifferentContext()
...
2025-05-30 12:53:02.936:WARN :oejs.AbstractSessionManager:qtp839775035-172: Error loading session w101hdjx2529abqr1ky00aqdq8khm4
org.eclipse.jetty.session.UnreadableSessionDataException: Unreadable session w101hdjx2529abqr1ky00aqdq8khm4 for w10__foo_0.0.0.0
	at org.eclipse.jetty.session.JDBCSessionDataStore.doLoad(JDBCSessionDataStore.java:667)
	at org.eclipse.jetty.session.AbstractSessionDataStore.lambda$load$0(AbstractSessionDataStore.java:147)
	at org.eclipse.jetty.server.handler.ContextHandler$ScopedContext.run(ContextHandler.java:1637)
...
  • [x] Mongo tests very verbose
2025-05-30 12:54:31.626:INFO :oejnm.MongoLogs:docker-java-stream--1700030093: STDOUT: {"t":{"$date":"2025-05-30T12:54:31.626+00:00"},"s":"I",  "c":"NETWORK",  "id":22943,   "ctx":"listener","msg":"Connection accepted","attr":{"remote":"172.20.0.1:50093","uuid":"9d672df7-fb3c-494a-836d-798795e8ea46","connectionId":38,"connectionCount":28}}
2025-05-30 12:54:31.627:INFO :oejnm.MongoLogs:docker-java-stream--1700030093: STDOUT: {"t":{"$date":"2025-05-30T12:54:31.627+00:00"},"s":"I",  "c":"NETWORK",  "id":51800,   "ctx":"conn38","msg":"client metadata","attr":{"remote":"172.20.0.1:50093","client":"conn38","negotiatedCompressors":[],"doc":{"driver":{"name":"mongo-java-driver|sync","version":"5.1.4"},"os":{"type":"Linux","name":"Linux","architecture":"amd64","version":"6.8.0-60-lowlatency"},"platform":"Java/Eclipse Adoptium/24.0.1+9","env":{"container":{"orchestrator":"kubernetes"}}}}}
2025-05-30 12:54:31.628:INFO :omd.cluster:cluster-ClusterId{value='6839aa872892733ba48f2044', description='null'}-10.0.1.1:40785: Monitor thread successfully connected to server with description ServerDescription{address=10.0.1.1:40785, type=REPLICA_SET_PRIMARY, state=CONNECTED, ok=true, minWireVersion=0, maxWireVersion=13, maxDocumentSize=16777216, logicalSessionTimeoutMinutes=30, roundTripTimeNanos=1203653, setName='docker-rs', canonicalAddress=7c864b9e1144:27017, hosts=[7c864b9e1144:27017], passives=[], arbiters=[], primary='7c864b9e1144:27017', tagSet=TagSet{[]}, electionId=7fffffff0000000000000001, setVersion=1, topologyVersion=TopologyVersion{processId=6839aa85917856d77c0b0cb3, counter=6}, lastWriteDate=Fri May 30 12:54:31 GMT 2025, lastUpdateTimeNanos=1225480396837444}
2025-05-30 12:54:31.628:INFO :oejnm.MongoLogs:docker-java-stream--1700030093: STDOUT: {"t":{"$date":"2025-05-30T12:54:31.628+00:00"},"s":"I",  "c":"NETWORK",  "id":22943,   "ctx":"listener","msg":"Connection accepted","attr":{"remote":"172.20.0.1:28275","uuid":"a6e05f1c-d54e-4eeb-87f5-795af29dc80e","connectionId":39,"connectionCount":29}}
2025-05-30 12:54:31.629:INFO :oejnm.MongoLogs:docker-java-stream--1700030093: STDOUT: {"t":{"$date":"2025-05-30T12:54:31.629+00:00"},"s":"I",  "c":"NETWORK",  "id":51800,   "ctx":"conn39","msg":"client metadata","attr":{"remote":"172.20.0.1:28275","client":"conn39","negotiatedCompressors":[],"doc":{"driver":{"name":"mongo-java-driver|sync","version":"5.1.4"},"os":{"type":"Linux","name":"Linux","architecture":"amd64","version":"6.8.0-60-lowlatency"},"platform":"Java/Eclipse Adoptium/24.0.1+9","env":{"container":{"orchestrator":"kubernetes"}}}}}
2025-05-30 12:54:31.629:INFO :oejnm.MongoLogs:docker-java-stream--1700030093: STDOUT: {"t":{"$date":"2025-05-30T12:54:31.629+00:00"},"s":"I",  "c":"NETWORK",  "id":22943,   "ctx":"listener","msg":"Connection accepted","attr":{"remote":"172.20.0.1:3844","uuid":"b54bf59d-61e4-4752-ae6a-9e7b9a73eac6","connectionId":40,"connectionCount":30}}
etc.
  • [ ] Abnormal websocket close
Running 2025-05-30 12:54:52.689:INFO :oejs.Server:ForkJoinPool-1-worker-4: Stopped oejs.Server@558c13ae{STOPPING}[12.1.0-SNAPSHOT,sto=1000]
org.eclipse.jetty.ee11.websocket.jakarta.tests.server.examples.WebSocketServerExamplesTest.testMyAuthedSocket()
2025-05-30 12:54:52.687:WARN :oejewjt.CloseInOnOpenTest$ClosingListener:qtp1159413497-52: Unhandled Error: org.eclipse.jetty.ee11.websocket.jakarta.tests.CloseInOnOpenTest$ClosingListener@39fb76ff
org.eclipse.jetty.websocket.core.exception.CloseException: Abnormal Close
	at org.eclipse.jetty.ee11.websocket.jakarta.common.JakartaWebSocketSession.lambda$close$0(JakartaWebSocketSession.java:198)
	at org.eclipse.jetty.util.Callback$5.completed(Callback.java:259)
	at org.eclipse.jetty.util.Callback$Completing.succeeded(Callback.java:586)
...

that will do for now...

gregw avatar Jun 01 '25 22:06 gregw