search

nextcloud / nextcloudpi

📦 Build code for NextcloudPi: Raspberry Pi, Odroid, Rock64, curl installer...
https://nextcloudpi.com
2.49k stars 295 forks source link

Redis exception - WRONGPASS invalid username-password pair / NOAUTH Authentication required. #1740

Open Tchiimy opened 1 year ago

Tchiimy commented 1 year ago

Hello,

I'm using the docker version of NextcloudPi on my RPI4 (OS 64 bits), I use an external HDD to store my files. Everything was well, but recently I have troubles to launch/use it and I don't know why…

When I launch the docker, my logs are this:

02/28/2023 11:16:34 AM
Install default configurations for new ncp apps
02/28/2023 11:16:35 AM
Installing template 'php/opcache.ini.sh'...
02/28/2023 11:16:38 AM
Installing template 'php/90-ncp.ini.sh'...
02/28/2023 11:16:44 AM
Installing template 'php/pool.d.www.conf.sh'...
02/28/2023 11:16:49 AM
Installing template 'ncp-metrics.cfg.sh'...
02/28/2023 11:17:05 AM
Starting PHP-fpm
02/28/2023 11:17:06 AM
Starting Apache
02/28/2023 11:17:06 AM
Installing template 'mysql/90-ncp.cnf.sh'...
02/28/2023 11:17:09 AM
INFO: Docker container detected.
02/28/2023 11:17:09 AM
Starting mariaDB
02/28/2023 11:17:09 AM
2023-02-28 10:17:09 0 [Note] mysqld (mysqld 10.5.18-MariaDB-0+deb11u1) starting as process 457 ...
02/28/2023 11:17:09 AM
2023-02-28 10:17:09 0 [Warning] The parameter innodb_file_format is deprecated and has no effect. It may be removed in future releases. See https://mariadb.com/kb/en/library/xtradbinnodb-file-format/
02/28/2023 11:17:09 AM
2023-02-28 10:17:09 0 [Warning] The parameter innodb_buffer_pool_instances is deprecated and has no effect.
02/28/2023 11:17:09 AM
2023-02-28 10:17:09 0 [Note] InnoDB: Uses event mutexes
02/28/2023 11:17:09 AM
2023-02-28 10:17:09 0 [Note] InnoDB: Compressed tables use zlib 1.2.11
02/28/2023 11:17:09 AM
2023-02-28 10:17:09 0 [Note] InnoDB: Number of pools: 1
02/28/2023 11:17:09 AM
2023-02-28 10:17:09 0 [Note] InnoDB: Using ARMv8 crc32 instructions
02/28/2023 11:17:09 AM
2023-02-28 10:17:09 0 [Note] mysqld: O_TMPFILE is not supported on /tmp (disabling future attempts)
02/28/2023 11:17:09 AM
2023-02-28 10:17:09 0 [Note] InnoDB: Using Linux native AIO
02/28/2023 11:17:09 AM
2023-02-28 10:17:09 0 [Note] InnoDB: Initializing buffer pool, total size = 268435456, chunk size = 134217728
02/28/2023 11:17:09 AM
2023-02-28 10:17:09 0 [Note] InnoDB: Completed initialization of buffer pool
02/28/2023 11:17:09 AM
2023-02-28 10:17:09 0 [Note] InnoDB: Starting crash recovery from checkpoint LSN=10866669497,10866669497
02/28/2023 11:17:09 AM
2023-02-28 10:17:09 0 [Note] InnoDB: Starting final batch to recover 194 pages from redo log.
02/28/2023 11:17:10 AM
2023-02-28 10:17:10 0 [Note] InnoDB: 128 rollback segments are active.
02/28/2023 11:17:10 AM
2023-02-28 10:17:10 0 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
02/28/2023 11:17:10 AM
2023-02-28 10:17:10 0 [Note] InnoDB: Creating shared tablespace for temporary tables
02/28/2023 11:17:10 AM
2023-02-28 10:17:10 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
02/28/2023 11:17:10 AM
2023-02-28 10:17:10 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
02/28/2023 11:17:10 AM
2023-02-28 10:17:10 0 [Note] InnoDB: 10.5.18 started; log sequence number 10867225184; transaction id 19391101
02/28/2023 11:17:10 AM
2023-02-28 10:17:10 0 [Note] Plugin 'FEEDBACK' is disabled.
02/28/2023 11:17:10 AM
2023-02-28 10:17:10 0 [Note] InnoDB: Loading buffer pool(s) from /data/database/ib_buffer_pool
02/28/2023 11:17:10 AM
2023-02-28 10:17:10 0 [Note] Server socket created on IP: '127.0.0.1'.
02/28/2023 11:17:10 AM
2023-02-28 10:17:10 0 [Note] Reading of all Master_info entries succeeded
02/28/2023 11:17:10 AM
2023-02-28 10:17:10 0 [Note] Added new Master_info '' to hash table
02/28/2023 11:17:10 AM
2023-02-28 10:17:10 0 [Note] mysqld: ready for connections.
02/28/2023 11:17:10 AM
Version: '10.5.18-MariaDB-0+deb11u1'  socket: '/run/mysqld/mysqld.sock'  port: 3306  Debian 11
02/28/2023 11:17:11 AM
2023-02-28 10:17:11 0 [Note] InnoDB: Buffer pool(s) load completed at 230228 10:17:11
02/28/2023 11:17:14 AM
2023-02-28 10:17:14 3 [Warning] Access denied for user 'ncadmin'@'localhost' (using password: YES)
02/28/2023 11:17:14 AM
2023-02-28 10:17:14 4 [Warning] Access denied for user 'ncadmin'@'localhost' (using password: YES)
02/28/2023 11:17:14 AM
2023-02-28 10:17:14 5 [Warning] Access denied for user 'ncadmin'@'localhost' (using password: YES)
02/28/2023 11:17:14 AM
2023-02-28 10:17:14 6 [Warning] Access denied for user 'ncadmin'@'localhost' (using password: YES)
02/28/2023 11:17:14 AM
2023-02-28 10:17:14 7 [Warning] Access denied for user 'ncadmin'@'localhost' (using password: YES)
02/28/2023 11:17:15 AM
2023-02-28 10:17:15 8 [Warning] Access denied for user 'ncadmin'@'localhost' (using password: YES)
02/28/2023 11:17:15 AM
Starting Redis
02/28/2023 11:17:15 AM
Starting Cron
02/28/2023 11:17:15 AM
Starting Postfix
02/28/2023 11:17:17 AM
postsuper: Renamed to match inode number: 1 message
02/28/2023 11:17:17 AM
postsuper: warning: QUEUE FILE NAMES WERE CHANGED TO MATCH INODE NUMBERS
02/28/2023 11:17:18 AM
Provisioning
02/28/2023 11:17:24 AM
Provisioning Redis password
02/28/2023 11:17:24 AM
Updating NextCloud config with Redis password
02/28/2023 11:17:24 AM
Provisioning MariaDB password
02/28/2023 11:17:24 AM
Updating NextCloud config with MariaDB password
02/28/2023 11:17:27 AM
Running nc-limits
02/28/2023 11:17:27 AM
[ nc-limits ] (Tue Feb 28 10:17:27 UTC 2023)
02/28/2023 11:17:30 AM
2023-02-28 10:17:30 14 [Warning] Access denied for user 'ncadmin'@'localhost' (using password: YES)
02/28/2023 11:17:30 AM
2023-02-28 10:17:30 15 [Warning] Access denied for user 'ncadmin'@'localhost' (using password: YES)
02/28/2023 11:17:30 AM
2023-02-28 10:17:30 16 [Warning] Access denied for user 'ncadmin'@'localhost' (using password: YES)
02/28/2023 11:17:30 AM
2023-02-28 10:17:30 17 [Warning] Access denied for user 'ncadmin'@'localhost' (using password: YES)
02/28/2023 11:17:30 AM
2023-02-28 10:17:30 18 [Warning] Access denied for user 'ncadmin'@'localhost' (using password: YES)
02/28/2023 11:17:33 AM
Using 6183121920 for PHP max memory
02/28/2023 11:17:33 AM
Installing template 'php/90-ncp.ini.sh'...
02/28/2023 11:17:34 AM
Using 6183121920 for PHP max memory
02/28/2023 11:17:34 AM
Installing template 'php/90-ncp.ini.sh'...
02/28/2023 11:17:38 AM
2023-02-28 10:17:38 20 [Warning] Access denied for user 'ncadmin'@'localhost' (using password: YES)
02/28/2023 11:17:38 AM
2023-02-28 10:17:38 21 [Warning] Access denied for user 'ncadmin'@'localhost' (using password: YES)
02/28/2023 11:17:38 AM
2023-02-28 10:17:38 22 [Warning] Access denied for user 'ncadmin'@'localhost' (using password: YES)
02/28/2023 11:17:38 AM
2023-02-28 10:17:38 23 [Warning] Access denied for user 'ncadmin'@'localhost' (using password: YES)
02/28/2023 11:17:38 AM
2023-02-28 10:17:38 24 [Warning] Access denied for user 'ncadmin'@'localhost' (using password: YES)
02/28/2023 11:17:41 AM
Using 6 PHP threads
02/28/2023 11:17:41 AM
Installing template 'php/pool.d.www.conf.sh'...
02/28/2023 11:17:41 AM
Using 6 PHP threads
02/28/2023 11:17:41 AM
Installing template 'php/pool.d.www.conf.sh'...
02/28/2023 11:17:43 AM
2023-02-28 10:17:43 26 [Warning] Access denied for user 'ncadmin'@'localhost' (using password: YES)
02/28/2023 11:17:43 AM
2023-02-28 10:17:43 27 [Warning] Access denied for user 'ncadmin'@'localhost' (using password: YES)
02/28/2023 11:17:43 AM
2023-02-28 10:17:43 28 [Warning] Access denied for user 'ncadmin'@'localhost' (using password: YES)
02/28/2023 11:17:43 AM
2023-02-28 10:17:43 29 [Warning] Access denied for user 'ncadmin'@'localhost' (using password: YES)
02/28/2023 11:17:43 AM
2023-02-28 10:17:43 30 [Warning] Access denied for user 'ncadmin'@'localhost' (using password: YES)
02/28/2023 11:17:44 AM
Installing template 'mysql/91-ncp.cnf.sh'...
02/28/2023 11:17:45 AM
Installing template 'mysql/91-ncp.cnf.sh'...
02/28/2023 11:17:46 AM
2023-02-28 10:17:46 0 [Note] mysqld (initiated by: root[root] @ localhost []): Normal shutdown
02/28/2023 11:17:46 AM
2023-02-28 10:17:46 0 [Note] Event Scheduler: Purging the queue. 0 events
02/28/2023 11:17:46 AM
2023-02-28 10:17:46 0 [Note] InnoDB: FTS optimize thread exiting.
02/28/2023 11:17:46 AM
2023-02-28 10:17:46 0 [Note] InnoDB: Starting shutdown...
02/28/2023 11:17:46 AM
2023-02-28 10:17:46 0 [Note] InnoDB: Dumping buffer pool(s) to /data/database/ib_buffer_pool
02/28/2023 11:17:46 AM
2023-02-28 10:17:46 0 [Note] InnoDB: Restricted to 4032 pages due to innodb_buf_pool_dump_pct=25
02/28/2023 11:17:46 AM
2023-02-28 10:17:46 0 [Note] InnoDB: Buffer pool(s) dump completed at 230228 10:17:46
02/28/2023 11:17:48 AM
2023-02-28 10:17:48 0 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
02/28/2023 11:17:48 AM
2023-02-28 10:17:48 0 [Note] InnoDB: Shutdown completed; log sequence number 10867225994; transaction id 19391103
02/28/2023 11:17:48 AM
2023-02-28 10:17:48 0 [Note] mysqld: Shutdown complete
02/28/2023 11:17:48 AM
02/28/2023 11:17:48 AM
Stopping MariaDB database server: mariadbdStopping MariaDB database server: mariadbd.
02/28/2023 11:17:49 AM
Starting MariaDB database server: mariadbd.
02/28/2023 11:17:51 AM
Starting MariaDB database server: mariadbd . ..
02/28/2023 11:17:51 AM
Stopping redis-server: redis-server.
02/28/2023 11:17:52 AM
.
02/28/2023 11:17:52 AM
Stopping redis-server: redis-server.
02/28/2023 11:17:52 AM
Starting redis-server: redis-server.
02/28/2023 11:17:53 AM
Starting redis-server: redis-server.
02/28/2023 11:17:53 AM
02/28/2023 11:17:59 AM
Starting notify_push daemon
02/28/2023 11:18:05 AM
Configuring Domain
02/28/2023 11:18:10 AM
Nextcloud is in maintenance mode, hence the database isn't accessible.
02/28/2023 11:18:10 AM
Cannot perform any command except 'maintenance:mode --off'
02/28/2023 11:18:10 AM
02/28/2023 11:18:10 AM
System config value trusted_domains => 11 set to string 77.204.154.52
02/28/2023 11:18:10 AM
Nextcloud is in maintenance mode, hence the database isn't accessible.
02/28/2023 11:18:10 AM
Cannot perform any command except 'maintenance:mode --off'
02/28/2023 11:18:10 AM
02/28/2023 11:18:10 AM
System config value trusted_domains => 1 set to string 172.17.0.2
02/28/2023 11:18:11 AM
Nextcloud is in maintenance mode, hence the database isn't accessible.
02/28/2023 11:18:11 AM
Cannot perform any command except 'maintenance:mode --off'
02/28/2023 11:18:11 AM
02/28/2023 11:18:11 AM
System config value trusted_domains => 14 set to string 77e25cf3e69d
02/28/2023 11:18:12 AM
Nextcloud is in maintenance mode, hence the database isn't accessible.
02/28/2023 11:18:12 AM
Cannot perform any command except 'maintenance:mode --off'
02/28/2023 11:18:12 AM
02/28/2023 11:18:13 AM
Nextcloud is in maintenance mode, hence the database isn't accessible.
02/28/2023 11:18:13 AM
Cannot perform any command except 'maintenance:mode --off'
02/28/2023 11:18:13 AM
02/28/2023 11:18:14 AM
Nextcloud is in maintenance mode, hence the database isn't accessible.
02/28/2023 11:18:14 AM
Cannot perform any command except 'maintenance:mode --off'
02/28/2023 11:18:14 AM
02/28/2023 11:18:15 AM
System config value trusted_domains => 3 set to string 77e25cf3e69d
02/28/2023 11:18:15 AM
Nextcloud is in maintenance mode, hence the database isn't accessible.
02/28/2023 11:18:15 AM
Cannot perform any command except 'maintenance:mode --off'
02/28/2023 11:18:15 AM
02/28/2023 11:18:16 AM
System config value overwrite.cli.url set to string https://77e25cf3e69d/
02/28/2023 11:18:17 AM
Nextcloud is in maintenance mode, hence the database isn't accessible.
02/28/2023 11:18:17 AM
Cannot perform any command except 'maintenance:mode --off'
02/28/2023 11:18:17 AM
02/28/2023 11:18:17 AM
Nextcloud is in maintenance mode, hence the database isn't accessible.
02/28/2023 11:18:17 AM
Cannot perform any command except 'maintenance:mode --off'
02/28/2023 11:18:17 AM
02/28/2023 11:18:18 AM
System config value trusted_proxies => 11 set to string 127.0.0.1
02/28/2023 11:18:19 AM
Nextcloud is in maintenance mode, hence the database isn't accessible.
02/28/2023 11:18:19 AM
Cannot perform any command except 'maintenance:mode --off'
02/28/2023 11:18:19 AM
02/28/2023 11:18:19 AM
System config value trusted_proxies => 12 set to string ::1
02/28/2023 11:18:20 AM
Nextcloud is in maintenance mode, hence the database isn't accessible.
02/28/2023 11:18:20 AM
Cannot perform any command except 'maintenance:mode --off'
02/28/2023 11:18:20 AM
02/28/2023 11:18:20 AM
System config value trusted_proxies => 13 set to string 77e25cf3e69d
02/28/2023 11:18:22 AM
Nextcloud is in maintenance mode, hence the database isn't accessible.
02/28/2023 11:18:22 AM
Cannot perform any command except 'maintenance:mode --off'
02/28/2023 11:18:22 AM
02/28/2023 11:18:23 AM
System config value trusted_proxies => 14 set to empty string
02/28/2023 11:18:28 AM
Setup notify_push (attempt 1/5)
02/28/2023 11:18:30 AM
✓ redis is configured
02/28/2023 11:18:30 AM
✓ push server is receiving redis messages
02/28/2023 11:18:30 AM
✓ push server can load mount info from database
02/28/2023 11:18:31 AM
🗴 push server can't connect to the Nextcloud server
02/28/2023 11:18:31 AM
  Invalid response when getting test cookie
02/28/2023 11:18:41 AM
Setup notify_push (attempt 2/5)
02/28/2023 11:18:42 AM
✓ redis is configured
02/28/2023 11:18:42 AM
✓ push server is receiving redis messages
02/28/2023 11:18:42 AM
✓ push server can load mount info from database
02/28/2023 11:18:42 AM
🗴 push server can't connect to the Nextcloud server
02/28/2023 11:18:42 AM
  Invalid response when getting test cookie
02/28/2023 11:18:52 AM
Setup notify_push (attempt 3/5)
02/28/2023 11:18:54 AM
✓ redis is configured
02/28/2023 11:18:54 AM
✓ push server is receiving redis messages
02/28/2023 11:18:54 AM
✓ push server can load mount info from database
02/28/2023 11:18:54 AM
🗴 push server can't connect to the Nextcloud server
02/28/2023 11:18:54 AM
  Invalid response when getting test cookie
02/28/2023 11:22:34 AM
Install default configurations for new ncp apps
02/28/2023 11:22:35 AM
Installing template 'php/opcache.ini.sh'...
02/28/2023 11:22:40 AM
Installing template 'php/90-ncp.ini.sh'...
02/28/2023 11:22:49 AM
Installing template 'php/pool.d.www.conf.sh'...
02/28/2023 11:23:05 AM
Installing template 'ncp-metrics.cfg.sh'...
02/28/2023 11:23:50 AM
Starting PHP-fpm
02/28/2023 11:23:52 AM
Starting Apache
02/28/2023 11:23:53 AM
Installing template 'mysql/90-ncp.cnf.sh'...
02/28/2023 11:24:02 AM
INFO: Docker container detected.
02/28/2023 11:24:02 AM
Starting mariaDB
02/28/2023 11:24:02 AM
2023-02-28 10:24:02 0 [Note] mysqld (mysqld 10.5.18-MariaDB-0+deb11u1) starting as process 456 ...
02/28/2023 11:24:03 AM
2023-02-28 10:24:03 0 [Warning] The parameter innodb_file_format is deprecated and has no effect. It may be removed in future releases. See https://mariadb.com/kb/en/library/xtradbinnodb-file-format/
02/28/2023 11:24:03 AM
2023-02-28 10:24:03 0 [Warning] The parameter innodb_buffer_pool_instances is deprecated and has no effect.
02/28/2023 11:24:03 AM
2023-02-28 10:24:03 0 [Note] InnoDB: Uses event mutexes
02/28/2023 11:24:03 AM
2023-02-28 10:24:03 0 [Note] InnoDB: Compressed tables use zlib 1.2.11
02/28/2023 11:24:03 AM
2023-02-28 10:24:03 0 [Note] InnoDB: Number of pools: 1
02/28/2023 11:24:03 AM
2023-02-28 10:24:03 0 [Note] InnoDB: Using ARMv8 crc32 instructions
02/28/2023 11:24:03 AM
2023-02-28 10:24:03 0 [Note] mysqld: O_TMPFILE is not supported on /tmp (disabling future attempts)
02/28/2023 11:24:03 AM
2023-02-28 10:24:03 0 [Note] InnoDB: Using Linux native AIO
02/28/2023 11:24:03 AM
2023-02-28 10:24:03 0 [Note] InnoDB: Initializing buffer pool, total size = 2013265920, chunk size = 134217728
02/28/2023 11:24:07 AM
2023-02-28 10:24:07 0 [Note] InnoDB: Completed initialization of buffer pool
02/28/2023 11:24:07 AM
2023-02-28 10:24:07 0 [Note] InnoDB: Starting crash recovery from checkpoint LSN=10867225994,10867225994
02/28/2023 11:24:08 AM
2023-02-28 10:24:08 0 [Note] InnoDB: Starting final batch to recover 21 pages from redo log.
02/28/2023 11:24:08 AM
2023-02-28 10:24:08 0 [Note] InnoDB: 128 rollback segments are active.
02/28/2023 11:24:09 AM
2023-02-28 10:24:09 0 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
02/28/2023 11:24:09 AM
2023-02-28 10:24:09 0 [Note] InnoDB: Creating shared tablespace for temporary tables
02/28/2023 11:24:09 AM
2023-02-28 10:24:09 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
02/28/2023 11:24:09 AM
2023-02-28 10:24:09 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
02/28/2023 11:24:09 AM
2023-02-28 10:24:09 0 [Note] InnoDB: 10.5.18 started; log sequence number 10867263015; transaction id 19391203
02/28/2023 11:24:09 AM
2023-02-28 10:24:09 0 [Note] Plugin 'FEEDBACK' is disabled.
02/28/2023 11:24:09 AM
2023-02-28 10:24:09 0 [Note] InnoDB: Loading buffer pool(s) from /data/database/ib_buffer_pool
02/28/2023 11:24:09 AM
2023-02-28 10:24:09 0 [Note] Server socket created on IP: '127.0.0.1'.
02/28/2023 11:24:10 AM
2023-02-28 10:24:10 0 [Note] Reading of all Master_info entries succeeded
02/28/2023 11:24:10 AM
2023-02-28 10:24:10 0 [Note] Added new Master_info '' to hash table
02/28/2023 11:24:10 AM
2023-02-28 10:24:10 0 [Note] mysqld: ready for connections.
02/28/2023 11:24:10 AM
Version: '10.5.18-MariaDB-0+deb11u1'  socket: '/run/mysqld/mysqld.sock'  port: 3306  Debian 11
02/28/2023 11:24:20 AM
Starting Redis
02/28/2023 11:24:21 AM
Starting Cron
02/28/2023 11:24:21 AM
2023-02-28 10:24:21 0 [Note] InnoDB: Buffer pool(s) load completed at 230228 10:24:21
02/28/2023 11:24:21 AM
Starting Postfix
02/28/2023 11:24:29 AM
Provisioning
02/28/2023 11:25:01 AM
Provisioning Redis password
02/28/2023 11:25:01 AM
Updating NextCloud config with Redis password
02/28/2023 11:25:02 AM
Provisioning MariaDB password
02/28/2023 11:25:03 AM
Updating NextCloud config with MariaDB password
02/28/2023 11:25:16 AM
Running nc-limits
02/28/2023 11:25:17 AM
[ nc-limits ] (Tue Feb 28 10:25:16 UTC 2023)
02/28/2023 11:25:35 AM
Using 6183121920 for PHP max memory
02/28/2023 11:25:35 AM
Installing template 'php/90-ncp.ini.sh'...
02/28/2023 11:25:36 AM
Using 6183121920 for PHP max memory
02/28/2023 11:25:36 AM
Installing template 'php/90-ncp.ini.sh'...
02/28/2023 11:25:58 AM
Using 6 PHP threads
02/28/2023 11:25:58 AM
Installing template 'php/pool.d.www.conf.sh'...
02/28/2023 11:25:59 AM
Using 6 PHP threads
02/28/2023 11:25:59 AM
Installing template 'php/pool.d.www.conf.sh'...
02/28/2023 11:26:14 AM
Installing template 'mysql/91-ncp.cnf.sh'...
02/28/2023 11:26:14 AM
Installing template 'mysql/91-ncp.cnf.sh'...
02/28/2023 11:26:22 AM
Stopping redis-server: redis-server.
02/28/2023 11:26:22 AM
Stopping redis-server: redis-server.
02/28/2023 11:26:23 AM
Starting redis-server: redis-server.
02/28/2023 11:26:23 AM
Starting redis-server: redis-server.
02/28/2023 11:26:23 AM
02/28/2023 11:26:48 AM
Starting notify_push daemon
02/28/2023 11:27:04 AM
Configuring Domain
02/28/2023 11:27:42 AM
System config value trusted_domains => 11 set to string 77.204.154.52
02/28/2023 11:27:50 AM
System config value trusted_domains => 1 set to string 172.17.0.5
02/28/2023 11:27:58 AM
System config value trusted_domains => 14 set to string 77e25cf3e69d
02/28/2023 11:28:23 AM
System config value trusted_domains => 3 set to string 77e25cf3e69d
02/28/2023 11:28:31 AM
System config value overwrite.cli.url set to string https://77e25cf3e69d/
02/28/2023 11:28:48 AM
System config value trusted_proxies => 11 set to string 127.0.0.1
02/28/2023 11:28:53 AM
System config value trusted_proxies => 12 set to string ::1
02/28/2023 11:28:58 AM
System config value trusted_proxies => 13 set to string 77e25cf3e69d
02/28/2023 11:29:06 AM
System config value trusted_proxies => 14 set to empty string
02/28/2023 11:29:11 AM
Setup notify_push (attempt 1/5)
02/28/2023 11:29:17 AM
✓ redis is configured
02/28/2023 11:29:18 AM
✓ push server is receiving redis messages
02/28/2023 11:29:18 AM
✓ push server can load mount info from database
02/28/2023 11:29:19 AM
🗴 push server can't connect to the Nextcloud server
02/28/2023 11:29:19 AM
  Invalid response when getting test cookie
02/28/2023 11:29:29 AM
Setup notify_push (attempt 2/5)
02/28/2023 11:29:34 AM
✓ redis is configured
02/28/2023 11:29:35 AM
✓ push server is receiving redis messages
02/28/2023 11:29:35 AM
✓ push server can load mount info from database
02/28/2023 11:29:35 AM
🗴 push server can't connect to the Nextcloud server
02/28/2023 11:29:35 AM
  Invalid response when getting test cookie
02/28/2023 11:29:46 AM
Setup notify_push (attempt 3/5)
02/28/2023 11:29:51 AM
✓ redis is configured
02/28/2023 11:29:51 AM
✓ push server is receiving redis messages
02/28/2023 11:29:51 AM
✓ push server can load mount info from database
02/28/2023 11:29:52 AM
🗴 push server can't connect to the Nextcloud server
02/28/2023 11:29:52 AM
  Invalid response when getting test cookie
02/28/2023 11:30:02 AM
Setup notify_push (attempt 4/5)
02/28/2023 11:30:14 AM
✓ redis is configured
02/28/2023 11:30:15 AM
✓ push server is receiving redis messages
02/28/2023 11:30:15 AM
✓ push server can load mount info from database
02/28/2023 11:30:15 AM
🗴 push server can't connect to the Nextcloud server
02/28/2023 11:30:15 AM
  Invalid response when getting test cookie
02/28/2023 11:30:25 AM
Setup notify_push (attempt 5/5)
02/28/2023 11:30:31 AM
✓ redis is configured
02/28/2023 11:30:32 AM
✓ push server is receiving redis messages
02/28/2023 11:30:32 AM
✓ push server can load mount info from database
02/28/2023 11:30:32 AM
🗴 push server can't connect to the Nextcloud server
02/28/2023 11:30:32 AM
  Invalid response when getting test cookie
02/28/2023 11:30:47 AM
Nextcloud version 24.0.5.1. NextCloudPi version v1.51.0
02/28/2023 11:30:48 AM
Back up current instance...
02/28/2023 11:30:53 AM

    app=indexexception.Code=0exception.CustomMessage=--exception.Exception=RedisExceptionexception.File=/data/nextcloud/lib/private/RedisFactory.phpexception.Line=143exception.Message=WRONGPASS invalid username-password pairexception.Trace=[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object]level=3message=WRONGPASS invalid username-password pairmethod=GETremoteAddr=172.17.0.1reqId=Y_3X3ZGrswGDymDRIpPqdgAAAFYtime=2023-02-28T10:30:53+00:00url=/user=--userAgent=axios/0.27.2version=24.0.5.1

02/28/2023 11:30:53 AM

    app=coreexception.Code=0exception.CustomMessage=--exception.Exception=RedisExceptionexception.File=/data/nextcloud/lib/private/RedisFactory.phpexception.Line=143exception.Message=WRONGPASS invalid username-password pairexception.Trace=[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object]level=3message=WRONGPASS invalid username-password pairmethod=GETremoteAddr=172.17.0.1reqId=Y_3X3ZGrswGDymDRIpPqdgAAAFYtime=2023-02-28T10:30:53+00:00url=/user=--userAgent=axios/0.27.2version=24.0.5.1

02/28/2023 11:30:53 AM

    app=coreexception.Code=0exception.CustomMessage=--exception.Exception=RedisExceptionexception.File=/data/nextcloud/lib/private/Memcache/Redis.phpexception.Line=48exception.Message=NOAUTH Authentication required.exception.Trace=[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object]level=3message=NOAUTH Authentication required.method=GETremoteAddr=172.17.0.1reqId=Y_3X3ZGrswGDymDRIpPqdgAAAFYtime=2023-02-28T10:30:53+00:00url=/user=--userAgent=axios/0.27.2version=24.0.5.1

02/28/2023 11:30:55 AM
check free space...
02/28/2023 11:31:07 AM

    app=indexexception.Code=0exception.CustomMessage=--exception.Exception=RedisExceptionexception.File=/data/nextcloud/lib/private/RedisFactory.phpexception.Line=143exception.Message=WRONGPASS invalid username-password pairexception.Trace=[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object]level=3message=WRONGPASS invalid username-password pairmethod=GETremoteAddr=172.17.0.1reqId=Y_3X65GrswGDymDRIpPqeAAAAEEtime=2023-02-28T10:31:07+00:00url=/user=--userAgent=axios/0.27.2version=24.0.5.1

02/28/2023 11:31:07 AM

    app=coreexception.Code=0exception.CustomMessage=--exception.Exception=RedisExceptionexception.File=/data/nextcloud/lib/private/RedisFactory.phpexception.Line=143exception.Message=WRONGPASS invalid username-password pairexception.Trace=[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object]level=3message=WRONGPASS invalid username-password pairmethod=GETremoteAddr=172.17.0.1reqId=Y_3X65GrswGDymDRIpPqeAAAAEEtime=2023-02-28T10:31:07+00:00url=/user=--userAgent=axios/0.27.2version=24.0.5.1

02/28/2023 11:31:07 AM

    app=coreexception.Code=0exception.CustomMessage=--exception.Exception=RedisExceptionexception.File=/data/nextcloud/lib/private/Memcache/Redis.phpexception.Line=48exception.Message=NOAUTH Authentication required.exception.Trace=[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object]level=3message=NOAUTH Authentication required.method=GETremoteAddr=172.17.0.1reqId=Y_3X65GrswGDymDRIpPqeAAAAEEtime=2023-02-28T10:31:07+00:00url=/user=--userAgent=axios/0.27.2version=24.0.5.1

Two exceptions seems to be linked with Redis authentification :

02/28/2023 11:36:58 AM

app=coreexception.Code=0exception.CustomMessage=--exception.Exception=RedisExceptionexception.File=/data/nextcloud/lib/private/RedisFactory.phpexception.Line=143exception.Message=WRONGPASS invalid username-password pairexception.Trace=[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object]level=3message=WRONGPASS invalid username-password pairmethod=GETremoteAddr=172.17.0.1reqId=Y_3ZSqQFb2Hl8CozYzbMewAAAAEtime=2023-02-28T10:36:58+00:00url=/user=--userAgent=axios/0.27.2version=24.0.5.1

add app=core
exception=
    add Code=0
    add CustomMessage=--
    add Exception=RedisException
    add File=/data/nextcloud/lib/private/RedisFactory.php
    add Line=143
    add Message=WRONGPASS invalid username-password pair
    add Trace=[ { "args": [ "*** sensitive parameters replaced ***" ], "class": "Redis", "file": "/data/nextcloud/lib/private/RedisFactory.php", "function": "auth", "line": 143, "type": "->" } ,{ "class": "OC\\RedisFactory", "file": "/data/nextcloud/lib/private/RedisFactory.php", "function": "create", "line": 178, "type": "->" } ,{ "class": "OC\\RedisFactory", "file": "/data/nextcloud/lib/private/Memcache/Redis.php", "function": "getInstance", "line": 43, "type": "->" } ,{ "class": "OC\\Memcache\\Redis", "file": "/data/nextcloud/lib/private/Memcache/Factory.php", "function": "__construct", "line": 118, "type": "->" } ,{ "class": "OC\\Memcache\\Factory", "file": "/data/nextcloud/lib/private/Server.php", "function": "createLocking", "line": 1106, "type": "->" } ,{ "args": [ "*** sensitive parameters replaced ***" ], "class": "OC\\Server", "file": "/data/nextcloud/lib/private/AppFramework/Utility/SimpleContainer.php", "function": "OC\\{closure}", "line": 162, "type": "->" } ,{ "args": [ "*** sensitive parameters replaced ***" ], "class": "OC\\AppFramework\\Utility\\SimpleContainer", "file": "/data/nextcloud/3rdparty/pimple/pimple/src/Pimple/Container.php", "function": "OC\\AppFramework\\Utility\\{closure}", "line": 122, "type": "->" } ,{ "class": "Pimple\\Container", "file": "/data/nextcloud/lib/private/AppFramework/Utility/SimpleContainer.php", "function": "offsetGet", "line": 129, "type": "->" } ,{ "class": "OC\\AppFramework\\Utility\\SimpleContainer", "file": "/data/nextcloud/lib/private/ServerContainer.php", "function": "query", "line": 136, "type": "->" } ,{ "class": "OC\\ServerContainer", "file": "/data/nextcloud/lib/private/AppFramework/Utility/SimpleContainer.php", "function": "query", "line": 57, "type": "->" } ,{ "class": "OC\\AppFramework\\Utility\\SimpleContainer", "file": "/data/nextcloud/lib/private/Server.php", "function": "get", "line": 2082, "type": "->" } ,{ "class": "OC\\Server", "file": "/data/nextcloud/lib/private/Files/View.php", "function": "getLockingProvider", "line": 122, "type": "->" } ,{ "class": "OC\\Files\\View", "file": "/data/nextcloud/lib/private/Server.php", "function": "__construct", "line": 454, "type": "->" } ,{ "args": [ "*** sensitive parameters replaced ***" ], "class": "OC\\Server", "file": "/data/nextcloud/lib/private/AppFramework/Utility/SimpleContainer.php", "function": "OC\\{closure}", "line": 162, "type": "->" } ,{ "args": [ "*** sensitive parameters replaced ***" ], "class": "OC\\AppFramework\\Utility\\SimpleContainer", "file": "/data/nextcloud/3rdparty/pimple/pimple/src/Pimple/Container.php", "function": "OC\\AppFramework\\Utility\\{closure}", "line": 122, "type": "->" } ,{ "class": "Pimple\\Container", "file": "/data/nextcloud/lib/private/AppFramework/Utility/SimpleContainer.php", "function": "offsetGet", "line": 129, "type": "->" } ,{ "class": "OC\\AppFramework\\Utility\\SimpleContainer", "file": "/data/nextcloud/lib/private/ServerContainer.php", "function": "query", "line": 136, "type": "->" } ,{ "class": "OC\\ServerContainer", "file": "/data/nextcloud/lib/private/AppFramework/Utility/SimpleContainer.php", "function": "query", "line": 57, "type": "->" } ,{ "class": "OC\\AppFramework\\Utility\\SimpleContainer", "file": "/data/nextcloud/lib/private/Server.php", "function": "get", "line": 1445, "type": "->" } ,{ "class": "OC\\Server", "file": "/data/nextcloud/lib/base.php", "function": "boot", "line": 602, "type": "->" } ,{ "class": "OC", "file": "/data/nextcloud/lib/base.php", "function": "init", "line": 1111, "type": "::" } ,{ "args": [ "/data/nextcloud/lib/base.php" ], "file": "/data/nextcloud/index.php", "function": "require_once", "line": 34 } ] 
add level=3
add message=WRONGPASS invalid username-password pair
add method=GET
add remoteAddr=172.17.0.1
add reqId=Y_3ZSqQFb2Hl8CozYzbMewAAAAE
add time=2023-02-28T10:36:58+00:00
add url=/
add user=--
add userAgent=axios/0.27.2
add version=24.0.5.1

And

02/28/2023 11:36:58 AM

app=coreexception.Code=0exception.CustomMessage=--exception.Exception=RedisExceptionexception.File=/data/nextcloud/lib/private/Memcache/Redis.phpexception.Line=48exception.Message=NOAUTH Authentication required.exception.Trace=[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object],[object Object]level=3message=NOAUTH Authentication required.method=GETremoteAddr=172.17.0.1reqId=Y_3ZSqQFb2Hl8CozYzbMewAAAAEtime=2023-02-28T10:36:58+00:00url=/user=--userAgent=axios/0.27.2version=24.0.5.1

add app=core
exception=
    add Code=0
    add CustomMessage=--
    add Exception=RedisException
    add File=/data/nextcloud/lib/private/Memcache/Redis.php
    add Line=48
    add Message=NOAUTH Authentication required.
    add Trace=[ { "class": "Redis", "file": "/data/nextcloud/lib/private/Memcache/Redis.php", "function": "get", "line": 48, "type": "->" } ,{ "class": "OC\\Memcache\\Redis", "file": "/data/nextcloud/lib/private/App/InfoParser.php", "function": "get", "line": 58, "type": "->" } ,{ "class": "OC\\App\\InfoParser", "file": "/data/nextcloud/lib/private/App/AppManager.php", "function": "parse", "line": 506, "type": "->" } ,{ "class": "OC\\App\\AppManager", "file": "/data/nextcloud/lib/private/App/AppManager.php", "function": "getAppInfo", "line": 521, "type": "->" } ,{ "class": "OC\\App\\AppManager", "file": "/data/nextcloud/lib/private/legacy/OC_App.php", "function": "getAppVersion", "line": 568, "type": "->" } ,{ "class": "OC_App", "file": "/data/nextcloud/lib/private/legacy/OC_App.php", "function": "getAppVersion", "line": 891, "type": "::" } ,{ "class": "OC_App", "file": "/data/nextcloud/lib/private/legacy/OC_Util.php", "function": "shouldUpgrade", "line": 1225, "type": "::" } ,{ "class": "OC_Util", "file": "/data/nextcloud/lib/public/Util.php", "function": "needUpgrade", "line": 579, "type": "::" } ,{ "class": "OCP\\Util", "file": "/data/nextcloud/lib/private/legacy/OC_Template.php", "function": "needUpgrade", "line": 116, "type": "::" } ,{ "class": "OC_Template", "file": "/data/nextcloud/lib/private/legacy/OC_Template.php", "function": "initTemplateEngine", "line": 78, "type": "::" } ,{ "class": "OC_Template", "file": "/data/nextcloud/lib/private/legacy/OC_Template.php", "function": "__construct", "line": 324, "type": "->" } ,{ "class": "OC_Template", "file": "/data/nextcloud/index.php", "function": "printExceptionErrorPage", "line": 74, "type": "::" } ] 
add level=3
add message=NOAUTH Authentication required.
add method=GET
add remoteAddr=172.17.0.1
add reqId=Y_3ZSqQFb2Hl8CozYzbMewAAAAE
add time=2023-02-28T10:36:58+00:00
add url=/
add user=--
add userAgent=axios/0.27.2
add version=24.0.5.1

I don't really know what could be the cause, someone has an idea ? I maybe presented the logs badly, is there a better to present them to facilitate the understanding ?

feutl commented 1 year ago

I need to back this, this started to happen on my docker installation too and have no clue why. Any updated on this?

feutl commented 1 year ago

Any news on this, a hint, just something to start debugging? It is really annoying and effect the reliability of nextcloud.

Thanks

twojstaryzdomu commented 1 year ago

Outside of docker (NC set up with ./install.sh) and during installation, these issues appear to do with failing to restart redis if the configuration (redis.cfg or config.php) has been updated with fresh credentials. It may be easily avoided, comparing the redis systemd start time to the config file modification time and acting as needed. See: https://github.com/twojstaryzdomu/nextcloudpi/commit/68bc7ee173591d339b9f7f2efdc2e02fab732286

feutl commented 1 year ago

For me, in NCP-Docker this (https://github.com/nextcloud/nextcloudpi/issues/1652#issuecomment-1445141639) fixed my problem of seeing auth errors with redis. Adding the user "default" into the config.php redis configuration.

feutl commented 1 year ago

I did some more testing, and actually not the default user fixed the problem, but restarting php after the docker instance was booted. It seems that the password change, which is done on every restart for the docker installation is propagated to the redis config, but not all services are rebooted afterwards.

For now I do a restart of redis and php and the problems on my NCP docker instance disappeared. service redis-server restart service php8.1-fpm restart

Tommy-01 commented 1 year ago

I did some more testing, and actually not the default user fixed the problem, but restarting php after the docker instance was booted. It seems that the password change, which is done on every restart for the docker installation is propagated to the redis config, but not all services are rebooted afterwards.

For now I do a restart of redis and php and the problems on my NCP docker instance disappeared. service redis-server restart service php8.1-fpm restart

Thank you! That solved the Problem for me. Will there be an permanent solution?

feutl commented 1 year ago

This is something we need to ask @theCalcaholic :)