Failed: Http2ServerSpec send RST_STREAM if entity stream is canceled

[johanandren]

https://github.com/akka/akka-http/actions/runs/6743966541/job/18332878735#step:8:2185

--> [The Http/2 server implementation should support stream for request entity data (min-collect-strict-entity-bytes = 1) should send RST_STREAM if entity stream is canceled] Start of log messages of test that [Failed(org.scalatest.exceptions.TestFailedException: WINDOW_UPDATE was not equal to RST_STREAM)]
| [DEBUG] [11/03/2023 10:32:54.059] [pool-1-thread-1-ScalaTest-running-Http2ServerSpec] [WithLogCapturing(akka://Http2ServerSpec)] Logging started for test [The Http/2 server implementation should support stream for request entity data (min-collect-strict-entity-bytes = 1) should send RST_STREAM if entity stream is canceled]
| [DEBUG] [11/03/2023 10:32:54.103] [Http2ServerSpec-akka.actor.default-dispatcher-7] [Http2ServerDemux(akka://Http2ServerSpec)] Changing state from Idle to WaitingForNetworkToSendControlFrames
| [DEBUG] [11/03/2023 10:32:54.103] [Http2ServerSpec-akka.actor.default-dispatcher-13] [akka.stream.Log(akka://Http2ServerSpec/system/Materializers/StreamSupervisor-16)] [network-plain-text UP  ] Element:  ByteString(24 bytes)
|  50 52 49 20 2A 20 48 54 54 50 2F 32 2E 30 0D 0A  | PRI * HTTP/2.0..
|  0D 0A 53 4D 0D 0A 0D 0A                          | ..SM....
| [DEBUG] [11/03/2023 10:32:54.103] [Http2ServerSpec-akka.actor.default-dispatcher-8] [Http2ServerDemux(akka://Http2ServerSpec)] Changing state from WaitingForNetworkToSendControlFrames to Idle
| [DEBUG] [11/03/2023 10:32:54.104] [Http2ServerSpec-akka.actor.default-dispatcher-8] [akka.stream.Log(akka://Http2ServerSpec/system/Materializers/StreamSupervisor-16)] [DOWN] Element:    0 SETT  SETTINGS_MAX_CONCURRENT_STREAMS -> 256
| [DEBUG] [11/03/2023 10:32:54.104] [Http2ServerSpec-akka.actor.default-dispatcher-8] [akka.stream.Log(akka://Http2ServerSpec/system/Materializers/StreamSupervisor-16)] [network-plain-text DOWN] Element:  ByteString(15 bytes)
|  00 00 06 04 00 00 00 00 00 00 03 00 00 01 00     | ...............
| [DEBUG] [11/03/2023 10:32:54.104] [Http2ServerSpec-akka.actor.default-dispatcher-7] [akka.stream.Log(akka://Http2ServerSpec/system/Materializers/StreamSupervisor-16)] [network-plain-text UP  ] Element:  ByteString(9 bytes)
|  00 00 00 04 00 00 00 00 00                       | .........
| [DEBUG] [11/03/2023 10:32:54.104] [Http2ServerSpec-akka.actor.default-dispatcher-7] [akka.stream.Log(akka://Http2ServerSpec/system/Materializers/StreamSupervisor-16)] [ UP ] Element:    0 SETT  
| [DEBUG] [11/03/2023 10:32:54.104] [Http2ServerSpec-akka.actor.default-dispatcher-7] [Http2ServerDemux(akka://Http2ServerSpec)] Changing state from Idle to WaitingForNetworkToSendControlFrames
| [DEBUG] [11/03/2023 10:32:54.104] [Http2ServerSpec-akka.actor.default-dispatcher-9] [Http2ServerDemux(akka://Http2ServerSpec)] Changing state from WaitingForNetworkToSendControlFrames to Idle
| [DEBUG] [11/03/2023 10:32:54.104] [Http2ServerSpec-akka.actor.default-dispatcher-9] [akka.stream.Log(akka://Http2ServerSpec/system/Materializers/StreamSupervisor-16)] [DOWN] Element:    0 SETA  
| [DEBUG] [11/03/2023 10:32:54.104] [Http2ServerSpec-akka.actor.default-dispatcher-9] [akka.stream.Log(akka://Http2ServerSpec/system/Materializers/StreamSupervisor-16)] [network-plain-text DOWN] Element:  ByteString(9 bytes)
|  00 00 00 04 01 00 00 00 00                       | .........
| [DEBUG] [11/03/2023 10:32:54.105] [Http2ServerSpec-akka.actor.default-dispatcher-7] [akka.stream.Log(akka://Http2ServerSpec/system/Materializers/StreamSupervisor-16)] [network-plain-text UP  ] Element:  ByteString(36 bytes)
|  00 00 1B 01 04 00 00 00 01 83 87 44 85 62 DA E8  | ...........D.b..
|  38 E4 41 87 1D 35 D0 55 C8 7A 7F 5F 87 A8 F5 15  | 8.A..5.U.z._....
|  8A 8F 51 7F                                      | ..Q.
| [DEBUG] [11/03/2023 10:32:54.105] [Http2ServerSpec-akka.actor.default-dispatcher-7] [akka.stream.Log(akka://Http2ServerSpec/system/Materializers/StreamSupervisor-16)] [ UP ] Element:    1 HEAD  :method -> HttpMethod(POST), :scheme -> https, :path -> (/upload,None), :authority -> ample.com, content-type -> none/none
| [DEBUG] [11/03/2023 10:32:54.105] [Http2ServerSpec-akka.actor.default-dispatcher-7] [Http2ServerDemux(akka://Http2ServerSpec)] Incoming side of stream [1] changed state: Idle -> CollectingIncomingData after handling [handleStreamEvent(ParsedHeadersFrame)]
| [DEBUG] [11/03/2023 10:32:54.105] [Http2ServerSpec-akka.actor.default-dispatcher-9] [akka.stream.Log(akka://Http2ServerSpec/system/Materializers/StreamSupervisor-16)] [network-plain-text UP  ] Element:  ByteString(15 bytes)
|  00 00 06 00 00 00 00 00 01 61 62 63 64 65 66     | .........abcdef
| [DEBUG] [11/03/2023 10:32:54.105] [Http2ServerSpec-akka.actor.default-dispatcher-9] [akka.stream.Log(akka://Http2ServerSpec/system/Materializers/StreamSupervisor-16)] [ UP ] Element:    1 DATA  61 62 63 64 65 66
| [DEBUG] [11/03/2023 10:32:54.105] [Http2ServerSpec-akka.actor.default-dispatcher-9] [Http2ServerDemux(akka://Http2ServerSpec)] Received DATA 6 for stream [1], remaining window space now 65529, buffered: 6
| [DEBUG] [11/03/2023 10:32:54.105] [Http2ServerSpec-akka.actor.default-dispatcher-9] [Http2ServerDemux(akka://Http2ServerSpec)] Changing state from Idle to WaitingForNetworkToSendControlFrames
| [DEBUG] [11/03/2023 10:32:54.105] [Http2ServerSpec-akka.actor.default-dispatcher-9] [Http2ServerDemux(akka://Http2ServerSpec)] Incoming side of stream [1] changed state: CollectingIncomingData -> OpenReceivingDataFirst after handling [handleStreamEvent(DataFrame)]
| [DEBUG] [11/03/2023 10:32:54.116] [Http2ServerSpec-akka.actor.default-dispatcher-7] [Http2ServerDemux(akka://Http2ServerSpec)] Dispatched chunk of 6 for stream [1], remaining window space now 65529, buffered: 0
| [DEBUG] [11/03/2023 10:32:54.116] [Http2ServerSpec-akka.actor.default-dispatcher-7] [Http2ServerDemux(akka://Http2ServerSpec)] adjusting con-level window by 0, stream-level window by 446471, remaining window space now 512000, buffered: 0, remaining connection window space now 9999994, total buffered: 0
| [DEBUG] [11/03/2023 10:32:54.116] [Http2ServerSpec-akka.actor.default-dispatcher-7] [Http2ServerDemux(akka://Http2ServerSpec)] Incoming side of stream [1] changed state: OpenReceivingDataFirst -> OpenReceivingDataFirst after handling [incomingStreamPulled]
| [DEBUG] [11/03/2023 10:32:54.116] [Http2ServerSpec-akka.actor.default-dispatcher-8] [Http2ServerDemux(akka://Http2ServerSpec)] Incoming side of stream [1] changed state: OpenReceivingDataFirst -> OpenReceivingDataFirst after handling [incomingStreamPulled]
| [DEBUG] [11/03/2023 10:32:54.117] [Http2ServerSpec-akka.actor.default-dispatcher-11] [akka.stream.Log(akka://Http2ServerSpec/system/Materializers/StreamSupervisor-16)] [DOWN] Element:    0 WIND  + 9934465
| [DEBUG] [11/03/2023 10:32:54.136] [Http2ServerSpec-akka.actor.default-dispatcher-11] [akka.stream.Log(akka://Http2ServerSpec/system/Materializers/StreamSupervisor-16)] [network-plain-text DOWN] Element:  ByteString(13 bytes)
|  00 00 04 08 00 00 00 00 00 00 97 96 81           | .............
| [DEBUG] [11/03/2023 10:32:54.137] [Http2ServerSpec-akka.actor.default-dispatcher-11] [Http2ServerDemux(akka://Http2ServerSpec)] Changing state from WaitingForNetworkToSendControlFrames to Idle
| [DEBUG] [11/03/2023 10:32:54.137] [Http2ServerSpec-akka.actor.default-dispatcher-11] [akka.stream.Log(akka://Http2ServerSpec/system/Materializers/StreamSupervisor-16)] [DOWN] Element:    1 WIND  + 446471
| [DEBUG] [11/03/2023 10:32:54.137] [Http2ServerSpec-akka.actor.default-dispatcher-11] [akka.stream.Log(akka://Http2ServerSpec/system/Materializers/StreamSupervisor-16)] [network-plain-text DOWN] Element:  ByteString(13 bytes)
|  00 00 04 08 00 00 00 00 01 00 06 D0 07           | .............
| [DEBUG] [11/03/2023 10:32:54.137] [Http2ServerSpec-akka.actor.default-dispatcher-11] [Http2ServerDemux(akka://Http2ServerSpec)] Incoming side of stream [1]: cancelling because downstream finished
| [DEBUG] [11/03/2023 10:32:54.137] [Http2ServerSpec-akka.actor.default-dispatcher-11] [Http2ServerDemux(akka://Http2ServerSpec)] Changing state from Idle to WaitingForNetworkToSendControlFrames
| [DEBUG] [11/03/2023 10:32:54.138] [pool-1-thread-1-ScalaTest-running-Http2ServerSpec] [WithLogCapturing(akka://Http2ServerSpec)] Logging finished for test [The Http/2 server implementation should support stream for request entity data (min-collect-strict-entity-bytes = 1) should send RST_STREAM if entity stream is canceled]
<-- [The Http/2 server implementation should support stream for request entity data (min-collect-strict-entity-bytes = 1) should send RST_STREAM if entity stream is canceled] End of log messages of test that [Failed(org.scalatest.exceptions.TestFailedException: WINDOW_UPDATE was not equal to RST_STREAM)]
[info]   - should send RST_STREAM if entity stream is canceled *** FAILED *** (108 milliseconds)
[info]     WINDOW_UPDATE was not equal to RST_STREAM (Http2FrameProbe.scala:208)
[info]     org.scalatest.exceptions.TestFailedException:
[info]     at org.scalatest.matchers.MatchersHelper$.indicateFailure(MatchersHelper.scala:392)
[info]     at org.scalatest.matchers.should.Matchers.shouldBe(Matchers.scala:6985)
[info]     at org.scalatest.matchers.should.Matchers.shouldBe$(Matchers.scala:1808)
[info]     at akka.http.impl.engine.http2.Http2FrameProbe$.shouldBe(Http2FrameProbe.scala:106)
[info]     at akka.http.impl.engine.http2.Http2FrameProbe$$anon$1.expectFrameFlagsStreamIdAndPayload(Http2FrameProbe.scala:208)
[info]     at akka.http.impl.engine.http2.Http2FrameProbe$$anon$1.expectFrameFlagsAndPayload(Http2FrameProbe.scala:202)
[info]     at akka.http.impl.engine.http2.Http2FrameProbe$$anon$1.expectFramePayload(Http2FrameProbe.scala:197)
[info]     at akka.http.impl.engine.http2.Http2FrameProbe$$anon$1.expectRST_STREAM(Http2FrameProbe.scala:165)
[info]     at akka.http.impl.engine.http2.Http2FrameProbe$$anon$1.expectRST_STREAM(Http2FrameProbe.scala:162)
[info]     at akka.http.impl.engine.http2.Http2FrameProbeDelegator.expectRST_STREAM(Http2FrameProbe.scala:87)
[info]     at akka.http.impl.engine.http2.Http2FrameProbeDelegator.expectRST_STREAM$(Http2FrameProbe.scala:74)
[info]     at akka.http.impl.engine.http2.Http2ServerSpec$NetworkSide.expectRST_STREAM(Http2ServerSpec.scala:1760)
[info]     at akka.http.impl.engine.http2.Http2ServerSpec$$anon$19.<init>(Http2ServerSpec.scala:454)
[info]     at akka.http.impl.engine.http2.Http2ServerSpec.requestTests$1$$anonfun$1$$anonfun$8(Http2ServerSpec.scala:455)
[info]     at akka.http.impl.engine.http2.Http2ServerSpec.f$proxy22$1$$anonfun$1(Http2ServerSpec.scala:1721)
[info]     at akka.http.impl.engine.http2.Http2ServerSpec.akka$http$impl$engine$http2$Http2ServerSpec$InWithStoppedStages$$_$f$proxy22$1$$anonfun$adapted$1(Http2ServerSpec.scala:1728)