-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Tue 2025-05-13 18:34:13 BST. -- May 13 18:33:00 volumio volumio-remote-updater[594]: [2025-05-13 18:33:00] [connect] Successful connection May 13 18:33:01 volumio volumio[17129]: info: Loading plugin "outputs"... May 13 18:33:01 volumio volumio[17129]: info: Loading plugin "albumart"... May 13 18:33:01 volumio volumio[17129]: info: Plugin example_plugin is not enabled May 13 18:33:01 volumio volumio[17129]: info: Loading plugin "inputs"... May 13 18:33:01 volumio volumio[17129]: info: Loading plugin "updater_comm"... May 13 18:33:01 volumio volumio[17129]: info: Plugin mpdemulation is not enabled May 13 18:33:01 volumio volumio[17129]: info: Loading plugin "rest_api"... May 13 18:33:01 volumio volumio[17129]: info: Loading plugin "websocket"... May 13 18:33:01 volumio volumio[17129]: info: Starting Socket.io Server version 2.3.0 May 13 18:33:01 volumio volumio[17129]: info: Loading i18n strings for locale en May 13 18:33:01 volumio volumio[17129]: Updating browse sources language May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 18:33:01 volumio volumio[17129]: Forking 3 albumart workers May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::initPlayerControls May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 18:33:01 volumio volumio[17129]: Express server listening on port 3000 May 13 18:33:01 volumio volumio[17129]: [Metrics] WebUI: 4s 540.57ms May 13 18:33:01 volumio volumio[17129]: info: CoreStateMachine::resetVolumioState May 13 18:33:01 volumio volumio[17129]: info: CoreStateMachine::getcurrentVolume May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::volumioRetrievevolume May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:33:01 volumio volumio[17129]: info: Volumio Network Manager: Network status updated: 1 May 13 18:33:01 volumio volumio[17129]: verbose: New Socket.io Connection to 192.168.1.71 from 192.168.1.107 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 1 May 13 18:33:01 volumio volumio[17129]: verbose: New Socket.io Connection to 192.168.1.71:3000 from 192.168.1.107 UA: Dart/3.7 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 May 13 18:33:01 volumio volumio[17129]: verbose: New Socket.io Connection to 192.168.1.71 from 192.168.1.107 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 3 May 13 18:33:01 volumio volumio-remote-updater[594]: [2025-05-13 18:33:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747157580 101 May 13 18:33:01 volumio volumio[17129]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 18:33:01 volumio volumio[17129]: info: Discovery: Getting this device information May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::volumioGetState May 13 18:33:01 volumio volumio[17129]: info: CorePlayQueue::getTrack 0 May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 18:33:01 volumio volumio[17129]: info: Discovery: Getting this device information May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::volumioGetState May 13 18:33:01 volumio volumio[17129]: info: CorePlayQueue::getTrack 0 May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 18:33:01 volumio volumio[17129]: info: Reloading queue from file May 13 18:33:01 volumio volumio[17129]: info: VolumeController:: Volume=82 Mute =false May 13 18:33:01 volumio volumio[17129]: info: CoreStateMachine::pushState May 13 18:33:01 volumio volumio[17129]: info: CorePlayQueue::getTrack 0 May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::volumioPushState May 13 18:33:01 volumio volumio[17129]: info: CoreStateMachine::updateTrackBlock May 13 18:33:01 volumio volumio[17129]: info: CorePlayQueue::getTrackBlock May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::volumioRetrievevolume May 13 18:33:01 volumio volumio[17129]: info: CoreStateMachine::setRepeat null single undefined May 13 18:33:01 volumio volumio[17129]: info: CoreStateMachine::pushState May 13 18:33:01 volumio volumio[17129]: info: CorePlayQueue::getTrack 0 May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::volumioPushState May 13 18:33:01 volumio volumio[17129]: info: CoreStateMachine::setRandom null May 13 18:33:01 volumio volumio[17129]: info: CoreStateMachine::pushState May 13 18:33:01 volumio volumio[17129]: info: CorePlayQueue::getTrack 0 May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::volumioPushState May 13 18:33:01 volumio volumio[17129]: info: Setting Device type: Raspberry PI May 13 18:33:01 volumio volumio[17129]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 13 18:33:01 volumio volumio[17129]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 May 13 18:33:01 volumio volumio[17129]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 13 18:33:01 volumio volumio[17129]: info: Completed loading Core Plugins May 13 18:33:01 volumio volumio[17129]: info: Preparing to generate the ALSA configuration file May 13 18:33:01 volumio volumio[17129]: info: Discovery: adding 92c5a1ba-1964-48d1-a417-80fb20509952 May 13 18:33:01 volumio volumio[17129]: info: Discovery: Found device Volumio May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::volumioGetState May 13 18:33:01 volumio volumio[17129]: info: CorePlayQueue::getTrack 0 May 13 18:33:01 volumio volumio[17129]: info: VolumeController:: Volume=82 Mute =false May 13 18:33:01 volumio volumio[17129]: info: CoreStateMachine::pushState May 13 18:33:01 volumio volumio[17129]: info: CorePlayQueue::getTrack 0 May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::volumioPushState May 13 18:33:01 volumio volumio[17129]: info: Asound.conf file unchanged, so no further update is needed May 13 18:33:01 volumio volumio[17129]: info: Output device has changed, restarting MPD May 13 18:33:01 volumio volumio[17129]: Starting albumart workers May 13 18:33:01 volumio volumio[17129]: Starting albumart workers May 13 18:33:01 volumio sudo[17227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 13 18:33:01 volumio sudo[17227]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:33:01 volumio volumio[17129]: info: Output device has changed, restarting Shairport Sync May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:33:01 volumio sudo[17227]: pam_unix(sudo:session): session closed for user root May 13 18:33:01 volumio sudo[17230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 13 18:33:01 volumio sudo[17230]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:33:01 volumio volumio[17129]: Starting albumart workers May 13 18:33:01 volumio volumio[17129]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 13 18:33:01 volumio volumio[17129]: info: ___________ START PLUGINS ___________ May 13 18:33:01 volumio volumio[17129]: info: ControllerMpd::onStart: Initializing MPD May 13 18:33:01 volumio volumio[17129]: info: Creating MPD Configuration file May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 13 18:33:02 volumio volumio[17129]: info: [1747157582019] CoreMusicLibrary::Adding element Media Servers May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 18:33:02 volumio sudo[17236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 13 18:33:02 volumio sudo[17236]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:33:02 volumio sudo[17236]: pam_unix(sudo:session): session closed for user root May 13 18:33:02 volumio sudo[17238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 13 18:33:02 volumio sudo[17238]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 13 18:33:02 volumio volumio[17129]: info: [1747157582220] CoreMusicLibrary::Adding element Last_100 May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 13 18:33:02 volumio volumio[17129]: info: [1747157582248] CoreMusicLibrary::Adding element Webradio May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 13 18:33:02 volumio volumio[17129]: info: Initializing BBC Radios May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: Creating Spotify config file May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 13 18:33:02 volumio volumio[17129]: info: [1747157582429] CoreMusicLibrary::Adding element YouTube Music May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 18:33:02 volumio volumio[17129]: Cannot find translation for source YouTube Music May 13 18:33:02 volumio volumio[17129]: info: Volumio Calling Home May 13 18:33:02 volumio volumio[17129]: info: MPD Permissions set May 13 18:33:02 volumio volumio[17129]: info: MPD Permissions set May 13 18:33:02 volumio volumio[17129]: info: Spotify config file written May 13 18:33:02 volumio volumio[17129]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:02 volumio sudo[17285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 13 18:33:02 volumio sudo[17285]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:33:02 volumio systemd[1]: Stopping go-librespot Daemon... May 13 18:33:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM May 13 18:33:02 volumio systemd[1]: go-librespot-daemon.service: Succeeded. May 13 18:33:02 volumio systemd[1]: Stopped go-librespot Daemon. May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:02 volumio systemd[1]: Started go-librespot Daemon. May 13 18:33:02 volumio go-librespot[17291]: Librespot-go daemon starting... May 13 18:33:02 volumio sudo[17285]: pam_unix(sudo:session): session closed for user root May 13 18:33:02 volumio go-librespot[17291]: time="2025-05-13T18:33:02+01:00" level=info msg="generated new device id: 29a47ada82862e5783bd8a5d0085d90b74617153" May 13 18:33:02 volumio go-librespot[17291]: time="2025-05-13T18:33:02+01:00" level=debug msg="stored credentials found for ian_danbury" May 13 18:33:02 volumio volumio[17129]: info: Volumio called home May 13 18:33:02 volumio volumio[17129]: info: Starting Shairport Sync May 13 18:33:02 volumio volumio[17129]: info: Starting Shairport Sync May 13 18:33:02 volumio sudo[17303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 13 18:33:02 volumio sudo[17303]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:33:02 volumio volumio[17129]: info: Starting Shairport Sync May 13 18:33:02 volumio sudo[17306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 13 18:33:02 volumio sudo[17306]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:33:02 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 13 18:33:02 volumio systemd[1]: shairport-sync.service: Succeeded. May 13 18:33:02 volumio sudo[17309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 13 18:33:02 volumio sudo[17309]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:33:02 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 13 18:33:02 volumio volumio[17129]: info: CoreCommandRouter::volumioGetState May 13 18:33:02 volumio volumio[17129]: info: CorePlayQueue::getTrack 0 May 13 18:33:02 volumio go-librespot[17291]: time="2025-05-13T18:33:02+01:00" level=debug msg="obtained new client token: AABLABo+RbLTfp45cFd3ouTDX9Jc3nOzQQ/lNHXyxiJBhzXvHPjpvMcuYs2RG1SKogxdFW06YI3Zi4g5MiaVJjWLKbu2BvsTFn+kQ9u95ZSJYa/spAyl22PyRcUfXDGGArzMD2hDCivOcmxukSTinF3YWVrYKqfjJURjg8n5Duo5dyxEYsWCoWuHryv14iDeEqHmL3bWg1eYkzb0AczOhpLYkjx20/UCbVov9AoAp3OPFKUkgctQhp20utgV" May 13 18:33:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 13 18:33:02 volumio sudo[17306]: pam_unix(sudo:session): session closed for user root May 13 18:33:02 volumio volumio[17129]: info: Shairport-Sync Started May 13 18:33:02 volumio volumio[17129]: Error adding Membership: Error: addMembership EINVAL May 13 18:33:02 volumio sudo[17303]: pam_unix(sudo:session): session closed for user root May 13 18:33:02 volumio volumio[17129]: info: Shairport-Sync Started May 13 18:33:02 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 13 18:33:02 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 13 18:33:02 volumio systemd[1]: shairport-sync.service: Succeeded. May 13 18:33:02 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 13 18:33:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 13 18:33:02 volumio sudo[17309]: pam_unix(sudo:session): session closed for user root May 13 18:33:02 volumio volumio[17129]: info: Shairport-Sync Started May 13 18:33:02 volumio go-librespot[17291]: time="2025-05-13T18:33:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 13 18:33:02 volumio volumio[17129]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 13 18:33:02 volumio volumio[17129]: SPOTIFY: BQDxBIvGCVe5k_THsGsXEIhW5Ofcx6BbIlDcFx6grcyAY3r_3wZ8hoRxr8rLB3P7NjtKI_iTbCzsTHXA57AjVBPeAdS7cvyy8bN3wIhsCF_tUr5Y7ASMp-KDNddioUrtgNQ3qDFAVYDh3pIhVVMwZNGSU_yg8dY46zEKNDTWhbdlPRumudfGSUgXLu0j-w2OHsWVQBdTsNS09TO-oUJwCGXUkuLZxTpRjd-jY38KAvwskiJAlpBIMtveIzQwKeI15-BTYW2gLnKrnhSL7_HrdhhKx_9zKI4AQB-3IZ0 May 13 18:33:02 volumio volumio[17129]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 13 18:33:02 volumio volumio[17129]: info: New Spotify access token = BQDxBIvGCVe5k_THsGsXEIhW5Ofcx6BbIlDcFx6grcyAY3r_3wZ8hoRxr8rLB3P7NjtKI_iTbCzsTHXA57AjVBPeAdS7cvyy8bN3wIhsCF_tUr5Y7ASMp-KDNddioUrtgNQ3qDFAVYDh3pIhVVMwZNGSU_yg8dY46zEKNDTWhbdlPRumudfGSUgXLu0j-w2OHsWVQBdTsNS09TO-oUJwCGXUkuLZxTpRjd-jY38KAvwskiJAlpBIMtveIzQwKeI15-BTYW2gLnKrnhSL7_HrdhhKx_9zKI4AQB-3IZ0 May 13 18:33:02 volumio volumio[17129]: info: Spotify credentials grant success - running version from March 24, 2019 May 13 18:33:02 volumio go-librespot[17291]: time="2025-05-13T18:33:02+01:00" level=debug msg="completed keyexchange" May 13 18:33:03 volumio volumio[17129]: SPOTIFY: User informations: {"country":"GB","display_name":"ian_danbury","email":"ian_danbury@hotmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/ian_danbury"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/ian_danbury","id":"ian_danbury","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee85e1055dca6051d09c34566340","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b82e1055dca6051d09c34566340","width":64}],"product":"premium","type":"user","uri":"spotify:user:ian_danbury"} May 13 18:33:03 volumio volumio[17129]: info: Spotify Successfully logged in May 13 18:33:03 volumio volumio[17129]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 13 18:33:03 volumio volumio[17129]: info: [1747157583038] CoreMusicLibrary::Adding element Spotify May 13 18:33:03 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 18:33:03 volumio volumio[17129]: Cannot find translation for source YouTube Music May 13 18:33:03 volumio volumio[17129]: Cannot find translation for source Spotify May 13 18:33:03 volumio volumio[17129]: info: Cannot mount NAS _FLACs at system boot, trial number 2 ,retrying in 5 seconds May 13 18:33:03 volumio volumio[17129]: info: Cannot mount NAS _Boss_FLACs at system boot, trial number 2 ,retrying in 5 seconds May 13 18:33:03 volumio volumio[17129]: info: Cannot mount NAS MP3 at system boot, trial number 2 ,retrying in 5 seconds May 13 18:33:03 volumio go-librespot[17291]: time="2025-05-13T18:33:03+01:00" level=debug msg="completed challenge" May 13 18:33:03 volumio go-librespot[17291]: time="2025-05-13T18:33:03+01:00" level=debug msg="authenticated as ian_danbury" May 13 18:33:03 volumio go-librespot[17291]: time="2025-05-13T18:33:03+01:00" level=debug msg="authenticated as ian_danbury" May 13 18:33:03 volumio go-librespot[17291]: time="2025-05-13T18:33:03+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 13 18:33:03 volumio go-librespot[17291]: time="2025-05-13T18:33:03+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 13 18:33:03 volumio go-librespot[17291]: time="2025-05-13T18:33:03+01:00" level=debug msg="dealer connection opened" May 13 18:33:03 volumio go-librespot[17291]: time="2025-05-13T18:33:03+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 13 18:33:03 volumio go-librespot[17291]: time="2025-05-13T18:33:03+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 13 18:33:03 volumio go-librespot[17291]: time="2025-05-13T18:33:03+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 13 18:33:03 volumio go-librespot[17291]: time="2025-05-13T18:33:03+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 13 18:33:03 volumio go-librespot[17291]: time="2025-05-13T18:33:03+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" May 13 18:33:03 volumio go-librespot[17291]: time="2025-05-13T18:33:03+01:00" level=debug msg="received connection id: NDY0OGQyZjctZTVkZC00MmFmLTk3YWEtZmQ3ZGE4NmUyN2I4K2RlYWxlcit0Y3A6Ly8wYWNhNDEwOC5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArQTVGMEFBQjk3QkQ1QzlGNzNEOEU1QjY1NzA0NzRFNUEwRjhEN0E5RTQyRDM5RjA5ODEzOEYyRURGNDZENTUzRA==" May 13 18:33:03 volumio go-librespot[17291]: time="2025-05-13T18:33:03+01:00" level=debug msg="put connect state because NEW_DEVICE" May 13 18:33:04 volumio volumio[17129]: verbose: New Socket.io Connection to 192.168.1.71 from 192.168.1.107 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 May 13 18:33:04 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 13 18:33:04 volumio volumio[17129]: info: CoreCommandRouter::volumioGetVisibleSources May 13 18:33:04 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 18:33:04 volumio volumio[17129]: info: CoreCommandRouter::volumioGetState May 13 18:33:04 volumio volumio[17129]: info: CorePlayQueue::getTrack 0 May 13 18:33:04 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 13 18:33:04 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 13 18:33:04 volumio volumio[17129]: info: Received Get System Info May 13 18:33:04 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 18:33:04 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 18:33:04 volumio volumio[17129]: info: Discovery: Getting this device information May 13 18:33:04 volumio volumio[17129]: info: CoreCommandRouter::volumioGetState May 13 18:33:04 volumio volumio[17129]: info: CorePlayQueue::getTrack 0 May 13 18:33:04 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 18:33:04 volumio volumio[17129]: info: CoreCommandRouter::volumioGetState May 13 18:33:04 volumio volumio[17129]: info: CorePlayQueue::getTrack 0 May 13 18:33:04 volumio volumio[17129]: info: Listing playlists May 13 18:33:04 volumio volumio[17129]: info: CoreCommandRouter::volumioGetQueue May 13 18:33:04 volumio volumio[17129]: info: CoreStateMachine::getQueue May 13 18:33:04 volumio volumio[17129]: info: CorePlayQueue::getQueue May 13 18:33:04 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 18:33:04 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 18:33:04 volumio volumio[17129]: info: Discovery: Getting this device information May 13 18:33:04 volumio volumio[17129]: info: CoreCommandRouter::volumioGetState May 13 18:33:04 volumio volumio[17129]: info: CorePlayQueue::getTrack 0 May 13 18:33:04 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 18:33:05 volumio volumio[17129]: verbose: New Socket.io Connection to 192.168.1.71:3000 from 192.168.1.107 UA: Dart/3.7 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 May 13 18:33:05 volumio volumio[17129]: info: CoreCommandRouter::volumioGetState May 13 18:33:05 volumio volumio[17129]: info: CorePlayQueue::getTrack 0 May 13 18:33:05 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 13 18:33:05 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 13 18:33:05 volumio volumio[17129]: info: go-librespot daemon successfully initialized May 13 18:33:07 volumio mpd_monitor.sh[601]: MPD Monitor Service: MPD Appears to be inactive, restarting May 13 18:33:08 volumio volumio[17129]: info: Cannot mount NAS _FLACs at system boot, trial number 3 ,retrying in 5 seconds May 13 18:33:08 volumio volumio[17129]: info: Cannot mount NAS _Boss_FLACs at system boot, trial number 3 ,retrying in 5 seconds May 13 18:33:08 volumio volumio[17129]: info: Cannot mount NAS MP3 at system boot, trial number 3 ,retrying in 5 seconds May 13 18:33:08 volumio sudo[17334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 13 18:33:08 volumio sudo[17334]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:33:08 volumio sudo[17334]: pam_unix(sudo:session): session closed for user root May 13 18:33:08 volumio sudo[17337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 13 18:33:08 volumio sudo[17337]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:33:08 volumio sudo[17337]: pam_unix(sudo:session): session closed for user root May 13 18:33:08 volumio sudo[17340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 13 18:33:08 volumio sudo[17340]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:33:08 volumio sudo[17340]: pam_unix(sudo:session): session closed for user root May 13 18:33:08 volumio volumio[17129]: info: Upmpdcli Daemon Started May 13 18:33:08 volumio volumio[17129]: info: Initializing connection to go-librespot Websocket May 13 18:33:08 volumio go-librespot[17291]: time="2025-05-13T18:33:08+01:00" level=debug msg="new websocket client" May 13 18:33:08 volumio volumio[17129]: info: Connection to go-librespot Websocket established May 13 18:33:11 volumio volumio[17129]: info: Getting Spotify volume May 13 18:33:11 volumio volumio[17129]: info: Spotify volume: 100 May 13 18:33:11 volumio volumio[17129]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 13 18:33:11 volumio volumio[17129]: info: CoreCommandRouter::volumioGetState May 13 18:33:11 volumio volumio[17129]: info: CorePlayQueue::getTrack 0 May 13 18:33:11 volumio volumio[17129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 May 13 18:33:11 volumio volumio[17129]: SPOTIFY: SPOTIFY VOLUME 100 May 13 18:33:11 volumio volumio[17129]: SPOTIFY: VOLUMIO VOLUME 82 May 13 18:33:11 volumio volumio[17129]: SPOTIFY: DELTA VOLUME ENOUGH: true May 13 18:33:11 volumio volumio[17129]: info: Setting Spotify Volume from Volumio: 82 May 13 18:33:12 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 13 18:33:12 volumio volumio[17129]: info: CURURI: music-library May 13 18:33:12 volumio volumio[17129]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined May 13 18:33:13 volumio volumio[17129]: SPOTIFY: SETTING SPOTIFY VOLUME 82 May 13 18:33:13 volumio volumio[17129]: info: Sending Spotify command with payload to local API: /player/volume May 13 18:33:13 volumio go-librespot[17291]: time="2025-05-13T18:33:13+01:00" level=debug msg="update volume to 53738/65535" May 13 18:33:13 volumio volumio[17129]: info: Cannot mount NAS _FLACs at system boot, trial number 4 ,retrying in 5 seconds May 13 18:33:13 volumio volumio[17129]: info: Cannot mount NAS _Boss_FLACs at system boot, trial number 4 ,retrying in 5 seconds May 13 18:33:13 volumio volumio[17129]: info: Cannot mount NAS MP3 at system boot, trial number 4 ,retrying in 5 seconds May 13 18:33:13 volumio go-librespot[17291]: time="2025-05-13T18:33:13+01:00" level=debug msg="put connect state because VOLUME_CHANGED" May 13 18:33:13 volumio go-librespot[17291]: time="2025-05-13T18:33:13+01:00" level=trace msg="emitting websocket event: volume" May 13 18:33:13 volumio volumio[17129]: SPOTIFY: received: {"type":"volume","data":{"value":82,"max":100}} May 13 18:33:13 volumio volumio[17129]: SPOTIFY: RECEIVED SPOTIFY VOLUME 82 May 13 18:33:18 volumio volumio[17129]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 13 18:33:18 volumio volumio[17129]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 13 18:33:18 volumio volumio[17129]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 13 18:33:29 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 18:33:29 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 18:33:29 volumio volumio[17129]: info: Discovery: Getting this device information May 13 18:33:29 volumio volumio[17129]: info: CoreCommandRouter::volumioGetState May 13 18:33:29 volumio volumio[17129]: info: CorePlayQueue::getTrack 0 May 13 18:33:29 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 18:33:32 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 13 18:33:32 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:33:32 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 13 18:33:32 volumio volumio[17129]: error: Plugin music_service mpd failed to complete 'onStart' in a timely fashion May 13 18:33:32 volumio volumio[17129]: info: ------------------------------------------- May 13 18:33:32 volumio volumio[17129]: info: ----- MyVolumio plugins startup ---- May 13 18:33:32 volumio volumio[17129]: info: ------------------------------------------- May 13 18:33:32 volumio volumio[17129]: info: [MyVolumio PluginManager] Fetching plans data.... May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 13 18:33:40 volumio volumio[17129]: info: Adding plugin bluetooth to MyMusic Plugins May 13 18:33:40 volumio volumio[17129]: info: Adding plugin multiroom to MyMusic Plugins May 13 18:33:40 volumio volumio[17129]: info: Adding plugin metavolumio to MyMusic Plugins May 13 18:33:40 volumio volumio[17129]: info: Adding plugin cd_controller to MyMusic Plugins May 13 18:33:40 volumio volumio[17129]: info: Adding plugin qobuzconnect to MyMusic Plugins May 13 18:33:40 volumio volumio[17129]: info: Adding plugin smart_inputs to MyMusic Plugins May 13 18:33:40 volumio volumio[17129]: info: Adding plugin tidalconnect to MyMusic Plugins May 13 18:33:40 volumio volumio[17129]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 13 18:33:41 volumio volumio[17129]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 13 18:33:41 volumio volumio[17129]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 13 18:33:41 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:33:41 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:33:41 volumio volumio[17129]: info: Starting MyVolumio Remote Streaming Endpoints May 13 18:33:41 volumio volumio[17129]: info: MyVolumio login type: Token May 13 18:33:41 volumio volumio[17129]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 13 18:33:41 volumio volumio[17129]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 13 18:33:41 volumio volumio[17129]: info: Starting Streaming Service Transparent Proxy May 13 18:33:41 volumio volumio[17129]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 13 18:33:41 volumio volumio[17129]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 13 18:33:41 volumio volumio[17129]: info: Streaming services startup May 13 18:33:41 volumio volumio[17129]: info: Starting Streaming Daemon May 13 18:33:41 volumio sudo[17433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 13 18:33:41 volumio sudo[17433]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:33:41 volumio sudo[17433]: pam_unix(sudo:session): session closed for user root May 13 18:33:41 volumio volumio[17129]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 13 18:33:42 volumio volumio[17129]: error: Cannot start Volumio Streaming Daemon May 13 18:33:42 volumio volumio[17129]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 13 18:33:42 volumio volumio[17129]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 13 18:33:42 volumio volumio[17129]: error: MyVolumio Custom Token format not valid, refreshing it May 13 18:33:42 volumio volumio[17129]: STREAMING PROXY: Starting server on port 3245 May 13 18:33:42 volumio volumio[17129]: Node JS runtime: 14 May 13 18:33:42 volumio volumio[17129]: info: MyVolumio login type: Token May 13 18:33:43 volumio volumio[17129]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 13 18:33:44 volumio volumio[17129]: info: MyVolumio token set successfully May 13 18:33:44 volumio volumio[17129]: info: MYVOLUMIO: Adding device May 13 18:33:44 volumio volumio[17129]: info: MYVOLUMIO: Evaluating Server May 13 18:33:44 volumio volumio[17129]: info: MyVolumio Plan changed: virtuoso May 13 18:33:44 volumio volumio[17129]: info: [MyVolumio PluginManager] Subscribed plan changed to virtuoso May 13 18:33:44 volumio volumio[17129]: info: Removing browser output: myVolumio user plan is not superstar May 13 18:33:44 volumio volumio[17129]: info: Removing audio output: May 13 18:33:44 volumio volumio[17129]: info: MYVOLUMIO: Adding device May 13 18:33:44 volumio volumio[17129]: info: MYVOLUMIO: Evaluating Server May 13 18:33:44 volumio volumio[17129]: info: Remote config written successfully May 13 18:33:44 volumio volumio[17129]: info: Starting Tunnel 1 May 13 18:33:44 volumio volumio[17129]: info: Starting Tunnel Connection Checker May 13 18:33:44 volumio volumio[17129]: info: Completed starting MyVolumio Plugin May 13 18:33:45 volumio volumio[17129]: info: MYVolumio Device enabled May 13 18:33:45 volumio volumio[17129]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 13 18:33:45 volumio volumio[17129]: info: MyVolumio status changed May 13 18:33:45 volumio volumio[17129]: info: Streaming services startup May 13 18:33:45 volumio volumio[17129]: info: Starting Streaming Daemon May 13 18:33:45 volumio volumio[17129]: info: Setting Geolocation for MyVolumio to eu3 May 13 18:33:45 volumio sudo[17473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 13 18:33:45 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:33:45 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:33:45 volumio sudo[17473]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:33:45 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 13 18:33:45 volumio sudo[17473]: pam_unix(sudo:session): session closed for user root May 13 18:33:45 volumio volumio[17129]: error: [MyVolumio PluginManager] Cache data is invalid! May 13 18:33:45 volumio volumio[17129]: error: Cannot start Volumio Streaming Daemon May 13 18:33:45 volumio volumio[17129]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 13 18:33:45 volumio volumio[17129]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 13 18:33:45 volumio volumio[17129]: info: Setting Geolocation for MyVolumio to eu3 May 13 18:33:45 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:33:45 volumio volumio[17129]: info: Successfully Added MyVolumio device May 13 18:33:46 volumio volumio[17129]: info: Successfully Added MyVolumio device May 13 18:33:46 volumio volumio[17129]: info: Updating MyVolumio device info May 13 18:33:46 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:33:46 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:33:46 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:33:46 volumio volumio[17129]: info: Updating MyVolumio device info May 13 18:33:46 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:33:46 volumio volumio[17129]: info: Successfully Updated MyVolumio device May 13 18:33:46 volumio volumio[17129]: info: Successfully Updated MyVolumio device May 13 18:33:46 volumio volumio[17129]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 13 18:33:46 volumio volumio[17129]: info: [1747157626932] Starting BluetoothController May 13 18:33:46 volumio volumio[17129]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 13 18:33:46 volumio volumio[17129]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 13 18:33:47 volumio volumio[17129]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 13 18:33:47 volumio volumio[17129]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 13 18:33:47 volumio volumio[17129]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 13 18:33:47 volumio volumio[17129]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth May 13 18:33:47 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 13 18:33:47 volumio volumio[17129]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui May 13 18:33:47 volumio volumio[17129]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller May 13 18:33:47 volumio volumio[17129]: info: Preparing CD Folders May 13 18:33:47 volumio volumio[17129]: info: Adding CD REST API Endpoints May 13 18:33:47 volumio volumio[17129]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 13 18:33:47 volumio volumio[17129]: info: Starting UDEV Watcher for CD May 13 18:33:47 volumio volumio[17129]: info: Detecting CD presence with UDEV May 13 18:33:47 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 13 18:33:47 volumio sudo[17477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio May 13 18:33:47 volumio sudo[17477]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:33:47 volumio sudo[17477]: pam_unix(sudo:session): session closed for user root May 13 18:33:47 volumio volumio[17129]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal May 13 18:33:47 volumio volumio[17129]: info: Refreshing TIDAL token May 13 18:33:47 volumio volumio[17129]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz May 13 18:33:47 volumio volumio[17129]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 13 18:33:47 volumio volumio[17129]: info: Stopping AccessToken refresher cron for QOBUZ May 13 18:33:47 volumio sudo[17480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 13 18:33:47 volumio sudo[17480]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:33:47 volumio systemd[1]: Stopping Volumio Tidal Connect Service... May 13 18:33:47 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 13 18:33:47 volumio volumio[17129]: info: AccessToken refresher cron started for QOBUZ May 13 18:33:47 volumio volumio[17129]: info: Adding QOBUZ REST API Endpoints May 13 18:33:47 volumio volumio[17129]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz May 13 18:33:47 volumio systemd[1]: vtcs.service: Succeeded. May 13 18:33:47 volumio systemd[1]: Stopped Volumio Tidal Connect Service. May 13 18:33:47 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 13 18:33:47 volumio volumio[17129]: info: Bluetooth name changed to Volumio May 13 18:33:47 volumio sudo[17480]: pam_unix(sudo:session): session closed for user root May 13 18:33:47 volumio volumio[17129]: info: Access Token successfully retrieved May 13 18:33:47 volumio volumio[17129]: info: Successfully retrieved User Session From TIDAL May 13 18:33:47 volumio volumio[17129]: info: Successfully retrieved User Subscription From TIDAL May 13 18:33:47 volumio volumio[17129]: info: Adding TIDAL to Browse Sources May 13 18:33:47 volumio volumio[17129]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 13 18:33:47 volumio volumio[17129]: info: [1747157627859] CoreMusicLibrary::Adding element TIDAL May 13 18:33:47 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 18:33:47 volumio volumio[17129]: Cannot find translation for source YouTube Music May 13 18:33:47 volumio volumio[17129]: Cannot find translation for source Spotify May 13 18:33:47 volumio volumio[17129]: Cannot find translation for source TIDAL May 13 18:33:47 volumio volumio[17129]: info: Stopping AccessToken refresher cron May 13 18:33:47 volumio volumio[17129]: info: AccessToken refresher cron started May 13 18:33:47 volumio volumio[17129]: info: Adding TIDAL REST API Endpoints May 13 18:33:47 volumio volumio[17129]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal May 13 18:33:48 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 13 18:33:48 volumio volumio[17129]: info: CURURI: music-library May 13 18:33:48 volumio volumio[17129]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined May 13 18:33:50 volumio sudo[17502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 13 18:33:50 volumio sudo[17502]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:33:50 volumio sudo[17502]: pam_unix(sudo:session): session closed for user root May 13 18:33:50 volumio volumio[17129]: info: Volumio BT Module successfully started May 13 18:33:50 volumio volumio[17129]: info: TidalConnect service stoped! May 13 18:33:50 volumio volumio[17129]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 13 18:33:50 volumio volumio[17129]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 13 18:33:50 volumio sudo[17513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 13 18:33:50 volumio sudo[17513]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:33:50 volumio sudo[17517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 13 18:33:50 volumio sudo[17517]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:33:50 volumio systemd[1]: Started Volumio Tidal Connect Service. May 13 18:33:50 volumio sudo[17513]: pam_unix(sudo:session): session closed for user root May 13 18:33:50 volumio systemd[1]: Stopping MyVolumio SSH Tunnel... May 13 18:33:50 volumio autossh[2829]: received signal to exit (15) May 13 18:33:50 volumio systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM May 13 18:33:50 volumio systemd[1]: sshtunnel.service: Succeeded. May 13 18:33:50 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. May 13 18:33:50 volumio volumio[17129]: ------------------------------------ BT MESSAGE: BT STATUS: running May 13 18:33:50 volumio volumio[17129]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 13 18:33:50 volumio systemd[1]: Started MyVolumio SSH Tunnel. May 13 18:33:50 volumio sudo[17517]: pam_unix(sudo:session): session closed for user root May 13 18:33:50 volumio volumio[17129]: info: Remote SSH Started May 13 18:33:50 volumio autossh[17522]: port set to 0, monitoring disabled May 13 18:33:50 volumio autossh[17522]: starting ssh (count 1) May 13 18:33:50 volumio autossh[17522]: ssh child pid is 17525 May 13 18:33:50 volumio volumio[17129]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 13 18:33:50 volumio volumio[17129]: info: CoreCommandRouter::volumioGetState May 13 18:33:50 volumio volumio[17129]: info: CorePlayQueue::getTrack 0 May 13 18:33:50 volumio volumiossh-tunnel[17521]: Warning: Permanently added '[eu3.myvolumio.org]:2222,[167.71.136.233]:2222' (RSA) to the list of known hosts. May 13 18:33:50 volumio volumio[17129]: info: Executing endpoint tc_getconfig May 13 18:33:50 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 13 18:33:50 volumio vtcs[17515]: STARTING TidalConnect services, version: 1.4.0.34 May 13 18:33:50 volumio vtcs[17515]: STARTED TidalConnect services. May 13 18:33:50 volumio volumio[17129]: info: Executing endpoint tc_connect May 13 18:33:50 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 13 18:33:50 volumio volumio[17129]: info: Connecting to TidalConnect May 13 18:33:50 volumio volumio[17129]: info: CoreCommandRouter::servicePushState May 13 18:33:50 volumio volumio[17129]: info: CoreStateMachine::pushState May 13 18:33:50 volumio volumio[17129]: info: CorePlayQueue::getTrack 0 May 13 18:33:50 volumio volumio[17129]: info: CoreCommandRouter::volumioPushState May 13 18:33:50 volumio volumio[17129]: info: CorePlayQueue::getTrack 0 May 13 18:33:50 volumio volumio[17129]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 13 18:33:50 volumio volumio[17129]: info: CoreCommandRouter::servicePushState May 13 18:33:50 volumio volumio[17129]: info: CoreStateMachine::pushState May 13 18:33:50 volumio volumio[17129]: info: CorePlayQueue::getTrack 0 May 13 18:33:50 volumio volumio[17129]: info: CoreCommandRouter::volumioPushState May 13 18:33:50 volumio volumio[17129]: info: CorePlayQueue::getTrack 0 May 13 18:33:50 volumio volumio[17129]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 13 18:33:50 volumio volumio[17129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 May 13 18:33:50 volumio volumio[17129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 May 13 18:33:53 volumio volumio[17129]: info: TidalConnect service started! May 13 18:33:53 volumio volumio[17129]: [Metrics] CommandRouter: 56s 258.91ms May 13 18:33:53 volumio volumio[17129]: info: CoreCommandRouter::volumiosetStartupVolume May 13 18:33:53 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:33:53 volumio volumio[17129]: info: VolumeController:: Setting startup Volume 80 May 13 18:33:53 volumio volumio[17129]: info: VolumeController::SetAlsaVolume80 May 13 18:33:53 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:33:53 volumio volumio[17129]: info: CoreCommandRouter::Close All Modals sent May 13 18:33:53 volumio volumio[17129]: info: CoreCommandRouter::Close All Modals sent May 13 18:33:53 volumio volumio[17129]: info: CoreStateMachine::pushState May 13 18:33:53 volumio volumio[17129]: info: CorePlayQueue::getTrack 0 May 13 18:33:53 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 18:33:53 volumio volumio[17129]: info: CoreCommandRouter::volumioPushState May 13 18:33:53 volumio volumio[17129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 May 13 18:33:53 volumio volumio[17129]: SPOTIFY: SPOTIFY VOLUME 82 May 13 18:33:53 volumio volumio[17129]: SPOTIFY: VOLUMIO VOLUME 80 May 13 18:33:53 volumio volumio[17129]: SPOTIFY: DELTA VOLUME ENOUGH: true May 13 18:33:53 volumio volumio[17129]: info: Setting Spotify Volume from Volumio: 80 May 13 18:33:54 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 13 18:33:54 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 13 18:33:54 volumio volumio[17129]: xcb_connection_has_error() returned true May 13 18:33:54 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 13 18:33:54 volumio volumio[17129]: SPOTIFY: SETTING SPOTIFY VOLUME 80 May 13 18:33:54 volumio volumio[17129]: info: Sending Spotify command with payload to local API: /player/volume May 13 18:33:54 volumio go-librespot[17291]: time="2025-05-13T18:33:54+01:00" level=debug msg="update volume to 52428/65535" May 13 18:33:54 volumio go-librespot[17291]: time="2025-05-13T18:33:54+01:00" level=debug msg="put connect state because VOLUME_CHANGED" May 13 18:33:54 volumio go-librespot[17291]: time="2025-05-13T18:33:54+01:00" level=trace msg="emitting websocket event: volume" May 13 18:33:54 volumio volumio[17129]: SPOTIFY: received: {"type":"volume","data":{"value":80,"max":100}} May 13 18:33:54 volumio volumio[17129]: SPOTIFY: RECEIVED SPOTIFY VOLUME 80 May 13 18:33:56 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 13 18:33:56 volumio volumio[17129]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 13 18:33:56 volumio volumio[17129]: info: CoreCommandRouter::volumioGetState May 13 18:33:56 volumio volumio[17129]: info: CorePlayQueue::getTrack 0 May 13 18:33:59 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 18:33:59 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 18:33:59 volumio volumio[17129]: info: Discovery: Getting this device information May 13 18:33:59 volumio volumio[17129]: info: CoreCommandRouter::volumioGetState May 13 18:33:59 volumio volumio[17129]: info: CorePlayQueue::getTrack 0 May 13 18:33:59 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 18:34:00 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 13 18:34:00 volumio volumio[17129]: info: CURURI: music-library May 13 18:34:00 volumio volumio[17129]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined May 13 18:34:00 volumio volumio[17129]: info: BOOT COMPLETED May 13 18:34:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:34:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:34:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 13 18:34:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 13 18:34:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 13 18:34:01 volumio volumio[17129]: info: CoreCommandRouter::volumioGetBrowseSources May 13 18:34:01 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 13 18:34:07 volumio volumio[17129]: info: CoreCommandRouter::getUIConfigOnPlugin May 13 18:34:07 volumio volumio[17129]: info: CoreCommandRouter::getUIConfigOnPlugin May 13 18:34:07 volumio volumio[17129]: info: Retrieving Cloud Streaming UI May 13 18:34:07 volumio volumio[17129]: info: Getting Tidal Cloud Configuration May 13 18:34:07 volumio volumio[17129]: info: CoreCommandRouter::getUIConfigOnPlugin May 13 18:34:07 volumio volumio[17129]: info: Getting Qobuz Cloud Configuration May 13 18:34:07 volumio volumio[17129]: info: Asking plugin for UI Config May 13 18:34:07 volumio volumio[17129]: info: CoreCommandRouter::getUIConfigOnPlugin May 13 18:34:07 volumio volumio[17129]: info: Getting Spotify Cloud Configuration May 13 18:34:07 volumio volumio[17129]: info: Asking plugin for UI Config May 13 18:34:07 volumio volumio[17129]: info: CoreCommandRouter::getUIConfigOnPlugin May 13 18:34:07 volumio volumio[17129]: info: Saving Spotify Acccount May 13 18:34:07 volumio volumio[17129]: info: Got it May 13 18:34:07 volumio volumio[17129]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 13 18:34:07 volumio volumio[17129]: info: Got Tidal Cloud Configuration May 13 18:34:07 volumio volumio[17129]: info: Got it May 13 18:34:07 volumio volumio[17129]: info: CoreCommandRouter::getUIConfigOnPlugin May 13 18:34:07 volumio volumio[17129]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 13 18:34:07 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 13 18:34:07 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 13 18:34:07 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 13 18:34:07 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 13 18:34:07 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 18:34:07 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 18:34:07 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 18:34:07 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 18:34:07 volumio volumio[17129]: info: CoreCommandRouter::volumioGetBrowseSources May 13 18:34:07 volumio volumio[17129]: info: CoreCommandRouter::volumioGetBrowseSources May 13 18:34:07 volumio volumio[17129]: info: CoreCommandRouter::volumioGetBrowseSources May 13 18:34:07 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 13 18:34:07 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 13 18:34:11 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 13 18:34:13 volumio volumio[17129]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 13 18:34:13 volumio volumio[17129]: verbose: ControllerMpd::sendMpdCommand update May 13 18:34:13 volumio volumio[17129]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 18:34:13 volumio volumio[17129]: TypeError: Cannot read property 'then' of undefined May 13 18:34:13 volumio volumio[17129]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) May 13 18:34:13 volumio volumio[17129]: at ControllerMpd.updateDb (/volumio/app/plugins/music_service/mpd/index.js:1899:15) May 13 18:34:13 volumio volumio[17129]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) May 13 18:34:13 volumio volumio[17129]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1091:26) May 13 18:34:13 volumio volumio[17129]: at Socket.emit (events.js:315:20) May 13 18:34:13 volumio volumio[17129]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 13 18:34:13 volumio volumio[17129]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 13 18:34:13 volumio volumio[17129]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 18:34:13 volumio sudo[17632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-13 18:33 May 13 18:34:13 volumio sudo[17632]: 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="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"