nginx/nginx

"HTTP/2 000" response when upstream returns invalid status line

オープン

#378 opened on 2024/12/10

GitHub で見るコントリビューターガイド
 (3 件のコメント) (2 件のリアクション) (0 人の担当者)C (7,903 件のフォーク)batch import
buggood first issue

Repository metrics

Stars
 (30,331 個のスター)
PR merge metrics
 (平均マージ 27d 8h) (30d で 17 merged PRs)

説明

Environment

# nginx-debug -V
nginx version: nginx/1.27.3
built by gcc 12.2.0 (Debian 12.2.0-14) 
built with OpenSSL 3.0.11 19 Sep 2023 (running with OpenSSL 3.0.15 3 Sep 2024)
TLS SNI support enabled
configure arguments: --prefix=/etc/nginx --sbin-path=/usr/sbin/nginx --modules-path=/usr/lib/nginx/modules --conf-path=/etc/nginx/nginx.conf --error-log-path=/var/log/nginx/error.log --http-log-path=/var/log/nginx/access.log --pid-path=/var/run/nginx.pid --lock-path=/var/run/nginx.lock --http-client-body-temp-path=/var/cache/nginx/client_temp --http-proxy-temp-path=/var/cache/nginx/proxy_temp --http-fastcgi-temp-path=/var/cache/nginx/fastcgi_temp --http-uwsgi-temp-path=/var/cache/nginx/uwsgi_temp --http-scgi-temp-path=/var/cache/nginx/scgi_temp --user=nginx --group=nginx --with-compat --with-file-aio --with-threads --with-http_addition_module --with-http_auth_request_module --with-http_dav_module --with-http_flv_module --with-http_gunzip_module --with-http_gzip_static_module --with-http_mp4_module --with-http_random_index_module --with-http_realip_module --with-http_secure_link_module --with-http_slice_module --with-http_ssl_module --with-http_stub_status_module --with-http_sub_module --with-http_v2_module --with-http_v3_module --with-mail --with-mail_ssl_module --with-stream --with-stream_realip_module --with-stream_ssl_module --with-stream_ssl_preread_module --with-cc-opt='-g -O2 -ffile-prefix-map=/data/builder/debuild/nginx-1.27.3/debian/debuild-base/nginx-1.27.3=. -fstack-protector-strong -Wformat -Werror=format-security -Wp,-D_FORTIFY_SOURCE=2 -fPIC' --with-ld-opt='-Wl,-z,relro -Wl,-z,now -Wl,--as-needed -pie' --with-debug

# uname -a
Linux ef492c1932a8 6.11.5+bpo-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.11.5-1~bpo12+1 (2024-11-11) x86_64 GNU/Linux

Description

Actual behavior Nginx returns HTTP/2 000 if the upstream server sends an invalid status line. After 60 seconds (or the configured proxy_read_timeout value) Nginx sends a RST_STREAM frame to the client.

Expected behavior Nginx returns a 502 error when the upstream server sends an invalid response.

Details

  • The upstream server returns an invalid status line, for example HTTP/1.1 404: Not Found (unexpected : after the status code).
  • Nginx detects the invalid data (upstream sent no valid HTTP/1.0 header while reading response header from upstream) and sets the request HTTP version to NGX_HTTP_VERSION_9.
  • Just a guess: because headers like content-length from the upstream are not parsed (headers are not defined in HTTP/0.9), Nginx doesn't know when the response from the upstream ends and it waits for data for proxy_read_timeout seconds.
  • The request is logged with 009 status code ("GET / HTTP/2.0" 009 0 "-" "curl/7.88.1").

Test setup

  • Nginx listening on 172.19.0.2:80.
  • Upstream server on 172.19.0.1:8888.
  • curl test command:
$ time curl 172.19.0.2 -v --http2-prior-knowledge
*   Trying 172.19.0.2:80...
* Connected to 172.19.0.2 (172.19.0.2) port 80 (#0)
* h2h3 [:method: GET]
* h2h3 [:path: /]
* h2h3 [:scheme: http]
* h2h3 [:authority: 172.19.0.2]
* h2h3 [user-agent: curl/7.88.1]
* h2h3 [accept: */*]
* Using Stream ID: 1 (easy handle 0x55a1b56f3ce0)
> GET / HTTP/2
> Host: 172.19.0.2
> user-agent: curl/7.88.1
> accept: */*
> 
< HTTP/2 000 
< server: nginx/1.27.3
< date: Tue, 10 Dec 2024 08:14:44 GMT
< 
* HTTP/2 stream 1 was not closed cleanly: INTERNAL_ERROR (err 2)
* Connection #0 to host 172.19.0.2 left intact
curl: (92) HTTP/2 stream 1 was not closed cleanly: INTERNAL_ERROR (err 2)

real    1m0.065s
user    0m0.008s
sys     0m0.000s
  • The bug is reproducible with the latest version of nginx
  • The nginx configuration is minimized to the smallest possible to reproduce the issue and doesn't contain third-party modules

nginx configuration

events {
}

http {
    server {
        error_log stderr debug;
        listen 80;
        http2 on;
        location / {
            proxy_pass http://172.19.0.1:8888;
            proxy_http_version 1.1;
        }
    }
}

nginx debug log

2024/12/10 08:14:41 [debug] 9#9: epoll add event: fd:6 op:1 ev:00002001
2024/12/10 08:14:44 [debug] 9#9: accept on 0.0.0.0:80, ready: 0
2024/12/10 08:14:44 [debug] 9#9: posix_memalign: 000056517A0F8FD0:512 @16
2024/12/10 08:14:44 [debug] 9#9: *1 accept: 172.19.0.1:45682 fd:3
2024/12/10 08:14:44 [debug] 9#9: *1 event timer add: 3: 60000:29087477
2024/12/10 08:14:44 [debug] 9#9: *1 reusable connection: 1
2024/12/10 08:14:44 [debug] 9#9: *1 epoll add event: fd:3 op:1 ev:80002001
2024/12/10 08:14:44 [debug] 9#9: *1 http wait request handler
2024/12/10 08:14:44 [debug] 9#9: *1 malloc: 000056517A0F91E0:1024
2024/12/10 08:14:44 [debug] 9#9: *1 recv: eof:0, avail:-1
2024/12/10 08:14:44 [debug] 9#9: *1 recv: fd:3 100 of 1024
2024/12/10 08:14:44 [debug] 9#9: *1 init http2 connection
2024/12/10 08:14:44 [debug] 9#9: *1 malloc: 000056517A0F35E0:456
2024/12/10 08:14:44 [debug] 9#9: *1 posix_memalign: 000056517A1076E0:4096 @16
2024/12/10 08:14:44 [debug] 9#9: *1 add cleanup: 000056517A0F9158
2024/12/10 08:14:44 [debug] 9#9: *1 posix_memalign: 000056517A0F6990:512 @16
2024/12/10 08:14:44 [debug] 9#9: *1 http2 send SETTINGS frame
2024/12/10 08:14:44 [debug] 9#9: *1 http2 send WINDOW_UPDATE frame sid:0, window:2147418112
2024/12/10 08:14:44 [debug] 9#9: *1 reusable connection: 0
2024/12/10 08:14:44 [debug] 9#9: *1 http2 preface verified
2024/12/10 08:14:44 [debug] 9#9: *1 http2 frame type:4 f:0 l:18 sid:0
2024/12/10 08:14:44 [debug] 9#9: *1 http2 SETTINGS frame
2024/12/10 08:14:44 [debug] 9#9: *1 http2 setting 3:100
2024/12/10 08:14:44 [debug] 9#9: *1 http2 setting 4:33554432
2024/12/10 08:14:44 [debug] 9#9: *1 http2 setting 2:0
2024/12/10 08:14:44 [debug] 9#9: *1 http2 frame complete pos:000056517A0F9213 end:000056517A0F9244
2024/12/10 08:14:44 [debug] 9#9: *1 http2 frame type:8 f:0 l:4 sid:0
2024/12/10 08:14:44 [debug] 9#9: *1 http2 WINDOW_UPDATE frame sid:0 window:33488897
2024/12/10 08:14:44 [debug] 9#9: *1 http2 frame complete pos:000056517A0F9220 end:000056517A0F9244
2024/12/10 08:14:44 [debug] 9#9: *1 http2 frame type:1 f:5 l:27 sid:1
2024/12/10 08:14:44 [debug] 9#9: *1 http2 HEADERS frame sid:1 depends on 0 excl:0 weight:16
2024/12/10 08:14:44 [debug] 9#9: *1 posix_memalign: 000056517A1086F0:1024 @16
2024/12/10 08:14:44 [debug] 9#9: *1 posix_memalign: 000056517A0FDEB0:4096 @16
2024/12/10 08:14:44 [debug] 9#9: *1 posix_memalign: 000056517A0FEEC0:4096 @16
2024/12/10 08:14:44 [debug] 9#9: *1 event timer del: 3: 29087477
2024/12/10 08:14:44 [debug] 9#9: *1 http2 get indexed header: 2
2024/12/10 08:14:44 [debug] 9#9: *1 http2 header: ":method: GET"
2024/12/10 08:14:44 [debug] 9#9: *1 http2 get indexed header: 4
2024/12/10 08:14:44 [debug] 9#9: *1 http uri: "/"
2024/12/10 08:14:44 [debug] 9#9: *1 http args: ""
2024/12/10 08:14:44 [debug] 9#9: *1 http exten: ""
2024/12/10 08:14:44 [debug] 9#9: *1 http2 header: ":path: /"
2024/12/10 08:14:44 [debug] 9#9: *1 http2 get indexed header: 6
2024/12/10 08:14:44 [debug] 9#9: *1 http2 header: ":scheme: http"
2024/12/10 08:14:44 [debug] 9#9: *1 http2 get indexed name: 1
2024/12/10 08:14:44 [debug] 9#9: *1 http2 encoded string, len:7
2024/12/10 08:14:44 [debug] 9#9: *1 http2 table add: ":authority: 172.19.0.2"
2024/12/10 08:14:44 [debug] 9#9: *1 malloc: 000056517A0E5F30:512
2024/12/10 08:14:44 [debug] 9#9: *1 malloc: 000056517A0FFED0:4096
2024/12/10 08:14:44 [debug] 9#9: *1 http2 table account: 52 free:4096
2024/12/10 08:14:44 [debug] 9#9: *1 http2 header: ":authority: 172.19.0.2"
2024/12/10 08:14:44 [debug] 9#9: *1 http2 get indexed name: 58
2024/12/10 08:14:44 [debug] 9#9: *1 http2 encoded string, len:8
2024/12/10 08:14:44 [debug] 9#9: *1 http2 table add: "user-agent: curl/7.88.1"
2024/12/10 08:14:44 [debug] 9#9: *1 http2 table account: 53 free:4044
2024/12/10 08:14:44 [debug] 9#9: *1 http2 header: "user-agent: curl/7.88.1"
2024/12/10 08:14:44 [debug] 9#9: *1 http2 get indexed name: 19
2024/12/10 08:14:44 [debug] 9#9: *1 http2 raw string, len:3
2024/12/10 08:14:44 [debug] 9#9: *1 http2 table add: "accept: */*"
2024/12/10 08:14:44 [debug] 9#9: *1 http2 table account: 41 free:3991
2024/12/10 08:14:44 [debug] 9#9: *1 http2 header: "accept: */*"
2024/12/10 08:14:44 [debug] 9#9: *1 http2 request line: "GET / HTTP/2.0"
2024/12/10 08:14:44 [debug] 9#9: *1 generic phase: 0
2024/12/10 08:14:44 [debug] 9#9: *1 rewrite phase: 1
2024/12/10 08:14:44 [debug] 9#9: *1 test location: "/"
2024/12/10 08:14:44 [debug] 9#9: *1 using configuration "/"
2024/12/10 08:14:44 [debug] 9#9: *1 http cl:-1 max:1048576
2024/12/10 08:14:44 [debug] 9#9: *1 rewrite phase: 3
2024/12/10 08:14:44 [debug] 9#9: *1 post rewrite phase: 4
2024/12/10 08:14:44 [debug] 9#9: *1 generic phase: 5
2024/12/10 08:14:44 [debug] 9#9: *1 generic phase: 6
2024/12/10 08:14:44 [debug] 9#9: *1 generic phase: 7
2024/12/10 08:14:44 [debug] 9#9: *1 access phase: 8
2024/12/10 08:14:44 [debug] 9#9: *1 access phase: 9
2024/12/10 08:14:44 [debug] 9#9: *1 access phase: 10
2024/12/10 08:14:44 [debug] 9#9: *1 post access phase: 11
2024/12/10 08:14:44 [debug] 9#9: *1 generic phase: 12
2024/12/10 08:14:44 [debug] 9#9: *1 generic phase: 13
2024/12/10 08:14:44 [debug] 9#9: *1 http init upstream, client timer: 0
2024/12/10 08:14:44 [debug] 9#9: *1 post event 000056517A107A40
2024/12/10 08:14:44 [debug] 9#9: *1 http script copy: "Host"
2024/12/10 08:14:44 [debug] 9#9: *1 http script var: "172.19.0.1:8888"
2024/12/10 08:14:44 [debug] 9#9: *1 http script copy: "Connection"
2024/12/10 08:14:44 [debug] 9#9: *1 http script copy: "close"
2024/12/10 08:14:44 [debug] 9#9: *1 http script copy: ""
2024/12/10 08:14:44 [debug] 9#9: *1 http script copy: ""
2024/12/10 08:14:44 [debug] 9#9: *1 http proxy header: "user-agent: curl/7.88.1"
2024/12/10 08:14:44 [debug] 9#9: *1 http proxy header: "accept: */*"
2024/12/10 08:14:44 [debug] 9#9: *1 http proxy header:
"GET / HTTP/1.1
Host: 172.19.0.1:8888
Connection: close
user-agent: curl/7.88.1
accept: */*

"
2024/12/10 08:14:44 [debug] 9#9: *1 http cleanup add: 000056517A0FFD48
2024/12/10 08:14:44 [debug] 9#9: *1 get rr peer, try: 1
2024/12/10 08:14:44 [debug] 9#9: *1 stream socket 11
2024/12/10 08:14:44 [debug] 9#9: *1 epoll add connection: fd:11 ev:80002005
2024/12/10 08:14:44 [debug] 9#9: *1 connect to 172.19.0.1:8888, fd:11 #2
2024/12/10 08:14:44 [debug] 9#9: *1 http upstream connect: -2
2024/12/10 08:14:44 [debug] 9#9: *1 posix_memalign: 000056517A148320:128 @16
2024/12/10 08:14:44 [debug] 9#9: *1 event timer add: 11: 60000:29087477
2024/12/10 08:14:44 [debug] 9#9: *1 http finalize request: -4, "/?" a:1, c:2
2024/12/10 08:14:44 [debug] 9#9: *1 http request count:2 blk:0
2024/12/10 08:14:44 [debug] 9#9: *1 http2 frame complete pos:000056517A0F9244 end:000056517A0F9244
2024/12/10 08:14:44 [debug] 9#9: *1 http2 read handler
2024/12/10 08:14:44 [debug] 9#9: *1 recv: eof:0, avail:0
2024/12/10 08:14:44 [debug] 9#9: *1 http2 frame out: 000056517A107898 sid:0 bl:0 len:0
2024/12/10 08:14:44 [debug] 9#9: *1 http2 frame out: 000056517A1077E8 sid:0 bl:0 len:4
2024/12/10 08:14:44 [debug] 9#9: *1 http2 frame out: 000056517A107730 sid:0 bl:0 len:18
2024/12/10 08:14:44 [debug] 9#9: *1 writev: 49 of 49
2024/12/10 08:14:44 [debug] 9#9: *1 tcp_nodelay
2024/12/10 08:14:44 [debug] 9#9: *1 http2 frame sent: 000056517A107730 sid:0 bl:0 len:18
2024/12/10 08:14:44 [debug] 9#9: *1 http2 frame sent: 000056517A1077E8 sid:0 bl:0 len:4
2024/12/10 08:14:44 [debug] 9#9: *1 http2 frame sent: 000056517A107898 sid:0 bl:0 len:0
2024/12/10 08:14:44 [debug] 9#9: *1 delete posted event 000056517A107A40
2024/12/10 08:14:44 [debug] 9#9: *1 http run request: "/?"
2024/12/10 08:14:44 [debug] 9#9: *1 http upstream check client, write event:0, "/"
2024/12/10 08:14:44 [debug] 9#9: *1 http upstream request: "/?"
2024/12/10 08:14:44 [debug] 9#9: *1 http upstream send request handler
2024/12/10 08:14:44 [debug] 9#9: *1 http upstream send request
2024/12/10 08:14:44 [debug] 9#9: *1 http upstream send request body
2024/12/10 08:14:44 [debug] 9#9: *1 chain writer buf fl:1 s:98
2024/12/10 08:14:44 [debug] 9#9: *1 chain writer in: 000056517A0FFD88
2024/12/10 08:14:44 [debug] 9#9: *1 writev: 98 of 98
2024/12/10 08:14:44 [debug] 9#9: *1 chain writer out: 0000000000000000
2024/12/10 08:14:44 [debug] 9#9: *1 event timer del: 11: 29087477
2024/12/10 08:14:44 [debug] 9#9: *1 event timer add: 11: 60000:29087477
2024/12/10 08:14:44 [debug] 9#9: *1 http2 read handler
2024/12/10 08:14:44 [debug] 9#9: *1 recv: eof:0, avail:-1
2024/12/10 08:14:44 [debug] 9#9: *1 recv: fd:3 9 of 262128
2024/12/10 08:14:44 [debug] 9#9: *1 http2 frame type:4 f:1 l:0 sid:0
2024/12/10 08:14:44 [debug] 9#9: *1 http2 SETTINGS frame
2024/12/10 08:14:44 [debug] 9#9: *1 http2 frame complete pos:00007FA135E17019 end:00007FA135E17019
2024/12/10 08:14:44 [debug] 9#9: *1 http upstream request: "/?"
2024/12/10 08:14:44 [debug] 9#9: *1 http upstream process header
2024/12/10 08:14:44 [debug] 9#9: *1 malloc: 000056517A18E160:4096
2024/12/10 08:14:44 [debug] 9#9: *1 posix_memalign: 000056517A18F170:4096 @16
2024/12/10 08:14:44 [debug] 9#9: *1 recv: eof:0, avail:-1
2024/12/10 08:14:44 [debug] 9#9: *1 recv: fd:11 65 of 4096
2024/12/10 08:14:44 [error] 9#9: *1 upstream sent no valid HTTP/1.0 header while reading response header from upstream, client: 172.19.0.1, server: , request: "GET / HTTP/2.0", upstream: "http://172.19.0.1:8888/", host: "172.19.0.2"
2024/12/10 08:14:44 [debug] 9#9: *1 http2 header filter
2024/12/10 08:14:44 [debug] 9#9: *1 http2 output header: ":status: 000"
2024/12/10 08:14:44 [debug] 9#9: *1 http2 output header: "server: nginx/1.27.3"
2024/12/10 08:14:44 [debug] 9#9: *1 http2 output header: "date: Tue, 10 Dec 2024 08:14:44 GMT"
2024/12/10 08:14:44 [debug] 9#9: *1 http2:1 create HEADERS frame 000056517A0FFE80: len:40 fin:0
2024/12/10 08:14:44 [debug] 9#9: *1 http cleanup add: 000056517A18F410
2024/12/10 08:14:44 [debug] 9#9: *1 http2 frame out: 000056517A0FFE80 sid:1 bl:1 len:40
2024/12/10 08:14:44 [debug] 9#9: *1 writev: 49 of 49
2024/12/10 08:14:44 [debug] 9#9: *1 http2:1 HEADERS frame 000056517A0FFE80 was sent
2024/12/10 08:14:44 [debug] 9#9: *1 http2 frame sent: 000056517A0FFE80 sid:1 bl:1 len:40
2024/12/10 08:14:44 [debug] 9#9: *1 http cacheable: 0
2024/12/10 08:14:44 [debug] 9#9: *1 http proxy filter init s:0 h:0 c:0 l:-1
2024/12/10 08:14:44 [debug] 9#9: *1 http upstream process upstream
2024/12/10 08:14:44 [debug] 9#9: *1 pipe read upstream: 0
2024/12/10 08:14:44 [debug] 9#9: *1 pipe preread: 65
2024/12/10 08:14:44 [debug] 9#9: *1 pipe buf free s:0 t:1 f:0 000056517A18E160, pos 000056517A18E160, size: 65 file: 0, size: 0
2024/12/10 08:14:44 [debug] 9#9: *1 pipe length: -1
2024/12/10 08:14:44 [debug] 9#9: *1 pipe write downstream: 1
2024/12/10 08:14:44 [debug] 9#9: *1 pipe write busy: 0
2024/12/10 08:14:44 [debug] 9#9: *1 pipe write: out:0000000000000000, f:0
2024/12/10 08:14:44 [debug] 9#9: *1 pipe read upstream: 0
2024/12/10 08:14:44 [debug] 9#9: *1 pipe buf free s:0 t:1 f:0 000056517A18E160, pos 000056517A18E160, size: 65 file: 0, size: 0
2024/12/10 08:14:44 [debug] 9#9: *1 pipe length: -1
2024/12/10 08:14:44 [debug] 9#9: *1 event timer: 11, old: 29087477, new: 29087477
2024/12/10 08:14:44 [debug] 9#9: *1 http upstream request: "/?"
2024/12/10 08:14:44 [debug] 9#9: *1 http upstream dummy handler
2024/12/10 08:15:44 [debug] 9#9: *1 event timer del: 11: 29087477
2024/12/10 08:15:44 [debug] 9#9: *1 http upstream request: "/?"
2024/12/10 08:15:44 [debug] 9#9: *1 http upstream process upstream
2024/12/10 08:15:44 [error] 9#9: *1 upstream timed out (110: Connection timed out) while reading upstream, client: 172.19.0.1, server: , request: "GET / HTTP/2.0", upstream: "http://172.19.0.1:8888/", host: "172.19.0.2"
2024/12/10 08:15:44 [debug] 9#9: *1 http upstream exit: 0000000000000000
2024/12/10 08:15:44 [debug] 9#9: *1 finalize http upstream request: 502
2024/12/10 08:15:44 [debug] 9#9: *1 finalize http proxy request
2024/12/10 08:15:44 [debug] 9#9: *1 free rr peer 1 0
2024/12/10 08:15:44 [debug] 9#9: *1 close http upstream connection: 11
2024/12/10 08:15:44 [debug] 9#9: *1 free: 000056517A148320, unused: 48
2024/12/10 08:15:44 [debug] 9#9: *1 reusable connection: 0
2024/12/10 08:15:44 [debug] 9#9: *1 http upstream temp fd: -1
2024/12/10 08:15:44 [debug] 9#9: *1 http output filter "/?"
2024/12/10 08:15:44 [debug] 9#9: *1 http copy filter: "/?"
2024/12/10 08:15:44 [debug] 9#9: *1 http postpone filter "/?" 00007FFE21EBEA20
2024/12/10 08:15:44 [debug] 9#9: *1 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
2024/12/10 08:15:44 [debug] 9#9: *1 http write filter: l:0 f:1 s:0
2024/12/10 08:15:44 [debug] 9#9: *1 http write filter limit 2097152
2024/12/10 08:15:44 [debug] 9#9: *1 http2 send chain: 000056517A18F620
2024/12/10 08:15:44 [debug] 9#9: *1 http write filter 0000000000000000
2024/12/10 08:15:44 [debug] 9#9: *1 http copy filter: 0 "/?"
2024/12/10 08:15:44 [debug] 9#9: *1 http finalize request: 0, "/?" a:1, c:1
2024/12/10 08:15:44 [debug] 9#9: *1 http request count:1 blk:0
2024/12/10 08:15:44 [debug] 9#9: *1 http2 close stream 1, queued 0, processing 1
2024/12/10 08:15:44 [debug] 9#9: *1 http2 send RST_STREAM frame sid:1, status:2
2024/12/10 08:15:44 [debug] 9#9: *1 http close request
2024/12/10 08:15:44 [debug] 9#9: *1 http log handler
172.19.0.1 - - [10/Dec/2024:08:15:44 +0000] "GET / HTTP/2.0" 009 0 "-" "curl/7.88.1"
2024/12/10 08:15:44 [debug] 9#9: *1 free: 000056517A18E160
2024/12/10 08:15:44 [debug] 9#9: *1 free: 000056517A0FDEB0, unused: 0
2024/12/10 08:15:44 [debug] 9#9: *1 free: 000056517A0FEEC0, unused: 8
2024/12/10 08:15:44 [debug] 9#9: *1 free: 000056517A18F170, unused: 2759
2024/12/10 08:15:44 [debug] 9#9: *1 free: 000056517A1086F0, unused: 915
2024/12/10 08:15:44 [debug] 9#9: *1 post event 000056517A176200
2024/12/10 08:15:44 [debug] 9#9: *1 delete posted event 000056517A176200
2024/12/10 08:15:44 [debug] 9#9: *1 http2 handle connection handler
2024/12/10 08:15:44 [debug] 9#9: *1 http2 frame out: 000056517A107898 sid:0 bl:0 len:4
2024/12/10 08:15:44 [debug] 9#9: *1 writev: 13 of 13
2024/12/10 08:15:44 [debug] 9#9: *1 http2 frame sent: 000056517A107898 sid:0 bl:0 len:4
2024/12/10 08:15:44 [debug] 9#9: *1 event timer add: 3: 75000:29162536
2024/12/10 08:15:44 [debug] 9#9: *1 reusable connection: 1
2024/12/10 08:15:44 [debug] 9#9: *1 free: 000056517A1076E0, unused: 2936
2024/12/10 08:15:44 [debug] 9#9: *1 http2 idle handler
2024/12/10 08:15:44 [debug] 9#9: *1 reusable connection: 0
2024/12/10 08:15:44 [debug] 9#9: *1 posix_memalign: 000056517A1076E0:4096 @16
2024/12/10 08:15:44 [debug] 9#9: *1 http2 read handler
2024/12/10 08:15:44 [debug] 9#9: *1 recv: eof:1, avail:-1
2024/12/10 08:15:44 [debug] 9#9: *1 recv: fd:3 0 of 262128
2024/12/10 08:15:44 [debug] 9#9: *1 close http connection: 3
2024/12/10 08:15:44 [debug] 9#9: *1 event timer del: 3: 29162536
2024/12/10 08:15:44 [debug] 9#9: *1 reusable connection: 0
2024/12/10 08:15:44 [debug] 9#9: *1 run cleanup: 000056517A0F9158
2024/12/10 08:15:44 [debug] 9#9: *1 free: 000056517A1076E0, unused: 4016
2024/12/10 08:15:44 [debug] 9#9: *1 free: 000056517A0FFED0
2024/12/10 08:15:44 [debug] 9#9: *1 free: 000056517A0E5F30
2024/12/10 08:15:44 [debug] 9#9: *1 free: 000056517A0F35E0
2024/12/10 08:15:44 [debug] 9#9: *1 free: 000056517A0F91E0
2024/12/10 08:15:44 [debug] 9#9: *1 free: 000056517A0F8FD0, unused: 0
2024/12/10 08:15:44 [debug] 9#9: *1 free: 000056517A0F6990, unused: 88

コントリビューターガイド

調査方針
ngx http proxy module.c のアップストリーム応答処理をトレースし、無効なステータス行が HTTP/0.9 へのフォールバックとタイムアウトを引き起こす理由を特定します。その後、502 エラーを返すための検証を実装します。
技術スタック
c
領域
backend
Issue 種別
バグ
難度新しい貢献者にとっての実装難度です。1 は非常に小さな変更、5 は専門的な作業です。
2
推定時間経験ある貢献者が調査、実装、テスト、pull request 準備にかけるおおよその時間範囲です。
1-3時間
活動状況Issue が今どれだけ取り組みやすいかを示します。新しい、活発、古い、ブロック中、またはメンテナー入力待ちなどです。
アクティブ
明確さ期待される変更、受け入れ条件、次の手順がどれだけ明確かを示します。
明確
前提条件
CHTTP
初心者向け度初回貢献者にどれだけ取り組みやすいかを 1-100 で推定したスコアです。
40