-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2025-02-23 13:09:29 CET. -- Feb 23 13:08:25 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 13:08:25 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 13:08:25 volumio volumio[1023]: info: Discovery: Getting this device information Feb 23 13:08:25 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Feb 23 13:08:25 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 13:08:55 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 13:08:55 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 13:08:55 volumio volumio[1023]: info: Discovery: Getting this device information Feb 23 13:08:55 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Feb 23 13:08:55 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 13:09:04 volumio volumio[1023]: info: Received OAUTH Data Feb 23 13:09:04 volumio volumio[1023]: info: Executing Spotify Oauth Login Feb 23 13:09:04 volumio volumio[1023]: info: Saving Spotify Refresh Token Feb 23 13:09:04 volumio volumio[1023]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 23 13:09:04 volumio volumio[1023]: SPOTIFY: BQDy7AotFUAIUUK_5FL5U5jpdK0e-9mIGySc7zWVhRd-rTGHhX_RI5CiSKsHflEKC-_LSZSp_vXjOtvMIMuEiyf90Fz-8EOZjx4eMODfdf2qvcjuFPRUhl72TpOQXZAO2QL5R0IRM40u9uxyRaLpHXaOfpOkTNQGEOVU615JXsiysJQFkH272vcbtRo5C90yjzqmO-EssdtSKAKkM7b9hoXgnN6eog7qX5HaOwX_fnt6e0403zejU7rNNNcuJDHEeAzdB5stWOHffqJNFAZ8au-HiTQi89IQikRa-YIPA_Q Feb 23 13:09:04 volumio volumio[1023]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 23 13:09:04 volumio volumio[1023]: info: New Spotify access token = BQDy7AotFUAIUUK_5FL5U5jpdK0e-9mIGySc7zWVhRd-rTGHhX_RI5CiSKsHflEKC-_LSZSp_vXjOtvMIMuEiyf90Fz-8EOZjx4eMODfdf2qvcjuFPRUhl72TpOQXZAO2QL5R0IRM40u9uxyRaLpHXaOfpOkTNQGEOVU615JXsiysJQFkH272vcbtRo5C90yjzqmO-EssdtSKAKkM7b9hoXgnN6eog7qX5HaOwX_fnt6e0403zejU7rNNNcuJDHEeAzdB5stWOHffqJNFAZ8au-HiTQi89IQikRa-YIPA_Q Feb 23 13:09:04 volumio volumio[1023]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 23 13:09:04 volumio sudo[3907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 13:09:04 volumio sudo[3907]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:09:04 volumio sudo[3907]: pam_unix(sudo:session): session closed for user root Feb 23 13:09:04 volumio sudo[3910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 13:09:04 volumio sudo[3910]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:09:04 volumio sudo[3910]: pam_unix(sudo:session): session closed for user root Feb 23 13:09:04 volumio volumio[1023]: SPOTIFY: User informations: {"country":"DE","display_name":"Dirk","email":"dirk.jalas@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31dmxtlyeuokxbybax53z3bpwc6i"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31dmxtlyeuokxbybax53z3bpwc6i","id":"31dmxtlyeuokxbybax53z3bpwc6i","images":[],"product":"premium","type":"user","uri":"spotify:user:31dmxtlyeuokxbybax53z3bpwc6i"} Feb 23 13:09:04 volumio volumio[1023]: info: Creating Spotify config file Feb 23 13:09:04 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:09:04 volumio volumio[1023]: info: Spotify config file written Feb 23 13:09:04 volumio sudo[3916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 23 13:09:04 volumio sudo[3916]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:09:04 volumio volumio[1023]: verbose: New Socket.io Connection to 192.168.178.44 from 192.168.178.22 UA: Mozilla/5.0 (Linux; Android 15; Pixel 6a Build/AP4A.250205.002.A1; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.163 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 23 13:09:05 volumio systemd[1]: Stopping go-librespot Daemon... Feb 23 13:09:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Feb 23 13:09:05 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Feb 23 13:09:05 volumio systemd[1]: Stopped go-librespot Daemon. Feb 23 13:09:05 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 23 13:09:05 volumio systemd[1]: Started go-librespot Daemon. Feb 23 13:09:05 volumio go-librespot[3918]: go-librespot daemon starting... Feb 23 13:09:05 volumio volumio[1023]: info: Connection to go-librespot Websocket closed Feb 23 13:09:05 volumio sudo[3916]: pam_unix(sudo:session): session closed for user root Feb 23 13:09:05 volumio volumio[1023]: info: CoreCommandRouter::volumioGetVisibleSources Feb 23 13:09:05 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:09:05 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Feb 23 13:09:05 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 23 13:09:05 volumio volumio[1023]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 13:09:05 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 23 13:09:05 volumio volumio[1023]: info: Received Get System Info Feb 23 13:09:05 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 13:09:05 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 13:09:05 volumio volumio[1023]: info: Discovery: Getting this device information Feb 23 13:09:05 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Feb 23 13:09:05 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 13:09:05 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Feb 23 13:09:05 volumio volumio[1023]: info: Listing playlists Feb 23 13:09:05 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 23 13:09:05 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 23 13:09:05 volumio go-librespot[3918]: time="2025-02-23T13:09:05+01:00" level=info msg="running go-librespot 0.2.0" Feb 23 13:09:05 volumio go-librespot[3918]: time="2025-02-23T13:09:05+01:00" level=debug msg="app state loaded" Feb 23 13:09:05 volumio go-librespot[3918]: time="2025-02-23T13:09:05+01:00" level=debug msg="stored credentials not found" Feb 23 13:09:05 volumio go-librespot[3918]: time="2025-02-23T13:09:05+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 13:09:05 volumio volumio[1023]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 23 13:09:05 volumio volumio[1023]: SPOTIFY: BQA2ZcyerOcB4626kYSZrUHTgeyNO4sc9JAVTAHzauWvbmNKH3biT_7-5oJkSp1VFg5-DBvBiKDdu6ukUJ9_oVMDRRkOK3wMxYeXMFEkjQ5-FGdBzE0PDS1nvrE5C2eA9nAg5ZdBlA0f9qxwJ31yat46Qx0ogmxg_9kV0dJuhEIbBx1h3t5Ykw3iNzxiOZrR7BSDNcZVRrYGBJXDWhv4mzBmEe9izvU3_0Nc9NwD84Tqss4ilpPDuNNxDu0SwpzJrPTuB2GtyktUykVe0tJ-Ria-A5HN7pb_Rnx_cUUtM20 Feb 23 13:09:05 volumio volumio[1023]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 23 13:09:05 volumio volumio[1023]: info: New Spotify access token = BQA2ZcyerOcB4626kYSZrUHTgeyNO4sc9JAVTAHzauWvbmNKH3biT_7-5oJkSp1VFg5-DBvBiKDdu6ukUJ9_oVMDRRkOK3wMxYeXMFEkjQ5-FGdBzE0PDS1nvrE5C2eA9nAg5ZdBlA0f9qxwJ31yat46Qx0ogmxg_9kV0dJuhEIbBx1h3t5Ykw3iNzxiOZrR7BSDNcZVRrYGBJXDWhv4mzBmEe9izvU3_0Nc9NwD84Tqss4ilpPDuNNxDu0SwpzJrPTuB2GtyktUykVe0tJ-Ria-A5HN7pb_Rnx_cUUtM20 Feb 23 13:09:05 volumio volumio[1023]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 23 13:09:05 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 23 13:09:05 volumio volumio[1023]: SPOTIFY: User informations: {"country":"DE","display_name":"Dirk","email":"dirk.jalas@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31dmxtlyeuokxbybax53z3bpwc6i"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31dmxtlyeuokxbybax53z3bpwc6i","id":"31dmxtlyeuokxbybax53z3bpwc6i","images":[],"product":"premium","type":"user","uri":"spotify:user:31dmxtlyeuokxbybax53z3bpwc6i"} Feb 23 13:09:05 volumio volumio[1023]: info: Spotify Successfully logged in Feb 23 13:09:05 volumio volumio[1023]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:09:05 volumio volumio[1023]: info: [1740312545317] CoreMusicLibrary::Adding element Spotify Feb 23 13:09:05 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:09:05 volumio volumio[1023]: Cannot find translation for source Spotify Feb 23 13:09:05 volumio go-librespot[3918]: time="2025-02-23T13:09:05+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-gae2.spotify.com:80]" Feb 23 13:09:05 volumio go-librespot[3918]: time="2025-02-23T13:09:05+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 23 13:09:05 volumio go-librespot[3918]: time="2025-02-23T13:09:05+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 23 13:09:05 volumio go-librespot[3918]: time="2025-02-23T13:09:05+01:00" level=info msg="zeroconf server listening on port 36237" Feb 23 13:09:05 volumio go-librespot[3918]: time="2025-02-23T13:09:05+01:00" level=debug msg="obtained new client token: AAA40pg4gNey67eromKDnfgbYlldBmclaltqT2kh0ir+Ahgm0UwOdBEYVO2nyWm2Q2bGkG+Ow/6p+OKIN4K2zBAxGdnHxdhIjUXKHEnR+bQD8b/tWmtzcwOL7qaIgXZ/Ma0vy27juqxPXPqN1EQZjTOUMTUEM25hL8aEJz+C0dtW4/pDhRynm8mm4NyVKcKZTAZRZNEOWTYXjfInl+jSCDOV56vGUx97+wwj1MOC3GyNJ486LizrE0CuLQ==" Feb 23 13:09:05 volumio go-librespot[3918]: time="2025-02-23T13:09:05+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 23 13:09:05 volumio go-librespot[3918]: time="2025-02-23T13:09:05+01:00" level=debug msg="completed keyexchange" Feb 23 13:09:05 volumio go-librespot[3918]: time="2025-02-23T13:09:05+01:00" level=debug msg="completed challenge" Feb 23 13:09:05 volumio go-librespot[3918]: time="2025-02-23T13:09:05+01:00" level=info msg="authenticated AP as 31dmxtlyeuokxbybax53z3bpwc6i" Feb 23 13:09:05 volumio go-librespot[3918]: time="2025-02-23T13:09:05+01:00" level=info msg="authenticated Login5 as 31dmxtlyeuokxbybax53z3bpwc6i" Feb 23 13:09:05 volumio go-librespot[3918]: time="2025-02-23T13:09:05+01:00" level=debug msg="stored credentials for 31dmxtlyeuokxbybax53z3bpwc6i" Feb 23 13:09:05 volumio go-librespot[3918]: time="2025-02-23T13:09:05+01:00" level=debug msg="initializing zeroconf session, username: 31dmxtlyeuokxbybax53z3bpwc6i" Feb 23 13:09:06 volumio go-librespot[3918]: time="2025-02-23T13:09:06+01:00" level=debug msg="dealer connection opened" Feb 23 13:09:06 volumio go-librespot[3918]: time="2025-02-23T13:09:06+01:00" level=trace msg="starting accesspoint recv loop" Feb 23 13:09:06 volumio go-librespot[3918]: time="2025-02-23T13:09:06+01:00" level=trace msg="starting dealer recv loop" Feb 23 13:09:06 volumio go-librespot[3918]: time="2025-02-23T13:09:06+01:00" level=trace msg="received accesspoint ping" Feb 23 13:09:06 volumio go-librespot[3918]: time="2025-02-23T13:09:06+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 23 13:09:06 volumio go-librespot[3918]: time="2025-02-23T13:09:06+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 23 13:09:06 volumio go-librespot[3918]: time="2025-02-23T13:09:06+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 23 13:09:06 volumio go-librespot[3918]: time="2025-02-23T13:09:06+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 23 13:09:06 volumio go-librespot[3918]: time="2025-02-23T13:09:06+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 23 13:09:06 volumio go-librespot[3918]: time="2025-02-23T13:09:06+01:00" level=debug msg="received connection id: YjZkM2Y1MmYtMDE3Zi00NDk3LTkxOGItZmFmZmZlYWNhNmEzK2RlYWxlcit0Y3A6Ly8wYWNhNTkyMC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArNEZBRjU0M0Y5NDcxMDczQzBGMTk3QkExMEY4NjZDOTEyNjIyRTAyMEEyQzNCNkY5QTNEMkM3NTRBOTBCMjM4RA==" Feb 23 13:09:06 volumio go-librespot[3918]: time="2025-02-23T13:09:06+01:00" level=trace msg="received accesspoint pong ack" Feb 23 13:09:06 volumio go-librespot[3918]: time="2025-02-23T13:09:06+01:00" level=debug msg="put connect state because NEW_DEVICE" Feb 23 13:09:06 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 23 13:09:06 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 13:09:06 volumio volumio[1023]: info: Received Get System Info Feb 23 13:09:06 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 13:09:06 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 13:09:06 volumio volumio[1023]: info: Discovery: Getting this device information Feb 23 13:09:06 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Feb 23 13:09:06 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 13:09:07 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 13:09:07 volumio volumio[1023]: info: Received Get System Info Feb 23 13:09:07 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 13:09:07 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 13:09:07 volumio volumio[1023]: info: Discovery: Getting this device information Feb 23 13:09:07 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Feb 23 13:09:07 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 13:09:08 volumio volumio[1023]: info: Initializing connection to go-librespot Websocket Feb 23 13:09:08 volumio go-librespot[3918]: time="2025-02-23T13:09:08+01:00" level=debug msg="new websocket client" Feb 23 13:09:08 volumio volumio[1023]: info: Connection to go-librespot Websocket established Feb 23 13:09:08 volumio volumio[1023]: info: go-librespot daemon successfully initialized Feb 23 13:09:11 volumio volumio[1023]: info: Getting Spotify volume Feb 23 13:09:11 volumio volumio[1023]: info: Spotify volume: 100 Feb 23 13:09:11 volumio volumio[1023]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 23 13:09:11 volumio volumio[1023]: info: Initializing connection to go-librespot Websocket Feb 23 13:09:11 volumio go-librespot[3918]: time="2025-02-23T13:09:11+01:00" level=debug msg="new websocket client" Feb 23 13:09:11 volumio volumio[1023]: info: Connection to go-librespot Websocket established Feb 23 13:09:11 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Feb 23 13:09:11 volumio volumio[1023]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Feb 23 13:09:11 volumio volumio[1023]: SPOTIFY: SPOTIFY VOLUME 100 Feb 23 13:09:11 volumio volumio[1023]: SPOTIFY: VOLUMIO VOLUME 19 Feb 23 13:09:11 volumio volumio[1023]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 23 13:09:11 volumio volumio[1023]: info: Setting Spotify Volume from Volumio: 19 Feb 23 13:09:12 volumio volumio[1023]: SPOTIFY: SETTING SPOTIFY VOLUME 19 Feb 23 13:09:12 volumio volumio[1023]: info: Sending Spotify command with payload to local API: /player/volume Feb 23 13:09:12 volumio go-librespot[3918]: time="2025-02-23T13:09:12+01:00" level=debug msg="update volume to 12451/65535" Feb 23 13:09:13 volumio go-librespot[3918]: time="2025-02-23T13:09:13+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 23 13:09:13 volumio go-librespot[3918]: time="2025-02-23T13:09:13+01:00" level=trace msg="emitting websocket event: volume" Feb 23 13:09:13 volumio volumio[1023]: SPOTIFY: received: {"type":"volume","data":{"value":19,"max":100}} Feb 23 13:09:13 volumio volumio[1023]: SPOTIFY: RECEIVED SPOTIFY VOLUME 19 Feb 23 13:09:13 volumio volumio[1023]: SPOTIFY: received: {"type":"volume","data":{"value":19,"max":100}} Feb 23 13:09:13 volumio volumio[1023]: SPOTIFY: RECEIVED SPOTIFY VOLUME 19 Feb 23 13:09:14 volumio volumio[1023]: info: Getting Spotify volume Feb 23 13:09:14 volumio volumio[1023]: info: Spotify volume: 19 Feb 23 13:09:14 volumio volumio[1023]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 23 13:09:14 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Feb 23 13:09:14 volumio volumio[1023]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Feb 23 13:09:14 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 23 13:09:22 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 23 13:09:22 volumio volumio[1023]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 23 13:09:22 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 23 13:09:22 volumio volumio[1023]: info: Received Get System Version Feb 23 13:09:22 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:09:22 volumio volumio[1023]: info: Received Get System Info Feb 23 13:09:22 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 13:09:22 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 13:09:22 volumio volumio[1023]: info: Discovery: Getting this device information Feb 23 13:09:22 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Feb 23 13:09:22 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 13:09:25 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 13:09:25 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 13:09:25 volumio volumio[1023]: info: Discovery: Getting this device information Feb 23 13:09:25 volumio volumio[1023]: info: CoreCommandRouter::volumioGetState Feb 23 13:09:25 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 13:09:25 volumio volumio[1023]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 13:09:25 volumio volumio[1023]: info: Retrieving Cloud Streaming UI Feb 23 13:09:25 volumio volumio[1023]: info: Getting Tidal Cloud Configuration Feb 23 13:09:25 volumio volumio[1023]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 13:09:25 volumio volumio[1023]: info: Getting Qobuz Cloud Configuration Feb 23 13:09:25 volumio volumio[1023]: info: Asking plugin for UI Config Feb 23 13:09:25 volumio volumio[1023]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 13:09:25 volumio volumio[1023]: info: Getting Spotify Cloud Configuration Feb 23 13:09:25 volumio volumio[1023]: info: Asking plugin for UI Config Feb 23 13:09:25 volumio volumio[1023]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 13:09:25 volumio volumio[1023]: info: Saving Spotify Acccount Feb 23 13:09:25 volumio volumio[1023]: info: Got Tidal Cloud Configuration Feb 23 13:09:25 volumio volumio[1023]: info: Got it Feb 23 13:09:25 volumio volumio[1023]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 23 13:09:25 volumio volumio[1023]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 13:09:25 volumio volumio[1023]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Feb 23 13:09:25 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 23 13:09:25 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 23 13:09:25 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 23 13:09:25 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 23 13:09:25 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:09:25 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:09:25 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:09:25 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:09:25 volumio volumio[1023]: info: CoreCommandRouter::volumioGetBrowseSources Feb 23 13:09:25 volumio volumio[1023]: info: CoreCommandRouter::volumioGetBrowseSources Feb 23 13:09:25 volumio volumio[1023]: info: CoreCommandRouter::volumioGetBrowseSources Feb 23 13:09:25 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 23 13:09:25 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 23 13:09:27 volumio volumio[1023]: info: Disabling MyMusic plugin upnp Feb 23 13:09:27 volumio sudo[4010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Feb 23 13:09:27 volumio sudo[4010]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:09:27 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Feb 23 13:09:27 volumio volumio[1023]: error: Upnp client error: Error: This socket has been ended by the other party Feb 23 13:09:28 volumio volumio[1023]: info: Enabling MyMusic plugin upnp Feb 23 13:09:28 volumio volumio[1023]: info: Enabling plugin upnp Feb 23 13:09:28 volumio volumio[1023]: info: Loading plugin "upnp"... Feb 23 13:09:28 volumio volumio[1023]: info: [1740312568410] Starting Upmpd Daemon Feb 23 13:09:28 volumio volumio[1023]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:09:28 volumio volumio[1023]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 13:09:28 volumio volumio[1023]: Error: listen EADDRINUSE: address already in use :::6599 Feb 23 13:09:28 volumio volumio[1023]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Feb 23 13:09:28 volumio volumio[1023]: at listenInCluster (net.js:1366:12) Feb 23 13:09:28 volumio volumio[1023]: at Server.listen (net.js:1452:7) Feb 23 13:09:28 volumio volumio[1023]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Feb 23 13:09:28 volumio volumio[1023]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Feb 23 13:09:28 volumio volumio[1023]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Feb 23 13:09:28 volumio volumio[1023]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 23 13:09:28 volumio volumio[1023]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Feb 23 13:09:28 volumio volumio[1023]: code: 'EADDRINUSE', Feb 23 13:09:28 volumio volumio[1023]: errno: -98, Feb 23 13:09:28 volumio volumio[1023]: syscall: 'listen', Feb 23 13:09:28 volumio volumio[1023]: address: '::', Feb 23 13:09:28 volumio volumio[1023]: port: 6599 Feb 23 13:09:28 volumio volumio[1023]: } Feb 23 13:09:28 volumio volumio[1023]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 13:09:29 volumio sudo[4025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-23 13:08 Feb 23 13:09:29 volumio sudo[4025]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"