-- Logs begin at Tue 2024-07-16 19:19:01 CEST, end at Fri 2024-07-26 11:46:30 CEST. -- Jul 26 11:45:29 foodrave wpa_supplicant[899]: wlan0: CTRL-EVENT-DISCONNECTED bssid=c0:06:c3:13:37:80 reason=0 locally_generated=1 Jul 26 11:45:29 foodrave dhcpcd[911]: wlan0: carrier lost Jul 26 11:45:29 foodrave wpa_supplicant[899]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 26 11:45:29 foodrave wpa_supplicant[899]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN Jul 26 11:45:29 foodrave avahi-daemon[675]: Withdrawing address record for 192.168.0.118 on wlan0. Jul 26 11:45:29 foodrave avahi-daemon[675]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.118. Jul 26 11:45:29 foodrave dhcpcd[911]: wlan0: deleting route to 192.168.0.0/24 Jul 26 11:45:29 foodrave dhcpcd[911]: wlan0: deleting default route via 192.168.0.1 Jul 26 11:45:29 foodrave avahi-daemon[675]: Interface wlan0.IPv4 no longer relevant for mDNS. Jul 26 11:45:29 foodrave volumio[6214]: info: Discovery: A device disappeared from network Jul 26 11:45:29 foodrave volumio[6214]: info: Discovery: Device anlage disappeared from network Jul 26 11:45:29 foodrave volumio[6214]: info: Discovery: Disconnected from remote: 192.168.0.114 Jul 26 11:45:29 foodrave volumio[6214]: info: Discovery: A device disappeared from network Jul 26 11:45:29 foodrave volumio[6214]: info: Discovery: Device foodrave disappeared from network Jul 26 11:45:31 foodrave ntpd[802]: Deleting interface #8 wlan0, 192.168.0.118#123, interface stats: received=183, sent=198, dropped=0, active_time=5225 secs Jul 26 11:45:31 foodrave ntpd[802]: 185.252.140.125 local addr 192.168.0.118 -> Jul 26 11:45:31 foodrave ntpd[802]: 194.164.164.175 local addr 192.168.0.118 -> Jul 26 11:45:31 foodrave ntpd[802]: 54.36.110.36 local addr 192.168.0.118 -> Jul 26 11:45:31 foodrave ntpd[802]: 176.9.90.88 local addr 192.168.0.118 -> Jul 26 11:45:31 foodrave ntpd[802]: 188.245.32.133 local addr 192.168.0.118 -> Jul 26 11:45:31 foodrave ntpd[802]: 194.59.207.107 local addr 192.168.0.118 -> Jul 26 11:45:31 foodrave ntpd[802]: 213.172.105.106 local addr 192.168.0.118 -> Jul 26 11:45:31 foodrave ntpd[802]: 185.248.188.98 local addr 192.168.0.118 -> Jul 26 11:45:31 foodrave ntpd[802]: 157.90.24.29 local addr 192.168.0.118 -> Jul 26 11:45:31 foodrave ntpd[802]: 79.133.44.143 local addr 192.168.0.118 -> Jul 26 11:45:31 foodrave ntpd[802]: 148.251.5.46 local addr 192.168.0.118 -> Jul 26 11:45:31 foodrave ntpd[802]: 79.133.44.139 local addr 192.168.0.118 -> Jul 26 11:45:31 foodrave ntpd[802]: 45.9.61.155 local addr 192.168.0.118 -> Jul 26 11:45:31 foodrave ntpd[802]: 79.133.44.142 local addr 192.168.0.118 -> Jul 26 11:45:31 foodrave ntpd[802]: 185.232.69.65 local addr 192.168.0.118 -> Jul 26 11:45:31 foodrave ntpd[802]: 94.130.184.193 local addr 192.168.0.118 -> Jul 26 11:45:41 foodrave wpa_supplicant[899]: wlan0: Trying to associate with SSID 'Slytherpuff' Jul 26 11:45:41 foodrave volumio[6214]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 11:45:41 foodrave volumio[6214]: Error: send ENETUNREACH 255.255.255.255:3483 Jul 26 11:45:41 foodrave volumio[6214]: at doSend (dgram.js:692:16) Jul 26 11:45:41 foodrave volumio[6214]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jul 26 11:45:41 foodrave volumio[6214]: at afterDns (dgram.js:638:5) Jul 26 11:45:41 foodrave volumio[6214]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jul 26 11:45:41 foodrave volumio[6214]: errno: -101, Jul 26 11:45:41 foodrave volumio[6214]: code: 'ENETUNREACH', Jul 26 11:45:41 foodrave volumio[6214]: syscall: 'send', Jul 26 11:45:41 foodrave volumio[6214]: address: '255.255.255.255', Jul 26 11:45:41 foodrave volumio[6214]: port: 3483 Jul 26 11:45:41 foodrave volumio[6214]: } Jul 26 11:45:41 foodrave volumio[6214]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 11:45:41 foodrave wpa_supplicant[899]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jul 26 11:45:42 foodrave sudo[14373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-26 11:44 Jul 26 11:45:42 foodrave sudo[14373]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:45:42 foodrave sudo[14373]: pam_unix(sudo:session): session closed for user root Jul 26 11:45:42 foodrave go-librespot[496]: time="2024-07-26T11:45:42+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jul 26 11:45:42 foodrave volumio-remote-updater[652]: [2024-07-26 11:45:42] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 26 11:45:42 foodrave volumio-remote-updater[652]: [2024-07-26 11:45:42] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 26 11:45:42 foodrave systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 26 11:45:42 foodrave go-librespot[496]: time="2024-07-26T11:45:42+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jul 26 11:45:42 foodrave systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 26 11:45:42 foodrave systemd[1]: Started dynamicswap service. Jul 26 11:45:42 foodrave systemd[1]: dynamicswap.service: Succeeded. Jul 26 11:45:42 foodrave systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 26 11:45:42 foodrave systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Jul 26 11:45:42 foodrave systemd[1]: Stopped Volumio Backend Module. Jul 26 11:45:42 foodrave wpa_supplicant[899]: wlan0: Trying to associate with SSID 'Slytherpuff' Jul 26 11:45:42 foodrave systemd[1]: Started Volumio Backend Module. Jul 26 11:45:42 foodrave systemd[1]: Started dynamicswap service. Jul 26 11:45:42 foodrave systemd[1]: dynamicswap.service: Succeeded. Jul 26 11:45:43 foodrave wpa_supplicant[899]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jul 26 11:45:44 foodrave volumio[14387]: info: ------------------------------------------- Jul 26 11:45:44 foodrave volumio[14387]: info: ----- Volumio3 ---- Jul 26 11:45:44 foodrave volumio[14387]: info: ------------------------------------------- Jul 26 11:45:44 foodrave volumio[14387]: info: ----- System startup ---- Jul 26 11:45:44 foodrave volumio[14387]: info: ------------------------------------------- Jul 26 11:45:45 foodrave wpa_supplicant[899]: wlan0: Trying to associate with SSID 'Slytherpuff' Jul 26 11:45:45 foodrave wpa_supplicant[899]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jul 26 11:45:45 foodrave wpa_supplicant[899]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Slytherpuff" auth_failures=1 duration=10 reason=CONN_FAILED Jul 26 11:45:45 foodrave volumio[14387]: info: MYVOLUMIO Environment detected Jul 26 11:45:45 foodrave volumio[14387]: info: Plugin folders cleanup Jul 26 11:45:45 foodrave volumio[14387]: info: Scanning into folder /volumio/app/plugins/ Jul 26 11:45:45 foodrave volumio[14387]: info: Scanning category audio_interface Jul 26 11:45:45 foodrave volumio[14387]: info: Scanning category miscellanea Jul 26 11:45:45 foodrave volumio[14387]: info: Scanning category music_service Jul 26 11:45:45 foodrave volumio[14387]: info: Scanning category plugins.json Jul 26 11:45:45 foodrave volumio[14387]: info: Scanning category system_controller Jul 26 11:45:45 foodrave volumio[14387]: info: Scanning category user_interface Jul 26 11:45:45 foodrave volumio[14387]: info: Scanning into folder /data/plugins/ Jul 26 11:45:45 foodrave volumio[14387]: info: Scanning category music_service Jul 26 11:45:45 foodrave volumio[14387]: info: Plugin folders cleanup completed Jul 26 11:45:45 foodrave volumio[14387]: info: ------------------------------------------- Jul 26 11:45:45 foodrave volumio[14387]: info: ----- Core plugins startup ---- Jul 26 11:45:45 foodrave volumio[14387]: info: ------------------------------------------- Jul 26 11:45:45 foodrave volumio[14387]: info: Loading plugins from folder /volumio/app/plugins/ Jul 26 11:45:45 foodrave volumio[14387]: info: Adding plugin upnp to MyMusic Plugins Jul 26 11:45:45 foodrave volumio[14387]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 26 11:45:45 foodrave volumio[14387]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 26 11:45:45 foodrave volumio[14387]: info: Loading plugins from folder /data/plugins/ Jul 26 11:45:45 foodrave volumio[14387]: info: Loading plugin "system"... Jul 26 11:45:45 foodrave volumio[14387]: info: Loading plugin "appearance"... Jul 26 11:45:46 foodrave volumio[14387]: info: Loading plugin "network"... Jul 26 11:45:46 foodrave volumio[14387]: info: Refreshing Cached IP Addresses Jul 26 11:45:46 foodrave sudo[14415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 26 11:45:46 foodrave sudo[14415]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:45:46 foodrave sudo[14415]: pam_unix(sudo:session): session closed for user root Jul 26 11:45:46 foodrave sudo[14417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 26 11:45:46 foodrave volumio[14387]: info: Loading plugin "services"... Jul 26 11:45:46 foodrave sudo[14417]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:45:46 foodrave volumio[14387]: info: Loading plugin "alsa_controller"... Jul 26 11:45:46 foodrave sudo[14417]: pam_unix(sudo:session): session closed for user root Jul 26 11:45:46 foodrave sudo[14420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 26 11:45:46 foodrave sudo[14420]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:45:47 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 26 11:45:47 foodrave volumio[14387]: info: Loading plugin "wizard"... Jul 26 11:45:47 foodrave volumio[14387]: info: Loading plugin "networkfs"... Jul 26 11:45:47 foodrave volumio[14387]: info: Starting Udev Watcher for removable devices Jul 26 11:45:47 foodrave volumio[14387]: info: Ignoring mount for partition: boot Jul 26 11:45:47 foodrave volumio[14387]: info: Ignoring mount for partition: volumio Jul 26 11:45:47 foodrave volumio[14387]: info: Ignoring mount for partition: volumio_data Jul 26 11:45:47 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 26 11:45:47 foodrave volumio[14387]: info: Loading plugin "volumio_command_line_client"... Jul 26 11:45:47 foodrave volumio[14387]: info: Loading plugin "upnp"... Jul 26 11:45:47 foodrave volumio[14387]: info: [1721987147136] Starting Upmpd Daemon Jul 26 11:45:47 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 26 11:45:47 foodrave volumio[14387]: info: Loading plugin "my_music"... Jul 26 11:45:47 foodrave volumio[14387]: info: Loading plugin "mpd"... Jul 26 11:45:47 foodrave volumio[14387]: info: Loading plugin "upnp_browser"... Jul 26 11:45:47 foodrave volumio-remote-updater[652]: [2024-07-26 11:45:47] [connect] Successful connection Jul 26 11:45:47 foodrave sudo[14420]: pam_unix(sudo:session): session closed for user root Jul 26 11:45:47 foodrave volumio[14387]: info: Loading plugin "alarm-clock"... Jul 26 11:45:47 foodrave volumio[14387]: info: Loading plugin "airplay_emulation"... Jul 26 11:45:47 foodrave volumio[14387]: info: Starting Shairport Sync Jul 26 11:45:47 foodrave volumio[14387]: info: Loading plugin "last_100"... Jul 26 11:45:47 foodrave volumio[14387]: info: Loading plugin "webradio"... Jul 26 11:45:48 foodrave volumio[14387]: info: Loading plugin "i2s_dacs"... Jul 26 11:45:48 foodrave volumio[14387]: info: Loading plugin "volumiodiscovery"... Jul 26 11:45:48 foodrave volumio[14387]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 26 11:45:48 foodrave volumio[14387]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 11:45:48 foodrave volumio[14387]: *** WARNING *** For more information see Jul 26 11:45:48 foodrave volumio[14387]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 26 11:45:48 foodrave node[14387]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 26 11:45:48 foodrave volumio[14387]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 11:45:48 foodrave volumio[14387]: *** WARNING *** For more information see Jul 26 11:45:48 foodrave node[14387]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 11:45:48 foodrave node[14387]: *** WARNING *** For more information see Jul 26 11:45:48 foodrave node[14387]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 26 11:45:48 foodrave node[14387]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 11:45:48 foodrave node[14387]: *** WARNING *** For more information see Jul 26 11:45:48 foodrave volumio[14387]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 26 11:45:48 foodrave volumio[14387]: info: Discovery: Started advertising with name: Foodrave Jul 26 11:45:48 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 26 11:45:48 foodrave volumio[14387]: info: Loading plugin "mixcloud"... Jul 26 11:45:48 foodrave volumio[14387]: info: Loading plugin "soundcloud"... Jul 26 11:45:49 foodrave volumio[14387]: info: Loading plugin "spop"... Jul 26 11:45:50 foodrave volumio[14387]: info: Loading plugin "squeezelite_mc"... Jul 26 11:45:51 foodrave volumio[14387]: info: Loading plugin "ytcr"... Jul 26 11:45:53 foodrave volumio[14387]: info: Loading plugin "ytmusic"... Jul 26 11:45:54 foodrave volumio[14387]: info: Loading plugin "outputs"... Jul 26 11:45:54 foodrave volumio[14387]: info: Loading plugin "albumart"... Jul 26 11:45:54 foodrave volumio[14387]: info: Plugin example_plugin is not enabled Jul 26 11:45:54 foodrave volumio[14387]: info: Loading plugin "inputs"... Jul 26 11:45:54 foodrave volumio[14387]: info: Loading plugin "updater_comm"... Jul 26 11:45:54 foodrave volumio[14387]: info: Plugin mpdemulation is not enabled Jul 26 11:45:54 foodrave volumio[14387]: info: Loading plugin "rest_api"... Jul 26 11:45:54 foodrave volumio[14387]: info: Loading plugin "websocket"... Jul 26 11:45:54 foodrave volumio[14387]: info: Starting Socket.io Server version 2.3.0 Jul 26 11:45:54 foodrave volumio[14387]: info: Plugin lms is not enabled Jul 26 11:45:54 foodrave volumio[14387]: info: Loading i18n strings for locale de Jul 26 11:45:54 foodrave volumio[14387]: Updating browse sources language Jul 26 11:45:54 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 11:45:54 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 11:45:54 foodrave volumio[14387]: info: CoreCommandRouter::initPlayerControls Jul 26 11:45:54 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:45:54 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:45:54 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:45:54 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:45:54 foodrave volumio[14387]: Forking 3 albumart workers Jul 26 11:45:54 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:45:54 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:45:54 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:45:54 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:45:54 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 11:45:54 foodrave volumio[14387]: Express server listening on port 3000 Jul 26 11:45:54 foodrave volumio[14387]: [Metrics] WebUI: 10s 603.57ms Jul 26 11:45:55 foodrave volumio[14387]: info: CoreStateMachine::resetVolumioState Jul 26 11:45:55 foodrave volumio[14387]: info: CoreStateMachine::getcurrentVolume Jul 26 11:45:55 foodrave volumio[14387]: info: CoreCommandRouter::volumioRetrievevolume Jul 26 11:45:55 foodrave wpa_supplicant[899]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Slytherpuff" Jul 26 11:45:55 foodrave wpa_supplicant[899]: wlan0: Trying to associate with SSID 'Slytherpuff' Jul 26 11:45:55 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 11:45:55 foodrave volumio[14387]: info: Reloading queue from file Jul 26 11:45:55 foodrave volumio[14387]: info: VolumeController:: Volume=96 Mute =false Jul 26 11:45:55 foodrave volumio[14387]: info: CoreStateMachine::pushState Jul 26 11:45:55 foodrave volumio[14387]: info: CorePlayQueue::getTrack 0 Jul 26 11:45:55 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 11:45:55 foodrave volumio[14387]: info: CoreCommandRouter::volumioPushState Jul 26 11:45:55 foodrave volumio[14387]: info: CoreStateMachine::updateTrackBlock Jul 26 11:45:55 foodrave volumio[14387]: info: CorePlayQueue::getTrackBlock Jul 26 11:45:55 foodrave volumio[14387]: info: CoreCommandRouter::volumioRetrievevolume Jul 26 11:45:55 foodrave volumio[14387]: info: Setting Device type: Raspberry PI Jul 26 11:45:55 foodrave volumio[14387]: info: CoreStateMachine::setRepeat null single undefined Jul 26 11:45:55 foodrave volumio[14387]: info: CoreStateMachine::pushState Jul 26 11:45:55 foodrave volumio[14387]: info: CorePlayQueue::getTrack 0 Jul 26 11:45:55 foodrave volumio[14387]: info: CoreCommandRouter::volumioPushState Jul 26 11:45:55 foodrave volumio[14387]: info: CoreStateMachine::setRandom null Jul 26 11:45:55 foodrave volumio[14387]: info: CoreStateMachine::pushState Jul 26 11:45:55 foodrave volumio[14387]: info: CorePlayQueue::getTrack 0 Jul 26 11:45:55 foodrave volumio[14387]: info: CoreCommandRouter::volumioPushState Jul 26 11:45:55 foodrave volumio[14387]: info: Completed loading Core Plugins Jul 26 11:45:55 foodrave volumio[14387]: info: Preparing to generate the ALSA configuration file Jul 26 11:45:55 foodrave volumio[14387]: info: Asound.conf file unchanged, so no further update is needed Jul 26 11:45:55 foodrave volumio[14387]: info: Output device has changed, restarting MPD Jul 26 11:45:55 foodrave wpa_supplicant[899]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jul 26 11:45:55 foodrave wpa_supplicant[899]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Slytherpuff" auth_failures=2 duration=23 reason=CONN_FAILED Jul 26 11:45:55 foodrave volumio[14387]: info: Output device has changed, restarting Shairport Sync Jul 26 11:45:55 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:45:55 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 11:45:55 foodrave sudo[14473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 26 11:45:55 foodrave sudo[14473]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:45:55 foodrave sudo[14474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 26 11:45:55 foodrave sudo[14474]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:45:55 foodrave sudo[14473]: pam_unix(sudo:session): session closed for user root Jul 26 11:45:55 foodrave volumio[14387]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 26 11:45:55 foodrave volumio[14387]: info: ___________ START PLUGINS ___________ Jul 26 11:45:56 foodrave volumio[14387]: info: ControllerMpd::onStart: Initializing MPD Jul 26 11:45:56 foodrave volumio[14387]: info: Creating MPD Configuration file Jul 26 11:45:56 foodrave systemd[1]: Stopping Music Player Daemon... Jul 26 11:45:56 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 11:45:56 foodrave volumio[14387]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 11:45:56 foodrave volumio[14387]: info: [1721987156081] CoreMusicLibrary::Adding element Medienserver Jul 26 11:45:56 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 11:45:56 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:45:56 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 11:45:56 foodrave systemd[1]: mpd.service: Succeeded. Jul 26 11:45:56 foodrave systemd[1]: Stopped Music Player Daemon. Jul 26 11:45:56 foodrave sudo[14481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 26 11:45:56 foodrave sudo[14481]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:45:56 foodrave sudo[14481]: pam_unix(sudo:session): session closed for user root Jul 26 11:45:56 foodrave sudo[14484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 26 11:45:56 foodrave systemd[1]: Starting Music Player Daemon... Jul 26 11:45:56 foodrave sudo[14484]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:45:56 foodrave systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 26 11:45:56 foodrave systemd[1]: mpd.service: Succeeded. Jul 26 11:45:56 foodrave systemd[1]: Stopped Music Player Daemon. Jul 26 11:45:56 foodrave systemd[1]: Starting Music Player Daemon... Jul 26 11:45:56 foodrave volumio[14387]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 26 11:45:56 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:45:56 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 11:45:56 foodrave volumio[14387]: Starting albumart workers Jul 26 11:45:56 foodrave volumio[14387]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 26 11:45:56 foodrave volumio[14387]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 11:45:56 foodrave volumio[14387]: info: [1721987156308] CoreMusicLibrary::Adding element Last_100 Jul 26 11:45:56 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 11:45:56 foodrave volumio[14387]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 11:45:56 foodrave volumio[14387]: info: [1721987156312] CoreMusicLibrary::Adding element Webradio Jul 26 11:45:56 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 11:45:56 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 26 11:45:56 foodrave volumio[14387]: info: Initializing BBC Radios Jul 26 11:45:56 foodrave sudo[14491]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 26 11:45:56 foodrave sudo[14491]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:45:56 foodrave sudo[14491]: pam_unix(sudo:session): session closed for user root Jul 26 11:45:56 foodrave volumio[14387]: Starting albumart workers Jul 26 11:45:56 foodrave volumio[14387]: Starting albumart workers Jul 26 11:45:56 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 26 11:45:56 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 11:45:56 foodrave volumio[14387]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 11:45:56 foodrave volumio[14387]: info: [1721987156661] CoreMusicLibrary::Adding element Mixcloud Jul 26 11:45:56 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 11:45:56 foodrave volumio[14387]: Cannot find translation for source Mixcloud Jul 26 11:45:56 foodrave volumio[14387]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 11:45:56 foodrave volumio[14387]: info: [1721987156688] CoreMusicLibrary::Adding element SoundCloud Jul 26 11:45:56 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 11:45:56 foodrave volumio[14387]: Cannot find translation for source Mixcloud Jul 26 11:45:56 foodrave volumio[14387]: Cannot find translation for source SoundCloud Jul 26 11:45:56 foodrave volumio[14387]: info: Creating Spotify config file Jul 26 11:45:56 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:45:57 foodrave volumio[14387]: info: [squeezelite_mc] Starting proxy server... Jul 26 11:45:57 foodrave volumio[14387]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 11:45:57 foodrave volumio[14387]: info: [1721987157168] CoreMusicLibrary::Adding element YouTube Music Jul 26 11:45:57 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 11:45:57 foodrave volumio[14387]: Cannot find translation for source Mixcloud Jul 26 11:45:57 foodrave volumio[14387]: Cannot find translation for source SoundCloud Jul 26 11:45:57 foodrave volumio[14387]: Cannot find translation for source YouTube Music Jul 26 11:45:57 foodrave volumio[14387]: info: Volumio Calling Home Jul 26 11:45:57 foodrave volumio[14387]: info: [squeezelite_mc] Proxy server started on port 40655 Jul 26 11:45:57 foodrave volumio[14387]: info: CoreCommandRouter::volumioRetrievevolume Jul 26 11:45:57 foodrave volumio[14387]: Unhandled rejection Error: No sockets available, cannot start. Jul 26 11:45:57 foodrave volumio[14387]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 26 11:45:57 foodrave volumio[14387]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 26 11:45:57 foodrave volumio[14387]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 26 11:45:57 foodrave volumio[14387]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 26 11:45:57 foodrave volumio[14387]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 26 11:45:57 foodrave volumio[14387]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 26 11:45:57 foodrave volumio[14387]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 26 11:45:57 foodrave volumio[14387]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 26 11:45:57 foodrave volumio[14387]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 26 11:45:57 foodrave volumio[14387]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 26 11:45:57 foodrave volumio[14387]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 26 11:45:57 foodrave volumio[14387]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 26 11:45:57 foodrave volumio[14387]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 26 11:45:57 foodrave volumio[14387]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 26 11:45:57 foodrave volumio[14387]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 26 11:45:57 foodrave volumio[14387]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 26 11:45:57 foodrave sudo[14536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 26 11:45:57 foodrave sudo[14536]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:45:57 foodrave sudo[14536]: pam_unix(sudo:session): session closed for user root Jul 26 11:45:57 foodrave volumio[14387]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Jul 26 11:45:57 foodrave sudo[14539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 26 11:45:57 foodrave sudo[14539]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:45:57 foodrave volumio[14387]: error: [yt-cast-receiver] (YouTube) Failed to obtain lounge token with screen Id from stored MDX context (9eua57nd8i7u87588ibv6nnnnb): Jul 26 11:45:57 foodrave volumio[14387]: (ConnectionError) (YouTube) Connection error in getting lounge token Jul 26 11:45:57 foodrave volumio[14387]: Error info: { Jul 26 11:45:57 foodrave volumio[14387]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Jul 26 11:45:57 foodrave volumio[14387]: } Jul 26 11:45:57 foodrave volumio[14387]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo EBUSY www.youtube.com Jul 26 11:45:57 foodrave volumio[14387]: Stack trace: Jul 26 11:45:57 foodrave volumio[14387]: ConnectionError: (YouTube) Connection error in getting lounge token Jul 26 11:45:57 foodrave volumio[14387]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Jul 26 11:45:57 foodrave volumio[14387]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:45:57 foodrave volumio[14387]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Jul 26 11:45:57 foodrave volumio[14387]: at async Promise.all (index 0) Jul 26 11:45:57 foodrave volumio[14387]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Jul 26 11:45:57 foodrave volumio[14387]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Jul 26 11:45:57 foodrave volumio[14387]: Going to generate fresh screen Id and try again... Jul 26 11:45:57 foodrave sudo[14539]: pam_unix(sudo:session): session closed for user root Jul 26 11:45:57 foodrave volumio[14387]: error: [yt-cast-receiver] (YouTube Music) Failed to obtain lounge token with screen Id from stored MDX context (4iag2ofhu256b892n9hu816u72): Jul 26 11:45:57 foodrave volumio[14387]: (ConnectionError) (YouTube Music) Connection error in getting lounge token Jul 26 11:45:57 foodrave volumio[14387]: Error info: { Jul 26 11:45:57 foodrave volumio[14387]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Jul 26 11:45:57 foodrave volumio[14387]: } Jul 26 11:45:57 foodrave volumio[14387]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo EBUSY www.youtube.com Jul 26 11:45:57 foodrave volumio[14387]: Stack trace: Jul 26 11:45:57 foodrave volumio[14387]: ConnectionError: (YouTube Music) Connection error in getting lounge token Jul 26 11:45:57 foodrave volumio[14387]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Jul 26 11:45:57 foodrave volumio[14387]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:45:57 foodrave volumio[14387]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Jul 26 11:45:57 foodrave volumio[14387]: at async Promise.all (index 1) Jul 26 11:45:57 foodrave volumio[14387]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Jul 26 11:45:57 foodrave volumio[14387]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Jul 26 11:45:57 foodrave volumio[14387]: Going to generate fresh screen Id and try again... Jul 26 11:45:57 foodrave volumio[14387]: info: MPD Permissions set Jul 26 11:45:57 foodrave volumio[14387]: info: MPD Permissions set Jul 26 11:45:57 foodrave volumio[14387]: info: VolumeController:: Volume=96 Mute =false Jul 26 11:45:57 foodrave volumio[14387]: info: CoreCommandRouter::volumioGetState Jul 26 11:45:57 foodrave volumio[14387]: info: CorePlayQueue::getTrack 0 Jul 26 11:45:57 foodrave volumio[14387]: info: CoreStateMachine::pushState Jul 26 11:45:57 foodrave volumio[14387]: info: CorePlayQueue::getTrack 0 Jul 26 11:45:57 foodrave volumio[14387]: info: CoreCommandRouter::volumioPushState Jul 26 11:45:57 foodrave volumio[14387]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Jul 26 11:45:57 foodrave volumio[14387]: LoungeScreenDisconnected { Jul 26 11:45:57 foodrave volumio[14387]: AID: null, Jul 26 11:45:57 foodrave volumio[14387]: name: 'loungeScreenDisconnected', Jul 26 11:45:57 foodrave volumio[14387]: payload: {} Jul 26 11:45:57 foodrave volumio[14387]: } Jul 26 11:45:57 foodrave volumio[14387]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:45:57 foodrave volumio[14387]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:45:57 foodrave volumio[14387]: Stack trace: Jul 26 11:45:57 foodrave volumio[14387]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:45:57 foodrave volumio[14387]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:45:57 foodrave volumio[14387]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:45:57 foodrave volumio[14387]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:45:57 foodrave volumio[14387]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:45:57 foodrave volumio[14387]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Jul 26 11:45:57 foodrave volumio[14387]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Jul 26 11:45:57 foodrave volumio[14387]: at new Promise () Jul 26 11:45:57 foodrave volumio[14387]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Jul 26 11:45:57 foodrave volumio[14387]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Jul 26 11:45:57 foodrave volumio[14387]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:207:26) Jul 26 11:45:57 foodrave volumio[14387]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:45:57 foodrave volumio[14387]: at async Promise.all (index 0) Jul 26 11:45:57 foodrave volumio[14387]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Jul 26 11:45:57 foodrave volumio[14387]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Jul 26 11:45:57 foodrave volumio[14387]: error: [yt-cast-receiver] Failed to start receiver: Jul 26 11:45:57 foodrave volumio[14387]: (AppError) Failed to start YouTubeApp Jul 26 11:45:57 foodrave volumio[14387]: --->(SessionError) (YouTube) Failed to establish session Jul 26 11:45:57 foodrave volumio[14387]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Jul 26 11:45:57 foodrave volumio[14387]: Error info: { Jul 26 11:45:57 foodrave volumio[14387]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Jul 26 11:45:57 foodrave volumio[14387]: } Jul 26 11:45:57 foodrave volumio[14387]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo EBUSY www.youtube.com Jul 26 11:45:57 foodrave volumio[14387]: Stack trace: Jul 26 11:45:57 foodrave volumio[14387]: AppError: Failed to start YouTubeApp Jul 26 11:45:57 foodrave volumio[14387]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Jul 26 11:45:57 foodrave volumio[14387]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:45:57 foodrave volumio[14387]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Jul 26 11:45:57 foodrave volumio[14387]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Jul 26 11:45:57 foodrave volumio[14387]: LoungeScreenDisconnected { Jul 26 11:45:57 foodrave volumio[14387]: AID: null, Jul 26 11:45:57 foodrave volumio[14387]: name: 'loungeScreenDisconnected', Jul 26 11:45:57 foodrave volumio[14387]: payload: {} Jul 26 11:45:57 foodrave volumio[14387]: } Jul 26 11:45:57 foodrave volumio[14387]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:45:57 foodrave volumio[14387]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:45:57 foodrave volumio[14387]: Stack trace: Jul 26 11:45:57 foodrave volumio[14387]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:45:57 foodrave volumio[14387]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:45:57 foodrave volumio[14387]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:45:57 foodrave volumio[14387]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:45:57 foodrave volumio[14387]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:45:57 foodrave volumio[14387]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Jul 26 11:45:57 foodrave volumio[14387]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Jul 26 11:45:57 foodrave volumio[14387]: at new Promise () Jul 26 11:45:57 foodrave volumio[14387]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Jul 26 11:45:57 foodrave volumio[14387]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Jul 26 11:45:57 foodrave volumio[14387]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:35 Jul 26 11:45:57 foodrave volumio[14387]: at Array.forEach () Jul 26 11:45:57 foodrave volumio[14387]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:24) Jul 26 11:45:57 foodrave volumio[14387]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:45:57 foodrave volumio[14387]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Jul 26 11:45:57 foodrave volumio[14387]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Jul 26 11:45:57 foodrave volumio[14387]: LoungeScreenDisconnected { Jul 26 11:45:57 foodrave volumio[14387]: AID: null, Jul 26 11:45:57 foodrave volumio[14387]: name: 'loungeScreenDisconnected', Jul 26 11:45:57 foodrave volumio[14387]: payload: {} Jul 26 11:45:57 foodrave volumio[14387]: } Jul 26 11:45:57 foodrave volumio[14387]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:45:57 foodrave volumio[14387]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:45:57 foodrave volumio[14387]: Stack trace: Jul 26 11:45:57 foodrave volumio[14387]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:45:57 foodrave volumio[14387]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:45:57 foodrave volumio[14387]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:45:57 foodrave volumio[14387]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:45:57 foodrave volumio[14387]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:45:57 foodrave volumio[14387]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Jul 26 11:45:57 foodrave volumio[14387]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:45:57 foodrave volumio[14387]: error: [ytcr] Failed to start plugin: Jul 26 11:45:57 foodrave volumio[14387]: (AppError) Failed to start YouTubeApp Jul 26 11:45:57 foodrave volumio[14387]: --->(SessionError) (YouTube) Failed to establish session Jul 26 11:45:57 foodrave volumio[14387]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Jul 26 11:45:57 foodrave volumio[14387]: Error info: { Jul 26 11:45:57 foodrave volumio[14387]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Jul 26 11:45:57 foodrave volumio[14387]: } Jul 26 11:45:57 foodrave volumio[14387]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo EBUSY www.youtube.com Jul 26 11:45:57 foodrave volumio[14387]: Stack trace: Jul 26 11:45:57 foodrave volumio[14387]: AppError: Failed to start YouTubeApp Jul 26 11:45:57 foodrave volumio[14387]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Jul 26 11:45:57 foodrave volumio[14387]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:45:57 foodrave volumio[14387]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Jul 26 11:45:57 foodrave volumio[14387]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Jul 26 11:45:57 foodrave volumio[14387]: LoungeScreenDisconnected { Jul 26 11:45:57 foodrave volumio[14387]: AID: null, Jul 26 11:45:57 foodrave volumio[14387]: name: 'loungeScreenDisconnected', Jul 26 11:45:57 foodrave volumio[14387]: payload: {} Jul 26 11:45:57 foodrave volumio[14387]: } Jul 26 11:45:57 foodrave volumio[14387]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:45:57 foodrave volumio[14387]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:45:57 foodrave volumio[14387]: Stack trace: Jul 26 11:45:57 foodrave volumio[14387]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:45:57 foodrave volumio[14387]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:45:57 foodrave volumio[14387]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:45:57 foodrave volumio[14387]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:45:57 foodrave volumio[14387]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:45:57 foodrave volumio[14387]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Jul 26 11:45:57 foodrave volumio[14387]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:45:57 foodrave volumio[14387]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Jul 26 11:45:57 foodrave volumio[14387]: info: Spotify config file written Jul 26 11:45:57 foodrave volumio[14387]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Jul 26 11:45:57 foodrave volumio[14387]: LoungeScreenDisconnected { Jul 26 11:45:57 foodrave volumio[14387]: AID: null, Jul 26 11:45:57 foodrave volumio[14387]: name: 'loungeScreenDisconnected', Jul 26 11:45:57 foodrave volumio[14387]: payload: {} Jul 26 11:45:57 foodrave volumio[14387]: } Jul 26 11:45:57 foodrave volumio[14387]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:45:57 foodrave volumio[14387]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:45:57 foodrave volumio[14387]: Stack trace: Jul 26 11:45:57 foodrave volumio[14387]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:45:57 foodrave volumio[14387]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:45:57 foodrave volumio[14387]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:45:57 foodrave volumio[14387]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:45:57 foodrave volumio[14387]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:45:57 foodrave volumio[14387]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Jul 26 11:45:57 foodrave volumio[14387]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:45:57 foodrave volumio[14387]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Jul 26 11:45:57 foodrave volumio[14387]: (node:14387) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Jul 26 11:45:57 foodrave volumio[14387]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Jul 26 11:45:57 foodrave volumio[14387]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:45:57 foodrave volumio[14387]: (Use `node --trace-warnings ...` to show where the warning was created) Jul 26 11:45:57 foodrave volumio[14387]: (node:14387) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 7) Jul 26 11:45:57 foodrave volumio[14387]: (node:14387) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Jul 26 11:45:57 foodrave volumio[14387]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Jul 26 11:45:57 foodrave volumio[14387]: LoungeScreenDisconnected { Jul 26 11:45:57 foodrave volumio[14387]: AID: null, Jul 26 11:45:57 foodrave volumio[14387]: name: 'loungeScreenDisconnected', Jul 26 11:45:57 foodrave volumio[14387]: payload: {} Jul 26 11:45:57 foodrave volumio[14387]: } Jul 26 11:45:57 foodrave volumio[14387]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:45:57 foodrave volumio[14387]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:45:57 foodrave volumio[14387]: Stack trace: Jul 26 11:45:57 foodrave volumio[14387]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:45:57 foodrave volumio[14387]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:45:57 foodrave volumio[14387]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:45:57 foodrave volumio[14387]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:45:57 foodrave volumio[14387]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:45:57 foodrave volumio[14387]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Jul 26 11:45:57 foodrave volumio[14387]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:45:57 foodrave sudo[14543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 26 11:45:57 foodrave volumio[14387]: (node:14387) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Jul 26 11:45:57 foodrave volumio[14387]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Jul 26 11:45:57 foodrave volumio[14387]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:45:57 foodrave volumio[14387]: (node:14387) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 9) Jul 26 11:45:57 foodrave sudo[14543]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:45:57 foodrave volumio[14387]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Jul 26 11:45:57 foodrave volumio[14387]: LoungeScreenDisconnected { Jul 26 11:45:57 foodrave volumio[14387]: AID: null, Jul 26 11:45:57 foodrave volumio[14387]: name: 'loungeScreenDisconnected', Jul 26 11:45:57 foodrave volumio[14387]: payload: {} Jul 26 11:45:57 foodrave volumio[14387]: } Jul 26 11:45:57 foodrave volumio[14387]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:45:57 foodrave volumio[14387]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:45:57 foodrave volumio[14387]: Stack trace: Jul 26 11:45:57 foodrave volumio[14387]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:45:57 foodrave volumio[14387]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:45:57 foodrave volumio[14387]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:45:57 foodrave volumio[14387]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:45:57 foodrave volumio[14387]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:45:57 foodrave volumio[14387]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Jul 26 11:45:57 foodrave volumio[14387]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:45:57 foodrave volumio[14387]: (node:14387) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Jul 26 11:45:57 foodrave volumio[14387]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Jul 26 11:45:57 foodrave volumio[14387]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:45:57 foodrave volumio[14387]: (node:14387) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 11) Jul 26 11:45:57 foodrave volumio[14387]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Jul 26 11:45:57 foodrave volumio[14387]: LoungeScreenDisconnected { Jul 26 11:45:57 foodrave volumio[14387]: AID: null, Jul 26 11:45:57 foodrave volumio[14387]: name: 'loungeScreenDisconnected', Jul 26 11:45:57 foodrave volumio[14387]: payload: {} Jul 26 11:45:57 foodrave volumio[14387]: } Jul 26 11:45:57 foodrave volumio[14387]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:45:57 foodrave volumio[14387]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:45:57 foodrave volumio[14387]: Stack trace: Jul 26 11:45:57 foodrave volumio[14387]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:45:57 foodrave volumio[14387]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:45:57 foodrave volumio[14387]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:45:57 foodrave volumio[14387]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:45:57 foodrave volumio[14387]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:45:57 foodrave volumio[14387]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Jul 26 11:45:57 foodrave volumio[14387]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:45:57 foodrave volumio[14387]: (node:14387) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Jul 26 11:45:57 foodrave volumio[14387]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Jul 26 11:45:57 foodrave volumio[14387]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:45:57 foodrave volumio[14387]: (node:14387) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 13) Jul 26 11:45:57 foodrave systemd[1]: Stopping go-librespot Daemon... Jul 26 11:45:57 foodrave systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Jul 26 11:45:57 foodrave systemd[1]: go-librespot-daemon.service: Succeeded. Jul 26 11:45:57 foodrave systemd[1]: Stopped go-librespot Daemon. Jul 26 11:45:57 foodrave volumio[14387]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jul 26 11:45:57 foodrave volumio[14387]: info: VolumeController:: Volume=96 Mute =false Jul 26 11:45:57 foodrave volumio[14387]: info: CoreCommandRouter::volumioGetState Jul 26 11:45:57 foodrave volumio[14387]: info: CorePlayQueue::getTrack 0 Jul 26 11:45:57 foodrave volumio[14387]: info: CoreStateMachine::pushState Jul 26 11:45:57 foodrave volumio[14387]: info: CorePlayQueue::getTrack 0 Jul 26 11:45:57 foodrave volumio[14387]: info: CoreCommandRouter::volumioPushState Jul 26 11:45:57 foodrave volumio[14387]: info: [squeezelite_mc] Server discovery started Jul 26 11:45:57 foodrave volumio[14387]: info: [squeezelite_mc] Player finder started Jul 26 11:45:57 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:45:57 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:45:57 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:45:57 foodrave volumio[14387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 11:45:57 foodrave volumio[14387]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jul 26 11:45:57 foodrave go-librespot[14545]: Librespot-go daemon starting... Jul 26 11:45:57 foodrave systemd[1]: Started go-librespot Daemon. Jul 26 11:45:57 foodrave sudo[14543]: pam_unix(sudo:session): session closed for user root Jul 26 11:45:57 foodrave go-librespot[14545]: time="2024-07-26T11:45:57+02:00" level=info msg="generated new device id: 0741af10820b839cd4a9fc79be00cb9cec0d67c4" Jul 26 11:45:57 foodrave volumio[14387]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 11:45:57 foodrave go-librespot[14545]: time="2024-07-26T11:45:57+02:00" level=debug msg="stored credentials found for od5o0ple11xtfxn9xt6s0gjqk" Jul 26 11:45:57 foodrave volumio[14387]: Error: send ENETUNREACH 255.255.255.255:3483 Jul 26 11:45:57 foodrave volumio[14387]: at doSend (dgram.js:692:16) Jul 26 11:45:57 foodrave volumio[14387]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jul 26 11:45:57 foodrave volumio[14387]: at afterDns (dgram.js:638:5) Jul 26 11:45:57 foodrave volumio[14387]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jul 26 11:45:57 foodrave volumio[14387]: errno: -101, Jul 26 11:45:57 foodrave volumio[14387]: code: 'ENETUNREACH', Jul 26 11:45:57 foodrave volumio[14387]: syscall: 'send', Jul 26 11:45:57 foodrave volumio[14387]: address: '255.255.255.255', Jul 26 11:45:57 foodrave volumio[14387]: port: 3483 Jul 26 11:45:57 foodrave volumio[14387]: } Jul 26 11:45:57 foodrave volumio[14387]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 11:45:57 foodrave go-librespot[14545]: time="2024-07-26T11:45:57+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Jul 26 11:45:57 foodrave systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 26 11:45:57 foodrave systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 26 11:45:57 foodrave kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jul 26 11:45:58 foodrave mpd[14497]: Jul 26 11:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 26 11:45:58 foodrave systemd[1]: Started Music Player Daemon. Jul 26 11:45:58 foodrave sudo[14474]: pam_unix(sudo:session): session closed for user root Jul 26 11:45:58 foodrave sudo[14484]: pam_unix(sudo:session): session closed for user root Jul 26 11:45:58 foodrave sudo[14566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-26 11:44 Jul 26 11:45:58 foodrave sudo[14566]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:45:58 foodrave sudo[14566]: pam_unix(sudo:session): session closed for user root Jul 26 11:45:58 foodrave systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 26 11:45:58 foodrave systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 26 11:45:58 foodrave systemd[1]: Started dynamicswap service. Jul 26 11:45:58 foodrave systemd[1]: dynamicswap.service: Succeeded. Jul 26 11:45:59 foodrave systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 26 11:45:59 foodrave systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Jul 26 11:45:59 foodrave systemd[1]: Stopped Volumio Backend Module. Jul 26 11:45:59 foodrave systemd[1]: Started Volumio Backend Module. Jul 26 11:45:59 foodrave systemd[1]: Started dynamicswap service. Jul 26 11:45:59 foodrave systemd[1]: dynamicswap.service: Succeeded. Jul 26 11:46:00 foodrave volumio-remote-updater[652]: [2024-07-26 11:46:00] [info] asio async_connect error: system:111 (Connection refused) Jul 26 11:46:00 foodrave volumio-remote-updater[652]: [2024-07-26 11:46:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 26 11:46:00 foodrave volumio-remote-updater[652]: [2024-07-26 11:46:00] [error] handle_connect error: Underlying Transport Error Jul 26 11:46:01 foodrave volumio[14582]: info: ------------------------------------------- Jul 26 11:46:01 foodrave volumio[14582]: info: ----- Volumio3 ---- Jul 26 11:46:01 foodrave volumio[14582]: info: ------------------------------------------- Jul 26 11:46:01 foodrave volumio[14582]: info: ----- System startup ---- Jul 26 11:46:01 foodrave volumio[14582]: info: ------------------------------------------- Jul 26 11:46:01 foodrave systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 26 11:46:01 foodrave systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jul 26 11:46:01 foodrave systemd[1]: Stopped go-librespot Daemon. Jul 26 11:46:01 foodrave systemd[1]: Started go-librespot Daemon. Jul 26 11:46:01 foodrave go-librespot[14603]: Librespot-go daemon starting... Jul 26 11:46:01 foodrave go-librespot[14603]: time="2024-07-26T11:46:01+02:00" level=info msg="generated new device id: 3e5dc52b0392cf053145a255e5f0d8072c74075a" Jul 26 11:46:01 foodrave go-librespot[14603]: time="2024-07-26T11:46:01+02:00" level=debug msg="stored credentials found for od5o0ple11xtfxn9xt6s0gjqk" Jul 26 11:46:01 foodrave go-librespot[14603]: time="2024-07-26T11:46:01+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Jul 26 11:46:01 foodrave systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 26 11:46:01 foodrave systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 26 11:46:01 foodrave volumio[14582]: info: MYVOLUMIO Environment detected Jul 26 11:46:01 foodrave volumio[14582]: info: Plugin folders cleanup Jul 26 11:46:01 foodrave volumio[14582]: info: Scanning into folder /volumio/app/plugins/ Jul 26 11:46:01 foodrave volumio[14582]: info: Scanning category audio_interface Jul 26 11:46:01 foodrave volumio[14582]: info: Scanning category miscellanea Jul 26 11:46:01 foodrave volumio[14582]: info: Scanning category music_service Jul 26 11:46:01 foodrave volumio[14582]: info: Scanning category plugins.json Jul 26 11:46:01 foodrave volumio[14582]: info: Scanning category system_controller Jul 26 11:46:01 foodrave volumio[14582]: info: Scanning category user_interface Jul 26 11:46:01 foodrave volumio[14582]: info: Scanning into folder /data/plugins/ Jul 26 11:46:01 foodrave volumio[14582]: info: Scanning category music_service Jul 26 11:46:01 foodrave volumio[14582]: info: Plugin folders cleanup completed Jul 26 11:46:01 foodrave volumio[14582]: info: ------------------------------------------- Jul 26 11:46:01 foodrave volumio[14582]: info: ----- Core plugins startup ---- Jul 26 11:46:01 foodrave volumio[14582]: info: ------------------------------------------- Jul 26 11:46:01 foodrave volumio[14582]: info: Loading plugins from folder /volumio/app/plugins/ Jul 26 11:46:01 foodrave volumio[14582]: info: Adding plugin upnp to MyMusic Plugins Jul 26 11:46:01 foodrave volumio[14582]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 26 11:46:01 foodrave volumio[14582]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 26 11:46:01 foodrave volumio[14582]: info: Loading plugins from folder /data/plugins/ Jul 26 11:46:01 foodrave volumio[14582]: info: Loading plugin "system"... Jul 26 11:46:01 foodrave volumio[14582]: info: Loading plugin "appearance"... Jul 26 11:46:02 foodrave volumio[14582]: info: Loading plugin "network"... Jul 26 11:46:02 foodrave volumio[14582]: info: Refreshing Cached IP Addresses Jul 26 11:46:02 foodrave sudo[14616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 26 11:46:02 foodrave sudo[14616]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:02 foodrave sudo[14616]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:02 foodrave sudo[14618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 26 11:46:02 foodrave volumio[14582]: info: Loading plugin "services"... Jul 26 11:46:02 foodrave sudo[14618]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:02 foodrave volumio[14582]: info: Loading plugin "alsa_controller"... Jul 26 11:46:02 foodrave sudo[14618]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:02 foodrave sudo[14621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 26 11:46:03 foodrave sudo[14621]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:03 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 26 11:46:03 foodrave volumio[14582]: info: Loading plugin "wizard"... Jul 26 11:46:03 foodrave volumio[14582]: info: Loading plugin "networkfs"... Jul 26 11:46:03 foodrave volumio[14582]: info: Starting Udev Watcher for removable devices Jul 26 11:46:03 foodrave volumio[14582]: info: Ignoring mount for partition: boot Jul 26 11:46:03 foodrave volumio[14582]: info: Ignoring mount for partition: volumio Jul 26 11:46:03 foodrave volumio[14582]: info: Ignoring mount for partition: volumio_data Jul 26 11:46:03 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 26 11:46:03 foodrave volumio[14582]: info: Loading plugin "volumio_command_line_client"... Jul 26 11:46:03 foodrave volumio[14582]: info: Loading plugin "upnp"... Jul 26 11:46:03 foodrave volumio[14582]: info: [1721987163175] Starting Upmpd Daemon Jul 26 11:46:03 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 26 11:46:03 foodrave volumio[14582]: info: Loading plugin "my_music"... Jul 26 11:46:03 foodrave volumio[14582]: info: Loading plugin "mpd"... Jul 26 11:46:03 foodrave volumio[14582]: info: Loading plugin "upnp_browser"... Jul 26 11:46:03 foodrave sudo[14621]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:03 foodrave volumio[14582]: info: Loading plugin "alarm-clock"... Jul 26 11:46:04 foodrave volumio[14582]: info: Loading plugin "airplay_emulation"... Jul 26 11:46:04 foodrave volumio[14582]: info: Starting Shairport Sync Jul 26 11:46:04 foodrave volumio[14582]: info: Loading plugin "last_100"... Jul 26 11:46:04 foodrave volumio[14582]: info: Loading plugin "webradio"... Jul 26 11:46:04 foodrave volumio[14582]: info: Loading plugin "i2s_dacs"... Jul 26 11:46:04 foodrave volumio[14582]: info: Loading plugin "volumiodiscovery"... Jul 26 11:46:04 foodrave volumio[14582]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 26 11:46:04 foodrave volumio[14582]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 11:46:04 foodrave volumio[14582]: *** WARNING *** For more information see Jul 26 11:46:04 foodrave volumio[14582]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 26 11:46:04 foodrave node[14582]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 26 11:46:04 foodrave volumio[14582]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 11:46:04 foodrave volumio[14582]: *** WARNING *** For more information see Jul 26 11:46:04 foodrave node[14582]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 11:46:04 foodrave node[14582]: *** WARNING *** For more information see Jul 26 11:46:04 foodrave node[14582]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 26 11:46:04 foodrave node[14582]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 11:46:04 foodrave node[14582]: *** WARNING *** For more information see Jul 26 11:46:04 foodrave volumio[14582]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 26 11:46:04 foodrave volumio[14582]: info: Discovery: Started advertising with name: Foodrave Jul 26 11:46:04 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 26 11:46:04 foodrave volumio[14582]: info: Loading plugin "mixcloud"... Jul 26 11:46:04 foodrave systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 26 11:46:04 foodrave systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Jul 26 11:46:04 foodrave systemd[1]: Stopped go-librespot Daemon. Jul 26 11:46:04 foodrave systemd[1]: Started go-librespot Daemon. Jul 26 11:46:04 foodrave go-librespot[14630]: Librespot-go daemon starting... Jul 26 11:46:04 foodrave go-librespot[14630]: time="2024-07-26T11:46:04+02:00" level=info msg="generated new device id: 75ea58fc6d28c5f56f28079812063219fa01646a" Jul 26 11:46:04 foodrave go-librespot[14630]: time="2024-07-26T11:46:04+02:00" level=debug msg="stored credentials found for od5o0ple11xtfxn9xt6s0gjqk" Jul 26 11:46:04 foodrave go-librespot[14630]: time="2024-07-26T11:46:04+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Jul 26 11:46:04 foodrave systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 26 11:46:04 foodrave systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 26 11:46:04 foodrave volumio[14582]: info: Loading plugin "soundcloud"... Jul 26 11:46:05 foodrave volumio[14582]: info: Loading plugin "spop"... Jul 26 11:46:06 foodrave volumio[14582]: info: Loading plugin "squeezelite_mc"... Jul 26 11:46:07 foodrave volumio[14582]: info: Loading plugin "ytcr"... Jul 26 11:46:07 foodrave systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 26 11:46:07 foodrave systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Jul 26 11:46:07 foodrave systemd[1]: Stopped go-librespot Daemon. Jul 26 11:46:07 foodrave systemd[1]: Started go-librespot Daemon. Jul 26 11:46:07 foodrave go-librespot[14637]: Librespot-go daemon starting... Jul 26 11:46:07 foodrave go-librespot[14637]: time="2024-07-26T11:46:07+02:00" level=info msg="generated new device id: 311154801fd552f5045c06bc05ca812cc2c1bb85" Jul 26 11:46:07 foodrave go-librespot[14637]: time="2024-07-26T11:46:07+02:00" level=debug msg="stored credentials found for od5o0ple11xtfxn9xt6s0gjqk" Jul 26 11:46:07 foodrave go-librespot[14637]: time="2024-07-26T11:46:07+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Jul 26 11:46:07 foodrave systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 26 11:46:07 foodrave systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 26 11:46:09 foodrave volumio[14582]: info: Loading plugin "ytmusic"... Jul 26 11:46:10 foodrave volumio-remote-updater[652]: [2024-07-26 11:46:10] [connect] Successful connection Jul 26 11:46:10 foodrave volumio[14582]: info: Loading plugin "outputs"... Jul 26 11:46:10 foodrave volumio[14582]: info: Loading plugin "albumart"... Jul 26 11:46:10 foodrave volumio[14582]: info: Plugin example_plugin is not enabled Jul 26 11:46:10 foodrave volumio[14582]: info: Loading plugin "inputs"... Jul 26 11:46:10 foodrave volumio[14582]: info: Loading plugin "updater_comm"... Jul 26 11:46:10 foodrave volumio[14582]: info: Plugin mpdemulation is not enabled Jul 26 11:46:10 foodrave volumio[14582]: info: Loading plugin "rest_api"... Jul 26 11:46:10 foodrave volumio[14582]: info: Loading plugin "websocket"... Jul 26 11:46:10 foodrave volumio[14582]: info: Starting Socket.io Server version 2.3.0 Jul 26 11:46:10 foodrave volumio[14582]: info: Plugin lms is not enabled Jul 26 11:46:10 foodrave volumio[14582]: info: Loading i18n strings for locale de Jul 26 11:46:10 foodrave volumio[14582]: Updating browse sources language Jul 26 11:46:10 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 11:46:10 foodrave systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 26 11:46:10 foodrave systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Jul 26 11:46:10 foodrave systemd[1]: Stopped go-librespot Daemon. Jul 26 11:46:10 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 11:46:10 foodrave volumio[14582]: info: CoreCommandRouter::initPlayerControls Jul 26 11:46:10 foodrave systemd[1]: Started go-librespot Daemon. Jul 26 11:46:10 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:10 foodrave volumio[14582]: Forking 3 albumart workers Jul 26 11:46:10 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:10 foodrave go-librespot[14689]: Librespot-go daemon starting... Jul 26 11:46:10 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 11:46:10 foodrave volumio[14582]: Express server listening on port 3000 Jul 26 11:46:10 foodrave volumio[14582]: [Metrics] WebUI: 10s 489.24ms Jul 26 11:46:10 foodrave go-librespot[14689]: time="2024-07-26T11:46:10+02:00" level=info msg="generated new device id: 33910c1bece310d4dc127198df808435ffa51229" Jul 26 11:46:10 foodrave go-librespot[14689]: time="2024-07-26T11:46:10+02:00" level=debug msg="stored credentials found for od5o0ple11xtfxn9xt6s0gjqk" Jul 26 11:46:10 foodrave go-librespot[14689]: time="2024-07-26T11:46:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Jul 26 11:46:10 foodrave systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 26 11:46:10 foodrave systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 26 11:46:11 foodrave volumio[14582]: info: CoreStateMachine::resetVolumioState Jul 26 11:46:11 foodrave volumio[14582]: info: CoreStateMachine::getcurrentVolume Jul 26 11:46:11 foodrave volumio[14582]: info: CoreCommandRouter::volumioRetrievevolume Jul 26 11:46:11 foodrave volumio-remote-updater[652]: [2024-07-26 11:46:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1721987170 101 Jul 26 11:46:11 foodrave volumio[14582]: 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: 1 Jul 26 11:46:11 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 11:46:11 foodrave volumio[14582]: info: Reloading queue from file Jul 26 11:46:11 foodrave volumio[14582]: info: CoreStateMachine::setRepeat null single undefined Jul 26 11:46:11 foodrave volumio[14582]: info: CoreStateMachine::pushState Jul 26 11:46:11 foodrave volumio[14582]: info: CorePlayQueue::getTrack 0 Jul 26 11:46:11 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 11:46:11 foodrave volumio[14582]: info: CoreCommandRouter::volumioPushState Jul 26 11:46:11 foodrave volumio[14582]: info: CoreStateMachine::setRandom null Jul 26 11:46:11 foodrave volumio[14582]: info: CoreStateMachine::pushState Jul 26 11:46:11 foodrave volumio[14582]: info: CorePlayQueue::getTrack 0 Jul 26 11:46:11 foodrave volumio[14582]: info: CoreCommandRouter::volumioPushState Jul 26 11:46:11 foodrave volumio[14582]: info: Setting Device type: Raspberry PI Jul 26 11:46:11 foodrave volumio[14582]: info: Completed loading Core Plugins Jul 26 11:46:11 foodrave volumio[14582]: info: Preparing to generate the ALSA configuration file Jul 26 11:46:11 foodrave volumio[14582]: info: VolumeController:: Volume=96 Mute =false Jul 26 11:46:11 foodrave volumio[14582]: info: CoreStateMachine::pushState Jul 26 11:46:11 foodrave volumio[14582]: info: CorePlayQueue::getTrack 0 Jul 26 11:46:11 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 11:46:11 foodrave volumio[14582]: info: CoreCommandRouter::volumioPushState Jul 26 11:46:11 foodrave volumio[14582]: info: CoreStateMachine::updateTrackBlock Jul 26 11:46:11 foodrave volumio[14582]: info: CorePlayQueue::getTrackBlock Jul 26 11:46:11 foodrave volumio[14582]: info: CoreCommandRouter::volumioRetrievevolume Jul 26 11:46:11 foodrave volumio[14582]: info: Asound.conf file unchanged, so no further update is needed Jul 26 11:46:11 foodrave volumio[14582]: info: Output device has changed, restarting MPD Jul 26 11:46:11 foodrave sudo[14729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 26 11:46:11 foodrave sudo[14729]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:11 foodrave sudo[14729]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:11 foodrave volumio[14582]: info: Output device has changed, restarting Shairport Sync Jul 26 11:46:11 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:11 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 11:46:11 foodrave sudo[14732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 26 11:46:11 foodrave sudo[14732]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:11 foodrave volumio[14582]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 26 11:46:11 foodrave volumio[14582]: info: ___________ START PLUGINS ___________ Jul 26 11:46:11 foodrave volumio[14582]: info: ControllerMpd::onStart: Initializing MPD Jul 26 11:46:11 foodrave volumio[14582]: info: Creating MPD Configuration file Jul 26 11:46:11 foodrave systemd[1]: Stopping Music Player Daemon... Jul 26 11:46:11 foodrave systemd[1]: mpd.service: Succeeded. Jul 26 11:46:11 foodrave systemd[1]: Stopped Music Player Daemon. Jul 26 11:46:12 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 11:46:12 foodrave volumio[14582]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 11:46:12 foodrave volumio[14582]: info: [1721987172004] CoreMusicLibrary::Adding element Medienserver Jul 26 11:46:12 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 11:46:12 foodrave systemd[1]: Starting Music Player Daemon... Jul 26 11:46:12 foodrave sudo[14738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 26 11:46:12 foodrave sudo[14738]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:12 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:12 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 11:46:12 foodrave sudo[14741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 26 11:46:12 foodrave sudo[14741]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:12 foodrave sudo[14738]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:12 foodrave systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 26 11:46:12 foodrave systemd[1]: mpd.service: Succeeded. Jul 26 11:46:12 foodrave systemd[1]: Stopped Music Player Daemon. Jul 26 11:46:12 foodrave systemd[1]: Starting Music Player Daemon... Jul 26 11:46:12 foodrave volumio[14582]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 26 11:46:12 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:12 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 11:46:12 foodrave volumio[14582]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 26 11:46:12 foodrave volumio[14582]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 11:46:12 foodrave volumio[14582]: info: [1721987172220] CoreMusicLibrary::Adding element Last_100 Jul 26 11:46:12 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 11:46:12 foodrave volumio[14582]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 11:46:12 foodrave volumio[14582]: info: [1721987172225] CoreMusicLibrary::Adding element Webradio Jul 26 11:46:12 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 11:46:12 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 26 11:46:12 foodrave volumio[14582]: info: Initializing BBC Radios Jul 26 11:46:12 foodrave volumio[14582]: Starting albumart workers Jul 26 11:46:12 foodrave sudo[14747]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 26 11:46:12 foodrave sudo[14747]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:12 foodrave sudo[14747]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:12 foodrave volumio[14582]: Starting albumart workers Jul 26 11:46:12 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 26 11:46:12 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 11:46:12 foodrave volumio[14582]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 11:46:12 foodrave volumio[14582]: info: [1721987172376] CoreMusicLibrary::Adding element Mixcloud Jul 26 11:46:12 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 11:46:12 foodrave volumio[14582]: Cannot find translation for source Mixcloud Jul 26 11:46:12 foodrave volumio[14582]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 11:46:12 foodrave volumio[14582]: info: [1721987172394] CoreMusicLibrary::Adding element SoundCloud Jul 26 11:46:12 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 11:46:12 foodrave volumio[14582]: Cannot find translation for source Mixcloud Jul 26 11:46:12 foodrave volumio[14582]: Cannot find translation for source SoundCloud Jul 26 11:46:12 foodrave volumio[14582]: info: Creating Spotify config file Jul 26 11:46:12 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:12 foodrave volumio[14582]: Starting albumart workers Jul 26 11:46:12 foodrave volumio[14582]: info: [squeezelite_mc] Starting proxy server... Jul 26 11:46:12 foodrave volumio[14582]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 11:46:12 foodrave volumio[14582]: info: [1721987172740] CoreMusicLibrary::Adding element YouTube Music Jul 26 11:46:12 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 11:46:12 foodrave volumio[14582]: Cannot find translation for source Mixcloud Jul 26 11:46:12 foodrave volumio[14582]: Cannot find translation for source SoundCloud Jul 26 11:46:12 foodrave volumio[14582]: Cannot find translation for source YouTube Music Jul 26 11:46:12 foodrave volumio[14582]: info: Volumio Calling Home Jul 26 11:46:12 foodrave volumio[14582]: info: [squeezelite_mc] Proxy server started on port 45037 Jul 26 11:46:12 foodrave volumio[14582]: info: CoreCommandRouter::volumioRetrievevolume Jul 26 11:46:13 foodrave volumio[14582]: Unhandled rejection Error: No sockets available, cannot start. Jul 26 11:46:13 foodrave volumio[14582]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 26 11:46:13 foodrave volumio[14582]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 26 11:46:13 foodrave volumio[14582]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 26 11:46:13 foodrave volumio[14582]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 26 11:46:13 foodrave volumio[14582]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 26 11:46:13 foodrave volumio[14582]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 26 11:46:13 foodrave volumio[14582]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 26 11:46:13 foodrave volumio[14582]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 26 11:46:13 foodrave volumio[14582]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 26 11:46:13 foodrave volumio[14582]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 26 11:46:13 foodrave volumio[14582]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 26 11:46:13 foodrave volumio[14582]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 26 11:46:13 foodrave volumio[14582]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 26 11:46:13 foodrave volumio[14582]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 26 11:46:13 foodrave volumio[14582]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 26 11:46:13 foodrave volumio[14582]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 26 11:46:13 foodrave volumio[14582]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Jul 26 11:46:13 foodrave volumio[14582]: error: [yt-cast-receiver] (YouTube) Failed to obtain lounge token with screen Id from stored MDX context (9eua57nd8i7u87588ibv6nnnnb): Jul 26 11:46:13 foodrave volumio[14582]: (ConnectionError) (YouTube) Connection error in getting lounge token Jul 26 11:46:13 foodrave volumio[14582]: Error info: { Jul 26 11:46:13 foodrave volumio[14582]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Jul 26 11:46:13 foodrave volumio[14582]: } Jul 26 11:46:13 foodrave volumio[14582]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo EBUSY www.youtube.com Jul 26 11:46:13 foodrave volumio[14582]: Stack trace: Jul 26 11:46:13 foodrave volumio[14582]: ConnectionError: (YouTube) Connection error in getting lounge token Jul 26 11:46:13 foodrave volumio[14582]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave volumio[14582]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Jul 26 11:46:13 foodrave volumio[14582]: at async Promise.all (index 0) Jul 26 11:46:13 foodrave volumio[14582]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Jul 26 11:46:13 foodrave volumio[14582]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Jul 26 11:46:13 foodrave volumio[14582]: Going to generate fresh screen Id and try again... Jul 26 11:46:13 foodrave volumio[14582]: error: [yt-cast-receiver] (YouTube Music) Failed to obtain lounge token with screen Id from stored MDX context (4iag2ofhu256b892n9hu816u72): Jul 26 11:46:13 foodrave volumio[14582]: (ConnectionError) (YouTube Music) Connection error in getting lounge token Jul 26 11:46:13 foodrave volumio[14582]: Error info: { Jul 26 11:46:13 foodrave volumio[14582]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Jul 26 11:46:13 foodrave volumio[14582]: } Jul 26 11:46:13 foodrave volumio[14582]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo EBUSY www.youtube.com Jul 26 11:46:13 foodrave volumio[14582]: Stack trace: Jul 26 11:46:13 foodrave volumio[14582]: ConnectionError: (YouTube Music) Connection error in getting lounge token Jul 26 11:46:13 foodrave volumio[14582]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave volumio[14582]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Jul 26 11:46:13 foodrave volumio[14582]: at async Promise.all (index 1) Jul 26 11:46:13 foodrave volumio[14582]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Jul 26 11:46:13 foodrave volumio[14582]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Jul 26 11:46:13 foodrave volumio[14582]: Going to generate fresh screen Id and try again... Jul 26 11:46:13 foodrave volumio[14582]: info: MPD Permissions set Jul 26 11:46:13 foodrave volumio[14582]: info: MPD Permissions set Jul 26 11:46:13 foodrave volumio[14582]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Jul 26 11:46:13 foodrave volumio[14582]: LoungeScreenDisconnected { Jul 26 11:46:13 foodrave volumio[14582]: AID: null, Jul 26 11:46:13 foodrave volumio[14582]: name: 'loungeScreenDisconnected', Jul 26 11:46:13 foodrave volumio[14582]: payload: {} Jul 26 11:46:13 foodrave volumio[14582]: } Jul 26 11:46:13 foodrave volumio[14582]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:46:13 foodrave volumio[14582]: Stack trace: Jul 26 11:46:13 foodrave volumio[14582]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:46:13 foodrave volumio[14582]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:46:13 foodrave volumio[14582]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:46:13 foodrave volumio[14582]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:46:13 foodrave volumio[14582]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Jul 26 11:46:13 foodrave volumio[14582]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Jul 26 11:46:13 foodrave volumio[14582]: at new Promise () Jul 26 11:46:13 foodrave volumio[14582]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Jul 26 11:46:13 foodrave volumio[14582]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Jul 26 11:46:13 foodrave volumio[14582]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:207:26) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave volumio[14582]: at async Promise.all (index 0) Jul 26 11:46:13 foodrave volumio[14582]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Jul 26 11:46:13 foodrave volumio[14582]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Jul 26 11:46:13 foodrave sudo[14777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 26 11:46:13 foodrave sudo[14777]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:13 foodrave volumio[14582]: error: [yt-cast-receiver] Failed to start receiver: Jul 26 11:46:13 foodrave volumio[14582]: (AppError) Failed to start YouTubeApp Jul 26 11:46:13 foodrave volumio[14582]: --->(SessionError) (YouTube) Failed to establish session Jul 26 11:46:13 foodrave volumio[14582]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Jul 26 11:46:13 foodrave volumio[14582]: Error info: { Jul 26 11:46:13 foodrave volumio[14582]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Jul 26 11:46:13 foodrave volumio[14582]: } Jul 26 11:46:13 foodrave volumio[14582]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo EBUSY www.youtube.com Jul 26 11:46:13 foodrave volumio[14582]: Stack trace: Jul 26 11:46:13 foodrave volumio[14582]: AppError: Failed to start YouTubeApp Jul 26 11:46:13 foodrave volumio[14582]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave volumio[14582]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Jul 26 11:46:13 foodrave volumio[14582]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Jul 26 11:46:13 foodrave volumio[14582]: LoungeScreenDisconnected { Jul 26 11:46:13 foodrave volumio[14582]: AID: null, Jul 26 11:46:13 foodrave volumio[14582]: name: 'loungeScreenDisconnected', Jul 26 11:46:13 foodrave volumio[14582]: payload: {} Jul 26 11:46:13 foodrave volumio[14582]: } Jul 26 11:46:13 foodrave volumio[14582]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:46:13 foodrave volumio[14582]: Stack trace: Jul 26 11:46:13 foodrave volumio[14582]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:46:13 foodrave volumio[14582]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:46:13 foodrave volumio[14582]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:46:13 foodrave volumio[14582]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:46:13 foodrave volumio[14582]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Jul 26 11:46:13 foodrave volumio[14582]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Jul 26 11:46:13 foodrave volumio[14582]: at new Promise () Jul 26 11:46:13 foodrave volumio[14582]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Jul 26 11:46:13 foodrave volumio[14582]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Jul 26 11:46:13 foodrave volumio[14582]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:35 Jul 26 11:46:13 foodrave volumio[14582]: at Array.forEach () Jul 26 11:46:13 foodrave volumio[14582]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:24) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave volumio[14582]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Jul 26 11:46:13 foodrave sudo[14777]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:13 foodrave volumio[14582]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Jul 26 11:46:13 foodrave volumio[14582]: LoungeScreenDisconnected { Jul 26 11:46:13 foodrave volumio[14582]: AID: null, Jul 26 11:46:13 foodrave volumio[14582]: name: 'loungeScreenDisconnected', Jul 26 11:46:13 foodrave volumio[14582]: payload: {} Jul 26 11:46:13 foodrave volumio[14582]: } Jul 26 11:46:13 foodrave volumio[14582]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:46:13 foodrave volumio[14582]: Stack trace: Jul 26 11:46:13 foodrave volumio[14582]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:46:13 foodrave volumio[14582]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:46:13 foodrave volumio[14582]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:46:13 foodrave volumio[14582]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:46:13 foodrave volumio[14582]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave volumio[14582]: error: [ytcr] Failed to start plugin: Jul 26 11:46:13 foodrave volumio[14582]: (AppError) Failed to start YouTubeApp Jul 26 11:46:13 foodrave volumio[14582]: --->(SessionError) (YouTube) Failed to establish session Jul 26 11:46:13 foodrave volumio[14582]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Jul 26 11:46:13 foodrave volumio[14582]: Error info: { Jul 26 11:46:13 foodrave volumio[14582]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Jul 26 11:46:13 foodrave volumio[14582]: } Jul 26 11:46:13 foodrave volumio[14582]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo EBUSY www.youtube.com Jul 26 11:46:13 foodrave volumio[14582]: Stack trace: Jul 26 11:46:13 foodrave volumio[14582]: AppError: Failed to start YouTubeApp Jul 26 11:46:13 foodrave volumio[14582]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave volumio[14582]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Jul 26 11:46:13 foodrave sudo[14784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 26 11:46:13 foodrave volumio[14582]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Jul 26 11:46:13 foodrave volumio[14582]: LoungeScreenDisconnected { Jul 26 11:46:13 foodrave volumio[14582]: AID: null, Jul 26 11:46:13 foodrave volumio[14582]: name: 'loungeScreenDisconnected', Jul 26 11:46:13 foodrave volumio[14582]: payload: {} Jul 26 11:46:13 foodrave volumio[14582]: } Jul 26 11:46:13 foodrave volumio[14582]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:46:13 foodrave volumio[14582]: Stack trace: Jul 26 11:46:13 foodrave volumio[14582]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:46:13 foodrave volumio[14582]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:46:13 foodrave volumio[14582]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:46:13 foodrave volumio[14582]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:46:13 foodrave volumio[14582]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave volumio[14582]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Jul 26 11:46:13 foodrave volumio[14582]: info: VolumeController:: Volume=96 Mute =false Jul 26 11:46:13 foodrave sudo[14784]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::volumioGetState Jul 26 11:46:13 foodrave volumio[14582]: info: CorePlayQueue::getTrack 0 Jul 26 11:46:13 foodrave volumio[14582]: info: CoreStateMachine::pushState Jul 26 11:46:13 foodrave volumio[14582]: info: CorePlayQueue::getTrack 0 Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::volumioPushState Jul 26 11:46:13 foodrave sudo[14784]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:13 foodrave volumio[14582]: info: Spotify config file written Jul 26 11:46:13 foodrave volumio[14582]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Jul 26 11:46:13 foodrave volumio[14582]: LoungeScreenDisconnected { Jul 26 11:46:13 foodrave volumio[14582]: AID: null, Jul 26 11:46:13 foodrave volumio[14582]: name: 'loungeScreenDisconnected', Jul 26 11:46:13 foodrave volumio[14582]: payload: {} Jul 26 11:46:13 foodrave volumio[14582]: } Jul 26 11:46:13 foodrave volumio[14582]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:46:13 foodrave volumio[14582]: Stack trace: Jul 26 11:46:13 foodrave volumio[14582]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:46:13 foodrave volumio[14582]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:46:13 foodrave volumio[14582]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:46:13 foodrave volumio[14582]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:46:13 foodrave volumio[14582]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave volumio[14582]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Jul 26 11:46:13 foodrave volumio[14582]: (node:14582) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Jul 26 11:46:13 foodrave volumio[14582]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave volumio[14582]: (Use `node --trace-warnings ...` to show where the warning was created) Jul 26 11:46:13 foodrave volumio[14582]: (node:14582) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 7) Jul 26 11:46:13 foodrave volumio[14582]: (node:14582) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Jul 26 11:46:13 foodrave volumio[14582]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Jul 26 11:46:13 foodrave volumio[14582]: LoungeScreenDisconnected { Jul 26 11:46:13 foodrave volumio[14582]: AID: null, Jul 26 11:46:13 foodrave volumio[14582]: name: 'loungeScreenDisconnected', Jul 26 11:46:13 foodrave volumio[14582]: payload: {} Jul 26 11:46:13 foodrave volumio[14582]: } Jul 26 11:46:13 foodrave volumio[14582]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:46:13 foodrave volumio[14582]: Stack trace: Jul 26 11:46:13 foodrave volumio[14582]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:46:13 foodrave volumio[14582]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:46:13 foodrave volumio[14582]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:46:13 foodrave volumio[14582]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:46:13 foodrave volumio[14582]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave sudo[14792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 26 11:46:13 foodrave sudo[14792]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:13 foodrave volumio[14582]: (node:14582) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Jul 26 11:46:13 foodrave volumio[14582]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave volumio[14582]: (node:14582) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 9) Jul 26 11:46:13 foodrave volumio[14582]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Jul 26 11:46:13 foodrave volumio[14582]: LoungeScreenDisconnected { Jul 26 11:46:13 foodrave volumio[14582]: AID: null, Jul 26 11:46:13 foodrave volumio[14582]: name: 'loungeScreenDisconnected', Jul 26 11:46:13 foodrave volumio[14582]: payload: {} Jul 26 11:46:13 foodrave volumio[14582]: } Jul 26 11:46:13 foodrave volumio[14582]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:46:13 foodrave volumio[14582]: Stack trace: Jul 26 11:46:13 foodrave volumio[14582]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:46:13 foodrave volumio[14582]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:46:13 foodrave volumio[14582]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:46:13 foodrave volumio[14582]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:46:13 foodrave volumio[14582]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave volumio[14582]: (node:14582) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Jul 26 11:46:13 foodrave volumio[14582]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave volumio[14582]: (node:14582) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 11) Jul 26 11:46:13 foodrave volumio[14582]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Jul 26 11:46:13 foodrave volumio[14582]: LoungeScreenDisconnected { Jul 26 11:46:13 foodrave volumio[14582]: AID: null, Jul 26 11:46:13 foodrave volumio[14582]: name: 'loungeScreenDisconnected', Jul 26 11:46:13 foodrave volumio[14582]: payload: {} Jul 26 11:46:13 foodrave volumio[14582]: } Jul 26 11:46:13 foodrave volumio[14582]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:46:13 foodrave volumio[14582]: Stack trace: Jul 26 11:46:13 foodrave volumio[14582]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:46:13 foodrave volumio[14582]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:46:13 foodrave volumio[14582]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:46:13 foodrave volumio[14582]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:46:13 foodrave volumio[14582]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave systemd[1]: Stopped go-librespot Daemon. Jul 26 11:46:13 foodrave volumio[14582]: (node:14582) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Jul 26 11:46:13 foodrave volumio[14582]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave volumio[14582]: (node:14582) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 13) Jul 26 11:46:13 foodrave volumio[14582]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:13 foodrave systemd[1]: Started go-librespot Daemon. Jul 26 11:46:13 foodrave go-librespot[14794]: Librespot-go daemon starting... Jul 26 11:46:13 foodrave sudo[14792]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:13 foodrave go-librespot[14794]: time="2024-07-26T11:46:13+02:00" level=info msg="generated new device id: 9c230a260b4da57b01d05bfd5acb94f67c92f47d" Jul 26 11:46:13 foodrave go-librespot[14794]: time="2024-07-26T11:46:13+02:00" level=debug msg="stored credentials found for od5o0ple11xtfxn9xt6s0gjqk" Jul 26 11:46:13 foodrave go-librespot[14794]: time="2024-07-26T11:46:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Jul 26 11:46:13 foodrave systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 26 11:46:13 foodrave systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:13 foodrave volumio[14582]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Jul 26 11:46:13 foodrave volumio[14582]: LoungeScreenDisconnected { Jul 26 11:46:13 foodrave volumio[14582]: AID: null, Jul 26 11:46:13 foodrave volumio[14582]: name: 'loungeScreenDisconnected', Jul 26 11:46:13 foodrave volumio[14582]: payload: {} Jul 26 11:46:13 foodrave volumio[14582]: } Jul 26 11:46:13 foodrave volumio[14582]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:46:13 foodrave volumio[14582]: Stack trace: Jul 26 11:46:13 foodrave volumio[14582]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:46:13 foodrave volumio[14582]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:46:13 foodrave volumio[14582]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:46:13 foodrave volumio[14582]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:46:13 foodrave volumio[14582]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave volumio[14582]: (node:14582) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Jul 26 11:46:13 foodrave volumio[14582]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave volumio[14582]: (node:14582) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 15) Jul 26 11:46:13 foodrave volumio[14582]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Jul 26 11:46:13 foodrave volumio[14582]: LoungeScreenDisconnected { Jul 26 11:46:13 foodrave volumio[14582]: AID: null, Jul 26 11:46:13 foodrave volumio[14582]: name: 'loungeScreenDisconnected', Jul 26 11:46:13 foodrave volumio[14582]: payload: {} Jul 26 11:46:13 foodrave volumio[14582]: } Jul 26 11:46:13 foodrave volumio[14582]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:46:13 foodrave volumio[14582]: Stack trace: Jul 26 11:46:13 foodrave volumio[14582]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:46:13 foodrave volumio[14582]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:46:13 foodrave volumio[14582]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:46:13 foodrave volumio[14582]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:46:13 foodrave volumio[14582]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave volumio[14582]: (node:14582) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Jul 26 11:46:13 foodrave volumio[14582]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave volumio[14582]: (node:14582) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 17) Jul 26 11:46:13 foodrave volumio[14582]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Jul 26 11:46:13 foodrave volumio[14582]: LoungeScreenDisconnected { Jul 26 11:46:13 foodrave volumio[14582]: AID: null, Jul 26 11:46:13 foodrave volumio[14582]: name: 'loungeScreenDisconnected', Jul 26 11:46:13 foodrave volumio[14582]: payload: {} Jul 26 11:46:13 foodrave volumio[14582]: } Jul 26 11:46:13 foodrave volumio[14582]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:46:13 foodrave volumio[14582]: Stack trace: Jul 26 11:46:13 foodrave volumio[14582]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:46:13 foodrave volumio[14582]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:46:13 foodrave volumio[14582]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:46:13 foodrave volumio[14582]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:46:13 foodrave volumio[14582]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave volumio[14582]: (node:14582) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Jul 26 11:46:13 foodrave volumio[14582]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave volumio[14582]: (node:14582) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 19) Jul 26 11:46:13 foodrave volumio[14582]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Jul 26 11:46:13 foodrave volumio[14582]: LoungeScreenDisconnected { Jul 26 11:46:13 foodrave volumio[14582]: AID: null, Jul 26 11:46:13 foodrave volumio[14582]: name: 'loungeScreenDisconnected', Jul 26 11:46:13 foodrave volumio[14582]: payload: {} Jul 26 11:46:13 foodrave volumio[14582]: } Jul 26 11:46:13 foodrave volumio[14582]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:46:13 foodrave volumio[14582]: Stack trace: Jul 26 11:46:13 foodrave volumio[14582]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:46:13 foodrave volumio[14582]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:46:13 foodrave volumio[14582]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:46:13 foodrave volumio[14582]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:46:13 foodrave volumio[14582]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:46:13 foodrave volumio[14582]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave volumio[14582]: (node:14582) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Jul 26 11:46:13 foodrave volumio[14582]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:13 foodrave volumio[14582]: (node:14582) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 21) Jul 26 11:46:13 foodrave volumio[14582]: info: VolumeController:: Volume=96 Mute =false Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::volumioGetState Jul 26 11:46:13 foodrave volumio[14582]: info: CorePlayQueue::getTrack 0 Jul 26 11:46:13 foodrave volumio[14582]: info: CoreStateMachine::pushState Jul 26 11:46:13 foodrave volumio[14582]: info: CorePlayQueue::getTrack 0 Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::volumioPushState Jul 26 11:46:13 foodrave volumio[14582]: info: [squeezelite_mc] Server discovery started Jul 26 11:46:13 foodrave volumio[14582]: info: [squeezelite_mc] Player finder started Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:13 foodrave volumio[14582]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 11:46:13 foodrave volumio[14582]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jul 26 11:46:13 foodrave volumio[14582]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 11:46:13 foodrave volumio[14582]: Error: send ENETUNREACH 255.255.255.255:3483 Jul 26 11:46:13 foodrave volumio[14582]: at doSend (dgram.js:692:16) Jul 26 11:46:13 foodrave volumio[14582]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jul 26 11:46:13 foodrave volumio[14582]: at afterDns (dgram.js:638:5) Jul 26 11:46:13 foodrave volumio[14582]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jul 26 11:46:13 foodrave volumio[14582]: errno: -101, Jul 26 11:46:13 foodrave volumio[14582]: code: 'ENETUNREACH', Jul 26 11:46:13 foodrave volumio[14582]: syscall: 'send', Jul 26 11:46:13 foodrave volumio[14582]: address: '255.255.255.255', Jul 26 11:46:13 foodrave volumio[14582]: port: 3483 Jul 26 11:46:13 foodrave volumio[14582]: } Jul 26 11:46:13 foodrave volumio[14582]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 11:46:13 foodrave kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jul 26 11:46:14 foodrave mpd[14755]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 26 11:46:14 foodrave mpd[14755]: output: No 'audio_output' defined in config file Jul 26 11:46:14 foodrave mpd[14755]: output: Successfully detected a sndio audio device Jul 26 11:46:14 foodrave mpd[14755]: zeroconf: No global port, disabling zeroconf Jul 26 11:46:14 foodrave systemd[1]: Started Music Player Daemon. Jul 26 11:46:14 foodrave sudo[14732]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:14 foodrave sudo[14741]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:14 foodrave sudo[14830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-26 11:45 Jul 26 11:46:14 foodrave sudo[14830]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:14 foodrave sudo[14830]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:14 foodrave volumio-remote-updater[652]: [2024-07-26 11:46:14] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 26 11:46:14 foodrave volumio-remote-updater[652]: [2024-07-26 11:46:14] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 26 11:46:14 foodrave systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 26 11:46:14 foodrave systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 26 11:46:14 foodrave systemd[1]: Started dynamicswap service. Jul 26 11:46:14 foodrave systemd[1]: dynamicswap.service: Succeeded. Jul 26 11:46:15 foodrave systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 26 11:46:15 foodrave systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Jul 26 11:46:15 foodrave systemd[1]: Started dynamicswap service. Jul 26 11:46:15 foodrave systemd[1]: Stopped Volumio Backend Module. Jul 26 11:46:15 foodrave systemd[1]: Started Volumio Backend Module. Jul 26 11:46:15 foodrave systemd[1]: dynamicswap.service: Succeeded. Jul 26 11:46:15 foodrave nmbd[758]: [2024/07/26 11:46:15.510647, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces) Jul 26 11:46:15 foodrave nmbd[758]: reload_interfaces: No subnets to listen to. Waiting.. Jul 26 11:46:16 foodrave systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 26 11:46:16 foodrave systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Jul 26 11:46:16 foodrave systemd[1]: Stopped go-librespot Daemon. Jul 26 11:46:16 foodrave systemd[1]: Started go-librespot Daemon. Jul 26 11:46:16 foodrave go-librespot[14866]: Librespot-go daemon starting... Jul 26 11:46:16 foodrave go-librespot[14866]: time="2024-07-26T11:46:16+02:00" level=info msg="generated new device id: 66b72b53d3cb6689817f579f3199183fe23154cf" Jul 26 11:46:16 foodrave go-librespot[14866]: time="2024-07-26T11:46:16+02:00" level=debug msg="stored credentials found for od5o0ple11xtfxn9xt6s0gjqk" Jul 26 11:46:16 foodrave go-librespot[14866]: time="2024-07-26T11:46:16+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Jul 26 11:46:16 foodrave systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 26 11:46:16 foodrave systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 26 11:46:17 foodrave volumio[14845]: info: ------------------------------------------- Jul 26 11:46:17 foodrave volumio[14845]: info: ----- Volumio3 ---- Jul 26 11:46:17 foodrave volumio[14845]: info: ------------------------------------------- Jul 26 11:46:17 foodrave volumio[14845]: info: ----- System startup ---- Jul 26 11:46:17 foodrave volumio[14845]: info: ------------------------------------------- Jul 26 11:46:17 foodrave volumio[14845]: info: MYVOLUMIO Environment detected Jul 26 11:46:17 foodrave volumio[14845]: info: Plugin folders cleanup Jul 26 11:46:17 foodrave volumio[14845]: info: Scanning into folder /volumio/app/plugins/ Jul 26 11:46:17 foodrave volumio[14845]: info: Scanning category audio_interface Jul 26 11:46:17 foodrave volumio[14845]: info: Scanning category miscellanea Jul 26 11:46:17 foodrave volumio[14845]: info: Scanning category music_service Jul 26 11:46:17 foodrave volumio[14845]: info: Scanning category plugins.json Jul 26 11:46:17 foodrave volumio[14845]: info: Scanning category system_controller Jul 26 11:46:17 foodrave volumio[14845]: info: Scanning category user_interface Jul 26 11:46:17 foodrave volumio[14845]: info: Scanning into folder /data/plugins/ Jul 26 11:46:17 foodrave volumio[14845]: info: Scanning category music_service Jul 26 11:46:17 foodrave volumio[14845]: info: Plugin folders cleanup completed Jul 26 11:46:17 foodrave volumio[14845]: info: ------------------------------------------- Jul 26 11:46:17 foodrave volumio[14845]: info: ----- Core plugins startup ---- Jul 26 11:46:17 foodrave volumio[14845]: info: ------------------------------------------- Jul 26 11:46:17 foodrave volumio[14845]: info: Loading plugins from folder /volumio/app/plugins/ Jul 26 11:46:17 foodrave volumio[14845]: info: Adding plugin upnp to MyMusic Plugins Jul 26 11:46:17 foodrave volumio[14845]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 26 11:46:17 foodrave volumio[14845]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 26 11:46:17 foodrave volumio[14845]: info: Loading plugins from folder /data/plugins/ Jul 26 11:46:17 foodrave volumio[14845]: info: Loading plugin "system"... Jul 26 11:46:17 foodrave volumio[14845]: info: Loading plugin "appearance"... Jul 26 11:46:18 foodrave volumio[14845]: info: Loading plugin "network"... Jul 26 11:46:18 foodrave volumio[14845]: info: Refreshing Cached IP Addresses Jul 26 11:46:18 foodrave sudo[14880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 26 11:46:18 foodrave sudo[14880]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:18 foodrave sudo[14880]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:19 foodrave sudo[14882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 26 11:46:19 foodrave volumio[14845]: info: Loading plugin "services"... Jul 26 11:46:19 foodrave sudo[14882]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:19 foodrave volumio[14845]: info: Loading plugin "alsa_controller"... Jul 26 11:46:19 foodrave sudo[14882]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:19 foodrave sudo[14885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 26 11:46:19 foodrave sudo[14885]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:19 foodrave sudo[14885]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:19 foodrave wpa_supplicant[899]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Slytherpuff" Jul 26 11:46:19 foodrave wpa_supplicant[899]: wlan0: Trying to associate with SSID 'Slytherpuff' Jul 26 11:46:19 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 26 11:46:19 foodrave volumio[14845]: info: Loading plugin "wizard"... Jul 26 11:46:19 foodrave volumio[14845]: info: Loading plugin "networkfs"... Jul 26 11:46:19 foodrave volumio[14845]: info: Starting Udev Watcher for removable devices Jul 26 11:46:19 foodrave volumio[14845]: info: Ignoring mount for partition: boot Jul 26 11:46:19 foodrave volumio[14845]: info: Ignoring mount for partition: volumio Jul 26 11:46:19 foodrave volumio[14845]: info: Ignoring mount for partition: volumio_data Jul 26 11:46:19 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 26 11:46:19 foodrave volumio[14845]: info: Loading plugin "volumio_command_line_client"... Jul 26 11:46:19 foodrave volumio[14845]: info: Loading plugin "upnp"... Jul 26 11:46:19 foodrave volumio[14845]: info: [1721987179204] Starting Upmpd Daemon Jul 26 11:46:19 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 26 11:46:19 foodrave volumio[14845]: info: Loading plugin "my_music"... Jul 26 11:46:19 foodrave volumio[14845]: info: Loading plugin "mpd"... Jul 26 11:46:19 foodrave volumio[14845]: info: Loading plugin "upnp_browser"... Jul 26 11:46:19 foodrave wpa_supplicant[899]: wlan0: Associated with c0:06:c3:13:37:80 Jul 26 11:46:19 foodrave wpa_supplicant[899]: wlan0: CTRL-EVENT-CONNECTED - Connection to c0:06:c3:13:37:80 completed [id=0 id_str=] Jul 26 11:46:19 foodrave dhcpcd[911]: wlan0: carrier acquired Jul 26 11:46:19 foodrave wpa_supplicant[899]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 26 11:46:19 foodrave dhcpcd[911]: wlan0: IAID eb:12:57:d0 Jul 26 11:46:19 foodrave dhcpcd[911]: wlan0: carrier lost Jul 26 11:46:19 foodrave volumio-remote-updater[652]: [2024-07-26 11:46:19] [connect] Successful connection Jul 26 11:46:19 foodrave dhcpcd[911]: wlan0: carrier acquired Jul 26 11:46:19 foodrave volumio[14845]: info: Loading plugin "alarm-clock"... Jul 26 11:46:19 foodrave dhcpcd[911]: wlan0: IAID eb:12:57:d0 Jul 26 11:46:20 foodrave dhcpcd[911]: wlan0: rebinding lease of 192.168.0.118 Jul 26 11:46:20 foodrave volumio[14845]: info: Loading plugin "airplay_emulation"... Jul 26 11:46:20 foodrave volumio[14845]: info: Starting Shairport Sync Jul 26 11:46:20 foodrave volumio[14845]: info: Loading plugin "last_100"... Jul 26 11:46:20 foodrave volumio[14845]: info: Loading plugin "webradio"... Jul 26 11:46:20 foodrave systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 26 11:46:20 foodrave systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Jul 26 11:46:20 foodrave systemd[1]: Stopped go-librespot Daemon. Jul 26 11:46:20 foodrave volumio[14845]: info: Loading plugin "i2s_dacs"... Jul 26 11:46:20 foodrave volumio[14845]: info: Loading plugin "volumiodiscovery"... Jul 26 11:46:20 foodrave systemd[1]: Started go-librespot Daemon. Jul 26 11:46:20 foodrave go-librespot[14904]: Librespot-go daemon starting... Jul 26 11:46:20 foodrave go-librespot[14904]: time="2024-07-26T11:46:20+02:00" level=info msg="generated new device id: fb4bf6071dfb3aaf2cd5da70c5ca5a13e139975c" Jul 26 11:46:20 foodrave go-librespot[14904]: time="2024-07-26T11:46:20+02:00" level=debug msg="stored credentials found for od5o0ple11xtfxn9xt6s0gjqk" Jul 26 11:46:20 foodrave go-librespot[14904]: time="2024-07-26T11:46:20+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Jul 26 11:46:20 foodrave systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 26 11:46:20 foodrave systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 26 11:46:20 foodrave volumio[14845]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 26 11:46:20 foodrave volumio[14845]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 11:46:20 foodrave volumio[14845]: *** WARNING *** For more information see Jul 26 11:46:20 foodrave volumio[14845]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 26 11:46:20 foodrave node[14845]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 26 11:46:20 foodrave volumio[14845]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 11:46:20 foodrave volumio[14845]: *** WARNING *** For more information see Jul 26 11:46:20 foodrave node[14845]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 11:46:20 foodrave node[14845]: *** WARNING *** For more information see Jul 26 11:46:20 foodrave node[14845]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 26 11:46:20 foodrave node[14845]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 11:46:20 foodrave node[14845]: *** WARNING *** For more information see Jul 26 11:46:20 foodrave volumio[14845]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 26 11:46:20 foodrave volumio[14845]: info: Discovery: Started advertising with name: Foodrave Jul 26 11:46:20 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 26 11:46:20 foodrave volumio[14845]: info: Loading plugin "mixcloud"... Jul 26 11:46:20 foodrave dhcpcd[911]: wlan0: soliciting an IPv6 router Jul 26 11:46:20 foodrave volumio[14845]: info: Loading plugin "soundcloud"... Jul 26 11:46:21 foodrave volumio[14845]: info: Loading plugin "spop"... Jul 26 11:46:22 foodrave volumio[14845]: info: Loading plugin "squeezelite_mc"... Jul 26 11:46:22 foodrave wpa_supplicant[899]: wlan0: CTRL-EVENT-DISCONNECTED bssid=c0:06:c3:13:37:80 reason=15 Jul 26 11:46:22 foodrave dhcpcd[911]: wlan0: carrier lost Jul 26 11:46:22 foodrave wpa_supplicant[899]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 26 11:46:22 foodrave wpa_supplicant[899]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN Jul 26 11:46:23 foodrave volumio[14845]: info: Loading plugin "ytcr"... Jul 26 11:46:23 foodrave systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 26 11:46:23 foodrave systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Jul 26 11:46:23 foodrave systemd[1]: Stopped go-librespot Daemon. Jul 26 11:46:23 foodrave systemd[1]: Started go-librespot Daemon. Jul 26 11:46:23 foodrave go-librespot[14932]: Librespot-go daemon starting... Jul 26 11:46:23 foodrave go-librespot[14932]: time="2024-07-26T11:46:23+02:00" level=info msg="generated new device id: ebd627115d184fc27653be254cd5a042fdb45859" Jul 26 11:46:23 foodrave go-librespot[14932]: time="2024-07-26T11:46:23+02:00" level=debug msg="stored credentials found for od5o0ple11xtfxn9xt6s0gjqk" Jul 26 11:46:23 foodrave go-librespot[14932]: time="2024-07-26T11:46:23+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Jul 26 11:46:23 foodrave systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 26 11:46:23 foodrave systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 26 11:46:23 foodrave wpa_supplicant[899]: wlan0: Trying to associate with SSID 'Slytherpuff' Jul 26 11:46:24 foodrave dhcpcd[911]: wlan0: carrier acquired Jul 26 11:46:24 foodrave wpa_supplicant[899]: wlan0: Associated with c0:06:c3:13:37:80 Jul 26 11:46:24 foodrave wpa_supplicant[899]: wlan0: CTRL-EVENT-CONNECTED - Connection to c0:06:c3:13:37:80 completed [id=0 id_str=] Jul 26 11:46:24 foodrave wpa_supplicant[899]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 26 11:46:24 foodrave dhcpcd[911]: wlan0: IAID eb:12:57:d0 Jul 26 11:46:24 foodrave dhcpcd[911]: wlan0: carrier lost Jul 26 11:46:24 foodrave dhcpcd[911]: wlan0: carrier acquired Jul 26 11:46:24 foodrave dhcpcd[911]: wlan0: IAID eb:12:57:d0 Jul 26 11:46:24 foodrave dhcpcd[911]: wlan0: rebinding lease of 192.168.0.118 Jul 26 11:46:24 foodrave dhcpcd[911]: wlan0: soliciting an IPv6 router Jul 26 11:46:25 foodrave volumio[14845]: info: Loading plugin "ytmusic"... Jul 26 11:46:25 foodrave dhcpcd[911]: wlan0: probing address 192.168.0.118/24 Jul 26 11:46:26 foodrave volumio[14845]: info: Loading plugin "outputs"... Jul 26 11:46:26 foodrave volumio[14845]: info: Loading plugin "albumart"... Jul 26 11:46:26 foodrave volumio[14845]: info: Plugin example_plugin is not enabled Jul 26 11:46:26 foodrave volumio[14845]: info: Loading plugin "inputs"... Jul 26 11:46:26 foodrave volumio[14845]: info: Loading plugin "updater_comm"... Jul 26 11:46:26 foodrave systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 26 11:46:26 foodrave systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Jul 26 11:46:26 foodrave systemd[1]: Stopped go-librespot Daemon. Jul 26 11:46:26 foodrave volumio[14845]: info: Plugin mpdemulation is not enabled Jul 26 11:46:26 foodrave volumio[14845]: info: Loading plugin "rest_api"... Jul 26 11:46:26 foodrave volumio[14845]: info: Loading plugin "websocket"... Jul 26 11:46:26 foodrave volumio[14845]: info: Starting Socket.io Server version 2.3.0 Jul 26 11:46:26 foodrave systemd[1]: Started go-librespot Daemon. Jul 26 11:46:26 foodrave go-librespot[14958]: Librespot-go daemon starting... Jul 26 11:46:26 foodrave volumio[14845]: info: Plugin lms is not enabled Jul 26 11:46:26 foodrave volumio[14845]: info: Loading i18n strings for locale de Jul 26 11:46:26 foodrave go-librespot[14958]: time="2024-07-26T11:46:26+02:00" level=info msg="generated new device id: 647c4cfda3133568ae23c1bbf5c22162bf541b53" Jul 26 11:46:26 foodrave go-librespot[14958]: time="2024-07-26T11:46:26+02:00" level=debug msg="stored credentials found for od5o0ple11xtfxn9xt6s0gjqk" Jul 26 11:46:26 foodrave go-librespot[14958]: time="2024-07-26T11:46:26+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Jul 26 11:46:26 foodrave systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 26 11:46:26 foodrave systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 26 11:46:26 foodrave volumio[14845]: Updating browse sources language Jul 26 11:46:26 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 11:46:26 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 11:46:26 foodrave volumio[14845]: info: CoreCommandRouter::initPlayerControls Jul 26 11:46:26 foodrave volumio[14845]: Forking 3 albumart workers Jul 26 11:46:26 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:26 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:26 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:26 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:26 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:26 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:26 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:26 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:26 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 11:46:26 foodrave volumio[14845]: Express server listening on port 3000 Jul 26 11:46:26 foodrave volumio[14845]: [Metrics] WebUI: 10s 438.65ms Jul 26 11:46:26 foodrave volumio[14845]: info: CoreStateMachine::resetVolumioState Jul 26 11:46:26 foodrave volumio[14845]: info: CoreStateMachine::getcurrentVolume Jul 26 11:46:26 foodrave volumio[14845]: info: CoreCommandRouter::volumioRetrievevolume Jul 26 11:46:27 foodrave volumio[14845]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jul 26 11:46:27 foodrave volumio[14845]: wlan0 Interface doesn't support scanning : Device or resource busy Jul 26 11:46:27 foodrave volumio[14845]: info: Cannot use regular scanning, forcing with ap-force Jul 26 11:46:27 foodrave sudo[14992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jul 26 11:46:27 foodrave sudo[14992]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:27 foodrave volumio[14845]: Starting albumart workers Jul 26 11:46:27 foodrave volumio[14845]: Starting albumart workers Jul 26 11:46:27 foodrave volumio[14845]: Starting albumart workers Jul 26 11:46:27 foodrave sudo[14992]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:28 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 11:46:28 foodrave volumio[14845]: info: Reloading queue from file Jul 26 11:46:28 foodrave volumio[14845]: info: CoreStateMachine::setRepeat null single undefined Jul 26 11:46:28 foodrave volumio[14845]: info: CoreStateMachine::pushState Jul 26 11:46:28 foodrave volumio[14845]: info: CorePlayQueue::getTrack 0 Jul 26 11:46:28 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 11:46:28 foodrave volumio[14845]: info: CoreCommandRouter::volumioPushState Jul 26 11:46:28 foodrave volumio[14845]: info: CoreStateMachine::setRandom null Jul 26 11:46:28 foodrave volumio[14845]: info: CoreStateMachine::pushState Jul 26 11:46:28 foodrave volumio[14845]: info: CorePlayQueue::getTrack 0 Jul 26 11:46:28 foodrave volumio[14845]: info: CoreCommandRouter::volumioPushState Jul 26 11:46:28 foodrave volumio[14845]: info: Setting Device type: Raspberry PI Jul 26 11:46:28 foodrave volumio[14845]: info: VolumeController:: Volume=96 Mute =false Jul 26 11:46:28 foodrave volumio[14845]: info: CoreStateMachine::pushState Jul 26 11:46:28 foodrave volumio[14845]: info: CorePlayQueue::getTrack 0 Jul 26 11:46:28 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 11:46:28 foodrave volumio[14845]: info: CoreCommandRouter::volumioPushState Jul 26 11:46:28 foodrave volumio[14845]: info: CoreStateMachine::updateTrackBlock Jul 26 11:46:28 foodrave volumio[14845]: info: CorePlayQueue::getTrackBlock Jul 26 11:46:28 foodrave volumio[14845]: info: CoreCommandRouter::volumioRetrievevolume Jul 26 11:46:28 foodrave volumio[14845]: info: Completed loading Core Plugins Jul 26 11:46:28 foodrave volumio[14845]: info: Preparing to generate the ALSA configuration file Jul 26 11:46:28 foodrave volumio[14845]: info: Asound.conf file unchanged, so no further update is needed Jul 26 11:46:28 foodrave volumio[14845]: info: Output device has changed, restarting MPD Jul 26 11:46:28 foodrave sudo[15026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 26 11:46:28 foodrave sudo[15026]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:28 foodrave sudo[15026]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:28 foodrave volumio[14845]: info: Output device has changed, restarting Shairport Sync Jul 26 11:46:28 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:28 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 11:46:28 foodrave sudo[15029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 26 11:46:28 foodrave sudo[15029]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:28 foodrave systemd[1]: Stopping Music Player Daemon... Jul 26 11:46:28 foodrave systemd[1]: mpd.service: Succeeded. Jul 26 11:46:28 foodrave systemd[1]: Stopped Music Player Daemon. Jul 26 11:46:28 foodrave volumio[14845]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 26 11:46:28 foodrave volumio[14845]: info: ___________ START PLUGINS ___________ Jul 26 11:46:28 foodrave volumio[14845]: info: ControllerMpd::onStart: Initializing MPD Jul 26 11:46:28 foodrave volumio[14845]: info: Creating MPD Configuration file Jul 26 11:46:28 foodrave systemd[1]: Starting Music Player Daemon... Jul 26 11:46:28 foodrave sudo[15038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 26 11:46:28 foodrave sudo[15038]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:28 foodrave sudo[15038]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:28 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 11:46:28 foodrave volumio[14845]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 11:46:28 foodrave volumio[14845]: info: [1721987188822] CoreMusicLibrary::Adding element Medienserver Jul 26 11:46:28 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 11:46:28 foodrave sudo[15042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 26 11:46:28 foodrave sudo[15042]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:28 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:28 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 11:46:28 foodrave systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 26 11:46:28 foodrave systemd[1]: mpd.service: Succeeded. Jul 26 11:46:28 foodrave systemd[1]: Stopped Music Player Daemon. Jul 26 11:46:28 foodrave systemd[1]: Starting Music Player Daemon... Jul 26 11:46:28 foodrave volumio[14845]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 26 11:46:28 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:28 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 11:46:28 foodrave volumio[14845]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 26 11:46:28 foodrave volumio[14845]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 11:46:28 foodrave volumio[14845]: info: [1721987188974] CoreMusicLibrary::Adding element Last_100 Jul 26 11:46:28 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 11:46:28 foodrave volumio[14845]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 11:46:28 foodrave volumio[14845]: info: [1721987188977] CoreMusicLibrary::Adding element Webradio Jul 26 11:46:28 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 11:46:28 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 26 11:46:28 foodrave volumio[14845]: info: Initializing BBC Radios Jul 26 11:46:28 foodrave sudo[15045]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 26 11:46:29 foodrave sudo[15045]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:29 foodrave sudo[15045]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:29 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 26 11:46:29 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 11:46:29 foodrave volumio[14845]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 11:46:29 foodrave volumio[14845]: info: [1721987189098] CoreMusicLibrary::Adding element Mixcloud Jul 26 11:46:29 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 11:46:29 foodrave volumio[14845]: Cannot find translation for source Mixcloud Jul 26 11:46:29 foodrave volumio[14845]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 11:46:29 foodrave volumio[14845]: info: [1721987189109] CoreMusicLibrary::Adding element SoundCloud Jul 26 11:46:29 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 11:46:29 foodrave volumio[14845]: Cannot find translation for source Mixcloud Jul 26 11:46:29 foodrave volumio[14845]: Cannot find translation for source SoundCloud Jul 26 11:46:29 foodrave volumio[14845]: info: Creating Spotify config file Jul 26 11:46:29 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:29 foodrave volumio[14845]: info: [squeezelite_mc] Starting proxy server... Jul 26 11:46:29 foodrave volumio[14845]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 11:46:29 foodrave volumio[14845]: info: [1721987189317] CoreMusicLibrary::Adding element YouTube Music Jul 26 11:46:29 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 11:46:29 foodrave volumio[14845]: Cannot find translation for source Mixcloud Jul 26 11:46:29 foodrave volumio[14845]: Cannot find translation for source SoundCloud Jul 26 11:46:29 foodrave volumio[14845]: Cannot find translation for source YouTube Music Jul 26 11:46:29 foodrave volumio[14845]: info: Volumio Calling Home Jul 26 11:46:29 foodrave volumio[14845]: info: [squeezelite_mc] Proxy server started on port 34973 Jul 26 11:46:29 foodrave volumio[14845]: info: CoreCommandRouter::volumioRetrievevolume Jul 26 11:46:29 foodrave volumio[14845]: Unhandled rejection Error: No sockets available, cannot start. Jul 26 11:46:29 foodrave volumio[14845]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 26 11:46:29 foodrave volumio[14845]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 26 11:46:29 foodrave volumio[14845]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 26 11:46:29 foodrave volumio[14845]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 26 11:46:29 foodrave volumio[14845]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 26 11:46:29 foodrave volumio[14845]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 26 11:46:29 foodrave volumio[14845]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 26 11:46:29 foodrave volumio[14845]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 26 11:46:29 foodrave volumio[14845]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 26 11:46:29 foodrave volumio[14845]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 26 11:46:29 foodrave volumio[14845]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 26 11:46:29 foodrave volumio[14845]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 26 11:46:29 foodrave volumio[14845]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 26 11:46:29 foodrave volumio[14845]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 26 11:46:29 foodrave volumio[14845]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 26 11:46:29 foodrave volumio[14845]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 26 11:46:29 foodrave sudo[15068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 26 11:46:29 foodrave sudo[15068]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:29 foodrave volumio[14845]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Jul 26 11:46:29 foodrave sudo[15070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 26 11:46:29 foodrave sudo[15068]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:29 foodrave sudo[15070]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:29 foodrave volumio[14845]: error: [yt-cast-receiver] (YouTube) Failed to obtain lounge token with screen Id from stored MDX context (9eua57nd8i7u87588ibv6nnnnb): Jul 26 11:46:29 foodrave volumio[14845]: (ConnectionError) (YouTube) Connection error in getting lounge token Jul 26 11:46:29 foodrave volumio[14845]: Error info: { Jul 26 11:46:29 foodrave volumio[14845]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Jul 26 11:46:29 foodrave volumio[14845]: } Jul 26 11:46:29 foodrave volumio[14845]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo EBUSY www.youtube.com Jul 26 11:46:29 foodrave volumio[14845]: Stack trace: Jul 26 11:46:29 foodrave volumio[14845]: ConnectionError: (YouTube) Connection error in getting lounge token Jul 26 11:46:29 foodrave volumio[14845]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Jul 26 11:46:29 foodrave volumio[14845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:29 foodrave volumio[14845]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Jul 26 11:46:29 foodrave volumio[14845]: at async Promise.all (index 0) Jul 26 11:46:29 foodrave volumio[14845]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Jul 26 11:46:29 foodrave volumio[14845]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Jul 26 11:46:29 foodrave volumio[14845]: Going to generate fresh screen Id and try again... Jul 26 11:46:29 foodrave volumio[14845]: error: [yt-cast-receiver] (YouTube Music) Failed to obtain lounge token with screen Id from stored MDX context (4iag2ofhu256b892n9hu816u72): Jul 26 11:46:29 foodrave volumio[14845]: (ConnectionError) (YouTube Music) Connection error in getting lounge token Jul 26 11:46:29 foodrave volumio[14845]: Error info: { Jul 26 11:46:29 foodrave volumio[14845]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Jul 26 11:46:29 foodrave volumio[14845]: } Jul 26 11:46:29 foodrave volumio[14845]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo EBUSY www.youtube.com Jul 26 11:46:29 foodrave volumio[14845]: Stack trace: Jul 26 11:46:29 foodrave volumio[14845]: ConnectionError: (YouTube Music) Connection error in getting lounge token Jul 26 11:46:29 foodrave volumio[14845]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Jul 26 11:46:29 foodrave volumio[14845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:29 foodrave volumio[14845]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Jul 26 11:46:29 foodrave volumio[14845]: at async Promise.all (index 1) Jul 26 11:46:29 foodrave volumio[14845]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Jul 26 11:46:29 foodrave volumio[14845]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Jul 26 11:46:29 foodrave volumio[14845]: Going to generate fresh screen Id and try again... Jul 26 11:46:29 foodrave sudo[15070]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:29 foodrave volumio[14845]: info: MPD Permissions set Jul 26 11:46:29 foodrave volumio[14845]: info: MPD Permissions set Jul 26 11:46:29 foodrave volumio[14845]: info: VolumeController:: Volume=96 Mute =false Jul 26 11:46:29 foodrave volumio[14845]: info: CoreCommandRouter::volumioGetState Jul 26 11:46:29 foodrave volumio[14845]: info: CorePlayQueue::getTrack 0 Jul 26 11:46:29 foodrave volumio[14845]: info: CoreStateMachine::pushState Jul 26 11:46:29 foodrave volumio[14845]: info: CorePlayQueue::getTrack 0 Jul 26 11:46:29 foodrave volumio[14845]: info: CoreCommandRouter::volumioPushState Jul 26 11:46:29 foodrave volumio[14845]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Jul 26 11:46:29 foodrave volumio[14845]: LoungeScreenDisconnected { Jul 26 11:46:29 foodrave volumio[14845]: AID: null, Jul 26 11:46:29 foodrave volumio[14845]: name: 'loungeScreenDisconnected', Jul 26 11:46:29 foodrave volumio[14845]: payload: {} Jul 26 11:46:29 foodrave volumio[14845]: } Jul 26 11:46:29 foodrave volumio[14845]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:46:29 foodrave volumio[14845]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:46:29 foodrave volumio[14845]: Stack trace: Jul 26 11:46:29 foodrave volumio[14845]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:46:29 foodrave volumio[14845]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:46:29 foodrave volumio[14845]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:46:29 foodrave volumio[14845]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:46:29 foodrave volumio[14845]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:46:29 foodrave volumio[14845]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Jul 26 11:46:29 foodrave volumio[14845]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Jul 26 11:46:29 foodrave volumio[14845]: at new Promise () Jul 26 11:46:29 foodrave volumio[14845]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Jul 26 11:46:29 foodrave volumio[14845]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Jul 26 11:46:29 foodrave volumio[14845]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:207:26) Jul 26 11:46:29 foodrave volumio[14845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:29 foodrave volumio[14845]: at async Promise.all (index 0) Jul 26 11:46:29 foodrave volumio[14845]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Jul 26 11:46:29 foodrave volumio[14845]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Jul 26 11:46:29 foodrave systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 26 11:46:29 foodrave systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Jul 26 11:46:29 foodrave systemd[1]: Stopped go-librespot Daemon. Jul 26 11:46:29 foodrave systemd[1]: Started go-librespot Daemon. Jul 26 11:46:29 foodrave go-librespot[15074]: Librespot-go daemon starting... Jul 26 11:46:29 foodrave volumio[14845]: error: [yt-cast-receiver] Failed to start receiver: Jul 26 11:46:29 foodrave volumio[14845]: (AppError) Failed to start YouTubeApp Jul 26 11:46:29 foodrave volumio[14845]: --->(SessionError) (YouTube) Failed to establish session Jul 26 11:46:29 foodrave volumio[14845]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Jul 26 11:46:29 foodrave volumio[14845]: Error info: { Jul 26 11:46:29 foodrave volumio[14845]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Jul 26 11:46:29 foodrave volumio[14845]: } Jul 26 11:46:29 foodrave volumio[14845]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo EBUSY www.youtube.com Jul 26 11:46:29 foodrave volumio[14845]: Stack trace: Jul 26 11:46:29 foodrave volumio[14845]: AppError: Failed to start YouTubeApp Jul 26 11:46:29 foodrave volumio[14845]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Jul 26 11:46:29 foodrave volumio[14845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:29 foodrave volumio[14845]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Jul 26 11:46:29 foodrave volumio[14845]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Jul 26 11:46:29 foodrave volumio[14845]: LoungeScreenDisconnected { Jul 26 11:46:29 foodrave volumio[14845]: AID: null, Jul 26 11:46:29 foodrave volumio[14845]: name: 'loungeScreenDisconnected', Jul 26 11:46:29 foodrave volumio[14845]: payload: {} Jul 26 11:46:29 foodrave volumio[14845]: } Jul 26 11:46:29 foodrave volumio[14845]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:46:29 foodrave volumio[14845]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:46:29 foodrave volumio[14845]: Stack trace: Jul 26 11:46:29 foodrave volumio[14845]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:46:29 foodrave volumio[14845]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:46:29 foodrave volumio[14845]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:46:29 foodrave volumio[14845]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:46:29 foodrave volumio[14845]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:46:29 foodrave volumio[14845]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Jul 26 11:46:29 foodrave volumio[14845]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Jul 26 11:46:29 foodrave volumio[14845]: at new Promise () Jul 26 11:46:29 foodrave volumio[14845]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Jul 26 11:46:29 foodrave volumio[14845]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Jul 26 11:46:29 foodrave volumio[14845]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:35 Jul 26 11:46:29 foodrave volumio[14845]: at Array.forEach () Jul 26 11:46:29 foodrave volumio[14845]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:24) Jul 26 11:46:29 foodrave volumio[14845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:29 foodrave volumio[14845]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Jul 26 11:46:29 foodrave volumio[14845]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Jul 26 11:46:29 foodrave volumio[14845]: LoungeScreenDisconnected { Jul 26 11:46:29 foodrave volumio[14845]: AID: null, Jul 26 11:46:29 foodrave volumio[14845]: name: 'loungeScreenDisconnected', Jul 26 11:46:29 foodrave volumio[14845]: payload: {} Jul 26 11:46:29 foodrave volumio[14845]: } Jul 26 11:46:29 foodrave volumio[14845]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:46:29 foodrave volumio[14845]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:46:29 foodrave volumio[14845]: Stack trace: Jul 26 11:46:29 foodrave volumio[14845]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:46:29 foodrave volumio[14845]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:46:29 foodrave volumio[14845]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:46:29 foodrave volumio[14845]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:46:29 foodrave volumio[14845]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:46:29 foodrave volumio[14845]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Jul 26 11:46:29 foodrave volumio[14845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:29 foodrave go-librespot[15074]: time="2024-07-26T11:46:29+02:00" level=info msg="generated new device id: 14ee66dbe58fb277129a28b3ba3deb40c3fb4259" Jul 26 11:46:29 foodrave go-librespot[15074]: time="2024-07-26T11:46:29+02:00" level=debug msg="stored credentials found for od5o0ple11xtfxn9xt6s0gjqk" Jul 26 11:46:29 foodrave go-librespot[15074]: time="2024-07-26T11:46:29+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Jul 26 11:46:29 foodrave volumio[14845]: error: [ytcr] Failed to start plugin: Jul 26 11:46:29 foodrave volumio[14845]: (AppError) Failed to start YouTubeApp Jul 26 11:46:29 foodrave volumio[14845]: --->(SessionError) (YouTube) Failed to establish session Jul 26 11:46:29 foodrave volumio[14845]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Jul 26 11:46:29 foodrave volumio[14845]: Error info: { Jul 26 11:46:29 foodrave volumio[14845]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Jul 26 11:46:29 foodrave volumio[14845]: } Jul 26 11:46:29 foodrave volumio[14845]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo EBUSY www.youtube.com Jul 26 11:46:29 foodrave volumio[14845]: Stack trace: Jul 26 11:46:29 foodrave volumio[14845]: AppError: Failed to start YouTubeApp Jul 26 11:46:29 foodrave volumio[14845]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Jul 26 11:46:29 foodrave volumio[14845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:29 foodrave volumio[14845]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Jul 26 11:46:29 foodrave systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 26 11:46:29 foodrave systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 26 11:46:30 foodrave volumio[14845]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Jul 26 11:46:30 foodrave volumio[14845]: LoungeScreenDisconnected { Jul 26 11:46:30 foodrave volumio[14845]: AID: null, Jul 26 11:46:30 foodrave volumio[14845]: name: 'loungeScreenDisconnected', Jul 26 11:46:30 foodrave volumio[14845]: payload: {} Jul 26 11:46:30 foodrave volumio[14845]: } Jul 26 11:46:30 foodrave volumio[14845]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:46:30 foodrave volumio[14845]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:46:30 foodrave volumio[14845]: Stack trace: Jul 26 11:46:30 foodrave volumio[14845]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:46:30 foodrave volumio[14845]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:46:30 foodrave volumio[14845]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:46:30 foodrave volumio[14845]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:46:30 foodrave volumio[14845]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:46:30 foodrave volumio[14845]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Jul 26 11:46:30 foodrave volumio[14845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:30 foodrave volumio[14845]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Jul 26 11:46:30 foodrave volumio[14845]: info: Spotify config file written Jul 26 11:46:30 foodrave volumio[14845]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Jul 26 11:46:30 foodrave volumio[14845]: LoungeScreenDisconnected { Jul 26 11:46:30 foodrave volumio[14845]: AID: null, Jul 26 11:46:30 foodrave volumio[14845]: name: 'loungeScreenDisconnected', Jul 26 11:46:30 foodrave volumio[14845]: payload: {} Jul 26 11:46:30 foodrave volumio[14845]: } Jul 26 11:46:30 foodrave volumio[14845]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:46:30 foodrave volumio[14845]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:46:30 foodrave volumio[14845]: Stack trace: Jul 26 11:46:30 foodrave volumio[14845]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:46:30 foodrave volumio[14845]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:46:30 foodrave volumio[14845]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:46:30 foodrave volumio[14845]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:46:30 foodrave volumio[14845]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:46:30 foodrave volumio[14845]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Jul 26 11:46:30 foodrave volumio[14845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:30 foodrave volumio[14845]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Jul 26 11:46:30 foodrave volumio[14845]: (node:14845) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Jul 26 11:46:30 foodrave volumio[14845]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Jul 26 11:46:30 foodrave volumio[14845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:30 foodrave volumio[14845]: (Use `node --trace-warnings ...` to show where the warning was created) Jul 26 11:46:30 foodrave volumio[14845]: (node:14845) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 7) Jul 26 11:46:30 foodrave volumio[14845]: (node:14845) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Jul 26 11:46:30 foodrave sudo[15082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 26 11:46:30 foodrave volumio[14845]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Jul 26 11:46:30 foodrave volumio[14845]: LoungeScreenDisconnected { Jul 26 11:46:30 foodrave volumio[14845]: AID: null, Jul 26 11:46:30 foodrave volumio[14845]: name: 'loungeScreenDisconnected', Jul 26 11:46:30 foodrave volumio[14845]: payload: {} Jul 26 11:46:30 foodrave volumio[14845]: } Jul 26 11:46:30 foodrave volumio[14845]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:46:30 foodrave volumio[14845]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:46:30 foodrave volumio[14845]: Stack trace: Jul 26 11:46:30 foodrave volumio[14845]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:46:30 foodrave volumio[14845]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:46:30 foodrave volumio[14845]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:46:30 foodrave volumio[14845]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:46:30 foodrave volumio[14845]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:46:30 foodrave volumio[14845]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Jul 26 11:46:30 foodrave volumio[14845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:30 foodrave sudo[15082]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 11:46:30 foodrave volumio[14845]: (node:14845) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Jul 26 11:46:30 foodrave volumio[14845]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Jul 26 11:46:30 foodrave volumio[14845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:30 foodrave volumio[14845]: (node:14845) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 9) Jul 26 11:46:30 foodrave volumio[14845]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Jul 26 11:46:30 foodrave volumio[14845]: LoungeScreenDisconnected { Jul 26 11:46:30 foodrave volumio[14845]: AID: null, Jul 26 11:46:30 foodrave volumio[14845]: name: 'loungeScreenDisconnected', Jul 26 11:46:30 foodrave volumio[14845]: payload: {} Jul 26 11:46:30 foodrave volumio[14845]: } Jul 26 11:46:30 foodrave volumio[14845]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:46:30 foodrave volumio[14845]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:46:30 foodrave volumio[14845]: Stack trace: Jul 26 11:46:30 foodrave volumio[14845]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:46:30 foodrave volumio[14845]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:46:30 foodrave volumio[14845]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:46:30 foodrave volumio[14845]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:46:30 foodrave volumio[14845]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:46:30 foodrave volumio[14845]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Jul 26 11:46:30 foodrave volumio[14845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:30 foodrave volumio[14845]: (node:14845) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Jul 26 11:46:30 foodrave volumio[14845]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Jul 26 11:46:30 foodrave volumio[14845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:30 foodrave volumio[14845]: (node:14845) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 11) Jul 26 11:46:30 foodrave systemd[1]: Stopped go-librespot Daemon. Jul 26 11:46:30 foodrave volumio[14845]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Jul 26 11:46:30 foodrave volumio[14845]: LoungeScreenDisconnected { Jul 26 11:46:30 foodrave volumio[14845]: AID: null, Jul 26 11:46:30 foodrave volumio[14845]: name: 'loungeScreenDisconnected', Jul 26 11:46:30 foodrave volumio[14845]: payload: {} Jul 26 11:46:30 foodrave volumio[14845]: } Jul 26 11:46:30 foodrave volumio[14845]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jul 26 11:46:30 foodrave volumio[14845]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jul 26 11:46:30 foodrave volumio[14845]: Stack trace: Jul 26 11:46:30 foodrave volumio[14845]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jul 26 11:46:30 foodrave volumio[14845]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Jul 26 11:46:30 foodrave volumio[14845]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Jul 26 11:46:30 foodrave volumio[14845]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Jul 26 11:46:30 foodrave volumio[14845]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Jul 26 11:46:30 foodrave volumio[14845]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Jul 26 11:46:30 foodrave volumio[14845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:30 foodrave volumio[14845]: (node:14845) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Jul 26 11:46:30 foodrave volumio[14845]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Jul 26 11:46:30 foodrave volumio[14845]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 26 11:46:30 foodrave volumio[14845]: (node:14845) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 13) Jul 26 11:46:30 foodrave volumio[14845]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jul 26 11:46:30 foodrave volumio[14845]: info: VolumeController:: Volume=96 Mute =false Jul 26 11:46:30 foodrave volumio[14845]: info: CoreCommandRouter::volumioGetState Jul 26 11:46:30 foodrave volumio[14845]: info: CorePlayQueue::getTrack 0 Jul 26 11:46:30 foodrave volumio[14845]: info: CoreStateMachine::pushState Jul 26 11:46:30 foodrave volumio[14845]: info: CorePlayQueue::getTrack 0 Jul 26 11:46:30 foodrave volumio[14845]: info: CoreCommandRouter::volumioPushState Jul 26 11:46:30 foodrave volumio[14845]: info: [squeezelite_mc] Server discovery started Jul 26 11:46:30 foodrave volumio[14845]: info: [squeezelite_mc] Player finder started Jul 26 11:46:30 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 11:46:30 foodrave volumio[14845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 11:46:30 foodrave go-librespot[15084]: Librespot-go daemon starting... Jul 26 11:46:30 foodrave systemd[1]: Started go-librespot Daemon. Jul 26 11:46:30 foodrave volumio[14845]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jul 26 11:46:30 foodrave sudo[15082]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:30 foodrave go-librespot[15084]: time="2024-07-26T11:46:30+02:00" level=info msg="generated new device id: a5e8b5d9cc1b1a2abae74e5f1eff8b7c301ee33a" Jul 26 11:46:30 foodrave go-librespot[15084]: time="2024-07-26T11:46:30+02:00" level=debug msg="stored credentials found for od5o0ple11xtfxn9xt6s0gjqk" Jul 26 11:46:30 foodrave volumio[14845]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 11:46:30 foodrave go-librespot[15084]: time="2024-07-26T11:46:30+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Jul 26 11:46:30 foodrave systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 26 11:46:30 foodrave systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 26 11:46:30 foodrave volumio[14845]: Error: send ENETUNREACH 255.255.255.255:3483 Jul 26 11:46:30 foodrave volumio[14845]: at doSend (dgram.js:692:16) Jul 26 11:46:30 foodrave volumio[14845]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jul 26 11:46:30 foodrave volumio[14845]: at afterDns (dgram.js:638:5) Jul 26 11:46:30 foodrave volumio[14845]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jul 26 11:46:30 foodrave volumio[14845]: errno: -101, Jul 26 11:46:30 foodrave volumio[14845]: code: 'ENETUNREACH', Jul 26 11:46:30 foodrave volumio[14845]: syscall: 'send', Jul 26 11:46:30 foodrave volumio[14845]: address: '255.255.255.255', Jul 26 11:46:30 foodrave volumio[14845]: port: 3483 Jul 26 11:46:30 foodrave volumio[14845]: } Jul 26 11:46:30 foodrave volumio[14845]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 11:46:30 foodrave dhcpcd[911]: wlan0: leased 192.168.0.118 for infinity Jul 26 11:46:30 foodrave kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jul 26 11:46:30 foodrave avahi-daemon[675]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.118. Jul 26 11:46:30 foodrave dhcpcd[911]: wlan0: adding route to 192.168.0.0/24 Jul 26 11:46:30 foodrave dhcpcd[911]: wlan0: adding default route via 192.168.0.1 Jul 26 11:46:30 foodrave avahi-daemon[675]: New relevant interface wlan0.IPv4 for mDNS. Jul 26 11:46:30 foodrave avahi-daemon[675]: Registering new address record for 192.168.0.118 on wlan0.IPv4. Jul 26 11:46:30 foodrave mpd[15053]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 26 11:46:30 foodrave mpd[15053]: output: No 'audio_output' defined in config file Jul 26 11:46:30 foodrave mpd[15053]: output: Successfully detected a sndio audio device Jul 26 11:46:30 foodrave mpd[15053]: zeroconf: No global port, disabling zeroconf Jul 26 11:46:30 foodrave systemd[1]: Started Music Player Daemon. Jul 26 11:46:30 foodrave sudo[15029]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:30 foodrave sudo[15042]: pam_unix(sudo:session): session closed for user root Jul 26 11:46:30 foodrave sudo[15127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-26 11:45 Jul 26 11:46:30 foodrave sudo[15127]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"