Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

s6 overlay v2? supervisor not listening #1072

Closed
2 tasks
Falenone opened this issue Apr 19, 2022 · 54 comments
Closed
2 tasks

s6 overlay v2? supervisor not listening #1072

Falenone opened this issue Apr 19, 2022 · 54 comments
Labels

Comments

@Falenone
Copy link

This is a: Run Issue (running Pi-hole container failing)

Details

On my Ubuntu 20.04.4 LTS with Docker version 20.10.14 The container won't start up using the official latest image

Related Issues

  • [ x] I have searched this repository/Pi-hole forums for existing issues and pull requests that look similar

I did not find anything similar

How to reproduce the issue

  1. Environment data
  • Operating System: Ubuntu 20.04.4 LTS
  • Hardware: PC
  • Kernel Architecture: x86/amd64
  • Docker Install Info and version:
    • Software source: official docker-ce
    • Supplimentary Software: portainer
  • Hardware architecture: amd64
  1. docker-compose.yml contents, docker run shell command, or paste a screenshot of any UI based configuration of containers here
  2. any additional info to help reproduce

These common fixes didn't work for my issue

  • [x ] I have tried removing/destroying my container, and re-creating a new container
  • [ x] I have tried fresh volume data by backing up and moving/removing the old volume data
  • [ x] I have tried running the stock docker run example(s) in the readme (removing any customizations I added)
  • I have tried a newer or older version of Docker Pi-hole (depending what version the issue started in for me)
  • I have tried running without my volume data mounts to eliminate volumes as the cause

If the above debugging / fixes revealed any new information note it here.
Add any other debugging steps you've taken or theories on root cause that may help.

[i] Installing configs from /etc/.pihole...
[i] Existing dnsmasq.conf found... it is not a Pi-hole file, leaving alone!
[i] Installing /etc/dnsmasq.d/01-pihole.conf...
[✓] Installed /etc/dnsmasq.d/01-pihole.conf
[i] Installing /etc/.pihole/advanced/06-rfc6761.conf...
[✓] Installed /etc/dnsmasq.d/06-rfc6761.conf
Existing DNS servers detected in setupVars.conf. Leaving them alone
[✓] New password set
DNSMasq binding to default interface: eth0
Added ENV to php:
"TZ" => "Estonia/Tallinn",
"PIHOLE_DOCKER_TAG" => "2022.04.2",
"PHP_ERROR_LOG" => "/var/log/lighttpd/error.log",
"ServerIP" => "0.0.0.0",
"CORS_HOSTS" => "",
"VIRTUAL_HOST" => "0.0.0.0",
Using IPv4 and IPv6
::: setup_blocklists now setting default blocklists up:
::: TIP: Use a docker volume for /etc/pihole/adlists.list if you want to customize for first boot
::: Blocklists (/etc/pihole/adlists.list) now set to:
https://raw.githubusercontent.com/StevenBlack/hosts/master/hosts
::: Testing lighttpd config: Syntax OK
::: All config checks passed, cleared for startup ...
::: Enabling Query Logging
[i] Enabling logging...

[✓] Logging has been enabled!
::: Docker start setup complete
Checking if custom gravity.db is set in /etc/pihole/pihole-FTL.conf
Pi-hole version is v5.9.1 (Latest: v5.9.1)
AdminLTE version is v5.11 (Latest: v5.11)
FTL version is v5.14 (Latest: v5.14)
Container tag is: 2022.04.2
[cont-init.d] 20-start.sh: exited 0.
[cont-init.d] done.
[services.d] starting services
Starting pihole-FTL (no-daemon) as pihole
Starting lighttpd
Starting crond
[services.d] done.
[2022-04-19 01:33:04.183 522M] Using log file /var/log/pihole-FTL.log
[2022-04-19 01:33:04.183 522M] ########## FTL started on 73c24eed5c14! ##########
[2022-04-19 01:33:04.184 522M] FTL branch: master
[2022-04-19 01:33:04.184 522M] FTL version: v5.14
[2022-04-19 01:33:04.184 522M] FTL commit: 52e6b95
[2022-04-19 01:33:04.184 522M] FTL date: 2022-02-12 19:58:34 +0000
[2022-04-19 01:33:04.184 522M] FTL user: pihole
[2022-04-19 01:33:04.184 522M] Compiled for x86_64 (compiled on CI) using gcc (Debian 6.3.0-18+deb9u1) 6.3.0 20170516
[2022-04-19 01:33:04.185 522M] Creating mutex
[2022-04-19 01:33:04.185 522M] Creating mutex
[2022-04-19 01:33:04.186 522M] Starting config file parsing (/etc/pihole/pihole-FTL.conf)
[2022-04-19 01:33:04.186 522M] SOCKET_LISTENING: only local
[2022-04-19 01:33:04.187 522M] AAAA_QUERY_ANALYSIS: Show AAAA queries
[2022-04-19 01:33:04.187 522M] MAXDBDAYS: max age for stored queries is 365 days
[2022-04-19 01:33:04.187 522M] RESOLVE_IPV6: Resolve IPv6 addresses
[2022-04-19 01:33:04.187 522M] RESOLVE_IPV4: Resolve IPv4 addresses
[2022-04-19 01:33:04.187 522M] DBINTERVAL: saving to DB file every minute
[2022-04-19 01:33:04.187 522M] DBFILE: Using /etc/pihole/pihole-FTL.db
[2022-04-19 01:33:04.187 522M] MAXLOGAGE: Importing up to 24.0 hours of log data
[2022-04-19 01:33:04.188 522M] PRIVACYLEVEL: Set to 0
[2022-04-19 01:33:04.188 522M] IGNORE_LOCALHOST: Show queries from localhost
[2022-04-19 01:33:04.188 522M] BLOCKINGMODE: Null IPs for blocked domains
[2022-04-19 01:33:04.188 522M] ANALYZE_ONLY_A_AND_AAAA: Disabled. Analyzing all queries
[2022-04-19 01:33:04.188 522M] DBIMPORT: Importing history from database
[2022-04-19 01:33:04.188 522M] PIDFILE: Using /run/pihole-FTL.pid
[2022-04-19 01:33:04.188 522M] PORTFILE: Using /run/pihole-FTL.port
[2022-04-19 01:33:04.189 522M] SOCKETFILE: Using /run/pihole/FTL.sock
[2022-04-19 01:33:04.189 522M] SETUPVARSFILE: Using /etc/pihole/setupVars.conf
[2022-04-19 01:33:04.189 522M] MACVENDORDB: Using /etc/pihole/macvendor.db
[2022-04-19 01:33:04.189 522M] GRAVITYDB: Using /etc/pihole/gravity.db
[2022-04-19 01:33:04.189 522M] PARSE_ARP_CACHE: Active
[2022-04-19 01:33:04.189 522M] CNAME_DEEP_INSPECT: Active
[2022-04-19 01:33:04.189 522M] DELAY_STARTUP: No delay requested.
[2022-04-19 01:33:04.190 522M] BLOCK_ESNI: Enabled, blocking _esni.{blocked domain}
[2022-04-19 01:33:04.190 522M] NICE: Cannot change niceness to -10 (permission denied)
[2022-04-19 01:33:04.190 522M] MAXNETAGE: Removing IP addresses and host names from network table after 365 days
[2022-04-19 01:33:04.190 522M] NAMES_FROM_NETDB: Enabled, trying to get names from network database
[2022-04-19 01:33:04.190 522M] EDNS0_ECS: Overwrite client from ECS information
[2022-04-19 01:33:04.190 522M] REFRESH_HOSTNAMES: Periodically refreshing IPv4 names
[2022-04-19 01:33:04.190 522M] RATE_LIMIT: Rate-limiting client making more than 1000 queries in 60 seconds
[2022-04-19 01:33:04.190 522M] LOCAL_IPV4: Automatic interface-dependent detection of address
[2022-04-19 01:33:04.191 522M] LOCAL_IPV6: Automatic interface-dependent detection of address
[2022-04-19 01:33:04.191 522M] BLOCK_IPV4: Automatic interface-dependent detection of address
[2022-04-19 01:33:04.191 522M] BLOCK_IPV6: Automatic interface-dependent detection of address
[2022-04-19 01:33:04.191 522M] REPLY_ADDR4: Using IPv4 address 0.0.0.0 instead of automatically determined IP address
[2022-04-19 01:33:04.191 522M] SHOW_DNSSEC: Enabled, showing automatically generated DNSSEC queries
[2022-04-19 01:33:04.191 522M] MOZILLA_CANARY: Enabled
[2022-04-19 01:33:04.191 522M] PIHOLE_PTR: internal PTR generation enabled (pi.hole)
[2022-04-19 01:33:04.191 522M] ADDR2LINE: Enabled
[2022-04-19 01:33:04.191 522M] REPLY_WHEN_BUSY: Permit queries when the database is busy
[2022-04-19 01:33:04.192 522M] BLOCK_TTL: 2 seconds
[2022-04-19 01:33:04.192 522M] BLOCK_ICLOUD_PR: Enabled
[2022-04-19 01:33:04.192 522M] CHECK_LOAD: Enabled
[2022-04-19 01:3Stopping pihole-FTL
Stopping cron
Stopping lighttpd
s6-svc: fatal: unable to control /var/run/s6/services/lighttpd-access-log: supervisor not listening
[cont-finish.d] executing container finish scripts...
s6-svc: fatal: unable to control /var/run/s6/services/lighttpd-error-log: supervisor not listening
[cont-finish.d] done.
[s6-finish] waiting for services.
[s6-finish] sending all processes the TERM signal.
[s6-finish] sending all processes the KILL signal and exiting.

I went to s6 folks and they pointed out that I have s6 overlay v3 and the container possibly uses v2. What can I do to get it up and running?

@Falenone
Copy link
Author

Same thing on latest ubuntu server with brand new installation

@dschaper
Copy link
Member

The version of s6 shouldn't matter. We use v2 inside the container and that's what will drive the container daemons. The host version doesn't come in to play.

We need to see the docker run or the docker-compose file you are using to start things up.

@Bauxitedev
Copy link

Bauxitedev commented Apr 24, 2022

Same issue here running on Raspbian 10 after updating to the latest docker image (2022.04.3). Downgrading back to version 2022.02.1 seems to solve the issue.

How I fixed it:

  1. Edit docker-compose.yml and change the line image: pihole/pihole:latest to image: pihole/pihole:2022.02.1
  2. Run docker-compose pull to download the old version (if you don't have it yet)
  3. Run docker-compose stop pihole to stop pihole
  4. Run docker-compose rm pihole to remove the previous image
  5. Run docker-compose up -d to start the pihole daemon again

@PromoFaux
Copy link
Member

Same issue here running on Raspbian 10

Are you sure it is the same issue? Please see the upgrade notes on the Readme for information about buster based operating systems

@Bauxitedev
Copy link

@PromoFaux I'm pretty sure this is the same issue, since the error message I get is exactly the same as OP's:

s6-svc: fatal: unable to control /var/run/s6/services/lighttpd-access-log: supervisor not listening

@rdwebdesign
Copy link
Member

What is the s6 version installed on your host system? What is the return of:

apt list s6*

As pointed above, the s6 version installed on your system shouldn't matter, but sometimes we find weird cases...

@PromoFaux
Copy link
Member

PromoFaux commented Apr 25, 2022

Can we also get your docker-compose or run scripts please, so that we know exactly how you're starting it.

Just going by the error message, have you got the env variable WEBLOGS_STDOUT set to 1?

Edit: That said, I have just tried on :latest with that env var set and am not seeing the issue on windows... will try on my rpi

Double edit: Same thing on Rpi (Raspbian Buster) - works with no issue...

@PromoFaux
Copy link
Member

PromoFaux commented Apr 25, 2022

Ah hold on. Both @Falenone and @Bauxitedev - I think this is another side-effect of the outdated libseccomp issue

@Bauxitedev, as you're on Raspbian Buster - take a look at this comment here to see if that fixes things #1042 (comment)

@Falenone : I'm not sure if there is a similar solution for Ubuntu 20.04, but the original issue on the moby repo is probably worth a read in your case

Edit: TL;DR you need to be running libseccomp2 version >=2.5 in order for latest to work properly. This is available through backports on Debian buster, and updates on Ubuntu. Personally I would recommend you upgrade the whole OS if you can - updating libseccomp2 via backports/updates should be OK - but there is no telling whether or not it will break something else on your system

https://pkgs.org/download/libseccomp2

@iAmSaugata
Copy link

iAmSaugata commented Apr 27, 2022

I am also getting this error every time I am restarting or stopping container after I updated pihole to the latest version.
I am on Bullseye.

2022-04-27T16:59:33Z INF Autoupdate frequency is set autoupdateFreq=86400000
Stopping cron
Stopping pihole-FTL
Stopping Stubby
Stopping Cloudflared
[cont-finish.d] executing container finish scripts...
Stopping lighttpd
[cont-finish.d] done.
[s6-finish] waiting for services.
s6-svc: fatal: unable to control /var/run/s6/services/lighttpd-access-log: supervisor not listening
s6-svc: fatal: unable to control /var/run/s6/services/lighttpd-error-log: supervisor not listening
[s6-finish] sending all processes the TERM signal.
[s6-finish] sending all processes the KILL signal and exiting.
[s6-init] making user provided files available at /var/run/s6/etc...exited 0.
[s6-init] ensuring user provided files have correct perms...exited 0.
[fix-attrs.d] applying ownership & permissions fixes...
[fix-attrs.d] 01-resolver-resolv: applying...
[fix-attrs.d] 01-resolver-resolv: exited 0.
[fix-attrs.d] done.
[cont-init.d] executing container initialization scripts...
[cont-init.d] 05-changer-uid-gid.sh: executing...
[cont-init.d] 05-changer-uid-gid.sh: exited 0.
[cont-init.d] 20-start.sh: executing...
::: Starting docker specific checks & setup for docker pihole/pihole
[i] Installing configs from /etc/.pihole...
[i] Existing dnsmasq.conf found... it is not a Pi-hole file, leaving alone!
[i] Installing /etc/dnsmasq.d/01-pihole.conf...
[✓] Installed /etc/dnsmasq.d/01-pihole.conf
[i] Installing /etc/.pihole/advanced/06-rfc6761.conf...
[✓] Installed /etc/dnsmasq.d/06-rfc6761.conf
Setting DNS servers based on PIHOLE_DNS_ variable

@dschaper
Copy link
Member

What is your docker run or your docker-compose.yml file please?

@iAmSaugata
Copy link

iAmSaugata commented Apr 28, 2022

Please find my docket-compose.yml file bellow. (I am using DHCP helper).

version: "3"
services:
  pihole:
    build: ./pihole-doh-dot
    container_name: pihole-doh-dot-dhcp
    hostname: pihole-svr
    ports:
      - "53:53/tcp"
      - "53:53/udp"
      - "8008:80/tcp"
    restart: unless-stopped
    cap_add:
        - NET_ADMIN
        - SYS_NICE
    environment:
      TZ: 'Asia/Kolkata'
      WEBPASSWORD: "PASSWORD"
      ServerIP: "192.168.0.114"
      VIRTUAL_HOST:  "pihole.dns"
      PIHOLE_DNS_: "127.0.0.1#5053;127.0.0.1#5153"
      IPv6: "false"
      DNSMASQ_LISTENING: "all"
      PIHOLE_DOMAIN: "local"
      DNSMASQ_USER: "root"
    volumes:
      - '/home/pi/pihole-doh-dot-install/pihole/:/etc/pihole/'
      - '/home/pi/pihole-doh-dot-install/dnsmasq.d/:/etc/dnsmasq.d/'
      - '/home/pi/pihole-doh-dot-install/config/:/config/'
    depends_on:
      - dhcphelper
    networks:
      backend:
        ipv4_address: '172.33.0.100'
  dhcphelper:
    build: ./dhcp-helper
    container_name: pihole-dhcp-helper
    hostname: dhcp-helper-svr
    restart: unless-stopped
    network_mode: "host"
    command: -s 172.33.0.100
    cap_add:
      - NET_ADMIN
networks:
  backend:
    ipam:
      config:
        - subnet: 172.33.0.0/16

@github-actions
Copy link

This issue is stale because it has been open 30 days with no activity. Please comment or update this issue or it will be closed in 5 days.

@github-actions github-actions bot added the stale label May 28, 2022
@iAmSaugata
Copy link

This issue still there.

@rdwebdesign
Copy link
Member

rdwebdesign commented Jun 1, 2022

What is the output of apt list -a libseccomp2?

And docker version?

@github-actions github-actions bot removed the stale label Jun 1, 2022
@iAmSaugata
Copy link

iAmSaugata commented Jun 1, 2022

Host:

Listing... Done
libseccomp2/stable,now 2.5.1-1+deb11u1 arm64 [installed]

libseccomp2/stable 2.5.1-1+deb11u1 armhf

Pihole:

Listing... Done
libseccomp2/stable,now 2.5.1-1+deb11u1 arm64 [installed,automatic]

Docker

Client: Docker Engine - Community
Version: 20.10.16
API version: 1.41
Go version: go1.17.10
Git commit: aa7e414
Built: Thu May 12 09:15:56 2022
OS/Arch: linux/arm64
Context: default
Experimental: true

Server: Docker Engine - Community
Engine:
Version: 20.10.16
API version: 1.41 (minimum version 1.12)
Go version: go1.17.10
Git commit: f756502
Built: Thu May 12 09:14:19 2022
OS/Arch: linux/arm64
Experimental: false
containerd:
Version: 1.6.4
GitCommit: 212e8b6fa2f44b9c21b2798135fc6fb7c53efc16
runc:
Version: 1.1.1
GitCommit: v1.1.1-0-g52de29d
docker-init:
Version: 0.19.0
GitCommit: de40ad0

@dschaper
Copy link
Member

dschaper commented Jun 1, 2022

What is the dockerfile for the image you are building?

@rdwebdesign
Copy link
Member

rdwebdesign commented Jun 1, 2022

I re-read this whole issue.

You said you are receiving this error message:

s6-svc: fatal: unable to control /var/run/s6/services/lighttpd-access-log: supervisor not listening

Do you have other problems besides this message?
Is your container running pi-hole correctly?

@iAmSaugata
Copy link

iAmSaugata commented Jun 2, 2022

Yes, I am only getting this message while rebooting the container, and this started from the latest version of pihole, other than getting this message, pihole itself running fine.

today at 5:25:31 PMStopping cron
today at 5:25:31 PMStopping lighttpd
today at 5:25:31 PMStopping pihole-FTL
today at 5:25:31 PM[cont-finish.d] executing container finish scripts...
today at 5:25:31 PMs6-svc: fatal: unable to control /var/run/s6/services/lighttpd-access-log: supervisor not listening
today at 5:25:31 PM[cont-finish.d] done.
today at 5:25:31 PM[s6-finish] waiting for services.
today at 5:25:31 PMs6-svc: fatal: unable to control /var/run/s6/services/lighttpd-error-log: supervisor not listening
today at 5:25:31 PM[s6-finish] sending all processes the TERM signal.
today at 5:25:34 PM[s6-finish] sending all processes the KILL signal and exiting.
today at 5:25:34 PMContainer stopped
today at 5:25:36 PMContainer started
today at 5:25:34 PM[s6-finish] sending all processes the KILL signal and exiting.
today at 5:25:36 PM[s6-init] making user provided files available at /var/run/s6/etc...exited 0.
today at 5:25:36 PM[s6-init] ensuring user provided files have correct perms...exited 0.

@rdwebdesign
Copy link
Member

So you don't have an issue.

Note:
We are aware of this message.
This message only appears when the container is stopped and some processes are killed before these 2 services. The services are probably already finished, but the script sends an error message. This message is harmless.

@iAmSaugata
Copy link

Yes, I don't have any issue other than those messages in the longs.

@ausfestivus
Copy link

Same problems here. Seeing the same errors.

Turns out that an underlying OS update had re-enabled systemd-resolve.

A quick

systemctl disable systemd-resolved.service
systemctl stop systemd-resolved

and were back to normal

@JohnyJack01
Copy link

I have the same issue, container start fails with the same error message. I am using OpenSuse 15.4 (latest packages installed) and have the latest libseccomp2 version installed. Using latest pihole docker image. Systemd-resoved is disabled. Any ideas?

@thomasmerz
Copy link

thomasmerz commented Jul 7, 2022

I found (by accident) that my Pi-hole installations running in docker container on OpenSUSE 15.3 also shows this message - but not my other installations running in docker container on Ubuntu LTS 22.04… 🤔

Docker Tag 2022.05 Pi-hole v5.10 FTL v5.15 Web Interface v5.12

2022-06-26T07:20:07.153833780Z [cont-finish.d] executing container finish scripts...
2022-06-26T07:20:07.153530700Z s6-svc: fatal: unable to control /var/run/s6/services/lighttpd-access-log: supervisor not listening
2022-06-26T07:20:07.154413156Z s6-svc: fatal: unable to control /var/run/s6/services/lighttpd-error-log: supervisor not listening
2022-06-26T07:20:07.154437636Z [cont-finish.d] done.
2022-06-26T07:20:07.154481434Z [s6-finish] waiting for services.
2022-06-26T07:20:07.384785922Z [s6-finish] sending all processes the TERM signal.

But Pi-hole is up and running 👍🏻

But is this hourly stopping+starting related to this?

…
2022-07-07T06:03:04.088400999Z Stopping pihole-FTL
2022-07-07T06:03:04.501757604Z Starting pihole-FTL (no-daemon) as pihole
2022-07-07T07:03:04.648493997Z Stopping pihole-FTL
2022-07-07T07:03:05.059152086Z Starting pihole-FTL (no-daemon) as pihole```

@PromoFaux
Copy link
Member

I have no idea if this will make a difference to folk here, but we've upgraded the version of S6 inside the container to v3, which is currently in the dev branch.

Please can you try the :dev tag and let us know how you get on?

@thomasmerz
Copy link

This is much worse now: docker container is crash-looping-forever 😢

$ docker logs -tf pihole 2022-07-18T09:10:49.760131874Z s6-rc: info: service cron: starting 2022-07-18T09:10:49.760604849Z s6-rc: info: service s6rc-oneshot-runner: starting 2022-07-18T09:10:49.762721559Z s6-rc: info: service cron successfully started 2022-07-18T09:10:49.764263076Z s6-rc: info: service s6rc-oneshot-runner successfully started 2022-07-18T09:10:49.766818904Z s6-rc: info: service fix-attrs: starting 2022-07-18T09:10:49.766829968Z s6-rc: info: service 1-startup: starting 2022-07-18T09:10:49.771727652Z fix-attrs: info: applying /etc/fix-attrs.d/01-resolver-resolv 2022-07-18T09:10:49.773193924Z fix-attrs: warning: fix-attrs is deprecated, please fix volume permissions in your container manager instead 2022-07-18T09:10:49.773211195Z Starting crond 2022-07-18T09:10:49.773323939Z s6-rc: info: service fix-attrs successfully started 2022-07-18T09:10:49.773668939Z s6-rc: info: service legacy-cont-init: starting 2022-07-18T09:10:49.776341620Z cont-init: info: running /etc/cont-init.d/05-changer-uid-gid.sh 2022-07-18T09:10:49.778155631Z cont-init: info: /etc/cont-init.d/05-changer-uid-gid.sh exited 0 2022-07-18T09:10:49.778711281Z s6-rc: info: service legacy-cont-init successfully started 2022-07-18T09:10:49.786727410Z ::: Starting docker specific checks & setup for docker pihole/pihole 2022-07-18T09:10:49.794080954Z 2022-07-18T09:10:49.794096634Z [i] Installing configs from /etc/.pihole... 2022-07-18T09:10:49.800519722Z [i] Existing dnsmasq.conf found... it is not a Pi-hole file, leaving alone! [✓] Installed /etc/dnsmasq.d/01-pihole.conf [✓] Installed /etc/dnsmasq.d/06-rfc6761.conf 2022-07-18T09:10:49.856390391Z [✓] Installing latest logrotate script 2022-07-18T09:10:50.075955807Z Existing DNS servers detected in setupVars.conf. Leaving them alone 2022-07-18T09:10:50.077167725Z Applying pihole-FTL.conf setting REPLY_ADDR4=0.0.0.0 2022-07-18T09:10:50.081211377Z FTL binding to default interface: eth0 2022-07-18T09:10:50.085782902Z ::: Enabling Query Logging 2022-07-18T09:10:50.121473992Z ::: Assigning password defined by Environment Variable 2022-07-18T09:10:50.146079966Z [✓] New password set 2022-07-18T09:10:50.146520477Z Setting Web Theme based on WEBTHEME variable, using value default-light 2022-07-18T09:10:50.164383285Z Added ENV to php: 2022-07-18T09:10:50.165019498Z "TZ" => "Europe/Berlin", 2022-07-18T09:10:50.165033859Z "PIHOLE_DOCKER_TAG" => "dev", 2022-07-18T09:10:50.165038484Z "PHP_ERROR_LOG" => "/var/log/lighttpd/error-pihole.log", 2022-07-18T09:10:50.165057946Z "CORS_HOSTS" => "", 2022-07-18T09:10:50.165062896Z "VIRTUAL_HOST" => "0.0.0.0", 2022-07-18T09:10:50.165770569Z Using IPv4 and IPv6 2022-07-18T09:10:50.186667581Z [✓] Setting admin contact to pihole-nimbus@rhoenwurz.de 2022-07-18T09:10:50.187329305Z ::: Preexisting ad list /etc/pihole/adlists.list detected ((exiting setup_blocklists early)) 2022-07-18T09:10:50.232674003Z ::: Testing lighttpd config: Syntax OK 2022-07-18T09:10:50.232692357Z ::: All config checks passed, cleared for startup ... 2022-07-18T09:10:50.234489224Z ::: Docker start setup complete 2022-07-18T09:10:50.256531125Z Pi-hole version is v5.11.4 (Latest: v5.11.4) 2022-07-18T09:10:50.286687934Z AdminLTE version is v5.13 (Latest: v5.13) 2022-07-18T09:10:50.293702083Z FTL version is v5.16.1 (Latest: v5.16.1) 2022-07-18T09:10:50.293712348Z Container tag is: dev 2022-07-18T09:10:50.293666883Z s6-rc: info: service 1-startup successfully started 2022-07-18T09:10:50.293722258Z s6-rc: info: service pihole-FTL: starting 2022-07-18T09:10:50.319829266Z s6-rc: info: service pihole-FTL successfully started 2022-07-18T09:10:50.319848356Z s6-rc: info: service 1-gravityonboot: starting 2022-07-18T09:10:50.320119904Z s6-rc: info: service lighttpd: starting 2022-07-18T09:10:50.322330117Z Checking if custom gravity.db is set in /etc/pihole/pihole-FTL.conf 2022-07-18T09:10:50.324736285Z s6-rc: info: service lighttpd successfully started 2022-07-18T09:10:50.337929962Z Starting pihole-FTL (no-daemon) as pihole 2022-07-18T09:10:50.344205435Z Starting lighttpd 2022-07-18T09:10:54.519138670Z [i] Neutrino emissions detected... [✓] Pulling blocklist source list into range 2022-07-18T09:10:54.535538915Z [✓] Preparing new gravity database 2022-07-18T09:10:54.575070356Z [i] Using libz compression 2022-07-18T09:10:54.575090426Z 2022-07-18T09:10:54.575125609Z [i] Target: https://raw.githubusercontent.com/StevenBlack/hosts/master/hosts 2022-07-18T09:10:54.764329646Z s6-sudoc: fatal: unable to get exit status from server: Operation timed out 2022-07-18T09:10:54.764373168Z s6-rc: fatal: timed out 2022-07-18T09:10:54.768055494Z /run/s6/basedir/scripts/rc.init: warning: s6-rc failed to properly bring all the services up! Check your logs (in /run/uncaught-logs/current if you have in-container logging) for more information. 2022-07-18T09:10:54.768097896Z prog: fatal: stopping the container. 2022-07-18T09:10:54.775282169Z s6-rc: info: service legacy-cont-init: stopping 2022-07-18T09:10:54.776481191Z s6-rc: info: service lighttpd: stopping 2022-07-18T09:10:54.777556842Z s6-rc: info: service cron: stopping 2022-07-18T09:10:54.785628796Z [i] Status: Pending...Stopping lighttpd 2022-07-18T09:10:54.795304894Z Stopping cron 2022-07-18T09:10:54.800022295Z s6-rc: info: service cron successfully stopped 2022-07-18T09:10:54.801014733Z s6-rc: info: service legacy-cont-init successfully stopped 2022-07-18T09:10:54.801195017Z s6-rc: info: service fix-attrs: stopping 2022-07-18T09:10:54.802764786Z s6-rc: info: service fix-attrs successfully stopped 2022-07-18T09:10:54.809806323Z s6-rc: info: service lighttpd successfully stopped 2022-07-18T09:10:54.810178243Z s6-rc: info: service pihole-FTL: stopping 2022-07-18T09:10:54.813092225Z Stopping pihole-FTL 2022-07-18T09:10:54.816466186Z s6-rc: info: service pihole-FTL successfully stopped 2022-07-18T09:10:54.816786959Z s6-rc: info: service 1-startup: stopping 2022-07-18T09:10:54.817651336Z s6-rc: info: service 1-startup successfully stopped 2022-07-18T09:10:54.817899629Z s6-rc: info: service s6rc-oneshot-runner: stopping 2022-07-18T09:10:54.820773286Z s6-rc: info: service s6rc-oneshot-runner successfully stopped 2022-07-18T09:11:00.723721649Z s6-rc: info: service cron: starting 2022-07-18T09:11:00.723884715Z s6-rc: info: service s6rc-oneshot-runner: starting 2022-07-18T09:11:00.725870441Z s6-rc: info: service s6rc-oneshot-runner successfully started 2022-07-18T09:11:00.728368799Z s6-rc: info: service fix-attrs: starting 2022-07-18T09:11:00.731795075Z s6-rc: info: service 1-startup: starting 2022-07-18T09:11:00.732841501Z s6-rc: info: service cron successfully started 2022-07-18T09:11:00.736389535Z Starting crond 2022-07-18T09:11:00.738243677Z fix-attrs: info: applying /etc/fix-attrs.d/01-resolver-resolv 2022-07-18T09:11:00.746599537Z fix-attrs: warning: fix-attrs is deprecated, please fix volume permissions in your container manager instead 2022-07-18T09:11:00.746646860Z ::: Starting docker specific checks & setup for docker pihole/pihole 2022-07-18T09:11:00.749134643Z s6-rc: info: service fix-attrs successfully started 2022-07-18T09:11:00.749153837Z s6-rc: info: service legacy-cont-init: starting 2022-07-18T09:11:00.762365971Z 2022-07-18T09:11:00.762390717Z [i] Installing configs from /etc/.pihole... 2022-07-18T09:11:00.762407518Z cont-init: info: running /etc/cont-init.d/05-changer-uid-gid.sh 2022-07-18T09:11:00.762412070Z cont-init: info: /etc/cont-init.d/05-changer-uid-gid.sh exited 0 2022-07-18T09:11:00.765191079Z s6-rc: info: service legacy-cont-init successfully started 2022-07-18T09:11:00.809473303Z [i] Existing dnsmasq.conf found... it is not a Pi-hole file, leaving alone! [✓] Installed /etc/dnsmasq.d/01-pihole.conf [✓] Installed /etc/dnsmasq.d/06-rfc6761.conf 2022-07-18T09:11:00.833913900Z 2022-07-18T09:11:00.836982531Z [i] Installing latest logrotate script... 2022-07-18T09:11:00.837000336Z [i] Existing logrotate file found. No changes made. 2022-07-18T09:11:00.868125709Z Existing DNS servers detected in setupVars.conf. Leaving them alone 2022-07-18T09:11:00.870158219Z Applying pihole-FTL.conf setting REPLY_ADDR4=0.0.0.0 2022-07-18T09:11:00.877872906Z FTL binding to default interface: eth0 2022-07-18T09:11:00.882910411Z ::: Enabling Query Logging 2022-07-18T09:11:00.921158062Z ::: Assigning password defined by Environment Variable 2022-07-18T09:11:00.951381757Z [✓] New password set 2022-07-18T09:11:00.951402492Z Setting Web Theme based on WEBTHEME variable, using value default-light 2022-07-18T09:11:00.968200285Z Added ENV to php: 2022-07-18T09:11:00.969328706Z "TZ" => "Europe/Berlin", 2022-07-18T09:11:00.969340607Z "PIHOLE_DOCKER_TAG" => "dev", 2022-07-18T09:11:00.969345537Z "PHP_ERROR_LOG" => "/var/log/lighttpd/error-pihole.log", 2022-07-18T09:11:00.969349640Z "CORS_HOSTS" => "", 2022-07-18T09:11:00.969353367Z "VIRTUAL_HOST" => "0.0.0.0", 2022-07-18T09:11:00.969872690Z Using IPv4 and IPv6 2022-07-18T09:11:00.988901083Z [✓] Setting admin contact to pihole-nimbus@rhoenwurz.de 2022-07-18T09:11:00.989298463Z ::: Preexisting ad list /etc/pihole/adlists.list detected ((exiting setup_blocklists early)) 2022-07-18T09:11:01.008343459Z ::: Testing lighttpd config: Syntax OK 2022-07-18T09:11:01.008642597Z ::: All config checks passed, cleared for startup ... 2022-07-18T09:11:01.008956663Z ::: Docker start setup complete 2022-07-18T09:11:01.020494263Z Pi-hole version is v5.11.4 (Latest: v5.11.4) 2022-07-18T09:11:01.026296845Z AdminLTE version is v5.13 (Latest: v5.13) 2022-07-18T09:11:01.030683151Z FTL version is v5.16.1 (Latest: v5.16.1) 2022-07-18T09:11:01.030877620Z Container tag is: dev 2022-07-18T09:11:01.031361733Z s6-rc: info: service 1-startup successfully started 2022-07-18T09:11:01.031533955Z s6-rc: info: service pihole-FTL: starting 2022-07-18T09:11:01.033049917Z s6-rc: info: service pihole-FTL successfully started 2022-07-18T09:11:01.033248432Z s6-rc: info: service 1-gravityonboot: starting 2022-07-18T09:11:01.033417955Z s6-rc: info: service lighttpd: starting 2022-07-18T09:11:01.034681139Z s6-rc: info: service lighttpd successfully started 2022-07-18T09:11:01.034870912Z Checking if custom gravity.db is set in /etc/pihole/pihole-FTL.conf 2022-07-18T09:11:01.036613012Z Starting lighttpd 2022-07-18T09:11:01.044782390Z Starting pihole-FTL (no-daemon) as pihole 2022-07-18T09:11:05.116833796Z [i] Neutrino emissions detected... [✓] Pulling blocklist source list into range 2022-07-18T09:11:05.148590853Z [✓] Preparing new gravity database 2022-07-18T09:11:05.188874569Z [i] Using libz compression 2022-07-18T09:11:05.189008818Z 2022-07-18T09:11:05.189457602Z [i] Target: https://raw.githubusercontent.com/StevenBlack/hosts/master/hosts [✓] Status: Retrieval successful 2022-07-18T09:11:05.728102952Z s6-rc: fatal: timed out 2022-07-18T09:11:05.728123159Z s6-sudoc: fatal: unable to get exit status from server: Operation timed out 2022-07-18T09:11:05.730107361Z /run/s6/basedir/scripts/rc.init: warning: s6-rc failed to properly bring all the services up! Check your logs (in /run/uncaught-logs/current if you have in-container logging) for more information. 2022-07-18T09:11:05.730147757Z prog: fatal: stopping the container. 2022-07-18T09:11:05.735556617Z s6-rc: info: service legacy-cont-init: stopping 2022-07-18T09:11:05.737670726Z s6-rc: info: service lighttpd: stopping 2022-07-18T09:11:05.738140953Z s6-rc: info: service cron: stopping 2022-07-18T09:11:05.743773287Z Stopping lighttpd 2022-07-18T09:11:05.746670967Z Stopping cron 2022-07-18T09:11:05.748668257Z s6-rc: info: service legacy-cont-init successfully stopped 2022-07-18T09:11:05.748793268Z s6-rc: info: service fix-attrs: stopping 2022-07-18T09:11:05.749734762Z s6-rc: info: service fix-attrs successfully stopped 2022-07-18T09:11:05.751538488Z s6-rc: info: service cron successfully stopped 2022-07-18T09:11:05.755189532Z s6-rc: info: service lighttpd successfully stopped 2022-07-18T09:11:05.755384266Z s6-rc: info: service pihole-FTL: stopping 2022-07-18T09:11:05.758105064Z Stopping pihole-FTL 2022-07-18T09:11:05.760206659Z s6-rc: info: service pihole-FTL successfully stopped 2022-07-18T09:11:05.760428931Z s6-rc: info: service 1-startup: stopping 2022-07-18T09:11:05.761503922Z s6-rc: info: service 1-startup successfully stopped 2022-07-18T09:11:05.761705266Z s6-rc: info: service s6rc-oneshot-runner: stopping 2022-07-18T09:11:05.763217482Z s6-rc: info: service s6rc-oneshot-runner successfully stopped 2022-07-18T09:11:11.759668967Z s6-rc: info: service cron: starting 2022-07-18T09:11:11.759767212Z s6-rc: info: service s6rc-oneshot-runner: starting 2022-07-18T09:11:11.765525831Z s6-rc: info: service s6rc-oneshot-runner successfully started 2022-07-18T09:11:11.768179607Z s6-rc: info: service fix-attrs: starting 2022-07-18T09:11:11.771472464Z Starting crond 2022-07-18T09:11:11.776595867Z s6-rc: info: service 1-startup: starting 2022-07-18T09:11:11.777114181Z s6-rc: info: service cron successfully started 2022-07-18T09:11:11.778885958Z fix-attrs: info: applying /etc/fix-attrs.d/01-resolver-resolv 2022-07-18T09:11:11.791467069Z fix-attrs: warning: fix-attrs is deprecated, please fix volume permissions in your container manager instead 2022-07-18T09:11:11.791602658Z ::: Starting docker specific checks & setup for docker pihole/pihole 2022-07-18T09:11:11.791619201Z s6-rc: info: service fix-attrs successfully started 2022-07-18T09:11:11.792571652Z s6-rc: info: service legacy-cont-init: starting 2022-07-18T09:11:11.796812626Z 2022-07-18T09:11:11.796833653Z [i] Installing configs from /etc/.pihole... 2022-07-18T09:11:11.816620005Z cont-init: info: running /etc/cont-init.d/05-changer-uid-gid.sh 2022-07-18T09:11:11.816645368Z [i] Existing dnsmasq.conf found... it is not a Pi-hole file, leaving alone! [✓] Installed /etc/dnsmasq.d/01-pihole.conf 2022-07-18T09:11:11.843157320Z cont-init: info: /etc/cont-init.d/05-changer-uid-gid.sh exited 0 2022-07-18T09:11:11.848296183Z s6-rc: info: service legacy-cont-init successfully started [✓] Installed /etc/dnsmasq.d/06-rfc6761.conf 2022-07-18T09:11:11.862173404Z 2022-07-18T09:11:11.864562898Z [i] Installing latest logrotate script... 2022-07-18T09:11:11.864582590Z [i] Existing logrotate file found. No changes made. 2022-07-18T09:11:11.894037176Z Existing DNS servers detected in setupVars.conf. Leaving them alone 2022-07-18T09:11:11.895894375Z Applying pihole-FTL.conf setting REPLY_ADDR4=0.0.0.0 2022-07-18T09:11:11.902693273Z FTL binding to default interface: eth0 2022-07-18T09:11:11.908075972Z ::: Enabling Query Logging 2022-07-18T09:11:11.944159532Z ::: Assigning password defined by Environment Variable 2022-07-18T09:11:11.967854050Z [✓] New password set 2022-07-18T09:11:11.968541178Z Setting Web Theme based on WEBTHEME variable, using value default-light 2022-07-18T09:11:11.985047940Z Added ENV to php: 2022-07-18T09:11:11.985948492Z "TZ" => "Europe/Berlin", 2022-07-18T09:11:11.985963651Z "PIHOLE_DOCKER_TAG" => "dev", 2022-07-18T09:11:11.985968943Z "PHP_ERROR_LOG" => "/var/log/lighttpd/error-pihole.log", 2022-07-18T09:11:11.985973105Z "CORS_HOSTS" => "", 2022-07-18T09:11:11.985976784Z "VIRTUAL_HOST" => "0.0.0.0", 2022-07-18T09:11:11.986260782Z Using IPv4 and IPv6 2022-07-18T09:11:12.005356281Z [✓] Setting admin contact to pihole-nimbus@rhoenwurz.de 2022-07-18T09:11:12.005948806Z ::: Preexisting ad list /etc/pihole/adlists.list detected ((exiting setup_blocklists early)) 2022-07-18T09:11:12.022901442Z ::: Testing lighttpd config: Syntax OK 2022-07-18T09:11:12.023030994Z ::: All config checks passed, cleared for startup ... 2022-07-18T09:11:12.023119046Z ::: Docker start setup complete 2022-07-18T09:11:12.034383144Z Pi-hole version is v5.11.4 (Latest: v5.11.4) 2022-07-18T09:11:12.040182475Z AdminLTE version is v5.13 (Latest: v5.13) 2022-07-18T09:11:12.044543894Z FTL version is v5.16.1 (Latest: v5.16.1) 2022-07-18T09:11:12.044784353Z Container tag is: dev 2022-07-18T09:11:12.045333401Z s6-rc: info: service 1-startup successfully started 2022-07-18T09:11:12.045624761Z s6-rc: info: service pihole-FTL: starting 2022-07-18T09:11:12.047164851Z s6-rc: info: service pihole-FTL successfully started 2022-07-18T09:11:12.047412317Z s6-rc: info: service 1-gravityonboot: starting 2022-07-18T09:11:12.048312560Z s6-rc: info: service lighttpd: starting 2022-07-18T09:11:12.049189884Z Checking if custom gravity.db is set in /etc/pihole/pihole-FTL.conf 2022-07-18T09:11:12.049981123Z s6-rc: info: service lighttpd successfully started 2022-07-18T09:11:12.051501367Z Starting lighttpd 2022-07-18T09:11:12.054524206Z Starting pihole-FTL (no-daemon) as pihole 2022-07-18T09:11:16.124574772Z [i] Neutrino emissions detected... [✓] Pulling blocklist source list into range 2022-07-18T09:11:16.137937842Z [✓] Preparing new gravity database 2022-07-18T09:11:16.158206492Z [i] Using libz compression 2022-07-18T09:11:16.158435722Z 2022-07-18T09:11:16.158723799Z [i] Target: https://raw.githubusercontent.com/StevenBlack/hosts/master/hosts [✓] Status: Retrieval successful 2022-07-18T09:11:16.764462978Z s6-rc: fatal: timed out 2022-07-18T09:11:16.765737250Z /run/s6/basedir/scripts/rc.init: warning: s6-rc failed to properly bring all the services up! Check your logs (in /run/uncaught-logs/current if you have in-container logging) for more information. 2022-07-18T09:11:16.765803046Z s6-sudoc: fatal: unable to get exit status from server: Operation timed out 2022-07-18T09:11:16.765932139Z prog: fatal: stopping the container. 2022-07-18T09:11:16.767811029Z s6-rc: info: service legacy-cont-init: stopping 2022-07-18T09:11:16.767940454Z s6-rc: info: service lighttpd: stopping 2022-07-18T09:11:16.768271199Z s6-rc: info: service cron: stopping 2022-07-18T09:11:16.770881645Z Stopping lighttpd 2022-07-18T09:11:16.771613547Z Stopping cron 2022-07-18T09:11:16.772618853Z s6-rc: info: service legacy-cont-init successfully stopped 2022-07-18T09:11:16.773529106Z s6-rc: info: service fix-attrs: stopping 2022-07-18T09:11:16.774185147Z s6-rc: info: service cron successfully stopped 2022-07-18T09:11:16.775019919Z s6-rc: info: service fix-attrs successfully stopped 2022-07-18T09:11:16.779625314Z s6-rc: info: service lighttpd successfully stopped 2022-07-18T09:11:16.779906178Z s6-rc: info: service pihole-FTL: stopping 2022-07-18T09:11:16.782763129Z Stopping pihole-FTL 2022-07-18T09:11:16.784827043Z s6-rc: info: service pihole-FTL successfully stopped 2022-07-18T09:11:16.784933277Z s6-rc: info: service 1-startup: stopping 2022-07-18T09:11:16.785914830Z s6-rc: info: service 1-startup successfully stopped 2022-07-18T09:11:16.785966786Z s6-rc: info: service s6rc-oneshot-runner: stopping 2022-07-18T09:11:16.787871351Z s6-rc: info: service s6rc-oneshot-runner successfully stopped 🦎🖥 ✔ ~/dev/docker-pi-hole [master|…6] 11:11 $ docker logs -tf pihole 2022-07-18T09:10:49.760131874Z s6-rc: info: service cron: starting 2022-07-18T09:10:49.760604849Z s6-rc: info: service s6rc-oneshot-runner: starting 2022-07-18T09:10:49.762721559Z s6-rc: info: service cron successfully started 2022-07-18T09:10:49.764263076Z s6-rc: info: service s6rc-oneshot-runner successfully started 2022-07-18T09:10:49.766818904Z s6-rc: info: service fix-attrs: starting 2022-07-18T09:10:49.766829968Z s6-rc: info: service 1-startup: starting 2022-07-18T09:10:49.771727652Z fix-attrs: info: applying /etc/fix-attrs.d/01-resolver-resolv 2022-07-18T09:10:49.773193924Z fix-attrs: warning: fix-attrs is deprecated, please fix volume permissions in your container manager instead 2022-07-18T09:10:49.773211195Z Starting crond 2022-07-18T09:10:49.773323939Z s6-rc: info: service fix-attrs successfully started 2022-07-18T09:10:49.773668939Z s6-rc: info: service legacy-cont-init: starting 2022-07-18T09:10:49.776341620Z cont-init: info: running /etc/cont-init.d/05-changer-uid-gid.sh 2022-07-18T09:10:49.778155631Z cont-init: info: /etc/cont-init.d/05-changer-uid-gid.sh exited 0 2022-07-18T09:10:49.778711281Z s6-rc: info: service legacy-cont-init successfully started 2022-07-18T09:10:49.786727410Z ::: Starting docker specific checks & setup for docker pihole/pihole 2022-07-18T09:10:49.794080954Z 2022-07-18T09:10:49.794096634Z [i] Installing configs from /etc/.pihole... 2022-07-18T09:10:49.800519722Z [i] Existing dnsmasq.conf found... it is not a Pi-hole file, leaving alone! [✓] Installed /etc/dnsmasq.d/01-pihole.conf [✓] Installed /etc/dnsmasq.d/06-rfc6761.conf 2022-07-18T09:10:49.856390391Z [✓] Installing latest logrotate script 2022-07-18T09:10:50.075955807Z Existing DNS servers detected in setupVars.conf. Leaving them alone 2022-07-18T09:10:50.077167725Z Applying pihole-FTL.conf setting REPLY_ADDR4=0.0.0.0 2022-07-18T09:10:50.081211377Z FTL binding to default interface: eth0 2022-07-18T09:10:50.085782902Z ::: Enabling Query Logging 2022-07-18T09:10:50.121473992Z ::: Assigning password defined by Environment Variable 2022-07-18T09:10:50.146079966Z [✓] New password set 2022-07-18T09:10:50.146520477Z Setting Web Theme based on WEBTHEME variable, using value default-light 2022-07-18T09:10:50.164383285Z Added ENV to php: 2022-07-18T09:10:50.165019498Z "TZ" => "Europe/Berlin", 2022-07-18T09:10:50.165033859Z "PIHOLE_DOCKER_TAG" => "dev", 2022-07-18T09:10:50.165038484Z "PHP_ERROR_LOG" => "/var/log/lighttpd/error-pihole.log", 2022-07-18T09:10:50.165057946Z "CORS_HOSTS" => "", 2022-07-18T09:10:50.165062896Z "VIRTUAL_HOST" => "0.0.0.0", 2022-07-18T09:10:50.165770569Z Using IPv4 and IPv6 2022-07-18T09:10:50.186667581Z [✓] Setting admin contact to pihole-nimbus@rhoenwurz.de 2022-07-18T09:10:50.187329305Z ::: Preexisting ad list /etc/pihole/adlists.list detected ((exiting setup_blocklists early)) 2022-07-18T09:10:50.232674003Z ::: Testing lighttpd config: Syntax OK 2022-07-18T09:10:50.232692357Z ::: All config checks passed, cleared for startup ... 2022-07-18T09:10:50.234489224Z ::: Docker start setup complete 2022-07-18T09:10:50.256531125Z Pi-hole version is v5.11.4 (Latest: v5.11.4) 2022-07-18T09:10:50.286687934Z AdminLTE version is v5.13 (Latest: v5.13) 2022-07-18T09:10:50.293702083Z FTL version is v5.16.1 (Latest: v5.16.1) 2022-07-18T09:10:50.293712348Z Container tag is: dev 2022-07-18T09:10:50.293666883Z s6-rc: info: service 1-startup successfully started 2022-07-18T09:10:50.293722258Z s6-rc: info: service pihole-FTL: starting 2022-07-18T09:10:50.319829266Z s6-rc: info: service pihole-FTL successfully started 2022-07-18T09:10:50.319848356Z s6-rc: info: service 1-gravityonboot: starting 2022-07-18T09:10:50.320119904Z s6-rc: info: service lighttpd: starting 2022-07-18T09:10:50.322330117Z Checking if custom gravity.db is set in /etc/pihole/pihole-FTL.conf 2022-07-18T09:10:50.324736285Z s6-rc: info: service lighttpd successfully started 2022-07-18T09:10:50.337929962Z Starting pihole-FTL (no-daemon) as pihole 2022-07-18T09:10:50.344205435Z Starting lighttpd 2022-07-18T09:10:54.519138670Z [i] Neutrino emissions detected... [✓] Pulling blocklist source list into range 2022-07-18T09:10:54.535538915Z [✓] Preparing new gravity database 2022-07-18T09:10:54.575070356Z [i] Using libz compression 2022-07-18T09:10:54.575090426Z 2022-07-18T09:10:54.575125609Z [i] Target: https://raw.githubusercontent.com/StevenBlack/hosts/master/hosts 2022-07-18T09:10:54.764329646Z s6-sudoc: fatal: unable to get exit status from server: Operation timed out 2022-07-18T09:10:54.764373168Z s6-rc: fatal: timed out 2022-07-18T09:10:54.768055494Z /run/s6/basedir/scripts/rc.init: warning: s6-rc failed to properly bring all the services up! Check your logs (in /run/uncaught-logs/current if you have in-container logging) for more information. 2022-07-18T09:10:54.768097896Z prog: fatal: stopping the container. 2022-07-18T09:10:54.775282169Z s6-rc: info: service legacy-cont-init: stopping 2022-07-18T09:10:54.776481191Z s6-rc: info: service lighttpd: stopping 2022-07-18T09:10:54.777556842Z s6-rc: info: service cron: stopping 2022-07-18T09:10:54.785628796Z [i] Status: Pending...Stopping lighttpd 2022-07-18T09:10:54.795304894Z Stopping cron 2022-07-18T09:10:54.800022295Z s6-rc: info: service cron successfully stopped 2022-07-18T09:10:54.801014733Z s6-rc: info: service legacy-cont-init successfully stopped 2022-07-18T09:10:54.801195017Z s6-rc: info: service fix-attrs: stopping 2022-07-18T09:10:54.802764786Z s6-rc: info: service fix-attrs successfully stopped 2022-07-18T09:10:54.809806323Z s6-rc: info: service lighttpd successfully stopped 2022-07-18T09:10:54.810178243Z s6-rc: info: service pihole-FTL: stopping 2022-07-18T09:10:54.813092225Z Stopping pihole-FTL 2022-07-18T09:10:54.816466186Z s6-rc: info: service pihole-FTL successfully stopped 2022-07-18T09:10:54.816786959Z s6-rc: info: service 1-startup: stopping 2022-07-18T09:10:54.817651336Z s6-rc: info: service 1-startup successfully stopped 2022-07-18T09:10:54.817899629Z s6-rc: info: service s6rc-oneshot-runner: stopping 2022-07-18T09:10:54.820773286Z s6-rc: info: service s6rc-oneshot-runner successfully stopped 2022-07-18T09:11:00.723721649Z s6-rc: info: service cron: starting 2022-07-18T09:11:00.723884715Z s6-rc: info: service s6rc-oneshot-runner: starting 2022-07-18T09:11:00.725870441Z s6-rc: info: service s6rc-oneshot-runner successfully started 2022-07-18T09:11:00.728368799Z s6-rc: info: service fix-attrs: starting 2022-07-18T09:11:00.731795075Z s6-rc: info: service 1-startup: starting 2022-07-18T09:11:00.732841501Z s6-rc: info: service cron successfully started 2022-07-18T09:11:00.736389535Z Starting crond 2022-07-18T09:11:00.738243677Z fix-attrs: info: applying /etc/fix-attrs.d/01-resolver-resolv 2022-07-18T09:11:00.746599537Z fix-attrs: warning: fix-attrs is deprecated, please fix volume permissions in your container manager instead 2022-07-18T09:11:00.746646860Z ::: Starting docker specific checks & setup for docker pihole/pihole 2022-07-18T09:11:00.749134643Z s6-rc: info: service fix-attrs successfully started 2022-07-18T09:11:00.749153837Z s6-rc: info: service legacy-cont-init: starting 2022-07-18T09:11:00.762365971Z 2022-07-18T09:11:00.762390717Z [i] Installing configs from /etc/.pihole... 2022-07-18T09:11:00.762407518Z cont-init: info: running /etc/cont-init.d/05-changer-uid-gid.sh 2022-07-18T09:11:00.762412070Z cont-init: info: /etc/cont-init.d/05-changer-uid-gid.sh exited 0 2022-07-18T09:11:00.765191079Z s6-rc: info: service legacy-cont-init successfully started 2022-07-18T09:11:00.809473303Z [i] Existing dnsmasq.conf found... it is not a Pi-hole file, leaving alone! [✓] Installed /etc/dnsmasq.d/01-pihole.conf [✓] Installed /etc/dnsmasq.d/06-rfc6761.conf 2022-07-18T09:11:00.833913900Z 2022-07-18T09:11:00.836982531Z [i] Installing latest logrotate script... 2022-07-18T09:11:00.837000336Z [i] Existing logrotate file found. No changes made. 2022-07-18T09:11:00.868125709Z Existing DNS servers detected in setupVars.conf. Leaving them alone 2022-07-18T09:11:00.870158219Z Applying pihole-FTL.conf setting REPLY_ADDR4=0.0.0.0 2022-07-18T09:11:00.877872906Z FTL binding to default interface: eth0 2022-07-18T09:11:00.882910411Z ::: Enabling Query Logging 2022-07-18T09:11:00.921158062Z ::: Assigning password defined by Environment Variable 2022-07-18T09:11:00.951381757Z [✓] New password set 2022-07-18T09:11:00.951402492Z Setting Web Theme based on WEBTHEME variable, using value default-light 2022-07-18T09:11:00.968200285Z Added ENV to php: 2022-07-18T09:11:00.969328706Z "TZ" => "Europe/Berlin", 2022-07-18T09:11:00.969340607Z "PIHOLE_DOCKER_TAG" => "dev", 2022-07-18T09:11:00.969345537Z "PHP_ERROR_LOG" => "/var/log/lighttpd/error-pihole.log", 2022-07-18T09:11:00.969349640Z "CORS_HOSTS" => "", 2022-07-18T09:11:00.969353367Z "VIRTUAL_HOST" => "0.0.0.0", 2022-07-18T09:11:00.969872690Z Using IPv4 and IPv6 2022-07-18T09:11:00.988901083Z [✓] Setting admin contact to pihole-nimbus@rhoenwurz.de 2022-07-18T09:11:00.989298463Z ::: Preexisting ad list /etc/pihole/adlists.list detected ((exiting setup_blocklists early)) 2022-07-18T09:11:01.008343459Z ::: Testing lighttpd config: Syntax OK 2022-07-18T09:11:01.008642597Z ::: All config checks passed, cleared for startup ... 2022-07-18T09:11:01.008956663Z ::: Docker start setup complete 2022-07-18T09:11:01.020494263Z Pi-hole version is v5.11.4 (Latest: v5.11.4) 2022-07-18T09:11:01.026296845Z AdminLTE version is v5.13 (Latest: v5.13) 2022-07-18T09:11:01.030683151Z FTL version is v5.16.1 (Latest: v5.16.1) 2022-07-18T09:11:01.030877620Z Container tag is: dev 2022-07-18T09:11:01.031361733Z s6-rc: info: service 1-startup successfully started 2022-07-18T09:11:01.031533955Z s6-rc: info: service pihole-FTL: starting 2022-07-18T09:11:01.033049917Z s6-rc: info: service pihole-FTL successfully started 2022-07-18T09:11:01.033248432Z s6-rc: info: service 1-gravityonboot: starting 2022-07-18T09:11:01.033417955Z s6-rc: info: service lighttpd: starting 2022-07-18T09:11:01.034681139Z s6-rc: info: service lighttpd successfully started 2022-07-18T09:11:01.034870912Z Checking if custom gravity.db is set in /etc/pihole/pihole-FTL.conf 2022-07-18T09:11:01.036613012Z Starting lighttpd 2022-07-18T09:11:01.044782390Z Starting pihole-FTL (no-daemon) as pihole 2022-07-18T09:11:05.116833796Z [i] Neutrino emissions detected... [✓] Pulling blocklist source list into range 2022-07-18T09:11:05.148590853Z [✓] Preparing new gravity database 2022-07-18T09:11:05.188874569Z [i] Using libz compression 2022-07-18T09:11:05.189008818Z 2022-07-18T09:11:05.189457602Z [i] Target: https://raw.githubusercontent.com/StevenBlack/hosts/master/hosts [✓] Status: Retrieval successful 2022-07-18T09:11:05.728102952Z s6-rc: fatal: timed out 2022-07-18T09:11:05.728123159Z s6-sudoc: fatal: unable to get exit status from server: Operation timed out 2022-07-18T09:11:05.730107361Z /run/s6/basedir/scripts/rc.init: warning: s6-rc failed to properly bring all the services up! Check your logs (in /run/uncaught-logs/current if you have in-container logging) for more information. 2022-07-18T09:11:05.730147757Z prog: fatal: stopping the container. 2022-07-18T09:11:05.735556617Z s6-rc: info: service legacy-cont-init: stopping 2022-07-18T09:11:05.737670726Z s6-rc: info: service lighttpd: stopping 2022-07-18T09:11:05.738140953Z s6-rc: info: service cron: stopping 2022-07-18T09:11:05.743773287Z Stopping lighttpd 2022-07-18T09:11:05.746670967Z Stopping cron 2022-07-18T09:11:05.748668257Z s6-rc: info: service legacy-cont-init successfully stopped 2022-07-18T09:11:05.748793268Z s6-rc: info: service fix-attrs: stopping 2022-07-18T09:11:05.749734762Z s6-rc: info: service fix-attrs successfully stopped 2022-07-18T09:11:05.751538488Z s6-rc: info: service cron successfully stopped 2022-07-18T09:11:05.755189532Z s6-rc: info: service lighttpd successfully stopped 2022-07-18T09:11:05.755384266Z s6-rc: info: service pihole-FTL: stopping 2022-07-18T09:11:05.758105064Z Stopping pihole-FTL 2022-07-18T09:11:05.760206659Z s6-rc: info: service pihole-FTL successfully stopped 2022-07-18T09:11:05.760428931Z s6-rc: info: service 1-startup: stopping 2022-07-18T09:11:05.761503922Z s6-rc: info: service 1-startup successfully stopped 2022-07-18T09:11:05.761705266Z s6-rc: info: service s6rc-oneshot-runner: stopping 2022-07-18T09:11:05.763217482Z s6-rc: info: service s6rc-oneshot-runner successfully stopped 2022-07-18T09:11:11.759668967Z s6-rc: info: service cron: starting 2022-07-18T09:11:11.759767212Z s6-rc: info: service s6rc-oneshot-runner: starting 2022-07-18T09:11:11.765525831Z s6-rc: info: service s6rc-oneshot-runner successfully started 2022-07-18T09:11:11.768179607Z s6-rc: info: service fix-attrs: starting 2022-07-18T09:11:11.771472464Z Starting crond 2022-07-18T09:11:11.776595867Z s6-rc: info: service 1-startup: starting 2022-07-18T09:11:11.777114181Z s6-rc: info: service cron successfully started 2022-07-18T09:11:11.778885958Z fix-attrs: info: applying /etc/fix-attrs.d/01-resolver-resolv 2022-07-18T09:11:11.791467069Z fix-attrs: warning: fix-attrs is deprecated, please fix volume permissions in your container manager instead 2022-07-18T09:11:11.791602658Z ::: Starting docker specific checks & setup for docker pihole/pihole 2022-07-18T09:11:11.791619201Z s6-rc: info: service fix-attrs successfully started 2022-07-18T09:11:11.792571652Z s6-rc: info: service legacy-cont-init: starting 2022-07-18T09:11:11.796812626Z 2022-07-18T09:11:11.796833653Z [i] Installing configs from /etc/.pihole... 2022-07-18T09:11:11.816620005Z cont-init: info: running /etc/cont-init.d/05-changer-uid-gid.sh 2022-07-18T09:11:11.816645368Z [i] Existing dnsmasq.conf found... it is not a Pi-hole file, leaving alone! [✓] Installed /etc/dnsmasq.d/01-pihole.conf 2022-07-18T09:11:11.843157320Z cont-init: info: /etc/cont-init.d/05-changer-uid-gid.sh exited 0 2022-07-18T09:11:11.848296183Z s6-rc: info: service legacy-cont-init successfully started [✓] Installed /etc/dnsmasq.d/06-rfc6761.conf 2022-07-18T09:11:11.862173404Z 2022-07-18T09:11:11.864562898Z [i] Installing latest logrotate script... 2022-07-18T09:11:11.864582590Z [i] Existing logrotate file found. No changes made. 2022-07-18T09:11:11.894037176Z Existing DNS servers detected in setupVars.conf. Leaving them alone 2022-07-18T09:11:11.895894375Z Applying pihole-FTL.conf setting REPLY_ADDR4=0.0.0.0 2022-07-18T09:11:11.902693273Z FTL binding to default interface: eth0 2022-07-18T09:11:11.908075972Z ::: Enabling Query Logging 2022-07-18T09:11:11.944159532Z ::: Assigning password defined by Environment Variable 2022-07-18T09:11:11.967854050Z [✓] New password set 2022-07-18T09:11:11.968541178Z Setting Web Theme based on WEBTHEME variable, using value default-light 2022-07-18T09:11:11.985047940Z Added ENV to php: 2022-07-18T09:11:11.985948492Z "TZ" => "Europe/Berlin", 2022-07-18T09:11:11.985963651Z "PIHOLE_DOCKER_TAG" => "dev", 2022-07-18T09:11:11.985968943Z "PHP_ERROR_LOG" => "/var/log/lighttpd/error-pihole.log", 2022-07-18T09:11:11.985973105Z "CORS_HOSTS" => "", 2022-07-18T09:11:11.985976784Z "VIRTUAL_HOST" => "0.0.0.0", 2022-07-18T09:11:11.986260782Z Using IPv4 and IPv6 2022-07-18T09:11:12.005356281Z [✓] Setting admin contact to pihole-nimbus@rhoenwurz.de 2022-07-18T09:11:12.005948806Z ::: Preexisting ad list /etc/pihole/adlists.list detected ((exiting setup_blocklists early)) 2022-07-18T09:11:12.022901442Z ::: Testing lighttpd config: Syntax OK 2022-07-18T09:11:12.023030994Z ::: All config checks passed, cleared for startup ... 2022-07-18T09:11:12.023119046Z ::: Docker start setup complete 2022-07-18T09:11:12.034383144Z Pi-hole version is v5.11.4 (Latest: v5.11.4) 2022-07-18T09:11:12.040182475Z AdminLTE version is v5.13 (Latest: v5.13) 2022-07-18T09:11:12.044543894Z FTL version is v5.16.1 (Latest: v5.16.1) 2022-07-18T09:11:12.044784353Z Container tag is: dev 2022-07-18T09:11:12.045333401Z s6-rc: info: service 1-startup successfully started 2022-07-18T09:11:12.045624761Z s6-rc: info: service pihole-FTL: starting 2022-07-18T09:11:12.047164851Z s6-rc: info: service pihole-FTL successfully started 2022-07-18T09:11:12.047412317Z s6-rc: info: service 1-gravityonboot: starting 2022-07-18T09:11:12.048312560Z s6-rc: info: service lighttpd: starting 2022-07-18T09:11:12.049189884Z Checking if custom gravity.db is set in /etc/pihole/pihole-FTL.conf 2022-07-18T09:11:12.049981123Z s6-rc: info: service lighttpd successfully started 2022-07-18T09:11:12.051501367Z Starting lighttpd 2022-07-18T09:11:12.054524206Z Starting pihole-FTL (no-daemon) as pihole 2022-07-18T09:11:16.124574772Z [i] Neutrino emissions detected... [✓] Pulling blocklist source list into range 2022-07-18T09:11:16.137937842Z [✓] Preparing new gravity database 2022-07-18T09:11:16.158206492Z [i] Using libz compression 2022-07-18T09:11:16.158435722Z 2022-07-18T09:11:16.158723799Z [i] Target: https://raw.githubusercontent.com/StevenBlack/hosts/master/hosts [✓] Status: Retrieval successful 2022-07-18T09:11:16.764462978Z s6-rc: fatal: timed out 2022-07-18T09:11:16.765737250Z /run/s6/basedir/scripts/rc.init: warning: s6-rc failed to properly bring all the services up! Check your logs (in /run/uncaught-logs/current if you have in-container logging) for more information. 2022-07-18T09:11:16.765803046Z s6-sudoc: fatal: unable to get exit status from server: Operation timed out 2022-07-18T09:11:16.765932139Z prog: fatal: stopping the container. 2022-07-18T09:11:16.767811029Z s6-rc: info: service legacy-cont-init: stopping 2022-07-18T09:11:16.767940454Z s6-rc: info: service lighttpd: stopping 2022-07-18T09:11:16.768271199Z s6-rc: info: service cron: stopping 2022-07-18T09:11:16.770881645Z Stopping lighttpd 2022-07-18T09:11:16.771613547Z Stopping cron 2022-07-18T09:11:16.772618853Z s6-rc: info: service legacy-cont-init successfully stopped 2022-07-18T09:11:16.773529106Z s6-rc: info: service fix-attrs: stopping 2022-07-18T09:11:16.774185147Z s6-rc: info: service cron successfully stopped 2022-07-18T09:11:16.775019919Z s6-rc: info: service fix-attrs successfully stopped 2022-07-18T09:11:16.779625314Z s6-rc: info: service lighttpd successfully stopped 2022-07-18T09:11:16.779906178Z s6-rc: info: service pihole-FTL: stopping 2022-07-18T09:11:16.782763129Z Stopping pihole-FTL 2022-07-18T09:11:16.784827043Z s6-rc: info: service pihole-FTL successfully stopped 2022-07-18T09:11:16.784933277Z s6-rc: info: service 1-startup: stopping 2022-07-18T09:11:16.785914830Z s6-rc: info: service 1-startup successfully stopped 2022-07-18T09:11:16.785966786Z s6-rc: info: service s6rc-oneshot-runner: stopping 2022-07-18T09:11:16.787871351Z s6-rc: info: service s6rc-oneshot-runner successfully stopped 2022-07-18T09:11:22.703900623Z s6-rc: info: service cron: starting 2022-07-18T09:11:22.703922100Z s6-rc: info: service s6rc-oneshot-runner: starting 2022-07-18T09:11:22.713302079Z s6-rc: info: service cron successfully started 2022-07-18T09:11:22.714776450Z Starting crond 2022-07-18T09:11:22.715041868Z s6-rc: info: service s6rc-oneshot-runner successfully started 2022-07-18T09:11:22.715527155Z s6-rc: info: service fix-attrs: starting 2022-07-18T09:11:22.715540928Z s6-rc: info: service 1-startup: starting 2022-07-18T09:11:22.719145199Z fix-attrs: info: applying /etc/fix-attrs.d/01-resolver-resolv 2022-07-18T09:11:22.720821043Z fix-attrs: warning: fix-attrs is deprecated, please fix volume permissions in your container manager instead 2022-07-18T09:11:22.721312217Z s6-rc: info: service fix-attrs successfully started 2022-07-18T09:11:22.721528128Z s6-rc: info: service legacy-cont-init: starting 2022-07-18T09:11:22.726306817Z cont-init: info: running /etc/cont-init.d/05-changer-uid-gid.sh 2022-07-18T09:11:22.728199895Z ::: Starting docker specific checks & setup for docker pihole/pihole 2022-07-18T09:11:22.728229583Z cont-init: info: /etc/cont-init.d/05-changer-uid-gid.sh exited 0 2022-07-18T09:11:22.728654708Z s6-rc: info: service legacy-cont-init successfully started 2022-07-18T09:11:22.730213788Z 2022-07-18T09:11:22.730225965Z [i] Installing configs from /etc/.pihole... 2022-07-18T09:11:22.732055331Z [i] Existing dnsmasq.conf found... it is not a Pi-hole file, leaving alone! [✓] Installed /etc/dnsmasq.d/01-pihole.conf [✓] Installed /etc/dnsmasq.d/06-rfc6761.conf 2022-07-18T09:11:22.775399725Z 2022-07-18T09:11:22.775419367Z [i] Installing latest logrotate script... 2022-07-18T09:11:22.775435643Z [i] Existing logrotate file found. No changes made. 2022-07-18T09:11:22.799913763Z Existing DNS servers detected in setupVars.conf. Leaving them alone 2022-07-18T09:11:22.801874891Z Applying pihole-FTL.conf setting REPLY_ADDR4=0.0.0.0 2022-07-18T09:11:22.809027622Z FTL binding to default interface: eth0 2022-07-18T09:11:22.813674868Z ::: Enabling Query Logging 2022-07-18T09:11:22.849337621Z ::: Assigning password defined by Environment Variable 2022-07-18T09:11:22.872258321Z [✓] New password set 2022-07-18T09:11:22.872603819Z Setting Web Theme based on WEBTHEME variable, using value default-light 2022-07-18T09:11:22.888772570Z Added ENV to php: 2022-07-18T09:11:22.889846114Z "TZ" => "Europe/Berlin", 2022-07-18T09:11:22.889860189Z "PIHOLE_DOCKER_TAG" => "dev", 2022-07-18T09:11:22.889865178Z "PHP_ERROR_LOG" => "/var/log/lighttpd/error-pihole.log", 2022-07-18T09:11:22.889869123Z "CORS_HOSTS" => "", 2022-07-18T09:11:22.889872889Z "VIRTUAL_HOST" => "0.0.0.0", 2022-07-18T09:11:22.890020503Z Using IPv4 and IPv6 2022-07-18T09:11:22.908837411Z [✓] Setting admin contact to pihole-nimbus@rhoenwurz.de 2022-07-18T09:11:22.909310136Z ::: Preexisting ad list /etc/pihole/adlists.list detected ((exiting setup_blocklists early)) 2022-07-18T09:11:22.926184092Z ::: Testing lighttpd config: Syntax OK 2022-07-18T09:11:22.926391519Z ::: All config checks passed, cleared for startup ... 2022-07-18T09:11:22.926402912Z ::: Docker start setup complete 2022-07-18T09:11:22.937415474Z Pi-hole version is v5.11.4 (Latest: v5.11.4) 2022-07-18T09:11:22.943137129Z AdminLTE version is v5.13 (Latest: v5.13) 2022-07-18T09:11:22.947482776Z FTL version is v5.16.1 (Latest: v5.16.1) 2022-07-18T09:11:22.947653021Z Container tag is: dev 2022-07-18T09:11:22.948223015Z s6-rc: info: service 1-startup successfully started 2022-07-18T09:11:22.948359159Z s6-rc: info: service pihole-FTL: starting 2022-07-18T09:11:22.949949689Z s6-rc: info: service pihole-FTL successfully started

@PromoFaux
Copy link
Member

@thomasmerz is that a fresh container with empty volume mounts? Looks like the gravity on boot one-shot is timing out... I've not seen that in my testing so far

2022-07-18T09:11:12.047412317Z s6-rc: info: service 1-gravityonboot: starting
2022-07-18T09:11:12.048312560Z s6-rc: info: service lighttpd: starting
2022-07-18T09:11:12.049189884Z   Checking if custom gravity.db is set in /etc/pihole/pihole-FTL.conf
2022-07-18T09:11:12.049981123Z s6-rc: info: service lighttpd successfully started
2022-07-18T09:11:12.051501367Z Starting lighttpd
2022-07-18T09:11:12.054524206Z Starting pihole-FTL (no-daemon) as pihole
2022-07-18T09:11:16.124574772Z   [i] Neutrino emissions detected...
  [✓] Pulling blocklist source list into range
2022-07-18T09:11:16.137937842Z
  [✓] Preparing new gravity database
2022-07-18T09:11:16.158206492Z   [i] Using libz compression
2022-07-18T09:11:16.158435722Z
2022-07-18T09:11:16.158723799Z   [i] Target: https://raw.githubusercontent.com/StevenBlack/hosts/master/hosts
  [✓] Status: Retrieval successful
2022-07-18T09:11:16.764462978Z s6-rc: fatal: timed out

The key here being:

2022-07-18T09:11:16.764462978Z s6-rc: fatal: timed out

I wonder what, if anything, is causing gravity to timeout. Can you try seeing the SKIPGRAVITYONBOOT env var to 1 and seeing if that makes a diff, please?

@edgd1er
Copy link
Contributor

edgd1er commented Jul 18, 2022

To troubleshoot start problems I 've added in dockerfile
ENV S6_BEHAVIOUR_IF_STAGE2_FAILS 1
ENV S6_CMD_WAIT_FOR_SERVICES_MAXTIME=30000

You could set theese in docker-compose env.
On a RPI3, I still have S6-rc time out but the container continue to boot thanks to S6_BEHAVIOUR_IF_STAGE2_FAILS 1.
I'm not sure the Wait maxtime is high enough to allow the service not being in timeout.
on a laptop, no timeout problem.

pihole-pihole-1  |   [i] List stayed unchanged
pihole-pihole-1  | s6-rc: fatal: timed out
pihole-pihole-1  | /run/s6/basedir/scripts/rc.init: warning: s6-rc failed to properly bring all the services up! Check your logs (in /run/uncaught-logs/current if you have in-container logging) for more information.
pihole-pihole-1  | s6-sudoc: fatal: unable to get exit status from server: Operation timed out
pihole-pihole-1  | 
pihole-pihole-1  |   [i] Target: https://s3.amazonaws.com/lists.disconnect.me/simple_tracking.txt
....
pihole-pihole-1  |   [✓] Pi-hole blocking is enabled

@PromoFaux
Copy link
Member

on a laptop, no timeout problem.

This may be where i was going wrong with my testing - it was on my desktop :D

@SnakeEater96

This comment was marked as off-topic.

@PromoFaux

This comment was marked as off-topic.

@SnakeEater96

This comment was marked as off-topic.

@PromoFaux

This comment was marked as off-topic.

@SnakeEater96

This comment was marked as off-topic.

@PromoFaux
Copy link
Member

@thomasmerz can you try again on latest dev, please?

@thomasmerz
Copy link

I tried with this:

Docker Tag dev Pi-hole v5.11.4 FTL v5.16.1 Web Interface v5.13

$ docker images|grep pihole
pihole/pihole dev a8ce65e0ac18 19 hours ago 305MB
pihole/pihole latest 2b580d272b6c 12 days ago 303MB

Result:

  • GUI is up and running (also https)
  • last message in docker logs -tf pihole is 2022-07-22T14:07:23.767315319Z [✓] Pi-hole blocking is enabled

@thomasmerz
Copy link

Running with latest again because this is my "production" Pi-hole at home running in Docker on OpenSUSE 15.3 <- this is the only case where this problem arose, but not on my other Pi-hole instances running on Ubuntu 22.04 LTS… 🤷🏼‍♂️

Docker Tag 2022.07.1 Pi-hole v5.11.4 FTL v5.16.1 Web Interface v5.13

Last messages:

…
2022-07-22T14:14:26.189659887Z  ::: Docker start setup complete
2022-07-22T14:14:26.190096461Z   Checking if custom gravity.db is set in /etc/pihole/pihole-FTL.conf
2022-07-22T14:14:26.207493690Z   Pi-hole version is v5.11.4 (Latest: v5.11.4)
2022-07-22T14:14:26.215472553Z   AdminLTE version is v5.13 (Latest: v5.13)
2022-07-22T14:14:26.222750375Z   FTL version is v5.16.1 (Latest: v5.16.1)
2022-07-22T14:14:26.223148161Z   Container tag is: 2022.07.1
2022-07-22T14:14:26.224206725Z [cont-init.d] 20-start.sh: exited 0.
2022-07-22T14:14:26.225515748Z [cont-init.d] done.
2022-07-22T14:14:26.226989639Z [services.d] starting services
2022-07-22T14:14:26.236832957Z Starting lighttpd
2022-07-22T14:14:26.237030980Z Starting crond
2022-07-22T14:14:26.237093908Z Starting pihole-FTL (no-daemon) as pihole
2022-07-22T14:14:26.239469474Z [services.d] done.

Waiting for…?!

…
2022-07-22T08:03:04.284896549Z Starting pihole-FTL (no-daemon) as pihole
2022-07-22T09:03:04.856634220Z Stopping pihole-FTL
2022-07-22T09:03:05.269458682Z Starting pihole-FTL (no-daemon) as pihole
2022-07-22T10:03:04.257280630Z Stopping pihole-FTL
2022-07-22T10:03:04.672792940Z Starting pihole-FTL (no-daemon) as pihole
2022-07-22T11:03:04.546169681Z Stopping pihole-FTL
2022-07-22T11:03:04.970292507Z Starting pihole-FTL (no-daemon) as pihole
2022-07-22T12:03:04.654210476Z Stopping pihole-FTL
2022-07-22T12:03:05.078003680Z Starting pihole-FTL (no-daemon) as pihole
2022-07-22T13:03:04.619979617Z Stopping pihole-FTL
…

@thomasmerz
Copy link

2022-07-22T15:03:04.676500960Z Stopping pihole-FTL 
2022-07-22T15:03:05.088685699Z Starting pihole-FTL (no-daemon) as pihole

@PromoFaux
Copy link
Member

Anything interesting in /var/log/pihole/FTL.log?

@thomasmerz
Copy link

If you mean that "errors" or "warnings" are "interesting": no.

root@pihole-merz-nimbus:/var/log/pihole# grep WARN FTL.log
[2022-07-22 17:03:04.942 539M] WARNING: Unable to remove PORT file: Permission denied
[2022-07-22 17:03:04.942 539M] WARNING: Unable to remove PID file: Permission denied
[2022-07-22 18:03:04.837 9083M] WARNING: Unable to remove PORT file: Permission denied
[2022-07-22 18:03:04.837 9083M] WARNING: Unable to remove PID file: Permission denied
root@pihole-merz-nimbus:/var/log/pihole# grep ERR FTL.log
[2022-07-22 16:40:06.065 539M] ERR: Port mismatch for 45.90.28.39: we derived 53, dnsmasq told us 43
[2022-07-22 16:40:06.066 539M] ERR: Port mismatch for 45.90.30.39: we derived 53, dnsmasq told us 43
[2022-07-22 16:40:06.066 539M] ERR: Port mismatch for 45.90.28.39: we derived 53, dnsmasq told us 43
[2022-07-22 16:40:06.066 539M] ERR: Port mismatch for 45.90.30.39: we derived 53, dnsmasq told us 43
[2022-07-22 16:40:06.105 539M] ERR: Port mismatch for 45.90.28.39: we derived 53, dnsmasq told us 43
[2022-07-22 16:40:06.105 539M] ERR: Port mismatch for 45.90.30.39: we derived 53, dnsmasq told us 43
[2022-07-22 16:45:07.915 539M] ERR: Port mismatch for 45.90.28.39: we derived 53, dnsmasq told us 43
[2022-07-22 16:45:07.915 539M] ERR: Port mismatch for 45.90.30.39: we derived 53, dnsmasq told us 43
[2022-07-22 16:45:07.916 539M] ERR: Port mismatch for 45.90.28.39: we derived 53, dnsmasq told us 43
[2022-07-22 16:45:07.916 539M] ERR: Port mismatch for 45.90.30.39: we derived 53, dnsmasq told us 43
[2022-07-22 16:45:07.917 539M] ERR: Port mismatch for 45.90.28.39: we derived 53, dnsmasq told us 43
[2022-07-22 16:45:07.917 539M] ERR: Port mismatch for 45.90.30.39: we derived 53, dnsmasq told us 43

@PromoFaux
Copy link
Member

The warnings can be ignored, and will be sorted on the next FTL release pi-hole/FTL#1384

The Err's however, that is a new one to me! @DL6ER ?

@DL6ER
Copy link
Member

DL6ER commented Jul 22, 2022

We've seen them before, it's a bug in dnsmasq and we already submitted a patch upstream: pi-hole/dnsmasq#10

However, there may be more similar bugs in the Dnsmasq code. @thomasmerz could you find the exact lines in pihole.log? If it is too noisy, we can enable debugging output (DEBUG_QUERIES=true) in FTL to make this a lot easier. Does it happen often (grep -c ...)?

@thomasmerz
Copy link

thomasmerz commented Jul 22, 2022

  • 12 times grep ERR FTL.log -ic
  • Did you really want the file numbers? 🤔
$ grep ERR -n FTL.log
108:[2022-07-22 16:40:06.065 539M] ERR: Port mismatch for 45.90.28.39: we derived 53, dnsmasq told us 43
109:[2022-07-22 16:40:06.066 539M] ERR: Port mismatch for 45.90.30.39: we derived 53, dnsmasq told us 43
110:[2022-07-22 16:40:06.066 539M] ERR: Port mismatch for 45.90.28.39: we derived 53, dnsmasq told us 43
111:[2022-07-22 16:40:06.066 539M] ERR: Port mismatch for 45.90.30.39: we derived 53, dnsmasq told us 43
112:[2022-07-22 16:40:06.105 539M] ERR: Port mismatch for 45.90.28.39: we derived 53, dnsmasq told us 43
113:[2022-07-22 16:40:06.105 539M] ERR: Port mismatch for 45.90.30.39: we derived 53, dnsmasq told us 43re 
115:[2022-07-22 16:45:07.915 539M] ERR: Port mismatch for 45.90.28.39: we derived 53, dnsmasq told us 43
116:[2022-07-22 16:45:07.915 539M] ERR: Port mismatch for 45.90.30.39: we derived 53, dnsmasq told us 43
117:[2022-07-22 16:45:07.916 539M] ERR: Port mismatch for 45.90.28.39: we derived 53, dnsmasq told us 43
118:[2022-07-22 16:45:07.916 539M] ERR: Port mismatch for 45.90.30.39: we derived 53, dnsmasq told us 43
119:[2022-07-22 16:45:07.917 539M] ERR: Port mismatch for 45.90.28.39: we derived 53, dnsmasq told us 43
120:[2022-07-22 16:45:07.917 539M] ERR: Port mismatch for 45.90.30.39: we derived 53, dnsmasq told us 43
  • there is no "ERR" or "WARN" in pihole.log - we were talking about FTL.log.

@DL6ER
Copy link
Member

DL6ER commented Jul 22, 2022

Sorry, I could have been clearer. These warning have been triggered by queries happening at that time. The queries causing these errors have been logged in pihole.log as FTL.log does not contain any debugging information without debugging mod enabled. That's why I asked for the "exact lines" over there.

@thomasmerz
Copy link

I got you and focussed on "2022-07-22 16:40:0x":

root@pihole-merz-nimbus:/var/log/pihole# grep ERR FTL.log
[2022-07-22 16:40:06.065 539M] ERR: Port mismatch for 45.90.28.39: we derived 53, dnsmasq told us 43
[2022-07-22 16:40:06.066 539M] ERR: Port mismatch for 45.90.30.39: we derived 53, dnsmasq told us 43
[2022-07-22 16:40:06.066 539M] ERR: Port mismatch for 45.90.28.39: we derived 53, dnsmasq told us 43
[2022-07-22 16:40:06.066 539M] ERR: Port mismatch for 45.90.30.39: we derived 53, dnsmasq told us 43
[2022-07-22 16:40:06.105 539M] ERR: Port mismatch for 45.90.28.39: we derived 53, dnsmasq told us 43
[2022-07-22 16:40:06.105 539M] ERR: Port mismatch for 45.90.30.39: we derived 53, dnsmasq told us 43
[2022-07-22 16:45:07.915 539M] ERR: Port mismatch for 45.90.28.39: we derived 53, dnsmasq told us 43
[2022-07-22 16:45:07.915 539M] ERR: Port mismatch for 45.90.30.39: we derived 53, dnsmasq told us 43
[2022-07-22 16:45:07.916 539M] ERR: Port mismatch for 45.90.28.39: we derived 53, dnsmasq told us 43
[2022-07-22 16:45:07.916 539M] ERR: Port mismatch for 45.90.30.39: we derived 53, dnsmasq told us 43
[2022-07-22 16:45:07.917 539M] ERR: Port mismatch for 45.90.28.39: we derived 53, dnsmasq told us 43
[2022-07-22 16:45:07.917 539M] ERR: Port mismatch for 45.90.30.39: we derived 53, dnsmasq told us 43
[2022-07-22 20:40:10.875 27492M] ERR: Port mismatch for 45.90.28.39: we derived 53, dnsmasq told us 43
[2022-07-22 20:40:10.875 27492M] ERR: Port mismatch for 45.90.30.39: we derived 53, dnsmasq told us 43
[2022-07-22 21:21:43.490 1182M] ERR: Port mismatch for 45.90.28.39: we derived 53, dnsmasq told us 43
[2022-07-22 21:21:43.490 1182M] ERR: Port mismatch for 45.90.30.39: we derived 53, dnsmasq told us 43

root@pihole-merz-nimbus:/var/log/pihole# grep "Jul 22 16:40:0" pihole.log | grep "45.90"
…
Jul 22 16:40:05 dnsmasq[539]: forwarded de.m.wikipedia.org to 45.90.28.39
Jul 22 16:40:05 dnsmasq[539]: forwarded de.m.wikipedia.org to 45.90.28.39
Jul 22 16:40:05 dnsmasq[539]: forwarded de.m.wikipedia.org to 45.90.28.39
Jul 22 16:40:05 dnsmasq[539]: dnssec-query[DS] wikipedia.org to 45.90.28.39
Jul 22 16:40:06 dnsmasq[539]: dnssec-retry[DS] wikipedia.org to 45.90.28.39
Jul 22 16:40:06 dnsmasq[539]: dnssec-retry[DS] wikipedia.org to 45.90.30.39
Jul 22 16:40:06 dnsmasq[539]: dnssec-retry[DS] wikipedia.org to 45.90.28.39
Jul 22 16:40:06 dnsmasq[539]: dnssec-retry[DS] wikipedia.org to 45.90.30.39
Jul 22 16:40:06 dnsmasq[539]: dnssec-retry[DS] wikipedia.org to 45.90.28.39
Jul 22 16:40:06 dnsmasq[539]: dnssec-retry[DS] wikipedia.org to 45.90.30.39
Jul 22 16:40:06 dnsmasq[539]: dnssec-query[DS] wikimedia.org to 45.90.28.39
Jul 22 16:40:06 dnsmasq[539]: forwarded dyna.wikimedia.org to 45.90.30.39
Jul 22 16:40:06 dnsmasq[539]: forwarded upload.wikimedia.org to 45.90.30.39
Jul 22 16:40:06 dnsmasq[539]: forwarded upload.wikimedia.org to 45.90.30.39
Jul 22 16:40:06 dnsmasq[539]: forwarded upload.wikimedia.org to 45.90.30.39
Jul 22 16:40:06 dnsmasq[539]: forwarded itunes.apple.com to 45.90.30.39
Jul 22 16:40:06 dnsmasq[539]: forwarded itunes.apple.com to 45.90.30.39
Jul 22 16:40:06 dnsmasq[539]: forwarded itunes.apple.com to 45.90.30.39
Jul 22 16:40:07 dnsmasq[539]: forwarded login.wikimedia.org to 45.90.30.39
Jul 22 16:40:07 dnsmasq[539]: forwarded login.wikimedia.org to 45.90.30.39
Jul 22 16:40:07 dnsmasq[539]: forwarded login.wikimedia.org to 45.90.30.39
Jul 22 16:40:07 dnsmasq[539]: forwarded meta.wikimedia.org to 45.90.30.39
Jul 22 16:40:07 dnsmasq[539]: forwarded meta.wikimedia.org to 45.90.30.39
Jul 22 16:40:07 dnsmasq[539]: forwarded meta.wikimedia.org to 45.90.30.39
Jul 22 16:40:07 dnsmasq[539]: forwarded meta.m.wikimedia.org to 45.90.30.39
Jul 22 16:40:07 dnsmasq[539]: forwarded meta.m.wikimedia.org to 45.90.30.39
Jul 22 16:40:07 dnsmasq[539]: forwarded meta.m.wikimedia.org to 45.90.30.39
Jul 22 16:40:08 dnsmasq[539]: forwarded meta.wikimedia.org to 45.90.28.39
Jul 22 16:40:08 dnsmasq[539]: forwarded meta.wikimedia.org to 45.90.30.39
root@pihole-merz-nimbus:/var/log/pihole#

@thomasmerz
Copy link

~16:45

…
Jul 22 16:45:06 dnsmasq[539]: dnssec-query[DS] eu to 45.90.28.39
Jul 22 16:45:06 dnsmasq[539]: dnssec-query[DS] amazon.eu to 45.90.28.39
Jul 22 16:45:07 dnsmasq[539]: forwarded arcus-uswest.amazon.com to 45.90.28.39
Jul 22 16:45:07 dnsmasq[539]: forwarded c.amazon-adsystem.com to 45.90.28.39
Jul 22 16:45:07 dnsmasq[539]: forwarded mads.amazon-adsystem.com to 45.90.28.39
Jul 22 16:45:07 dnsmasq[539]: forwarded mads-eu.amazon.com to 45.90.28.39
Jul 22 16:45:07 dnsmasq[539]: forwarded d1ykf07e75w7ss.cloudfront.net to 45.90.28.39
Jul 22 16:45:07 dnsmasq[539]: forwarded d1ykf07e75w7ss.cloudfront.net to 45.90.28.39
Jul 22 16:45:07 dnsmasq[539]: forwarded mads.amazon.com to 45.90.28.39
Jul 22 16:45:07 dnsmasq[539]: dnssec-retry[DS] amazon.eu to 45.90.28.39
Jul 22 16:45:07 dnsmasq[539]: dnssec-retry[DS] amazon.eu to 45.90.30.39
Jul 22 16:45:07 dnsmasq[539]: dnssec-retry[DS] amazon.eu to 45.90.28.39
Jul 22 16:45:07 dnsmasq[539]: dnssec-retry[DS] amazon.eu to 45.90.30.39
Jul 22 16:45:07 dnsmasq[539]: dnssec-retry[DS] amazon.eu to 45.90.28.39
Jul 22 16:45:07 dnsmasq[539]: dnssec-retry[DS] amazon.eu to 45.90.30.39
Jul 22 16:45:07 dnsmasq[539]: dnssec-query[DNSKEY] eu to 45.90.30.39

@thomasmerz
Copy link

~20:40:10

Jul 22 20:40:10 dnsmasq[27492]: dnssec-query[DS] fsit.com to 45.90.28.39
Jul 22 20:40:10 dnsmasq[27492]: forwarded diefsspeed001.fsit.com to 45.90.28.39
Jul 22 20:40:10 dnsmasq[27492]: forwarded speedtest.iway.ch to 45.90.28.39
Jul 22 20:40:10 dnsmasq[27492]: dnssec-query[DS] iway.ch to 45.90.28.39
Jul 22 20:40:10 dnsmasq[27492]: dnssec-query[DNSKEY] iway.ch to 45.90.28.39
Jul 22 20:40:10 dnsmasq[27492]: forwarded speedtest.iway.ch to 45.90.28.39
Jul 22 20:40:10 dnsmasq[27492]: dnssec-retry[DS] azure.com to 45.90.28.39
Jul 22 20:40:10 dnsmasq[27492]: dnssec-retry[DS] azure.com to 45.90.30.39
Jul 22 20:40:10 dnsmasq[27492]: forwarded pexsucpeu02.westeurope.cloudapp.azure.com to 45.90.28.39

@thomasmerz
Copy link

~21:21:43:

Jul 22 21:21:43 dnsmasq[1182]: dnssec-retry[DS] app to 45.90.28.39
Jul 22 21:21:43 dnsmasq[1182]: dnssec-retry[DS] app to 45.90.30.39
Jul 22 21:21:43 dnsmasq[1182]: forwarded lbnew.consentmanager.net to 45.90.28.39
Jul 22 21:21:43 dnsmasq[1182]: forwarded lbnew.consentmanager.net to 45.90.30.39
Jul 22 21:21:43 dnsmasq[1182]: forwarded 1376624012.rsc.cdn77.org to 45.90.28.39
Jul 22 21:21:43 dnsmasq[1182]: forwarded 1376624012.rsc.cdn77.org to 45.90.30.39
Jul 22 21:21:43 dnsmasq[1182]: dnssec-query[DS] permutive.app to 45.90.28.39
Jul 22 21:21:43 dnsmasq[1182]: dnssec-query[DNSKEY] app to 45.90.28.39
Jul 22 21:21:43 dnsmasq[1182]: dnssec-query[DNSKEY] permutive.app to 45.90.28.39

@DL6ER
Copy link
Member

DL6ER commented Jul 23, 2022

Okay, so nothing unexpected, these errors all come from dnssec-retry queries and this has already been fixed in the upstream dnsmasq code (which has not been released, so far). You can safely ignore these errors for now, FTL knows that there is an issue and uses the correct values nonetheless. It just informs you that something is off and might be worth reporting to us. But this one is already fixed.

@github-actions
Copy link

This issue is stale because it has been open 30 days with no activity. Please comment or update this issue or it will be closed in 5 days.

@github-actions github-actions bot added the stale label Aug 22, 2022
@Rikj000
Copy link

Rikj000 commented Aug 25, 2022

I'm currently experiencing the same issue and been unable to resolve it through the suggestions here / in other issues.

Environment

  • OS: Dietpi v8.7.1 - Debian GNU/Linux 11 (bullseye) aarch64
  • Host: Raspberry Pi 4 Model B Rev 1.4
  • Kernel: 5.15.56-v8+
  • Shell: fish 3.1.2
  • Libseccomp2: 2.5.1-1+deb11u1 arm64
  • Docker: 20.10.17, build 100c701
  • Dokku: 0.28.1
  • Ledokku: 0.7.0 PR#421

PiHole

  • Docker Tag: 2022.02.1
    • Pi-hole: v5.9
    • FTL: v5.14
    • Web Interface: v5.11

Latest

Show/Hide `dokku git:from-image pihole pihole/pihole:latest` logs

Fails with: s6-overlay-suexec: fatal: can only run as pid 1

dokku git:from-image pihole pihole/pihole:latest
================================================

-----> Generating build context
       Setting Dockerfile
-----> Updating git repository with specified build context
-----> Cleaning up...
-----> Building pihole from Dockerfile
Sending build context to Docker daemon  2.048kB
Step 1/8 : FROM pihole/pihole:latest
latest: Pulling from pihole/pihole
...
4b69808e513c: Pull complete
Digest: sha256:0077a645483b2152048bc3f42a0f88150806debaf7539f2ccd2bfd9d2f491835
Status: Downloaded newer image for pihole/pihole:latest
 ---> ac953a8efeb0
Step 2/8 : LABEL com.dokku.docker-image-labeler/alternate-tags=[\"pihole/pihole:latest\"]
 ---> Running in ae1c5fcf6e13
Removing intermediate container ae1c5fcf6e13
 ---> 224382ab6030
Step 3/8 : LABEL com.dokku.app-name=pihole
 ---> Running in 8aea695434a2
Removing intermediate container 8aea695434a2
 ---> 8143d594d537
Step 4/8 : LABEL com.dokku.builder-type=dockerfile
 ---> Running in 2a08dd5bcd68
Removing intermediate container 2a08dd5bcd68
 ---> 79ee7ef9dc51
Step 5/8 : LABEL com.dokku.image-stage=build
 ---> Running in 245b28db0d72
Removing intermediate container 245b28db0d72
 ---> 12048eff9548
Step 6/8 : LABEL dokku=
 ---> Running in ecba2579e597
Removing intermediate container ecba2579e597
 ---> 8bc9daa51e23
Step 7/8 : LABEL org.label-schema.schema-version=1.0
 ---> Running in 04395c732334
Removing intermediate container 04395c732334
 ---> ed0c0cc60548
Step 8/8 : LABEL org.label-schema.vendor=dokku
 ---> Running in 574d60a6fba1
Removing intermediate container 574d60a6fba1
 ---> 4a45b79e768c
Successfully built 4a45b79e768c
Successfully tagged dokku/pihole:latest
-----> Setting config vars
       DOKKU_DOCKERFILE_PORTS:  53/tcp 53/udp 67/udp 80/tcp
-----> Releasing pihole...
-----> Checking for predeploy task
       No predeploy task found, skipping
-----> Checking for release task
       No release task found, skipping
-----> Checking for first deploy postdeploy task
       No first deploy postdeploy task found, skipping
-----> No Procfile found in app image
=====> Processing deployment checks
       No CHECKS file found. Simple container checks will be performed.
       For more efficient zero downtime deployments, create a CHECKS file. See https://dokku.com/docs/deployment/zero-downtime-deploys/ for examples
-----> Deploying pihole via the docker-local scheduler...
-----> Deploying web (count=1)
       Attempting pre-flight checks (web.1)
       Waiting for 10 seconds (web.1)
0bcafa0c09fac5f7f640691cd4d5a5b32aecad7b734b911f61242c3530b3a97a
 !     App container failed to start (web.1)
=====> Start of pihole container output (web.1)
       s6-overlay-suexec: fatal: can only run as pid 1
       s6-overlay-suexec: fatal: can only run as pid 1
       s6-overlay-suexec: fatal: can only run as pid 1
       s6-overlay-suexec: fatal: can only run as pid 1
       s6-overlay-suexec: fatal: can only run as pid 1
       s6-overlay-suexec: fatal: can only run as pid 1
       s6-overlay-suexec: fatal: can only run as pid 1
=====> End of pihole container output (web.1)
parallel: This job failed:
/var/lib/dokku/plugins/available/scheduler-docker-local/bin/scheduler-deploy-process-container pihole dockerfile dokku/pihole:latest latest web 1 1

v2022.02.1

Show/Hide `dokku git:from-image pihole pihole/pihole:2022.02.1` logs

Builds without issues

dokku git:from-image pihole pihole/pihole:2022.02.1
===================================================

-----> Generating build context
       Setting Dockerfile
-----> Updating git repository with specified build context
-----> Cleaning up...
-----> Building pihole from Dockerfile
Sending build context to Docker daemon  2.048kB
Step 1/8 : FROM pihole/pihole:2022.02.1
2022.02.1: Pulling from pihole/pihole
...
06894eaaf0fa: Pull complete
Digest: sha256:60a9127372b0f7bb4b5eb09bc95e2735eb7b237999acf4bb079eb14b0f14632e
Status: Downloaded newer image for pihole/pihole:2022.02.1
 ---> 4493c74adab8
Step 2/8 : LABEL com.dokku.docker-image-labeler/alternate-tags=[\"pihole/pihole:2022.02.1\"]
 ---> Running in ea5ee2d7b123
Removing intermediate container ea5ee2d7b123
 ---> 7adb22c26354
Step 3/8 : LABEL com.dokku.app-name=pihole
 ---> Running in 5df8743906c9
Removing intermediate container 5df8743906c9
 ---> aa275dd7bdcd
Step 4/8 : LABEL com.dokku.builder-type=dockerfile
 ---> Running in aeafc632830b
Removing intermediate container aeafc632830b
 ---> 7d3aa64a4b6a
Step 5/8 : LABEL com.dokku.image-stage=build
 ---> Running in 1e07a8c75dda
Removing intermediate container 1e07a8c75dda
 ---> c2a4b5f08f6a
Step 6/8 : LABEL dokku=
 ---> Running in bb50def8f3ed
Removing intermediate container bb50def8f3ed
 ---> 7e0dab56cd78
Step 7/8 : LABEL org.label-schema.schema-version=1.0
 ---> Running in b1a319b2a879
Removing intermediate container b1a319b2a879
 ---> 68466516041f
Step 8/8 : LABEL org.label-schema.vendor=dokku
 ---> Running in ef30e23241bf
Removing intermediate container ef30e23241bf
 ---> 8f5f98342700
Successfully built 8f5f98342700
Successfully tagged dokku/pihole:latest
-----> Setting config vars
       DOKKU_DOCKERFILE_PORTS:  53/tcp 53/udp 67/udp 80/tcp
-----> Releasing pihole...
-----> Checking for predeploy task
       No predeploy task found, skipping
-----> Checking for release task
       No release task found, skipping
-----> Checking for first deploy postdeploy task
       No first deploy postdeploy task found, skipping
-----> No Procfile found in app image
=====> Processing deployment checks
       No CHECKS file found. Simple container checks will be performed.
       For more efficient zero downtime deployments, create a CHECKS file. See https://dokku.com/docs/deployment/zero-downtime-deploys/ for examples
-----> Deploying pihole via the docker-local scheduler...
-----> Deploying web (count=1)
       Attempting pre-flight checks (web.1)
       Waiting for 10 seconds (web.1)
       Default container check successful (web.1)
-----> Running post-deploy
-----> Creating new app virtual host file...
-----> Configuring pihole.my-domain.com...(using built-in template)
-----> Creating http nginx.conf
       Reloading nginx
-----> Renaming containers
       Renaming container pihole.web.1.upcoming-13246 (c5837d953ba5) to pihole.web.1
-----> Checking for postdeploy task
       No postdeploy task found, skipping
=====> Application deployed:
       http://pihole.my-domain.com
       http://pihole.my-domain.com:53
Show/Hide `dokku run pihole` logs

Throws s6-svc: fatal: unable to control /var/run/s6/services/lighttpd-access-log: supervisor not listening when entering container CLI.

dokku run pihole
================

[s6-init] making user provided files available at /var/run/s6/etc...exited 0.
[s6-init] ensuring user provided files have correct perms...exited 0.
[fix-attrs.d] applying ownership & permissions fixes...
[fix-attrs.d] 01-resolver-resolv: applying... 
[fix-attrs.d] 01-resolver-resolv: exited 0.
[fix-attrs.d] done.
[cont-init.d] executing container initialization scripts...
[cont-init.d] 05-changer-uid-gid.sh: executing... 
[cont-init.d] 05-changer-uid-gid.sh: exited 0.
[cont-init.d] 20-start.sh: executing... 
 ::: Starting docker specific checks & setup for docker pihole/pihole
Assigning random password: my-password

  [i] Installing configs from /etc/.pihole...
  [i] Existing dnsmasq.conf found... it is not a Pi-hole file, leaving alone!
  [✓] Installed /etc/dnsmasq.d/01-pihole.conf
  [✓] Installed /etc/dnsmasq.d/06-rfc6761.conf
Existing DNS servers detected in setupVars.conf. Leaving them alone
  [✓] New password set
DNSMasq binding to default interface: eth0
Added ENV to php:
			"TZ" => "",
			"PIHOLE_DOCKER_TAG" => "2022.02.1",
			"PHP_ERROR_LOG" => "/var/log/lighttpd/error.log",
			"ServerIP" => "0.0.0.0",
			"CORS_HOSTS" => "",
			"VIRTUAL_HOST" => "0.0.0.0",
Using IPv4 and IPv6
::: setup_blocklists now setting default blocklists up: 
::: TIP: Use a docker volume for /etc/pihole/adlists.list if you want to customize for first boot
::: Blocklists (/etc/pihole/adlists.list) now set to:
https://raw.githubusercontent.com/StevenBlack/hosts/master/hosts
::: Testing lighttpd config: Syntax OK
::: All config checks passed, cleared for startup ...
::: Enabling Query Logging
  [i] Enabling logging...
  [✓] Restarting DNS server
  [✓] Logging has been enabled!
 ::: Docker start setup complete
  Checking if custom gravity.db is set in /etc/pihole/pihole-FTL.conf
  Pi-hole version is v5.9 (Latest: v5.9)
  AdminLTE version is v5.11 (Latest: v5.11)
  FTL version is v5.14 (Latest: v5.14)
  Container tag is: 2022.02.1
[cont-init.d] 20-start.sh: exited 0.
[cont-init.d] done.
[services.d] starting services
Starting crond
Starting lighttpd
Starting pihole-FTL (no-daemon) as pihole
[services.d] done.
root@1b8b62e785eb:/# pihole status
  [✓] FTL is listening on port 53
     [✓] UDP (IPv4)
     [✓] TCP (IPv4)
     [✓] UDP (IPv6)
     [✓] TCP (IPv6)

  [✓] Pi-hole blocking is enabled
root@1b8b62e785eb:/# cat /var/log/lighttpd/error.log
2022-08-25 19:39:24: (server.c.1464) server started (lighttpd/1.4.53) 
root@1b8b62e785eb:/# exit
exit
[cmd] /bin/bash exited 0
Stopping pihole-FTL
Stopping cron
Stopping lighttpd
s6-svc: fatal: unable to control /var/run/s6/services/lighttpd-access-log: supervisor not listening
[cont-finish.d] executing container finish scripts...
[cont-finish.d] done.
[s6-finish] waiting for services.
s6-svc: fatal: unable to control /var/run/s6/services/lighttpd-error-log: supervisor not listening
[s6-finish] sending all processes the TERM signal.
[s6-finish] sending all processes the KILL signal and exiting.

@PromoFaux
Copy link
Member

The issue you are seeing is actually: #1176

Please try the :exp tag

@PromoFaux
Copy link
Member

s6-svc: fatal: unable to control /var/run/s6/services/lighttpd-access-log: supervisor not listening

This can be ignored, and AFAIK is not an issue on the latest images

@Rikj000
Copy link

Rikj000 commented Aug 25, 2022

The issue you are seeing is actually: #1176

Please try the :exp tag

Will post my test-results there in a moment, thanks for the quick reply!

@thomasmerz
Copy link

thomasmerz commented Sep 2, 2022

Just FYI: My problem (stoping/starting every hour) that I have mentioned above has vanished… 🤷🏼‍♂️

Current Setup (auto-updating once a week on mondays):

Tag 2022.08.2 Pi-hole v5.11.4 FTL v5.16.3 Web Interface v5.13

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Projects
None yet
Development

No branches or pull requests