Lax / traffic-accounting-nginx-module

Monitor the incoming and outgoing traffic metrics in realtime for NGINX
https://github.com/Lax/traffic-accounting-nginx-module
BSD 2-Clause "Simplified" License
280 stars 77 forks source link

Issues when loading as dynamic module on macOS #51

Open nemphys opened 3 years ago

nemphys commented 3 years ago

I just compiled the module as a dynamic module on macOS (nginx 1.19.7 installed via Homebrew) and when I add the load_module directive in nginx.conf and start the server, the error log gets flooded with the following errors (with high CPU utilization):

2021/02/28 11:05:10 [alert] 42874#0: worker process 49555 exited on signal 11 2021/02/28 11:05:10 [alert] 42874#0: cache manager process 49558 exited on signal 11

Turning on debug logging produces the following (repeatedly):

2021/02/28 11:17:04 [debug] 81313#0: bind() 127.0.0.1:3333 #7 2021/02/28 11:17:04 [debug] 81313#0: bind() 0.0.0.0:80 #8 2021/02/28 11:17:04 [debug] 81313#0: bind() 0.0.0.0:443 #9 2021/02/28 11:17:04 [notice] 81313#0: using the "kqueue" event method 2021/02/28 11:17:04 [warn] 81313#0: 1024 worker_connections exceed open file resource limit: 256 2021/02/28 11:17:04 [debug] 81313#0: counter: 000000010396D080, 1 2021/02/28 11:17:04 [notice] 81313#0: nginx/1.19.7 2021/02/28 11:17:04 [notice] 81313#0: built by clang 12.0.0 (clang-1200.0.32.29) 2021/02/28 11:17:04 [notice] 81313#0: OS: Darwin 20.3.0 2021/02/28 11:17:04 [notice] 81313#0: hw.ncpu: 8 2021/02/28 11:17:04 [notice] 81313#0: net.inet.tcp.sendspace: 131072 2021/02/28 11:17:04 [notice] 81313#0: kern.ipc.somaxconn: 128 2021/02/28 11:17:04 [notice] 81313#0: getrlimit(RLIMIT_NOFILE): 256:9223372036854775807 2021/02/28 11:17:04 [debug] 81313#0: write: 10, 00007FFEEC455A70, 6, 0 2021/02/28 11:17:04 [debug] 81313#0: setproctitle: "nginx: master process /usr/local/opt/nginx/bin/nginx -g daemon off;" 2021/02/28 11:17:04 [notice] 81313#0: start worker processes 2021/02/28 11:17:04 [debug] 81313#0: channel 3:10 2021/02/28 11:17:04 [notice] 81313#0: start worker process 81326 2021/02/28 11:17:04 [debug] 81313#0: channel 11:12 2021/02/28 11:17:04 [debug] 81326#0: add cleanup: 00007F92E80DB508 2021/02/28 11:17:04 [debug] 81326#0: malloc: 00007F92DE704290:8 2021/02/28 11:17:04 [notice] 81313#0: start worker process 81327 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:1 pid:81327 fd:11 to s:0 pid:81326 fd:3 2021/02/28 11:17:04 [debug] 81313#0: channel 13:14 2021/02/28 11:17:04 [debug] 81327#0: add cleanup: 00007F92E80DB508 2021/02/28 11:17:04 [debug] 81327#0: malloc: 00007F92DF004340:8 2021/02/28 11:17:04 [notice] 81313#0: start cache manager process 81328 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:2 pid:81328 fd:13 to s:0 pid:81326 fd:3 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:2 pid:81328 fd:13 to s:1 pid:81327 fd:11 2021/02/28 11:17:04 [debug] 81313#0: channel 15:16 2021/02/28 11:17:04 [debug] 81328#0: close listening 127.0.0.1:3333 #7 2021/02/28 11:17:04 [notice] 81313#0: start cache loader process 81330 2021/02/28 11:17:04 [debug] 81328#0: close listening 0.0.0.0:80 #8 2021/02/28 11:17:04 [debug] 81328#0: close listening 0.0.0.0:443 #9 2021/02/28 11:17:04 [debug] 81328#0: add cleanup: 00007F92E80DB508 2021/02/28 11:17:04 [debug] 81328#0: malloc: 00007F92DE6040A0:8 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:3 pid:81330 fd:15 to s:0 pid:81326 fd:3 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:3 pid:81330 fd:15 to s:1 pid:81327 fd:11 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:3 pid:81330 fd:15 to s:2 pid:81328 fd:13 2021/02/28 11:17:04 [debug] 81313#0: sigsuspend 2021/02/28 11:17:04 [debug] 81330#0: close listening 127.0.0.1:3333 #7 2021/02/28 11:17:04 [debug] 81330#0: close listening 0.0.0.0:80 #8 2021/02/28 11:17:04 [debug] 81330#0: close listening 0.0.0.0:443 #9 2021/02/28 11:17:04 [debug] 81330#0: add cleanup: 00007F92E80DB508 2021/02/28 11:17:04 [debug] 81330#0: malloc: 00007F92DF004390:8 2021/02/28 11:17:04 [debug] 81326#0: malloc: 00007F92E80DC800:16384 2021/02/28 11:17:04 [debug] 81326#0: malloc: 00007F92E800C600:16384 2021/02/28 11:17:04 [debug] 81327#0: malloc: 00007F92CF808200:16384 2021/02/28 11:17:04 [debug] 81327#0: malloc: 00007F92E8811A00:16384 2021/02/28 11:17:04 [debug] 81326#0: malloc: 00007F92C0008000:237568 2021/02/28 11:17:04 [debug] 81327#0: malloc: 00007F92C0008000:237568 2021/02/28 11:17:04 [debug] 81327#0: malloc: 00007F92C0048000:106496 2021/02/28 11:17:04 [debug] 81326#0: malloc: 00007F92B0008000:106496 2021/02/28 11:17:04 [debug] 81327#0: malloc: 00007F92C0068000:106496 2021/02/28 11:17:04 [debug] 81326#0: malloc: 00007F92B8008000:106496 2021/02/28 11:17:04 [debug] 81328#0: malloc: 00007F92CF808200:16384 2021/02/28 11:17:04 [debug] 81328#0: malloc: 00007F92CF80C200:16384 2021/02/28 11:17:04 [debug] 81327#0: kevent set event: 7: ft:-1 fl:0005 2021/02/28 11:17:04 [debug] 81326#0: kevent set event: 7: ft:-1 fl:0005 2021/02/28 11:17:04 [debug] 81326#0: kevent set event: 8: ft:-1 fl:0005 2021/02/28 11:17:04 [debug] 81326#0: kevent set event: 9: ft:-1 fl:0005 2021/02/28 11:17:04 [debug] 81327#0: kevent set event: 8: ft:-1 fl:0005 2021/02/28 11:17:04 [debug] 81328#0: malloc: 00007F92C0008000:118784 2021/02/28 11:17:04 [debug] 81327#0: kevent set event: 9: ft:-1 fl:0005 2021/02/28 11:17:04 [debug] 81328#0: malloc: 00007F92B0008000:53248 2021/02/28 11:17:04 [debug] 81328#0: malloc: 00007F92C0028000:53248 2021/02/28 11:17:04 [notice] 81313#0: signal 20 (SIGCHLD) received from 81326 2021/02/28 11:17:04 [alert] 81313#0: worker process 81327 exited on signal 11 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [alert] 81313#0: worker process 81326 exited on signal 11 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81330#0: malloc: 00007F92E8811A00:16384 2021/02/28 11:17:04 [debug] 81330#0: malloc: 00007F92E8815A00:16384 2021/02/28 11:17:04 [debug] 81313#0: wake up, sigio 0 2021/02/28 11:17:04 [debug] 81330#0: malloc: 00007F92C0008000:118784 2021/02/28 11:17:04 [debug] 81313#0: reap children 2021/02/28 11:17:04 [debug] 81330#0: malloc: 00007F92E0008000:53248 2021/02/28 11:17:04 [debug] 81313#0: child: 0 81326 e:0 t:1 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:0 pid:81326 to:81328 2021/02/28 11:17:04 [debug] 81330#0: malloc: 00007F92E0018000:53248 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:0 pid:81326 to:81330 2021/02/28 11:17:04 [debug] 81313#0: channel 3:10 2021/02/28 11:17:04 [notice] 81313#0: start worker process 81334 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:0 pid:81334 fd:3 to s:1 pid:81327 fd:11 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:0 pid:81334 fd:3 to s:2 pid:81328 fd:13 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:0 pid:81334 fd:3 to s:3 pid:81330 fd:15 2021/02/28 11:17:04 [debug] 81313#0: child: 1 81327 e:0 t:1 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:1 pid:81327 to:81334 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:1 pid:81327 to:81328 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:1 pid:81327 to:81330 2021/02/28 11:17:04 [debug] 81313#0: channel 11:12 2021/02/28 11:17:04 [debug] 81334#0: add cleanup: 00007F92E80DB508 2021/02/28 11:17:04 [debug] 81334#0: malloc: 00007F92DE407CA0:8 2021/02/28 11:17:04 [notice] 81313#0: start worker process 81335 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:1 pid:81335 fd:11 to s:0 pid:81334 fd:3 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:1 pid:81335 fd:11 to s:2 pid:81328 fd:13 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:1 pid:81335 fd:11 to s:3 pid:81330 fd:15 2021/02/28 11:17:04 [debug] 81313#0: child: 2 81328 e:0 t:0 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: child: 3 81330 e:0 t:0 d:0 r:0 j:0 2021/02/28 11:17:04 [debug] 81313#0: sigsuspend 2021/02/28 11:17:04 [notice] 81313#0: signal 23 (SIGIO) received 2021/02/28 11:17:04 [notice] 81313#0: signal 20 (SIGCHLD) received from 81330 2021/02/28 11:17:04 [alert] 81313#0: cache loader process 81330 exited on signal 11 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [alert] 81313#0: cache manager process 81328 exited on signal 11 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: wake up, sigio 0 2021/02/28 11:17:04 [debug] 81313#0: reap children 2021/02/28 11:17:04 [debug] 81313#0: child: 0 81334 e:0 t:0 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: child: 1 81335 e:0 t:0 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: child: 2 81328 e:0 t:1 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:2 pid:81328 to:81334 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:2 pid:81328 to:81335 2021/02/28 11:17:04 [debug] 81313#0: channel 13:14 2021/02/28 11:17:04 [debug] 81335#0: add cleanup: 00007F92E80DB508 2021/02/28 11:17:04 [debug] 81335#0: malloc: 00007F92DF1175D0:8 2021/02/28 11:17:04 [notice] 81313#0: start cache manager process 81336 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:2 pid:81336 fd:13 to s:0 pid:81334 fd:3 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:2 pid:81336 fd:13 to s:1 pid:81335 fd:11 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:2 pid:81336 fd:13 to s:3 pid:81330 fd:15 2021/02/28 11:17:04 [debug] 81313#0: child: 3 81330 e:0 t:1 d:0 r:0 j:0 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:3 pid:81330 to:81334 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:3 pid:81330 to:81335 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:3 pid:81330 to:81336 2021/02/28 11:17:04 [debug] 81313#0: sigsuspend 2021/02/28 11:17:04 [debug] 81336#0: close listening 127.0.0.1:3333 #7 2021/02/28 11:17:04 [debug] 81336#0: close listening 0.0.0.0:80 #8 2021/02/28 11:17:04 [debug] 81336#0: close listening 0.0.0.0:443 #9 2021/02/28 11:17:04 [debug] 81336#0: add cleanup: 00007F92E80DB508 2021/02/28 11:17:04 [debug] 81336#0: malloc: 00007F92DF004330:8 2021/02/28 11:17:04 [debug] 81334#0: malloc: 00007F92E8811A00:16384 2021/02/28 11:17:04 [debug] 81334#0: malloc: 00007F92E8815A00:16384 2021/02/28 11:17:04 [debug] 81334#0: malloc: 00007F92C0008000:237568 2021/02/28 11:17:04 [debug] 81334#0: malloc: 00007F92C0048000:106496 2021/02/28 11:17:04 [debug] 81334#0: malloc: 00007F92C0068000:106496 2021/02/28 11:17:04 [debug] 81335#0: malloc: 00007F92CF008200:16384 2021/02/28 11:17:04 [debug] 81335#0: malloc: 00007F92CF00C200:16384 2021/02/28 11:17:04 [debug] 81335#0: malloc: 00007F92C0008000:237568 2021/02/28 11:17:04 [debug] 81335#0: malloc: 00007F92C0048000:106496 2021/02/28 11:17:04 [debug] 81334#0: kevent set event: 7: ft:-1 fl:0005 2021/02/28 11:17:04 [debug] 81334#0: kevent set event: 8: ft:-1 fl:0005 2021/02/28 11:17:04 [debug] 81334#0: kevent set event: 9: ft:-1 fl:0005 2021/02/28 11:17:04 [debug] 81335#0: malloc: 00007F92C0068000:106496 2021/02/28 11:17:04 [notice] 81313#0: signal 23 (SIGIO) received from 81334 2021/02/28 11:17:04 [debug] 81313#0: wake up, sigio 0 2021/02/28 11:17:04 [debug] 81313#0: sigsuspend 2021/02/28 11:17:04 [debug] 81335#0: kevent set event: 7: ft:-1 fl:0005 2021/02/28 11:17:04 [debug] 81335#0: kevent set event: 8: ft:-1 fl:0005 2021/02/28 11:17:04 [debug] 81335#0: kevent set event: 9: ft:-1 fl:0005 2021/02/28 11:17:04 [notice] 81313#0: signal 23 (SIGIO) received 2021/02/28 11:17:04 [notice] 81313#0: signal 20 (SIGCHLD) received from 81334 2021/02/28 11:17:04 [alert] 81313#0: worker process 81334 exited on signal 11 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: wake up, sigio 0 2021/02/28 11:17:04 [debug] 81313#0: reap children 2021/02/28 11:17:04 [debug] 81313#0: child: 0 81334 e:0 t:1 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:0 pid:81334 to:81335 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:0 pid:81334 to:81336 2021/02/28 11:17:04 [debug] 81313#0: channel 3:10 2021/02/28 11:17:04 [debug] 81336#0: malloc: 00007F92E8811A00:16384 2021/02/28 11:17:04 [debug] 81336#0: malloc: 00007F92E8815A00:16384 2021/02/28 11:17:04 [debug] 81336#0: malloc: 00007F92C0008000:118784 2021/02/28 11:17:04 [debug] 81336#0: malloc: 00007F92C0028000:53248 2021/02/28 11:17:04 [debug] 81336#0: malloc: 00007F92C0038000:53248 2021/02/28 11:17:04 [notice] 81313#0: start worker process 81338 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:0 pid:81338 fd:3 to s:1 pid:81335 fd:11 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:0 pid:81338 fd:3 to s:2 pid:81336 fd:13 2021/02/28 11:17:04 [debug] 81313#0: child: 1 81335 e:0 t:0 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: child: 2 81336 e:0 t:0 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: sigsuspend 2021/02/28 11:17:04 [notice] 81313#0: signal 23 (SIGIO) received 2021/02/28 11:17:04 [debug] 81338#0: add cleanup: 00007F92E80DB508 2021/02/28 11:17:04 [debug] 81338#0: malloc: 00007F92DE704290:8 2021/02/28 11:17:04 [notice] 81313#0: signal 20 (SIGCHLD) received from 81336 2021/02/28 11:17:04 [alert] 81313#0: cache manager process 81336 exited on signal 11 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [alert] 81313#0: worker process 81335 exited on signal 11 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: wake up, sigio 0 2021/02/28 11:17:04 [debug] 81313#0: reap children 2021/02/28 11:17:04 [debug] 81313#0: child: 0 81338 e:0 t:0 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: child: 1 81335 e:0 t:1 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:1 pid:81335 to:81338 2021/02/28 11:17:04 [debug] 81313#0: channel 11:12 2021/02/28 11:17:04 [notice] 81313#0: start worker process 81339 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:1 pid:81339 fd:11 to s:0 pid:81338 fd:3 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:1 pid:81339 fd:11 to s:2 pid:81336 fd:13 2021/02/28 11:17:04 [debug] 81313#0: child: 2 81336 e:0 t:1 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:2 pid:81336 to:81338 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:2 pid:81336 to:81339 2021/02/28 11:17:04 [debug] 81313#0: channel 13:14 2021/02/28 11:17:04 [debug] 81339#0: add cleanup: 00007F92E80DB508 2021/02/28 11:17:04 [debug] 81339#0: malloc: 00007F92DF004390:8 2021/02/28 11:17:04 [debug] 81338#0: malloc: 00007F92CF808200:16384 2021/02/28 11:17:04 [debug] 81338#0: malloc: 00007F92CF80C200:16384 2021/02/28 11:17:04 [debug] 81338#0: malloc: 00007F92C0008000:237568 2021/02/28 11:17:04 [debug] 81338#0: malloc: 00007F92C0048000:106496 2021/02/28 11:17:04 [debug] 81338#0: malloc: 00007F92C0068000:106496 2021/02/28 11:17:04 [debug] 81338#0: kevent set event: 7: ft:-1 fl:0005 2021/02/28 11:17:04 [debug] 81338#0: kevent set event: 8: ft:-1 fl:0005 2021/02/28 11:17:04 [debug] 81338#0: kevent set event: 9: ft:-1 fl:0005 2021/02/28 11:17:04 [notice] 81313#0: start cache manager process 81340 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:2 pid:81340 fd:13 to s:0 pid:81338 fd:3 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:2 pid:81340 fd:13 to s:1 pid:81339 fd:11 2021/02/28 11:17:04 [debug] 81313#0: sigsuspend 2021/02/28 11:17:04 [notice] 81313#0: signal 23 (SIGIO) received 2021/02/28 11:17:04 [notice] 81313#0: signal 20 (SIGCHLD) received from 81338 2021/02/28 11:17:04 [alert] 81313#0: worker process 81338 exited on signal 11 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: wake up, sigio 0 2021/02/28 11:17:04 [debug] 81313#0: reap children 2021/02/28 11:17:04 [debug] 81313#0: child: 0 81338 e:0 t:1 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:0 pid:81338 to:81339 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:0 pid:81338 to:81340 2021/02/28 11:17:04 [debug] 81340#0: close listening 127.0.0.1:3333 #7 2021/02/28 11:17:04 [debug] 81313#0: channel 3:10 2021/02/28 11:17:04 [debug] 81339#0: malloc: 00007F92CF008200:16384 2021/02/28 11:17:04 [debug] 81339#0: malloc: 00007F92CF00C200:16384 2021/02/28 11:17:04 [debug] 81340#0: close listening 0.0.0.0:80 #8 2021/02/28 11:17:04 [debug] 81339#0: malloc: 00007F92C0008000:237568 2021/02/28 11:17:04 [debug] 81339#0: malloc: 00007F92C0048000:106496 2021/02/28 11:17:04 [debug] 81340#0: close listening 0.0.0.0:443 #9 2021/02/28 11:17:04 [debug] 81339#0: malloc: 00007F92C0068000:106496 2021/02/28 11:17:04 [debug] 81340#0: add cleanup: 00007F92E80DB508 2021/02/28 11:17:04 [debug] 81340#0: malloc: 00007F92DF1175D0:8 2021/02/28 11:17:04 [debug] 81339#0: kevent set event: 7: ft:-1 fl:0005 2021/02/28 11:17:04 [debug] 81339#0: kevent set event: 8: ft:-1 fl:0005 2021/02/28 11:17:04 [debug] 81339#0: kevent set event: 9: ft:-1 fl:0005 2021/02/28 11:17:04 [notice] 81313#0: start worker process 81342 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:0 pid:81342 fd:3 to s:1 pid:81339 fd:11 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:0 pid:81342 fd:3 to s:2 pid:81340 fd:13 2021/02/28 11:17:04 [debug] 81313#0: child: 1 81339 e:0 t:0 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: child: 2 81340 e:0 t:0 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: sigsuspend 2021/02/28 11:17:04 [notice] 81313#0: signal 23 (SIGIO) received 2021/02/28 11:17:04 [notice] 81313#0: signal 20 (SIGCHLD) received from 81339 2021/02/28 11:17:04 [alert] 81313#0: worker process 81339 exited on signal 11 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: wake up, sigio 0 2021/02/28 11:17:04 [debug] 81313#0: reap children 2021/02/28 11:17:04 [debug] 81313#0: child: 0 81342 e:0 t:0 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: child: 1 81339 e:0 t:1 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:1 pid:81339 to:81342 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:1 pid:81339 to:81340 2021/02/28 11:17:04 [debug] 81313#0: channel 11:12 2021/02/28 11:17:04 [debug] 81342#0: add cleanup: 00007F92E80DB508 2021/02/28 11:17:04 [debug] 81342#0: malloc: 00007F92DE6040A0:8 2021/02/28 11:17:04 [debug] 81340#0: malloc: 00007F92CF808200:16384 2021/02/28 11:17:04 [debug] 81340#0: malloc: 00007F92CF80C200:16384 2021/02/28 11:17:04 [debug] 81340#0: malloc: 00007F92C0008000:118784 2021/02/28 11:17:04 [debug] 81340#0: malloc: 00007F92C0028000:53248 2021/02/28 11:17:04 [debug] 81340#0: malloc: 00007F92C0038000:53248 2021/02/28 11:17:04 [notice] 81313#0: start worker process 81344 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:1 pid:81344 fd:11 to s:0 pid:81342 fd:3 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:1 pid:81344 fd:11 to s:2 pid:81340 fd:13 2021/02/28 11:17:04 [debug] 81313#0: child: 2 81340 e:0 t:0 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: sigsuspend 2021/02/28 11:17:04 [notice] 81313#0: signal 23 (SIGIO) received from 81340 2021/02/28 11:17:04 [debug] 81313#0: wake up, sigio 0 2021/02/28 11:17:04 [debug] 81313#0: sigsuspend 2021/02/28 11:17:04 [notice] 81313#0: signal 23 (SIGIO) received 2021/02/28 11:17:04 [notice] 81313#0: signal 20 (SIGCHLD) received from 81340 2021/02/28 11:17:04 [alert] 81313#0: cache manager process 81340 exited on signal 11 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81344#0: add cleanup: 00007F92E80DB508 2021/02/28 11:17:04 [debug] 81313#0: wake up, sigio 0 2021/02/28 11:17:04 [debug] 81313#0: reap children 2021/02/28 11:17:04 [debug] 81313#0: child: 0 81342 e:0 t:0 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: child: 1 81344 e:0 t:0 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: child: 2 81340 e:0 t:1 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:2 pid:81340 to:81342 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:2 pid:81340 to:81344 2021/02/28 11:17:04 [debug] 81344#0: malloc: 00007F92DE407CA0:8 2021/02/28 11:17:04 [debug] 81313#0: channel 13:14 2021/02/28 11:17:04 [debug] 81342#0: malloc: 00007F92DF82CE00:16384 2021/02/28 11:17:04 [debug] 81342#0: malloc: 00007F92DF830E00:16384 2021/02/28 11:17:04 [debug] 81342#0: malloc: 00007F92C0008000:237568 2021/02/28 11:17:04 [debug] 81342#0: malloc: 00007F92C0048000:106496 2021/02/28 11:17:04 [debug] 81342#0: malloc: 00007F92C0068000:106496 2021/02/28 11:17:04 [debug] 81342#0: kevent set event: 7: ft:-1 fl:0005 2021/02/28 11:17:04 [debug] 81342#0: kevent set event: 8: ft:-1 fl:0005 2021/02/28 11:17:04 [debug] 81342#0: kevent set event: 9: ft:-1 fl:0005 2021/02/28 11:17:04 [debug] 81344#0: malloc: 00007F92CF008200:16384 2021/02/28 11:17:04 [debug] 81344#0: malloc: 00007F92CF00C200:16384 2021/02/28 11:17:04 [debug] 81344#0: malloc: 00007F92C0008000:237568 2021/02/28 11:17:04 [debug] 81344#0: malloc: 00007F92C0048000:106496 2021/02/28 11:17:04 [notice] 81313#0: start cache manager process 81346 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:2 pid:81346 fd:13 to s:0 pid:81342 fd:3 2021/02/28 11:17:04 [debug] 81344#0: malloc: 00007F92C0068000:106496 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:2 pid:81346 fd:13 to s:1 pid:81344 fd:11 2021/02/28 11:17:04 [debug] 81313#0: sigsuspend 2021/02/28 11:17:04 [notice] 81313#0: signal 23 (SIGIO) received 2021/02/28 11:17:04 [notice] 81313#0: signal 20 (SIGCHLD) received from 81342 2021/02/28 11:17:04 [alert] 81313#0: worker process 81342 exited on signal 11 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81344#0: kevent set event: 7: ft:-1 fl:0005 2021/02/28 11:17:04 [debug] 81344#0: kevent set event: 8: ft:-1 fl:0005 2021/02/28 11:17:04 [debug] 81344#0: kevent set event: 9: ft:-1 fl:0005 2021/02/28 11:17:04 [debug] 81313#0: wake up, sigio 0 2021/02/28 11:17:04 [debug] 81313#0: reap children 2021/02/28 11:17:04 [debug] 81313#0: child: 0 81342 e:0 t:1 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:0 pid:81342 to:81344 2021/02/28 11:17:04 [debug] 81346#0: close listening 127.0.0.1:3333 #7 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:0 pid:81342 to:81346 2021/02/28 11:17:04 [debug] 81346#0: close listening 0.0.0.0:80 #8 2021/02/28 11:17:04 [debug] 81346#0: close listening 0.0.0.0:443 #9 2021/02/28 11:17:04 [debug] 81313#0: channel 3:10 2021/02/28 11:17:04 [debug] 81346#0: add cleanup: 00007F92E80DB508 2021/02/28 11:17:04 [debug] 81346#0: malloc: 00007F92DF1175D0:8 2021/02/28 11:17:04 [notice] 81313#0: start worker process 81348 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:0 pid:81348 fd:3 to s:1 pid:81344 fd:11 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:0 pid:81348 fd:3 to s:2 pid:81346 fd:13 2021/02/28 11:17:04 [debug] 81313#0: child: 1 81344 e:0 t:0 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: child: 2 81346 e:0 t:0 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: sigsuspend 2021/02/28 11:17:04 [notice] 81313#0: signal 23 (SIGIO) received 2021/02/28 11:17:04 [notice] 81313#0: signal 20 (SIGCHLD) received from 81344 2021/02/28 11:17:04 [alert] 81313#0: worker process 81344 exited on signal 11 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: shmtx forced unlock 2021/02/28 11:17:04 [debug] 81313#0: wake up, sigio 0 2021/02/28 11:17:04 [debug] 81313#0: reap children 2021/02/28 11:17:04 [debug] 81313#0: child: 0 81348 e:0 t:0 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81348#0: add cleanup: 00007F92E80DB508 2021/02/28 11:17:04 [debug] 81348#0: malloc: 00007F92DE6040A0:8 2021/02/28 11:17:04 [debug] 81313#0: child: 1 81344 e:0 t:1 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:1 pid:81344 to:81348 2021/02/28 11:17:04 [debug] 81313#0: pass close channel s:1 pid:81344 to:81346 2021/02/28 11:17:04 [debug] 81313#0: channel 11:12 2021/02/28 11:17:04 [debug] 81346#0: malloc: 00007F92CE808600:16384 2021/02/28 11:17:04 [debug] 81346#0: malloc: 00007F92CE80C600:16384 2021/02/28 11:17:04 [notice] 81313#0: start worker process 81350 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:1 pid:81350 fd:11 to s:0 pid:81348 fd:3 2021/02/28 11:17:04 [debug] 81313#0: pass channel s:1 pid:81350 fd:11 to s:2 pid:81346 fd:13 2021/02/28 11:17:04 [debug] 81313#0: child: 2 81346 e:0 t:0 d:0 r:1 j:0 2021/02/28 11:17:04 [debug] 81313#0: sigsuspend 2021/02/28 11:17:04 [debug] 81346#0: malloc: 00007F92C0008000:118784 2021/02/28 11:17:04 [debug] 81346#0: malloc: 00007F92B0008000:53248 2021/02/28 11:17:04 [debug] 81346#0: malloc: 00007F92B0018000:53248 2021/02/28 11:17:04 [debug] 81350#0: add cleanup: 00007F92E80DB508 2021/02/28 11:17:04 [debug] 81350#0: malloc: 00007F92DF1175D0:8 2021/02/28 11:17:04 [notice] 81313#0: signal 23 (SIGIO) received from 81346 2021/02/28 11:17:04 [debug] 81313#0: wake up, sigio 0 2021/02/28 11:17:04 [debug] 81313#0: sigsuspend 2021/02/28 11:17:04 [notice] 81313#0: signal 23 (SIGIO) received 2021/02/28 11:17:04 [notice] 81313#0: signal 20 (SIGCHLD) received from 81346 2021/02/28 11:17:04 [alert] 81313#0: cache manager process 81346 exited on signal 11

Any ideas?

nemphys commented 3 years ago

I just realised that my issue is the same as issue #39 (outstanding since 2019), so I guess it will never be solved (project seems to be abandoned?) I tried building v1.3 and at least it seems to work (no streams support).

Lax commented 3 years ago

@nemphys can you show the ./configure parameters, I will try to reproduce the issue first

nemphys commented 3 years ago

The installation is the default current Homebrew installation of 1.19.7 under Big Sur. Configure parameters: --prefix=/usr/local/Cellar/nginx/1.19.7 --sbin-path=/usr/local/Cellar/nginx/1.19.7/bin/nginx --with-cc-opt='-I/usr/local/opt/pcre/include -I/usr/local/opt/openssl@1.1/include' --with-ld-opt='-L/usr/local/opt/pcre/lib -L/usr/local/opt/openssl@1.1/lib' --conf-path=/usr/local/etc/nginx/nginx.conf --pid-path=/usr/local/var/run/nginx.pid --lock-path=/usr/local/var/run/nginx.lock --http-client-body-temp-path=/usr/local/var/run/nginx/client_body_temp --http-proxy-temp-path=/usr/local/var/run/nginx/proxy_temp --http-fastcgi-temp-path=/usr/local/var/run/nginx/fastcgi_temp --http-uwsgi-temp-path=/usr/local/var/run/nginx/uwsgi_temp --http-scgi-temp-path=/usr/local/var/run/nginx/scgi_temp --http-log-path=/usr/local/var/log/nginx/access.log --error-log-path=/usr/local/var/log/nginx/error.log --with-compat --with-debug --with-http_addition_module --with-http_auth_request_module --with-http_dav_module --with-http_degradation_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-ipv6 --with-mail --with-mail_ssl_module --with-pcre --with-pcre-jit --with-stream --with-stream_realip_module --with-stream_ssl_module --with-stream_ssl_preread_module

Lax commented 3 years ago

cannot reproduce the issue.

configuration options and nginx.conf see below:

➜  nginx-1.19.7 ./objs/nginx -V
nginx version: nginx/1.19.7
built by clang 12.0.0 (clang-1200.0.32.29)
built with OpenSSL 1.1.1j  16 Feb 2021
TLS SNI support enabled
configure arguments: --prefix=/usr/local/Cellar/nginx/1.19.7 --sbin-path=/usr/local/Cellar/nginx/1.19.7/bin/nginx --with-cc-opt='-I/usr/local/opt/pcre/include -I/usr/local/opt/openssl@1.1/include' --with-ld-opt='-L/usr/local/opt/pcre/lib -L/usr/local/opt/openssl@1.1/lib' --conf-path=/usr/local/etc/nginx/nginx.conf --pid-path=/usr/local/var/run/nginx.pid --lock-path=/usr/local/var/run/nginx.lock --http-client-body-temp-path=/usr/local/var/run/nginx/client_body_temp --http-proxy-temp-path=/usr/local/var/run/nginx/proxy_temp --http-fastcgi-temp-path=/usr/local/var/run/nginx/fastcgi_temp --http-uwsgi-temp-path=/usr/local/var/run/nginx/uwsgi_temp --http-scgi-temp-path=/usr/local/var/run/nginx/scgi_temp --http-log-path=/usr/local/var/log/nginx/access.log --error-log-path=/usr/local/var/log/nginx/error.log --with-compat --with-debug --with-http_addition_module --with-http_auth_request_module --with-http_dav_module --with-http_degradation_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-ipv6 --with-mail --with-mail_ssl_module --with-pcre --with-pcre-jit --with-stream --with-stream_realip_module --with-stream_ssl_module --with-stream_ssl_preread_module --add-dynamic-module=traffic-accounting-nginx-module
load_module objs/ngx_http_accounting_module.so;

#user  nobody;
worker_processes  1;

events {
    worker_connections  1024;
}

http {
    include       mime.types;
    default_type  application/octet-stream;

    sendfile        on;

    keepalive_timeout  65;

    accounting on;
    accounting_log  logs/http-accounting.log;

    server {
        listen       8081;
        server_name  localhost;

        location / {
            root   html;
            index  index.html index.htm;
        }

        error_page   500 502 503 504  /50x.html;
        location = /50x.html {
            root   html;
        }

    }
}

stream {
    accounting on;
    accounting_id 'STREAM';
    accounting_interval 60;
    accounting_perturb on;
    accounting_log logs/stream-accounting.log;

    upstream echo {
        server 127.0.0.1:8888   max_fails=3 fail_timeout=30s;
    }

    server {
        listen 9999;
        proxy_connect_timeout 1s;
        proxy_timeout 30s;
        proxy_pass echo;
        resolver 127.0.0.1;
        accounting_id "TCP_PROXY_ECHO";
    }

    upstream dns {
       server 8.8.8.8:53;
    }

    server {
        listen 53 udp;
        proxy_responses 1;
        proxy_timeout 20s;
        proxy_pass dns;
        accounting_id $remote_addr;
    }
}
Lax commented 3 years ago

I notice that there's a message cache manager process 81340 exited on signal 11 that may be related to cache configuration, can you show the nginx configuration @nemphys

nemphys commented 3 years ago

Here is my config (please note that I am not using the stream module at all):

`load_module modules/ngx_http_accounting_module.so;

user nobody staff; worker_processes 4;

error_log /usr/local/var/log/nginx/error.log;

events { worker_connections 256; }

http { http_accounting on; http_accounting_log /usr/local/var/log/nginx/http-accounting.log; http_accounting_id $hostname; http_accounting_interval 10;

include       mime.types;
default_type  application/octet-stream;

sendfile        on;
tcp_nodelay    on;
tcp_nopush     on;

keepalive_timeout  65;

client_max_body_size 512M;

gzip on;
gzip_http_version 1.1;
gzip_comp_level 5;
gzip_proxied any;
gzip_min_length  256;
gzip_buffers 16 8k;
gzip_vary on;
gzip_types
  application/atom+xml
  application/javascript
  application/x-javascript
  application/json
  application/rss+xml
  application/vnd.ms-fontobject
  application/x-font-ttf
  application/x-web-app-manifest+json
  application/xhtml+xml
  application/xml
  font/opentype
  image/svg+xml
  image/x-icon
  text/css
  text/plain
  text/x-component;

##
# SSL Settings
##

ssl_protocols TLSv1.2 TLSv1.3; # Dropping SSLv3, ref: POODLE
ssl_prefer_server_ciphers on;
ssl_ciphers TLS-CHACHA20-POLY1305-SHA256:TLS-AES-256-GCM-SHA384:TLS-AES-128-GCM-SHA256:ECDH+AESGCM:DH+AESGCM:ECDH+AES256:DH+AES256:ECDH+AES128:DH+AES:!aNULL:!MD5:!DSS; # TLS1.3
ssl_dhparam /usr/local/etc/nginx/ssl/dhparams.pem;

ssl_session_cache shared:SSL:32m;
ssl_session_timeout 180m;
ssl_buffer_size 8k;

map $scheme $fastcgi_https { ## Detect when HTTPS is used
    default off;
    https on;
}

proxy_cache_path /tmp/cache keys_zone=microcache:50m levels=1:2 inactive=600s max_size=100m;

server {
  listen 127.0.0.1:3333;
  proxy_http_version 1.1;
  proxy_set_header    Connection              "";
  proxy_set_header    Host                    $http_host;
  proxy_set_header    X-Forwarded-For         $proxy_add_x_forwarded_for;
  proxy_set_header    X-Forwarded-Proto       $scheme;
  proxy_set_header    X-Real-IP               $remote_addr;
  proxy_set_header    X-Real-PORT             $remote_port;
  proxy_buffers 32 4k;
  proxy_buffer_size 16k;
  http_accounting_id xxxxxxx;

  location / {
    proxy_cache microcache;
    proxy_cache_revalidate on;
    proxy_cache_valid 200 1s;
    proxy_cache_lock on;
    proxy_cache_use_stale updating;
    proxy_ignore_headers Set-Cookie;
    proxy_hide_header Set-Cookie;
    proxy_ignore_headers Cache-Control;
    add_header X-Cache-Status $upstream_cache_status;
    proxy_pass http://nodejs;
}

} }`

nemphys commented 3 years ago

Any news on this one?

waster commented 3 years ago

The same issue for me trying to load dynamic module after compiling it with nginx 1.21.0-1~focal package from official repository on Ubuntu 20.04. Even with a sample minimal config:

http {
    include       /etc/nginx/mime.types;
    default_type  application/octet-stream;

    access_log  /var/log/nginx/access.log;
}