May 25 11:38:02 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:02 volumio volumio[1365]: info: Discovery: Restarting Browsing May 25 11:38:03 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState May 25 11:38:04 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 11:38:06 volumio volumio[1365]: info: CoreCommandRouter::Close All Modals sent May 25 11:38:07 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:07 volumio volumio[1365]: info: Discovery: Restarting Browsing May 25 11:38:08 volumio volumio[1365]: info: Enabling plugin yandex_music May 25 11:38:08 volumio volumio[1365]: info: Loading plugin "yandex_music"... May 25 11:38:09 volumio volumio[1365]: info: PLUGIN START: yandex_music May 25 11:38:09 volumio volumio[1365]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 11:38:09 volumio volumio[1365]: info: [1779698289087] CoreMusicLibrary::Adding element Яндекс Музыка May 25 11:38:09 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 11:38:09 volumio volumio[1365]: Cannot find translation for source Randomizer May 25 11:38:09 volumio volumio[1365]: Cannot find translation for source Яндекс Музыка May 25 11:38:09 volumio volumio[1365]: info: Done. May 25 11:38:12 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:12 volumio volumio[1365]: info: Discovery: Restarting Browsing May 25 11:38:13 volumio volumio[1365]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: unknown Engine version: 4 Transport: websocket Total Clients: 11 May 25 11:38:13 volumio volumio[1365]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: unknown Engine version: 4 Transport: websocket Total Clients: 11 May 25 11:38:13 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState May 25 11:38:13 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 25 11:38:13 volumio volumio[1365]: info: CoreCommandRouter::volumioGetQueue May 25 11:38:13 volumio volumio[1365]: info: CoreStateMachine::getQueue May 25 11:38:13 volumio volumio[1365]: info: CorePlayQueue::getQueue May 25 11:38:15 volumio volumio[1365]: info: Enabling plugin spop May 25 11:38:15 volumio volumio[1365]: info: Loading plugin "spop"... May 25 11:38:16 volumio volumio[1365]: info: PLUGIN START: spop May 25 11:38:16 volumio volumio[1365]: info: Creating Spotify config file May 25 11:38:16 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:38:16 volumio volumio[1365]: info: Done. May 25 11:38:16 volumio volumio[1365]: info: Spotify config file written May 25 11:38:16 volumio volumio[1365]: info: No need to fix Spotify hosts May 25 11:38:16 volumio sudo[14144]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 25 11:38:16 volumio sudo[14144]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:38:16 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 25 11:38:16 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 25 11:38:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:16 volumio go-librespot[14146]: go-librespot daemon starting... May 25 11:38:16 volumio sudo[14144]: pam_unix(sudo:session): session closed for user root May 25 11:38:16 volumio go-librespot[14147]: time="2026-05-25T11:38:16+03:00" level=info msg="running go-librespot 0.7.1" May 25 11:38:16 volumio go-librespot[14147]: time="2026-05-25T11:38:16+03:00" level=debug msg="no app state found" May 25 11:38:16 volumio go-librespot[14147]: time="2026-05-25T11:38:16+03:00" level=debug msg="stored credentials not found" May 25 11:38:16 volumio go-librespot[14147]: time="2026-05-25T11:38:16+03:00" level=info msg="generated new device id: ba8e47c7ccd38c3e62832adf46f521aa674623f1" May 25 11:38:16 volumio go-librespot[14147]: time="2026-05-25T11:38:16+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 11:38:16 volumio volumio[1365]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 May 25 11:38:16 volumio go-librespot[14147]: time="2026-05-25T11:38:16+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 25 11:38:16 volumio go-librespot[14147]: time="2026-05-25T11:38:16+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 25 11:38:16 volumio go-librespot[14147]: time="2026-05-25T11:38:16+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 25 11:38:17 volumio go-librespot[14147]: time="2026-05-25T11:38:17+03:00" level=info msg="zeroconf server listening on port 44639" May 25 11:38:17 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:17 volumio go-librespot[14147]: time="2026-05-25T11:38:17+03:00" level=fatal msg="failed running zeroconf" error="failed initializing zeroconf: failed initializing avahi registrar: failed to connect to system bus: The maximum number of active connections for UID 1000 has been reached" May 25 11:38:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 11:38:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 11:38:17 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:17 volumio volumio[1365]: info: Discovery: Restarting Browsing May 25 11:38:18 volumio volumio[1365]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 May 25 11:38:18 volumio volumio[1365]: info: Downloading plugin at http://127.0.0.1:3000/plugin-serve/youtube2.zip May 25 11:38:18 volumio volumio5-onboarding[1825]: time=2026-05-25T08:38:18.210Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 11:38:18 volumio volumio[1365]: info: END DOWNLOAD: http://127.0.0.1:3000/plugin-serve/youtube2.zip May 25 11:38:18 volumio volumio[1365]: info: Folder /tmp/plugins removed May 25 11:38:18 volumio volumio5-onboarding[1825]: time=2026-05-25T08:38:18.221Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 11:38:19 volumio volumio[1365]: info: go-librespot daemon successfully initialized May 25 11:38:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. May 25 11:38:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:20 volumio go-librespot[14173]: go-librespot daemon starting... May 25 11:38:20 volumio go-librespot[14174]: time="2026-05-25T11:38:20+03:00" level=info msg="running go-librespot 0.7.1" May 25 11:38:20 volumio go-librespot[14174]: time="2026-05-25T11:38:20+03:00" level=debug msg="app state loaded" May 25 11:38:20 volumio go-librespot[14174]: time="2026-05-25T11:38:20+03:00" level=debug msg="stored credentials not found" May 25 11:38:20 volumio go-librespot[14174]: time="2026-05-25T11:38:20+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 11:38:20 volumio go-librespot[14174]: time="2026-05-25T11:38:20+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 25 11:38:20 volumio go-librespot[14174]: time="2026-05-25T11:38:20+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 25 11:38:20 volumio go-librespot[14174]: time="2026-05-25T11:38:20+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 25 11:38:20 volumio go-librespot[14174]: time="2026-05-25T11:38:20+03:00" level=info msg="zeroconf server listening on port 37941" May 25 11:38:20 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:20 volumio go-librespot[14174]: time="2026-05-25T11:38:20+03:00" level=fatal msg="failed running zeroconf" error="failed initializing zeroconf: failed initializing avahi registrar: failed to connect to system bus: The maximum number of active connections for UID 1000 has been reached" May 25 11:38:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 11:38:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 11:38:21 volumio volumio5-onboarding[1825]: time=2026-05-25T08:38:21.130Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 11:38:21 volumio volumio[1365]: info: Check plugin dependencies May 25 11:38:21 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 11:38:21 volumio volumio[1365]: info: Checking if plugin already exists May 25 11:38:21 volumio volumio[1365]: info: Rename folder May 25 11:38:21 volumio volumio5-onboarding[1825]: time=2026-05-25T08:38:21.178Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 11:38:21 volumio volumio5-onboarding[1825]: time=2026-05-25T08:38:21.178Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 11:38:21 volumio volumio[1365]: info: Folder /tmp/downloaded_plugin.zip removed May 25 11:38:21 volumio volumio[1365]: info: Move to category May 25 11:38:21 volumio volumio5-onboarding[1825]: time=2026-05-25T08:38:21.189Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 11:38:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. May 25 11:38:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:23 volumio go-librespot[14214]: go-librespot daemon starting... May 25 11:38:23 volumio go-librespot[14215]: time="2026-05-25T11:38:23+03:00" level=info msg="running go-librespot 0.7.1" May 25 11:38:23 volumio go-librespot[14215]: time="2026-05-25T11:38:23+03:00" level=debug msg="app state loaded" May 25 11:38:23 volumio go-librespot[14215]: time="2026-05-25T11:38:23+03:00" level=debug msg="stored credentials not found" May 25 11:38:23 volumio go-librespot[14215]: time="2026-05-25T11:38:23+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 11:38:23 volumio go-librespot[14215]: time="2026-05-25T11:38:23+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 25 11:38:23 volumio go-librespot[14215]: time="2026-05-25T11:38:23+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 25 11:38:23 volumio go-librespot[14215]: time="2026-05-25T11:38:23+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 25 11:38:23 volumio go-librespot[14215]: time="2026-05-25T11:38:23+03:00" level=info msg="zeroconf server listening on port 37519" May 25 11:38:23 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:23 volumio go-librespot[14215]: time="2026-05-25T11:38:23+03:00" level=fatal msg="failed running zeroconf" error="failed initializing zeroconf: failed initializing avahi registrar: failed to connect to system bus: The maximum number of active connections for UID 1000 has been reached" May 25 11:38:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 11:38:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 11:38:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. May 25 11:38:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:26 volumio go-librespot[14225]: go-librespot daemon starting... May 25 11:38:26 volumio go-librespot[14226]: time="2026-05-25T11:38:26+03:00" level=info msg="running go-librespot 0.7.1" May 25 11:38:26 volumio go-librespot[14226]: time="2026-05-25T11:38:26+03:00" level=debug msg="app state loaded" May 25 11:38:26 volumio go-librespot[14226]: time="2026-05-25T11:38:26+03:00" level=debug msg="stored credentials not found" May 25 11:38:26 volumio go-librespot[14226]: time="2026-05-25T11:38:26+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 11:38:27 volumio go-librespot[14226]: time="2026-05-25T11:38:27+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 25 11:38:27 volumio go-librespot[14226]: time="2026-05-25T11:38:27+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 25 11:38:27 volumio go-librespot[14226]: time="2026-05-25T11:38:27+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 25 11:38:27 volumio go-librespot[14226]: time="2026-05-25T11:38:27+03:00" level=info msg="zeroconf server listening on port 43363" May 25 11:38:27 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:27 volumio go-librespot[14226]: time="2026-05-25T11:38:27+03:00" level=fatal msg="failed running zeroconf" error="failed initializing zeroconf: failed initializing avahi registrar: failed to connect to system bus: The maximum number of active connections for UID 1000 has been reached" May 25 11:38:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 11:38:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 11:38:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. May 25 11:38:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:30 volumio go-librespot[14235]: go-librespot daemon starting... May 25 11:38:30 volumio go-librespot[14236]: time="2026-05-25T11:38:30+03:00" level=info msg="running go-librespot 0.7.1" May 25 11:38:30 volumio go-librespot[14236]: time="2026-05-25T11:38:30+03:00" level=debug msg="app state loaded" May 25 11:38:30 volumio go-librespot[14236]: time="2026-05-25T11:38:30+03:00" level=debug msg="stored credentials not found" May 25 11:38:30 volumio go-librespot[14236]: time="2026-05-25T11:38:30+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 11:38:30 volumio go-librespot[14236]: time="2026-05-25T11:38:30+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 25 11:38:30 volumio go-librespot[14236]: time="2026-05-25T11:38:30+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 25 11:38:30 volumio go-librespot[14236]: time="2026-05-25T11:38:30+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 25 11:38:30 volumio go-librespot[14236]: time="2026-05-25T11:38:30+03:00" level=info msg="zeroconf server listening on port 43537" May 25 11:38:30 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:30 volumio go-librespot[14236]: time="2026-05-25T11:38:30+03:00" level=fatal msg="failed running zeroconf" error="failed initializing zeroconf: failed initializing avahi registrar: failed to connect to system bus: The maximum number of active connections for UID 1000 has been reached" May 25 11:38:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 11:38:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 11:38:30 volumio volumio5-onboarding[1825]: time=2026-05-25T08:38:30.559Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 11:38:30 volumio volumio[1365]: info: Checking if install.sh is present May 25 11:38:30 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:30 volumio volumio[1365]: info: Discovery: Restarting Browsing May 25 11:38:30 volumio volumio[1365]: info: Initializing connection to go-librespot Websocket May 25 11:38:30 volumio volumio[1365]: info: Enabling plugin Bluetoothremote May 25 11:38:30 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState May 25 11:38:30 volumio volumio[1365]: info: Loading plugin "Bluetoothremote"... May 25 11:38:30 volumio volumio[1365]: info: PLUGIN START: Bluetoothremote May 25 11:38:30 volumio volumio[1365]: info: Loading i18n strings for locale ru May 25 11:38:30 volumio volumio[1365]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. May 25 11:38:30 volumio volumio[1365]: info: Done. May 25 11:38:30 volumio volumio[1365]: info: Executing install.sh May 25 11:38:30 volumio volumio[1365]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 25 11:38:30 volumio sudo[14249]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/youtube2/install.sh May 25 11:38:30 volumio sudo[14249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:38:30 volumio volumio[1365]: info: Skipping Deno installation - not supported on armhf May 25 11:38:30 volumio volumio[1365]: info: Installing Python... May 25 11:38:30 volumio volumio5-onboarding[1825]: time=2026-05-25T08:38:30.675Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 11:38:30 volumio volumio5-onboarding[1825]: time=2026-05-25T08:38:30.677Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 11:38:30 volumio sudo[14252]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt update May 25 11:38:30 volumio sudo[14252]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 11:38:30 volumio volumio[1365]: info: Hit:1 http://raspbian.raspberrypi.com/raspbian bookworm InRelease May 25 11:38:30 volumio volumio5-onboarding[1825]: time=2026-05-25T08:38:30.990Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 11:38:30 volumio volumio[1365]: info: Hit:2 http://archive.raspberrypi.com/debian bookworm InRelease May 25 11:38:30 volumio volumio5-onboarding[1825]: time=2026-05-25T08:38:30.993Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 11:38:32 volumio volumio[1365]: info: Reading package lists... May 25 11:38:32 volumio volumio5-onboarding[1825]: time=2026-05-25T08:38:32.526Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 11:38:32 volumio volumio[1365]: info: Building dependency tree... May 25 11:38:32 volumio volumio5-onboarding[1825]: time=2026-05-25T08:38:32.845Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 11:38:32 volumio volumio[1365]: info: Reading state information... May 25 11:38:32 volumio volumio5-onboarding[1825]: time=2026-05-25T08:38:32.855Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 11:38:32 volumio sudo[14252]: pam_unix(sudo:session): session closed for user root May 25 11:38:32 volumio volumio[1365]: info: 50 packages can be upgraded. Run 'apt list --upgradable' to see them. May 25 11:38:32 volumio volumio5-onboarding[1825]: time=2026-05-25T08:38:32.895Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 11:38:32 volumio sudo[14412]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get install -y python3 May 25 11:38:32 volumio sudo[14412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 11:38:32 volumio volumio[1365]: info: Reading package lists... May 25 11:38:32 volumio volumio5-onboarding[1825]: time=2026-05-25T08:38:32.967Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 11:38:33 volumio volumio[1365]: info: Building dependency tree... May 25 11:38:33 volumio volumio5-onboarding[1825]: time=2026-05-25T08:38:33.258Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 11:38:33 volumio volumio[1365]: info: Reading state information... May 25 11:38:33 volumio volumio5-onboarding[1825]: time=2026-05-25T08:38:33.262Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 11:38:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. May 25 11:38:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:33 volumio go-librespot[14420]: go-librespot daemon starting... May 25 11:38:33 volumio go-librespot[14421]: time="2026-05-25T11:38:33+03:00" level=info msg="running go-librespot 0.7.1" May 25 11:38:33 volumio go-librespot[14421]: time="2026-05-25T11:38:33+03:00" level=debug msg="app state loaded" May 25 11:38:33 volumio go-librespot[14421]: time="2026-05-25T11:38:33+03:00" level=debug msg="stored credentials not found" May 25 11:38:33 volumio go-librespot[14421]: time="2026-05-25T11:38:33+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 11:38:33 volumio go-librespot[14421]: time="2026-05-25T11:38:33+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 25 11:38:33 volumio go-librespot[14421]: time="2026-05-25T11:38:33+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 25 11:38:33 volumio go-librespot[14421]: time="2026-05-25T11:38:33+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 25 11:38:33 volumio go-librespot[14421]: time="2026-05-25T11:38:33+03:00" level=info msg="zeroconf server listening on port 45495" May 25 11:38:33 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:33 volumio go-librespot[14421]: time="2026-05-25T11:38:33+03:00" level=fatal msg="failed running zeroconf" error="failed initializing zeroconf: failed initializing avahi registrar: failed to connect to system bus: The maximum number of active connections for UID 1000 has been reached" May 25 11:38:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 11:38:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 11:38:33 volumio volumio[1365]: info: Initializing connection to go-librespot Websocket May 25 11:38:33 volumio volumio[1365]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 25 11:38:33 volumio volumio[1365]: info: python3 is already the newest version (3.11.2-1). May 25 11:38:33 volumio sudo[14412]: pam_unix(sudo:session): session closed for user root May 25 11:38:33 volumio sudo[14249]: pam_unix(sudo:session): session closed for user root May 25 11:38:33 volumio volumio[1365]: info: 0 upgraded, 0 newly installed, 0 to remove and 50 not upgraded. May 25 11:38:33 volumio volumio5-onboarding[1825]: time=2026-05-25T08:38:33.876Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 11:38:33 volumio volumio5-onboarding[1825]: time=2026-05-25T08:38:33.877Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 11:38:33 volumio volumio[1365]: info: Install script completed May 25 11:38:33 volumio volumio5-onboarding[1825]: time=2026-05-25T08:38:33.891Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 11:38:33 volumio volumio[1365]: info: Adding reference to registry May 25 11:38:33 volumio volumio5-onboarding[1825]: time=2026-05-25T08:38:33.894Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 11:38:33 volumio volumio[1365]: info: Done installing plugin. May 25 11:38:33 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 11:38:33 volumio volumio[1365]: info: Folder /tmp/plugins removed May 25 11:38:33 volumio volumio[1365]: info: Folder /tmp/downloaded_plugin.zip removed May 25 11:38:33 volumio volumio[1365]: info: Folder /data/temp removed May 25 11:38:33 volumio volumio[1365]: info: YouTube2 plugin installed May 25 11:38:33 volumio volumio[1365]: Plugin install end detected on script May 25 11:38:33 volumio volumio5-onboarding[1825]: time=2026-05-25T08:38:33.906Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 25 11:38:35 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 25 11:38:35 volumio volumio[1365]: info: CURURI: music-library May 25 11:38:35 volumio volumio[1365]: info: Preload queue cleared May 25 11:38:35 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:35 volumio volumio[1365]: info: Discovery: Restarting Browsing May 25 11:38:36 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 25 11:38:36 volumio volumio[1365]: info: CURURI: music-library/INTERNAL May 25 11:38:36 volumio volumio[1365]: info: Preload queue cleared May 25 11:38:36 volumio volumio[1365]: info: Initializing connection to go-librespot Websocket May 25 11:38:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. May 25 11:38:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:36 volumio volumio[1365]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 25 11:38:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:36 volumio go-librespot[14441]: go-librespot daemon starting... May 25 11:38:36 volumio go-librespot[14442]: time="2026-05-25T11:38:36+03:00" level=info msg="running go-librespot 0.7.1" May 25 11:38:36 volumio go-librespot[14442]: time="2026-05-25T11:38:36+03:00" level=debug msg="app state loaded" May 25 11:38:36 volumio go-librespot[14442]: time="2026-05-25T11:38:36+03:00" level=debug msg="stored credentials not found" May 25 11:38:36 volumio go-librespot[14442]: time="2026-05-25T11:38:36+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 11:38:36 volumio go-librespot[14442]: time="2026-05-25T11:38:36+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 25 11:38:36 volumio go-librespot[14442]: time="2026-05-25T11:38:36+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 25 11:38:36 volumio go-librespot[14442]: time="2026-05-25T11:38:36+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 25 11:38:36 volumio go-librespot[14442]: time="2026-05-25T11:38:36+03:00" level=info msg="zeroconf server listening on port 40739" May 25 11:38:36 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:36 volumio go-librespot[14442]: time="2026-05-25T11:38:36+03:00" level=fatal msg="failed running zeroconf" error="failed initializing zeroconf: failed initializing avahi registrar: failed to connect to system bus: The maximum number of active connections for UID 1000 has been reached" May 25 11:38:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 11:38:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 11:38:39 volumio volumio[1365]: info: Initializing connection to go-librespot Websocket May 25 11:38:39 volumio volumio[1365]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 25 11:38:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. May 25 11:38:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:40 volumio go-librespot[14457]: go-librespot daemon starting... May 25 11:38:40 volumio go-librespot[14458]: time="2026-05-25T11:38:40+03:00" level=info msg="running go-librespot 0.7.1" May 25 11:38:40 volumio go-librespot[14458]: time="2026-05-25T11:38:40+03:00" level=debug msg="app state loaded" May 25 11:38:40 volumio go-librespot[14458]: time="2026-05-25T11:38:40+03:00" level=debug msg="stored credentials not found" May 25 11:38:40 volumio go-librespot[14458]: time="2026-05-25T11:38:40+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 11:38:40 volumio go-librespot[14458]: time="2026-05-25T11:38:40+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 25 11:38:40 volumio go-librespot[14458]: time="2026-05-25T11:38:40+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 25 11:38:40 volumio go-librespot[14458]: time="2026-05-25T11:38:40+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 25 11:38:40 volumio go-librespot[14458]: time="2026-05-25T11:38:40+03:00" level=info msg="zeroconf server listening on port 42791" May 25 11:38:40 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:40 volumio go-librespot[14458]: time="2026-05-25T11:38:40+03:00" level=fatal msg="failed running zeroconf" error="failed initializing zeroconf: failed initializing avahi registrar: failed to connect to system bus: The maximum number of active connections for UID 1000 has been reached" May 25 11:38:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 11:38:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 11:38:40 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:40 volumio volumio[1365]: info: Discovery: Restarting Browsing May 25 11:38:41 volumio volumio[1365]: info: Preload queue cleared May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::ClearQueue May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::stop May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::stPlaybackTimer May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::updateTrackBlock May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrackBlock May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::serviceStop May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::serviceStop May 25 11:38:41 volumio volumio[1365]: info: [1779698321402] ControllerWebradio::stop May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand stop May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::clearPlayQueue May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::saveQueue May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushQueue May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::addQueueItems May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::addQueueItems May 25 11:38:41 volumio volumio[1365]: info: Preload queue cleared May 25 11:38:41 volumio volumio[1365]: info: Adding Item to queue: music-library/INTERNAL/VA - Music News vol.346 May 25 11:38:41 volumio volumio[1365]: info: Exploding uri music-library/INTERNAL/VA - Music News vol.346 in service mpd May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=webradio volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 25 11:38:41 volumio volumio[1365]: info: May 25 11:38:41 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%90%D0%BD%D0%BD%D0%B0%20%D0%A1%D0%B5%D0%BC%D0%B5%D0%BD%D0%BE%D0%B2%D0%B8%D1%87/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F001.%20%D0%90%D0%BD%D0%BD%D0%B0%20%D0%A1%D0%B5%D0%BC%D0%B5%D0%BD%D0%BE%D0%B2%D0%B8%D1%87%20-%20%D0%93%D0%BB%D0%B0%D0%B4%D0%B8%D0%BE%D0%BB%D1%83%D1%81%D1%8B.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/001. Анна Семенович - Гладиолусы.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Aden%20Foyer/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F002.%20Aden%20Foyer%20-%20Hello%20Radio.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/002. Aden Foyer - Hello Radio.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Adon%20Mix%20feat.%20%D0%9F%D0%BE%D1%88%D0%BB%D1%8B%D0%B9/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F003.%20Adon%20Mix%20feat.%20%D0%9F%D0%BE%D1%88%D0%BB%D1%8B%D0%B9%20-%20%D0%90%D0%BB%D0%B5%2C%20%D0%9C%D0%B0%D0%BB%D0%B0%D1%8F.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/003. Adon Mix feat. Пошлый - Але, Малая.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=AGAYAN/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F004.%20AGAYAN%20-%20%D0%9D%D0%B0%D0%B1%D0%B5%D1%80%D0%B8.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/004. AGAYAN - Набери.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Alex%20Christensen%20feat.%20Alex%20C.%20%26%20Steve%20Modana/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F005.%20Alex%20Christensen%20feat.%20Alex%20C.%20%26%20Steve%20Modana%20-%20Wir%20Sind%20Frei%20(Vois%20Sur%20Ton%20Chemin).mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/005. Alex Christensen feat. Alex C. & Steve Modana - Wir Sind Frei (Vois Sur Ton Chemin).mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Alex%20Cruz%20%26%20Brascon%20feat.%20Bailey/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F006.%20Alex%20Cruz%20%26%20Brascon%20feat.%20Bailey%20-%20High%20Wire.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/006. Alex Cruz & Brascon feat. Bailey - High Wire.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Alex%20Velea/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F007.%20Alex%20Velea%20-%20Monali.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/007. Alex Velea - Monali.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Ana%20Mena/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F008.%20Ana%20Mena%20-%20La%20Gata%20Bajo%20La%20Lluvia.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/008. Ana Mena - La Gata Bajo La Lluvia.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Arabella/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F009.%20Arabella%20-%20On%20My%20Body.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/009. Arabella - On My Body.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Bakhtin/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F010.%20Bakhtin%20-%20%D0%9F%D0%BE%20%D0%9A%D0%B0%D0%B1%D0%B0%D0%BA%D0%B0%D0%BC.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/010. Bakhtin - По Кабакам.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Beachbag%20feat.%20YUNA/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F011.%20Beachbag%20feat.%20YUNA%20-%20Deepend.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/011. Beachbag feat. YUNA - Deepend.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Bobina/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F012.%20Bobina%20-%20The%20Unforgiven%20(Vadim%20Adamov%20%26%20Hardphol%20Remix).mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/012. Bobina - The Unforgiven (Vadim Adamov & Hardphol Remix).mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Cale/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F013.%20Cale%20-%20Skyfall.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/013. Cale - Skyfall.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Cigarettes%20After%20Sex/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F014.%20Cigarettes%20After%20Sex%20-%20Tejano%20Blue.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/014. Cigarettes After Sex - Tejano Blue.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Daria%20Lupi%20feat.%20Cabron%20%26%20DJ%20Catsultan/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F015.%20Daria%20Lupi%20feat.%20Cabron%20%26%20DJ%20Catsultan%20-%20In%20Bataia%20Vantului%20(SASU%20%26%20IVO%20Remix).mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/015. Daria Lupi feat. Cabron & DJ Catsultan - In Bataia Vantului (SASU & IVO Remix).mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=3-%D0%B8%D0%B9%20%D0%AF%D0%BD%D0%B2%D0%B0%D1%80%D1%8C/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F016.%203-%D0%B8%D0%B9%20%D0%AF%D0%BD%D0%B2%D0%B0%D1%80%D1%8C%20-%20%D0%9F%D0%BE%D0%BB%D1%8F%D1%80%D0%BD%D0%B0%D1%8F%20%D0%97%D0%B2%D0%B5%D0%B7%D0%B4%D0%B0.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/016. 3-ий Январь - Полярная Звезда.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Edward%20Maya%20feat.%20Vika%20Jigulina/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F017.%20Edward%20Maya%20feat.%20Vika%20Jigulina%20-%20Stereo%20Love%20(SP3CTRUM%20Remix).mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/017. Edward Maya feat. Vika Jigulina - Stereo Love (SP3CTRUM Remix).mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=FATO/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F018.%20FATO%20-%20%D0%9A%D0%BE%D0%BB%D0%B5%D1%81%D0%BE.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/018. FATO - Колесо.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Flyana%20Boss/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F019.%20Flyana%20Boss%20-%20Yeaaa.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/019. Flyana Boss - Yeaaa.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Gulyaev/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F020.%20Gulyaev%20-%20%D0%93%D0%BE%D1%80%D0%B8%20%D0%AF%D1%81%D0%BD%D0%BE.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/020. Gulyaev - Гори Ясно.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Heylee%20feat.%20Shavrov/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F021.%20Heylee%20feat.%20Shavrov%20-%20%D0%9F%D0%BE%D0%BC%D0%BE%D0%B3%D0%B8%20%D0%9C%D0%BD%D0%B5.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/021. Heylee feat. Shavrov - Помоги Мне.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Hunxho%20feat.%20Summer%20Walker/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F022.%20Hunxho%20feat.%20Summer%20Walker%20-%20Your%20Friends.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/022. Hunxho feat. Summer Walker - Your Friends.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Ingo%20feat.%20Namic%20%26%20Sarah%20Bird/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F023.%20Ingo%20feat.%20Namic%20%26%20Sarah%20Bird%20-%20Burning.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/023. Ingo feat. Namic & Sarah Bird - Burning.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=JERRY/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F024.%20JERRY%20-%20%D0%9C%D0%B5%D0%BD%D1%8C%D1%88%D0%B5%20%D0%A1%D0%BB%D0%BE%D0%B2-%D0%91%D0%BE%D0%BB%D1%8C%D1%88%D0%B5%20%D0%94%D0%B5%D0%BB%D0%B0.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/024. JERRY - Меньше Слов-Больше Дела.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Jessie%20Murph/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F025.%20Jessie%20Murph%20-%20Son%20Of%20A%20Bitch.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/025. Jessie Murph - Son Of A Bitch.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Jevile/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F026.%20Jevile%20-%20%D0%92%D1%80%D0%B5%D0%BC%D0%B5%D0%BD%D0%B0%D0%BC%D0%B8.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/026. Jevile - Временами.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Kalvados/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F027.%20Kalvados%20-%20What's%20Up.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/027. Kalvados - What's Up.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Kanii%20feat.%20Riovaz%20%26%20Nimstarr/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F028.%20Kanii%20feat.%20Riovaz%20%26%20Nimstarr%20-%20Diva.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/028. Kanii feat. Riovaz & Nimstarr - Diva.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Kaskader/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F029.%20Kaskader%20-%20%D0%A1%D1%82%D1%80%D0%BE%D0%B1%D0%BE%D1%81%D0%BA%D0%BE%D0%BF.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/029. Kaskader - Стробоскоп.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Kim%20Petras/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F030.%20Kim%20Petras%20-%20Ein%20Tausend%20Teile.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/030. Kim Petras - Ein Tausend Teile.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Kllin%20feat.%20Bartiz/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F031.%20Kllin%20feat.%20Bartiz%20-%20USA%20%D0%93%D0%B5%D1%80%D0%BB.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/031. Kllin feat. Bartiz - USA Герл.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Knox/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F032.%20Knox%20-%20Me%2C%20Myself%20%26%20Your%20Eyes.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/032. Knox - Me, Myself & Your Eyes.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=KSON/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F033.%20KSON%20-%20%D0%9D%D0%BE%D1%87%D1%8C%20%D0%A1%20%D0%A2%D0%BE%D0%B1%D0%BE%D0%B9.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/033. KSON - Ночь С Тобой.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Loafers%20feat.%20One%20Trick%20Pony/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F034.%20Loafers%20feat.%20One%20Trick%20Pony%20-%20Where%20Are%20You%20Now.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/034. Loafers feat. One Trick Pony - Where Are You Now.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=lonov/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F035.%20lonov%20-%20%D0%9D%D0%B0%20%D0%A7%D0%B5%D1%80%D0%BD%D0%BE%D0%B9%20%D0%9B%D0%B0%D0%B4%D0%B5.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/035. lonov - На Черной Ладе.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Lustova/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F036.%20Lustova%20-%20%D0%91%D0%B5%D0%BB%D0%BE%D0%B9%20%D0%92%D1%8C%D1%8E%D0%B3%D0%BE%D0%B9.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/036. Lustova - Белой Вьюгой.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Maksum%20Everest/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F037.%20Maksum%20Everest%20-%20%D0%94%D0%B5%D0%B2%D0%BE%D1%87%D0%BA%D0%B0%20%D0%97%D0%B0%D0%B3%D0%B0%D0%B4%D0%BA%D0%B0.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/037. Maksum Everest - Девочка Загадка.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Medkova%20feat.%20Alex%20Sed/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F038.%20Medkova%20feat.%20Alex%20Sed%20-%20%D0%94%D1%83%D1%88%D0%B0%20%D0%9F%D0%BE%D0%B5%D1%82.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/038. Medkova feat. Alex Sed - Душа Поет.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Michael%20Brun%20feat.%20Naika/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F039.%20Michael%20Brun%20feat.%20Naika%20-%20Agua.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/039. Michael Brun feat. Naika - Agua.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=MIDARA/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F040.%20MIDARA%20-%20%D0%92%D0%B5%D1%87%D0%BD%D0%BE%D1%81%D1%82%D1%8C.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/040. MIDARA - Вечность.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Miki-Glyba/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F041.%20Miki-Glyba%20-%20%D0%9F%D1%80%D0%B8%D0%BD%D1%86%D0%B5%D1%81%D1%81%D0%B0.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/041. Miki-Glyba - Принцесса.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Milan%20Gavris%20feat.%20Zadi/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F042.%20Milan%20Gavris%20feat.%20Zadi%20-%20Wild%20Lov3%20(Radio%20Edit).mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/042. Milan Gavris feat. Zadi - Wild Lov3 (Radio Edit).mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Milen/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F043.%20Milen%20-%20%D0%AF%20%D0%A6%D0%B5%D0%BB%D1%83%D1%8E%20%D0%A2%D0%B2%D0%BE%D0%B8%20%D0%A0%D1%83%D0%BA%D0%B8.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/043. Milen - Я Целую Твои Руки.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Milly/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F044.%20Milly%20-%20%D0%AF%D0%B4.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/044. Milly - Яд.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=MOSOVICH%20feat.%20Batrai/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F045.%20MOSOVICH%20feat.%20Batrai%20-%20%D0%9F%D0%B5%D1%80%D0%B5%D0%B3%D1%80%D0%B5%D0%B2%D0%B0%D0%BB%D0%B0%D1%81%D1%8C%20%D0%93%D0%BE%D0%BB%D0%BE%D0%B2%D0%B0.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/045. MOSOVICH feat. Batrai - Перегревалась Голова.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Moyak/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F046.%20Moyak%20-%20%D0%94%D1%8B%D0%BC.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/046. Moyak - Дым.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=MSeven/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F047.%20MSeven%20-%20%D0%A2%D1%8B%20%D0%A2%D0%B0%D0%BA%20%D0%9A%D1%80%D0%B0%D1%81%D0%B8%D0%B2%D0%B0.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/047. MSeven - Ты Так Красива.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=MUJEVA/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F048.%20MUJEVA%20-%20%D0%97%D0%B0%D1%87%D0%B5%D0%BC%20%D0%A2%D1%8B%20%D0%92%D1%80%D0%B5%D1%88%D1%8C.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/048. MUJEVA - Зачем Ты Врешь.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=NiceVi/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F049.%20NiceVi%20-%20%D0%9F%D1%80%D0%BE%D1%81%D1%82%D0%B0%D1%8F.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/049. NiceVi - Простая.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Nizam/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F050.%20Nizam%20-%20%D0%9B%D1%83%D1%87%D1%88%D0%B8%D0%B9%20%D0%A1%D0%BE%D1%80%D1%82.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/050. Nizam - Лучший Сорт.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Paul%20Damixie%20feat.%20April%20Bender/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F051.%20Paul%20Damixie%20feat.%20April%20Bender%20-%20Dumb.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/051. Paul Damixie feat. April Bender - Dumb.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Playmen%20%26%20Valeron%20feat.%20Klavdia/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F052.%20Playmen%20%26%20Valeron%20feat.%20Klavdia%20-%20Touch%20Me%20(Radio%20Edit).mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/052. Playmen & Valeron feat. Klavdia - Touch Me (Radio Edit).mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Prorokov%20feat.%20Tinoki/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F053.%20Prorokov%20feat.%20Tinoki%20-%20%D0%9D%D0%BE%D1%87%D1%8C.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/053. Prorokov feat. Tinoki - Ночь.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Rachel%20Chinouriri/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F054.%20Rachel%20Chinouriri%20-%20What%20A%20Devastating%20Turn%20Of%20Events.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/054. Rachel Chinouriri - What A Devastating Turn Of Events.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Rekudo/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F055.%20Rekudo%20-%20%D0%97%D0%B0%D0%B2%D0%BE%D0%B6%D1%83%20%D0%95%D0%B5.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/055. Rekudo - Завожу Ее.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=ROBBI/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F056.%20ROBBI%20-%20%D0%90%D1%83%D1%80%D0%B8%D0%BC.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/056. ROBBI - Аурим.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=STRCTRE/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F057.%20STRCTRE%20-%20%D0%9F%D0%BE%D0%B3%D0%B8%D0%B1%D0%B0%D0%B9.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/057. STRCTRE - Погибай.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Tanir%20%26%20Tyomcha/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F058.%20Tanir%20%26%20Tyomcha%20-%20%D0%AF%20%D0%9D%D0%B0%D0%B9%D0%B4%D1%83%20%D0%A2%D0%B5%D0%B1%D1%8F.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/058. Tanir & Tyomcha - Я Найду Тебя.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Tatar%20feat.%20Roully/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F059.%20Tatar%20feat.%20Roully%20-%20%D0%94%D0%B0%D0%BB-%D0%94%D0%B0%D0%BB.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/059. Tatar feat. Roully - Дал-Дал.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=TIMZ/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F060.%20TIMZ%20-%20%D0%9F%D0%BE%D0%B3%D0%BE%D0%B2%D0%BE%D1%80%D0%B8%20%D0%A1%D0%BE%20%D0%9C%D0%BD%D0%BE%D0%B9.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/060. TIMZ - Поговори Со Мной.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=TypeLuv/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F061.%20TypeLuv%20-%20%D0%94%D0%B5%D0%B2%D0%BE%D1%87%D0%BA%D0%B0.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/061. TypeLuv - Девочка.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Vadim%20Adamov%20feat.%20Hardphol%20%26%20Alena%20Roxis/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F062.%20Vadim%20Adamov%20feat.%20Hardphol%20%26%20Alena%20Roxis%20-%20Wrecking%20Ball.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/062. Vadim Adamov feat. Hardphol & Alena Roxis - Wrecking Ball.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=VAVAN/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F063.%20VAVAN%20-%20%D0%91%D0%B0%D1%82%D1%83%D1%82.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/063. VAVAN - Батут.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Victoria/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F064.%20Victoria%20-%20Paradox.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/064. Victoria - Paradox.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=WHITE%20GALLOWS/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F065.%20WHITE%20GALLOWS%20-%20%D0%9C%D0%B0%D1%8F%D0%BA.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/065. WHITE GALLOWS - Маяк.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Xassa/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F066.%20Xassa%20-%20%D0%91%D0%B0%D1%81%20%D0%A3%D0%B1%D0%B8%D0%B2%D0%B0%D0%B5%D1%82%20%D0%92%20%D0%A5%D0%B0%D1%82%D0%B5.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/066. Xassa - Бас Убивает В Хате.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Yngluv/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F067.%20Yngluv%20-%20%D0%9D%D0%B5%D0%BD%D0%BE%D1%80%D0%BC%D0%B0%D0%BB%D1%8C%D0%BD%D0%B0%D1%8F.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/067. Yngluv - Ненормальная.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%90%D0%9A%D0%A3%D0%9B%D0%98%D0%A7%20feat.%20%D0%9C%D0%BE%D0%BB%D0%BE%D0%B4%D0%BE%D0%B9%20%D0%9F%D0%BB%D0%B0%D1%82%D0%BE%D0%BD/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F068.%20%D0%90%D0%9A%D0%A3%D0%9B%D0%98%D0%A7%20feat.%20%D0%9C%D0%BE%D0%BB%D0%BE%D0%B4%D0%BE%D0%B9%20%D0%9F%D0%BB%D0%B0%D1%82%D0%BE%D0%BD%20-%20%D0%9F%D0%BE%D0%B4%D0%B0%D1%80%D0%BE%D0%BA.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/068. АКУЛИЧ feat. Молодой Платон - Подарок.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%90%D0%BB%D0%B5%D0%BA%D1%81%D0%B0%D0%BD%D0%B4%D1%80%20%D0%A8%D0%BE%D1%83%D0%B0%20feat.%20%D0%9D%D0%B5%D0%BF%D0%B0%D1%80%D0%B0/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F069.%20%D0%90%D0%BB%D0%B5%D0%BA%D1%81%D0%B0%D0%BD%D0%B4%D1%80%20%D0%A8%D0%BE%D1%83%D0%B0%20feat.%20%D0%9D%D0%B5%D0%BF%D0%B0%D1%80%D0%B0%20-%20%D0%9F%D1%80%D0%B8%D1%82%D1%8F%D0%B6%D0%B5%D0%BD%D0%B8%D0%B5.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/069. Александр Шоуа feat. Непара - Притяжение.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%90%D0%BB%D0%B5%D0%BD%D0%B0%20%D0%A0%D0%BE%D1%81%D1%81/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F070.%20%D0%90%D0%BB%D0%B5%D0%BD%D0%B0%20%D0%A0%D0%BE%D1%81%D1%81%20-%20%D0%9D%D0%B0%D0%B2%D1%81%D1%82%D1%80%D0%B5%D1%87%D1%83%20%D0%92%D0%B5%D1%82%D1%80%D1%83.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/070. Алена Росс - Навстречу Ветру.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%90%D0%BB%D0%B5%D0%BD%D0%BE%D1%87%D0%BA%D0%B0/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F071.%20%D0%90%D0%BB%D0%B5%D0%BD%D0%BE%D1%87%D0%BA%D0%B0%20-%20%D0%9C%D0%B8%D0%BD%D1%83%D1%81%20%D0%A1%D1%82%D0%BE.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/071. Аленочка - Минус Сто.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%90%D0%BB%D0%B8%D1%81%D0%B0%20%D0%9A%D0%B5%D1%87%D0%BA%D0%B8%D0%BD%D0%B0/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F072.%20%D0%90%D0%BB%D0%B8%D1%81%D0%B0%20%D0%9A%D0%B5%D1%87%D0%BA%D0%B8%D0%BD%D0%B0%20-%20%D0%AF%20%D0%9E%D1%82%D0%BF%D1%83%D1%81%D0%BA%D0%B0%D1%8E%20%D0%A2%D0%B5%D0%B1%D1%8F.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/072. Алиса Кечкина - Я Отпускаю Тебя.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Dono./Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F073.%20Dono.%20-%20%D0%94%D1%8B%D1%88%D0%B8%D0%BC%20%D0%AD%D0%BD%D0%B4%D0%BE%D1%80%D1%84%D0%B8%D0%BD%D0%B0%D0%BC%D0%B8.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/073. Dono. - Дышим Эндорфинами.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%91%D0%B8%D0%BF%D0%BE%D0%BB%D1%8F%D1%80%D0%BA%D0%B0/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F074.%20%D0%91%D0%B8%D0%BF%D0%BE%D0%BB%D1%8F%D1%80%D0%BA%D0%B0%20-%20%D0%A2%D0%BE%D1%87%D0%BA%D0%B0.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/074. Биполярка - Точка.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%92%D0%B0%D1%81%D1%8F%20%D0%9E%D0%B1%D0%BB%D0%BE%D0%BC%D0%BE%D0%B2/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F075.%20%D0%92%D0%B0%D1%81%D1%8F%20%D0%9E%D0%B1%D0%BB%D0%BE%D0%BC%D0%BE%D0%B2%20-%20%D0%A2%D0%B5%D0%BF%D0%B5%D1%80%D1%8C%20%D0%94%D0%B0%D0%BB%D0%B5%D0%BA%D0%BE%20%D0%9E%D1%82%D1%81%D1%8E%D0%B4%D0%B0.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/075. Вася Обломов - Теперь Далеко Отсюда.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%92%D0%BB%D0%B0%D0%B4%20%D0%9F%D1%80%D0%BE%D1%85%D0%BE%D1%80%D0%BE%D0%B2/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F076.%20%D0%92%D0%BB%D0%B0%D0%B4%20%D0%9F%D1%80%D0%BE%D1%85%D0%BE%D1%80%D0%BE%D0%B2%20-%20%D0%A7%D1%83%D0%B6%D0%B8%D0%B5%20%D0%93%D1%83%D0%B1%D1%8B.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/076. Влад Прохоров - Чужие Губы.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%92%D1%8F%D1%87%D0%B5%D1%81%D0%BB%D0%B0%D0%B2%20%D0%9C%D0%B0%D0%BA%D0%B0%D1%80%D0%BE%D0%B2/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F077.%20%D0%92%D1%8F%D1%87%D0%B5%D1%81%D0%BB%D0%B0%D0%B2%20%D0%9C%D0%B0%D0%BA%D0%B0%D1%80%D0%BE%D0%B2%20-%20%D0%91%D0%B5%D0%B3%D0%B8.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/077. Вячеслав Макаров - Беги.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%94%D0%B5%D0%BC%D0%B8%D0%B4%D1%8B%D1%87/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F078.%20%D0%94%D0%B5%D0%BC%D0%B8%D0%B4%D1%8B%D1%87%20-%20%D0%94%D1%8F%D0%B4%D1%8F%20%D0%A4%D0%B5%D0%B4%D1%8F.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/078. Демидыч - Дядя Федя.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%94%D0%B5%D0%BD%D0%B8%D1%81%20%D0%9E%D0%BA%D0%B5%D0%B0%D0%BD/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F079.%20%D0%94%D0%B5%D0%BD%D0%B8%D1%81%20%D0%9E%D0%BA%D0%B5%D0%B0%D0%BD%20-%20%D0%A1%D1%87%D0%B0%D1%81%D1%82%D0%BB%D0%B8%D0%B2%D1%8B%D0%B9%20%D0%9F%D0%B0%D1%86%D0%B0%D0%BD.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/079. Денис Океан - Счастливый Пацан.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%94%D0%B8%D0%BB%D0%B5%D0%BE%D0%BD%20feat.%20Yung%20Breezy/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F080.%20%D0%94%D0%B8%D0%BB%D0%B5%D0%BE%D0%BD%20feat.%20Yung%20Breezy%20-%20%D0%9D%D0%B5%20%D0%9C%D0%BE%D1%8F.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/080. Дилеон feat. Yung Breezy - Не Моя.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%94%D0%B8%D0%BC%D0%B0%20%D0%A4%D0%B0%D0%BD%D1%82%D0%BE%D0%BC%D0%B0%D1%81/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F081.%20%D0%94%D0%B8%D0%BC%D0%B0%20%D0%A4%D0%B0%D0%BD%D1%82%D0%BE%D0%BC%D0%B0%D1%81%20-%20%D0%9A%D0%BE%D0%B3%D0%B4%D0%B0%20%D0%92%D0%BB%D1%8E%D0%B1%D0%BB%D0%B5%D0%BD%D0%BD%D1%8B%D1%85%20%D0%9C%D0%BD%D0%BE%D0%B3%D0%BE.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/081. Дима Фантомас - Когда Влюбленных Много.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%94%D0%BC%D0%B8%D1%82%D1%80%D0%B8%D0%B9%20%D0%91%D1%83%D0%B4%D0%B8%D0%BC%D0%B8%D1%80/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F082.%20%D0%94%D0%BC%D0%B8%D1%82%D1%80%D0%B8%D0%B9%20%D0%91%D1%83%D0%B4%D0%B8%D0%BC%D0%B8%D1%80%20-%20%D0%9E%D1%82%D0%BF%D1%83%D1%81%D1%82%D0%B8%D1%82%D0%B5%20%D0%9F%D0%B0%D1%86%D0%B0%D0%BD%D0%BE%D0%B2.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/082. Дмитрий Будимир - Отпустите Пацанов.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%94%D0%BC%D0%B8%D1%82%D1%80%D0%B8%D0%B9%20%D0%93%D0%BE%D0%BB%D0%B4/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F083.%20%D0%94%D0%BC%D0%B8%D1%82%D1%80%D0%B8%D0%B9%20%D0%93%D0%BE%D0%BB%D0%B4%20-%20%D0%AF%20%D0%9E%D0%B4%D0%B8%D0%BD.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/083. Дмитрий Голд - Я Один.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%94%D0%BC%D0%B8%D1%82%D1%80%D0%B8%D0%B9%20%D0%9A%D1%83%D0%B1%D0%B0%D1%81%D0%BE%D0%B2/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F084.%20%D0%94%D0%BC%D0%B8%D1%82%D1%80%D0%B8%D0%B9%20%D0%9A%D1%83%D0%B1%D0%B0%D1%81%D0%BE%D0%B2%20-%20%D0%94%D0%B5%D0%B2%D0%BE%D1%87%D0%BA%D0%B0%20%D0%A1%20%D0%A1%D0%BE%D1%81%D0%B5%D0%B4%D0%BD%D0%B5%D0%B9%20%D0%A3%D0%BB%D0%B8%D1%86%D1%8B.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/084. Дмитрий Кубасов - Девочка С Соседней Улицы.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%95%D0%B2%D0%B3%D0%B5%D0%BD%D0%B8%D0%B9%20%D0%9F%D1%83%D1%82%D0%B8%D0%BB%D0%BE%D0%B2/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F085.%20%D0%95%D0%B2%D0%B3%D0%B5%D0%BD%D0%B8%D0%B9%20%D0%9F%D1%83%D1%82%D0%B8%D0%BB%D0%BE%D0%B2%20-%20%D0%A2%D1%8B%20%D0%A0%D0%B0%D0%B7%D0%B1%D0%B8%D0%BB%D0%B0%20%D0%92%D1%81%D0%B5%20%D0%9C%D0%BE%D0%B8%20%D0%9C%D0%B5%D1%87%D1%82%D1%8B.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/085. Евгений Путилов - Ты Разбила Все Мои Мечты.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%97%D0%B0%D0%B2%D1%82%D1%80%D0%B0%20%D0%91%D1%80%D0%BE%D1%88%D1%83%20feat.%20%D0%9D%D1%8E%D0%B4%D1%81%20%D0%91%D0%BE%D0%B9/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F086.%20%D0%97%D0%B0%D0%B2%D1%82%D1%80%D0%B0%20%D0%91%D1%80%D0%BE%D1%88%D1%83%20feat.%20%D0%9D%D1%8E%D0%B4%D1%81%20%D0%91%D0%BE%D0%B9%20-%20%D0%93%D1%80%D1%83%D1%81%D1%82%D0%BD%D0%B0%D1%8F.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/086. Завтра Брошу feat. Нюдс Бой - Грустная.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%98%D0%B3%D0%BE%D1%80%D1%8C%20%D0%90%D0%BF%D1%80%D0%B5%D1%81%D0%BE%D0%B2/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F087.%20%D0%98%D0%B3%D0%BE%D1%80%D1%8C%20%D0%90%D0%BF%D1%80%D0%B5%D1%81%D0%BE%D0%B2%20-%20%D0%94%D0%BB%D1%8F%20%D0%A2%D0%B5%D0%B1%D1%8F%20%D0%9E%D0%B4%D0%BD%D0%BE%D0%B9.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/087. Игорь Апресов - Для Тебя Одной.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%98%D0%B3%D0%BE%D1%80%D1%8C%20%D0%9A%D0%B8%D0%B1%D0%B8%D1%80%D0%B5%D0%B2/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F088.%20%D0%98%D0%B3%D0%BE%D1%80%D1%8C%20%D0%9A%D0%B8%D0%B1%D0%B8%D1%80%D0%B5%D0%B2%20-%20%D0%A1%D0%BA%D0%B0%D0%B6%D0%B8%20%D0%9C%D0%BD%D0%B5.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/088. Игорь Кибирев - Скажи Мне.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%98%D0%B3%D0%BE%D1%80%D1%8C%20%D0%9B%D0%B0%D1%82%D1%8B%D1%88%D0%BA%D0%BE/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F089.%20%D0%98%D0%B3%D0%BE%D1%80%D1%8C%20%D0%9B%D0%B0%D1%82%D1%8B%D1%88%D0%BA%D0%BE%20-%20%D0%A2%D0%BE%D0%BB%D1%8C%D0%BA%D0%BE%20%D0%9E%20%D0%A2%D0%B5%D0%B1%D0%B5.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/089. Игорь Латышко - Только О Тебе.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%9A%D0%B5%D1%82%D1%81%D0%B0%D0%BB%D1%8C/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F090.%20%D0%9A%D0%B5%D1%82%D1%81%D0%B0%D0%BB%D1%8C%20-%20%D0%92%D0%BB%D1%8E%D0%B1%D0%B8%D0%BB%D1%81%D1%8F%20%D0%92%20%D0%94%D0%B5%D0%B2%D1%87%D0%BE%D0%BD%D0%BA%D1%83.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/090. Кетсаль - Влюбился В Девчонку.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%9B%D0%B8%D0%BB%D0%B0%D1%8F/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F091.%20%D0%9B%D0%B8%D0%BB%D0%B0%D1%8F%20-%20%D0%9C%D0%B5%D1%82%D0%B5%D0%BB%D1%8C.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/091. Лилая - Метель.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%9C%D0%B0%D1%80%D0%B0%D1%82%20%D0%9F%D0%B0%D1%88%D0%B0%D1%8F%D0%BD%20feat.%20%D0%90%D1%80%D0%BD%D0%B8%20%D0%9F%D0%B0%D1%88%D0%B0%D1%8F%D0%BD/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F092.%20%D0%9C%D0%B0%D1%80%D0%B0%D1%82%20%D0%9F%D0%B0%D1%88%D0%B0%D1%8F%D0%BD%20feat.%20%D0%90%D1%80%D0%BD%D0%B8%20%D0%9F%D0%B0%D1%88%D0%B0%D1%8F%D0%BD%20-%20%D0%94%D0%B5%D0%BD%D0%B5%D0%B3%20%D0%9D%D0%B5%D1%82.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/092. Марат Пашаян feat. Арни Пашаян - Денег Нет.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%9C%D0%B5%D1%81%D1%82%D0%BD%D1%8B%D0%B9%20feat.%20Darom%20Dabro/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F093.%20%D0%9C%D0%B5%D1%81%D1%82%D0%BD%D1%8B%D0%B9%20feat.%20Darom%20Dabro%20-%20%D0%97%D0%B0%20%D0%93%D0%BE%D1%80%D0%BB%D0%BE.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/093. Местный feat. Darom Dabro - За Горло.mp3 May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand stop took 48 milliseconds May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:41 volumio volumio[1365]: info: May 25 11:38:41 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:41 volumio volumio[1365]: info: May 25 11:38:41 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:41 volumio volumio[1365]: info: May 25 11:38:41 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%9C%D0%B8%D0%BB%D0%B0%20%D0%98%D0%BA%D0%BE%D0%BD%D1%81%D0%BA%D0%B0%D1%8F/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F094.%20%D0%9C%D0%B8%D0%BB%D0%B0%20%D0%98%D0%BA%D0%BE%D0%BD%D1%81%D0%BA%D0%B0%D1%8F%20-%20%D0%9D%D0%B8%20%D0%9E%20%D0%A7%D0%B5%D0%BC%20%D0%9D%D0%B5%20%D0%96%D0%B0%D0%BB%D0%B5%D0%B9.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/094. Мила Иконская - Ни О Чем Не Жалей.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%9C%D0%98%D0%A0/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F095.%20%D0%9C%D0%98%D0%A0%20-%20%D0%A1%D1%82%D1%80%D0%B0%D1%88%D0%BD%D0%BE%D0%B5%20%D0%9B%D0%B8%D1%86%D0%BE.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/095. МИР - Страшное Лицо.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%9C%D0%B8%D1%85%D0%B0%D0%B8%D0%BB%20%D0%97%D0%B0%D0%B4%D0%BE%D1%80%D0%B8%D0%BD/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F096.%20%D0%9C%D0%B8%D1%85%D0%B0%D0%B8%D0%BB%20%D0%97%D0%B0%D0%B4%D0%BE%D1%80%D0%B8%D0%BD%20-%20%D0%94%D0%B2%D0%B0%20%D0%A1%D0%B5%D1%80%D0%B4%D1%86%D0%B0.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/096. Михаил Задорин - Два Сердца.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%9D%D0%B0%D0%B4%D1%8F%20%D0%9C%D0%B0%D0%B9%D0%B5%D1%80/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F097.%20%D0%9D%D0%B0%D0%B4%D1%8F%20%D0%9C%D0%B0%D0%B9%D0%B5%D1%80%20-%20%D0%92%D1%80%D0%B5%D0%BC%D0%B5%D0%BD%D0%B8%20%D0%9D%D0%B5%D1%82.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/097. Надя Майер - Времени Нет.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%9D%D0%B0%D1%88%D0%B5%20%D0%9F%D0%BE%D1%81%D0%BB%D0%B5%D0%B4%D0%BD%D0%B5%D0%B5%20%D0%9B%D0%B5%D1%82%D0%BE/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F098.%20%D0%9D%D0%B0%D1%88%D0%B5%20%D0%9F%D0%BE%D1%81%D0%BB%D0%B5%D0%B4%D0%BD%D0%B5%D0%B5%20%D0%9B%D0%B5%D1%82%D0%BE%20-%20%D0%91%D0%B5%D0%B7%20%D0%A2%D0%B5%D0%B1%D1%8F.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/098. Наше Последнее Лето - Без Тебя.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%9E%D0%BA%D1%81%D0%B0%D0%BD%D0%B0%20%D0%98%D0%B2%D0%B0%D1%89%D1%83%D0%BA/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F099.%20%D0%9E%D0%BA%D1%81%D0%B0%D0%BD%D0%B0%20%D0%98%D0%B2%D0%B0%D1%89%D1%83%D0%BA%20-%20%D0%9E%D0%B9%20%D0%9E%D0%B9%20%D0%9E%D0%B9.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/099. Оксана Иващук - Ой Ой Ой.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%9F%D0%BE%D0%BB%D0%B8%D0%BA%D0%B0%D1%80%D0%BF%D0%BE%D0%B2/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F100.%20%D0%9F%D0%BE%D0%BB%D0%B8%D0%BA%D0%B0%D1%80%D0%BF%D0%BE%D0%B2%20-%20%D0%9A%D0%B0%D0%BF%D0%BA%D0%B0%D0%BD.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/100. Поликарпов - Капкан.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%A0%D0%B0%D1%80%D0%B8%D1%82%D0%B8/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F101.%20%D0%A0%D0%B0%D1%80%D0%B8%D1%82%D0%B8%20-%20%D0%92%D0%B7%D1%80%D0%BE%D1%81%D0%BB%D1%8B%D0%B5%20%D0%94%D0%B5%D1%82%D0%B8.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/101. Рарити - Взрослые Дети.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%A1%D0%B5%D1%80%D0%B3%D0%B5%D0%B9%20%D0%9A%D0%BB%D1%83%D1%88%D0%B8%D0%BD/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F102.%20%D0%A1%D0%B5%D1%80%D0%B3%D0%B5%D0%B9%20%D0%9A%D0%BB%D1%83%D1%88%D0%B8%D0%BD%20-%20%D0%A1%D1%80%D0%B5%D0%B4%D0%B8%20%D0%9A%D0%BE%D0%BB%D1%8E%D1%87%D0%B8%D1%85%20%D0%9B%D0%B0%D0%B3%D0%B5%D1%80%D0%B5%D0%B9.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/102. Сергей Клушин - Среди Колючих Лагерей.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%A2%D0%B0%D1%82%D1%8C%D1%8F%D0%BD%D0%B0%20%D0%91%D1%83%D0%BB%D0%B0%D0%BD%D0%BE%D0%B2%D0%B0/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F103.%20%D0%A2%D0%B0%D1%82%D1%8C%D1%8F%D0%BD%D0%B0%20%D0%91%D1%83%D0%BB%D0%B0%D0%BD%D0%BE%D0%B2%D0%B0%20-%20%D0%A2%D1%80%D0%BE%D0%B3%D0%B0%D1%82%D0%B5%D0%BB%D1%8C%D0%BD%D0%BE.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/103. Татьяна Буланова - Трогательно.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%A2%D0%B5%D1%81%D1%82%D0%BE%D1%81%D1%82%D0%B5%D1%80%D0%BE%D0%B2%D0%B8%D1%87%20feat.%20Bolin/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F104.%20%D0%A2%D0%B5%D1%81%D1%82%D0%BE%D1%81%D1%82%D0%B5%D1%80%D0%BE%D0%B2%D0%B8%D1%87%20feat.%20Bolin%20-%20%D0%9C%D0%BE%D0%BB%D0%BE%D0%B4%D0%BE%D1%81%D1%82%D1%8C%20%D0%92%D1%81%D0%B5%20%D0%9F%D1%80%D0%BE%D1%81%D1%82%D0%B8%D1%82.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/104. Тестостерович feat. Bolin - Молодость Все Простит.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%A2%D0%B8%D0%BC%D1%83%D1%80%20Timbigfamily%20feat.%20Crouch/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F105.%20%D0%A2%D0%B8%D0%BC%D1%83%D1%80%20Timbigfamily%20feat.%20Crouch%20-%20%D0%A1%D0%BB%D0%BE%D0%B2%D0%BE%20%D0%9F%D0%B0%D1%86%D0%B0%D0%BD%D0%B0.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/105. Тимур Timbigfamily feat. Crouch - Слово Пацана.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%A5%D0%B0%D0%B9%D0%B2%D0%B8/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F106.%20%D0%A5%D0%B0%D0%B9%D0%B2%D0%B8%20-%20%D0%90%D0%B4%D1%80%D0%B5%D0%BD%D0%B0%D0%BB%D0%B8%D0%BD.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/106. Хайви - Адреналин.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%AD%D0%BB%D0%BB%D0%B0%D0%B8/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F107.%20%D0%AD%D0%BB%D0%BB%D0%B0%D0%B8%20-%20%D0%9C%D0%B0%D1%8F%D0%BA.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/107. Эллаи - Маяк.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%AE%D0%BB%D0%B8%D1%8F%20%D0%A0%D0%B0%D0%B9%D0%BD%D0%B5%D1%80/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F108.%20%D0%AE%D0%BB%D0%B8%D1%8F%20%D0%A0%D0%B0%D0%B9%D0%BD%D0%B5%D1%80%20-%20%D0%9F%D0%B0%D0%B4%D0%B0%D0%B9%20%D0%92%20%D0%9C%D0%BE%D1%8E%20%D0%9B%D1%8E%D0%B1%D0%BE%D0%B2%D1%8C.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/108. Юлия Райнер - Падай В Мою Любовь.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=%D0%AF%D0%BD%D0%B0%20%D0%92%D0%B0%D0%B9%D0%BD%D0%BE%D0%B2%D1%81%D0%BA%D0%B0%D1%8F/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F109.%20%D0%AF%D0%BD%D0%B0%20%D0%92%D0%B0%D0%B9%D0%BD%D0%BE%D0%B2%D1%81%D0%BA%D0%B0%D1%8F%20-%20%D0%9D%D0%B5%20%D0%9C%D0%BE%D1%8F%20%D0%97%D0%B8%D0%BC%D0%B0.mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/109. Яна Вайновская - Не Моя Зима.mp3 May 25 11:38:41 volumio volumio[1365]: info: ALBUMART /albumart?cacheid=642&web=Accept/Music%20News%20vol.346/extralarge&path=%2Fmnt%2FINTERNAL%2FVA%20-%20Music%20News%20vol.346%2F110.%20Accept%20-%20Humanoid%20(Bonus%20Track).mp3&metadata=false May 25 11:38:41 volumio volumio[1365]: info: URI /mnt/INTERNAL/VA - Music News vol.346/110. Accept - Humanoid (Bonus Track).mp3 May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand status took 17 milliseconds May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand status took 16 milliseconds May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand status took 16 milliseconds May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand status took 16 milliseconds May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushQueue May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::saveQueue May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::updateTrackBlock May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrackBlock May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPlay May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::play index 0 May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::stop May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::play index undefined May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::startPlaybackTimer May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/VA - Music News vol.346/001. Анна Семенович - Гладиолусы.mp3 May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand stop May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 16 milliseconds May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 16 milliseconds May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 16 milliseconds May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 15 milliseconds May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand stop took 3 milliseconds May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand clear May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Post Malone feat. 21 Savage - Rockstar","artist":"Radio BEST","album":null,"uri":"http://nashe1.hostingradio.ru/best-256","trackType":""} May 25 11:38:41 volumio volumio[1365]: verbose: CURRENT POSITION 0 May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState stateService stop May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: No code May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Post Malone feat. 21 Savage - Rockstar","artist":"Radio BEST","album":null,"uri":"http://nashe1.hostingradio.ru/best-256","trackType":""} May 25 11:38:41 volumio volumio[1365]: verbose: CURRENT POSITION 0 May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState stateService stop May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: No code May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Post Malone feat. 21 Savage - Rockstar","artist":"Radio BEST","album":null,"uri":"http://nashe1.hostingradio.ru/best-256","trackType":""} May 25 11:38:41 volumio volumio[1365]: verbose: CURRENT POSITION 0 May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState stateService stop May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: No code May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Post Malone feat. 21 Savage - Rockstar","artist":"Radio BEST","album":null,"uri":"http://nashe1.hostingradio.ru/best-256","trackType":""} May 25 11:38:41 volumio volumio[1365]: verbose: CURRENT POSITION 0 May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState stateService stop May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: No code May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: ------------------------------ 75ms May 25 11:38:41 volumio volumio[1365]: info: ------------------------------ 45ms May 25 11:38:41 volumio volumio[1365]: info: ------------------------------ 44ms May 25 11:38:41 volumio volumio[1365]: info: ------------------------------ 44ms May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 25 11:38:41 volumio volumio[1365]: info: touch_display: Setting screensaver timeout to 3600 seconds. May 25 11:38:41 volumio volumio[1365]: info: May 25 11:38:41 volumio volumio[1365]: ---------------------------- MPD announces system playlist update May 25 11:38:41 volumio volumio[1365]: info: Ignoring MPD Status Update May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand clear took 40 milliseconds May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/VA - Music News vol.346/001. Анна Семенович - Гладиолусы.mp3" May 25 11:38:41 volumio volumio[1365]: info: May 25 11:38:41 volumio volumio[1365]: ---------------------------- MPD announces system playlist update May 25 11:38:41 volumio volumio[1365]: info: Ignoring MPD Status Update May 25 11:38:41 volumio volumio[1365]: info: May 25 11:38:41 volumio volumio[1365]: ---------------------------- MPD announces system playlist update May 25 11:38:41 volumio volumio[1365]: info: Ignoring MPD Status Update May 25 11:38:41 volumio volumio[1365]: info: May 25 11:38:41 volumio volumio[1365]: ---------------------------- MPD announces system playlist update May 25 11:38:41 volumio volumio[1365]: info: Ignoring MPD Status Update May 25 11:38:41 volumio volumio[1365]: error: updateQueue error: null May 25 11:38:41 volumio volumio[1365]: info: May 25 11:38:41 volumio volumio[1365]: ---------------------------- MPD announces system playlist update May 25 11:38:41 volumio volumio[1365]: info: Ignoring MPD Status Update May 25 11:38:41 volumio volumio[1365]: info: ------------------------------ 3ms May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand add "INTERNAL/VA - Music News vol.346/001. Анна Семенович - Гладиолусы.mp3" took 2 milliseconds May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand play May 25 11:38:41 volumio volumio[1365]: info: ------------------------------ 4ms May 25 11:38:41 volumio volumio[1365]: info: ------------------------------ 3ms May 25 11:38:41 volumio volumio[1365]: info: ------------------------------ 3ms May 25 11:38:41 volumio volumio[1365]: info: ------------------------------ 2ms May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand play took 1 milliseconds May 25 11:38:41 volumio volumio[1365]: info: May 25 11:38:41 volumio volumio[1365]: ---------------------------- MPD announces system playlist update May 25 11:38:41 volumio volumio[1365]: info: Ignoring MPD Status Update May 25 11:38:41 volumio volumio[1365]: info: May 25 11:38:41 volumio volumio[1365]: ---------------------------- MPD announces system playlist update May 25 11:38:41 volumio volumio[1365]: info: Ignoring MPD Status Update May 25 11:38:41 volumio volumio[1365]: info: May 25 11:38:41 volumio volumio[1365]: ---------------------------- MPD announces system playlist update May 25 11:38:41 volumio volumio[1365]: info: Ignoring MPD Status Update May 25 11:38:41 volumio volumio[1365]: info: May 25 11:38:41 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:41 volumio volumio[1365]: info: ------------------------------ 8ms May 25 11:38:41 volumio volumio[1365]: info: ------------------------------ 7ms May 25 11:38:41 volumio volumio[1365]: info: ------------------------------ 7ms May 25 11:38:41 volumio volumio[1365]: info: May 25 11:38:41 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:41 volumio volumio[1365]: info: May 25 11:38:41 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:41 volumio volumio[1365]: info: May 25 11:38:41 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:41 volumio volumio[1365]: info: May 25 11:38:41 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand status took 6 milliseconds May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand status took 5 milliseconds May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand status took 3 milliseconds May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand status took 1 milliseconds May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:41 volumio volumio[1365]: info: May 25 11:38:41 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:41 volumio volumio[1365]: info: May 25 11:38:41 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:41 volumio volumio[1365]: info: May 25 11:38:41 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand status took 7 milliseconds May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 6 milliseconds May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 6 milliseconds May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 6 milliseconds May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 6 milliseconds May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Гладиолусы","artist":"Анна Семенович","album":"Music News vol.346","uri":"INTERNAL/VA - Music News vol.346/001. Анна Семенович - Гладиолусы.mp3","trackType":"mp3"} May 25 11:38:41 volumio volumio[1365]: verbose: CURRENT POSITION 0 May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Гладиолусы","artist":"Анна Семенович","album":"Music News vol.346","uri":"INTERNAL/VA - Music News vol.346/001. Анна Семенович - Гладиолусы.mp3","trackType":"mp3"} May 25 11:38:41 volumio volumio[1365]: verbose: CURRENT POSITION 0 May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play May 25 11:38:41 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Гладиолусы","artist":"Анна Семенович","album":"Music News vol.346","uri":"INTERNAL/VA - Music News vol.346/001. Анна Семенович - Гладиолусы.mp3","trackType":"mp3"} May 25 11:38:41 volumio volumio[1365]: verbose: CURRENT POSITION 0 May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play May 25 11:38:41 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Гладиолусы","artist":"Анна Семенович","album":"Music News vol.346","uri":"INTERNAL/VA - Music News vol.346/001. Анна Семенович - Гладиолусы.mp3","trackType":"mp3"} May 25 11:38:41 volumio volumio[1365]: verbose: CURRENT POSITION 0 May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play May 25 11:38:41 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: ------------------------------ 24ms May 25 11:38:41 volumio volumio[1365]: info: ------------------------------ 26ms May 25 11:38:41 volumio volumio[1365]: info: ------------------------------ 27ms May 25 11:38:41 volumio volumio[1365]: info: ------------------------------ 24ms May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand status took 19 milliseconds May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand status took 19 milliseconds May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand status took 19 milliseconds May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 18 milliseconds May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Гладиолусы","artist":"Анна Семенович","album":"Music News vol.346","uri":"INTERNAL/VA - Music News vol.346/001. Анна Семенович - Гладиолусы.mp3","trackType":"mp3"} May 25 11:38:41 volumio volumio[1365]: verbose: CURRENT POSITION 0 May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play May 25 11:38:41 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: ------------------------------ 39ms May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 42 milliseconds May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 42 milliseconds May 25 11:38:41 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 41 milliseconds May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:41 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Гладиолусы","artist":"Анна Семенович","album":"Music News vol.346","uri":"INTERNAL/VA - Music News vol.346/001. Анна Семенович - Гладиолусы.mp3","trackType":"mp3"} May 25 11:38:41 volumio volumio[1365]: verbose: CURRENT POSITION 0 May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play May 25 11:38:41 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Гладиолусы","artist":"Анна Семенович","album":"Music News vol.346","uri":"INTERNAL/VA - Music News vol.346/001. Анна Семенович - Гладиолусы.mp3","trackType":"mp3"} May 25 11:38:41 volumio volumio[1365]: verbose: CURRENT POSITION 0 May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play May 25 11:38:41 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Гладиолусы","artist":"Анна Семенович","album":"Music News vol.346","uri":"INTERNAL/VA - Music News vol.346/001. Анна Семенович - Гладиолусы.mp3","trackType":"mp3"} May 25 11:38:41 volumio volumio[1365]: verbose: CURRENT POSITION 0 May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play May 25 11:38:41 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:41 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:41 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:41 volumio volumio[1365]: info: ------------------------------ 69ms May 25 11:38:41 volumio volumio[1365]: info: ------------------------------ 68ms May 25 11:38:41 volumio volumio[1365]: info: ------------------------------ 68ms May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:41 volumio volumio[1365]: info: touch_display: Setting screensaver timeout to 0 seconds. May 25 11:38:42 volumio volumio[1365]: info: Initializing connection to go-librespot Websocket May 25 11:38:42 volumio volumio[1365]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 25 11:38:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. May 25 11:38:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:43 volumio go-librespot[14554]: go-librespot daemon starting... May 25 11:38:43 volumio go-librespot[14555]: time="2026-05-25T11:38:43+03:00" level=info msg="running go-librespot 0.7.1" May 25 11:38:43 volumio go-librespot[14555]: time="2026-05-25T11:38:43+03:00" level=debug msg="app state loaded" May 25 11:38:43 volumio go-librespot[14555]: time="2026-05-25T11:38:43+03:00" level=debug msg="stored credentials not found" May 25 11:38:43 volumio go-librespot[14555]: time="2026-05-25T11:38:43+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 11:38:43 volumio go-librespot[14555]: time="2026-05-25T11:38:43+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 25 11:38:43 volumio go-librespot[14555]: time="2026-05-25T11:38:43+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 25 11:38:43 volumio go-librespot[14555]: time="2026-05-25T11:38:43+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 25 11:38:43 volumio go-librespot[14555]: time="2026-05-25T11:38:43+03:00" level=info msg="zeroconf server listening on port 40147" May 25 11:38:43 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:43 volumio go-librespot[14555]: time="2026-05-25T11:38:43+03:00" level=fatal msg="failed running zeroconf" error="failed initializing zeroconf: failed initializing avahi registrar: failed to connect to system bus: The maximum number of active connections for UID 1000 has been reached" May 25 11:38:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 11:38:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 11:38:43 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState May 25 11:38:43 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:45 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:45 volumio volumio[1365]: info: Discovery: Restarting Browsing May 25 11:38:45 volumio volumio[1365]: info: Initializing connection to go-librespot Websocket May 25 11:38:45 volumio volumio[1365]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 25 11:38:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. May 25 11:38:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:46 volumio go-librespot[14571]: go-librespot daemon starting... May 25 11:38:46 volumio go-librespot[14572]: time="2026-05-25T11:38:46+03:00" level=info msg="running go-librespot 0.7.1" May 25 11:38:46 volumio go-librespot[14572]: time="2026-05-25T11:38:46+03:00" level=debug msg="app state loaded" May 25 11:38:46 volumio go-librespot[14572]: time="2026-05-25T11:38:46+03:00" level=debug msg="stored credentials not found" May 25 11:38:46 volumio go-librespot[14572]: time="2026-05-25T11:38:46+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 11:38:46 volumio go-librespot[14572]: time="2026-05-25T11:38:46+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 25 11:38:46 volumio go-librespot[14572]: time="2026-05-25T11:38:46+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 25 11:38:46 volumio go-librespot[14572]: time="2026-05-25T11:38:46+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 25 11:38:46 volumio go-librespot[14572]: time="2026-05-25T11:38:46+03:00" level=info msg="zeroconf server listening on port 38803" May 25 11:38:46 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:46 volumio go-librespot[14572]: time="2026-05-25T11:38:46+03:00" level=fatal msg="failed running zeroconf" error="failed initializing zeroconf: failed initializing avahi registrar: failed to connect to system bus: The maximum number of active connections for UID 1000 has been reached" May 25 11:38:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 11:38:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 11:38:48 volumio volumio[1365]: info: Initializing connection to go-librespot Websocket May 25 11:38:48 volumio volumio[1365]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 25 11:38:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. May 25 11:38:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:49 volumio go-librespot[14591]: go-librespot daemon starting... May 25 11:38:49 volumio go-librespot[14592]: time="2026-05-25T11:38:49+03:00" level=info msg="running go-librespot 0.7.1" May 25 11:38:49 volumio go-librespot[14592]: time="2026-05-25T11:38:49+03:00" level=debug msg="app state loaded" May 25 11:38:49 volumio go-librespot[14592]: time="2026-05-25T11:38:49+03:00" level=debug msg="stored credentials not found" May 25 11:38:49 volumio go-librespot[14592]: time="2026-05-25T11:38:49+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 11:38:50 volumio go-librespot[14592]: time="2026-05-25T11:38:50+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 25 11:38:50 volumio go-librespot[14592]: time="2026-05-25T11:38:50+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 25 11:38:50 volumio go-librespot[14592]: time="2026-05-25T11:38:50+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 25 11:38:50 volumio go-librespot[14592]: time="2026-05-25T11:38:50+03:00" level=info msg="zeroconf server listening on port 43421" May 25 11:38:50 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:50 volumio go-librespot[14592]: time="2026-05-25T11:38:50+03:00" level=fatal msg="failed running zeroconf" error="failed initializing zeroconf: failed initializing avahi registrar: failed to connect to system bus: The maximum number of active connections for UID 1000 has been reached" May 25 11:38:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 11:38:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 11:38:50 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:50 volumio volumio[1365]: info: Discovery: Restarting Browsing May 25 11:38:51 volumio volumio[1365]: info: Initializing connection to go-librespot Websocket May 25 11:38:51 volumio volumio[1365]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 25 11:38:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. May 25 11:38:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:53 volumio go-librespot[14617]: go-librespot daemon starting... May 25 11:38:53 volumio go-librespot[14618]: time="2026-05-25T11:38:53+03:00" level=info msg="running go-librespot 0.7.1" May 25 11:38:53 volumio go-librespot[14618]: time="2026-05-25T11:38:53+03:00" level=debug msg="app state loaded" May 25 11:38:53 volumio go-librespot[14618]: time="2026-05-25T11:38:53+03:00" level=debug msg="stored credentials not found" May 25 11:38:53 volumio go-librespot[14618]: time="2026-05-25T11:38:53+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 11:38:53 volumio go-librespot[14618]: time="2026-05-25T11:38:53+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 25 11:38:53 volumio go-librespot[14618]: time="2026-05-25T11:38:53+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 25 11:38:53 volumio go-librespot[14618]: time="2026-05-25T11:38:53+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 25 11:38:53 volumio go-librespot[14618]: time="2026-05-25T11:38:53+03:00" level=info msg="zeroconf server listening on port 34579" May 25 11:38:53 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:53 volumio go-librespot[14618]: time="2026-05-25T11:38:53+03:00" level=fatal msg="failed running zeroconf" error="failed initializing zeroconf: failed initializing avahi registrar: failed to connect to system bus: The maximum number of active connections for UID 1000 has been reached" May 25 11:38:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 11:38:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::volumioNext May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::next May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::stop May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::stPlaybackTimer May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::updateTrackBlock May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrackBlock May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::serviceStop May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::serviceStop May 25 11:38:53 volumio volumio[1365]: info: ControllerMpd::stop May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand stop May 25 11:38:53 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 25 11:38:53 volumio volumio[1365]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 25 11:38:53 volumio volumio[1365]: info: touch_display: Setting screensaver timeout to 3600 seconds. May 25 11:38:53 volumio volumio[1365]: info: May 25 11:38:53 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand stop took 21 milliseconds May 25 11:38:53 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::play index undefined May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::startPlaybackTimer May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/VA - Music News vol.346/002. Aden Foyer - Hello Radio.mp3 May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand stop May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::updateTrackBlock May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrackBlock May 25 11:38:53 volumio volumio[1365]: info: May 25 11:38:53 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:53 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:53 volumio volumio[1365]: info: May 25 11:38:53 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:53 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:53 volumio volumio[1365]: info: May 25 11:38:53 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:53 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand status took 2 milliseconds May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand stop took 2 milliseconds May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand status took 1 milliseconds May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand status took 1 milliseconds May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand status took 1 milliseconds May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand clear May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:53 volumio volumio[1365]: info: May 25 11:38:53 volumio volumio[1365]: ---------------------------- MPD announces system playlist update May 25 11:38:53 volumio volumio[1365]: info: Ignoring MPD Status Update May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand clear took 1 milliseconds May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/VA - Music News vol.346/002. Aden Foyer - Hello Radio.mp3" May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:53 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Гладиолусы","artist":"Анна Семенович","album":"Music News vol.346","uri":"INTERNAL/VA - Music News vol.346/001. Анна Семенович - Гладиолусы.mp3","trackType":"mp3"} May 25 11:38:53 volumio volumio[1365]: verbose: CURRENT POSITION 1 May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::syncState stateService stop May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:53 volumio volumio[1365]: info: No code May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:53 volumio volumio[1365]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 25 11:38:53 volumio volumio[1365]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 25 11:38:53 volumio volumio[1365]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 25 11:38:53 volumio volumio[1365]: info: ------------------------------ 10ms May 25 11:38:53 volumio volumio[1365]: info: ------------------------------ 8ms May 25 11:38:53 volumio volumio[1365]: info: ------------------------------ 8ms May 25 11:38:53 volumio volumio[1365]: info: ------------------------------ 8ms May 25 11:38:53 volumio volumio[1365]: info: May 25 11:38:53 volumio volumio[1365]: ---------------------------- MPD announces system playlist update May 25 11:38:53 volumio volumio[1365]: info: Ignoring MPD Status Update May 25 11:38:53 volumio volumio[1365]: info: May 25 11:38:53 volumio volumio[1365]: ---------------------------- MPD announces system playlist update May 25 11:38:53 volumio volumio[1365]: info: Ignoring MPD Status Update May 25 11:38:53 volumio volumio[1365]: info: May 25 11:38:53 volumio volumio[1365]: ---------------------------- MPD announces system playlist update May 25 11:38:53 volumio volumio[1365]: info: Ignoring MPD Status Update May 25 11:38:53 volumio volumio[1365]: error: updateQueue error: null May 25 11:38:53 volumio volumio[1365]: info: May 25 11:38:53 volumio volumio[1365]: ---------------------------- MPD announces system playlist update May 25 11:38:53 volumio volumio[1365]: info: Ignoring MPD Status Update May 25 11:38:53 volumio volumio[1365]: info: ------------------------------ 10ms May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand add "INTERNAL/VA - Music News vol.346/002. Aden Foyer - Hello Radio.mp3" took 9 milliseconds May 25 11:38:53 volumio volumio[1365]: info: ------------------------------ 3ms May 25 11:38:53 volumio volumio[1365]: info: ------------------------------ 2ms May 25 11:38:53 volumio volumio[1365]: info: ------------------------------ 1ms May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand play May 25 11:38:53 volumio volumio[1365]: info: May 25 11:38:53 volumio volumio[1365]: ---------------------------- MPD announces system playlist update May 25 11:38:53 volumio volumio[1365]: info: Ignoring MPD Status Update May 25 11:38:53 volumio volumio[1365]: info: May 25 11:38:53 volumio volumio[1365]: ---------------------------- MPD announces system playlist update May 25 11:38:53 volumio volumio[1365]: info: Ignoring MPD Status Update May 25 11:38:53 volumio volumio[1365]: info: May 25 11:38:53 volumio volumio[1365]: ---------------------------- MPD announces system playlist update May 25 11:38:53 volumio volumio[1365]: info: Ignoring MPD Status Update May 25 11:38:53 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 25 11:38:53 volumio volumio[1365]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 25 11:38:53 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 25 11:38:53 volumio volumio[1365]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 25 11:38:53 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 25 11:38:53 volumio volumio[1365]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 25 11:38:53 volumio volumio[1365]: info: May 25 11:38:53 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:53 volumio volumio[1365]: info: ------------------------------ 9ms May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand play took 9 milliseconds May 25 11:38:53 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:53 volumio volumio[1365]: info: ------------------------------ 8ms May 25 11:38:53 volumio volumio[1365]: info: ------------------------------ 8ms May 25 11:38:53 volumio volumio[1365]: info: ------------------------------ 8ms May 25 11:38:53 volumio volumio[1365]: info: May 25 11:38:53 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:53 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:53 volumio volumio[1365]: info: May 25 11:38:53 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:53 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:53 volumio volumio[1365]: info: May 25 11:38:53 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:53 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:53 volumio volumio[1365]: info: May 25 11:38:53 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand status took 2 milliseconds May 25 11:38:53 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand status took 2 milliseconds May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand status took 1 milliseconds May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand status took 2 milliseconds May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:53 volumio volumio[1365]: info: May 25 11:38:53 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:53 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:53 volumio volumio[1365]: info: May 25 11:38:53 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:53 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:53 volumio volumio[1365]: info: May 25 11:38:53 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:38:53 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand status took 2 milliseconds May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 2 milliseconds May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 2 milliseconds May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 2 milliseconds May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 2 milliseconds May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand status took 2 milliseconds May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand status took 1 milliseconds May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand status took 1 milliseconds May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:38:53 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":181,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Hello Radio","artist":"Aden Foyer","album":"Music News vol.346","uri":"INTERNAL/VA - Music News vol.346/002. Aden Foyer - Hello Radio.mp3","trackType":"mp3"} May 25 11:38:53 volumio volumio[1365]: verbose: CURRENT POSITION 1 May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop May 25 11:38:53 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":181,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Hello Radio","artist":"Aden Foyer","album":"Music News vol.346","uri":"INTERNAL/VA - Music News vol.346/002. Aden Foyer - Hello Radio.mp3","trackType":"mp3"} May 25 11:38:53 volumio volumio[1365]: verbose: CURRENT POSITION 1 May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play May 25 11:38:53 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:53 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":181,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Hello Radio","artist":"Aden Foyer","album":"Music News vol.346","uri":"INTERNAL/VA - Music News vol.346/002. Aden Foyer - Hello Radio.mp3","trackType":"mp3"} May 25 11:38:53 volumio volumio[1365]: verbose: CURRENT POSITION 1 May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play May 25 11:38:53 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:53 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":181,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Hello Radio","artist":"Aden Foyer","album":"Music News vol.346","uri":"INTERNAL/VA - Music News vol.346/002. Aden Foyer - Hello Radio.mp3","trackType":"mp3"} May 25 11:38:53 volumio volumio[1365]: verbose: CURRENT POSITION 1 May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play May 25 11:38:53 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:53 volumio volumio[1365]: info: ------------------------------ 8ms May 25 11:38:53 volumio volumio[1365]: info: ------------------------------ 13ms May 25 11:38:53 volumio volumio[1365]: info: ------------------------------ 12ms May 25 11:38:53 volumio volumio[1365]: info: ------------------------------ 12ms May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 10 milliseconds May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 10 milliseconds May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 10 milliseconds May 25 11:38:53 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 10 milliseconds May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:53 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:38:53 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":181,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Hello Radio","artist":"Aden Foyer","album":"Music News vol.346","uri":"INTERNAL/VA - Music News vol.346/002. Aden Foyer - Hello Radio.mp3","trackType":"mp3"} May 25 11:38:53 volumio volumio[1365]: verbose: CURRENT POSITION 1 May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play May 25 11:38:53 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:53 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":181,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Hello Radio","artist":"Aden Foyer","album":"Music News vol.346","uri":"INTERNAL/VA - Music News vol.346/002. Aden Foyer - Hello Radio.mp3","trackType":"mp3"} May 25 11:38:53 volumio volumio[1365]: verbose: CURRENT POSITION 1 May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play May 25 11:38:53 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:53 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":181,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Hello Radio","artist":"Aden Foyer","album":"Music News vol.346","uri":"INTERNAL/VA - Music News vol.346/002. Aden Foyer - Hello Radio.mp3","trackType":"mp3"} May 25 11:38:53 volumio volumio[1365]: verbose: CURRENT POSITION 1 May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play May 25 11:38:53 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:53 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":181,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Hello Radio","artist":"Aden Foyer","album":"Music News vol.346","uri":"INTERNAL/VA - Music News vol.346/002. Aden Foyer - Hello Radio.mp3","trackType":"mp3"} May 25 11:38:53 volumio volumio[1365]: verbose: CURRENT POSITION 1 May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play May 25 11:38:53 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:53 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:38:53 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:38:53 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:38:53 volumio volumio[1365]: info: ------------------------------ 24ms May 25 11:38:53 volumio volumio[1365]: info: ------------------------------ 23ms May 25 11:38:53 volumio volumio[1365]: info: ------------------------------ 23ms May 25 11:38:53 volumio volumio[1365]: info: ------------------------------ 22ms May 25 11:38:53 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:53 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:53 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:53 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:53 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:53 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:53 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:53 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:53 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:53 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:53 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:53 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:53 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:53 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 25 11:38:53 volumio volumio[1365]: info: touch_display: Setting screensaver timeout to 0 seconds. May 25 11:38:54 volumio volumio[1365]: info: Initializing connection to go-librespot Websocket May 25 11:38:54 volumio volumio[1365]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 25 11:38:55 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:55 volumio volumio[1365]: info: Discovery: Restarting Browsing May 25 11:38:55 volumio volumio[1365]: info: Bluetoothremote--- Checking for trusted devices to reconnect... May 25 11:38:55 volumio volumio[1365]: info: Bluetoothremote--- Device list cleared and placeholder written. May 25 11:38:55 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:55 volumio volumio[1365]: error: Bluetoothremote--- Failed to list Bluetooth devices: dbus[14672]: arguments to dbus_connection_get_object_path_data() were incorrect, assertion "connection != NULL" failed in file ../../../dbus/dbus-connection.c line 5969. May 25 11:38:55 volumio volumio[1365]: This is normally a bug in some application using the D-Bus library. May 25 11:38:55 volumio volumio[1365]: D-Bus not built with -rdynamic so unable to print a backtrace May 25 11:38:55 volumio volumio[1365]: Aborted May 25 11:38:56 volumio volumio[1365]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 11:38:56 volumio volumio[1365]: info: Bluetoothremote--- Device found: Press scan to detect BT device - xx May 25 11:38:56 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:56 volumio volumio[1365]: warn: Bluetoothremote--- Failed to get connected devices: May 25 11:38:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. May 25 11:38:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:56 volumio go-librespot[14677]: go-librespot daemon starting... May 25 11:38:56 volumio go-librespot[14679]: time="2026-05-25T11:38:56+03:00" level=info msg="running go-librespot 0.7.1" May 25 11:38:56 volumio go-librespot[14679]: time="2026-05-25T11:38:56+03:00" level=debug msg="app state loaded" May 25 11:38:56 volumio go-librespot[14679]: time="2026-05-25T11:38:56+03:00" level=debug msg="stored credentials not found" May 25 11:38:56 volumio go-librespot[14679]: time="2026-05-25T11:38:56+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 11:38:56 volumio go-librespot[14679]: time="2026-05-25T11:38:56+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 25 11:38:56 volumio go-librespot[14679]: time="2026-05-25T11:38:56+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 25 11:38:56 volumio go-librespot[14679]: time="2026-05-25T11:38:56+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 25 11:38:56 volumio go-librespot[14679]: time="2026-05-25T11:38:56+03:00" level=info msg="zeroconf server listening on port 37147" May 25 11:38:56 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:38:56 volumio go-librespot[14679]: time="2026-05-25T11:38:56+03:00" level=fatal msg="failed running zeroconf" error="failed initializing zeroconf: failed initializing avahi registrar: failed to connect to system bus: The maximum number of active connections for UID 1000 has been reached" May 25 11:38:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 11:38:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 11:38:57 volumio volumio[1365]: info: Initializing connection to go-librespot Websocket May 25 11:38:57 volumio volumio[1365]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 25 11:38:58 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 11:38:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. May 25 11:38:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 11:38:59 volumio go-librespot[14698]: go-librespot daemon starting... May 25 11:38:59 volumio go-librespot[14699]: time="2026-05-25T11:38:59+03:00" level=info msg="running go-librespot 0.7.1" May 25 11:38:59 volumio go-librespot[14699]: time="2026-05-25T11:38:59+03:00" level=debug msg="app state loaded" May 25 11:38:59 volumio go-librespot[14699]: time="2026-05-25T11:38:59+03:00" level=debug msg="stored credentials not found" May 25 11:38:59 volumio go-librespot[14699]: time="2026-05-25T11:38:59+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 11:39:00 volumio volumio[1365]: info: CoreCommandRouter::Close All Modals sent May 25 11:39:00 volumio go-librespot[14699]: time="2026-05-25T11:39:00+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 25 11:39:00 volumio go-librespot[14699]: time="2026-05-25T11:39:00+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 25 11:39:00 volumio go-librespot[14699]: time="2026-05-25T11:39:00+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 25 11:39:00 volumio go-librespot[14699]: time="2026-05-25T11:39:00+03:00" level=info msg="zeroconf server listening on port 33271" May 25 11:39:00 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:39:00 volumio go-librespot[14699]: time="2026-05-25T11:39:00+03:00" level=fatal msg="failed running zeroconf" error="failed initializing zeroconf: failed initializing avahi registrar: failed to connect to system bus: The maximum number of active connections for UID 1000 has been reached" May 25 11:39:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 11:39:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 11:39:00 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:39:00 volumio volumio[1365]: info: Discovery: Restarting Browsing May 25 11:39:00 volumio volumio[1365]: info: Initializing connection to go-librespot Websocket May 25 11:39:00 volumio volumio[1365]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 25 11:39:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. May 25 11:39:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 11:39:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 11:39:03 volumio go-librespot[14729]: go-librespot daemon starting... May 25 11:39:03 volumio go-librespot[14730]: time="2026-05-25T11:39:03+03:00" level=info msg="running go-librespot 0.7.1" May 25 11:39:03 volumio go-librespot[14730]: time="2026-05-25T11:39:03+03:00" level=debug msg="app state loaded" May 25 11:39:03 volumio go-librespot[14730]: time="2026-05-25T11:39:03+03:00" level=debug msg="stored credentials not found" May 25 11:39:03 volumio go-librespot[14730]: time="2026-05-25T11:39:03+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 11:39:03 volumio go-librespot[14730]: time="2026-05-25T11:39:03+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 25 11:39:03 volumio go-librespot[14730]: time="2026-05-25T11:39:03+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 25 11:39:03 volumio go-librespot[14730]: time="2026-05-25T11:39:03+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 25 11:39:03 volumio go-librespot[14730]: time="2026-05-25T11:39:03+03:00" level=info msg="zeroconf server listening on port 36165" May 25 11:39:03 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:39:03 volumio go-librespot[14730]: time="2026-05-25T11:39:03+03:00" level=fatal msg="failed running zeroconf" error="failed initializing zeroconf: failed initializing avahi registrar: failed to connect to system bus: The maximum number of active connections for UID 1000 has been reached" May 25 11:39:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 11:39:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::stop May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::stPlaybackTimer May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::updateTrackBlock May 25 11:39:03 volumio volumio[1365]: info: CorePlayQueue::getTrackBlock May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:39:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::serviceStop May 25 11:39:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::serviceStop May 25 11:39:03 volumio volumio[1365]: info: ControllerMpd::stop May 25 11:39:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand stop May 25 11:39:03 volumio volumio[1365]: info: Disabling plugin peppy_screensaver May 25 11:39:03 volumio volumio[1365]: info: Preparing to generate the ALSA configuration file May 25 11:39:03 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 25 11:39:03 volumio volumio[1365]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 25 11:39:03 volumio volumio[1365]: info: May 25 11:39:03 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:39:03 volumio volumio[1365]: info: sendMpdCommand stop took 21 milliseconds May 25 11:39:03 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:39:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:39:03 volumio volumio[1365]: info: Removing peppy_screensaver REST Endpoint May 25 11:39:03 volumio volumio[1365]: info: May 25 11:39:03 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:39:03 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:39:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:39:03 volumio volumio[1365]: info: May 25 11:39:03 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:39:03 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:39:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:39:03 volumio volumio[1365]: info: May 25 11:39:03 volumio volumio[1365]: ---------------------------- MPD announces state update: player May 25 11:39:03 volumio volumio[1365]: info: ControllerMpd::getState May 25 11:39:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status May 25 11:39:03 volumio volumio[1365]: info: sendMpdCommand status took 19 milliseconds May 25 11:39:03 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:39:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:39:03 volumio volumio[1365]: info: touch_display: Setting screensaver timeout to 3600 seconds. May 25 11:39:03 volumio volumio[1365]: info: sendMpdCommand status took 3 milliseconds May 25 11:39:03 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:39:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: spop , initializeLibrespotDaemon May 25 11:39:03 volumio volumio[1365]: info: Creating Spotify config file May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:03 volumio volumio[1365]: info: Asound.conf file written May 25 11:39:03 volumio sudo[14747]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl May 25 11:39:03 volumio sudo[14747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:03 volumio sudo[14752]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount /volumio/app/plugins/music_service/airplay_emulation/shairport-sync.conf.tmpl May 25 11:39:03 volumio sudo[14752]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:03 volumio sudo[14755]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 25 11:39:03 volumio sudo[14755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:03 volumio systemd[1]: volumio-app-plugins-music_service-mpd-mpd.conf.tmpl.mount: Deactivated successfully. May 25 11:39:03 volumio sudo[14747]: pam_unix(sudo:session): session closed for user root May 25 11:39:03 volumio systemd[1]: volumio-app-plugins-music_service-airplay_emulation-shairport\x2dsync.conf.tmpl.mount: Deactivated successfully. May 25 11:39:03 volumio sudo[14755]: pam_unix(sudo:session): session closed for user root May 25 11:39:03 volumio sudo[14752]: pam_unix(sudo:session): session closed for user root May 25 11:39:03 volumio volumio[1365]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 25 11:39:03 volumio volumio[1365]: No state is present for card vc4hdmi0 May 25 11:39:03 volumio volumio[1365]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 25 11:39:03 volumio volumio[1365]: Found hardware: "vc4-hdmi" "" "" "" "" May 25 11:39:03 volumio volumio[1365]: Hardware is initialized using a generic method May 25 11:39:03 volumio volumio[1365]: No state is present for card vc4hdmi0 May 25 11:39:03 volumio volumio[1365]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 25 11:39:03 volumio volumio[1365]: No state is present for card vc4hdmi1 May 25 11:39:03 volumio volumio[1365]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 25 11:39:03 volumio volumio[1365]: Found hardware: "vc4-hdmi" "" "" "" "" May 25 11:39:03 volumio volumio[1365]: Hardware is initialized using a generic method May 25 11:39:03 volumio volumio[1365]: No state is present for card vc4hdmi1 May 25 11:39:03 volumio volumio[1365]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 25 11:39:03 volumio volumio[1365]: No state is present for card sndrpihifiberry May 25 11:39:03 volumio volumio[1365]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 25 11:39:03 volumio volumio[1365]: Found hardware: "RPi-WM8804" "" "" "" "" May 25 11:39:03 volumio volumio[1365]: Hardware is initialized using a generic method May 25 11:39:03 volumio volumio[1365]: No state is present for card sndrpihifiberry May 25 11:39:03 volumio volumio[1365]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 May 25 11:39:03 volumio volumio[1365]: No state is present for card Dummy May 25 11:39:03 volumio volumio[1365]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 May 25 11:39:03 volumio volumio[1365]: Found hardware: "Dummy" "Dummy Mixer" "" "" "" May 25 11:39:03 volumio volumio[1365]: Hardware is initialized using a generic method May 25 11:39:03 volumio volumio[1365]: No state is present for card Dummy May 25 11:39:03 volumio volumio[1365]: info: Output device has changed, restarting MPD May 25 11:39:03 volumio sudo[14763]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 11:39:03 volumio sudo[14763]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:03 volumio volumio[1365]: info: Output device has changed, restarting Shairport Sync May 25 11:39:03 volumio sudo[14763]: pam_unix(sudo:session): session closed for user root May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 11:39:03 volumio sudo[14767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 11:39:03 volumio sudo[14767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:03 volumio volumio[1365]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 11:39:03 volumio volumio[1365]: info: Done. May 25 11:39:03 volumio volumio[1365]: info: sendMpdCommand status took 70 milliseconds May 25 11:39:03 volumio volumio[1365]: info: sendMpdCommand status took 70 milliseconds May 25 11:39:03 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 69 milliseconds May 25 11:39:03 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 67 milliseconds May 25 11:39:03 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:39:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:39:03 volumio volumio[1365]: verbose: ControllerMpd::parseState May 25 11:39:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 11:39:03 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:39:03 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:39:03 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:39:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:39:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:39:03 volumio volumio[1365]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hello Radio","artist":"Aden Foyer","album":"Music News vol.346","uri":"INTERNAL/VA - Music News vol.346/002. Aden Foyer - Hello Radio.mp3","trackType":"mp3"} May 25 11:39:03 volumio volumio[1365]: verbose: CURRENT POSITION 1 May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::syncState stateService stop May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:39:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:39:03 volumio volumio[1365]: info: No code May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:39:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:39:03 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:39:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:39:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:39:03 volumio volumio[1365]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hello Radio","artist":"Aden Foyer","album":"Music News vol.346","uri":"INTERNAL/VA - Music News vol.346/002. Aden Foyer - Hello Radio.mp3","trackType":"mp3"} May 25 11:39:03 volumio volumio[1365]: verbose: CURRENT POSITION 1 May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::syncState stateService stop May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:39:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:39:03 volumio volumio[1365]: info: No code May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:39:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:39:03 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... May 25 11:39:03 volumio volumio[1365]: info: ------------------------------ 101ms May 25 11:39:03 volumio volumio[1365]: info: ------------------------------ 84ms May 25 11:39:03 volumio volumio[1365]: info: MPD Permissions set May 25 11:39:03 volumio volumio[1365]: info: Spotify config file written May 25 11:39:03 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 25 milliseconds May 25 11:39:03 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 25 milliseconds May 25 11:39:03 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:39:03 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo May 25 11:39:03 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:39:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:39:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:39:03 volumio volumio[1365]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hello Radio","artist":"Aden Foyer","album":"Music News vol.346","uri":"INTERNAL/VA - Music News vol.346/002. Aden Foyer - Hello Radio.mp3","trackType":"mp3"} May 25 11:39:03 volumio volumio[1365]: verbose: CURRENT POSITION 1 May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::syncState stateService stop May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:39:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:39:03 volumio volumio[1365]: info: No code May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:39:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:39:03 volumio volumio[1365]: info: ControllerMpd::pushState May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::servicePushState May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:39:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:39:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:39:03 volumio volumio[1365]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hello Radio","artist":"Aden Foyer","album":"Music News vol.346","uri":"INTERNAL/VA - Music News vol.346/002. Aden Foyer - Hello Radio.mp3","trackType":"mp3"} May 25 11:39:03 volumio volumio[1365]: verbose: CURRENT POSITION 1 May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::syncState stateService stop May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:39:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:39:03 volumio volumio[1365]: info: No code May 25 11:39:03 volumio volumio[1365]: info: CoreStateMachine::pushState May 25 11:39:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState May 25 11:39:03 volumio sudo[14786]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 25 11:39:03 volumio sudo[14786]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:03 volumio volumio[1365]: info: ------------------------------ 107ms May 25 11:39:03 volumio volumio[1365]: info: ------------------------------ 107ms May 25 11:39:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 11:39:03 volumio volumio[1365]: info: Initializing connection to go-librespot Websocket May 25 11:39:03 volumio volumio[1365]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 11:39:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:03 volumio volumio[1365]: info: Starting Shairport Sync May 25 11:39:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 11:39:03 volumio go-librespot[14799]: go-librespot daemon starting... May 25 11:39:03 volumio sudo[14786]: pam_unix(sudo:session): session closed for user root May 25 11:39:03 volumio go-librespot[14806]: time="2026-05-25T11:39:03+03:00" level=info msg="running go-librespot 0.7.1" May 25 11:39:03 volumio go-librespot[14806]: time="2026-05-25T11:39:03+03:00" level=debug msg="app state loaded" May 25 11:39:03 volumio go-librespot[14806]: time="2026-05-25T11:39:03+03:00" level=debug msg="stored credentials not found" May 25 11:39:03 volumio go-librespot[14806]: time="2026-05-25T11:39:03+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 11:39:03 volumio sudo[14812]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 11:39:03 volumio sudo[14812]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:03 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 25 11:39:03 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 25 11:39:03 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 11:39:03 volumio systemd[1]: shairport-sync.service: Consumed 2.731s CPU time. May 25 11:39:03 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 11:39:03 volumio sudo[14812]: pam_unix(sudo:session): session closed for user root May 25 11:39:03 volumio volumio[1365]: info: Shairport-Sync Started May 25 11:39:03 volumio systemd[1]: mpd.service: Deactivated successfully. May 25 11:39:03 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 25 11:39:03 volumio systemd[1]: mpd.service: Consumed 15.531s CPU time. May 25 11:39:03 volumio systemd[1]: mpd.socket: Deactivated successfully. May 25 11:39:03 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 25 11:39:03 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 25 11:39:03 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 25 11:39:03 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 25 11:39:03 volumio go-librespot[14806]: time="2026-05-25T11:39:03+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 25 11:39:03 volumio go-librespot[14806]: time="2026-05-25T11:39:03+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 25 11:39:03 volumio go-librespot[14806]: time="2026-05-25T11:39:03+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 25 11:39:03 volumio go-librespot[14806]: time="2026-05-25T11:39:03+03:00" level=info msg="zeroconf server listening on port 40877" May 25 11:39:03 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:39:03 volumio go-librespot[14806]: time="2026-05-25T11:39:03+03:00" level=fatal msg="failed running zeroconf" error="failed initializing zeroconf: failed initializing avahi registrar: failed to connect to system bus: The maximum number of active connections for UID 1000 has been reached" May 25 11:39:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 11:39:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 11:39:03 volumio sudo[14831]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 11:39:03 volumio sudo[14831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 11:39:03 volumio sudo[14831]: pam_unix(sudo:session): session closed for user root May 25 11:39:04 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:04 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:04 volumio mpd[14835]: 2026-05-25T11:39:04 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 11:39:04 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 25 11:39:04 volumio sudo[14767]: pam_unix(sudo:session): session closed for user root May 25 11:39:04 volumio volumio[1365]: error: updateQueue error: null May 25 11:39:05 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:39:05 volumio volumio[1365]: info: Discovery: Restarting Browsing May 25 11:39:06 volumio volumio[1365]: info: Initializing connection to go-librespot Websocket May 25 11:39:06 volumio volumio[1365]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 25 11:39:06 volumio volumio[1365]: info: go-librespot daemon successfully initialized May 25 11:39:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. May 25 11:39:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 11:39:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 11:39:07 volumio go-librespot[14840]: go-librespot daemon starting... May 25 11:39:07 volumio go-librespot[14841]: time="2026-05-25T11:39:07+03:00" level=info msg="running go-librespot 0.7.1" May 25 11:39:07 volumio go-librespot[14841]: time="2026-05-25T11:39:07+03:00" level=debug msg="app state loaded" May 25 11:39:07 volumio go-librespot[14841]: time="2026-05-25T11:39:07+03:00" level=debug msg="stored credentials not found" May 25 11:39:07 volumio go-librespot[14841]: time="2026-05-25T11:39:07+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 11:39:07 volumio go-librespot[14841]: time="2026-05-25T11:39:07+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 25 11:39:07 volumio go-librespot[14841]: time="2026-05-25T11:39:07+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 25 11:39:07 volumio go-librespot[14841]: time="2026-05-25T11:39:07+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 25 11:39:07 volumio go-librespot[14841]: time="2026-05-25T11:39:07+03:00" level=info msg="zeroconf server listening on port 41953" May 25 11:39:07 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:39:07 volumio go-librespot[14841]: time="2026-05-25T11:39:07+03:00" level=fatal msg="failed running zeroconf" error="failed initializing zeroconf: failed initializing avahi registrar: failed to connect to system bus: The maximum number of active connections for UID 1000 has been reached" May 25 11:39:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 11:39:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 11:39:07 volumio volumio[1365]: info: Enabling plugin peppy_screensaver May 25 11:39:07 volumio volumio[1365]: info: Loading plugin "peppy_screensaver"... May 25 11:39:07 volumio volumio[1365]: info: Applying required configuration parameters for plugin peppy_screensaver May 25 11:39:07 volumio volumio[1365]: info: Preparing to generate the ALSA configuration file May 25 11:39:07 volumio volumio[1365]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 25 11:39:07 volumio volumio[1365]: info: Reading ALSA contributions from plugins. May 25 11:39:07 volumio volumio[1365]: info: Asound.conf file written May 25 11:39:07 volumio sudo[14853]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 25 11:39:07 volumio sudo[14853]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:07 volumio sudo[14853]: pam_unix(sudo:session): session closed for user root May 25 11:39:07 volumio volumio[1365]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 25 11:39:07 volumio volumio[1365]: No state is present for card vc4hdmi0 May 25 11:39:07 volumio volumio[1365]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 25 11:39:07 volumio volumio[1365]: Found hardware: "vc4-hdmi" "" "" "" "" May 25 11:39:07 volumio volumio[1365]: Hardware is initialized using a generic method May 25 11:39:07 volumio volumio[1365]: No state is present for card vc4hdmi0 May 25 11:39:07 volumio volumio[1365]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 25 11:39:07 volumio volumio[1365]: No state is present for card vc4hdmi1 May 25 11:39:07 volumio volumio[1365]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 25 11:39:07 volumio volumio[1365]: Found hardware: "vc4-hdmi" "" "" "" "" May 25 11:39:07 volumio volumio[1365]: Hardware is initialized using a generic method May 25 11:39:07 volumio volumio[1365]: No state is present for card vc4hdmi1 May 25 11:39:07 volumio volumio[1365]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 25 11:39:07 volumio volumio[1365]: No state is present for card sndrpihifiberry May 25 11:39:07 volumio volumio[1365]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 25 11:39:07 volumio volumio[1365]: Found hardware: "RPi-WM8804" "" "" "" "" May 25 11:39:07 volumio volumio[1365]: Hardware is initialized using a generic method May 25 11:39:07 volumio volumio[1365]: No state is present for card sndrpihifiberry May 25 11:39:07 volumio volumio[1365]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 May 25 11:39:07 volumio volumio[1365]: No state is present for card Dummy May 25 11:39:07 volumio volumio[1365]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 May 25 11:39:07 volumio volumio[1365]: Found hardware: "Dummy" "Dummy Mixer" "" "" "" May 25 11:39:07 volumio volumio[1365]: Hardware is initialized using a generic method May 25 11:39:07 volumio volumio[1365]: No state is present for card Dummy May 25 11:39:07 volumio volumio[1365]: info: Output device has changed, restarting MPD May 25 11:39:07 volumio volumio[1365]: info: Output device has changed, restarting Shairport Sync May 25 11:39:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 11:39:07 volumio sudo[14860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 11:39:07 volumio sudo[14860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:07 volumio sudo[14861]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 11:39:07 volumio sudo[14860]: pam_unix(sudo:session): session closed for user root May 25 11:39:07 volumio sudo[14861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:07 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... May 25 11:39:07 volumio volumio[1365]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 11:39:07 volumio volumio[1365]: info: PLUGIN START: peppy_screensaver May 25 11:39:07 volumio volumio[1365]: info: Loading i18n strings for locale ru May 25 11:39:07 volumio volumio[1365]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. May 25 11:39:07 volumio volumio[1365]: info: /tmp/myfifo created May 25 11:39:07 volumio volumio[1365]: info: /tmp/myfifosa created May 25 11:39:07 volumio systemd[1]: mpd.service: Deactivated successfully. May 25 11:39:07 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 25 11:39:07 volumio systemd[1]: mpd.socket: Deactivated successfully. May 25 11:39:07 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 25 11:39:07 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 25 11:39:07 volumio sudo[14880]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 May 25 11:39:07 volumio sudo[14880]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:07 volumio sudo[14880]: pam_unix(sudo:session): session closed for user root May 25 11:39:07 volumio volumio[1365]: info: snd-dummy loaded May 25 11:39:07 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 25 11:39:07 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 25 11:39:07 volumio sudo[14884]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 11:39:07 volumio sudo[14884]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 11:39:07 volumio sudo[14884]: pam_unix(sudo:session): session closed for user root May 25 11:39:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync May 25 11:39:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 11:39:07 volumio volumio[1365]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 11:39:07 volumio volumio[1365]: info: Adding peppy_screensaver REST Endpoint for plugin: user_interface/peppy_screensaver May 25 11:39:07 volumio volumio[1365]: info: peppy_screensaver: REST endpoint registered: peppy_screensaver May 25 11:39:07 volumio volumio[1365]: info: Updating peppy_screensaver_font REST Endpoint for plugin: user_interface/peppy_screensaver May 25 11:39:07 volumio volumio[1365]: info: peppy_screensaver: REST endpoint registered: peppy_screensaver_font May 25 11:39:07 volumio volumio[1365]: info: Updating peppy_screensaver_vinyl REST Endpoint for plugin: user_interface/peppy_screensaver May 25 11:39:07 volumio volumio[1365]: info: peppy_screensaver: REST endpoint registered: peppy_screensaver_vinyl May 25 11:39:07 volumio volumio[1365]: info: peppy_screensaver: Config version updated: b8f65f83 May 25 11:39:07 volumio volumio[1365]: info: Done. May 25 11:39:07 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState May 25 11:39:07 volumio volumio[1365]: info: CorePlayQueue::getTrack 1 May 25 11:39:07 volumio volumio[1365]: info: MPD Permissions set May 25 11:39:07 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 25 11:39:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: spop , initializeLibrespotDaemon May 25 11:39:07 volumio volumio[1365]: info: Creating Spotify config file May 25 11:39:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 11:39:07 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:07 volumio volumio[1365]: info: Spotify config file written May 25 11:39:07 volumio volumio[1365]: info: Starting Shairport Sync May 25 11:39:07 volumio volumio[1365]: info: Starting Shairport Sync May 25 11:39:08 volumio volumio[1365]: info: peppy_screensaver: ALSA template: /data/plugins/user_interface/peppy_screensaver/Peppyalsa.postPeppyalsa.5.conf.tmpl (isX64=false) May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: spop , initializeLibrespotDaemon May 25 11:39:08 volumio volumio[1365]: info: Creating Spotify config file May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 25 11:39:08 volumio volumio[1365]: info: Preparing to generate the ALSA configuration file May 25 11:39:08 volumio volumio[1365]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 25 11:39:08 volumio volumio[1365]: info: Reading ALSA contributions from plugins. May 25 11:39:08 volumio volumio[1365]: info: Spotify config file written May 25 11:39:08 volumio volumio[1365]: info: Asound.conf file unchanged, so no further update is needed May 25 11:39:08 volumio volumio[1365]: info: Output device has changed, restarting MPD May 25 11:39:08 volumio volumio[1365]: info: Output device has changed, restarting Shairport Sync May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 11:39:08 volumio volumio[1365]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:08 volumio volumio[1365]: info: Starting Shairport Sync May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:08 volumio sudo[14918]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/shairport-sync.conf.tmpl /volumio/app/plugins/music_service/airplay_emulation/shairport-sync.conf.tmpl May 25 11:39:08 volumio sudo[14918]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:08 volumio sudo[14932]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 11:39:08 volumio sudo[14932]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:08 volumio sudo[14918]: pam_unix(sudo:session): session closed for user root May 25 11:39:08 volumio sudo[14946]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 25 11:39:08 volumio sudo[14946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:08 volumio sudo[14937]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl May 25 11:39:08 volumio sudo[14930]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 11:39:08 volumio sudo[14937]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:08 volumio sudo[14930]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:08 volumio sudo[14949]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 11:39:08 volumio sudo[14949]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:08 volumio sudo[14949]: pam_unix(sudo:session): session closed for user root May 25 11:39:08 volumio sudo[14951]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 11:39:08 volumio sudo[14937]: pam_unix(sudo:session): session closed for user root May 25 11:39:08 volumio sudo[14951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:08 volumio sudo[14928]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile May 25 11:39:08 volumio sudo[14928]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:08 volumio volumio[1365]: info: MPD Permissions set May 25 11:39:08 volumio sudo[14971]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 11:39:08 volumio sudo[14971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:08 volumio sudo[14971]: pam_unix(sudo:session): session closed for user root May 25 11:39:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:08 volumio volumio[1365]: info: MPD Permissions set May 25 11:39:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 11:39:08 volumio go-librespot[14978]: go-librespot daemon starting... May 25 11:39:08 volumio sudo[14946]: pam_unix(sudo:session): session closed for user root May 25 11:39:08 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 25 11:39:08 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 25 11:39:08 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 11:39:08 volumio systemd[1]: shairport-sync.service: Consumed 1.510s CPU time. May 25 11:39:08 volumio go-librespot[14979]: time="2026-05-25T11:39:08+03:00" level=info msg="running go-librespot 0.7.1" May 25 11:39:08 volumio go-librespot[14979]: time="2026-05-25T11:39:08+03:00" level=debug msg="app state loaded" May 25 11:39:08 volumio go-librespot[14979]: time="2026-05-25T11:39:08+03:00" level=debug msg="stored credentials not found" May 25 11:39:08 volumio sudo[14928]: pam_unix(sudo:session): session closed for user root May 25 11:39:08 volumio go-librespot[14979]: time="2026-05-25T11:39:08+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 11:39:08 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 11:39:08 volumio sudo[14932]: pam_unix(sudo:session): session closed for user root May 25 11:39:08 volumio sudo[14930]: pam_unix(sudo:session): session closed for user root May 25 11:39:08 volumio volumio[1365]: info: Shairport-Sync Started May 25 11:39:08 volumio volumio[1365]: info: Shairport-Sync Started May 25 11:39:08 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 25 11:39:08 volumio sudo[15001]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 11:39:08 volumio sudo[15001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:08 volumio sudo[15003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 11:39:08 volumio sudo[15003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:08 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 25 11:39:08 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 25 11:39:08 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 11:39:08 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 11:39:08 volumio sudo[15003]: pam_unix(sudo:session): session closed for user root May 25 11:39:10 volumio systemd[1]: mpd.service: Deactivated successfully. May 25 11:39:10 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 25 11:39:10 volumio systemd[1]: mpd.socket: Deactivated successfully. May 25 11:39:10 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 25 11:39:10 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 25 11:39:10 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 25 11:39:10 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 25 11:39:10 volumio volumio[1365]: info: Shairport-Sync Started May 25 11:39:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:39:10 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 25 11:39:10 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:39:10 volumio volumio[1365]: info: Discovery: Restarting Browsing May 25 11:39:10 volumio volumio[1365]: info: Initializing connection to go-librespot Websocket May 25 11:39:10 volumio volumio[1365]: info: Initializing connection to go-librespot Websocket May 25 11:39:10 volumio go-librespot[14979]: time="2026-05-25T11:39:10+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 25 11:39:10 volumio go-librespot[14979]: time="2026-05-25T11:39:10+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 25 11:39:10 volumio go-librespot[14979]: time="2026-05-25T11:39:10+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 25 11:39:10 volumio go-librespot[14979]: time="2026-05-25T11:39:10+03:00" level=debug msg="new websocket client" May 25 11:39:10 volumio go-librespot[14979]: time="2026-05-25T11:39:10+03:00" level=debug msg="new websocket client" May 25 11:39:10 volumio volumio[1365]: info: Connection to go-librespot Websocket established May 25 11:39:10 volumio volumio[1365]: info: Connection to go-librespot Websocket established May 25 11:39:10 volumio go-librespot[14979]: time="2026-05-25T11:39:10+03:00" level=info msg="zeroconf server listening on port 41939" May 25 11:39:10 volumio sudo[15022]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 11:39:10 volumio sudo[15032]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service May 25 11:39:10 volumio sudo[15032]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:10 volumio sudo[15022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 11:39:10 volumio dbus-daemon[1016]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) May 25 11:39:10 volumio go-librespot[14979]: time="2026-05-25T11:39:10+03:00" level=fatal msg="failed running zeroconf" error="failed initializing zeroconf: failed initializing avahi registrar: failed to connect to system bus: The maximum number of active connections for UID 1000 has been reached" May 25 11:39:10 volumio sudo[15033]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf May 25 11:39:10 volumio sudo[15035]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 11:39:10 volumio sudo[15033]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:10 volumio sudo[15034]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service May 25 11:39:10 volumio sudo[15034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:10 volumio sudo[15035]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:10 volumio sudo[15038]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service May 25 11:39:10 volumio sudo[15038]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:10 volumio volumio[1365]: info: Connection to go-librespot Websocket closed May 25 11:39:10 volumio volumio[1365]: info: Connection to go-librespot Websocket closed May 25 11:39:10 volumio sudo[15033]: pam_unix(sudo:session): session closed for user root May 25 11:39:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 11:39:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 11:39:10 volumio volumio[1365]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. May 25 11:39:11 volumio volumio[1365]: info: go-librespot daemon successfully initialized May 25 11:39:11 volumio volumio[1365]: info: go-librespot daemon successfully initialized May 25 11:39:11 volumio sudo[15022]: pam_unix(sudo:session): session closed for user root May 25 11:39:11 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. May 25 11:39:11 volumio sudo[15034]: pam_unix(sudo:session): session closed for user root May 25 11:39:11 volumio startx[8645]: xinit: connection to X server lost May 25 11:39:11 volumio startx[8645]: May 25 11:39:11 volumio systemd[1]: Stopping volumio-kiosk.service - Volumio Kiosk... May 25 11:39:11 volumio volumio[1365]: info: touch_display: systemctl start getty@tty1.service succeeded. May 25 11:39:11 volumio systemd[1]: mpd.service: Deactivated successfully. May 25 11:39:11 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 25 11:39:11 volumio systemd[1]: Reloading. May 25 11:39:11 volumio startx[8645]: waiting for X server to shut down May 25 11:39:11 volumio startx[8646]: (II) Server terminated successfully (0). Closing log file. May 25 11:39:12 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 25 11:39:12 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 25 11:39:12 volumio sudo[15032]: pam_unix(sudo:session): session closed for user root May 25 11:39:12 volumio volumio[1365]: info: touch_display: systemctl enable getty@tty1.service succeeded. May 25 11:39:12 volumio systemd[1]: mpd.socket: Deactivated successfully. May 25 11:39:12 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 25 11:39:12 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 25 11:39:12 volumio (sd-listen)[15080]: mpd.socket: Failed to create listening socket ([::]:6600): Address already in use May 25 11:39:12 volumio systemd[1]: mpd.socket: Failed to receive listening socket ([::]:6600): Input/output error May 25 11:39:12 volumio systemd[1]: mpd.socket: Failed to listen on sockets: Input/output error May 25 11:39:12 volumio systemd[1]: mpd.socket: Failed with result 'resources'. May 25 11:39:12 volumio systemd[1]: Failed to listen on mpd.socket - Music Player Daemon Socket. May 25 11:39:12 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 25 11:39:12 volumio sudo[15081]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 11:39:12 volumio sudo[15081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 11:39:12 volumio sudo[15081]: pam_unix(sudo:session): session closed for user root May 25 11:39:12 volumio mpd[15083]: 2026-05-25T11:39:12 exception: Failed to bind to '[::]:6600'; Failed to bind socket: Address already in use May 25 11:39:12 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE May 25 11:39:12 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. May 25 11:39:12 volumio systemd[1]: Failed to start mpd.service - Music Player Daemon. May 25 11:39:12 volumio sudo[14951]: pam_unix(sudo:session): session closed for user root May 25 11:39:12 volumio sudo[15035]: pam_unix(sudo:session): session closed for user root May 25 11:39:12 volumio sudo[15001]: pam_unix(sudo:session): session closed for user root May 25 11:39:12 volumio sudo[14861]: pam_unix(sudo:session): session closed for user root May 25 11:39:12 volumio volumio[1365]: error: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service May 25 11:39:12 volumio volumio[1365]: Job for mpd.service failed because the control process exited with error code. May 25 11:39:12 volumio volumio[1365]: See "systemctl status mpd.service" and "journalctl -xeu mpd.service" for details. May 25 11:39:12 volumio volumio[1365]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service May 25 11:39:12 volumio volumio[1365]: Job for mpd.service failed because the control process exited with error code. May 25 11:39:12 volumio volumio[1365]: See "systemctl status mpd.service" and "journalctl -xeu mpd.service" for details. May 25 11:39:12 volumio volumio[1365]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service May 25 11:39:12 volumio volumio[1365]: Job for mpd.service failed because the control process exited with error code. May 25 11:39:12 volumio volumio[1365]: See "systemctl status mpd.service" and "journalctl -xeu mpd.service" for details. May 25 11:39:12 volumio volumio[1365]: error: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service May 25 11:39:12 volumio volumio[1365]: Job for mpd.service failed because the control process exited with error code. May 25 11:39:12 volumio volumio[1365]: See "systemctl status mpd.service" and "journalctl -xeu mpd.service" for details. May 25 11:39:12 volumio volumio[1365]: error: MPD error: Error: connect ECONNREFUSED /run/mpd/socket May 25 11:39:12 volumio volumio[1365]: error: connect ECONNREFUSED /run/mpd/socket {"address":"/run/mpd/socket","code":"ECONNREFUSED","errno":-111,"syscall":"connect"} May 25 11:39:12 volumio volumio[1365]: error: MPD error: Error: connect ECONNREFUSED /run/mpd/socket May 25 11:39:12 volumio volumio[1365]: error: connect ECONNREFUSED /run/mpd/socket {"address":"/run/mpd/socket","code":"ECONNREFUSED","errno":-111,"syscall":"connect"} May 25 11:39:12 volumio startx[8645]: xinit: unexpected signal 15 May 25 11:39:12 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE May 25 11:39:12 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. May 25 11:39:12 volumio systemd[1]: Stopped volumio-kiosk.service - Volumio Kiosk. May 25 11:39:12 volumio systemd[1]: volumio-kiosk.service: Consumed 25.027s CPU time. May 25 11:39:12 volumio sudo[15038]: pam_unix(sudo:session): session closed for user root May 25 11:39:12 volumio volumio[1365]: warn: peppy_screensaver: Failed to set MPD output: Error: Command failed: mpc enable 1 May 25 11:39:12 volumio volumio[1365]: MPD error: Connection reset by peer May 25 11:39:12 volumio volumio[1365]: warn: peppy_screensaver: Startup - Failed to set MPD output: Error: Command failed: mpc enable 1 May 25 11:39:12 volumio volumio[1365]: MPD error: Connection reset by peer May 25 11:39:12 volumio volumio[1365]: info: touch_display: systemctl stop volumio-kiosk.service succeeded. May 25 11:39:12 volumio volumio[1365]: info: Disabling plugin touch_display May 25 11:39:12 volumio volumio[1365]: info: Done. May 25 11:39:13 volumio volumio[1365]: info: Getting Spotify volume May 25 11:39:13 volumio volumio[1365]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 11:39:13 volumio volumio[1365]: Error: connect ECONNREFUSED 127.0.0.1:9879 May 25 11:39:13 volumio volumio[1365]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { May 25 11:39:13 volumio volumio[1365]: errno: -111, May 25 11:39:13 volumio volumio[1365]: code: 'ECONNREFUSED', May 25 11:39:13 volumio volumio[1365]: syscall: 'connect', May 25 11:39:13 volumio volumio[1365]: address: '127.0.0.1', May 25 11:39:13 volumio volumio[1365]: port: 9879, May 25 11:39:13 volumio volumio[1365]: response: undefined May 25 11:39:13 volumio volumio[1365]: } May 25 11:39:13 volumio volumio[1365]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 11:39:13 volumio sudo[15102]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-25 11:38' May 25 11:39:13 volumio sudo[15102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:39:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. May 25 11:39:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"