Bug: Problem at docker startup #487

Open
opened 2025-11-20 04:24:31 -05:00 by saavagebueno · 0 comments
Owner

Originally created by @Starlord0006 on GitHub (Jan 13, 2025).

TLDR: When de docker start first query fail

  1. Is this urgent: No
  2. DNS provider(s) you use: EASYDNS
  3. Program version: Running version latest built on 2024-12-24T09:20:36.130Z (commit 78f3061)
  4. What are you using to run the container: QNAP (Container Station)
  5. Extra information (optional)

Logs:

Version / Config

========================================
========================================
============= ddns-updater =============
========================================
=========== Made with ❤️ by ============
======= https://github.com/qdm12 =======
========================================
========================================

Running version latest built on 2024-12-24T09:20:36.130Z (commit 78f3061)

🔧 Need help? ☕ Discussion? https://github.com/qdm12/ddns-updater/discussions/new/choose
🐛 Bug? ✨ New feature? https://github.com/qdm12/ddns-updater/issues/new/choose
💻 Email? quentin.mcgaw@gmail.com
💰 Help me? https://www.paypal.me/qmcgaw https://github.com/sponsors/qdm12
2025-01-13T12:59:35-05:00 INFO Settings summary:
├── HTTP client
|   └── Timeout: 10s
├── Update
|   ├── Period: 10m0s
|   └── Cooldown: 6m0s
├── Public IP fetching
|   ├── HTTP enabled: yes
|   ├── HTTP IP providers
|   |   └── all
|   ├── HTTP IPv4 providers
|   |   └── all
|   ├── HTTP IPv6 providers
|   |   └── all
|   ├── DNS enabled: yes
|   ├── DNS timeout: 10s
|   └── DNS over TLS providers
|       └── all
├── Resolver: use Go default resolver
├── Server
|   ├── Listening address: :80
|   └── Root URL: /
├── Health
|   ├── Server listening address: 127.0.0.1:9999
|   ├── Healthchecks.io base URL: https://hc-ping.com
|   └── Healthchecks.io UUID: 8*****27-c09a-4**1-bd7e-e********f3
├── Paths
|   ├── Data directory: /updater/data
|   ├── Config file: /updater/data/config.json
|   └── Umask: system default
├── Backup: disabled
├── Logger
|   ├── Level: debug
|   └── Caller: hidden
└── Shoutrrr
    ├── Default title: DDNS_Updater
    └── Addresses
        └── telegram://74******07:AA****sy_BA********OBNEji3OGs****c@telegram?chats=66******33
2025-01-13T12:59:35-05:00 INFO reading JSON config from file /updater/data/config.json
2025-01-13T12:59:35-05:00 DEBUG config read: {
  "settings": [
    {
      "provider": "easydns",
      "domain": "starlord.ca",
      "username": "ABC",
      "token": "2***************2",
      "ip_version": "ipv4",
      "ipv6_suffix": ""
    },
    {
      "provider": "easydns",
      "domain": "*.starlord.ca",
      "username": "ABC",
      "token": "2***************2",
      "ip_version": "ipv4",
      "ipv6_suffix": ""
    }
  ]
}

Config read

2025-01-13T12:59:35-05:00 INFO Found 2 settings to update records
2025-01-13T12:59:45-05:00 WARN performing request: Get "https://github.com": context deadline exceeded (Client.Timeout exceeded while awaiting headers)
2025-01-13T12:59:45-05:00 INFO Reading history from database: domain starlord.ca owner @ ipv4
2025-01-13T12:59:45-05:00 INFO Reading history from database: domain starlord.ca owner * ipv4
2025-01-13T12:59:45-05:00 INFO [healthcheck server] health http server listening on 127.0.0.1:9999
2025-01-13T12:59:45-05:00 INFO [http server] http server listening on [::]:80
2025-01-13T12:59:45-05:00 INFO [backup] disabled

1st Query (FAIL)

2025-01-13T12:59:45-05:00 DEBUG configured to fetch IP: v4 or v6: false, v4: true, v6: false
2025-01-13T12:59:50-05:00 DEBUG obtaining ipv4 address: try 1 of 3 failed: Get "https://ipv4.seeip.org": context deadline exceeded
2025-01-13T12:59:51-05:00 DEBUG obtaining ipv4 address: try 2 of 3 failed: dial tcp4 1.1.1.1:853: connect: no route to host
2025-01-13T12:59:55-05:00 ERROR [shoutrrr] telegram: failed to send using telegram: timed out
2025-01-13T12:59:56-05:00 DEBUG obtaining ipv4 address: try 3 of 3 failed: Get "https://api.ipify.org": context deadline exceeded
2025-01-13T12:59:56-05:00 DEBUG your public IP address are: v4 or v6: invalid IP, v4: invalid IP, v6: invalid IP
2025-01-13T12:59:56-05:00 ERROR obtaining ipv4 address: after 3 tries, errors were: Get "https://ipv4.seeip.org": context deadline exceeded, dial tcp4 1.1.1.1:853: connect: no route to host, Get "https://api.ipify.org": context deadline exceeded
2025-01-13T12:59:56-05:00 WARN Skipping update for starlord.ca because ipv4 address was not found
2025-01-13T12:59:56-05:00 WARN Skipping update for any.starlord.ca because ipv4 address was not found
2025-01-13T13:00:06-05:00 ERROR pinging healthchecks.io failed: doing http request: Get "https://hc-ping.com/8*****27-c09a-4**1-bd7e-e********f3/fail": context deadline exceeded (Client.Timeout exceeded while awaiting headers)
2025/01/13 13:00:23 "GET http://192.168.210.25:80/ HTTP/1.1" from 192.168.210.20:40176 - 200 2909B in 100.161µs

Web Query (both entry failed)

2025/01/13 13:05:23 "GET http://192.168.210.25/ HTTP/1.1" from 192.168.110.90:51337 - 200 2913B in 94.82µs
2025/01/13 13:05:23 "GET http://192.168.210.25/static/styles.css HTTP/1.1" from 192.168.110.90:51337 - 200 3203B in 310.231µs
2025/01/13 13:05:23 "GET http://192.168.210.25/static/favicon.ico HTTP/1.1" from 192.168.110.90:51337 - 200 15086B in 158.22µs

image

2nd Query (Success)

2025-01-13T13:09:45-05:00 DEBUG configured to fetch IP: v4 or v6: false, v4: true, v6: false
2025-01-13T13:09:45-05:00 DEBUG your public IP address are: v4 or v6: invalid IP, v4: 76.71.217.182, v6: invalid IP
2025-01-13T13:09:45-05:00 INFO ipv4 address of starlord.ca is 76.71.217.223 and your ipv4 address is 76.71.217.182
2025-01-13T13:09:45-05:00 INFO ipv4 address of any.starlord.ca is 76.71.217.222 and your ipv4 address is 76.71.217.182
2025-01-13T13:09:45-05:00 INFO Updating record [domain: starlord.ca | owner: @ | provider: easydns | ip: ipv4] to use 76.71.217.182
2025-01-13T13:09:45-05:00 DEBUG GET https://ABC:2***************2@api.cp.easydns.com/dyn/generic.php?hostname=starlord.ca&myip=76.71.217.182 | headers: User-Agent: DDNS-Updater quentin.mcgaw@gmail.com; Authorization: Basic c3RhcmxvcmQ6MjE2MTdGNDBBMDBCREY5Mg==
2025-01-13T13:09:47-05:00 DEBUG 200 OK | headers: Expires: Thu, 19 Nov 1981 08:52:00 GMT; Cache-Control: no-store, no-cache, must-revalidate; X-Frame-Options: sameorigin; Content-Security-Policy: frame-ancestors 'self';; Vary: Accept-Encoding; Date: Mon, 13 Jan 2025 18:09:45 GMT; Server: Apache; Pragma: no-cache; Set-Cookie: easydns_language=en_US; expires=Tue, 13-Jan-2026 18:09:45 GMT; Max-Age=31536000; path=/,EasyDNS_SID=5a44c6c2e78583c4081cb23b46e5d636; expires=Mon, 13-Jan-2025 20:09:46 GMT; Max-Age=7200; path=/; domain=api.cp.easydns.com; secure; HttpOnly; Content-Type: text/html; charset=UTF-8 | body: <HTML><BODY><FONT FACE="sans-serif" SIZE="-1">OK<br /><hr noshade size="1">starlord.ca updated to 76.71.217.182<br /></FONT></BODY></HTML>
2025-01-13T13:09:48-05:00 INFO Updating record [domain: starlord.ca | owner: * | provider: easydns | ip: ipv4] to use 76.71.217.182
2025-01-13T13:09:48-05:00 DEBUG GET https://ANC:2***************2@api.cp.easydns.com/dyn/generic.php?hostname=%2A.starlord.ca&myip=76.71.217.182&wildcard=ON | headers: User-Agent: DDNS-Updater quentin.mcgaw@gmail.com; Authorization: Basic c3RhcmxvcmQ6MjE2MTdGNDBBMDBCREY5Mg==
2025-01-13T13:09:50-05:00 DEBUG 200 OK | headers: Cache-Control: no-store, no-cache, must-revalidate; Set-Cookie: easydns_language=en_US; expires=Tue, 13-Jan-2026 18:09:48 GMT; Max-Age=31536000; path=/,EasyDNS_SID=43ecb964b3360f162437567e08c7221d; expires=Mon, 13-Jan-2025 20:09:48 GMT; Max-Age=7200; path=/; domain=api.cp.easydns.com; secure; HttpOnly; Vary: Accept-Encoding; Content-Type: text/html; charset=UTF-8; Expires: Thu, 19 Nov 1981 08:52:00 GMT; Server: Apache; Pragma: no-cache; X-Frame-Options: sameorigin; Content-Security-Policy: frame-ancestors 'self';; Date: Mon, 13 Jan 2025 18:09:48 GMT | body: <HTML><BODY><FONT FACE="sans-serif" SIZE="-1">OK<br /><hr noshade size="1">*.starlord.ca updated to 76.71.217.182<br /></FONT></BODY></HTML>

Web Query (both entry Success)

2025/01/13 13:13:36 "GET http://192.168.210.25/ HTTP/1.1" from 192.168.110.90:51754 - 200 3023B in 94.746µs
2025/01/13 13:13:36 "GET http://192.168.210.25/static/styles.css HTTP/1.1" from 192.168.110.90:51754 - 200 3203B in 138.765µs
2025/01/13 13:13:36 "GET http://192.168.210.25/static/favicon.ico HTTP/1.1" from 192.168.110.90:51754 - 200 15086B in 231.134µs

image

Configuration file (remove your credentials!):

{
  "settings": [
    {
      "provider": "easydns",
      "domain": "starlord.ca",
      "username": "ABC",
      "token": "2***************2",
      "ip_version": "ipv4",
      "ipv6_suffix": ""
    },
    {
      "provider": "easydns",
      "domain": "*.starlord.ca",
      "username": "ABC",
      "token": "2***************2",
      "ip_version": "ipv4",
      "ipv6_suffix": ""
    }
  ]
}

Host OS: QTS 5.2.2.2958

Observation:
I use dedicated IP on a spécific VLAN.

Query start to quick, the network stack not ready when the check is made. Network stack look's to be ready about 30 seconds after the container is started.

I tried with NAT network on specific port, aned I dont have this error. Is it possible to add a 30 seconds delay for the first Internet interaction?

Thanks

Originally created by @Starlord0006 on GitHub (Jan 13, 2025). <!-- YOU CAN CHAT THERE EVENTUALLY: https://github.com/qdm12/ddns-updater/discussions --> **TLDR**: *When de docker start first query fail* 1. Is this urgent: No 2. DNS provider(s) you use: EASYDNS 3. Program version: Running version latest built on 2024-12-24T09:20:36.130Z (commit 78f3061) 4. What are you using to run the container: QNAP (Container Station) 5. Extra information (optional) Logs: **Version / Config** ------------------------------------------------------- ``` ======================================== ======================================== ============= ddns-updater ============= ======================================== =========== Made with ❤️ by ============ ======= https://github.com/qdm12 ======= ======================================== ======================================== Running version latest built on 2024-12-24T09:20:36.130Z (commit 78f3061) 🔧 Need help? ☕ Discussion? https://github.com/qdm12/ddns-updater/discussions/new/choose 🐛 Bug? ✨ New feature? https://github.com/qdm12/ddns-updater/issues/new/choose 💻 Email? quentin.mcgaw@gmail.com 💰 Help me? https://www.paypal.me/qmcgaw https://github.com/sponsors/qdm12 2025-01-13T12:59:35-05:00 INFO Settings summary: ├── HTTP client | └── Timeout: 10s ├── Update | ├── Period: 10m0s | └── Cooldown: 6m0s ├── Public IP fetching | ├── HTTP enabled: yes | ├── HTTP IP providers | | └── all | ├── HTTP IPv4 providers | | └── all | ├── HTTP IPv6 providers | | └── all | ├── DNS enabled: yes | ├── DNS timeout: 10s | └── DNS over TLS providers | └── all ├── Resolver: use Go default resolver ├── Server | ├── Listening address: :80 | └── Root URL: / ├── Health | ├── Server listening address: 127.0.0.1:9999 | ├── Healthchecks.io base URL: https://hc-ping.com | └── Healthchecks.io UUID: 8*****27-c09a-4**1-bd7e-e********f3 ├── Paths | ├── Data directory: /updater/data | ├── Config file: /updater/data/config.json | └── Umask: system default ├── Backup: disabled ├── Logger | ├── Level: debug | └── Caller: hidden └── Shoutrrr ├── Default title: DDNS_Updater └── Addresses └── telegram://74******07:AA****sy_BA********OBNEji3OGs****c@telegram?chats=66******33 2025-01-13T12:59:35-05:00 INFO reading JSON config from file /updater/data/config.json 2025-01-13T12:59:35-05:00 DEBUG config read: { "settings": [ { "provider": "easydns", "domain": "starlord.ca", "username": "ABC", "token": "2***************2", "ip_version": "ipv4", "ipv6_suffix": "" }, { "provider": "easydns", "domain": "*.starlord.ca", "username": "ABC", "token": "2***************2", "ip_version": "ipv4", "ipv6_suffix": "" } ] } ``` ------------------------------------------------------- **Config read** ------------------------------------------------------- ``` 2025-01-13T12:59:35-05:00 INFO Found 2 settings to update records 2025-01-13T12:59:45-05:00 WARN performing request: Get "https://github.com": context deadline exceeded (Client.Timeout exceeded while awaiting headers) 2025-01-13T12:59:45-05:00 INFO Reading history from database: domain starlord.ca owner @ ipv4 2025-01-13T12:59:45-05:00 INFO Reading history from database: domain starlord.ca owner * ipv4 2025-01-13T12:59:45-05:00 INFO [healthcheck server] health http server listening on 127.0.0.1:9999 2025-01-13T12:59:45-05:00 INFO [http server] http server listening on [::]:80 2025-01-13T12:59:45-05:00 INFO [backup] disabled ``` **1st Query (FAIL)** ------------------------------------------------------- ``` 2025-01-13T12:59:45-05:00 DEBUG configured to fetch IP: v4 or v6: false, v4: true, v6: false 2025-01-13T12:59:50-05:00 DEBUG obtaining ipv4 address: try 1 of 3 failed: Get "https://ipv4.seeip.org": context deadline exceeded 2025-01-13T12:59:51-05:00 DEBUG obtaining ipv4 address: try 2 of 3 failed: dial tcp4 1.1.1.1:853: connect: no route to host 2025-01-13T12:59:55-05:00 ERROR [shoutrrr] telegram: failed to send using telegram: timed out 2025-01-13T12:59:56-05:00 DEBUG obtaining ipv4 address: try 3 of 3 failed: Get "https://api.ipify.org": context deadline exceeded 2025-01-13T12:59:56-05:00 DEBUG your public IP address are: v4 or v6: invalid IP, v4: invalid IP, v6: invalid IP 2025-01-13T12:59:56-05:00 ERROR obtaining ipv4 address: after 3 tries, errors were: Get "https://ipv4.seeip.org": context deadline exceeded, dial tcp4 1.1.1.1:853: connect: no route to host, Get "https://api.ipify.org": context deadline exceeded 2025-01-13T12:59:56-05:00 WARN Skipping update for starlord.ca because ipv4 address was not found 2025-01-13T12:59:56-05:00 WARN Skipping update for any.starlord.ca because ipv4 address was not found 2025-01-13T13:00:06-05:00 ERROR pinging healthchecks.io failed: doing http request: Get "https://hc-ping.com/8*****27-c09a-4**1-bd7e-e********f3/fail": context deadline exceeded (Client.Timeout exceeded while awaiting headers) 2025/01/13 13:00:23 "GET http://192.168.210.25:80/ HTTP/1.1" from 192.168.210.20:40176 - 200 2909B in 100.161µs ``` ------------------------------------------------------- **Web Query (both entry failed)** ------------------------------------------------------- ``` 2025/01/13 13:05:23 "GET http://192.168.210.25/ HTTP/1.1" from 192.168.110.90:51337 - 200 2913B in 94.82µs 2025/01/13 13:05:23 "GET http://192.168.210.25/static/styles.css HTTP/1.1" from 192.168.110.90:51337 - 200 3203B in 310.231µs 2025/01/13 13:05:23 "GET http://192.168.210.25/static/favicon.ico HTTP/1.1" from 192.168.110.90:51337 - 200 15086B in 158.22µs ``` ------------------------------------------------------- ![image](https://github.com/user-attachments/assets/101060be-9acf-46d5-9eb3-7cf371c251b2) ------------------------------------------------------- **2nd Query (Success)** ------------------------------------------------------- ``` 2025-01-13T13:09:45-05:00 DEBUG configured to fetch IP: v4 or v6: false, v4: true, v6: false 2025-01-13T13:09:45-05:00 DEBUG your public IP address are: v4 or v6: invalid IP, v4: 76.71.217.182, v6: invalid IP 2025-01-13T13:09:45-05:00 INFO ipv4 address of starlord.ca is 76.71.217.223 and your ipv4 address is 76.71.217.182 2025-01-13T13:09:45-05:00 INFO ipv4 address of any.starlord.ca is 76.71.217.222 and your ipv4 address is 76.71.217.182 2025-01-13T13:09:45-05:00 INFO Updating record [domain: starlord.ca | owner: @ | provider: easydns | ip: ipv4] to use 76.71.217.182 2025-01-13T13:09:45-05:00 DEBUG GET https://ABC:2***************2@api.cp.easydns.com/dyn/generic.php?hostname=starlord.ca&myip=76.71.217.182 | headers: User-Agent: DDNS-Updater quentin.mcgaw@gmail.com; Authorization: Basic c3RhcmxvcmQ6MjE2MTdGNDBBMDBCREY5Mg== 2025-01-13T13:09:47-05:00 DEBUG 200 OK | headers: Expires: Thu, 19 Nov 1981 08:52:00 GMT; Cache-Control: no-store, no-cache, must-revalidate; X-Frame-Options: sameorigin; Content-Security-Policy: frame-ancestors 'self';; Vary: Accept-Encoding; Date: Mon, 13 Jan 2025 18:09:45 GMT; Server: Apache; Pragma: no-cache; Set-Cookie: easydns_language=en_US; expires=Tue, 13-Jan-2026 18:09:45 GMT; Max-Age=31536000; path=/,EasyDNS_SID=5a44c6c2e78583c4081cb23b46e5d636; expires=Mon, 13-Jan-2025 20:09:46 GMT; Max-Age=7200; path=/; domain=api.cp.easydns.com; secure; HttpOnly; Content-Type: text/html; charset=UTF-8 | body: <HTML><BODY><FONT FACE="sans-serif" SIZE="-1">OK<br /><hr noshade size="1">starlord.ca updated to 76.71.217.182<br /></FONT></BODY></HTML> 2025-01-13T13:09:48-05:00 INFO Updating record [domain: starlord.ca | owner: * | provider: easydns | ip: ipv4] to use 76.71.217.182 2025-01-13T13:09:48-05:00 DEBUG GET https://ANC:2***************2@api.cp.easydns.com/dyn/generic.php?hostname=%2A.starlord.ca&myip=76.71.217.182&wildcard=ON | headers: User-Agent: DDNS-Updater quentin.mcgaw@gmail.com; Authorization: Basic c3RhcmxvcmQ6MjE2MTdGNDBBMDBCREY5Mg== 2025-01-13T13:09:50-05:00 DEBUG 200 OK | headers: Cache-Control: no-store, no-cache, must-revalidate; Set-Cookie: easydns_language=en_US; expires=Tue, 13-Jan-2026 18:09:48 GMT; Max-Age=31536000; path=/,EasyDNS_SID=43ecb964b3360f162437567e08c7221d; expires=Mon, 13-Jan-2025 20:09:48 GMT; Max-Age=7200; path=/; domain=api.cp.easydns.com; secure; HttpOnly; Vary: Accept-Encoding; Content-Type: text/html; charset=UTF-8; Expires: Thu, 19 Nov 1981 08:52:00 GMT; Server: Apache; Pragma: no-cache; X-Frame-Options: sameorigin; Content-Security-Policy: frame-ancestors 'self';; Date: Mon, 13 Jan 2025 18:09:48 GMT | body: <HTML><BODY><FONT FACE="sans-serif" SIZE="-1">OK<br /><hr noshade size="1">*.starlord.ca updated to 76.71.217.182<br /></FONT></BODY></HTML> ``` ------------------------------------------------------- **Web Query (both entry Success)** ------------------------------------------------------- ``` 2025/01/13 13:13:36 "GET http://192.168.210.25/ HTTP/1.1" from 192.168.110.90:51754 - 200 3023B in 94.746µs 2025/01/13 13:13:36 "GET http://192.168.210.25/static/styles.css HTTP/1.1" from 192.168.110.90:51754 - 200 3203B in 138.765µs 2025/01/13 13:13:36 "GET http://192.168.210.25/static/favicon.ico HTTP/1.1" from 192.168.110.90:51754 - 200 15086B in 231.134µs ``` ------------------------------------------------------- ![image](https://github.com/user-attachments/assets/494cc7ac-d71c-46c0-9af6-506cc4eb78c0) ------------------------------------------------------- Configuration file (**remove your credentials!**): ``` { "settings": [ { "provider": "easydns", "domain": "starlord.ca", "username": "ABC", "token": "2***************2", "ip_version": "ipv4", "ipv6_suffix": "" }, { "provider": "easydns", "domain": "*.starlord.ca", "username": "ABC", "token": "2***************2", "ip_version": "ipv4", "ipv6_suffix": "" } ] } ``` Host OS: QTS 5.2.2.2958 Observation: I use dedicated IP on a spécific VLAN. Query start to quick, the network stack not ready when the check is made. Network stack look's to be ready about 30 seconds after the container is started. I tried with NAT network on specific port, aned I dont have this error. Is it possible to add a 30 seconds delay for the first Internet interaction? Thanks
Sign in to join this conversation.
1 Participants
Notifications
Due Date
No due date set.
Dependencies

No dependencies set.

Reference: starred/ddns-updater#487