Log full of remote_endpoint errors

Open ajpanton opened this issue 10 months ago • 10 comments

Just noticed that my container's log shows this

[2025-Feb-13 14:14:59.337] 1 - [error] "wthttp/async: remote_endpoint() threw: remote_endpoint: Socket not connected [system:107 at /usr/include/boost/asio/detail/reactive_socket_service.hpp:218 in function 'remote_endpoint']"
[2025-Feb-13 14:15:04.339] 1 - [error] "wthttp/async: remote_endpoint() threw: remote_endpoint: Socket not connected [system:107 at /usr/include/boost/asio/detail/reactive_socket_service.hpp:218 in function 'remote_endpoint']"
[2025-Feb-13 14:15:09.350] 1 - [error] "wthttp/async: remote_endpoint() threw: remote_endpoint: Socket not connected [system:107 at /usr/include/boost/asio/detail/reactive_socket_service.hpp:218 in function 'remote_endpoint']"
[2025-Feb-13 14:15:14.356] 1 - [error] "wthttp/async: remote_endpoint() threw: remote_endpoint: Socket not connected [system:107 at /usr/include/boost/asio/detail/reactive_socket_service.hpp:218 in function 'remote_endpoint']"
[2025-Feb-13 14:15:19.361] 1 - [error] "wthttp/async: remote_endpoint() threw: remote_endpoint: Socket not connected [system:107 at /usr/include/boost/asio/detail/reactive_socket_service.hpp:218 in function 'remote_endpoint']"

It's a non-stop stream of errors every 5 seconds, day and night, whether a client is connected or not. Even if it turns out to be harmless, I'd like to make it stop.

My docker compose is quite basic I think:

services:
  lms:
    container_name: lms
    image: epoupon/lms:3.63.0
    network_mode: bridge
    ports:
      - 5082:5082
    volumes:
      - /etc/localtime:/etc/localtime:ro #standard volume I have in all my containers
      - /mnt/Docker/Volumes/lms_config:/conf:ro #points to a location I have external access to
      - lms_data:/var/lms #just a named local volume
      - lms_music:/mnt/Music:ro #points to an SMB share
    command: /conf/lms-custom.conf
    restart: unless-stopped

Config file is vanilla, except behind-reverse-proxy = true; and I've added my gateway's (reverse proxy's) IP to trusted proxies.

Feb 13 '25 12:02 ajpanton

Hello!

Unfortunately, I don't manage to reproduce. But this is very curious, especially if no client is connected... Do the errors appear right from the start? If you launch it with logs set to "debug," maybe we could see what's triggering this? Are you using ListenBrainz?

Feb 14 '25 09:02 epoupon

Tested again: Log level set to debug. Phone set to airplane mode to make absolutely sure the app doesn't connect. Apparently I had set up ListenBrainz, so I removed all that stuff from the settings. Again checked that my conf is completely vanilla, except the reverse/trusted proxy stuff, and now the log level. There's still some ListenBrainz in the conf, I assume that's fine.

This is what it looks like on startup:

- 140559740571488 [MAIN] locale set to 'C.UTF-8;C;C;C;C;C'
ARG = /usr/bin/lms
ARG = --config=/var/lms/wt_config.xml
ARG = --docroot=/usr/share/lms/docroot/;/resources,/css,/images,/js,/favicon.ico
ARG = --approot=/usr/share/lms/approot
ARG = --deploy-path=/
ARG = --resources-dir=/usr/share/Wt/resources
ARG = --http-port=5082
ARG = --http-address=0.0.0.0
ARG = --threads=8
[2025-Feb-14 11:29:02.102] 1 - [info] "config: reading Wt config file: /var/lms/wt_config.xml (location = '/usr/bin/lms')"
[2025-Feb-14 11:29:02.102] 1 - [info] - 140559740571488 [MAIN] Starting init web server...
[2025-Feb-14 11:29:02.102] 1 - [info] "WServer/wthttp: initializing built-in wthttpd"
[2025-Feb-14 11:29:02.106] 1 - [info] "wthttp: started server: http://0.0.0.0:5082"
[2025-Feb-14 11:29:02.106] 1 - [info] - 140559740571488 [UTILS] Starting IO context with 8 threads...
[2025-Feb-14 11:29:02.106] 1 - [info] - 140559740571488 [DB] Creating connection pool on file "/var/lms/lms.db"
[2025-Feb-14 11:29:02.107] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings...
[2025-Feb-14 11:29:02.109] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings done!
[2025-Feb-14 11:29:02.109] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings...
[2025-Feb-14 11:29:02.110] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings done!
[2025-Feb-14 11:29:02.110] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings...
[2025-Feb-14 11:29:02.111] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings done!
[2025-Feb-14 11:29:02.111] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings...
[2025-Feb-14 11:29:02.112] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings done!
[2025-Feb-14 11:29:02.112] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings...
[2025-Feb-14 11:29:02.113] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings done!
[2025-Feb-14 11:29:02.113] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings...
[2025-Feb-14 11:29:02.114] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings done!
[2025-Feb-14 11:29:02.114] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings...
[2025-Feb-14 11:29:02.114] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings done!
[2025-Feb-14 11:29:02.115] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings...
[2025-Feb-14 11:29:02.115] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings done!
[2025-Feb-14 11:29:02.115] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings...
[2025-Feb-14 11:29:02.116] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings done!
[2025-Feb-14 11:29:02.116] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings...
[2025-Feb-14 11:29:02.118] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings done!
[2025-Feb-14 11:29:02.118] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings...
[2025-Feb-14 11:29:02.119] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings done!
[2025-Feb-14 11:29:02.119] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings...
[2025-Feb-14 11:29:02.120] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings done!
[2025-Feb-14 11:29:02.120] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings...
[2025-Feb-14 11:29:02.120] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings done!
[2025-Feb-14 11:29:02.121] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings...
[2025-Feb-14 11:29:02.121] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings done!
[2025-Feb-14 11:29:02.122] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings...
[2025-Feb-14 11:29:02.122] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings done!
[2025-Feb-14 11:29:02.122] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings...
[2025-Feb-14 11:29:02.123] 1 - [debug] - 140559740571488 [DB] Setting per-connection settings done!
[2025-Feb-14 11:29:02.123] 1 - [info] - 140559740571488 [DB] Preparing tables...
[2025-Feb-14 11:29:02.125] 1 - [debug] - 140559740571488 [DB] Cannot create tables: Sqlite3: create table "rated_track" (
  "id" integer primary key autoincrement,
  "version" integer not null,
  "rating" integer not null,
  "last_updated" text,
  "track_id" bigint,
  "user_id" bigint,
  constraint "fk_rated_track_track" foreign key ("track_id") references "track" ("id") on delete cascade deferrable initially deferred,
  constraint "fk_rated_track_user" foreign key ("user_id") references "user" ("id") on delete cascade deferrable initially deferred
): table "rated_track" already exists
[2025-Feb-14 11:29:02.125] 1 - [info] - 140559740571488 [DB] Database version = 80, LMS binary version = 80
[2025-Feb-14 11:29:02.125] 1 - [info] - 140559740571488 [DB] Creating indexes... This may take a while...
[2025-Feb-14 11:29:02.126] 1 - [info] - 140559740571488 [DB] Indexes created!
[2025-Feb-14 11:29:02.126] 1 - [info] - 140559740571488 [DB] page stats: page_count = 7644, freelist_count = 0
[2025-Feb-14 11:29:02.126] 1 - [info] - 140559740571488 [DB] Performing database analyze... This may take a while...
[2025-Feb-14 11:29:02.126] 1 - [debug] - 140559740571488 [DB] Analyzing rated_track
[2025-Feb-14 11:29:02.128] 1 - [debug] - 140559740571488 [DB] Analyzing rated_track: done!
[2025-Feb-14 11:29:02.128] 1 - [debug] - 140559740571488 [DB] Analyzing sqlite_sequence
[2025-Feb-14 11:29:02.129] 1 - [debug] - 140559740571488 [DB] Analyzing sqlite_sequence: done!
[2025-Feb-14 11:29:02.129] 1 - [debug] - 140559740571488 [DB] Analyzing cluster_type
[2025-Feb-14 11:29:02.129] 1 - [debug] - 140559740571488 [DB] Analyzing cluster_type: done!
[2025-Feb-14 11:29:02.129] 1 - [debug] - 140559740571488 [DB] Analyzing rated_artist
[2025-Feb-14 11:29:02.129] 1 - [debug] - 140559740571488 [DB] Analyzing rated_artist: done!
[2025-Feb-14 11:29:02.129] 1 - [debug] - 140559740571488 [DB] Analyzing release_type
[2025-Feb-14 11:29:02.129] 1 - [debug] - 140559740571488 [DB] Analyzing release_type: done!
[2025-Feb-14 11:29:02.129] 1 - [debug] - 140559740571488 [DB] Analyzing track_lyrics
[2025-Feb-14 11:29:02.129] 1 - [debug] - 140559740571488 [DB] Analyzing track_lyrics: done!
[2025-Feb-14 11:29:02.129] 1 - [debug] - 140559740571488 [DB] Analyzing version_info
[2025-Feb-14 11:29:02.129] 1 - [debug] - 140559740571488 [DB] Analyzing version_info: done!
[2025-Feb-14 11:29:02.129] 1 - [debug] - 140559740571488 [DB] Analyzing media_library
[2025-Feb-14 11:29:02.130] 1 - [debug] - 140559740571488 [DB] Analyzing media_library: done!
[2025-Feb-14 11:29:02.130] 1 - [debug] - 140559740571488 [DB] Analyzing playlist_file
[2025-Feb-14 11:29:02.130] 1 - [debug] - 140559740571488 [DB] Analyzing playlist_file: done!
[2025-Feb-14 11:29:02.130] 1 - [debug] - 140559740571488 [DB] Analyzing rated_release
[2025-Feb-14 11:29:02.130] 1 - [debug] - 140559740571488 [DB] Analyzing rated_release: done!
[2025-Feb-14 11:29:02.130] 1 - [debug] - 140559740571488 [DB] Analyzing scan_settings
[2025-Feb-14 11:29:02.130] 1 - [debug] - 140559740571488 [DB] Analyzing scan_settings: done!
[2025-Feb-14 11:29:02.130] 1 - [debug] - 140559740571488 [DB] Analyzing starred_track
[2025-Feb-14 11:29:02.130] 1 - [debug] - 140559740571488 [DB] Analyzing starred_track: done!
[2025-Feb-14 11:29:02.130] 1 - [debug] - 140559740571488 [DB] Analyzing starred_artist
[2025-Feb-14 11:29:02.130] 1 - [debug] - 140559740571488 [DB] Analyzing starred_artist: done!
[2025-Feb-14 11:29:02.130] 1 - [debug] - 140559740571488 [DB] Analyzing track_bookmark
[2025-Feb-14 11:29:02.130] 1 - [debug] - 140559740571488 [DB] Analyzing track_bookmark: done!
[2025-Feb-14 11:29:02.131] 1 - [debug] - 140559740571488 [DB] Analyzing track_features
[2025-Feb-14 11:29:02.131] 1 - [debug] - 140559740571488 [DB] Analyzing track_features: done!
[2025-Feb-14 11:29:02.131] 1 - [debug] - 140559740571488 [DB] Analyzing starred_release
[2025-Feb-14 11:29:02.131] 1 - [debug] - 140559740571488 [DB] Analyzing starred_release: done!
[2025-Feb-14 11:29:02.131] 1 - [debug] - 140559740571488 [DB] Analyzing tracklist_entry
[2025-Feb-14 11:29:02.131] 1 - [debug] - 140559740571488 [DB] Analyzing tracklist_entry: done!
[2025-Feb-14 11:29:02.131] 1 - [debug] - 140559740571488 [DB] Analyzing track_artist_link
[2025-Feb-14 11:29:02.147] 1 - [debug] - 140559740571488 [DB] Analyzing track_artist_link: done!
[2025-Feb-14 11:29:02.147] 1 - [debug] - 140559740571488 [DB] Analyzing user
[2025-Feb-14 11:29:02.148] 1 - [debug] - 140559740571488 [DB] Analyzing user: done!
[2025-Feb-14 11:29:02.148] 1 - [debug] - 140559740571488 [DB] Analyzing image
[2025-Feb-14 11:29:02.149] 1 - [debug] - 140559740571488 [DB] Analyzing image: done!
[2025-Feb-14 11:29:02.149] 1 - [debug] - 140559740571488 [DB] Analyzing label
[2025-Feb-14 11:29:02.149] 1 - [debug] - 140559740571488 [DB] Analyzing label: done!
[2025-Feb-14 11:29:02.149] 1 - [debug] - 140559740571488 [DB] Analyzing track
[2025-Feb-14 11:29:02.192] 1 - [debug] - 140559740571488 [DB] Analyzing track: done!
[2025-Feb-14 11:29:02.192] 1 - [debug] - 140559740571488 [DB] Analyzing artist
[2025-Feb-14 11:29:02.194] 1 - [debug] - 140559740571488 [DB] Analyzing artist: done!
[2025-Feb-14 11:29:02.194] 1 - [debug] - 140559740571488 [DB] Analyzing listen
[2025-Feb-14 11:29:02.232] 1 - [debug] - 140559740571488 [DB] Analyzing listen: done!
[2025-Feb-14 11:29:02.232] 1 - [debug] - 140559740571488 [DB] Analyzing cluster
[2025-Feb-14 11:29:02.232] 1 - [debug] - 140559740571488 [DB] Analyzing cluster: done!
[2025-Feb-14 11:29:02.232] 1 - [debug] - 140559740571488 [DB] Analyzing release
[2025-Feb-14 11:29:02.233] 1 - [debug] - 140559740571488 [DB] Analyzing release: done!
[2025-Feb-14 11:29:02.233] 1 - [debug] - 140559740571488 [DB] Analyzing ui_state
[2025-Feb-14 11:29:02.233] 1 - [debug] - 140559740571488 [DB] Analyzing ui_state: done!
[2025-Feb-14 11:29:02.233] 1 - [debug] - 140559740571488 [DB] Analyzing auth_token
[2025-Feb-14 11:29:02.233] 1 - [debug] - 140559740571488 [DB] Analyzing auth_token: done!
[2025-Feb-14 11:29:02.233] 1 - [debug] - 140559740571488 [DB] Analyzing directory
[2025-Feb-14 11:29:02.234] 1 - [debug] - 140559740571488 [DB] Analyzing directory: done!
[2025-Feb-14 11:29:02.234] 1 - [debug] - 140559740571488 [DB] Analyzing playqueue
[2025-Feb-14 11:29:02.234] 1 - [debug] - 140559740571488 [DB] Analyzing playqueue: done!
[2025-Feb-14 11:29:02.234] 1 - [debug] - 140559740571488 [DB] Analyzing tracklist
[2025-Feb-14 11:29:02.235] 1 - [debug] - 140559740571488 [DB] Analyzing tracklist: done!
[2025-Feb-14 11:29:02.235] 1 - [debug] - 140559740571488 [DB] Analyzing release_release_type
[2025-Feb-14 11:29:02.235] 1 - [debug] - 140559740571488 [DB] Analyzing release_release_type: done!
[2025-Feb-14 11:29:02.235] 1 - [debug] - 140559740571488 [DB] Analyzing sqlite_autoindex_release_release_type_1
[2025-Feb-14 11:29:02.235] 1 - [debug] - 140559740571488 [DB] Analyzing sqlite_autoindex_release_release_type_1: done!
[2025-Feb-14 11:29:02.235] 1 - [debug] - 140559740571488 [DB] Analyzing release_label
[2025-Feb-14 11:29:02.235] 1 - [debug] - 140559740571488 [DB] Analyzing release_label: done!
[2025-Feb-14 11:29:02.235] 1 - [debug] - 140559740571488 [DB] Analyzing sqlite_autoindex_release_label_1
[2025-Feb-14 11:29:02.235] 1 - [debug] - 140559740571488 [DB] Analyzing sqlite_autoindex_release_label_1: done!
[2025-Feb-14 11:29:02.235] 1 - [debug] - 140559740571488 [DB] Analyzing track_cluster
[2025-Feb-14 11:29:02.238] 1 - [debug] - 140559740571488 [DB] Analyzing track_cluster: done!
[2025-Feb-14 11:29:02.238] 1 - [debug] - 140559740571488 [DB] Analyzing sqlite_autoindex_track_cluster_1
[2025-Feb-14 11:29:02.240] 1 - [debug] - 140559740571488 [DB] Analyzing sqlite_autoindex_track_cluster_1: done!
[2025-Feb-14 11:29:02.240] 1 - [debug] - 140559740571488 [DB] Analyzing user_starred_artists
[2025-Feb-14 11:29:02.240] 1 - [debug] - 140559740571488 [DB] Analyzing user_starred_artists: done!
[2025-Feb-14 11:29:02.240] 1 - [debug] - 140559740571488 [DB] Analyzing sqlite_autoindex_user_starred_artists_1
[2025-Feb-14 11:29:02.240] 1 - [debug] - 140559740571488 [DB] Analyzing sqlite_autoindex_user_starred_artists_1: done!
[2025-Feb-14 11:29:02.240] 1 - [debug] - 140559740571488 [DB] Analyzing playqueue_track
[2025-Feb-14 11:29:02.240] 1 - [debug] - 140559740571488 [DB] Analyzing playqueue_track: done!
[2025-Feb-14 11:29:02.240] 1 - [debug] - 140559740571488 [DB] Analyzing sqlite_autoindex_playqueue_track_1
[2025-Feb-14 11:29:02.240] 1 - [debug] - 140559740571488 [DB] Analyzing sqlite_autoindex_playqueue_track_1: done!
[2025-Feb-14 11:29:02.240] 1 - [debug] - 140559740571488 [DB] Analyzing sqlite_stat1
[2025-Feb-14 11:29:02.240] 1 - [debug] - 140559740571488 [DB] Analyzing sqlite_stat1: done!
[2025-Feb-14 11:29:02.240] 1 - [debug] - 140559740571488 [DB] Analyzing release_release_type_release_type
[2025-Feb-14 11:29:02.240] 1 - [debug] - 140559740571488 [DB] Analyzing release_release_type_release_type: done!
[2025-Feb-14 11:29:02.240] 1 - [debug] - 140559740571488 [DB] Analyzing release_release_type_release
[2025-Feb-14 11:29:02.240] 1 - [debug] - 140559740571488 [DB] Analyzing release_release_type_release: done!
[2025-Feb-14 11:29:02.240] 1 - [debug] - 140559740571488 [DB] Analyzing release_label_label
[2025-Feb-14 11:29:02.241] 1 - [debug] - 140559740571488 [DB] Analyzing release_label_label: done!
[2025-Feb-14 11:29:02.241] 1 - [debug] - 140559740571488 [DB] Analyzing release_label_release
[2025-Feb-14 11:29:02.241] 1 - [debug] - 140559740571488 [DB] Analyzing release_label_release: done!
[2025-Feb-14 11:29:02.241] 1 - [debug] - 140559740571488 [DB] Analyzing track_cluster_track
[2025-Feb-14 11:29:02.242] 1 - [debug] - 140559740571488 [DB] Analyzing track_cluster_track: done!
[2025-Feb-14 11:29:02.242] 1 - [debug] - 140559740571488 [DB] Analyzing track_cluster_cluster
[2025-Feb-14 11:29:02.243] 1 - [debug] - 140559740571488 [DB] Analyzing track_cluster_cluster: done!
[2025-Feb-14 11:29:02.243] 1 - [debug] - 140559740571488 [DB] Analyzing user_starred_artists_artist
[2025-Feb-14 11:29:02.243] 1 - [debug] - 140559740571488 [DB] Analyzing user_starred_artists_artist: done!
[2025-Feb-14 11:29:02.243] 1 - [debug] - 140559740571488 [DB] Analyzing user_starred_artists_starred_artist
[2025-Feb-14 11:29:02.243] 1 - [debug] - 140559740571488 [DB] Analyzing user_starred_artists_starred_artist: done!
[2025-Feb-14 11:29:02.243] 1 - [debug] - 140559740571488 [DB] Analyzing playqueue_track_playqueue
[2025-Feb-14 11:29:02.243] 1 - [debug] - 140559740571488 [DB] Analyzing playqueue_track_playqueue: done!
[2025-Feb-14 11:29:02.243] 1 - [debug] - 140559740571488 [DB] Analyzing playqueue_track_track
[2025-Feb-14 11:29:02.243] 1 - [debug] - 140559740571488 [DB] Analyzing playqueue_track_track: done!
[2025-Feb-14 11:29:02.243] 1 - [debug] - 140559740571488 [DB] Analyzing artist_id_idx
[2025-Feb-14 11:29:02.243] 1 - [debug] - 140559740571488 [DB] Analyzing artist_id_idx: done!
[2025-Feb-14 11:29:02.243] 1 - [debug] - 140559740571488 [DB] Analyzing artist_image_idx
[2025-Feb-14 11:29:02.244] 1 - [debug] - 140559740571488 [DB] Analyzing artist_image_idx: done!
[2025-Feb-14 11:29:02.244] 1 - [debug] - 140559740571488 [DB] Analyzing artist_name_idx
[2025-Feb-14 11:29:02.245] 1 - [debug] - 140559740571488 [DB] Analyzing artist_name_idx: done!
[2025-Feb-14 11:29:02.245] 1 - [debug] - 140559740571488 [DB] Analyzing artist_sort_name_nocase_idx
[2025-Feb-14 11:29:02.245] 1 - [debug] - 140559740571488 [DB] Analyzing artist_sort_name_nocase_idx: done!
[2025-Feb-14 11:29:02.245] 1 - [debug] - 140559740571488 [DB] Analyzing artist_mbid_idx
[2025-Feb-14 11:29:02.246] 1 - [debug] - 140559740571488 [DB] Analyzing artist_mbid_idx: done!
[2025-Feb-14 11:29:02.246] 1 - [debug] - 140559740571488 [DB] Analyzing auth_token_user_domain_idx
[2025-Feb-14 11:29:02.246] 1 - [debug] - 140559740571488 [DB] Analyzing auth_token_user_domain_idx: done!
[2025-Feb-14 11:29:02.246] 1 - [debug] - 140559740571488 [DB] Analyzing auth_token_domain_expiry_idx
[2025-Feb-14 11:29:02.246] 1 - [debug] - 140559740571488 [DB] Analyzing auth_token_domain_expiry_idx: done!
[2025-Feb-14 11:29:02.246] 1 - [debug] - 140559740571488 [DB] Analyzing auth_token_domain_value_idx
[2025-Feb-14 11:29:02.246] 1 - [debug] - 140559740571488 [DB] Analyzing auth_token_domain_value_idx: done!
[2025-Feb-14 11:29:02.246] 1 - [debug] - 140559740571488 [DB] Analyzing cluster_cluster_type_idx
[2025-Feb-14 11:29:02.246] 1 - [debug] - 140559740571488 [DB] Analyzing cluster_cluster_type_idx: done!
[2025-Feb-14 11:29:02.246] 1 - [debug] - 140559740571488 [DB] Analyzing cluster_type_name_idx
[2025-Feb-14 11:29:02.247] 1 - [debug] - 140559740571488 [DB] Analyzing cluster_type_name_idx: done!
[2025-Feb-14 11:29:02.247] 1 - [debug] - 140559740571488 [DB] Analyzing directory_id_idx
[2025-Feb-14 11:29:02.247] 1 - [debug] - 140559740571488 [DB] Analyzing directory_id_idx: done!
[2025-Feb-14 11:29:02.247] 1 - [debug] - 140559740571488 [DB] Analyzing directory_parent_directory_idx
[2025-Feb-14 11:29:02.247] 1 - [debug] - 140559740571488 [DB] Analyzing directory_parent_directory_idx: done!
[2025-Feb-14 11:29:02.247] 1 - [debug] - 140559740571488 [DB] Analyzing directory_path_idx
[2025-Feb-14 11:29:02.247] 1 - [debug] - 140559740571488 [DB] Analyzing directory_path_idx: done!
[2025-Feb-14 11:29:02.247] 1 - [debug] - 140559740571488 [DB] Analyzing directory_media_library_idx
[2025-Feb-14 11:29:02.248] 1 - [debug] - 140559740571488 [DB] Analyzing directory_media_library_idx: done!
[2025-Feb-14 11:29:02.248] 1 - [debug] - 140559740571488 [DB] Analyzing directory_name_idx
[2025-Feb-14 11:29:02.248] 1 - [debug] - 140559740571488 [DB] Analyzing directory_name_idx: done!
[2025-Feb-14 11:29:02.248] 1 - [debug] - 140559740571488 [DB] Analyzing image_directory_stem_idx
[2025-Feb-14 11:29:02.249] 1 - [debug] - 140559740571488 [DB] Analyzing image_directory_stem_idx: done!
[2025-Feb-14 11:29:02.249] 1 - [debug] - 140559740571488 [DB] Analyzing image_id_idx
[2025-Feb-14 11:29:02.249] 1 - [debug] - 140559740571488 [DB] Analyzing image_id_idx: done!
[2025-Feb-14 11:29:02.249] 1 - [debug] - 140559740571488 [DB] Analyzing image_path_idx
[2025-Feb-14 11:29:02.249] 1 - [debug] - 140559740571488 [DB] Analyzing image_path_idx: done!
[2025-Feb-14 11:29:02.249] 1 - [debug] - 140559740571488 [DB] Analyzing image_stem_idx
[2025-Feb-14 11:29:02.250] 1 - [debug] - 140559740571488 [DB] Analyzing image_stem_idx: done!
[2025-Feb-14 11:29:02.250] 1 - [debug] - 140559740571488 [DB] Analyzing label_name_idx
[2025-Feb-14 11:29:02.250] 1 - [debug] - 140559740571488 [DB] Analyzing label_name_idx: done!
[2025-Feb-14 11:29:02.250] 1 - [debug] - 140559740571488 [DB] Analyzing listen_backend_idx
[2025-Feb-14 11:29:02.253] 1 - [debug] - 140559740571488 [DB] Analyzing listen_backend_idx: done!
[2025-Feb-14 11:29:02.253] 1 - [debug] - 140559740571488 [DB] Analyzing listen_id_idx
[2025-Feb-14 11:29:02.257] 1 - [debug] - 140559740571488 [DB] Analyzing listen_id_idx: done!
[2025-Feb-14 11:29:02.257] 1 - [debug] - 140559740571488 [DB] Analyzing listen_user_backend_idx
[2025-Feb-14 11:29:02.262] 1 - [debug] - 140559740571488 [DB] Analyzing listen_user_backend_idx: done!
[2025-Feb-14 11:29:02.262] 1 - [debug] - 140559740571488 [DB] Analyzing listen_user_backend_date_time_idx
[2025-Feb-14 11:29:02.272] 1 - [debug] - 140559740571488 [DB] Analyzing listen_user_backend_date_time_idx: done!
[2025-Feb-14 11:29:02.272] 1 - [debug] - 140559740571488 [DB] Analyzing listen_track_user_backend_idx
[2025-Feb-14 11:29:02.280] 1 - [debug] - 140559740571488 [DB] Analyzing listen_track_user_backend_idx: done!
[2025-Feb-14 11:29:02.280] 1 - [debug] - 140559740571488 [DB] Analyzing listen_user_track_backend_date_time_idx
[2025-Feb-14 11:29:02.290] 1 - [debug] - 140559740571488 [DB] Analyzing listen_user_track_backend_date_time_idx: done!
[2025-Feb-14 11:29:02.290] 1 - [debug] - 140559740571488 [DB] Analyzing media_library_id_idx
[2025-Feb-14 11:29:02.290] 1 - [debug] - 140559740571488 [DB] Analyzing media_library_id_idx: done!
[2025-Feb-14 11:29:02.290] 1 - [debug] - 140559740571488 [DB] Analyzing playlist_file_id_idx
[2025-Feb-14 11:29:02.291] 1 - [debug] - 140559740571488 [DB] Analyzing playlist_file_id_idx: done!
[2025-Feb-14 11:29:02.291] 1 - [debug] - 140559740571488 [DB] Analyzing playlist_file_absolute_file_path_idx
[2025-Feb-14 11:29:02.291] 1 - [debug] - 140559740571488 [DB] Analyzing playlist_file_absolute_file_path_idx: done!
[2025-Feb-14 11:29:02.291] 1 - [debug] - 140559740571488 [DB] Analyzing rated_artist_user_artist_idx
[2025-Feb-14 11:29:02.291] 1 - [debug] - 140559740571488 [DB] Analyzing rated_artist_user_artist_idx: done!
[2025-Feb-14 11:29:02.291] 1 - [debug] - 140559740571488 [DB] Analyzing rated_release_user_release_idx
[2025-Feb-14 11:29:02.291] 1 - [debug] - 140559740571488 [DB] Analyzing rated_release_user_release_idx: done!
[2025-Feb-14 11:29:02.291] 1 - [debug] - 140559740571488 [DB] Analyzing rated_track_user_track_idx
[2025-Feb-14 11:29:02.292] 1 - [debug] - 140559740571488 [DB] Analyzing rated_track_user_track_idx: done!
[2025-Feb-14 11:29:02.292] 1 - [debug] - 140559740571488 [DB] Analyzing release_id_idx
[2025-Feb-14 11:29:02.292] 1 - [debug] - 140559740571488 [DB] Analyzing release_id_idx: done!
[2025-Feb-14 11:29:02.292] 1 - [debug] - 140559740571488 [DB] Analyzing release_image_idx
[2025-Feb-14 11:29:02.293] 1 - [debug] - 140559740571488 [DB] Analyzing release_image_idx: done!
[2025-Feb-14 11:29:02.293] 1 - [debug] - 140559740571488 [DB] Analyzing release_mbid_idx
[2025-Feb-14 11:29:02.293] 1 - [debug] - 140559740571488 [DB] Analyzing release_mbid_idx: done!
[2025-Feb-14 11:29:02.293] 1 - [debug] - 140559740571488 [DB] Analyzing release_name_idx
[2025-Feb-14 11:29:02.293] 1 - [debug] - 140559740571488 [DB] Analyzing release_name_idx: done!
[2025-Feb-14 11:29:02.293] 1 - [debug] - 140559740571488 [DB] Analyzing release_name_nocase_idx
[2025-Feb-14 11:29:02.294] 1 - [debug] - 140559740571488 [DB] Analyzing release_name_nocase_idx: done!
[2025-Feb-14 11:29:02.294] 1 - [debug] - 140559740571488 [DB] Analyzing release_type_name_idx
[2025-Feb-14 11:29:02.294] 1 - [debug] - 140559740571488 [DB] Analyzing release_type_name_idx: done!
[2025-Feb-14 11:29:02.294] 1 - [debug] - 140559740571488 [DB] Analyzing track_id_idx
[2025-Feb-14 11:29:02.295] 1 - [debug] - 140559740571488 [DB] Analyzing track_id_idx: done!
[2025-Feb-14 11:29:02.295] 1 - [debug] - 140559740571488 [DB] Analyzing track_absolute_path_idx
[2025-Feb-14 11:29:02.298] 1 - [debug] - 140559740571488 [DB] Analyzing track_absolute_path_idx: done!
[2025-Feb-14 11:29:02.298] 1 - [debug] - 140559740571488 [DB] Analyzing track_date_idx
[2025-Feb-14 11:29:02.300] 1 - [debug] - 140559740571488 [DB] Analyzing track_date_idx: done!
[2025-Feb-14 11:29:02.300] 1 - [debug] - 140559740571488 [DB] Analyzing track_directory_release_idx
[2025-Feb-14 11:29:02.301] 1 - [debug] - 140559740571488 [DB] Analyzing track_directory_release_idx: done!
[2025-Feb-14 11:29:02.301] 1 - [debug] - 140559740571488 [DB] Analyzing track_directory_file_stem_idx
[2025-Feb-14 11:29:02.304] 1 - [debug] - 140559740571488 [DB] Analyzing track_directory_file_stem_idx: done!
[2025-Feb-14 11:29:02.304] 1 - [debug] - 140559740571488 [DB] Analyzing track_file_added_idx
[2025-Feb-14 11:29:02.305] 1 - [debug] - 140559740571488 [DB] Analyzing track_file_added_idx: done!
[2025-Feb-14 11:29:02.305] 1 - [debug] - 140559740571488 [DB] Analyzing track_file_added_desc_idx
[2025-Feb-14 11:29:02.307] 1 - [debug] - 140559740571488 [DB] Analyzing track_file_added_desc_idx: done!
[2025-Feb-14 11:29:02.307] 1 - [debug] - 140559740571488 [DB] Analyzing track_file_last_write_idx
[2025-Feb-14 11:29:02.309] 1 - [debug] - 140559740571488 [DB] Analyzing track_file_last_write_idx: done!
[2025-Feb-14 11:29:02.309] 1 - [debug] - 140559740571488 [DB] Analyzing track_file_last_write_desc_idx
[2025-Feb-14 11:29:02.311] 1 - [debug] - 140559740571488 [DB] Analyzing track_file_last_write_desc_idx: done!
[2025-Feb-14 11:29:02.311] 1 - [debug] - 140559740571488 [DB] Analyzing track_file_name_idx
[2025-Feb-14 11:29:02.314] 1 - [debug] - 140559740571488 [DB] Analyzing track_file_name_idx: done!
[2025-Feb-14 11:29:02.314] 1 - [debug] - 140559740571488 [DB] Analyzing track_media_library_idx
[2025-Feb-14 11:29:02.315] 1 - [debug] - 140559740571488 [DB] Analyzing track_media_library_idx: done!
[2025-Feb-14 11:29:02.315] 1 - [debug] - 140559740571488 [DB] Analyzing track_media_library_release_idx
[2025-Feb-14 11:29:02.316] 1 - [debug] - 140559740571488 [DB] Analyzing track_media_library_release_idx: done!
[2025-Feb-14 11:29:02.316] 1 - [debug] - 140559740571488 [DB] Analyzing track_mbid_idx
[2025-Feb-14 11:29:02.318] 1 - [debug] - 140559740571488 [DB] Analyzing track_mbid_idx: done!
[2025-Feb-14 11:29:02.318] 1 - [debug] - 140559740571488 [DB] Analyzing track_name_idx
[2025-Feb-14 11:29:02.320] 1 - [debug] - 140559740571488 [DB] Analyzing track_name_idx: done!
[2025-Feb-14 11:29:02.320] 1 - [debug] - 140559740571488 [DB] Analyzing track_name_nocase_idx
[2025-Feb-14 11:29:02.322] 1 - [debug] - 140559740571488 [DB] Analyzing track_name_nocase_idx: done!
[2025-Feb-14 11:29:02.322] 1 - [debug] - 140559740571488 [DB] Analyzing track_original_date_idx
[2025-Feb-14 11:29:02.323] 1 - [debug] - 140559740571488 [DB] Analyzing track_original_date_idx: done!
[2025-Feb-14 11:29:02.323] 1 - [debug] - 140559740571488 [DB] Analyzing track_recording_mbid_idx
[2025-Feb-14 11:29:02.324] 1 - [debug] - 140559740571488 [DB] Analyzing track_recording_mbid_idx: done!
[2025-Feb-14 11:29:02.325] 1 - [debug] - 140559740571488 [DB] Analyzing track_release_idx
[2025-Feb-14 11:29:02.326] 1 - [debug] - 140559740571488 [DB] Analyzing track_release_idx: done!
[2025-Feb-14 11:29:02.326] 1 - [debug] - 140559740571488 [DB] Analyzing track_release_date_idx
[2025-Feb-14 11:29:02.327] 1 - [debug] - 140559740571488 [DB] Analyzing track_release_date_idx: done!
[2025-Feb-14 11:29:02.327] 1 - [debug] - 140559740571488 [DB] Analyzing track_release_date_desc_idx
[2025-Feb-14 11:29:02.329] 1 - [debug] - 140559740571488 [DB] Analyzing track_release_date_desc_idx: done!
[2025-Feb-14 11:29:02.329] 1 - [debug] - 140559740571488 [DB] Analyzing track_release_file_last_write_idx
[2025-Feb-14 11:29:02.332] 1 - [debug] - 140559740571488 [DB] Analyzing track_release_file_last_write_idx: done!
[2025-Feb-14 11:29:02.332] 1 - [debug] - 140559740571488 [DB] Analyzing track_release_file_last_write_desc_idx
[2025-Feb-14 11:29:02.334] 1 - [debug] - 140559740571488 [DB] Analyzing track_release_file_last_write_desc_idx: done!
[2025-Feb-14 11:29:02.334] 1 - [debug] - 140559740571488 [DB] Analyzing track_release_file_added_idx
[2025-Feb-14 11:29:02.336] 1 - [debug] - 140559740571488 [DB] Analyzing track_release_file_added_idx: done!
[2025-Feb-14 11:29:02.336] 1 - [debug] - 140559740571488 [DB] Analyzing track_release_file_added_desc_idx
[2025-Feb-14 11:29:02.338] 1 - [debug] - 140559740571488 [DB] Analyzing track_release_file_added_desc_idx: done!
[2025-Feb-14 11:29:02.338] 1 - [debug] - 140559740571488 [DB] Analyzing tracklist_name_idx
[2025-Feb-14 11:29:02.339] 1 - [debug] - 140559740571488 [DB] Analyzing tracklist_name_idx: done!
[2025-Feb-14 11:29:02.339] 1 - [debug] - 140559740571488 [DB] Analyzing tracklist_user_type_idx
[2025-Feb-14 11:29:02.339] 1 - [debug] - 140559740571488 [DB] Analyzing tracklist_user_type_idx: done!
[2025-Feb-14 11:29:02.339] 1 - [debug] - 140559740571488 [DB] Analyzing tracklist_last_modified_date_time_idx
[2025-Feb-14 11:29:02.339] 1 - [debug] - 140559740571488 [DB] Analyzing tracklist_last_modified_date_time_idx: done!
[2025-Feb-14 11:29:02.339] 1 - [debug] - 140559740571488 [DB] Analyzing tracklist_entry_idx
[2025-Feb-14 11:29:02.339] 1 - [debug] - 140559740571488 [DB] Analyzing tracklist_entry_idx: done!
[2025-Feb-14 11:29:02.339] 1 - [debug] - 140559740571488 [DB] Analyzing tracklist_entry_tracklist_track_idx
[2025-Feb-14 11:29:02.339] 1 - [debug] - 140559740571488 [DB] Analyzing tracklist_entry_tracklist_track_idx: done!
[2025-Feb-14 11:29:02.339] 1 - [debug] - 140559740571488 [DB] Analyzing track_artist_link_artist_idx
[2025-Feb-14 11:29:02.341] 1 - [debug] - 140559740571488 [DB] Analyzing track_artist_link_artist_idx: done!
[2025-Feb-14 11:29:02.341] 1 - [debug] - 140559740571488 [DB] Analyzing track_artist_link_artist_track_idx
[2025-Feb-14 11:29:02.344] 1 - [debug] - 140559740571488 [DB] Analyzing track_artist_link_artist_track_idx: done!
[2025-Feb-14 11:29:02.344] 1 - [debug] - 140559740571488 [DB] Analyzing track_artist_link_artist_type_idx
[2025-Feb-14 11:29:02.347] 1 - [debug] - 140559740571488 [DB] Analyzing track_artist_link_artist_type_idx: done!
[2025-Feb-14 11:29:02.347] 1 - [debug] - 140559740571488 [DB] Analyzing track_artist_link_track_artist_idx
[2025-Feb-14 11:29:02.349] 1 - [debug] - 140559740571488 [DB] Analyzing track_artist_link_track_artist_idx: done!
[2025-Feb-14 11:29:02.349] 1 - [debug] - 140559740571488 [DB] Analyzing track_artist_link_track_type_idx
[2025-Feb-14 11:29:02.352] 1 - [debug] - 140559740571488 [DB] Analyzing track_artist_link_track_type_idx: done!
[2025-Feb-14 11:29:02.352] 1 - [debug] - 140559740571488 [DB] Analyzing track_artist_link_type_track_artist_idx
[2025-Feb-14 11:29:02.355] 1 - [debug] - 140559740571488 [DB] Analyzing track_artist_link_type_track_artist_idx: done!
[2025-Feb-14 11:29:02.355] 1 - [debug] - 140559740571488 [DB] Analyzing track_features_track_idx
[2025-Feb-14 11:29:02.355] 1 - [debug] - 140559740571488 [DB] Analyzing track_features_track_idx: done!
[2025-Feb-14 11:29:02.355] 1 - [debug] - 140559740571488 [DB] Analyzing track_lyrics_id_idx
[2025-Feb-14 11:29:02.355] 1 - [debug] - 140559740571488 [DB] Analyzing track_lyrics_id_idx: done!
[2025-Feb-14 11:29:02.355] 1 - [debug] - 140559740571488 [DB] Analyzing track_lyrics_absolute_file_path_idx
[2025-Feb-14 11:29:02.355] 1 - [debug] - 140559740571488 [DB] Analyzing track_lyrics_absolute_file_path_idx: done!
[2025-Feb-14 11:29:02.355] 1 - [debug] - 140559740571488 [DB] Analyzing track_lyrics_track_idx
[2025-Feb-14 11:29:02.356] 1 - [debug] - 140559740571488 [DB] Analyzing track_lyrics_track_idx: done!
[2025-Feb-14 11:29:02.356] 1 - [debug] - 140559740571488 [DB] Analyzing track_bookmark_user_idx
[2025-Feb-14 11:29:02.356] 1 - [debug] - 140559740571488 [DB] Analyzing track_bookmark_user_idx: done!
[2025-Feb-14 11:29:02.356] 1 - [debug] - 140559740571488 [DB] Analyzing track_bookmark_user_track_idx
[2025-Feb-14 11:29:02.356] 1 - [debug] - 140559740571488 [DB] Analyzing track_bookmark_user_track_idx: done!
[2025-Feb-14 11:29:02.356] 1 - [debug] - 140559740571488 [DB] Analyzing starred_artist_user_backend_idx
[2025-Feb-14 11:29:02.356] 1 - [debug] - 140559740571488 [DB] Analyzing starred_artist_user_backend_idx: done!
[2025-Feb-14 11:29:02.356] 1 - [debug] - 140559740571488 [DB] Analyzing starred_artist_artist_user_backend_idx
[2025-Feb-14 11:29:02.356] 1 - [debug] - 140559740571488 [DB] Analyzing starred_artist_artist_user_backend_idx: done!
[2025-Feb-14 11:29:02.356] 1 - [debug] - 140559740571488 [DB] Analyzing starred_release_user_backend_idx
[2025-Feb-14 11:29:02.356] 1 - [debug] - 140559740571488 [DB] Analyzing starred_release_user_backend_idx: done!
[2025-Feb-14 11:29:02.356] 1 - [debug] - 140559740571488 [DB] Analyzing starred_release_release_user_backend_idx
[2025-Feb-14 11:29:02.356] 1 - [debug] - 140559740571488 [DB] Analyzing starred_release_release_user_backend_idx: done!
[2025-Feb-14 11:29:02.356] 1 - [debug] - 140559740571488 [DB] Analyzing starred_track_user_backend_idx
[2025-Feb-14 11:29:02.356] 1 - [debug] - 140559740571488 [DB] Analyzing starred_track_user_backend_idx: done!
[2025-Feb-14 11:29:02.356] 1 - [debug] - 140559740571488 [DB] Analyzing starred_track_track_user_backend_idx
[2025-Feb-14 11:29:02.356] 1 - [debug] - 140559740571488 [DB] Analyzing starred_track_track_user_backend_idx: done!
[2025-Feb-14 11:29:02.356] 1 - [info] - 140559740571488 [DB] Analyze complete!
[2025-Feb-14 11:29:02.357] 1 - [info] - 140559740571488 [COVER] JPEG export quality = 75
[2025-Feb-14 11:29:02.357] 1 - [info] - 140559740571488 [COVER] Default release cover path = "/usr/share/lms/approot//images/unknown-cover.svg"
[2025-Feb-14 11:29:02.357] 1 - [info] - 140559740571488 [COVER] Max cache size = 30000000
[2025-Feb-14 11:29:02.357] 1 - [debug] - 140559740571488 [DB UPDATER] Scanner settings updated
[2025-Feb-14 11:29:02.358] 1 - [debug] - 140559740571488 [DB UPDATER] Using scan settings version 0
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [METADATA] Using TagLib parser with read style = average
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [UTILS] Starting IO context with 4 threads...
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".aac" for Audio scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".alac" for Audio scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".aif" for Audio scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".aiff" for Audio scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".ape" for Audio scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".dsf" for Audio scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".flac" for Audio scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".m4a" for Audio scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".m4b" for Audio scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".mp3" for Audio scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".mpc" for Audio scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".oga" for Audio scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".ogg" for Audio scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".opus" for Audio scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".shn" for Audio scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".wav" for Audio scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".wma" for Audio scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".wv" for Audio scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".jpg" for Image scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".jpeg" for Image scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".png" for Image scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".bmp" for Image scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".lrc" for Lyrics scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".txt" for Lyrics scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".m3u" for PlayList scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Registered extension ".m3u8" for PlayList scanner
[2025-Feb-14 11:29:02.358] 1 - [info] - 140559740571488 [DB UPDATER] Using 4 thread(s) for scanning file metadata
[2025-Feb-14 11:29:02.359] 1 - [info] - 140559740571488 [SCROBBLING] Starting service...
[2025-Feb-14 11:29:02.359] 1 - [debug] - 140559730031416 [DB UPDATER] Scheduling next scan
[2025-Feb-14 11:29:02.359] 1 - [info] - 140559740571488 [FEEDBACK] [listenbrainz] Starting Feedbacks synchronizer, maxSyncFeedbackCount = 1000, _syncFeedbacksPeriod = 1 hours
[2025-Feb-14 11:29:02.359] 1 - [debug] - 140559740571488 [FEEDBACK] [listenbrainz] Scheduled sync in 30 seconds...
[2025-Feb-14 11:29:02.359] 1 - [info] - 140559740571488 [FEEDBACK] [listenbrainz] Starting ListenBrainz feedback backend... API endpoint = 'https://api.listenbrainz.org'
[2025-Feb-14 11:29:02.359] 1 - [info] - 140559740571488 [SCROBBLING] Service started!
[2025-Feb-14 11:29:02.359] 1 - [info] - 140559740571488 [SCROBBLING] Starting service...
[2025-Feb-14 11:29:02.359] 1 - [info] - 140559740571488 [SCROBBLING] [listenbrainz] Starting Listens synchronizer, maxSyncListenCount = 1000, _syncListensPeriod = 1 hours
[2025-Feb-14 11:29:02.359] 1 - [debug] - 140559740571488 [SCROBBLING] [listenbrainz] Scheduled sync in 30 seconds...
[2025-Feb-14 11:29:02.359] 1 - [info] - 140559740571488 [SCROBBLING] [listenbrainz] Starting ListenBrainz backend... API endpoint = 'https://api.listenbrainz.org'
[2025-Feb-14 11:29:02.359] 1 - [info] - 140559740571488 [SCROBBLING] Service started!
[2025-Feb-14 11:29:02.359] 1 - [info] - 140559740571488 [MAIN] Stopping init web server...
[2025-Feb-14 11:29:02.360] 1 - [info] "WebController: shutdown: stopping 0 sessions."
[2025-Feb-14 11:29:02.360] 1 - [info] "WServer/wthttp: Shutdown: stopping web server."
[2025-Feb-14 11:29:02.360] 1 - [info] - 140559740571488 [MAIN] Starting init web server...
[2025-Feb-14 11:29:02.360] 1 - [info] "WServer/wthttp: initializing built-in wthttpd"
[2025-Feb-14 11:29:02.361] 1 - [info] - 140559730031416 [DB UPDATER] Scheduling next scan at Sat Feb 15 07:00:00 2025
[2025-Feb-14 11:29:02.361] 1 - [info] "wthttp: started server: http://0.0.0.0:5082"
[2025-Feb-14 11:29:02.361] 1 - [info] - 140559740571488 [MAIN] Now running...
[2025-Feb-14 11:29:05.906] 1 - [error] "wthttp/async: remote_endpoint() threw: remote_endpoint: Socket not connected [system:107 at /usr/include/boost/asio/detail/reactive_socket_service.hpp:218 in function 'remote_endpoint']"
[2025-Feb-14 11:29:10.910] 1 - [error] "wthttp/async: remote_endpoint() threw: remote_endpoint: Socket not connected [system:107 at /usr/include/boost/asio/detail/reactive_socket_service.hpp:218 in function 'remote_endpoint']"
[2025-Feb-14 11:29:15.914] 1 - [error] "wthttp/async: remote_endpoint() threw: remote_endpoint: Socket not connected [system:107 at /usr/include/boost/asio/detail/reactive_socket_service.hpp:218 in function 'remote_endpoint']"
[2025-Feb-14 11:29:20.917] 1 - [error] "wthttp/async: remote_endpoint() threw: remote_endpoint: Socket not connected [system:107 at /usr/include/boost/asio/detail/reactive_socket_service.hpp:218 in function 'remote_endpoint']"
[2025-Feb-14 11:29:25.921] 1 - [error] "wthttp/async: remote_endpoint() threw: remote_endpoint: Socket not connected [system:107 at /usr/include/boost/asio/detail/reactive_socket_service.hpp:218 in function 'remote_endpoint']"
[2025-Feb-14 11:29:30.923] 1 - [error] "wthttp/async: remote_endpoint() threw: remote_endpoint: Socket not connected [system:107 at /usr/include/boost/asio/detail/reactive_socket_service.hpp:218 in function 'remote_endpoint']"
[2025-Feb-14 11:29:32.381] 1 - [debug] - 140559738788664 [FEEDBACK] [listenbrainz] Starting sync!
[2025-Feb-14 11:29:32.381] 1 - [debug] - 140559738637112 [SCROBBLING] [listenbrainz] Starting sync!
[2025-Feb-14 11:29:32.383] 1 - [debug] - 140559738788664 [FEEDBACK] [listenbrainz] Queing 0 pending 'love' feedbacks
[2025-Feb-14 11:29:32.383] 1 - [debug] - 140559738788664 [FEEDBACK] [listenbrainz] Queing 0 pending 'erase' feedbacks
[2025-Feb-14 11:29:32.383] 1 - [debug] - 140559738788664 [FEEDBACK] [listenbrainz] Scheduled sync in 3600 seconds...
[2025-Feb-14 11:29:32.390] 1 - [debug] - 140559738637112 [SCROBBLING] [listenbrainz] Queing 0 pending listen
[2025-Feb-14 11:29:32.390] 1 - [debug] - 140559738637112 [SCROBBLING] [listenbrainz] Scheduled sync in 3600 seconds...
[2025-Feb-14 11:29:35.928] 1 - [error] "wthttp/async: remote_endpoint() threw: remote_endpoint: Socket not connected [system:107 at /usr/include/boost/asio/detail/reactive_socket_service.hpp:218 in function 'remote_endpoint']"
[2025-Feb-14 11:29:40.943] 1 - [error] "wthttp/async: remote_endpoint() threw: remote_endpoint: Socket not connected [system:107 at /usr/include/boost/asio/detail/reactive_socket_service.hpp:218 in function 'remote_endpoint']"
[2025-Feb-14 11:29:45.951] 1 - [error] "wthttp/async: remote_endpoint() threw: remote_endpoint: Socket not connected [system:107 at /usr/include/boost/asio/detail/reactive_socket_service.hpp:218 in function 'remote_endpoint']"
[2025-Feb-14 11:29:50.954] 1 - [error] "wthttp/async: remote_endpoint() threw: remote_endpoint: Socket not connected [system:107 at /usr/include/boost/asio/detail/reactive_socket_service.hpp:218 in function 'remote_endpoint']"
[2025-Feb-14 11:29:55.965] 1 - [error] "wthttp/async: remote_endpoint() threw: remote_endpoint: Socket not connected [system:107 at /usr/include/boost/asio/detail/reactive_socket_service.hpp:218 in function 'remote_endpoint']"

Feb 14 '25 10:02 ajpanton

Thanks! Btw, what is your host OS?

Feb 14 '25 13:02 epoupon

Alpine Linux as VM in Proxmox.

Feb 14 '25 15:02 ajpanton

Ok, can you please try the image epoupon/lms:7220f8f1? You still need to enable debug log levels: it contains internal debug logs from the embedded web server, I hope it will help to find the culprit! Thanks!

Feb 16 '25 13:02 epoupon

Here's the beginning, already containing some of the 5-second error loop, but I cut it after the listenbrainz part ends:

Logs when starting the container

- 140480545251120 [MAIN] locale set to 'C.UTF-8;C;C;C;C;C'
ARG = /usr/bin/lms
ARG = --config=/var/lms/wt_config.xml
ARG = --docroot=/usr/share/lms/docroot/;/resources,/css,/images,/js,/favicon.ico
ARG = --approot=/usr/share/lms/approot
ARG = --deploy-path=/
ARG = --resources-dir=/usr/share/Wt/resources
ARG = --http-port=5082
ARG = --http-address=0.0.0.0
ARG = --threads=8
[2025-Feb-16 23:35:57.478] 1 - [info] "config: reading Wt config file: /var/lms/wt_config.xml (location = '/usr/bin/lms')"
[2025-Feb-16 23:35:57.479] 1 - [info] - 140480545251120 [MAIN] Starting init web server...
[2025-Feb-16 23:35:57.479] 1 - [info] "WServer/wthttp: initializing built-in wthttpd"
[2025-Feb-16 23:35:57.483] 1 - [info] "wthttp: started server: http://0.0.0.0:5082"
[2025-Feb-16 23:35:57.483] 1 - [info] - 140480545251120 [UTILS] Starting IO context with 8 threads...
[2025-Feb-16 23:35:57.484] 1 - [info] - 140480545251120 [DB] Creating connection pool on file "/var/lms/lms.db"
[2025-Feb-16 23:35:57.484] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings...
[2025-Feb-16 23:35:57.486] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings done!
[2025-Feb-16 23:35:57.486] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings...
[2025-Feb-16 23:35:57.487] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings done!
[2025-Feb-16 23:35:57.487] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings...
[2025-Feb-16 23:35:57.488] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings done!
[2025-Feb-16 23:35:57.489] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings...
[2025-Feb-16 23:35:57.489] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings done!
[2025-Feb-16 23:35:57.490] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings...
[2025-Feb-16 23:35:57.490] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings done!
[2025-Feb-16 23:35:57.491] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings...
[2025-Feb-16 23:35:57.491] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings done!
[2025-Feb-16 23:35:57.492] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings...
[2025-Feb-16 23:35:57.492] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings done!
[2025-Feb-16 23:35:57.493] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings...
[2025-Feb-16 23:35:57.493] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings done!
[2025-Feb-16 23:35:57.494] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings...
[2025-Feb-16 23:35:57.494] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings done!
[2025-Feb-16 23:35:57.494] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings...
[2025-Feb-16 23:35:57.496] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings done!
[2025-Feb-16 23:35:57.496] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings...
[2025-Feb-16 23:35:57.497] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings done!
[2025-Feb-16 23:35:57.497] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings...
[2025-Feb-16 23:35:57.498] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings done!
[2025-Feb-16 23:35:57.498] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings...
[2025-Feb-16 23:35:57.499] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings done!
[2025-Feb-16 23:35:57.499] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings...
[2025-Feb-16 23:35:57.500] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings done!
[2025-Feb-16 23:35:57.500] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings...
[2025-Feb-16 23:35:57.501] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings done!
[2025-Feb-16 23:35:57.501] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings...
[2025-Feb-16 23:35:57.502] 1 - [debug] - 140480545251120 [DB] Setting per-connection settings done!
[2025-Feb-16 23:35:57.502] 1 - [info] - 140480545251120 [DB] Preparing tables...
[2025-Feb-16 23:35:57.504] 1 - [debug] - 140480545251120 [DB] Cannot create tables: Sqlite3: create table "rated_track" (
  "id" integer primary key autoincrement,
  "version" integer not null,
  "rating" integer not null,
  "last_updated" text,
  "track_id" bigint,
  "user_id" bigint,
  constraint "fk_rated_track_track" foreign key ("track_id") references "track" ("id") on delete cascade deferrable initially deferred,
  constraint "fk_rated_track_user" foreign key ("user_id") references "user" ("id") on delete cascade deferrable initially deferred
): table "rated_track" already exists
[2025-Feb-16 23:35:57.504] 1 - [info] - 140480545251120 [DB] Database version = 80, LMS binary version = 80
[2025-Feb-16 23:35:57.505] 1 - [info] - 140480545251120 [DB] Creating indexes... This may take a while...
[2025-Feb-16 23:35:57.505] 1 - [info] - 140480545251120 [DB] Indexes created!
[2025-Feb-16 23:35:57.505] 1 - [info] - 140480545251120 [DB] page stats: page_count = 7931, freelist_count = 6
[2025-Feb-16 23:35:57.505] 1 - [info] - 140480545251120 [DB] Performing database analyze... This may take a while...
[2025-Feb-16 23:35:57.506] 1 - [debug] - 140480545251120 [DB] Analyzing rated_track
[2025-Feb-16 23:35:57.508] 1 - [debug] - 140480545251120 [DB] Analyzing rated_track: done!
[2025-Feb-16 23:35:57.508] 1 - [debug] - 140480545251120 [DB] Analyzing sqlite_sequence
[2025-Feb-16 23:35:57.508] 1 - [debug] - 140480545251120 [DB] Analyzing sqlite_sequence: done!
[2025-Feb-16 23:35:57.508] 1 - [debug] - 140480545251120 [DB] Analyzing cluster_type
[2025-Feb-16 23:35:57.508] 1 - [debug] - 140480545251120 [DB] Analyzing cluster_type: done!
[2025-Feb-16 23:35:57.508] 1 - [debug] - 140480545251120 [DB] Analyzing rated_artist
[2025-Feb-16 23:35:57.508] 1 - [debug] - 140480545251120 [DB] Analyzing rated_artist: done!
[2025-Feb-16 23:35:57.508] 1 - [debug] - 140480545251120 [DB] Analyzing release_type
[2025-Feb-16 23:35:57.509] 1 - [debug] - 140480545251120 [DB] Analyzing release_type: done!
[2025-Feb-16 23:35:57.509] 1 - [debug] - 140480545251120 [DB] Analyzing track_lyrics
[2025-Feb-16 23:35:57.509] 1 - [debug] - 140480545251120 [DB] Analyzing track_lyrics: done!
[2025-Feb-16 23:35:57.509] 1 - [debug] - 140480545251120 [DB] Analyzing version_info
[2025-Feb-16 23:35:57.509] 1 - [debug] - 140480545251120 [DB] Analyzing version_info: done!
[2025-Feb-16 23:35:57.509] 1 - [debug] - 140480545251120 [DB] Analyzing media_library
[2025-Feb-16 23:35:57.509] 1 - [debug] - 140480545251120 [DB] Analyzing media_library: done!
[2025-Feb-16 23:35:57.509] 1 - [debug] - 140480545251120 [DB] Analyzing playlist_file
[2025-Feb-16 23:35:57.510] 1 - [debug] - 140480545251120 [DB] Analyzing playlist_file: done!
[2025-Feb-16 23:35:57.510] 1 - [debug] - 140480545251120 [DB] Analyzing rated_release
[2025-Feb-16 23:35:57.510] 1 - [debug] - 140480545251120 [DB] Analyzing rated_release: done!
[2025-Feb-16 23:35:57.510] 1 - [debug] - 140480545251120 [DB] Analyzing scan_settings
[2025-Feb-16 23:35:57.510] 1 - [debug] - 140480545251120 [DB] Analyzing scan_settings: done!
[2025-Feb-16 23:35:57.510] 1 - [debug] - 140480545251120 [DB] Analyzing starred_track
[2025-Feb-16 23:35:57.510] 1 - [debug] - 140480545251120 [DB] Analyzing starred_track: done!
[2025-Feb-16 23:35:57.510] 1 - [debug] - 140480545251120 [DB] Analyzing starred_artist
[2025-Feb-16 23:35:57.510] 1 - [debug] - 140480545251120 [DB] Analyzing starred_artist: done!
[2025-Feb-16 23:35:57.510] 1 - [debug] - 140480545251120 [DB] Analyzing track_bookmark
[2025-Feb-16 23:35:57.510] 1 - [debug] - 140480545251120 [DB] Analyzing track_bookmark: done!
[2025-Feb-16 23:35:57.510] 1 - [debug] - 140480545251120 [DB] Analyzing track_features
[2025-Feb-16 23:35:57.510] 1 - [debug] - 140480545251120 [DB] Analyzing track_features: done!
[2025-Feb-16 23:35:57.510] 1 - [debug] - 140480545251120 [DB] Analyzing starred_release
[2025-Feb-16 23:35:57.511] 1 - [debug] - 140480545251120 [DB] Analyzing starred_release: done!
[2025-Feb-16 23:35:57.511] 1 - [debug] - 140480545251120 [DB] Analyzing tracklist_entry
[2025-Feb-16 23:35:57.511] 1 - [debug] - 140480545251120 [DB] Analyzing tracklist_entry: done!
[2025-Feb-16 23:35:57.511] 1 - [debug] - 140480545251120 [DB] Analyzing track_artist_link
[2025-Feb-16 23:35:57.529] 1 - [debug] - 140480545251120 [DB] Analyzing track_artist_link: done!
[2025-Feb-16 23:35:57.530] 1 - [debug] - 140480545251120 [DB] Analyzing user
[2025-Feb-16 23:35:57.530] 1 - [debug] - 140480545251120 [DB] Analyzing user: done!
[2025-Feb-16 23:35:57.530] 1 - [debug] - 140480545251120 [DB] Analyzing image
[2025-Feb-16 23:35:57.532] 1 - [debug] - 140480545251120 [DB] Analyzing image: done!
[2025-Feb-16 23:35:57.532] 1 - [debug] - 140480545251120 [DB] Analyzing label
[2025-Feb-16 23:35:57.532] 1 - [debug] - 140480545251120 [DB] Analyzing label: done!
[2025-Feb-16 23:35:57.532] 1 - [debug] - 140480545251120 [DB] Analyzing track
[2025-Feb-16 23:35:57.575] 1 - [debug] - 140480545251120 [DB] Analyzing track: done!
[2025-Feb-16 23:35:57.575] 1 - [debug] - 140480545251120 [DB] Analyzing artist
[2025-Feb-16 23:35:57.577] 1 - [debug] - 140480545251120 [DB] Analyzing artist: done!
[2025-Feb-16 23:35:57.577] 1 - [debug] - 140480545251120 [DB] Analyzing listen
[2025-Feb-16 23:35:57.616] 1 - [debug] - 140480545251120 [DB] Analyzing listen: done!
[2025-Feb-16 23:35:57.616] 1 - [debug] - 140480545251120 [DB] Analyzing cluster
[2025-Feb-16 23:35:57.616] 1 - [debug] - 140480545251120 [DB] Analyzing cluster: done!
[2025-Feb-16 23:35:57.616] 1 - [debug] - 140480545251120 [DB] Analyzing release
[2025-Feb-16 23:35:57.617] 1 - [debug] - 140480545251120 [DB] Analyzing release: done!
[2025-Feb-16 23:35:57.617] 1 - [debug] - 140480545251120 [DB] Analyzing ui_state
[2025-Feb-16 23:35:57.617] 1 - [debug] - 140480545251120 [DB] Analyzing ui_state: done!
[2025-Feb-16 23:35:57.617] 1 - [debug] - 140480545251120 [DB] Analyzing auth_token
[2025-Feb-16 23:35:57.617] 1 - [debug] - 140480545251120 [DB] Analyzing auth_token: done!
[2025-Feb-16 23:35:57.617] 1 - [debug] - 140480545251120 [DB] Analyzing directory
[2025-Feb-16 23:35:57.618] 1 - [debug] - 140480545251120 [DB] Analyzing directory: done!
[2025-Feb-16 23:35:57.618] 1 - [debug] - 140480545251120 [DB] Analyzing playqueue
[2025-Feb-16 23:35:57.618] 1 - [debug] - 140480545251120 [DB] Analyzing playqueue: done!
[2025-Feb-16 23:35:57.618] 1 - [debug] - 140480545251120 [DB] Analyzing tracklist
[2025-Feb-16 23:35:57.618] 1 - [debug] - 140480545251120 [DB] Analyzing tracklist: done!
[2025-Feb-16 23:35:57.619] 1 - [debug] - 140480545251120 [DB] Analyzing release_release_type
[2025-Feb-16 23:35:57.619] 1 - [debug] - 140480545251120 [DB] Analyzing release_release_type: done!
[2025-Feb-16 23:35:57.619] 1 - [debug] - 140480545251120 [DB] Analyzing sqlite_autoindex_release_release_type_1
[2025-Feb-16 23:35:57.619] 1 - [debug] - 140480545251120 [DB] Analyzing sqlite_autoindex_release_release_type_1: done!
[2025-Feb-16 23:35:57.619] 1 - [debug] - 140480545251120 [DB] Analyzing release_label
[2025-Feb-16 23:35:57.619] 1 - [debug] - 140480545251120 [DB] Analyzing release_label: done!
[2025-Feb-16 23:35:57.619] 1 - [debug] - 140480545251120 [DB] Analyzing sqlite_autoindex_release_label_1
[2025-Feb-16 23:35:57.619] 1 - [debug] - 140480545251120 [DB] Analyzing sqlite_autoindex_release_label_1: done!
[2025-Feb-16 23:35:57.619] 1 - [debug] - 140480545251120 [DB] Analyzing track_cluster
[2025-Feb-16 23:35:57.623] 1 - [debug] - 140480545251120 [DB] Analyzing track_cluster: done!
[2025-Feb-16 23:35:57.623] 1 - [debug] - 140480545251120 [DB] Analyzing sqlite_autoindex_track_cluster_1
[2025-Feb-16 23:35:57.624] 1 - [debug] - 140480545251120 [DB] Analyzing sqlite_autoindex_track_cluster_1: done!
[2025-Feb-16 23:35:57.624] 1 - [debug] - 140480545251120 [DB] Analyzing user_starred_artists
[2025-Feb-16 23:35:57.624] 1 - [debug] - 140480545251120 [DB] Analyzing user_starred_artists: done!
[2025-Feb-16 23:35:57.624] 1 - [debug] - 140480545251120 [DB] Analyzing sqlite_autoindex_user_starred_artists_1
[2025-Feb-16 23:35:57.624] 1 - [debug] - 140480545251120 [DB] Analyzing sqlite_autoindex_user_starred_artists_1: done!
[2025-Feb-16 23:35:57.624] 1 - [debug] - 140480545251120 [DB] Analyzing playqueue_track
[2025-Feb-16 23:35:57.624] 1 - [debug] - 140480545251120 [DB] Analyzing playqueue_track: done!
[2025-Feb-16 23:35:57.624] 1 - [debug] - 140480545251120 [DB] Analyzing sqlite_autoindex_playqueue_track_1
[2025-Feb-16 23:35:57.625] 1 - [debug] - 140480545251120 [DB] Analyzing sqlite_autoindex_playqueue_track_1: done!
[2025-Feb-16 23:35:57.625] 1 - [debug] - 140480545251120 [DB] Analyzing sqlite_stat1
[2025-Feb-16 23:35:57.625] 1 - [debug] - 140480545251120 [DB] Analyzing sqlite_stat1: done!
[2025-Feb-16 23:35:57.625] 1 - [debug] - 140480545251120 [DB] Analyzing release_release_type_release_type
[2025-Feb-16 23:35:57.625] 1 - [debug] - 140480545251120 [DB] Analyzing release_release_type_release_type: done!
[2025-Feb-16 23:35:57.625] 1 - [debug] - 140480545251120 [DB] Analyzing release_release_type_release
[2025-Feb-16 23:35:57.625] 1 - [debug] - 140480545251120 [DB] Analyzing release_release_type_release: done!
[2025-Feb-16 23:35:57.625] 1 - [debug] - 140480545251120 [DB] Analyzing release_label_label
[2025-Feb-16 23:35:57.625] 1 - [debug] - 140480545251120 [DB] Analyzing release_label_label: done!
[2025-Feb-16 23:35:57.625] 1 - [debug] - 140480545251120 [DB] Analyzing release_label_release
[2025-Feb-16 23:35:57.625] 1 - [debug] - 140480545251120 [DB] Analyzing release_label_release: done!
[2025-Feb-16 23:35:57.625] 1 - [debug] - 140480545251120 [DB] Analyzing track_cluster_track
[2025-Feb-16 23:35:57.627] 1 - [debug] - 140480545251120 [DB] Analyzing track_cluster_track: done!
[2025-Feb-16 23:35:57.627] 1 - [debug] - 140480545251120 [DB] Analyzing track_cluster_cluster
[2025-Feb-16 23:35:57.628] 1 - [debug] - 140480545251120 [DB] Analyzing track_cluster_cluster: done!
[2025-Feb-16 23:35:57.628] 1 - [debug] - 140480545251120 [DB] Analyzing user_starred_artists_artist
[2025-Feb-16 23:35:57.628] 1 - [debug] - 140480545251120 [DB] Analyzing user_starred_artists_artist: done!
[2025-Feb-16 23:35:57.628] 1 - [debug] - 140480545251120 [DB] Analyzing user_starred_artists_starred_artist
[2025-Feb-16 23:35:57.628] 1 - [debug] - 140480545251120 [DB] Analyzing user_starred_artists_starred_artist: done!
[2025-Feb-16 23:35:57.628] 1 - [debug] - 140480545251120 [DB] Analyzing playqueue_track_playqueue
[2025-Feb-16 23:35:57.628] 1 - [debug] - 140480545251120 [DB] Analyzing playqueue_track_playqueue: done!
[2025-Feb-16 23:35:57.628] 1 - [debug] - 140480545251120 [DB] Analyzing playqueue_track_track
[2025-Feb-16 23:35:57.628] 1 - [debug] - 140480545251120 [DB] Analyzing playqueue_track_track: done!
[2025-Feb-16 23:35:57.628] 1 - [debug] - 140480545251120 [DB] Analyzing artist_id_idx
[2025-Feb-16 23:35:57.629] 1 - [debug] - 140480545251120 [DB] Analyzing artist_id_idx: done!
[2025-Feb-16 23:35:57.629] 1 - [debug] - 140480545251120 [DB] Analyzing artist_image_idx
[2025-Feb-16 23:35:57.629] 1 - [debug] - 140480545251120 [DB] Analyzing artist_image_idx: done!
[2025-Feb-16 23:35:57.629] 1 - [debug] - 140480545251120 [DB] Analyzing artist_name_idx
[2025-Feb-16 23:35:57.630] 1 - [debug] - 140480545251120 [DB] Analyzing artist_name_idx: done!
[2025-Feb-16 23:35:57.630] 1 - [debug] - 140480545251120 [DB] Analyzing artist_sort_name_nocase_idx
[2025-Feb-16 23:35:57.631] 1 - [debug] - 140480545251120 [DB] Analyzing artist_sort_name_nocase_idx: done!
[2025-Feb-16 23:35:57.631] 1 - [debug] - 140480545251120 [DB] Analyzing artist_mbid_idx
[2025-Feb-16 23:35:57.631] 1 - [debug] - 140480545251120 [DB] Analyzing artist_mbid_idx: done!
[2025-Feb-16 23:35:57.631] 1 - [debug] - 140480545251120 [DB] Analyzing auth_token_user_domain_idx
[2025-Feb-16 23:35:57.631] 1 - [debug] - 140480545251120 [DB] Analyzing auth_token_user_domain_idx: done!
[2025-Feb-16 23:35:57.631] 1 - [debug] - 140480545251120 [DB] Analyzing auth_token_domain_expiry_idx
[2025-Feb-16 23:35:57.631] 1 - [debug] - 140480545251120 [DB] Analyzing auth_token_domain_expiry_idx: done!
[2025-Feb-16 23:35:57.631] 1 - [debug] - 140480545251120 [DB] Analyzing auth_token_domain_value_idx
[2025-Feb-16 23:35:57.632] 1 - [debug] - 140480545251120 [DB] Analyzing auth_token_domain_value_idx: done!
[2025-Feb-16 23:35:57.632] 1 - [debug] - 140480545251120 [DB] Analyzing cluster_cluster_type_idx
[2025-Feb-16 23:35:57.632] 1 - [debug] - 140480545251120 [DB] Analyzing cluster_cluster_type_idx: done!
[2025-Feb-16 23:35:57.632] 1 - [debug] - 140480545251120 [DB] Analyzing cluster_type_name_idx
[2025-Feb-16 23:35:57.632] 1 - [debug] - 140480545251120 [DB] Analyzing cluster_type_name_idx: done!
[2025-Feb-16 23:35:57.632] 1 - [debug] - 140480545251120 [DB] Analyzing directory_id_idx
[2025-Feb-16 23:35:57.632] 1 - [debug] - 140480545251120 [DB] Analyzing directory_id_idx: done!
[2025-Feb-16 23:35:57.632] 1 - [debug] - 140480545251120 [DB] Analyzing directory_parent_directory_idx
[2025-Feb-16 23:35:57.633] 1 - [debug] - 140480545251120 [DB] Analyzing directory_parent_directory_idx: done!
[2025-Feb-16 23:35:57.633] 1 - [debug] - 140480545251120 [DB] Analyzing directory_path_idx
[2025-Feb-16 23:35:57.633] 1 - [debug] - 140480545251120 [DB] Analyzing directory_path_idx: done!
[2025-Feb-16 23:35:57.633] 1 - [debug] - 140480545251120 [DB] Analyzing directory_media_library_idx
[2025-Feb-16 23:35:57.633] 1 - [debug] - 140480545251120 [DB] Analyzing directory_media_library_idx: done!
[2025-Feb-16 23:35:57.633] 1 - [debug] - 140480545251120 [DB] Analyzing directory_name_idx
[2025-Feb-16 23:35:57.634] 1 - [debug] - 140480545251120 [DB] Analyzing directory_name_idx: done!
[2025-Feb-16 23:35:57.634] 1 - [debug] - 140480545251120 [DB] Analyzing image_directory_stem_idx
[2025-Feb-16 23:35:57.634] 1 - [debug] - 140480545251120 [DB] Analyzing image_directory_stem_idx: done!
[2025-Feb-16 23:35:57.634] 1 - [debug] - 140480545251120 [DB] Analyzing image_id_idx
[2025-Feb-16 23:35:57.635] 1 - [debug] - 140480545251120 [DB] Analyzing image_id_idx: done!
[2025-Feb-16 23:35:57.635] 1 - [debug] - 140480545251120 [DB] Analyzing image_path_idx
[2025-Feb-16 23:35:57.635] 1 - [debug] - 140480545251120 [DB] Analyzing image_path_idx: done!
[2025-Feb-16 23:35:57.635] 1 - [debug] - 140480545251120 [DB] Analyzing image_stem_idx
[2025-Feb-16 23:35:57.636] 1 - [debug] - 140480545251120 [DB] Analyzing image_stem_idx: done!
[2025-Feb-16 23:35:57.636] 1 - [debug] - 140480545251120 [DB] Analyzing label_name_idx
[2025-Feb-16 23:35:57.636] 1 - [debug] - 140480545251120 [DB] Analyzing label_name_idx: done!
[2025-Feb-16 23:35:57.636] 1 - [debug] - 140480545251120 [DB] Analyzing listen_backend_idx
[2025-Feb-16 23:35:57.639] 1 - [debug] - 140480545251120 [DB] Analyzing listen_backend_idx: done!
[2025-Feb-16 23:35:57.639] 1 - [debug] - 140480545251120 [DB] Analyzing listen_id_idx
[2025-Feb-16 23:35:57.643] 1 - [debug] - 140480545251120 [DB] Analyzing listen_id_idx: done!
[2025-Feb-16 23:35:57.643] 1 - [debug] - 140480545251120 [DB] Analyzing listen_user_backend_idx
[2025-Feb-16 23:35:57.648] 1 - [debug] - 140480545251120 [DB] Analyzing listen_user_backend_idx: done!
[2025-Feb-16 23:35:57.648] 1 - [debug] - 140480545251120 [DB] Analyzing listen_user_backend_date_time_idx
[2025-Feb-16 23:35:57.658] 1 - [debug] - 140480545251120 [DB] Analyzing listen_user_backend_date_time_idx: done!
[2025-Feb-16 23:35:57.658] 1 - [debug] - 140480545251120 [DB] Analyzing listen_track_user_backend_idx
[2025-Feb-16 23:35:57.665] 1 - [debug] - 140480545251120 [DB] Analyzing listen_track_user_backend_idx: done!
[2025-Feb-16 23:35:57.665] 1 - [debug] - 140480545251120 [DB] Analyzing listen_user_track_backend_date_time_idx
[2025-Feb-16 23:35:57.675] 1 - [debug] - 140480545251120 [DB] Analyzing listen_user_track_backend_date_time_idx: done!
[2025-Feb-16 23:35:57.675] 1 - [debug] - 140480545251120 [DB] Analyzing media_library_id_idx
[2025-Feb-16 23:35:57.675] 1 - [debug] - 140480545251120 [DB] Analyzing media_library_id_idx: done!
[2025-Feb-16 23:35:57.675] 1 - [debug] - 140480545251120 [DB] Analyzing playlist_file_id_idx
[2025-Feb-16 23:35:57.676] 1 - [debug] - 140480545251120 [DB] Analyzing playlist_file_id_idx: done!
[2025-Feb-16 23:35:57.676] 1 - [debug] - 140480545251120 [DB] Analyzing playlist_file_absolute_file_path_idx
[2025-Feb-16 23:35:57.676] 1 - [debug] - 140480545251120 [DB] Analyzing playlist_file_absolute_file_path_idx: done!
[2025-Feb-16 23:35:57.676] 1 - [debug] - 140480545251120 [DB] Analyzing rated_artist_user_artist_idx
[2025-Feb-16 23:35:57.676] 1 - [debug] - 140480545251120 [DB] Analyzing rated_artist_user_artist_idx: done!
[2025-Feb-16 23:35:57.676] 1 - [debug] - 140480545251120 [DB] Analyzing rated_release_user_release_idx
[2025-Feb-16 23:35:57.676] 1 - [debug] - 140480545251120 [DB] Analyzing rated_release_user_release_idx: done!
[2025-Feb-16 23:35:57.676] 1 - [debug] - 140480545251120 [DB] Analyzing rated_track_user_track_idx
[2025-Feb-16 23:35:57.677] 1 - [debug] - 140480545251120 [DB] Analyzing rated_track_user_track_idx: done!
[2025-Feb-16 23:35:57.677] 1 - [debug] - 140480545251120 [DB] Analyzing release_id_idx
[2025-Feb-16 23:35:57.678] 1 - [debug] - 140480545251120 [DB] Analyzing release_id_idx: done!
[2025-Feb-16 23:35:57.678] 1 - [debug] - 140480545251120 [DB] Analyzing release_image_idx
[2025-Feb-16 23:35:57.678] 1 - [debug] - 140480545251120 [DB] Analyzing release_image_idx: done!
[2025-Feb-16 23:35:57.678] 1 - [debug] - 140480545251120 [DB] Analyzing release_mbid_idx
[2025-Feb-16 23:35:57.678] 1 - [debug] - 140480545251120 [DB] Analyzing release_mbid_idx: done!
[2025-Feb-16 23:35:57.678] 1 - [debug] - 140480545251120 [DB] Analyzing release_name_idx
[2025-Feb-16 23:35:57.678] 1 - [debug] - 140480545251120 [DB] Analyzing release_name_idx: done!
[2025-Feb-16 23:35:57.679] 1 - [debug] - 140480545251120 [DB] Analyzing release_name_nocase_idx
[2025-Feb-16 23:35:57.679] 1 - [debug] - 140480545251120 [DB] Analyzing release_name_nocase_idx: done!
[2025-Feb-16 23:35:57.679] 1 - [debug] - 140480545251120 [DB] Analyzing release_type_name_idx
[2025-Feb-16 23:35:57.679] 1 - [debug] - 140480545251120 [DB] Analyzing release_type_name_idx: done!
[2025-Feb-16 23:35:57.679] 1 - [debug] - 140480545251120 [DB] Analyzing track_id_idx
[2025-Feb-16 23:35:57.680] 1 - [debug] - 140480545251120 [DB] Analyzing track_id_idx: done!
[2025-Feb-16 23:35:57.680] 1 - [debug] - 140480545251120 [DB] Analyzing track_absolute_path_idx
[2025-Feb-16 23:35:57.684] 1 - [debug] - 140480545251120 [DB] Analyzing track_absolute_path_idx: done!
[2025-Feb-16 23:35:57.684] 1 - [debug] - 140480545251120 [DB] Analyzing track_date_idx
[2025-Feb-16 23:35:57.685] 1 - [debug] - 140480545251120 [DB] Analyzing track_date_idx: done!
[2025-Feb-16 23:35:57.685] 1 - [debug] - 140480545251120 [DB] Analyzing track_directory_release_idx
[2025-Feb-16 23:35:57.687] 1 - [debug] - 140480545251120 [DB] Analyzing track_directory_release_idx: done!
[2025-Feb-16 23:35:57.687] 1 - [debug] - 140480545251120 [DB] Analyzing track_directory_file_stem_idx
[2025-Feb-16 23:35:57.690] 1 - [debug] - 140480545251120 [DB] Analyzing track_directory_file_stem_idx: done!
[2025-Feb-16 23:35:57.690] 1 - [debug] - 140480545251120 [DB] Analyzing track_file_added_idx
[2025-Feb-16 23:35:57.692] 1 - [debug] - 140480545251120 [DB] Analyzing track_file_added_idx: done!
[2025-Feb-16 23:35:57.692] 1 - [debug] - 140480545251120 [DB] Analyzing track_file_added_desc_idx
[2025-Feb-16 23:35:57.693] 1 - [debug] - 140480545251120 [DB] Analyzing track_file_added_desc_idx: done!
[2025-Feb-16 23:35:57.693] 1 - [debug] - 140480545251120 [DB] Analyzing track_file_last_write_idx
[2025-Feb-16 23:35:57.695] 1 - [debug] - 140480545251120 [DB] Analyzing track_file_last_write_idx: done!
[2025-Feb-16 23:35:57.695] 1 - [debug] - 140480545251120 [DB] Analyzing track_file_last_write_desc_idx
[2025-Feb-16 23:35:57.697] 1 - [debug] - 140480545251120 [DB] Analyzing track_file_last_write_desc_idx: done!
[2025-Feb-16 23:35:57.697] 1 - [debug] - 140480545251120 [DB] Analyzing track_file_name_idx
[2025-Feb-16 23:35:57.700] 1 - [debug] - 140480545251120 [DB] Analyzing track_file_name_idx: done!
[2025-Feb-16 23:35:57.700] 1 - [debug] - 140480545251120 [DB] Analyzing track_media_library_idx
[2025-Feb-16 23:35:57.701] 1 - [debug] - 140480545251120 [DB] Analyzing track_media_library_idx: done!
[2025-Feb-16 23:35:57.701] 1 - [debug] - 140480545251120 [DB] Analyzing track_media_library_release_idx
[2025-Feb-16 23:35:57.703] 1 - [debug] - 140480545251120 [DB] Analyzing track_media_library_release_idx: done!
[2025-Feb-16 23:35:57.703] 1 - [debug] - 140480545251120 [DB] Analyzing track_mbid_idx
[2025-Feb-16 23:35:57.704] 1 - [debug] - 140480545251120 [DB] Analyzing track_mbid_idx: done!
[2025-Feb-16 23:35:57.704] 1 - [debug] - 140480545251120 [DB] Analyzing track_name_idx
[2025-Feb-16 23:35:57.706] 1 - [debug] - 140480545251120 [DB] Analyzing track_name_idx: done!
[2025-Feb-16 23:35:57.706] 1 - [debug] - 140480545251120 [DB] Analyzing track_name_nocase_idx
[2025-Feb-16 23:35:57.709] 1 - [debug] - 140480545251120 [DB] Analyzing track_name_nocase_idx: done!
[2025-Feb-16 23:35:57.709] 1 - [debug] - 140480545251120 [DB] Analyzing track_original_date_idx
[2025-Feb-16 23:35:57.710] 1 - [debug] - 140480545251120 [DB] Analyzing track_original_date_idx: done!
[2025-Feb-16 23:35:57.710] 1 - [debug] - 140480545251120 [DB] Analyzing track_recording_mbid_idx
[2025-Feb-16 23:35:57.711] 1 - [debug] - 140480545251120 [DB] Analyzing track_recording_mbid_idx: done!
[2025-Feb-16 23:35:57.711] 1 - [debug] - 140480545251120 [DB] Analyzing track_release_idx
[2025-Feb-16 23:35:57.713] 1 - [debug] - 140480545251120 [DB] Analyzing track_release_idx: done!
[2025-Feb-16 23:35:57.713] 1 - [debug] - 140480545251120 [DB] Analyzing track_release_date_idx
[2025-Feb-16 23:35:57.714] 1 - [debug] - 140480545251120 [DB] Analyzing track_release_date_idx: done!
[2025-Feb-16 23:35:57.714] 1 - [debug] - 140480545251120 [DB] Analyzing track_release_date_desc_idx
[2025-Feb-16 23:35:57.716] 1 - [debug] - 140480545251120 [DB] Analyzing track_release_date_desc_idx: done!
[2025-Feb-16 23:35:57.716] 1 - [debug] - 140480545251120 [DB] Analyzing track_release_file_last_write_idx
[2025-Feb-16 23:35:57.719] 1 - [debug] - 140480545251120 [DB] Analyzing track_release_file_last_write_idx: done!
[2025-Feb-16 23:35:57.719] 1 - [debug] - 140480545251120 [DB] Analyzing track_release_file_last_write_desc_idx
[2025-Feb-16 23:35:57.722] 1 - [debug] - 140480545251120 [DB] Analyzing track_release_file_last_write_desc_idx: done!
[2025-Feb-16 23:35:57.722] 1 - [debug] - 140480545251120 [DB] Analyzing track_release_file_added_idx
[2025-Feb-16 23:35:57.724] 1 - [debug] - 140480545251120 [DB] Analyzing track_release_file_added_idx: done!
[2025-Feb-16 23:35:57.724] 1 - [debug] - 140480545251120 [DB] Analyzing track_release_file_added_desc_idx
[2025-Feb-16 23:35:57.726] 1 - [debug] - 140480545251120 [DB] Analyzing track_release_file_added_desc_idx: done!
[2025-Feb-16 23:35:57.726] 1 - [debug] - 140480545251120 [DB] Analyzing tracklist_name_idx
[2025-Feb-16 23:35:57.727] 1 - [debug] - 140480545251120 [DB] Analyzing tracklist_name_idx: done!
[2025-Feb-16 23:35:57.727] 1 - [debug] - 140480545251120 [DB] Analyzing tracklist_user_type_idx
[2025-Feb-16 23:35:57.727] 1 - [debug] - 140480545251120 [DB] Analyzing tracklist_user_type_idx: done!
[2025-Feb-16 23:35:57.727] 1 - [debug] - 140480545251120 [DB] Analyzing tracklist_last_modified_date_time_idx
[2025-Feb-16 23:35:57.727] 1 - [debug] - 140480545251120 [DB] Analyzing tracklist_last_modified_date_time_idx: done!
[2025-Feb-16 23:35:57.727] 1 - [debug] - 140480545251120 [DB] Analyzing tracklist_entry_idx
[2025-Feb-16 23:35:57.727] 1 - [debug] - 140480545251120 [DB] Analyzing tracklist_entry_idx: done!
[2025-Feb-16 23:35:57.727] 1 - [debug] - 140480545251120 [DB] Analyzing tracklist_entry_tracklist_track_idx
[2025-Feb-16 23:35:57.728] 1 - [debug] - 140480545251120 [DB] Analyzing tracklist_entry_tracklist_track_idx: done!
[2025-Feb-16 23:35:57.728] 1 - [debug] - 140480545251120 [DB] Analyzing track_artist_link_artist_idx
[2025-Feb-16 23:35:57.730] 1 - [debug] - 140480545251120 [DB] Analyzing track_artist_link_artist_idx: done!
[2025-Feb-16 23:35:57.730] 1 - [debug] - 140480545251120 [DB] Analyzing track_artist_link_artist_track_idx
[2025-Feb-16 23:35:57.733] 1 - [debug] - 140480545251120 [DB] Analyzing track_artist_link_artist_track_idx: done!
[2025-Feb-16 23:35:57.733] 1 - [debug] - 140480545251120 [DB] Analyzing track_artist_link_artist_type_idx
[2025-Feb-16 23:35:57.736] 1 - [debug] - 140480545251120 [DB] Analyzing track_artist_link_artist_type_idx: done!
[2025-Feb-16 23:35:57.736] 1 - [debug] - 140480545251120 [DB] Analyzing track_artist_link_track_artist_idx
[2025-Feb-16 23:35:57.739] 1 - [debug] - 140480545251120 [DB] Analyzing track_artist_link_track_artist_idx: done!
[2025-Feb-16 23:35:57.739] 1 - [debug] - 140480545251120 [DB] Analyzing track_artist_link_track_type_idx
[2025-Feb-16 23:35:57.742] 1 - [debug] - 140480545251120 [DB] Analyzing track_artist_link_track_type_idx: done!
[2025-Feb-16 23:35:57.742] 1 - [debug] - 140480545251120 [DB] Analyzing track_artist_link_type_track_artist_idx
[2025-Feb-16 23:35:57.746] 1 - [debug] - 140480545251120 [DB] Analyzing track_artist_link_type_track_artist_idx: done!
[2025-Feb-16 23:35:57.746] 1 - [debug] - 140480545251120 [DB] Analyzing track_features_track_idx
[2025-Feb-16 23:35:57.746] 1 - [debug] - 140480545251120 [DB] Analyzing track_features_track_idx: done!
[2025-Feb-16 23:35:57.746] 1 - [debug] - 140480545251120 [DB] Analyzing track_lyrics_id_idx
[2025-Feb-16 23:35:57.746] 1 - [debug] - 140480545251120 [DB] Analyzing track_lyrics_id_idx: done!
[2025-Feb-16 23:35:57.746] 1 - [debug] - 140480545251120 [DB] Analyzing track_lyrics_absolute_file_path_idx
[2025-Feb-16 23:35:57.746] 1 - [debug] - 140480545251120 [DB] Analyzing track_lyrics_absolute_file_path_idx: done!
[2025-Feb-16 23:35:57.746] 1 - [debug] - 140480545251120 [DB] Analyzing track_lyrics_track_idx
[2025-Feb-16 23:35:57.746] 1 - [debug] - 140480545251120 [DB] Analyzing track_lyrics_track_idx: done!
[2025-Feb-16 23:35:57.746] 1 - [debug] - 140480545251120 [DB] Analyzing track_bookmark_user_idx
[2025-Feb-16 23:35:57.747] 1 - [debug] - 140480545251120 [DB] Analyzing track_bookmark_user_idx: done!
[2025-Feb-16 23:35:57.747] 1 - [debug] - 140480545251120 [DB] Analyzing track_bookmark_user_track_idx
[2025-Feb-16 23:35:57.747] 1 - [debug] - 140480545251120 [DB] Analyzing track_bookmark_user_track_idx: done!
[2025-Feb-16 23:35:57.747] 1 - [debug] - 140480545251120 [DB] Analyzing starred_artist_user_backend_idx
[2025-Feb-16 23:35:57.747] 1 - [debug] - 140480545251120 [DB] Analyzing starred_artist_user_backend_idx: done!
[2025-Feb-16 23:35:57.747] 1 - [debug] - 140480545251120 [DB] Analyzing starred_artist_artist_user_backend_idx
[2025-Feb-16 23:35:57.747] 1 - [debug] - 140480545251120 [DB] Analyzing starred_artist_artist_user_backend_idx: done!
[2025-Feb-16 23:35:57.747] 1 - [debug] - 140480545251120 [DB] Analyzing starred_release_user_backend_idx
[2025-Feb-16 23:35:57.747] 1 - [debug] - 140480545251120 [DB] Analyzing starred_release_user_backend_idx: done!
[2025-Feb-16 23:35:57.747] 1 - [debug] - 140480545251120 [DB] Analyzing starred_release_release_user_backend_idx
[2025-Feb-16 23:35:57.747] 1 - [debug] - 140480545251120 [DB] Analyzing starred_release_release_user_backend_idx: done!
[2025-Feb-16 23:35:57.747] 1 - [debug] - 140480545251120 [DB] Analyzing starred_track_user_backend_idx
[2025-Feb-16 23:35:57.747] 1 - [debug] - 140480545251120 [DB] Analyzing starred_track_user_backend_idx: done!
[2025-Feb-16 23:35:57.747] 1 - [debug] - 140480545251120 [DB] Analyzing starred_track_track_user_backend_idx
[2025-Feb-16 23:35:57.747] 1 - [debug] - 140480545251120 [DB] Analyzing starred_track_track_user_backend_idx: done!
[2025-Feb-16 23:35:57.747] 1 - [info] - 140480545251120 [DB] Analyze complete!
[2025-Feb-16 23:35:57.748] 1 - [info] - 140480545251120 [COVER] JPEG export quality = 75
[2025-Feb-16 23:35:57.748] 1 - [info] - 140480545251120 [COVER] Default release cover path = "/usr/share/lms/approot//images/unknown-cover.svg"
[2025-Feb-16 23:35:57.748] 1 - [info] - 140480545251120 [COVER] Max cache size = 30000000
[2025-Feb-16 23:35:57.749] 1 - [debug] - 140480545251120 [DB UPDATER] Scanner settings updated
[2025-Feb-16 23:35:57.749] 1 - [debug] - 140480545251120 [DB UPDATER] Using scan settings version 0
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [METADATA] Using TagLib parser with read style = average
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [UTILS] Starting IO context with 4 threads...
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".aac" for Audio scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".alac" for Audio scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".aif" for Audio scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".aiff" for Audio scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".ape" for Audio scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".dsf" for Audio scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".flac" for Audio scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".m4a" for Audio scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".m4b" for Audio scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".mp3" for Audio scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".mpc" for Audio scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".oga" for Audio scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".ogg" for Audio scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".opus" for Audio scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".shn" for Audio scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".wav" for Audio scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".wma" for Audio scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".wv" for Audio scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".jpg" for Image scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".jpeg" for Image scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".png" for Image scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".bmp" for Image scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".lrc" for Lyrics scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".txt" for Lyrics scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".m3u" for PlayList scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Registered extension ".m3u8" for PlayList scanner
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [DB UPDATER] Using 4 thread(s) for scanning file metadata
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [SCROBBLING] Starting service...
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [FEEDBACK] [listenbrainz] Starting Feedbacks synchronizer, maxSyncFeedbackCount = 1000, _syncFeedbacksPeriod = 1 hours
[2025-Feb-16 23:35:57.749] 1 - [debug] - 140480545251120 [FEEDBACK] [listenbrainz] Scheduled sync in 30 seconds...
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [FEEDBACK] [listenbrainz] Starting ListenBrainz feedback backend... API endpoint = 'https://api.listenbrainz.org'
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [SCROBBLING] Service started!
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [SCROBBLING] Starting service...
[2025-Feb-16 23:35:57.749] 1 - [debug] - 140480534625080 [DB UPDATER] Scheduling next scan
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [SCROBBLING] [listenbrainz] Starting Listens synchronizer, maxSyncListenCount = 1000, _syncListensPeriod = 1 hours
[2025-Feb-16 23:35:57.749] 1 - [debug] - 140480545251120 [SCROBBLING] [listenbrainz] Scheduled sync in 30 seconds...
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [SCROBBLING] [listenbrainz] Starting ListenBrainz backend... API endpoint = 'https://api.listenbrainz.org'
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [SCROBBLING] Service started!
[2025-Feb-16 23:35:57.749] 1 - [info] - 140480545251120 [MAIN] Stopping init web server...
[2025-Feb-16 23:35:57.749] 1 - [info] "WebController: shutdown: stopping 0 sessions."
[2025-Feb-16 23:35:57.750] 1 - [info] "WServer/wthttp: Shutdown: stopping web server."
[2025-Feb-16 23:35:57.750] 1 - [debug] "wthttp: expireSession()Operation canceled"
[2025-Feb-16 23:35:57.750] 1 - [debug] "wthttp: handleTcpAccept: async_accept error (no listener, probably server shutdown): Operation canceled"
[2025-Feb-16 23:35:57.750] 1 - [debug] "wthttp/async: ~Connection"
[2025-Feb-16 23:35:57.751] 1 - [info] - 140480545251120 [MAIN] Starting init web server...
[2025-Feb-16 23:35:57.751] 1 - [info] "WServer/wthttp: initializing built-in wthttpd"
[2025-Feb-16 23:35:57.751] 1 - [info] - 140480534625080 [DB UPDATER] Scheduling next scan at Mon Feb 17 07:00:00 2025
[2025-Feb-16 23:35:57.751] 1 - [info] "wthttp: started server: http://0.0.0.0:5082"
[2025-Feb-16 23:35:57.751] 1 - [info] - 140480545251120 [MAIN] Now running...
[2025-Feb-16 23:36:00.890] 1 - [debug] "wthttp/async: new connection (#1)"
[2025-Feb-16 23:36:00.890] 1 - [debug] "wthttp/async: 44: start()"
[2025-Feb-16 23:36:00.890] 1 - [error] "wthttp/async: remote_endpoint() threw: remote_endpoint: Socket not connected [system:107 at /usr/include/boost/asio/detail/reactive_socket_service.hpp:218 in function 'remote_endpoint']"
[2025-Feb-16 23:36:00.890] 1 - [debug] "wthttp/async: 44: startAsyncReadRequest"
[2025-Feb-16 23:36:00.890] 1 - [debug] "wthttp/async: 44 setting read timeout (ws: -1)"
[2025-Feb-16 23:36:00.891] 1 - [debug] "wthttp/async: 44: handleReadRequest(): Connection reset by peer"
[2025-Feb-16 23:36:00.891] 1 - [debug] "wthttp/async: 44 cancel read timeout"
[2025-Feb-16 23:36:00.891] 1 - [debug] "wthttp/async: 44: error: Connection reset by peer"
[2025-Feb-16 23:36:00.891] 1 - [debug] "wthttp/async: 44 cancel read timeout"
[2025-Feb-16 23:36:00.891] 1 - [debug] "wthttp/async: 44 cancel write timeout"
[2025-Feb-16 23:36:00.891] 1 - [debug] "wthttp/async: 44: close()"
[2025-Feb-16 23:36:00.891] 1 - [debug] "wthttp/async: removed connection (#0)"
[2025-Feb-16 23:36:00.891] 1 - [debug] "wthttp/async: 44: stop()"
[2025-Feb-16 23:36:00.891] 1 - [debug] "wthttp/async: 44: closing socket"
[2025-Feb-16 23:36:00.891] 1 - [debug] "wthttp/async: ~Connection"
[2025-Feb-16 23:36:02.752] 1 - [debug] "wthttp: expireSession()No error information"
[2025-Feb-16 23:36:05.897] 1 - [debug] "wthttp/async: new connection (#1)"
[2025-Feb-16 23:36:05.897] 1 - [debug] "wthttp/async: 44: start()"
[2025-Feb-16 23:36:05.897] 1 - [error] "wthttp/async: remote_endpoint() threw: remote_endpoint: Socket not connected [system:107 at /usr/include/boost/asio/detail/reactive_socket_service.hpp:218 in function 'remote_endpoint']"
[2025-Feb-16 23:36:05.897] 1 - [debug] "wthttp/async: 44: startAsyncReadRequest"
[2025-Feb-16 23:36:05.897] 1 - [debug] "wthttp/async: 44 setting read timeout (ws: -1)"
[2025-Feb-16 23:36:05.897] 1 - [debug] "wthttp/async: 44: handleReadRequest(): Connection reset by peer"
[2025-Feb-16 23:36:05.897] 1 - [debug] "wthttp/async: 44 cancel read timeout"
[2025-Feb-16 23:36:05.897] 1 - [debug] "wthttp/async: 44: error: Connection reset by peer"
[2025-Feb-16 23:36:05.897] 1 - [debug] "wthttp/async: 44 cancel read timeout"
[2025-Feb-16 23:36:05.897] 1 - [debug] "wthttp/async: 44 cancel write timeout"
[2025-Feb-16 23:36:05.897] 1 - [debug] "wthttp/async: 44: close()"
[2025-Feb-16 23:36:05.897] 1 - [debug] "wthttp/async: removed connection (#0)"
[2025-Feb-16 23:36:05.897] 1 - [debug] "wthttp/async: 44: stop()"
[2025-Feb-16 23:36:05.897] 1 - [debug] "wthttp/async: 44: closing socket"
[2025-Feb-16 23:36:05.897] 1 - [debug] "wthttp/async: ~Connection"
[2025-Feb-16 23:36:07.754] 1 - [debug] "wthttp: expireSession()No error information"
[2025-Feb-16 23:36:10.904] 1 - [debug] "wthttp/async: new connection (#1)"
[2025-Feb-16 23:36:10.904] 1 - [debug] "wthttp/async: 44: start()"
[2025-Feb-16 23:36:10.904] 1 - [error] "wthttp/async: remote_endpoint() threw: remote_endpoint: Socket not connected [system:107 at /usr/include/boost/asio/detail/reactive_socket_service.hpp:218 in function 'remote_endpoint']"
[2025-Feb-16 23:36:10.904] 1 - [debug] "wthttp/async: 44: startAsyncReadRequest"
[2025-Feb-16 23:36:10.904] 1 - [debug] "wthttp/async: 44 setting read timeout (ws: -1)"
[2025-Feb-16 23:36:10.904] 1 - [debug] "wthttp/async: 44: handleReadRequest(): Connection reset by peer"
[2025-Feb-16 23:36:10.904] 1 - [debug] "wthttp/async: 44 cancel read timeout"
[2025-Feb-16 23:36:10.904] 1 - [debug] "wthttp/async: 44: error: Connection reset by peer"
[2025-Feb-16 23:36:10.904] 1 - [debug] "wthttp/async: 44 cancel read timeout"
[2025-Feb-16 23:36:10.904] 1 - [debug] "wthttp/async: 44 cancel write timeout"
[2025-Feb-16 23:36:10.904] 1 - [debug] "wthttp/async: 44: close()"
[2025-Feb-16 23:36:10.904] 1 - [debug] "wthttp/async: removed connection (#0)"
[2025-Feb-16 23:36:10.904] 1 - [debug] "wthttp/async: 44: stop()"
[2025-Feb-16 23:36:10.904] 1 - [debug] "wthttp/async: 44: closing socket"
[2025-Feb-16 23:36:10.904] 1 - [debug] "wthttp/async: ~Connection"
[2025-Feb-16 23:36:12.755] 1 - [debug] "wthttp: expireSession()No error information"
[2025-Feb-16 23:36:15.910] 1 - [debug] "wthttp/async: new connection (#1)"
[2025-Feb-16 23:36:15.910] 1 - [debug] "wthttp/async: 44: start()"
[2025-Feb-16 23:36:15.910] 1 - [error] "wthttp/async: remote_endpoint() threw: remote_endpoint: Socket not connected [system:107 at /usr/include/boost/asio/detail/reactive_socket_service.hpp:218 in function 'remote_endpoint']"
[2025-Feb-16 23:36:15.910] 1 - [debug] "wthttp/async: 44: startAsyncReadRequest"
[2025-Feb-16 23:36:15.910] 1 - [debug] "wthttp/async: 44 setting read timeout (ws: -1)"
[2025-Feb-16 23:36:15.910] 1 - [debug] "wthttp/async: 44: handleReadRequest(): Connection reset by peer"
[2025-Feb-16 23:36:15.910] 1 - [debug] "wthttp/async: 44 cancel read timeout"
[2025-Feb-16 23:36:15.910] 1 - [debug] "wthttp/async: 44: error: Connection reset by peer"
[2025-Feb-16 23:36:15.910] 1 - [debug] "wthttp/async: 44 cancel read timeout"
[2025-Feb-16 23:36:15.910] 1 - [debug] "wthttp/async: 44 cancel write timeout"
[2025-Feb-16 23:36:15.910] 1 - [debug] "wthttp/async: 44: close()"
[2025-Feb-16 23:36:15.910] 1 - [debug] "wthttp/async: removed connection (#0)"
[2025-Feb-16 23:36:15.910] 1 - [debug] "wthttp/async: 44: stop()"
[2025-Feb-16 23:36:15.910] 1 - [debug] "wthttp/async: 44: closing socket"
[2025-Feb-16 23:36:15.910] 1 - [debug] "wthttp/async: ~Connection"
[2025-Feb-16 23:36:17.756] 1 - [debug] "wthttp: expireSession()No error information"
[2025-Feb-16 23:36:20.913] 1 - [debug] "wthttp/async: new connection (#1)"
[2025-Feb-16 23:36:20.914] 1 - [debug] "wthttp/async: 44: start()"
[2025-Feb-16 23:36:20.914] 1 - [error] "wthttp/async: remote_endpoint() threw: remote_endpoint: Socket not connected [system:107 at /usr/include/boost/asio/detail/reactive_socket_service.hpp:218 in function 'remote_endpoint']"
[2025-Feb-16 23:36:20.914] 1 - [debug] "wthttp/async: 44: startAsyncReadRequest"
[2025-Feb-16 23:36:20.914] 1 - [debug] "wthttp/async: 44 setting read timeout (ws: -1)"
[2025-Feb-16 23:36:20.914] 1 - [debug] "wthttp/async: 44: handleReadRequest(): Connection reset by peer"
[2025-Feb-16 23:36:20.914] 1 - [debug] "wthttp/async: 44 cancel read timeout"
[2025-Feb-16 23:36:20.914] 1 - [debug] "wthttp/async: 44: error: Connection reset by peer"
[2025-Feb-16 23:36:20.914] 1 - [debug] "wthttp/async: 44 cancel read timeout"
[2025-Feb-16 23:36:20.914] 1 - [debug] "wthttp/async: 44 cancel write timeout"
[2025-Feb-16 23:36:20.914] 1 - [debug] "wthttp/async: 44: close()"
[2025-Feb-16 23:36:20.914] 1 - [debug] "wthttp/async: removed connection (#0)"
[2025-Feb-16 23:36:20.914] 1 - [debug] "wthttp/async: 44: stop()"
[2025-Feb-16 23:36:20.914] 1 - [debug] "wthttp/async: 44: closing socket"
[2025-Feb-16 23:36:20.914] 1 - [debug] "wthttp/async: ~Connection"
[2025-Feb-16 23:36:22.757] 1 - [debug] "wthttp: expireSession()No error information"
[2025-Feb-16 23:36:25.918] 1 - [debug] "wthttp/async: new connection (#1)"
[2025-Feb-16 23:36:25.918] 1 - [debug] "wthttp/async: 44: start()"
[2025-Feb-16 23:36:25.918] 1 - [error] "wthttp/async: remote_endpoint() threw: remote_endpoint: Socket not connected [system:107 at /usr/include/boost/asio/detail/reactive_socket_service.hpp:218 in function 'remote_endpoint']"
[2025-Feb-16 23:36:25.918] 1 - [debug] "wthttp/async: 44: startAsyncReadRequest"
[2025-Feb-16 23:36:25.918] 1 - [debug] "wthttp/async: 44 setting read timeout (ws: -1)"
[2025-Feb-16 23:36:25.918] 1 - [debug] "wthttp/async: 44: handleReadRequest(): Connection reset by peer"
[2025-Feb-16 23:36:25.918] 1 - [debug] "wthttp/async: 44 cancel read timeout"
[2025-Feb-16 23:36:25.918] 1 - [debug] "wthttp/async: 44: error: Connection reset by peer"
[2025-Feb-16 23:36:25.918] 1 - [debug] "wthttp/async: 44 cancel read timeout"
[2025-Feb-16 23:36:25.918] 1 - [debug] "wthttp/async: 44 cancel write timeout"
[2025-Feb-16 23:36:25.918] 1 - [debug] "wthttp/async: 44: close()"
[2025-Feb-16 23:36:25.918] 1 - [debug] "wthttp/async: removed connection (#0)"
[2025-Feb-16 23:36:25.918] 1 - [debug] "wthttp/async: 44: stop()"
[2025-Feb-16 23:36:25.918] 1 - [debug] "wthttp/async: 44: closing socket"
[2025-Feb-16 23:36:25.918] 1 - [debug] "wthttp/async: ~Connection"
[2025-Feb-16 23:36:27.758] 1 - [debug] "wthttp: expireSession()No error information"
[2025-Feb-16 23:36:27.778] 1 - [debug] - 140480543472440 [FEEDBACK] [listenbrainz] Starting sync!
[2025-Feb-16 23:36:27.778] 1 - [debug] - 140480543320888 [SCROBBLING] [listenbrainz] Starting sync!
[2025-Feb-16 23:36:27.780] 1 - [debug] - 140480543472440 [FEEDBACK] [listenbrainz] Queing 0 pending 'love' feedbacks
[2025-Feb-16 23:36:27.780] 1 - [debug] - 140480543472440 [FEEDBACK] [listenbrainz] Queing 0 pending 'erase' feedbacks
[2025-Feb-16 23:36:27.780] 1 - [debug] - 140480543472440 [FEEDBACK] [listenbrainz] Scheduled sync in 3600 seconds...
[2025-Feb-16 23:36:27.788] 1 - [debug] - 140480543320888 [SCROBBLING] [listenbrainz] Queing 0 pending listen
[2025-Feb-16 23:36:27.789] 1 - [debug] - 140480543320888 [SCROBBLING] [listenbrainz] Scheduled sync in 3600 seconds...

After that it just keeps looping this every 5 seconds:

Loop after the beginning

[2025-Feb-16 23:36:30.928] 1 - [debug] "wthttp/async: new connection (#1)"
[2025-Feb-16 23:36:30.928] 1 - [debug] "wthttp/async: 44: start()"
[2025-Feb-16 23:36:30.928] 1 - [error] "wthttp/async: remote_endpoint() threw: remote_endpoint: Socket not connected [system:107 at /usr/include/boost/asio/detail/reactive_socket_service.hpp:218 in function 'remote_endpoint']"
[2025-Feb-16 23:36:30.928] 1 - [debug] "wthttp/async: 44: startAsyncReadRequest"
[2025-Feb-16 23:36:30.928] 1 - [debug] "wthttp/async: 44 setting read timeout (ws: -1)"
[2025-Feb-16 23:36:30.928] 1 - [debug] "wthttp/async: 44: handleReadRequest(): Connection reset by peer"
[2025-Feb-16 23:36:30.928] 1 - [debug] "wthttp/async: 44 cancel read timeout"
[2025-Feb-16 23:36:30.928] 1 - [debug] "wthttp/async: 44: error: Connection reset by peer"
[2025-Feb-16 23:36:30.928] 1 - [debug] "wthttp/async: 44 cancel read timeout"
[2025-Feb-16 23:36:30.928] 1 - [debug] "wthttp/async: 44 cancel write timeout"
[2025-Feb-16 23:36:30.928] 1 - [debug] "wthttp/async: 44: close()"
[2025-Feb-16 23:36:30.928] 1 - [debug] "wthttp/async: removed connection (#0)"
[2025-Feb-16 23:36:30.928] 1 - [debug] "wthttp/async: 44: stop()"
[2025-Feb-16 23:36:30.928] 1 - [debug] "wthttp/async: 44: closing socket"
[2025-Feb-16 23:36:30.928] 1 - [debug] "wthttp/async: ~Connection"
[2025-Feb-16 23:36:32.759] 1 - [debug] "wthttp: expireSession()No error information"

Feb 16 '25 22:02 ajpanton

Thanks for the info! Will forward to the wt team.

Feb 18 '25 06:02 epoupon

Hello! Could you by chance test docker image 59f7bec8? Caution: it contains non backward compatible changes (you can't rollback on v3.63.0)

Feb 23 '25 08:02 epoupon

Tested :59f7bec8 and :3.64.0, both continue to show the same error.

Feb 23 '25 20:02 ajpanton

@epoupon Thanks to debugging some other stuff, I randomly figured out where this is coming from. Again, it's kind of my fault, but also LMS behaves differently here than anything else I've seen.

All my services are behind a pfSense router with HAProxy. It uses health checks to see if the services are up. The simplest and fastest health check method is supposedly "Basic socket connection check". This never crossed my mind as being the source, because it's a setting that I've kind of set and forgot about, as it's being used on all of my services. None of them have ever showed any logs on this, in any logging mode, except LMS which shows it as an error.

Is there a way to get rid of this? It feels unnecessary to log these health checks every 5 seconds, but I don't want to filter out error logs.

Jul 19 '25 10:07 ajpanton