Dec 14 19:06:47 volumio volumio[1275]: verbose: New Socket.io Connection to 192.168.70.109:3000 from 192.168.70.111 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 14 19:06:47 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 19:06:47 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 19:06:47 volumio volumio[1275]: info: Discovery: Getting this device information Dec 14 19:06:47 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 14 19:06:47 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 14 19:06:47 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 19:06:51 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 19:06:51 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 19:06:51 volumio volumio[1275]: info: Discovery: Getting this device information Dec 14 19:06:51 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 14 19:06:51 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 14 19:06:51 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 19:06:51 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 14 19:06:51 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 14 19:06:51 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 14 19:06:51 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 14 19:06:51 volumio sudo[2475]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 14 19:06:51 volumio sudo[2475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 19:06:51 volumio sudo[2479]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 14 19:06:51 volumio sudo[2479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 19:06:51 volumio sudo[2486]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 14 19:06:51 volumio sudo[2490]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 14 19:06:51 volumio sudo[2486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 19:06:51 volumio sudo[2490]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 19:06:51 volumio sudo[2486]: pam_unix(sudo:session): session closed for user root Dec 14 19:06:51 volumio sudo[2479]: pam_unix(sudo:session): session closed for user root Dec 14 19:06:51 volumio sudo[2490]: pam_unix(sudo:session): session closed for user root Dec 14 19:06:51 volumio sudo[2475]: pam_unix(sudo:session): session closed for user root Dec 14 19:06:51 volumio sudo[2497]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 19:06:51 volumio sudo[2497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 19:06:51 volumio sudo[2501]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 19:06:51 volumio sudo[2501]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 19:06:51 volumio sudo[2501]: pam_unix(sudo:session): session closed for user root Dec 14 19:06:51 volumio sudo[2497]: pam_unix(sudo:session): session closed for user root Dec 14 19:06:51 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 19:06:51 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 19:06:51 volumio volumio[1275]: info: Discovery: Getting this device information Dec 14 19:06:51 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 14 19:06:51 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 14 19:06:51 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 19:06:51 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 14 19:06:51 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 14 19:06:51 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 14 19:06:51 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 14 19:06:51 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 19:06:51 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 19:06:51 volumio volumio[1275]: info: Discovery: Getting this device information Dec 14 19:06:51 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 14 19:06:51 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 14 19:06:51 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 19:06:52 volumio volumio[1275]: verbose: New Socket.io Connection to 192.168.70.109:3000 from 192.168.70.111 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 14 19:06:52 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 14 19:06:52 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 14 19:06:52 volumio sudo[2507]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 19:06:52 volumio sudo[2507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 19:06:52 volumio sudo[2509]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 19:06:52 volumio sudo[2507]: pam_unix(sudo:session): session closed for user root Dec 14 19:06:52 volumio sudo[2509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 19:06:52 volumio sudo[2509]: pam_unix(sudo:session): session closed for user root Dec 14 19:06:52 volumio volumio[1275]: verbose: New Socket.io Connection to 192.168.70.109 from 192.168.70.111 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Dec 14 19:06:52 volumio sudo[2513]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 19:06:52 volumio sudo[2513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 19:06:52 volumio sudo[2515]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 19:06:52 volumio sudo[2513]: pam_unix(sudo:session): session closed for user root Dec 14 19:06:52 volumio sudo[2515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 19:06:52 volumio sudo[2515]: pam_unix(sudo:session): session closed for user root Dec 14 19:06:52 volumio volumio[1275]: verbose: New Socket.io Connection to 192.168.70.109 from 192.168.70.111 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Dec 14 19:06:53 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 14 19:06:53 volumio volumio[1275]: info: CoreCommandRouter::volumioGetVisibleSources Dec 14 19:06:53 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 19:06:53 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 14 19:06:53 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 14 19:06:53 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 14 19:06:53 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 14 19:06:53 volumio volumio[1275]: info: Received Get System Info Dec 14 19:06:53 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 19:06:53 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 19:06:53 volumio volumio[1275]: info: Discovery: Getting this device information Dec 14 19:06:53 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 14 19:06:53 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 14 19:06:53 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 19:06:53 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 14 19:06:53 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 14 19:06:53 volumio volumio[1275]: info: Listing playlists Dec 14 19:06:53 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 14 19:06:53 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 14 19:06:53 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 14 19:06:54 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 14 19:06:54 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 19:06:54 volumio volumio[1275]: info: Received Get System Info Dec 14 19:06:54 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 19:06:54 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 19:06:54 volumio volumio[1275]: info: Discovery: Getting this device information Dec 14 19:06:54 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 14 19:06:54 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 14 19:06:54 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 19:06:56 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 19:06:56 volumio volumio[1275]: info: Received Get System Info Dec 14 19:06:56 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 19:06:56 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 19:06:56 volumio volumio[1275]: info: Discovery: Getting this device information Dec 14 19:06:56 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 14 19:06:56 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 14 19:06:56 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 19:06:56 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 19:06:56 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 14 19:07:01 volumio volumio[1275]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 19:07:02 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 14 19:07:06 volumio volumio[1275]: info: Received OAUTH Data Dec 14 19:07:06 volumio volumio[1275]: info: Executing Spotify Oauth Login Dec 14 19:07:06 volumio volumio[1275]: info: Saving Spotify Refresh Token Dec 14 19:07:07 volumio volumio[1275]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 14 19:07:07 volumio volumio[1275]: SPOTIFY: BQBuEcZCpzuYOivfX29jfNizlDtc3FhMYXFpXnTlkPLNPmKvJb64WX9Sz8BY3VZBl0oC0GfY6AQHvyEJ7B7LQlMNg4iukXN2Co4f_Wk9Mb8CZug-HpqR5rd-f0js43i2dYRrGUw7nQ4bAgND6tSw5au0nhFnGUM8kVsbspAdGlAG_t7vXpKzaM_TL9CQ_v2RrRyAgUnpBVId1IUqAA120g9EN0wfesoljYAwQXS2OHqGHU68LH1E8LLn-WoBfYMIQA2YYHkzVVp7oW1HheP53aO41R8U-0yHeUpO Dec 14 19:07:07 volumio volumio[1275]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 14 19:07:07 volumio volumio[1275]: info: New Spotify access token = BQBuEcZCpzuYOivfX29jfNizlDtc3FhMYXFpXnTlkPLNPmKvJb64WX9Sz8BY3VZBl0oC0GfY6AQHvyEJ7B7LQlMNg4iukXN2Co4f_Wk9Mb8CZug-HpqR5rd-f0js43i2dYRrGUw7nQ4bAgND6tSw5au0nhFnGUM8kVsbspAdGlAG_t7vXpKzaM_TL9CQ_v2RrRyAgUnpBVId1IUqAA120g9EN0wfesoljYAwQXS2OHqGHU68LH1E8LLn-WoBfYMIQA2YYHkzVVp7oW1HheP53aO41R8U-0yHeUpO Dec 14 19:07:07 volumio volumio[1275]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 14 19:07:07 volumio sudo[2557]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 19:07:07 volumio sudo[2557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 19:07:07 volumio sudo[2559]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 19:07:07 volumio sudo[2559]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 19:07:07 volumio sudo[2557]: pam_unix(sudo:session): session closed for user root Dec 14 19:07:07 volumio sudo[2559]: pam_unix(sudo:session): session closed for user root Dec 14 19:07:07 volumio volumio[1275]: verbose: New Socket.io Connection to 192.168.70.109 from 192.168.70.111 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Dec 14 19:07:07 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 14 19:07:07 volumio volumio[1275]: info: CoreCommandRouter::volumioGetVisibleSources Dec 14 19:07:07 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 19:07:07 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 14 19:07:07 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 14 19:07:07 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 14 19:07:07 volumio volumio[1275]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 19:07:07 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 14 19:07:07 volumio volumio[1275]: info: Received Get System Info Dec 14 19:07:07 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 19:07:07 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 19:07:07 volumio volumio[1275]: info: Discovery: Getting this device information Dec 14 19:07:07 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 14 19:07:07 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 14 19:07:07 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 19:07:07 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 14 19:07:07 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 14 19:07:07 volumio volumio[1275]: info: Listing playlists Dec 14 19:07:07 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 14 19:07:07 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 14 19:07:07 volumio volumio[1275]: SPOTIFY: User informations: {"country":"DE","display_name":"Arne Schuch","email":"arneschuch@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/1199741024"},"followers":{"href":null,"total":7},"href":"https://api.spotify.com/v1/users/1199741024","id":"1199741024","images":[{"height":300,"url":"https://scontent-tpe1-1.xx.fbcdn.net/v/t39.30808-1/462084096_2785532054987188_8628156231518826675_n.jpg?stp=dst-jpg_s320x320_tt6&_nc_cat=104&ccb=1-7&_nc_sid=79bf43&_nc_ohc=UHK9Lcpy7bMQ7kNvwHTkG-a&_nc_oc=Adl1ntoeZAJ9SIJ15tNDDzUQLdt7zbBhwdFr94LXK_qWEN8ptPOgcI9Sb5aVhufhiE1VF76K9RGeyLvp_DLMserA&_nc_zt=24&_nc_ht=scontent-tpe1-1.xx&edm=AP4hL3IEAAAA&_nc_gid=X6sXHOVEnywnCFwFDYGc3A&_nc_tpa=Q5bMBQH6GjcDjqcJVPLoCMEoF1m3Hb6jehdSaREFhjIOxI0XrtguflKOqGT7pfLnKWjDKSN8aewd&oh=00_Afkkat7PhtZm8YcsqOE4n8jC0Re-8YjAR-vmIoYKr3otmw&oe=69444D19","width":300},{"height":64,"url":"https://scontent-tpe1-1.xx.fbcdn.net/v/t39.30808-1/462084096_2785532054987188_8628156231518826675_n.jpg?stp=cp0_dst-jpg_s50x50_tt6&_nc_cat=104&ccb=1-7&_nc_sid=28885b&_nc_ohc=UHK9Lcpy7bMQ7kNvwHTkG-a&_nc_oc=Adl1ntoeZAJ9SIJ15tNDDzUQLdt7zbBhwdFr94LXK_qWEN8ptPOgcI9Sb5aVhufhiE1VF76K9RGeyLvp_DLMserA&_nc_zt=24&_nc_ht=scontent-tpe1-1.xx&edm=AP4hL3IEAAAA&_nc_gid=X6sXHOVEnywnCFwFDYGc3A&_nc_tpa=Q5bMBQHdiugPBda-4-sv0P0XnUz3ylH0GrLvUm9i-AdHUaymqbwlp0pRU33JxR4LBQIYxtMKb8dK&oh=00_AfkvauFntZbjflEarYGQlgJIC7sHBj_yo2cpxfQTaa3fdQ&oe=69444D19","width":64}],"product":"premium","type":"user","uri":"spotify:user:1199741024"} Dec 14 19:07:07 volumio volumio[1275]: info: Creating Spotify config file Dec 14 19:07:07 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 19:07:07 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 14 19:07:07 volumio volumio[1275]: info: Spotify config file written Dec 14 19:07:07 volumio sudo[2564]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 14 19:07:07 volumio sudo[2564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 19:07:07 volumio volumio[1275]: info: Connection to go-librespot Websocket closed Dec 14 19:07:07 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 14 19:07:07 volumio volumio[1275]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 14 19:07:07 volumio volumio[1275]: SPOTIFY: BQDb4Erzw0SIyF8QB2ip1BaKoFfTYDd6htE5uAgpJg-8RbaAxOBSLfeWbQr5Z595ZdMhVE5jjrVv2_MLVBb_YLEh58G8ypce5RCCbkTt_SJL3BuenIAlt5FztMYshKyhak3n4P7AADAcAh_nlsZ9NCJf5Dqoe5CKNv7CzHHytLD5KZuchTYq1xUK6BXhIy-YseHBsdEW_ntvYc9IFjEPqk40iGvrpbJbhko2e_ZWH2ak9mCRLPYvDoMQPg3tMVkhBi6FSPBv6EbK7TdH_P6GlSIYVqaB9A-HAPBV Dec 14 19:07:07 volumio volumio[1275]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 14 19:07:07 volumio volumio[1275]: info: New Spotify access token = BQDb4Erzw0SIyF8QB2ip1BaKoFfTYDd6htE5uAgpJg-8RbaAxOBSLfeWbQr5Z595ZdMhVE5jjrVv2_MLVBb_YLEh58G8ypce5RCCbkTt_SJL3BuenIAlt5FztMYshKyhak3n4P7AADAcAh_nlsZ9NCJf5Dqoe5CKNv7CzHHytLD5KZuchTYq1xUK6BXhIy-YseHBsdEW_ntvYc9IFjEPqk40iGvrpbJbhko2e_ZWH2ak9mCRLPYvDoMQPg3tMVkhBi6FSPBv6EbK7TdH_P6GlSIYVqaB9A-HAPBV Dec 14 19:07:07 volumio volumio[1275]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 14 19:07:07 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 14 19:07:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 14 19:07:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 14 19:07:07 volumio go-librespot[2567]: go-librespot daemon starting... Dec 14 19:07:07 volumio volumio[1275]: SPOTIFY: User informations: {"country":"DE","display_name":"Arne Schuch","email":"arneschuch@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/1199741024"},"followers":{"href":null,"total":7},"href":"https://api.spotify.com/v1/users/1199741024","id":"1199741024","images":[{"height":300,"url":"https://scontent-tpe1-1.xx.fbcdn.net/v/t39.30808-1/462084096_2785532054987188_8628156231518826675_n.jpg?stp=dst-jpg_s320x320_tt6&_nc_cat=104&ccb=1-7&_nc_sid=79bf43&_nc_ohc=UHK9Lcpy7bMQ7kNvwHTkG-a&_nc_oc=Adl1ntoeZAJ9SIJ15tNDDzUQLdt7zbBhwdFr94LXK_qWEN8ptPOgcI9Sb5aVhufhiE1VF76K9RGeyLvp_DLMserA&_nc_zt=24&_nc_ht=scontent-tpe1-1.xx&edm=AP4hL3IEAAAA&_nc_gid=X6sXHOVEnywnCFwFDYGc3A&_nc_tpa=Q5bMBQH6GjcDjqcJVPLoCMEoF1m3Hb6jehdSaREFhjIOxI0XrtguflKOqGT7pfLnKWjDKSN8aewd&oh=00_Afkkat7PhtZm8YcsqOE4n8jC0Re-8YjAR-vmIoYKr3otmw&oe=69444D19","width":300},{"height":64,"url":"https://scontent-tpe1-1.xx.fbcdn.net/v/t39.30808-1/462084096_2785532054987188_8628156231518826675_n.jpg?stp=cp0_dst-jpg_s50x50_tt6&_nc_cat=104&ccb=1-7&_nc_sid=28885b&_nc_ohc=UHK9Lcpy7bMQ7kNvwHTkG-a&_nc_oc=Adl1ntoeZAJ9SIJ15tNDDzUQLdt7zbBhwdFr94LXK_qWEN8ptPOgcI9Sb5aVhufhiE1VF76K9RGeyLvp_DLMserA&_nc_zt=24&_nc_ht=scontent-tpe1-1.xx&edm=AP4hL3IEAAAA&_nc_gid=X6sXHOVEnywnCFwFDYGc3A&_nc_tpa=Q5bMBQHdiugPBda-4-sv0P0XnUz3ylH0GrLvUm9i-AdHUaymqbwlp0pRU33JxR4LBQIYxtMKb8dK&oh=00_AfkvauFntZbjflEarYGQlgJIC7sHBj_yo2cpxfQTaa3fdQ&oe=69444D19","width":64}],"product":"premium","type":"user","uri":"spotify:user:1199741024"} Dec 14 19:07:07 volumio volumio[1275]: info: Spotify Successfully logged in Dec 14 19:07:07 volumio volumio[1275]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 14 19:07:07 volumio volumio[1275]: info: [1765735627878] CoreMusicLibrary::Adding element Spotify Dec 14 19:07:07 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 19:07:07 volumio volumio[1275]: Cannot find translation for source Spotify Dec 14 19:07:07 volumio sudo[2564]: pam_unix(sudo:session): session closed for user root Dec 14 19:07:07 volumio go-librespot[2570]: time="2025-12-14T19:07:07+01:00" level=info msg="running go-librespot 0.4.0" Dec 14 19:07:07 volumio go-librespot[2570]: time="2025-12-14T19:07:07+01:00" level=debug msg="app state loaded" Dec 14 19:07:07 volumio go-librespot[2570]: time="2025-12-14T19:07:07+01:00" level=debug msg="stored credentials not found" Dec 14 19:07:07 volumio go-librespot[2570]: time="2025-12-14T19:07:07+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 14 19:07:08 volumio go-librespot[2570]: time="2025-12-14T19:07:08+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 14 19:07:08 volumio go-librespot[2570]: time="2025-12-14T19:07:08+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 14 19:07:08 volumio go-librespot[2570]: time="2025-12-14T19:07:08+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 14 19:07:08 volumio go-librespot[2570]: time="2025-12-14T19:07:08+01:00" level=info msg="zeroconf server listening on port 45111" Dec 14 19:07:08 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 14 19:07:09 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 19:07:09 volumio volumio[1275]: info: Received Get System Info Dec 14 19:07:09 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 19:07:09 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 19:07:09 volumio volumio[1275]: info: Discovery: Getting this device information Dec 14 19:07:09 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 14 19:07:09 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 14 19:07:09 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 19:07:09 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 19:07:09 volumio volumio[1275]: info: Received Get System Info Dec 14 19:07:09 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 19:07:09 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 19:07:09 volumio volumio[1275]: info: Discovery: Getting this device information Dec 14 19:07:09 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 14 19:07:09 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 14 19:07:09 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 19:07:10 volumio volumio[1275]: info: Initializing connection to go-librespot Websocket Dec 14 19:07:10 volumio go-librespot[2570]: time="2025-12-14T19:07:10+01:00" level=debug msg="new websocket client" Dec 14 19:07:10 volumio volumio[1275]: info: Connection to go-librespot Websocket established Dec 14 19:07:10 volumio volumio[1275]: info: go-librespot daemon successfully initialized Dec 14 19:07:13 volumio go-librespot[2570]: time="2025-12-14T19:07:13+01:00" level=debug msg="obtained new client token: AACFsVwTLIWVQmJvrK4Iz582hOJq6KStyXgtS9pLnJMQCzy9PXV+Eil3zo3bYdV7+FywRy7IWXB3acHT55AHu3qTyoLAY9FLgRxD/LBnCPr7OE9UQkmRrpWR01k91JkPOAs2Trx139JnRkwB9/ijO6QYEeu8knF0PHtclNt1s7VjHfKdGl7QKPfy/ZHC32Qk+uZo5D4ydliH96wACWzdrEaOHiQGRT7EQ6B59gLMMhcLGc33g1THg52p" Dec 14 19:07:13 volumio go-librespot[2570]: time="2025-12-14T19:07:13+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 14 19:07:13 volumio go-librespot[2570]: time="2025-12-14T19:07:13+01:00" level=debug msg="completed keyexchange" Dec 14 19:07:13 volumio go-librespot[2570]: time="2025-12-14T19:07:13+01:00" level=debug msg="completed challenge" Dec 14 19:07:13 volumio go-librespot[2570]: time="2025-12-14T19:07:13+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 14 19:07:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 14 19:07:13 volumio volumio[1275]: info: Connection to go-librespot Websocket closed Dec 14 19:07:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 14 19:07:13 volumio volumio[1275]: info: Getting Spotify volume Dec 14 19:07:13 volumio volumio[1275]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 19:07:13 volumio volumio[1275]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 19:07:13 volumio volumio[1275]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 14 19:07:13 volumio volumio[1275]: errno: -111, Dec 14 19:07:13 volumio volumio[1275]: code: 'ECONNREFUSED', Dec 14 19:07:13 volumio volumio[1275]: syscall: 'connect', Dec 14 19:07:13 volumio volumio[1275]: address: '127.0.0.1', Dec 14 19:07:13 volumio volumio[1275]: port: 9879, Dec 14 19:07:13 volumio volumio[1275]: response: undefined Dec 14 19:07:13 volumio volumio[1275]: } Dec 14 19:07:13 volumio volumio[1275]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 19:07:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 14 19:07:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 14 19:07:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 14 19:07:16 volumio go-librespot[2608]: go-librespot daemon starting... Dec 14 19:07:16 volumio go-librespot[2609]: time="2025-12-14T19:07:16+01:00" level=info msg="running go-librespot 0.4.0" Dec 14 19:07:16 volumio go-librespot[2609]: time="2025-12-14T19:07:16+01:00" level=debug msg="app state loaded" Dec 14 19:07:16 volumio go-librespot[2609]: time="2025-12-14T19:07:16+01:00" level=debug msg="stored credentials not found" Dec 14 19:07:16 volumio go-librespot[2609]: time="2025-12-14T19:07:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 14 19:07:17 volumio go-librespot[2609]: time="2025-12-14T19:07:17+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 14 19:07:17 volumio go-librespot[2609]: time="2025-12-14T19:07:17+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 14 19:07:17 volumio go-librespot[2609]: time="2025-12-14T19:07:17+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 14 19:07:17 volumio go-librespot[2609]: time="2025-12-14T19:07:17+01:00" level=info msg="zeroconf server listening on port 41805" Dec 14 19:07:17 volumio sudo[2618]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-14 19:06' Dec 14 19:07:17 volumio sudo[2618]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 19:07:17 volumio go-librespot[2609]: time="2025-12-14T19:07:17+01:00" level=debug msg="obtained new client token: AADQHk8yBRWFYGE840X4ftScEgCJHyzkXoit9oaemESAp/aJSNFSAZxv+tVYpehQWt0ziGsnaMa05avD7lyTHHSaK2zfzg9uqn6pWAj8WsoQA2LEtS4gFuPw59zlF2WchUZnuMAoyIRbt9w50dap4VLHKtYtN20qpKez6I3p+1Dy9qXtAv3EG2XlHH5TqN2zxiotOJzIf/vXu147Ervye5jg1jZnVfwQfOAX7s7x1OhJ4eid13ckKVSh" Dec 14 19:07:17 volumio go-librespot[2609]: time="2025-12-14T19:07:17+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"