Le 25/11/2023 à 16:05, Marno Krahmer a écrit :
Hey Christopher,
thanks a lot for the config snipped.
"Luckily" the issue appeared again, shortly after applied the config.
So here is the output of the ring file:
<0>2023-11-25T14:41:41.050846+00:00 [01|h1|0|mux_h1.c:4377] reporting error to
the app-layer stream : [F,RUN] [MSG_DONE, MSG_DONE] - req=(.fl=0x00001550
.curr_len=0 .body_len=0) res=(.fl=0x00001515 .curr_len=0 .body_len=0) -
h1c=0x7f50672668f0(0x00000200) conn=0x7f5043245e10(0x801c0300)
h1s=0x7f506722a2f0(0x00014010) sd=0x7f50672387e0(0x04014001)
sc=0x7f506722d890(0x00014422)
<0>2023-11-25T14:56:31.907270+00:00 [05|h1|0|mux_h1.c:3129] processing error :
[B,RUN] [MSG_DONE, MSG_RPBEFORE] - req=(.fl=0x00001511 .curr_len=0
.body_len=2352560) res=(.fl=0x00001404 .curr_len=0 .body_len=0) -
h1c=0x7f503b255a70(0x80000000) conn=0x7f5043c78f10(0x00000300)
h1s=0x7f503b252e40(0x00015040) sd=0x7f503b239f90(0x05010001)
sc=0x7f503b233260(0x00000401)
<0>2023-11-25T14:56:32.998177+00:00 [05|h1|0|mux_h1.c:3129] processing error :
[B,RUN] [MSG_DONE, MSG_DONE] - req=(.fl=0x00001511 .curr_len=0
.body_len=2501993) res=(.fl=0x00001535 .curr_len=0 .body_len=0) -
h1c=0x7f503b256620(0x80000100) conn=0x7f506722ed10(0x00040300)
h1s=0x7f503b23afa0(0x00015040) sd=0x7f503b23bf30(0x0101c001)
sc=0x7f503b234240(0x00040003)
<0>2023-11-25T14:56:32.998187+00:00 [05|h1|0|mux_h1.c:3169] txn done but data
waiting to be sent, set error on h1c : [B,RUN] [MSG_DONE, MSG_DONE] -
req=(.fl=0x00001511 .curr_len=0 .body_len=2501993) res=(.fl=0x00001535
.curr_len=0 .body_len=0) - h1c=0x7f503b256620(0x80000100)
conn=0x7f506722ed10(0x00040300) h1s=0x7f503b23afa0(0x00015040)
sd=0x7f503b23bf30(0x0101c001) sc=0x7f503b234240(0x00040003)
<0>2023-11-25T14:56:33.052598+00:00 [05|h1|0|mux_h1.c:3129] processing error :
[B,RUN] [MSG_DONE, MSG_RPBEFORE] - req=(.fl=0x00001511 .curr_len=0
.body_len=2551166) res=(.fl=0x00001404 .curr_len=0 .body_len=0) -
h1c=0x7f503b232eb0(0x80000000) conn=0x7f504723e230(0x00000300)
h1s=0x7f503b23b1b0(0x00015040) sd=0x7f503b22ea00(0x05010001)
sc=0x7f503b2420d0(0x00000401)
<0>2023-11-25T14:56:33.651131+00:00 [05|h1|0|mux_h1.c:3129] processing error :
[B,RUN] [MSG_DONE, MSG_DONE] - req=(.fl=0x00001511 .curr_len=0
.body_len=2618038) res=(.fl=0x00001535 .curr_len=0 .body_len=0) -
h1c=0x7f503b22a6e0(0x80000100) conn=0x7f504b22c3e0(0x00040300)
h1s=0x7f503b252d70(0x00015040) sd=0x7f503b22b5e0(0x0101c001)
sc=0x7f503b229720(0x00040003)
<0>2023-11-25T14:56:33.651139+00:00 [05|h1|0|mux_h1.c:3169] txn done but data
waiting to be sent, set error on h1c : [B,RUN] [MSG_DONE, MSG_DONE] -
req=(.fl=0x00001511 .curr_len=0 .body_len=2618038) res=(.fl=0x00001535
.curr_len=0 .body_len=0) - h1c=0x7f503b22a6e0(0x80000100)
conn=0x7f504b22c3e0(0x00040300) h1s=0x7f503b252d70(0x00015040)
sd=0x7f503b22b5e0(0x0101c001) sc=0x7f503b229720(0x00040003)
<0>2023-11-25T14:56:33.807314+00:00 [05|h1|0|mux_h1.c:3129] processing error :
[B,RUN] [MSG_DONE, MSG_RPBEFORE] - req=(.fl=0x00001511 .curr_len=0
.body_len=2697148) res=(.fl=0x00001404 .curr_len=0 .body_len=0) -
h1c=0x7f503b22cdb0(0x80000000) conn=0x7f5033249120(0x00000300)
h1s=0x7f503b241ae0(0x00015040) sd=0x7f503b238d50(0x05010001)
sc=0x7f503b238a30(0x00000401)
<0>2023-11-25T14:56:34.125475+00:00 [05|h1|0|mux_h1.c:3129] processing error :
[B,RUN] [MSG_DONE, MSG_RPBEFORE] - req=(.fl=0x00001511 .curr_len=0
.body_len=2777462) res=(.fl=0x00001404 .curr_len=0 .body_len=0) -
h1c=0x7f503b22ab00(0x80000000) conn=0x7f506725c350(0x00000300)
h1s=0x7f503b22a090(0x00015040) sd=0x7f503b232800(0x05010001)
sc=0x7f503b229640(0x00000401)
<0>2023-11-25T14:56:59.867896+00:00 [05|h1|0|mux_h1.c:3129] processing error :
[B,RUN] [MSG_DONE, MSG_RPBEFORE] - req=(.fl=0x00001511 .curr_len=0
.body_len=31809438) res=(.fl=0x00001404 .curr_len=0 .body_len=0) -
h1c=0x7f503b240ec0(0x80000000) conn=0x7f5043c78da0(0x00000300)
h1s=0x7f503b2571a0(0x00015040) sd=0x7f503b25c4d0(0x05010001)
sc=0x7f503b25c470(0x00000401)
Does that help you to debug further?
In case you need any additional information, feel free to ping me.
Thanks, It was really helpful. I found the bug. The internal error is reported
because the H2 request is announced with a content-length but also contains
trailers. It is valid in H2 but not in H1. In this case, we should ignore the
trailers.
I will fix the issue. It seems older versions are less affected, but it is still
possible to get a processing error.
The fix will be shipped with the 2.9-dev11.
--
Christopher Faulet