-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Wed 2025-03-12 22:03:23 CET. -- Mar 12 22:02:31 volumio ntpd[801]: Soliciting pool server 213.209.109.44 Mar 12 22:02:35 volumio volumio[906]: info: Received OAUTH Data Mar 12 22:02:35 volumio volumio[906]: info: Executing Spotify Oauth Login Mar 12 22:02:35 volumio volumio[906]: info: Saving Spotify Refresh Token Mar 12 22:02:35 volumio volumio[906]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 12 22:02:35 volumio volumio[906]: SPOTIFY: BQB2Jd1NYieZC3dupAUU-WoXkfYcJhaF_-afAxe1iejiQ2kiHyN4OaGyoL1V2Z0mobaFcWZoO13NnCnTFGdG5qc2hG14jg0hdlbbx4x-j1Tx3nBSULp36dqJpi5sStdD52wKjgZ1VXAqazJzSKrBdZrozV3RTlmepZPT2eWvfFbNl1KSkq7J3_ApUP_vepzp5eiztJF1CUwDaEqhENnvbCkFiUp1_9cfVqrDyaX7UyGmVqq6sApTpiZxe3ixIDuZZaAHowuqk0h6hOjIIT3IGaIAvMkkNiydrIDJIpx4uJs Mar 12 22:02:35 volumio volumio[906]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 12 22:02:35 volumio volumio[906]: info: New Spotify access token = BQB2Jd1NYieZC3dupAUU-WoXkfYcJhaF_-afAxe1iejiQ2kiHyN4OaGyoL1V2Z0mobaFcWZoO13NnCnTFGdG5qc2hG14jg0hdlbbx4x-j1Tx3nBSULp36dqJpi5sStdD52wKjgZ1VXAqazJzSKrBdZrozV3RTlmepZPT2eWvfFbNl1KSkq7J3_ApUP_vepzp5eiztJF1CUwDaEqhENnvbCkFiUp1_9cfVqrDyaX7UyGmVqq6sApTpiZxe3ixIDuZZaAHowuqk0h6hOjIIT3IGaIAvMkkNiydrIDJIpx4uJs Mar 12 22:02:35 volumio volumio[906]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 12 22:02:35 volumio volumio[906]: SPOTIFY: User informations: {"country":"DE","display_name":"LaLaLaut","email":"spotify@kodekitchen.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31erptsw5tysp5ufiovckvmmlqsu"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31erptsw5tysp5ufiovckvmmlqsu","id":"31erptsw5tysp5ufiovckvmmlqsu","images":[],"product":"premium","type":"user","uri":"spotify:user:31erptsw5tysp5ufiovckvmmlqsu"} Mar 12 22:02:35 volumio volumio[906]: info: Creating Spotify config file Mar 12 22:02:35 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 22:02:35 volumio volumio[906]: info: Spotify config file written Mar 12 22:02:35 volumio sudo[8800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 12 22:02:35 volumio sudo[8800]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 22:02:35 volumio systemd[1]: Stopping go-librespot Daemon... Mar 12 22:02:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Mar 12 22:02:35 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Mar 12 22:02:35 volumio systemd[1]: Stopped go-librespot Daemon. Mar 12 22:02:35 volumio sudo[8804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 12 22:02:35 volumio sudo[8804]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 22:02:35 volumio sudo[8804]: pam_unix(sudo:session): session closed for user root Mar 12 22:02:35 volumio sudo[8807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 22:02:35 volumio systemd[1]: Started go-librespot Daemon. Mar 12 22:02:35 volumio sudo[8807]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 22:02:35 volumio go-librespot[8802]: go-librespot daemon starting... Mar 12 22:02:35 volumio volumio[906]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 12 22:02:35 volumio sudo[8807]: pam_unix(sudo:session): session closed for user root Mar 12 22:02:35 volumio volumio[906]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 12 22:02:35 volumio sudo[8800]: pam_unix(sudo:session): session closed for user root Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=info msg="running go-librespot 0.2.0" Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=debug msg="app state loaded" Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=debug msg="stored credentials not found" Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 12 22:02:35 volumio volumio[906]: info: Connection to go-librespot Websocket closed Mar 12 22:02:35 volumio volumio[906]: verbose: New Socket.io Connection to 192.168.1.137 from 192.168.1.140 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:136.0) Gecko/20100101 Firefox/136.0 Engine version: 3 Transport: polling Total Clients: 7 Mar 12 22:02:35 volumio volumio[906]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 12 22:02:35 volumio volumio[906]: SPOTIFY: BQApjJDSuAUROv1GQ4gvg6n5JDrSsnywMkfpZEJGUx2X0aslK9SnOgpzO2GtpE2OqhXEcb5101bC8Tv-RETh7dQwn_kyyj5ffF9I5Ygk00VaeniBGTL9YG3p1fwiqT6OZ1HEOO6Iz5BwOHqG7kBNhE5WKN2Qs-r_bjJaeo5r547LXidNxAMLEiOCuIZZ83F-AsS6YwEPwnZQKb28ux7t_YBYa_F1NucdurZL4Kb3rtDyp9TS_SfaABTnIvnXUzKHRhsgxMn5p-ffYnej46V58s1RU14I3FPQ-r5ArPofM8Q Mar 12 22:02:35 volumio volumio[906]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 12 22:02:35 volumio volumio[906]: info: New Spotify access token = BQApjJDSuAUROv1GQ4gvg6n5JDrSsnywMkfpZEJGUx2X0aslK9SnOgpzO2GtpE2OqhXEcb5101bC8Tv-RETh7dQwn_kyyj5ffF9I5Ygk00VaeniBGTL9YG3p1fwiqT6OZ1HEOO6Iz5BwOHqG7kBNhE5WKN2Qs-r_bjJaeo5r547LXidNxAMLEiOCuIZZ83F-AsS6YwEPwnZQKb28ux7t_YBYa_F1NucdurZL4Kb3rtDyp9TS_SfaABTnIvnXUzKHRhsgxMn5p-ffYnej46V58s1RU14I3FPQ-r5ArPofM8Q Mar 12 22:02:35 volumio volumio[906]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 12 22:02:35 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 12 22:02:35 volumio volumio[906]: info: CoreCommandRouter::volumioGetVisibleSources Mar 12 22:02:35 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 22:02:35 volumio volumio[906]: info: CoreCommandRouter::volumioGetState Mar 12 22:02:35 volumio volumio[906]: info: CorePlayQueue::getTrack 0 Mar 12 22:02:35 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 12 22:02:35 volumio volumio[906]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 12 22:02:35 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 12 22:02:35 volumio volumio[906]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 12 22:02:35 volumio volumio[906]: info: Received Get System Info Mar 12 22:02:35 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 12 22:02:35 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 12 22:02:35 volumio volumio[906]: info: Discovery: Getting this device information Mar 12 22:02:35 volumio volumio[906]: info: CoreCommandRouter::volumioGetState Mar 12 22:02:35 volumio volumio[906]: info: CorePlayQueue::getTrack 0 Mar 12 22:02:35 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 12 22:02:35 volumio volumio[906]: info: CoreCommandRouter::volumioGetState Mar 12 22:02:35 volumio volumio[906]: info: CorePlayQueue::getTrack 0 Mar 12 22:02:35 volumio volumio[906]: info: Listing playlists Mar 12 22:02:35 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 12 22:02:35 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 12 22:02:35 volumio volumio[906]: SPOTIFY: User informations: {"country":"DE","display_name":"LaLaLaut","email":"spotify@kodekitchen.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31erptsw5tysp5ufiovckvmmlqsu"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31erptsw5tysp5ufiovckvmmlqsu","id":"31erptsw5tysp5ufiovckvmmlqsu","images":[],"product":"premium","type":"user","uri":"spotify:user:31erptsw5tysp5ufiovckvmmlqsu"} Mar 12 22:02:35 volumio volumio[906]: info: Spotify Successfully logged in Mar 12 22:02:35 volumio volumio[906]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 12 22:02:35 volumio volumio[906]: info: [1741813355551] CoreMusicLibrary::Adding element Spotify Mar 12 22:02:35 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 22:02:35 volumio volumio[906]: Cannot find translation for source Spotify Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=info msg="zeroconf server listening on port 39051" Mar 12 22:02:35 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=debug msg="obtained new client token: AADiCkoZD3reO/sK6NJ+xaJ1rjHGq6eg3boy3f/fjS/lpPNKsnnKEdea4ShtXmLOK1kFGBupUFQVwlc12id6dBxPq9AX3Tkspx7Kb+L/4+SM1vnu9DgUVSqynkFvE2+Iu3ozBCDSkkROOdQWECrJvmrJmQ579Ffhv+redT79Qll/zp1s2NWdDFBj97KPm7Er2ekZcJfRTvtsZ0SQIR5ysYFm45UyjyIFr9Sykcvbx+dTUEzN3tcS7pQF" Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=debug msg="completed keyexchange" Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=debug msg="completed challenge" Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=info msg="authenticated AP as 31erptsw5tysp5ufiovckvmmlqsu" Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=info msg="authenticated Login5 as 31erptsw5tysp5ufiovckvmmlqsu" Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=debug msg="stored credentials for 31erptsw5tysp5ufiovckvmmlqsu" Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=debug msg="initializing zeroconf session, username: 31erptsw5tysp5ufiovckvmmlqsu" Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=debug msg="dealer connection opened" Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=trace msg="starting accesspoint recv loop" Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=trace msg="starting dealer recv loop" Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=trace msg="received accesspoint ping" Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 12 22:02:35 volumio go-librespot[8802]: time="2025-03-12T22:02:35+01:00" level=debug msg="received connection id: MmYzZTQ1MTMtNmIzMi00NDdiLTljM2ItMzM0MWY2MzE5ZDdjK2RlYWxlcit0Y3A6Ly8wYWNhNTg4OS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArQzA0OTQyODQ4N0I2RDI4QjZGNjhEOTcxQTdEMUQ3NDA3QUI2QThFOTExMDJGQkE3MEM4MDJDRkZDMDg0MUMyRA==" Mar 12 22:02:36 volumio go-librespot[8802]: time="2025-03-12T22:02:36+01:00" level=trace msg="received accesspoint pong ack" Mar 12 22:02:36 volumio go-librespot[8802]: time="2025-03-12T22:02:36+01:00" level=debug msg="put connect state because NEW_DEVICE" Mar 12 22:02:37 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 12 22:02:37 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 12 22:02:37 volumio volumio[906]: info: Received Get System Info Mar 12 22:02:37 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 12 22:02:37 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 12 22:02:37 volumio volumio[906]: info: Discovery: Getting this device information Mar 12 22:02:37 volumio volumio[906]: info: CoreCommandRouter::volumioGetState Mar 12 22:02:37 volumio volumio[906]: info: CorePlayQueue::getTrack 0 Mar 12 22:02:37 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 12 22:02:37 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 12 22:02:38 volumio volumio[906]: info: go-librespot daemon successfully initialized Mar 12 22:02:38 volumio volumio[906]: info: Initializing connection to go-librespot Websocket Mar 12 22:02:38 volumio go-librespot[8802]: time="2025-03-12T22:02:38+01:00" level=debug msg="new websocket client" Mar 12 22:02:38 volumio volumio[906]: info: Connection to go-librespot Websocket established Mar 12 22:02:39 volumio volumio[906]: info: CoreCommandRouter::volumioGetState Mar 12 22:02:39 volumio volumio[906]: info: CorePlayQueue::getTrack 0 Mar 12 22:02:41 volumio volumio[906]: info: Initializing connection to go-librespot Websocket Mar 12 22:02:41 volumio go-librespot[8802]: time="2025-03-12T22:02:41+01:00" level=debug msg="new websocket client" Mar 12 22:02:41 volumio volumio[906]: info: Connection to go-librespot Websocket established Mar 12 22:02:41 volumio volumio[906]: info: Getting Spotify volume Mar 12 22:02:41 volumio volumio[906]: info: Spotify volume: 100 Mar 12 22:02:41 volumio volumio[906]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 12 22:02:41 volumio volumio[906]: info: CoreCommandRouter::volumioGetState Mar 12 22:02:41 volumio volumio[906]: info: CorePlayQueue::getTrack 0 Mar 12 22:02:41 volumio volumio[906]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 12 22:02:42 volumio volumio[906]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 12 22:02:42 volumio volumio[906]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 12 22:02:42 volumio volumio[906]: info: Retrieving Cloud Streaming UI Mar 12 22:02:42 volumio volumio[906]: info: Getting Tidal Cloud Configuration Mar 12 22:02:42 volumio volumio[906]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 12 22:02:42 volumio volumio[906]: info: Getting Qobuz Cloud Configuration Mar 12 22:02:42 volumio volumio[906]: info: Asking plugin for UI Config Mar 12 22:02:42 volumio volumio[906]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 12 22:02:42 volumio volumio[906]: info: Getting Spotify Cloud Configuration Mar 12 22:02:42 volumio volumio[906]: info: Asking plugin for UI Config Mar 12 22:02:42 volumio volumio[906]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 12 22:02:42 volumio volumio[906]: info: Saving Spotify Acccount Mar 12 22:02:42 volumio volumio[906]: info: Got it Mar 12 22:02:42 volumio volumio[906]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 12 22:02:42 volumio volumio[906]: info: Got Tidal Cloud Configuration Mar 12 22:02:42 volumio volumio[906]: info: Got it Mar 12 22:02:42 volumio volumio[906]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 12 22:02:42 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 12 22:02:42 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 12 22:02:42 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 12 22:02:42 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 12 22:02:42 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 12 22:02:42 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 12 22:02:42 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 12 22:02:42 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 12 22:02:42 volumio volumio[906]: info: CoreCommandRouter::volumioGetBrowseSources Mar 12 22:02:42 volumio volumio[906]: info: CoreCommandRouter::volumioGetBrowseSources Mar 12 22:02:42 volumio volumio[906]: info: CoreCommandRouter::volumioGetBrowseSources Mar 12 22:02:42 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 22:02:42 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 12 22:02:44 volumio volumio[906]: info: Getting Spotify volume Mar 12 22:02:44 volumio volumio[906]: info: Spotify volume: 100 Mar 12 22:02:44 volumio volumio[906]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 12 22:02:44 volumio volumio[906]: info: CoreCommandRouter::volumioGetState Mar 12 22:02:44 volumio volumio[906]: info: CorePlayQueue::getTrack 0 Mar 12 22:02:44 volumio volumio[906]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 12 22:02:45 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 12 22:02:46 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 22:02:49 volumio volumio[906]: info: Disabling MyMusic plugin airplay_emulation Mar 12 22:02:49 volumio volumio[906]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Mar 12 22:02:49 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 22:02:49 volumio volumio[906]: Cannot find translation for source Spotify Mar 12 22:02:49 volumio volumio[906]: info: Disabling plugin airplay_emulation Mar 12 22:02:49 volumio volumio[906]: info: Done. Mar 12 22:02:49 volumio sudo[8877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Mar 12 22:02:49 volumio sudo[8877]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 22:02:49 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 12 22:02:49 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 12 22:02:50 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 12 22:02:50 volumio volumio[906]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 12 22:02:50 volumio sudo[8877]: pam_unix(sudo:session): session closed for user root Mar 12 22:02:50 volumio volumio[906]: info: Shairport-Sync Stopped Mar 12 22:02:50 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 22:02:54 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 22:02:57 volumio volumio[906]: info: Disabling MyMusic plugin tidalconnect Mar 12 22:02:57 volumio volumio[906]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service tidalconnect Mar 12 22:02:57 volumio vtcs[8288]: [2025-03-12 22:02:57.476] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Mar 12 22:02:57 volumio vtcs[8288]: [2025-03-12 22:02:57.477] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Mar 12 22:02:57 volumio volumio[906]: info: Disabling plugin tidalconnect Mar 12 22:02:57 volumio sudo[8895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 12 22:02:57 volumio sudo[8895]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 22:02:57 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Mar 12 22:02:57 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Mar 12 22:02:57 volumio systemd[1]: vtcs.service: Succeeded. Mar 12 22:02:57 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Mar 12 22:02:57 volumio sudo[8895]: pam_unix(sudo:session): session closed for user root Mar 12 22:02:57 volumio volumio[906]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 12 22:02:58 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 12 22:02:58 volumio volumio[906]: info: Received Get System Info Mar 12 22:02:58 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 12 22:02:58 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 12 22:02:58 volumio volumio[906]: info: Discovery: Getting this device information Mar 12 22:02:58 volumio volumio[906]: info: CoreCommandRouter::volumioGetState Mar 12 22:02:58 volumio volumio[906]: info: CorePlayQueue::getTrack 0 Mar 12 22:02:58 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 12 22:02:58 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 22:03:00 volumio volumio[906]: info: TidalConnect service stoped! Mar 12 22:03:02 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 22:03:03 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 12 22:03:03 volumio volumio[906]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 12 22:03:03 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 12 22:03:03 volumio volumio[906]: info: Received Get System Version Mar 12 22:03:03 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 12 22:03:03 volumio volumio[906]: info: Received Get System Info Mar 12 22:03:03 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 12 22:03:03 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 12 22:03:03 volumio volumio[906]: info: Discovery: Getting this device information Mar 12 22:03:03 volumio volumio[906]: info: CoreCommandRouter::volumioGetState Mar 12 22:03:03 volumio volumio[906]: info: CorePlayQueue::getTrack 0 Mar 12 22:03:03 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 12 22:03:05 volumio go-librespot[8802]: time="2025-03-12T22:03:05+01:00" level=trace msg="sent dealer ping" Mar 12 22:03:06 volumio go-librespot[8802]: time="2025-03-12T22:03:06+01:00" level=trace msg="received dealer pong" Mar 12 22:03:06 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 22:03:10 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 22:03:12 volumio volumio[906]: info: CALLMETHOD: miscellanea my_music updateMusicLibraryBrowseSourcesVisibility [object Object] Mar 12 22:03:12 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: my_music , updateMusicLibraryBrowseSourcesVisibility Mar 12 22:03:12 volumio volumio[906]: info: CoreCommandRouter::volumioUpdateToBrowseSources Mar 12 22:03:12 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 22:03:12 volumio volumio[906]: Cannot find translation for source Spotify Mar 12 22:03:14 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 22:03:17 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Mar 12 22:03:17 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Mar 12 22:03:18 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 22:03:22 volumio volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 22:03:23 volumio volumio[906]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 12 22:03:23 volumio volumio[906]: Error: dns service error: unknown Mar 12 22:03:23 volumio volumio[906]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Mar 12 22:03:23 volumio volumio[906]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Mar 12 22:03:23 volumio volumio[906]: errorCode: -65537 Mar 12 22:03:23 volumio volumio[906]: } Mar 12 22:03:23 volumio volumio[906]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 12 22:03:23 volumio sudo[8962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-12 22:02 Mar 12 22:03:23 volumio sudo[8962]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"