Dec 19 11:18:00 volumio volumio[1352]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 19 11:18:04 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 11:18:04 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 11:18:04 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 19 11:18:04 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 19 11:18:04 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 19 11:18:04 volumio volumio[1352]: info: CoreCommandRouter::volumioGetBrowseSources Dec 19 11:18:04 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 19 11:18:06 volumio volumio[1352]: info: MyVolumio token set successfully Dec 19 11:18:06 volumio volumio[1352]: info: MYVOLUMIO: Adding device Dec 19 11:18:06 volumio volumio[1352]: info: MYVOLUMIO: Evaluating Server Dec 19 11:18:06 volumio volumio[1352]: info: MyVolumio status changed Dec 19 11:18:06 volumio volumio[1352]: info: Streaming services startup Dec 19 11:18:06 volumio volumio[1352]: info: Starting Streaming Daemon Dec 19 11:18:06 volumio volumio[1352]: info: Removing browser output: myVolumio user plan is not superstar Dec 19 11:18:06 volumio volumio[1352]: info: Removing audio output: Dec 19 11:18:06 volumio volumio[1352]: info: Stoppping Tunnel 1 Dec 19 11:18:06 volumio sudo[2168]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 19 11:18:06 volumio sudo[2168]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:18:06 volumio sudo[2170]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 19 11:18:06 volumio sudo[2170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:18:06 volumio sudo[2168]: pam_unix(sudo:session): session closed for user root Dec 19 11:18:06 volumio volumio[1352]: error: Cannot start Volumio Streaming Daemon Dec 19 11:18:06 volumio volumio[1352]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 19 11:18:06 volumio volumio[1352]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 19 11:18:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 19 11:18:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 19 11:18:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 19 11:18:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 19 11:18:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 19 11:18:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 19 11:18:06 volumio sudo[2170]: pam_unix(sudo:session): session closed for user root Dec 19 11:18:06 volumio volumio[1352]: info: Remote SSH Stopped Dec 19 11:18:12 volumio volumio[1352]: error: error Dec 19 11:18:12 volumio volumio[1352]: error: error Dec 19 11:18:12 volumio volumio[1352]: error: error Dec 19 11:18:14 volumio volumio[1352]: info: Setting Geolocation for MyVolumio to eu4 Dec 19 11:18:14 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 11:18:14 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 11:18:14 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 11:18:15 volumio volumio[1352]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 19 11:18:15 volumio volumio[1352]: info: Updating MyVolumio device info Dec 19 11:18:15 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 11:18:15 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 11:18:15 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 11:18:16 volumio volumio[1352]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 19 11:18:19 volumio volumio[1352]: info: MYVOLUMIO: Adding device Dec 19 11:18:19 volumio volumio[1352]: info: MYVOLUMIO: Evaluating Server Dec 19 11:18:24 volumio volumio[1352]: info: Setting Geolocation for MyVolumio to eu6 Dec 19 11:18:24 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 11:18:24 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 11:18:24 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 11:18:25 volumio volumio[1352]: info: Updating MyVolumio device info Dec 19 11:18:25 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 11:18:25 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 11:18:25 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 11:18:30 volumio volumio[1352]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 19 11:18:31 volumio volumio[1352]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 19 11:19:07 volumio sshd[2350]: pam_unix(sshd:auth): authentication failure; logname= uid=0 euid=0 tty=ssh ruser= rhost=10.50.5.69 user=volumio Dec 19 11:19:09 volumio sshd[2350]: Failed password for volumio from 10.50.5.69 port 54008 ssh2 Dec 19 11:19:12 volumio volumio[1352]: error: error Dec 19 11:19:12 volumio volumio[1352]: error: error Dec 19 11:19:12 volumio volumio[1352]: error: error Dec 19 11:19:14 volumio sshd[2350]: Accepted password for volumio from 10.50.5.69 port 54008 ssh2 Dec 19 11:19:14 volumio sshd[2350]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) Dec 19 11:19:14 volumio systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Dec 19 11:19:14 volumio systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Dec 19 11:19:14 volumio systemd-logind[772]: New session 1 of user volumio. Dec 19 11:19:14 volumio systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Dec 19 11:19:14 volumio systemd[1]: Starting user@1000.service - User Manager for UID 1000... Dec 19 11:19:14 volumio (systemd)[2377]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Dec 19 11:19:14 volumio systemd[2377]: Queued start job for default target default.target. Dec 19 11:19:14 volumio systemd[2377]: Created slice app.slice - User Application Slice. Dec 19 11:19:14 volumio systemd[2377]: Reached target paths.target - Paths. Dec 19 11:19:14 volumio systemd[2377]: Reached target timers.target - Timers. Dec 19 11:19:14 volumio systemd[2377]: Starting dbus.socket - D-Bus User Message Bus Socket... Dec 19 11:19:14 volumio systemd[2377]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Dec 19 11:19:14 volumio systemd[2377]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Dec 19 11:19:14 volumio systemd[2377]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Dec 19 11:19:14 volumio systemd[2377]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Dec 19 11:19:14 volumio systemd[2377]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Dec 19 11:19:14 volumio systemd[2377]: Listening on dbus.socket - D-Bus User Message Bus Socket. Dec 19 11:19:14 volumio systemd[2377]: Reached target sockets.target - Sockets. Dec 19 11:19:14 volumio systemd[2377]: Reached target basic.target - Basic System. Dec 19 11:19:14 volumio systemd[1]: Started user@1000.service - User Manager for UID 1000. Dec 19 11:19:14 volumio systemd[1]: Started session-1.scope - Session 1 of User volumio. Dec 19 11:19:14 volumio systemd[2377]: Started mpris-proxy.service - Bluetooth mpris proxy. Dec 19 11:19:14 volumio systemd[2377]: Reached target default.target - Main User Target. Dec 19 11:19:14 volumio systemd[2377]: Startup finished in 323ms. Dec 19 11:19:14 volumio sshd[2373]: Accepted password for volumio from 10.50.5.69 port 54009 ssh2 Dec 19 11:19:14 volumio systemd[2377]: Created slice session.slice - User Core Session Slice. Dec 19 11:19:14 volumio systemd[2377]: Starting dbus.service - D-Bus User Message Bus... Dec 19 11:19:14 volumio sshd[2373]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) Dec 19 11:19:14 volumio systemd-logind[772]: New session 3 of user volumio. Dec 19 11:19:14 volumio systemd[1]: Started session-3.scope - Session 3 of User volumio. Dec 19 11:19:14 volumio systemd[2377]: Started dbus.service - D-Bus User Message Bus. Dec 19 11:19:14 volumio sshd[2350]: pam_env(sshd:session): deprecated reading of user environment enabled Dec 19 11:19:14 volumio sshd[2373]: pam_env(sshd:session): deprecated reading of user environment enabled Dec 19 11:19:16 volumio sshd[2408]: error: Failed to allocate internet-domain X11 display socket. Dec 19 11:19:20 volumio sudo[2443]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 19 11:19:20 volumio sudo[2443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:19:20 volumio sudo[2443]: pam_unix(sudo:session): session closed for user root Dec 19 11:19:20 volumio sudo[2445]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 19 11:19:20 volumio sudo[2445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:19:20 volumio sudo[2445]: pam_unix(sudo:session): session closed for user root Dec 19 11:19:20 volumio volumio[1352]: verbose: New Socket.io Connection to 10.50.180.149 from 10.50.5.69 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Dec 19 11:19:20 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 19 11:19:20 volumio volumio[1352]: info: CoreCommandRouter::volumioGetState Dec 19 11:19:20 volumio volumio[1352]: info: CorePlayQueue::getTrack 0 Dec 19 11:19:20 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 19 11:19:20 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 19 11:19:20 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 19 11:19:20 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 19 11:19:20 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 19 11:19:22 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 19 11:19:22 volumio volumio[1352]: info: Received Get System Info Dec 19 11:19:22 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 19 11:19:22 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 19 11:19:22 volumio volumio[1352]: info: Discovery: Getting this device information Dec 19 11:19:22 volumio volumio[1352]: info: CoreCommandRouter::volumioGetState Dec 19 11:19:22 volumio volumio[1352]: info: CorePlayQueue::getTrack 0 Dec 19 11:19:22 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 19 11:19:23 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 19 11:19:23 volumio volumio[1352]: info: Received Get System Info Dec 19 11:19:23 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 19 11:19:23 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 19 11:19:23 volumio volumio[1352]: info: Discovery: Getting this device information Dec 19 11:19:23 volumio volumio[1352]: info: CoreCommandRouter::volumioGetState Dec 19 11:19:23 volumio volumio[1352]: info: CorePlayQueue::getTrack 0 Dec 19 11:19:23 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 19 11:19:30 volumio volumio[1352]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 11:19:36 volumio volumio[1352]: info: Received OAUTH Data Dec 19 11:19:36 volumio volumio[1352]: info: Executing Spotify Oauth Login Dec 19 11:19:36 volumio volumio[1352]: info: Saving Spotify Refresh Token Dec 19 11:19:36 volumio sudo[2492]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 19 11:19:36 volumio sudo[2492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:19:36 volumio sudo[2492]: pam_unix(sudo:session): session closed for user root Dec 19 11:19:37 volumio sudo[2494]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 19 11:19:37 volumio sudo[2494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:19:37 volumio sudo[2494]: pam_unix(sudo:session): session closed for user root Dec 19 11:19:37 volumio volumio[1352]: verbose: New Socket.io Connection to 10.50.180.149 from 10.50.5.69 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Dec 19 11:19:37 volumio volumio[1352]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 11:19:37 volumio volumio[1352]: info: CoreCommandRouter::volumioGetState Dec 19 11:19:37 volumio volumio[1352]: info: CorePlayQueue::getTrack 0 Dec 19 11:19:37 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 19 11:19:37 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 19 11:19:37 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 19 11:19:37 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 19 11:19:38 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 19 11:19:38 volumio volumio[1352]: info: Received Get System Info Dec 19 11:19:38 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 19 11:19:38 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 19 11:19:38 volumio volumio[1352]: info: Discovery: Getting this device information Dec 19 11:19:38 volumio volumio[1352]: info: CoreCommandRouter::volumioGetState Dec 19 11:19:38 volumio volumio[1352]: info: CorePlayQueue::getTrack 0 Dec 19 11:19:38 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 19 11:19:39 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 19 11:19:39 volumio volumio[1352]: info: Received Get System Info Dec 19 11:19:39 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 19 11:19:39 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 19 11:19:39 volumio volumio[1352]: info: Discovery: Getting this device information Dec 19 11:19:39 volumio volumio[1352]: info: CoreCommandRouter::volumioGetState Dec 19 11:19:39 volumio volumio[1352]: info: CorePlayQueue::getTrack 0 Dec 19 11:19:39 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 19 11:19:41 volumio volumio[1352]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 19 11:19:41 volumio volumio[1352]: SPOTIFY: BQD7x3fliqxgWR_kDdpbugCfxF1tR0Pr_QamrXmjPAVt1Xlff_4HPTMboWz5oT3GChX8hhCJvqLPvJjgYkuKQtvKkXaVcSGwOCsrwBm-GnoObzDUMd9vq8ISjfFZjWafRsTksql_wNiXDXncDGqKvwAQa01Y292LQJUhfrlbZDIDZ7o0ni_3-IkUl9JjB42a4G28tPNXF5mbqihoJT1rgGgZMX4gPw6etTMbcpTjlP85CE5iYKrvidoQq33XPKnv499DcdOAMABiuKqlGaH8eoI2zF40iaMf86o6mH_4uEm8IHzhklc1zqoQ Dec 19 11:19:41 volumio volumio[1352]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 19 11:19:41 volumio volumio[1352]: info: New Spotify access token = BQD7x3fliqxgWR_kDdpbugCfxF1tR0Pr_QamrXmjPAVt1Xlff_4HPTMboWz5oT3GChX8hhCJvqLPvJjgYkuKQtvKkXaVcSGwOCsrwBm-GnoObzDUMd9vq8ISjfFZjWafRsTksql_wNiXDXncDGqKvwAQa01Y292LQJUhfrlbZDIDZ7o0ni_3-IkUl9JjB42a4G28tPNXF5mbqihoJT1rgGgZMX4gPw6etTMbcpTjlP85CE5iYKrvidoQq33XPKnv499DcdOAMABiuKqlGaH8eoI2zF40iaMf86o6mH_4uEm8IHzhklc1zqoQ Dec 19 11:19:41 volumio volumio[1352]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 19 11:19:47 volumio volumio[1352]: SPOTIFY: User informations: {"country":"PL","display_name":"Marcin","email":"marcin.kochanski@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/f0ylfjtu126mqd7ly5pm4apzv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/f0ylfjtu126mqd7ly5pm4apzv","id":"f0ylfjtu126mqd7ly5pm4apzv","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee851a43b62ce7ae5d6034360e19","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b821a43b62ce7ae5d6034360e19","width":64}],"product":"premium","type":"user","uri":"spotify:user:f0ylfjtu126mqd7ly5pm4apzv"} Dec 19 11:19:47 volumio volumio[1352]: info: Creating Spotify config file Dec 19 11:19:47 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 11:19:47 volumio volumio[1352]: info: Spotify config file written Dec 19 11:19:47 volumio sudo[2539]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 19 11:19:47 volumio sudo[2539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:19:47 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 19 11:19:47 volumio systemd[1]: go-librespot-daemon.service: Killing process 1884 (go-librespot) with signal SIGKILL. Dec 19 11:19:47 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 19 11:19:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 19 11:19:47 volumio volumio[1352]: info: Connection to go-librespot Websocket closed Dec 19 11:19:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 19 11:19:47 volumio go-librespot[2541]: go-librespot daemon starting... Dec 19 11:19:47 volumio sudo[2539]: pam_unix(sudo:session): session closed for user root Dec 19 11:19:47 volumio go-librespot[2542]: time="2025-12-19T11:19:47+01:00" level=info msg="running go-librespot 0.4.0" Dec 19 11:19:47 volumio go-librespot[2542]: time="2025-12-19T11:19:47+01:00" level=debug msg="app state loaded" Dec 19 11:19:47 volumio go-librespot[2542]: time="2025-12-19T11:19:47+01:00" level=debug msg="stored credentials not found" Dec 19 11:19:47 volumio go-librespot[2542]: time="2025-12-19T11:19:47+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 19 11:19:50 volumio volumio[1352]: info: Initializing connection to go-librespot Websocket Dec 19 11:19:50 volumio go-librespot[2542]: time="2025-12-19T11:19:50+01:00" level=debug msg="new websocket client" Dec 19 11:19:50 volumio volumio[1352]: info: Connection to go-librespot Websocket established Dec 19 11:19:50 volumio volumio[1352]: info: go-librespot daemon successfully initialized Dec 19 11:19:52 volumio go-librespot[2542]: time="2025-12-19T11:19:52+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 19 11:19:52 volumio go-librespot[2542]: time="2025-12-19T11:19:52+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 19 11:19:52 volumio go-librespot[2542]: time="2025-12-19T11:19:52+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 19 11:19:52 volumio go-librespot[2542]: time="2025-12-19T11:19:52+01:00" level=info msg="zeroconf server listening on port 38543" Dec 19 11:19:52 volumio volumio[1352]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 19 11:19:52 volumio volumio[1352]: SPOTIFY: BQA_1LQkhk_xP5htHnXPVptBtB8Y6hQPjVhPt4Opof9MaoMwvxYkbzlJBuT7X9_rAfd3q797KfyIV1AceTyGov_NU-KlSpdydFQWiqfq_7ZXSJAfbEQ5pWD1mCcpnW-NZMloxiY2sb2OeJdXWygQ36b1hZCWE3OalH7bUpPN29R2B1FTS8e6FA9Yk_HW1cedp2W3iR2B1k1g2E5JGLbtPVWBOS3hlu-F1QXreyT3KRBESd0QpxbfjAs6rW190Rw7zXyU0YFBGDu3c8uaQNg9VVld_wBEsg-yO4RnzcXNmuMnokws7mv4HwLs Dec 19 11:19:52 volumio volumio[1352]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 19 11:19:52 volumio volumio[1352]: info: New Spotify access token = BQA_1LQkhk_xP5htHnXPVptBtB8Y6hQPjVhPt4Opof9MaoMwvxYkbzlJBuT7X9_rAfd3q797KfyIV1AceTyGov_NU-KlSpdydFQWiqfq_7ZXSJAfbEQ5pWD1mCcpnW-NZMloxiY2sb2OeJdXWygQ36b1hZCWE3OalH7bUpPN29R2B1FTS8e6FA9Yk_HW1cedp2W3iR2B1k1g2E5JGLbtPVWBOS3hlu-F1QXreyT3KRBESd0QpxbfjAs6rW190Rw7zXyU0YFBGDu3c8uaQNg9VVld_wBEsg-yO4RnzcXNmuMnokws7mv4HwLs Dec 19 11:19:52 volumio volumio[1352]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 19 11:19:53 volumio volumio[1352]: info: Getting Spotify volume Dec 19 11:19:53 volumio volumio[1352]: info: Initializing connection to go-librespot Websocket Dec 19 11:19:53 volumio go-librespot[2542]: time="2025-12-19T11:19:53+01:00" level=debug msg="new websocket client" Dec 19 11:19:53 volumio volumio[1352]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 19 11:19:53 volumio volumio[1352]: info: Connection to go-librespot Websocket established Dec 19 11:19:53 volumio volumio[1352]: info: CoreCommandRouter::volumioGetState Dec 19 11:19:53 volumio volumio[1352]: info: CorePlayQueue::getTrack 0 Dec 19 11:19:53 volumio volumio[1352]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 19 11:19:55 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 19 11:19:55 volumio volumio[1352]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 19 11:19:55 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 19 11:19:55 volumio volumio[1352]: info: Received Get System Version Dec 19 11:19:55 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 19 11:19:55 volumio volumio[1352]: info: Received Get System Info Dec 19 11:19:55 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 19 11:19:55 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 19 11:19:55 volumio volumio[1352]: info: Discovery: Getting this device information Dec 19 11:19:55 volumio volumio[1352]: info: CoreCommandRouter::volumioGetState Dec 19 11:19:55 volumio volumio[1352]: info: CorePlayQueue::getTrack 0 Dec 19 11:19:55 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 19 11:19:56 volumio volumio[1352]: info: Getting Spotify volume Dec 19 11:19:56 volumio volumio[1352]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 19 11:19:56 volumio volumio[1352]: info: CoreCommandRouter::volumioGetState Dec 19 11:19:56 volumio volumio[1352]: info: CorePlayQueue::getTrack 0 Dec 19 11:19:56 volumio volumio[1352]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 19 11:19:57 volumio volumio[1352]: SPOTIFY: User informations: {"country":"PL","display_name":"Marcin","email":"marcin.kochanski@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/f0ylfjtu126mqd7ly5pm4apzv"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/f0ylfjtu126mqd7ly5pm4apzv","id":"f0ylfjtu126mqd7ly5pm4apzv","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee851a43b62ce7ae5d6034360e19","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b821a43b62ce7ae5d6034360e19","width":64}],"product":"premium","type":"user","uri":"spotify:user:f0ylfjtu126mqd7ly5pm4apzv"} Dec 19 11:19:57 volumio volumio[1352]: info: Spotify Successfully logged in Dec 19 11:19:57 volumio volumio[1352]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 19 11:19:57 volumio volumio[1352]: info: [1766139597798] CoreMusicLibrary::Adding element Spotify Dec 19 11:19:57 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 11:19:57 volumio volumio[1352]: Cannot find translation for source YouTube2 Dec 19 11:19:57 volumio volumio[1352]: Cannot find translation for source YouTube Music Dec 19 11:19:57 volumio volumio[1352]: Cannot find translation for source Personal Radio Dec 19 11:19:57 volumio volumio[1352]: Cannot find translation for source Spotify Dec 19 11:19:59 volumio volumio[1352]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Dec 19 11:19:59 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Dec 19 11:19:59 volumio volumio[1352]: info: Creating Spotify config file Dec 19 11:19:59 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 11:19:59 volumio volumio[1352]: info: Spotify config file written Dec 19 11:19:59 volumio sudo[2602]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 19 11:19:59 volumio sudo[2602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:19:59 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 19 11:19:59 volumio systemd[1]: go-librespot-daemon.service: Killing process 2566 (go-librespot) with signal SIGKILL. Dec 19 11:19:59 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 19 11:19:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 19 11:19:59 volumio volumio[1352]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 11:19:59 volumio volumio[1352]: Error: socket hang up Dec 19 11:19:59 volumio volumio[1352]: at connResetException (node:internal/errors:720:14) Dec 19 11:19:59 volumio volumio[1352]: at Socket.socketOnEnd (node:_http_client:519:23) Dec 19 11:19:59 volumio volumio[1352]: at Socket.emit (node:events:526:35) Dec 19 11:19:59 volumio volumio[1352]: at endReadableNT (node:internal/streams/readable:1376:12) Dec 19 11:19:59 volumio volumio[1352]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Dec 19 11:19:59 volumio volumio[1352]: code: 'ECONNRESET', Dec 19 11:19:59 volumio volumio[1352]: response: undefined Dec 19 11:19:59 volumio volumio[1352]: } Dec 19 11:19:59 volumio volumio[1352]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 11:19:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 19 11:19:59 volumio go-librespot[2604]: go-librespot daemon starting... Dec 19 11:19:59 volumio sudo[2602]: pam_unix(sudo:session): session closed for user root Dec 19 11:19:59 volumio go-librespot[2608]: time="2025-12-19T11:19:59+01:00" level=info msg="running go-librespot 0.4.0" Dec 19 11:19:59 volumio go-librespot[2608]: time="2025-12-19T11:19:59+01:00" level=debug msg="app state loaded" Dec 19 11:19:59 volumio go-librespot[2608]: time="2025-12-19T11:19:59+01:00" level=debug msg="stored credentials not found" Dec 19 11:19:59 volumio go-librespot[2608]: time="2025-12-19T11:19:59+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 19 11:19:59 volumio sudo[2625]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-19 11:18' Dec 19 11:19:59 volumio sudo[2625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"