-- 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"