[BUG] DNSBL error due to ngx.timer process

[Antoninsm]

What happened?

After re-configuring service through UI which restarts scheduler to create new config, errors reaching DNSBL servers start appearing in /var/log/bunkerweb/error.log

How to reproduce?

Reconfigure any service through UI and check logs

Configuration file(s) (yaml or .env)

DNS_RESOLVERS= 9.9.9.9 8.8.8.8 8.8.4.4
HTTP_PORT=80
HTTPS_PORT=443
API_LISTEN_IP=127.0.0.1
MULTISITE=yes
UI_HOST=http://127.0.0.1:7000
SERVER_NAME=
DATABASE_URI=mariadb+pymysql://bunkerweb:##dbapassword##@##serverip##:3306/bunkerweb

Relevant log output

2024/11/28 22:00:48 [notice] 659#659: reconfiguring
2024/11/28 22:00:48 [notice] 659#659: ModSecurity-nginx v1.0.3 (rules loaded inline/local/remote: 0/1851/0)
2024/11/28 22:00:48 [notice] 659#659: [INIT] init phase started
2024/11/28 22:00:48 [notice] 659#659: [INIT] deleting old keys from datastore ...
2024/11/28 22:00:48 [notice] 659#659: [INIT] deleted old keys from datastore
2024/11/28 22:00:48 [notice] 659#659: [INIT] saving plugins into datastore ...
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin antibot v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin badbehavior v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin gzip v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin whitelist v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin reverseproxy v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin cors v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin blacklist v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin authbasic v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin bunkernet v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin redis v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin customcert v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin redirect v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin sessions v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin php v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin modsecurity v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin country v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin inject v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin pro v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin ui v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin backup v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin reversescan v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin clientcache v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin greylist v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin brotli v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin letsencrypt v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin misc v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin realip v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin headers v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin metrics v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin db v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin limit v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin errors v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin selfsigned v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin jobs v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin dnsbl v1.0
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin migration v0.8
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin backup_s3 v0.8
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin monitoring v0.8
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin letsencrypt_dns v0.8
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin reporting v0.8
2024/11/28 22:00:48 [notice] 659#659: [INIT] loaded plugin prometheus_exporter v0.8
2024/11/28 22:00:48 [notice] 659#659: [INIT] saving variables into datastore ...
2024/11/28 22:00:48 [notice] 659#659: [INIT] saved variables into datastore
2024/11/28 22:00:48 [notice] 659#659: [INIT] saving plugins order into datastore ...
2024/11/28 22:00:48 [notice] 659#659: [INIT] plugins order for phase log : ["badbehavior","bunkernet","errors","metrics"]
2024/11/28 22:00:48 [notice] 659#659: [INIT] plugins order for phase init_workers : {}
2024/11/28 22:00:48 [notice] 659#659: [INIT] plugins order for phase init : ["sessions","whitelist","blacklist","greylist","bunkernet","limit","selfsigned","headers","letsencrypt","customcert"]
2024/11/28 22:00:48 [notice] 659#659: [INIT] plugins order for phase init_worker : ["redis","bunkernet","dnsbl"]
2024/11/28 22:00:48 [notice] 659#659: [INIT] plugins order for phase header : ["antibot","headers","cors","misc"]
2024/11/28 22:00:48 [notice] 659#659: [INIT] plugins order for phase timer : ["metrics"]
2024/11/28 22:00:48 [notice] 659#659: [INIT] plugins order for phase set : ["sessions","whitelist","letsencrypt","customcert","selfsigned","ui"]
2024/11/28 22:00:48 [notice] 659#659: [INIT] plugins order for phase ssl_certificate : ["customcert","letsencrypt","selfsigned"]
2024/11/28 22:00:48 [notice] 659#659: [INIT] plugins order for phase preread : ["whitelist","blacklist","greylist","country","dnsbl","reversescan"]
2024/11/28 22:00:48 [notice] 659#659: [INIT] plugins order for phase log_stream : ["badbehavior","bunkernet"]
2024/11/28 22:00:48 [notice] 659#659: [INIT] plugins order for phase log_default : ["badbehavior","bunkernet","misc","metrics"]
2024/11/28 22:00:48 [notice] 659#659: [INIT] plugins order for phase rewrite : {}
2024/11/28 22:00:48 [notice] 659#659: [INIT] plugins order for phase access : ["whitelist","letsencrypt","blacklist","greylist","country","dnsbl","bunkernet","reversescan","limit","misc","cors","antibot"]
2024/11/28 22:00:48 [notice] 659#659: [INIT] plugins order for phase content : ["antibot"]
2024/11/28 22:00:48 [notice] 659#659: [INIT] saved plugins order into datastore
2024/11/28 22:00:48 [notice] 659#659: [INIT] calling init() methods of plugins ...
2024/11/28 22:00:48 [notice] 659#659: [INIT] sessions:init() call successful : sessions init successful
2024/11/28 22:00:48 [notice] 659#659: [INIT] whitelist:init() call successful : successfully loaded 0 IP/network/rDNS/ASN/User-Agent/URI
2024/11/28 22:00:48 [notice] 659#659: [INIT] blacklist:init() call successful : successfully loaded 2747 IP/network/rDNS/ASN/User-Agent/URI
2024/11/28 22:00:48 [notice] 659#659: [INIT] greylist:init() call successful : init not needed
2024/11/28 22:00:48 [notice] 659#659: [INIT] bunkernet:init() call successful : successfully loaded 100 bad IPs using instance ID f3569d06-ddab-4e70-bf31-31015980d81b
2024/11/28 22:00:48 [notice] 659#659: [INIT] limit:init() call successful : successfully loaded 3 limit rules for requests
2024/11/28 22:00:48 [notice] 659#659: [INIT] selfsigned:init() call successful : success
2024/11/28 22:00:48 [notice] 659#659: [INIT] headers:init() call successful : successfully loaded 3 custom headers
2024/11/28 22:00:48 [notice] 659#659: [INIT] letsencrypt:init() call successful : let's encrypt is not used
2024/11/28 22:00:48 [notice] 659#659: [INIT] customcert:init() call successful : custom cert is not used
2024/11/28 22:00:48 [notice] 659#659: [INIT] called init() methods of plugins
2024/11/28 22:00:48 [notice] 659#659: [INIT] init phase ended
2024/11/28 22:00:48 [notice] 659#659: using the "epoll" event method
2024/11/28 22:00:48 [notice] 659#659: start worker processes
2024/11/28 22:00:48 [notice] 659#659: start worker process 1009
2024/11/28 22:00:48 [notice] 659#659: start worker process 1010
2024/11/28 22:00:49 [notice] 947#947: gracefully shutting down
2024/11/28 22:00:49 [notice] 659#659: signal 17 (SIGCHLD) received from 1002
2024/11/28 22:00:49 [warn] 947#947: *83 [TIMER-1] worker is exiting, disabling timer, context: ngx.timer
2024/11/28 22:00:49 [notice] 946#946: gracefully shutting down
2024/11/28 22:00:49 [warn] 946#946: *84 [TIMER-0] worker is exiting, disabling timer, context: ngx.timer
2024/11/28 22:00:49 [notice] 946#946: exiting
2024/11/28 22:00:49 [notice] 659#659: unknown process 1002 exited with code 0
2024/11/28 22:00:49 [notice] 659#659: signal 1 (SIGHUP) received from 998, reconfiguring
2024/11/28 22:00:49 [notice] 659#659: reconfiguring
2024/11/28 22:00:49 [notice] 947#947: exiting
2024/11/28 22:00:49 [notice] 947#947: exit
2024/11/28 22:00:49 [notice] 946#946: exit
2024/11/28 22:00:49 [notice] 659#659: ModSecurity-nginx v1.0.3 (rules loaded inline/local/remote: 0/1851/0)
2024/11/28 22:00:49 [notice] 659#659: [INIT] init phase started
2024/11/28 22:00:49 [notice] 659#659: [INIT] deleting old keys from datastore ...
2024/11/28 22:00:49 [notice] 659#659: [INIT] deleted old keys from datastore
2024/11/28 22:00:49 [notice] 659#659: [INIT] saving plugins into datastore ...
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin antibot v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin badbehavior v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin gzip v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin whitelist v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin reverseproxy v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin cors v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin blacklist v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin authbasic v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin bunkernet v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin redis v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin customcert v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin redirect v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin sessions v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin php v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin modsecurity v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin country v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin inject v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin pro v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin ui v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin backup v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin reversescan v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin clientcache v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin greylist v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin brotli v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin letsencrypt v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin misc v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin realip v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin headers v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin metrics v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin db v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin limit v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin errors v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin selfsigned v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin jobs v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin dnsbl v1.0
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin migration v0.8
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin backup_s3 v0.8
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin monitoring v0.8
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin letsencrypt_dns v0.8
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin reporting v0.8
2024/11/28 22:00:49 [notice] 659#659: [INIT] loaded plugin prometheus_exporter v0.8
2024/11/28 22:00:49 [notice] 659#659: [INIT] saving variables into datastore ...
2024/11/28 22:00:49 [notice] 659#659: [INIT] saved variables into datastore
2024/11/28 22:00:49 [notice] 659#659: [INIT] saving plugins order into datastore ...
2024/11/28 22:00:49 [notice] 659#659: [INIT] plugins order for phase rewrite : {}
2024/11/28 22:00:49 [notice] 659#659: [INIT] plugins order for phase access : ["whitelist","letsencrypt","blacklist","greylist","country","dnsbl","bunkernet","reversescan","limit","misc","cors","antibot"]
2024/11/28 22:00:49 [notice] 659#659: [INIT] plugins order for phase content : ["antibot"]
2024/11/28 22:00:49 [notice] 659#659: [INIT] plugins order for phase init_workers : {}
2024/11/28 22:00:49 [notice] 659#659: [INIT] plugins order for phase timer : ["metrics"]
2024/11/28 22:00:49 [notice] 659#659: [INIT] plugins order for phase init : ["sessions","whitelist","blacklist","greylist","bunkernet","limit","headers","letsencrypt","customcert","selfsigned"]
2024/11/28 22:00:49 [notice] 659#659: [INIT] plugins order for phase init_worker : ["redis","bunkernet","dnsbl"]
2024/11/28 22:00:49 [notice] 659#659: [INIT] plugins order for phase header : ["cors","headers","antibot","misc"]
2024/11/28 22:00:49 [notice] 659#659: [INIT] plugins order for phase log_default : ["badbehavior","bunkernet","misc","metrics"]
2024/11/28 22:00:49 [notice] 659#659: [INIT] plugins order for phase log_stream : ["badbehavior","bunkernet"]
2024/11/28 22:00:49 [notice] 659#659: [INIT] plugins order for phase set : ["sessions","whitelist","letsencrypt","customcert","selfsigned","ui"]
2024/11/28 22:00:49 [notice] 659#659: [INIT] plugins order for phase preread : ["whitelist","blacklist","greylist","country","dnsbl","reversescan"]
2024/11/28 22:00:49 [notice] 659#659: [INIT] plugins order for phase log : ["badbehavior","bunkernet","errors","metrics"]
2024/11/28 22:00:49 [notice] 659#659: [INIT] plugins order for phase ssl_certificate : ["customcert","letsencrypt","selfsigned"]
2024/11/28 22:00:49 [notice] 659#659: [INIT] saved plugins order into datastore
2024/11/28 22:00:49 [notice] 659#659: [INIT] calling init() methods of plugins ...
2024/11/28 22:00:49 [notice] 659#659: [INIT] sessions:init() call successful : sessions init successful
2024/11/28 22:00:49 [notice] 659#659: [INIT] whitelist:init() call successful : successfully loaded 0 IP/network/rDNS/ASN/User-Agent/URI
2024/11/28 22:00:49 [notice] 659#659: [INIT] blacklist:init() call successful : successfully loaded 2747 IP/network/rDNS/ASN/User-Agent/URI
2024/11/28 22:00:49 [notice] 659#659: [INIT] greylist:init() call successful : init not needed
2024/11/28 22:00:49 [notice] 659#659: [INIT] bunkernet:init() call successful : successfully loaded 100 bad IPs using instance ID f3569d06-ddab-4e70-bf31-31015980d81b
2024/11/28 22:00:49 [notice] 659#659: [INIT] limit:init() call successful : successfully loaded 3 limit rules for requests
2024/11/28 22:00:49 [notice] 659#659: [INIT] headers:init() call successful : successfully loaded 3 custom headers
2024/11/28 22:00:49 [notice] 659#659: [INIT] letsencrypt:init() call successful : let's encrypt is not used
2024/11/28 22:00:49 [notice] 659#659: [INIT] customcert:init() call successful : custom cert is not used
2024/11/28 22:00:49 [notice] 659#659: [INIT] selfsigned:init() call successful : success
2024/11/28 22:00:49 [notice] 659#659: [INIT] called init() methods of plugins
2024/11/28 22:00:49 [notice] 659#659: [INIT] init phase ended
2024/11/28 22:00:49 [notice] 659#659: using the "epoll" event method
2024/11/28 22:00:49 [notice] 659#659: start worker processes
2024/11/28 22:00:49 [notice] 659#659: start worker process 1017
2024/11/28 22:00:49 [notice] 659#659: start worker process 1018
2024/11/28 22:00:50 [notice] 659#659: signal 17 (SIGCHLD) received from 947
2024/11/28 22:00:50 [notice] 1009#1009: gracefully shutting down
2024/11/28 22:00:50 [notice] 1010#1010: gracefully shutting down
2024/11/28 22:00:50 [error] 1009#1009: *89 [TIMER-0] can't create timer : process exiting, context: ngx.timer
2024/11/28 22:00:50 [notice] 659#659: worker process 946 exited with code 0
2024/11/28 22:00:50 [notice] 659#659: worker process 947 exited with code 0
2024/11/28 22:00:50 [notice] 659#659: unknown process 1011 exited with code 0
2024/11/28 22:00:50 [notice] 659#659: signal 29 (SIGIO) received
2024/11/28 22:00:50 [notice] 1010#1010: signal 17 (SIGCHLD) received from 1019
2024/11/28 22:00:50 [notice] 1010#1010: unknown process 1019 exited with code 0
2024/11/28 22:00:50 [error] 1010#1010: *91 [TIMER-1] can't create timer : process exiting, context: ngx.timer
2024/11/28 22:00:50 [notice] 1010#1010: *87 [BUNKERNET] connectivity with API using instance ID f3569d06-ddab-4e70-bf31-31015980d81b is successful, context: ngx.timer
2024/11/28 22:00:50 [error] 1010#1010: *87 [DNSBL] dnsbl check for xbl.spamhaus.org failed, context: ngx.timer
2024/11/28 22:00:50 [notice] 1010#1010: *87 [DNSBL] dnsbl check for bl.blocklist.de is successful, context: ngx.timer
2024/11/28 22:00:50 [error] 1010#1010: *87 [DNSBL] dnsbl check for sbl.spamhaus.org failed, context: ngx.timer
2024/11/28 22:00:50 [error] 1010#1010: *87 [DNSBL] dnsbl check for problems.dnsbl.sorbs.net failed, context: ngx.timer
2024/11/28 22:00:50 [notice] 1010#1010: *87 [INIT-WORKER] BunkerWeb is ready to fool hackers ! 🚀, context: ngx.timer
2024/11/28 22:00:50 [notice] 1010#1010: exiting
2024/11/28 22:00:50 [notice] 1009#1009: exiting
2024/11/28 22:00:50 [notice] 1010#1010: exit
2024/11/28 22:00:50 [notice] 1009#1009: exit
2024/11/28 22:00:51 [notice] 659#659: signal 17 (SIGCHLD) received from 1010
2024/11/28 22:00:51 [notice] 659#659: worker process 1010 exited with code 0
2024/11/28 22:00:51 [notice] 659#659: signal 29 (SIGIO) received
2024/11/28 22:00:51 [notice] 659#659: signal 17 (SIGCHLD) received from 1009
2024/11/28 22:00:51 [notice] 659#659: worker process 1009 exited with code 0
2024/11/28 22:00:51 [notice] 659#659: signal 29 (SIGIO) received

BunkerWeb version

1.5.12

What integration are you using?

Linux

Linux distribution (if applicable)

Debian 12

Removed private data

• I have removed all private data from the configuration file and the logs

Code of Conduct

• I agree to follow this project's Code of Conduct

[TheophileDiot]

Hi @Antoninsm, thank you for bringing this issue to our attention! After reviewing it, it appears to be a log entry indicating that a worker exited following a reload. This behavior is expected, as the reload process prevents the timer from starting. It doesn't seem to be a bug, but please let us know if you’re observing any unexpected behavior related to this.