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.

Sign up for GitHub

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

Jump to bottom

🐛 [Immich] Freshly installed addon not starting. #1676

Open
CForChrisProooo opened this issue Dec 19, 2024 · 15 comments
Open

🐛 [Immich] Freshly installed addon not starting. #1676

CForChrisProooo opened this issue Dec 19, 2024 · 15 comments
Labels
bug Something isn't working stale Element will be closed automatically

Comments

@CForChrisProooo
Copy link

Description

Addon does not start after being installed.

Reproduction steps

1.Install addon.
2.Start addon

Addon Logs

Starting...
/etc/cont-init.d/00-banner.sh: executing
-----------------------------------------------------------
 Add-on: Immich
 Self-hosted photo and video backup solution directly from your mobile phone
-----------------------------------------------------------
 Add-on version: 1.122.3
 You are running the latest version of this add-on.
 System: Home Assistant OS 13.2  (amd64 / qemux86-64)
 Home Assistant Core: 2024.11.3
 Home Assistant Supervisor: 2024.12.0
-----------------------------------------------------------
 Please, share the above information when looking for help
 or support in, e.g., GitHub, forums
-----------------------------------------------------------
 Provided by: https://github.com/alexbelgium/hassio-addons 
-----------------------------------------------------------
/etc/cont-init.d/00-global_var.sh: executing
DB_DATABASE_NAME='immich'
DB_HOSTNAME='homeassistant.local'
DB_PASSWORD=******
DB_PORT='5432'
DB_USERNAME='postgres'
DISABLE_MACHINE_LEARNING='false'
JWT_SECRET='jt+OVWY6WqKJXw3xF5qOxN5L5/f4u2jlIlCQgWS3E4w='
TZ='Europe/Paris'
data_location='/share/immich'
/etc/cont-init.d/00-local_mounts.sh: executing
/etc/cont-init.d/00-smb_mounts.sh: executing
/etc/cont-init.d/01-config_yaml.sh: executing
Setting permissions for the config.yaml directory
Load environment variables from /config/addons_config/immich/config.yaml if existing
If accessing the file with filebrowser it should be mapped to /homeassistant/addons_config/immich/config.yaml
---------------------------------------------------------
Wiki here on how to use : github.com/alexbelgium/hassio-addons/wiki/Add‐ons-feature-:-add-env-variables
... no env variables found, exiting
/etc/cont-init.d/01-custom_script.sh: executing
Execute /homeassistant/addons_autoscripts/immich.sh if existing
Wiki here : github.com/alexbelgium/hassio-addons/wiki/Add-ons-feature-:-customisation
/etc/cont-init.d/20-folders.sh: executing
[07:45:09] INFO: Setting data location
... check /share/immich folder exists
... setting permissions
chown: invalid user: ‘null:null’
Error : /etc/cont-init.d/20-folders.sh exiting 1
/etc/cont-init.d/99-run.sh: executing
[07:45:10] INFO: Setting variables
DB_DATABASE_NAME='immich'
DB_HOSTNAME='homeassistant.local'
DB_PASSWORD=******
DB_PORT='5432'
DB_USERNAME='postgres'
DISABLE_MACHINE_LEARNING='false'
JWT_SECRET='jt+OVWY6WqKJXw3xF5qOxN5L5/f4u2jlIlCQgWS3E4w='
TZ='Europe/Paris'
data_location='/share/immich'
[07:45:10] INFO: Defining database
[07:45:10] INFO: -----------------
[07:45:10] INFO: Connecting to external postgresql
[07:45:10] INFO: 
psql: error: could not translate host name "homeassistant.local" to address: No address associated with hostname
Starting the upstream container
[mod-init] Running Docker Modification Logic
[mod-init] Adding imagegenius/mods:universal-redis to container
[mod-init] Downloading imagegenius/mods:universal-redis from registry-1.docker.io
[mod-init] Installing imagegenius/mods:universal-redis
[mod-init] imagegenius/mods:universal-redis applied to container
[migrations] started
[migrations] no migrations found
╔═══════════════════════════════╗
       __  _____ _____       __
      / / |_   _/ ____|     / /
     / /    | || |  __     / /
    / /     | || | |_ |   / /
   / /     _| || |__| |  / /
  /_/     |_____\_____| /_/
  Baseimage from linuxserver.io
╠═══════════════════════════════╣
  User/Group ID:
  User UID: 0
  User GID: 0
╚═══════════════════════════════╝
**** Adding redis to package install list ****
[pkg-install-init] **** Installing all mod packages ****
Hit:1 http://apt.postgresql.org/pub/repos/apt noble-pgdg InRelease
Hit:2 http://archive.ubuntu.com/ubuntu noble InRelease
Get:3 http://archive.ubuntu.com/ubuntu noble-updates InRelease [126 kB]
Get:4 http://archive.ubuntu.com/ubuntu noble-security InRelease [126 kB]
Hit:5 http://archive.ubuntu.com/ubuntu oracular InRelease
Get:6 http://archive.ubuntu.com/ubuntu noble-updates/restricted Sources [27.6 kB]
Get:7 http://archive.ubuntu.com/ubuntu noble-updates/main Sources [399 kB]
Get:8 http://archive.ubuntu.com/ubuntu noble-updates/universe Sources [481 kB]
Get:9 http://archive.ubuntu.com/ubuntu noble-updates/universe amd64 Packages [1,209 kB]
Get:10 http://archive.ubuntu.com/ubuntu noble-updates/main amd64 Packages [953 kB]
Get:11 http://archive.ubuntu.com/ubuntu noble-updates/restricted amd64 Packages [724 kB]
Get:12 http://archive.ubuntu.com/ubuntu noble-security/restricted Sources [25.5 kB]
Get:13 http://archive.ubuntu.com/ubuntu noble-security/universe Sources [372 kB]
Get:14 http://archive.ubuntu.com/ubuntu noble-security/main Sources [171 kB]
Get:15 http://archive.ubuntu.com/ubuntu noble-security/restricted amd64 Packages [707 kB]
Get:16 http://archive.ubuntu.com/ubuntu noble-security/universe amd64 Packages [984 kB]
Get:17 http://archive.ubuntu.com/ubuntu noble-security/main amd64 Packages [708 kB]
Fetched 7,012 kB in 5s (1,493 kB/s)
Reading package lists...
W: http://apt.postgresql.org/pub/repos/apt/dists/noble-pgdg/InRelease: Key is stored in legacy trusted.gpg keyring (/etc/apt/trusted.gpg), see the DEPRECATION section in apt-key(8) for details.
Reading package lists...
Building dependency tree...
Reading state information...
The following additional packages will be installed:
  libatomic1 libjemalloc2 liblzf1 redis-tools
Suggested packages:
  ruby-redis
The following NEW packages will be installed:
  libatomic1 libjemalloc2 liblzf1 redis-server redis-tools
0 upgraded, 5 newly installed, 0 to remove and 7 not upgraded.
Need to get 1,491 kB of archives.
After this operation, 7,608 kB of additional disk space will be used.
Get:1 http://archive.ubuntu.com/ubuntu noble-updates/main amd64 libatomic1 amd64 14.2.0-4ubuntu2~24.04 [10.5 kB]
Get:2 http://archive.ubuntu.com/ubuntu noble/universe amd64 libjemalloc2 amd64 5.3.0-2build1 [256 kB]
Get:3 http://archive.ubuntu.com/ubuntu noble/universe amd64 liblzf1 amd64 3.6-4 [7,624 B]
Get:4 http://archive.ubuntu.com/ubuntu noble/universe amd64 redis-tools amd64 5:7.0.15-1build2 [1,165 kB]
Get:5 http://archive.ubuntu.com/ubuntu noble/universe amd64 redis-server amd64 5:7.0.15-1build2 [51.7 kB]
Fetched 1,491 kB in 3s (539 kB/s)
Selecting previously unselected package libatomic1:amd64.
(Reading database ... 
(Reading database ... 5%
(Reading database ... 10%
(Reading database ... 15%
(Reading database ... 20%
(Reading database ... 25%
(Reading database ... 30%
(Reading database ... 35%
(Reading database ... 40%
(Reading database ... 45%
(Reading database ... 50%
(Reading database ... 55%
(Reading database ... 60%
(Reading database ... 65%
(Reading database ... 70%
(Reading database ... 75%
(Reading database ... 80%
(Reading database ... 85%
(Reading database ... 90%
(Reading database ... 95%
(Reading database ... 100%
(Reading database ... 23046 files and directories currently installed.)
Preparing to unpack .../libatomic1_14.2.0-4ubuntu2~24.04_amd64.deb ...
Unpacking libatomic1:amd64 (14.2.0-4ubuntu2~24.04) ...
Selecting previously unselected package libjemalloc2:amd64.
Preparing to unpack .../libjemalloc2_5.3.0-2build1_amd64.deb ...
Unpacking libjemalloc2:amd64 (5.3.0-2build1) ...
Selecting previously unselected package liblzf1:amd64.
Preparing to unpack .../liblzf1_3.6-4_amd64.deb ...
Unpacking liblzf1:amd64 (3.6-4) ...
Selecting previously unselected package redis-tools.
Preparing to unpack .../redis-tools_5%3a7.0.15-1build2_amd64.deb ...
Unpacking redis-tools (5:7.0.15-1build2) ...
Selecting previously unselected package redis-server.
Preparing to unpack .../redis-server_5%3a7.0.15-1build2_amd64.deb ...
Unpacking redis-server (5:7.0.15-1build2) ...
Setting up libjemalloc2:amd64 (5.3.0-2build1) ...
Setting up liblzf1:amd64 (3.6-4) ...
Setting up libatomic1:amd64 (14.2.0-4ubuntu2~24.04) ...
Setting up redis-tools (5:7.0.15-1build2) ...
Setting up redis-server (5:7.0.15-1build2) ...
invoke-rc.d: could not determine current runlevel
invoke-rc.d: policy-rc.d denied execution of start.
Processing triggers for libc-bin (2.39-0ubuntu8.3) ...
[custom-init] No custom files found, skipping...
Starting api worker
[Nest] 1147  - 12/19/2024, 7:46:48 AM     LOG [NestFactory] Starting Nest application...
[Nest] 1147  - 12/19/2024, 7:46:48 AM     LOG [InstanceLoader] BullModule dependencies initialized +1ms
[Nest] 1147  - 12/19/2024, 7:46:48 AM     LOG [InstanceLoader] OpenTelemetryModule dependencies initialized +0ms
[Nest] 1147  - 12/19/2024, 7:46:48 AM     LOG [InstanceLoader] TypeOrmModule dependencies initialized +0ms
[Nest] 1147  - 12/19/2024, 7:46:48 AM     LOG [InstanceLoader] OpenTelemetryCoreModule dependencies initialized +0ms
[Nest] 1147  - 12/19/2024, 7:46:48 AM     LOG [InstanceLoader] DiscoveryModule dependencies initialized +0ms
[Nest] 1147  - 12/19/2024, 7:46:48 AM     LOG [InstanceLoader] ClsModule dependencies initialized +0ms
[Nest] 1147  - 12/19/2024, 7:46:48 AM     LOG [InstanceLoader] ScheduleModule dependencies initialized +0ms
[Nest] 1147  - 12/19/2024, 7:46:48 AM     LOG [InstanceLoader] BullModule dependencies initialized +0ms
[Nest] 1147  - 12/19/2024, 7:46:48 AM     LOG [InstanceLoader] BullModule dependencies initialized +0ms
[Nest] 1147  - 12/19/2024, 7:46:48 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (1)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1147  - 12/19/2024, 7:46:48 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (2)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1147  - 12/19/2024, 7:46:48 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (3)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1147  - 12/19/2024, 7:46:48 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (4)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1147  - 12/19/2024, 7:46:48 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (5)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1147  - 12/19/2024, 7:46:48 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (6)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1147  - 12/19/2024, 7:46:48 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (7)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1147  - 12/19/2024, 7:46:48 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (8)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1147  - 12/19/2024, 7:46:48 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (9)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1147  - 12/19/2024, 7:46:48 AM   ERROR [ExceptionHandler] getaddrinfo ENOTFOUND homeassistant.local
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
api worker exited with code 1
Starting api worker
[Nest] 1282  - 12/19/2024, 7:47:57 AM     LOG [NestFactory] Starting Nest application...
[Nest] 1282  - 12/19/2024, 7:47:57 AM     LOG [InstanceLoader] BullModule dependencies initialized +1ms
[Nest] 1282  - 12/19/2024, 7:47:57 AM     LOG [InstanceLoader] OpenTelemetryModule dependencies initialized +0ms
[Nest] 1282  - 12/19/2024, 7:47:57 AM     LOG [InstanceLoader] TypeOrmModule dependencies initialized +0ms
[Nest] 1282  - 12/19/2024, 7:47:57 AM     LOG [InstanceLoader] OpenTelemetryCoreModule dependencies initialized +1ms
[Nest] 1282  - 12/19/2024, 7:47:57 AM     LOG [InstanceLoader] DiscoveryModule dependencies initialized +0ms
[Nest] 1282  - 12/19/2024, 7:47:57 AM     LOG [InstanceLoader] ClsModule dependencies initialized +0ms
[Nest] 1282  - 12/19/2024, 7:47:57 AM     LOG [InstanceLoader] ScheduleModule dependencies initialized +0ms
[Nest] 1282  - 12/19/2024, 7:47:57 AM     LOG [InstanceLoader] BullModule dependencies initialized +0ms
[Nest] 1282  - 12/19/2024, 7:47:57 AM     LOG [InstanceLoader] BullModule dependencies initialized +1ms
[Nest] 1282  - 12/19/2024, 7:47:57 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (1)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1282  - 12/19/2024, 7:47:57 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (2)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1282  - 12/19/2024, 7:47:57 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (3)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1282  - 12/19/2024, 7:47:57 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (4)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1282  - 12/19/2024, 7:47:57 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (5)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1282  - 12/19/2024, 7:47:57 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (6)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1282  - 12/19/2024, 7:47:57 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (7)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1282  - 12/19/2024, 7:47:57 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (8)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1282  - 12/19/2024, 7:47:57 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (9)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1282  - 12/19/2024, 7:47:57 AM   ERROR [ExceptionHandler] getaddrinfo ENOTFOUND homeassistant.local
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
api worker exited with code 1
Starting api worker
[Nest] 1417  - 12/19/2024, 7:49:05 AM     LOG [NestFactory] Starting Nest application...
[Nest] 1417  - 12/19/2024, 7:49:05 AM     LOG [InstanceLoader] BullModule dependencies initialized +2ms
[Nest] 1417  - 12/19/2024, 7:49:05 AM     LOG [InstanceLoader] OpenTelemetryModule dependencies initialized +0ms
[Nest] 1417  - 12/19/2024, 7:49:05 AM     LOG [InstanceLoader] TypeOrmModule dependencies initialized +1ms
[Nest] 1417  - 12/19/2024, 7:49:05 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (1)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1417  - 12/19/2024, 7:49:05 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (2)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1417  - 12/19/2024, 7:49:05 AM     LOG [InstanceLoader] OpenTelemetryCoreModule dependencies initialized +0ms
[Nest] 1417  - 12/19/2024, 7:49:05 AM     LOG [InstanceLoader] DiscoveryModule dependencies initialized +0ms
[Nest] 1417  - 12/19/2024, 7:49:05 AM     LOG [InstanceLoader] ClsModule dependencies initialized +0ms
[Nest] 1417  - 12/19/2024, 7:49:05 AM     LOG [InstanceLoader] ScheduleModule dependencies initialized +0ms
[Nest] 1417  - 12/19/2024, 7:49:05 AM     LOG [InstanceLoader] BullModule dependencies initialized +1ms
[Nest] 1417  - 12/19/2024, 7:49:05 AM     LOG [InstanceLoader] BullModule dependencies initialized +0ms
[Nest] 1417  - 12/19/2024, 7:49:05 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (3)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1417  - 12/19/2024, 7:49:05 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (4)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1417  - 12/19/2024, 7:49:05 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (5)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1417  - 12/19/2024, 7:49:05 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (6)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1417  - 12/19/2024, 7:49:05 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (7)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1417  - 12/19/2024, 7:49:05 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (8)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1417  - 12/19/2024, 7:49:05 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (9)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1417  - 12/19/2024, 7:49:05 AM   ERROR [ExceptionHandler] getaddrinfo ENOTFOUND homeassistant.local
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
api worker exited with code 1
Starting api worker
[Nest] 1545  - 12/19/2024, 7:50:14 AM     LOG [NestFactory] Starting Nest application...
[Nest] 1545  - 12/19/2024, 7:50:14 AM     LOG [InstanceLoader] BullModule dependencies initialized +0ms
[Nest] 1545  - 12/19/2024, 7:50:14 AM     LOG [InstanceLoader] OpenTelemetryModule dependencies initialized +1ms
[Nest] 1545  - 12/19/2024, 7:50:14 AM     LOG [InstanceLoader] TypeOrmModule dependencies initialized +0ms
[Nest] 1545  - 12/19/2024, 7:50:14 AM     LOG [InstanceLoader] OpenTelemetryCoreModule dependencies initialized +0ms
[Nest] 1545  - 12/19/2024, 7:50:14 AM     LOG [InstanceLoader] DiscoveryModule dependencies initialized +0ms
[Nest] 1545  - 12/19/2024, 7:50:14 AM     LOG [InstanceLoader] ClsModule dependencies initialized +1ms
[Nest] 1545  - 12/19/2024, 7:50:14 AM     LOG [InstanceLoader] ScheduleModule dependencies initialized +0ms
[Nest] 1545  - 12/19/2024, 7:50:14 AM     LOG [InstanceLoader] BullModule dependencies initialized +0ms
[Nest] 1545  - 12/19/2024, 7:50:14 AM     LOG [InstanceLoader] BullModule dependencies initialized +0ms
[Nest] 1545  - 12/19/2024, 7:50:14 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (1)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1545  - 12/19/2024, 7:50:14 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (2)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1545  - 12/19/2024, 7:50:14 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (3)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1545  - 12/19/2024, 7:50:14 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (4)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1545  - 12/19/2024, 7:50:14 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (5)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1545  - 12/19/2024, 7:50:14 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (6)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1545  - 12/19/2024, 7:50:14 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (7)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1545  - 12/19/2024, 7:50:14 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (8)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1545  - 12/19/2024, 7:50:14 AM   ERROR [TypeOrmModule] Unable to connect to the database. Retrying (9)...
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
[Nest] 1545  - 12/19/2024, 7:50:14 AM   ERROR [ExceptionHandler] getaddrinfo ENOTFOUND homeassistant.local
Error: getaddrinfo ENOTFOUND homeassistant.local
    at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:120:26)
api worker exited with code 1
Starting api worker

Architecture

amd64

OS

HAos
@CForChrisProooo CForChrisProooo added the bug Something isn't working label Dec 19, 2024
@alexbelgium
Copy link
Owner

Hi have you installed postgres?

@CForChrisProooo
Copy link
Author

Yes.

` Add-on: Postgres 15
Postgres 15 with pgvecto.rs support

Add-on version: 15.7-4
You are running the latest version of this add-on.
System: Home Assistant OS 14.0 (amd64 / qemux86-64)
Home Assistant Core: 2024.11.3
Home Assistant Supervisor: 2024.12.0

Please, share the above information when looking for help
or support in, e.g., GitHub, forums

Provided by: https://github.com/alexbelgium/hassio-addons

/etc/cont-init.d/00-global_var.sh: executing
POSTGRES_PASSWORD=******
Timezone set from Etc/UTC to Australia/Brisbane
/etc/cont-init.d/01-config_yaml.sh: executing
Setting permissions for the config.yaml directory
Load environment variables from /config/config.yaml if existing
If accessing the file with filebrowser it should be mapped to /addon_configs/db21ed7f_postgres/config.yaml

Wiki here on how to use : github.com/alexbelgium/hassio-addons/wiki/Add‐ons-feature-:-add-env-variables
... no env variables found, exiting
/etc/cont-init.d/01-custom_script.sh: executing
Execute /addon_configs/db21ed7f_postgres/postgres.sh if existing
Wiki here : github.com/alexbelgium/hassio-addons/wiki/Add-ons-feature-:-customisation
/etc/cont-init.d/20-folders.sh: executing
/etc/cont-init.d/99-run.sh: executing
[19:02:01] WARNING: Using existing config.env file in /config.
[19:02:01] INFO: Starting the app
Everything started!
[19:02:01] INFO: Waiting for port 5432 to open...
PostgreSQL Database directory appears to contain a database; Skipping initialization
2024-12-19 19:02:01.496 AEST [212] LOG: starting PostgreSQL 15.7 (Debian 15.7-1.pgdg120+1) on x86_64-pc-linux-gnu, compiled by gcc (Debian 12.2.0-14) 12.2.0, 64-bit
2024-12-19 19:02:01.496 AEST [212] LOG: listening on IPv4 address "0.0.0.0", port 5432
2024-12-19 19:02:01.496 AEST [212] LOG: listening on IPv6 address "::", port 5432
2024-12-19 19:02:01.502 AEST [212] LOG: listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432"
2024-12-19 19:02:01.509 AEST [243] LOG: database system was shut down at 2024-12-19 19:01:59 AEST
2024-12-19 19:02:01.516 AEST [212] LOG: database system is ready to accept connections
2024-12-19 19:02:01.970 AEST [251] LOG: incomplete startup packet
[19:02:01] INFO: Enabling vector.rs
NOTICE: extension "vectors" does not exist, skipping`

@jcastro
Copy link

jcastro commented Dec 20, 2024

Same was happening to me, fully restarted HA and now it seems to be working!

@github-actions GitHub Actions
Copy link
Contributor

This issue has been automatically marked as stale because it has not had recent activity. It will be closed if no further activity occurs. Thank you for your contributions.

@github-actions github-actions bot added the stale Element will be closed automatically label Dec 29, 2024
@Nafania
Copy link

Nafania commented Jan 3, 2025

Same for me with pg adon installed

alexbelgium added a commit that referenced this issue Jan 3, 2025
@alexbelgium
Update pg command
f091789 
#1676 (comment)
@alexbelgium
Copy link
Owner

Hi, thanks I've pushed a new version that realigns with upstream and should avoid this error

@derJake
Copy link

derJake commented Jan 3, 2025

The issue still persists for me. According to the Add-On log startup is stuck at:

Everything started!
[18:30:23] INFO: Waiting for port 5432 to open...

Yet docker -ps shows the container being mapped to 5432.

@alexbelgium
Copy link
Owner

Thanks for fast answer - to be clear, do you still have the line NOTICE: extension "vectors" does not exist, skipping in your postgres log ?

@derJake
Copy link

derJake commented Jan 3, 2025

Thanks for the great add-ons!
So far the log looks like this:

server stopped
Terminating child PID 211
All subprocesses terminated. Exiting.
Starting...
/etc/cont-init.d/00-banner.sh: executing
-----------------------------------------------------------
 Add-on: Postgres 15
 Postgres 15 with pgvecto.rs support
-----------------------------------------------------------
 Add-on version: 15.7-5
 You are running the latest version of this add-on.
 System: Debian GNU/Linux 11 (bullseye)  (amd64 / qemux86-64)
 Home Assistant Core: 2024.12.5
 Home Assistant Supervisor: 2024.12.3
-----------------------------------------------------------
 Please, share the above information when looking for help
 or support in, e.g., GitHub, forums
-----------------------------------------------------------
 Provided by: https://github.com/alexbelgium/hassio-addons 
-----------------------------------------------------------
/etc/cont-init.d/00-global_var.sh: executing
POSTGRES_DB='*************'
POSTGRES_PASSWORD=******
POSTGRES_USER='*******'
Timezone set from Etc/UTC to Europe/Berlin
/etc/cont-init.d/01-config_yaml.sh: executing
Setting permissions for the config.yaml directory
Load environment variables from /config/config.yaml if existing
If accessing the file with filebrowser it should be mapped to /addon_configs/db21ed7f_postgres/config.yaml
---------------------------------------------------------
Wiki here on how to use : github.com/alexbelgium/hassio-addons/wiki/Add‐ons-feature-:-add-env-variables
... no config file, creating one from template. Please customize the file in /config/config.yaml before restarting.
... no env variables found, exiting
/etc/cont-init.d/01-custom_script.sh: executing
Execute /addon_configs/db21ed7f_postgres/postgres.sh if existing
Wiki here : github.com/alexbelgium/hassio-addons/wiki/Add-ons-feature-:-customisation
/etc/cont-init.d/20-folders.sh: executing
/etc/cont-init.d/99-run.sh: executing
[18:30:23] WARNING: A default config.env file was copied in /config. Please customize according to https://hub.docker.com/_/postgres and restart the add-on
[18:30:23] INFO: Starting the app
/etc/cont-init.d/99-run.sh: line 43: user: unbound variable
Everything started!
[18:30:23] INFO: Waiting for port 5432 to open...
[18:45:23] INFO: Enabling vector.rs
psql: error: connection to server at "localhost" (::1), port 5432 failed: Connection refused
	Is the server running on that host and accepting TCP/IP connections?
connection to server at "localhost" (127.0.0.1), port 5432 failed: Connection refused
	Is the server running on that host and accepting TCP/IP connections?

@alexbelgium
Copy link
Owner

Thanks - so the initial error is gone and now it's replaced by /etc/cont-init.d/99-run.sh: line 43: user: unbound variable
Please try the newly pushed version (once it's build)

@derJake
Copy link

derJake commented Jan 3, 2025

The log doesn’t seem to go past extension "vectors" does not exist, skipping. It’s been stuck for a few minutes now.

Starting...
/etc/cont-init.d/00-banner.sh: executing
-----------------------------------------------------------
 Add-on: Postgres 15
 Postgres 15 with pgvecto.rs support
-----------------------------------------------------------
 Add-on version: 15.7-6
 You are running the latest version of this add-on.
 System: Debian GNU/Linux 11 (bullseye)  (amd64 / qemux86-64)
 Home Assistant Core: 2024.12.5
 Home Assistant Supervisor: 2024.12.3
-----------------------------------------------------------
 Please, share the above information when looking for help
 or support in, e.g., GitHub, forums
-----------------------------------------------------------
 Provided by: https://github.com/alexbelgium/hassio-addons 
-----------------------------------------------------------
/etc/cont-init.d/00-global_var.sh: executing
POSTGRES_DB='homeassistant'
POSTGRES_PASSWORD=******
POSTGRES_USER='homeassistant'
Timezone set from Etc/UTC to Europe/Berlin
/etc/cont-init.d/01-config_yaml.sh: executing
Setting permissions for the config.yaml directory
Load environment variables from /config/config.yaml if existing
If accessing the file with filebrowser it should be mapped to /addon_configs/db21ed7f_postgres/config.yaml
---------------------------------------------------------
Wiki here on how to use : github.com/alexbelgium/hassio-addons/wiki/Add‐ons-feature-:-add-env-variables
... no env variables found, exiting
/etc/cont-init.d/01-custom_script.sh: executing
Execute /addon_configs/db21ed7f_postgres/postgres.sh if existing
Wiki here : github.com/alexbelgium/hassio-addons/wiki/Add-ons-feature-:-customisation
/etc/cont-init.d/20-folders.sh: executing
/etc/cont-init.d/99-run.sh: executing
[19:17:26] WARNING: Using existing config.env file in /config.
[19:17:26] INFO: Starting the app
Everything started!
[19:17:26] INFO: Waiting for port 5432 to open...
PostgreSQL Database directory appears to contain a database; Skipping initialization
2025-01-03 19:17:26.923 CET [232] LOG:  redirecting log output to logging collector process
2025-01-03 19:17:26.923 CET [232] HINT:  Future log output will appear in directory "log".
[19:17:27] INFO: Enabling vector.rs
NOTICE:  extension "vectors" does not exist, skipping

@alexbelgium
Copy link
Owner

alexbelgium commented Jan 3, 2025 •
edited
Loading

thanks ; they have changed something upstream (I'm using a self-contained postgres + vector.rs published by someone else), and strangely it does not find the vector.so library located in the postgres/15/lib folder. I'll try to look how to force it to find it

What is weird is that on my own amd64 system it works ;-) it doesn't help in troubleshooting

@derJake
Copy link

derJake commented Jan 3, 2025

Ugh, don’t we all know the feeling! Well I’m happy to test on Intel once you get to have another go at it.

@github-actions github-actions bot removed the stale Element will be closed automatically label Jan 5, 2025
@github-actions GitHub Actions
Copy link
Contributor

This issue has been automatically marked as stale because it has not had recent activity. It will be closed if no further activity occurs. Thank you for your contributions.

@github-actions github-actions bot added the stale Element will be closed automatically label Jan 12, 2025
@derJake
Copy link

derJake commented Jan 14, 2025

Hey there, the Postgres container is unfortunately still getting stuck on Intel.

Starting...
/etc/cont-init.d/00-banner.sh: executing
-----------------------------------------------------------
 Add-on: Postgres 15
 Postgres 15 with pgvecto.rs support
-----------------------------------------------------------
 Add-on version: 15.7-7
 You are running the latest version of this add-on.
 System: Debian GNU/Linux 11 (bullseye)  (amd64 / qemux86-64)
 Home Assistant Core: 2025.1.2
 Home Assistant Supervisor: 2024.12.3
-----------------------------------------------------------
 Please, share the above information when looking for help
 or support in, e.g., GitHub, forums
-----------------------------------------------------------
 Provided by: https://github.com/alexbelgium/hassio-addons 
-----------------------------------------------------------
/etc/cont-init.d/00-global_var.sh: executing
POSTGRES_DB='immichdb'
POSTGRES_PASSWORD=******
POSTGRES_USER='immichuser'
Timezone set from Etc/UTC to Europe/Berlin
/etc/cont-init.d/01-config_yaml.sh: executing
Setting permissions for the config.yaml directory
Load environment variables from /config/config.yaml if existing
If accessing the file with filebrowser it should be mapped to /addon_configs/db21ed7f_postgres/config.yaml
---------------------------------------------------------
Wiki here on how to use : github.com/alexbelgium/hassio-addons/wiki/Add‐ons-feature-:-add-env-variables
... no config file, creating one from template. Please customize the file in /config/config.yaml before restarting.
... no env variables found, exiting
/etc/cont-init.d/01-custom_script.sh: executing
Execute /addon_configs/db21ed7f_postgres/postgres.sh if existing
Wiki here : github.com/alexbelgium/hassio-addons/wiki/Add-ons-feature-:-customisation
/etc/cont-init.d/20-folders.sh: executing
/etc/cont-init.d/99-run.sh: executing
[20:23:26] WARNING: A default config.env file was copied in /config. Please customize according to https://hub.docker.com/_/postgres and restart the add-on
[20:23:26] INFO: Starting the app
Everything started!
The files belonging to this database system will be owned by user "postgres".
This user must also own the server process.
The database cluster will be initialized with locale "en_US.utf8".
The default database encoding has accordingly been set to "UTF8".
The default text search configuration will be set to "english".
Data page checksums are disabled.
fixing permissions on existing directory /config/database ... ok
creating subdirectories ... ok
selecting dynamic shared memory implementation ... posix
selecting default max_connections ... 100
selecting default shared_buffers ... 128MB
selecting default time zone ... Europe/Berlin
creating configuration files ... ok
running bootstrap script ... ok
performing post-bootstrap initialization ... ok
syncing data to disk ... ok
Success. You can now start the database server using:
    pg_ctl -D /config/database -l logfile start
initdb: warning: enabling "trust" authentication for local connections
initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb.
waiting for server to start....2025-01-14 20:23:27.544 CET [252] LOG:  redirecting log output to logging collector process
2025-01-14 20:23:27.544 CET [252] HINT:  Future log output will appear in directory "log".
 done
server started
CREATE DATABASE
/usr/local/bin/docker-entrypoint.sh: running /docker-entrypoint-initdb.d/10-vector.sh
[20:23:27] INFO: Waiting for port 5432 to open...
    pg_ctl -D /config/database -l logfile start
initdb: warning: enabling "trust" authentication for local connections
initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb.
waiting for server to start....2025-01-14 20:23:27.544 CET [252] LOG:  redirecting log output to logging collector process
2025-01-14 20:23:27.544 CET [252] HINT:  Future log output will appear in directory "log".
 done
server started
CREATE DATABASE
/usr/local/bin/docker-entrypoint.sh: running /docker-entrypoint-initdb.d/10-vector.sh
[20:23:27] INFO: Waiting for port 5432 to open...
[20:38:27] INFO: Enabling vector.rs
/docker-entrypoint-initdb.d/10-vector.sh: line 24: setup_postgres.sql: Permission denied

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug Something isn't working stale Element will be closed automatically
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
5 participants
@jcastro @Nafania @derJake @CForChrisProooo @alexbelgium