-- Logs begin at Sun 2025-12-21 14:54:41 JST, end at Sun 2025-12-28 17:16:34 JST. -- Dec 28 17:15:03 volumio dhcpcd[965]: wlan0: carrier lost Dec 28 17:15:03 volumio wpa_supplicant[918]: wlan0: Associated with 00:11:32:b6:9b:5f Dec 28 17:15:03 volumio wpa_supplicant[918]: wlan0: CTRL-EVENT-CONNECTED - Connection to 00:11:32:b6:9b:5f completed [id=0 id_str=] Dec 28 17:15:03 volumio wpa_supplicant[918]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 28 17:15:03 volumio avahi-daemon[586]: Withdrawing address record for 192.168.1.225 on wlan0. Dec 28 17:15:03 volumio avahi-daemon[586]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.225. Dec 28 17:15:03 volumio dhcpcd[965]: wlan0: deleting route to 192.168.1.0/24 Dec 28 17:15:03 volumio dhcpcd[965]: eth0: deleting route to 192.168.1.0/24 Dec 28 17:15:03 volumio dhcpcd[965]: wlan0: deleting default route via 192.168.1.1 Dec 28 17:15:03 volumio dhcpcd[865]: eth0: pid 965 deleted route to 192.168.1.0/24 Dec 28 17:15:03 volumio dhcpcd[965]: eth0: deleting default route via 192.168.1.1 Dec 28 17:15:03 volumio avahi-daemon[586]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 28 17:15:03 volumio dhcpcd[865]: eth0: pid 965 deleted default route via 192.168.1.1 Dec 28 17:15:03 volumio volumio[24786]: info: Discovery: A device disappeared from network Dec 28 17:15:03 volumio dhcpcd[965]: wlan0: carrier acquired Dec 28 17:15:03 volumio dhcpcd[965]: wlan0: IAID eb:c1:f3:58 Dec 28 17:15:04 volumio dhcpcd[965]: wlan0: rebinding lease of 192.168.1.225 Dec 28 17:15:04 volumio dhcpcd[965]: wlan0: probing address 192.168.1.225/24 Dec 28 17:15:04 volumio dhcpcd[965]: wlan0: soliciting an IPv6 router Dec 28 17:15:04 volumio volumio[24786]: info: Volumio Network Manager: Network status updated: 1 Dec 28 17:15:05 volumio ntpd[788]: Deleting interface #13 wlan0, 192.168.1.225#123, interface stats: received=0, sent=178, dropped=0, active_time=85419 secs Dec 28 17:15:05 volumio ntpd[788]: 151.248.68.194 local addr 192.168.1.98 -> Dec 28 17:15:09 volumio dhcpcd[965]: wlan0: leased 192.168.1.225 for 481 seconds Dec 28 17:15:09 volumio avahi-daemon[586]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.225. Dec 28 17:15:09 volumio dhcpcd[965]: wlan0: adding route to 192.168.1.0/24 Dec 28 17:15:09 volumio avahi-daemon[586]: New relevant interface wlan0.IPv4 for mDNS. Dec 28 17:15:09 volumio avahi-daemon[586]: Registering new address record for 192.168.1.225 on wlan0.IPv4. Dec 28 17:15:09 volumio dhcpcd[965]: wlan0: adding default route via 192.168.1.1 Dec 28 17:15:10 volumio wpa_supplicant[918]: wlan0: CTRL-EVENT-DISCONNECTED bssid=00:11:32:b6:9b:5f reason=34 Dec 28 17:15:10 volumio dhcpcd[965]: wlan0: carrier lost Dec 28 17:15:10 volumio wpa_supplicant[918]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Dec 28 17:15:10 volumio wpa_supplicant[918]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=JP Dec 28 17:15:10 volumio avahi-daemon[586]: Withdrawing address record for 192.168.1.225 on wlan0. Dec 28 17:15:10 volumio avahi-daemon[586]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.225. Dec 28 17:15:10 volumio dhcpcd[965]: wlan0: deleting route to 192.168.1.0/24 Dec 28 17:15:10 volumio dhcpcd[965]: wlan0: deleting default route via 192.168.1.1 Dec 28 17:15:10 volumio avahi-daemon[586]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 28 17:15:10 volumio volumio[24786]: info: Discovery: A device disappeared from network Dec 28 17:15:11 volumio volumio[24786]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 17:15:11 volumio volumio[24786]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 28 17:15:11 volumio volumio[24786]: at doSend (dgram.js:692:16) Dec 28 17:15:11 volumio volumio[24786]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 28 17:15:11 volumio volumio[24786]: at afterDns (dgram.js:638:5) Dec 28 17:15:11 volumio volumio[24786]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 28 17:15:11 volumio volumio[24786]: errno: -101, Dec 28 17:15:11 volumio volumio[24786]: code: 'ENETUNREACH', Dec 28 17:15:11 volumio volumio[24786]: syscall: 'send', Dec 28 17:15:11 volumio volumio[24786]: address: '255.255.255.255', Dec 28 17:15:11 volumio volumio[24786]: port: 3483 Dec 28 17:15:11 volumio volumio[24786]: } Dec 28 17:15:11 volumio volumio[24786]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 17:15:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Dec 28 17:15:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 28 17:15:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 28 17:15:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 28 17:15:16 volumio wpa_supplicant[918]: wlan0: Trying to associate with SSID 'Synology Router' Dec 28 17:15:16 volumio sudo[2040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-28 17:14 Dec 28 17:15:16 volumio sudo[2040]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:15:16 volumio wpa_supplicant[918]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Dec 28 17:15:16 volumio sudo[2040]: pam_unix(sudo:session): session closed for user root Dec 28 17:15:16 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 28 17:15:16 volumio go-librespot[25202]: time="2025-12-28T17:15:16+09:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Dec 28 17:15:16 volumio volumio-remote-updater[588]: [2025-12-28 17:15:16] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 28 17:15:17 volumio volumio-remote-updater[588]: [2025-12-28 17:15:17] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 28 17:15:17 volumio systemd[1]: media-USB\x2dDisk.mount: Succeeded. Dec 28 17:15:17 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 28 17:15:17 volumio systemd[1]: Started dynamicswap service. Dec 28 17:15:17 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 28 17:15:17 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Dec 28 17:15:17 volumio systemd[1]: Stopped Volumio Backend Module. Dec 28 17:15:17 volumio systemd[1]: Started Volumio Backend Module. Dec 28 17:15:17 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 28 17:15:20 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Dec 28 17:15:20 volumio volumio[2070]: info: ------------------------------------------- Dec 28 17:15:20 volumio volumio[2070]: info: ----- Volumio3 ---- Dec 28 17:15:20 volumio volumio[2070]: info: ------------------------------------------- Dec 28 17:15:20 volumio volumio[2070]: info: ----- System startup ---- Dec 28 17:15:20 volumio volumio[2070]: info: ------------------------------------------- Dec 28 17:15:20 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 28 17:15:20 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 28 17:15:20 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 28 17:15:21 volumio volumio[2070]: info: MYVOLUMIO Environment detected Dec 28 17:15:22 volumio volumio-remote-updater[588]: [2025-12-28 17:15:22] [connect] Successful connection Dec 28 17:15:22 volumio volumio[2070]: info: Plugin folders cleanup Dec 28 17:15:22 volumio volumio[2070]: info: Scanning into folder /volumio/app/plugins/ Dec 28 17:15:22 volumio volumio[2070]: info: Scanning category audio_interface Dec 28 17:15:22 volumio volumio[2070]: info: Scanning category miscellanea Dec 28 17:15:22 volumio volumio[2070]: info: Scanning category music_service Dec 28 17:15:22 volumio volumio[2070]: info: Scanning category plugins.json Dec 28 17:15:22 volumio volumio[2070]: info: Scanning category system_controller Dec 28 17:15:22 volumio volumio[2070]: info: Scanning category user_interface Dec 28 17:15:22 volumio volumio[2070]: info: Scanning into folder /data/plugins/ Dec 28 17:15:22 volumio volumio[2070]: info: Scanning category audio_interface Dec 28 17:15:22 volumio volumio[2070]: info: Scanning category music_service Dec 28 17:15:22 volumio volumio[2070]: info: Scanning category system_controller Dec 28 17:15:22 volumio volumio[2070]: info: Scanning category user_interface Dec 28 17:15:22 volumio volumio[2070]: info: Plugin folders cleanup completed Dec 28 17:15:22 volumio volumio[2070]: info: ------------------------------------------- Dec 28 17:15:22 volumio volumio[2070]: info: ----- Core plugins startup ---- Dec 28 17:15:22 volumio volumio[2070]: info: ------------------------------------------- Dec 28 17:15:22 volumio volumio[2070]: info: Loading plugins from folder /volumio/app/plugins/ Dec 28 17:15:22 volumio volumio[2070]: info: Adding plugin upnp to MyMusic Plugins Dec 28 17:15:22 volumio volumio[2070]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 28 17:15:22 volumio volumio[2070]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 28 17:15:22 volumio volumio[2070]: info: Loading plugins from folder /data/plugins/ Dec 28 17:15:22 volumio volumio[2070]: info: Loading plugin "system"... Dec 28 17:15:22 volumio volumio[2070]: info: Loading plugin "appearance"... Dec 28 17:15:22 volumio wpa_supplicant[918]: wlan0: Trying to associate with SSID 'Synology Router' Dec 28 17:15:23 volumio volumio[2070]: info: Loading plugin "network"... Dec 28 17:15:23 volumio volumio[2070]: info: Refreshing Cached IP Addresses Dec 28 17:15:23 volumio sudo[2096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 28 17:15:23 volumio sudo[2096]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:15:23 volumio sudo[2098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 28 17:15:23 volumio sudo[2096]: pam_unix(sudo:session): session closed for user root Dec 28 17:15:24 volumio sudo[2098]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:15:24 volumio sudo[2098]: pam_unix(sudo:session): session closed for user root Dec 28 17:15:24 volumio volumio[2070]: info: Loading plugin "services"... Dec 28 17:15:24 volumio volumio[2070]: info: Loading plugin "alsa_controller"... Dec 28 17:15:24 volumio sudo[2108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 28 17:15:24 volumio sudo[2108]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:15:24 volumio sudo[2108]: pam_unix(sudo:session): session closed for user root Dec 28 17:15:24 volumio kernel: ieee80211 phy0: brcmf_cfg80211_scan: Connecting: status (67) Dec 28 17:15:24 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 17:15:24 volumio volumio[2070]: info: Loading plugin "wizard"... Dec 28 17:15:24 volumio volumio[2070]: info: Loading plugin "networkfs"... Dec 28 17:15:24 volumio volumio[2070]: info: Cannot mount NAS music at system boot, trial number 1 ,retrying in 5 seconds Dec 28 17:15:24 volumio volumio[2070]: info: Starting Udev Watcher for removable devices Dec 28 17:15:24 volumio volumio[2070]: info: Ignoring mount for partition: boot Dec 28 17:15:24 volumio volumio[2070]: info: Ignoring mount for partition: volumio Dec 28 17:15:24 volumio volumio[2070]: info: Ignoring mount for partition: volumio_data Dec 28 17:15:24 volumio volumio[2070]: info: Mounting Device USB-Disk Dec 28 17:15:24 volumio sudo[2127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/USB-Disk -o noatime Dec 28 17:15:24 volumio sudo[2127]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:15:25 volumio sudo[2127]: pam_unix(sudo:session): session closed for user root Dec 28 17:15:25 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 17:15:25 volumio volumio[2070]: info: Loading plugin "volumio_command_line_client"... Dec 28 17:15:25 volumio volumio[2070]: info: Loading plugin "upnp"... Dec 28 17:15:25 volumio volumio[2070]: info: [1766909725059] Starting Upmpd Daemon Dec 28 17:15:25 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 17:15:25 volumio volumio[2070]: info: Loading plugin "my_music"... Dec 28 17:15:25 volumio volumio[2070]: info: Loading plugin "mpd"... Dec 28 17:15:25 volumio volumio[2070]: info: Loading plugin "upnp_browser"... Dec 28 17:15:25 volumio wpa_supplicant[918]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:11:32:a5:13:5e status_code=16 Dec 28 17:15:26 volumio volumio[2070]: info: Starting UPNP Browser Dec 28 17:15:26 volumio volumio[2070]: info: Loading plugin "alarm-clock"... Dec 28 17:15:26 volumio volumio[2070]: info: Loading plugin "airplay_emulation"... Dec 28 17:15:26 volumio volumio[2070]: info: Starting Shairport Sync Dec 28 17:15:26 volumio volumio[2070]: info: Loading plugin "last_100"... Dec 28 17:15:26 volumio volumio[2070]: info: Loading plugin "webradio"... Dec 28 17:15:26 volumio volumio[2070]: info: Loading plugin "i2s_dacs"... Dec 28 17:15:26 volumio volumio[2070]: info: Loading plugin "volumiodiscovery"... Dec 28 17:15:26 volumio volumio[2070]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 28 17:15:26 volumio volumio[2070]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 17:15:26 volumio volumio[2070]: *** WARNING *** For more information see Dec 28 17:15:26 volumio volumio[2070]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 28 17:15:26 volumio volumio[2070]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 17:15:26 volumio volumio[2070]: *** WARNING *** For more information see Dec 28 17:15:26 volumio node[2070]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 28 17:15:26 volumio node[2070]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 17:15:26 volumio node[2070]: *** WARNING *** For more information see Dec 28 17:15:26 volumio node[2070]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 28 17:15:26 volumio node[2070]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 17:15:26 volumio node[2070]: *** WARNING *** For more information see Dec 28 17:15:26 volumio volumio[2070]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 28 17:15:26 volumio volumio[2070]: info: Discovery: Started advertising with name: Volumio Dec 28 17:15:26 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 17:15:26 volumio volumio[2070]: info: Plugin bandcamp is not enabled Dec 28 17:15:26 volumio volumio[2070]: info: Plugin calmradio is not enabled Dec 28 17:15:26 volumio volumio[2070]: info: Plugin jellyfin is not enabled Dec 28 17:15:26 volumio volumio[2070]: info: Loading plugin "jellyfin_server"... Dec 28 17:15:27 volumio volumio[2070]: info: Plugin mixcloud is not enabled Dec 28 17:15:27 volumio volumio[2070]: info: Plugin soundcloud is not enabled Dec 28 17:15:27 volumio volumio[2070]: info: Loading plugin "spop"... Dec 28 17:15:29 volumio volumio[2070]: info: Loading plugin "squeezelite_mc"... Dec 28 17:15:29 volumio dhcpcd[965]: eth0: dhcp_sendudp: Network is unreachable Dec 28 17:15:30 volumio volumio[2070]: info: Loading plugin "youtube2"... Dec 28 17:15:30 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Dec 28 17:15:30 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 28 17:15:31 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 28 17:15:31 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 28 17:15:32 volumio volumio[2070]: info: Loading plugin "ytcr"... Dec 28 17:15:32 volumio wpa_supplicant[918]: wlan0: Trying to associate with SSID 'Synology Router' Dec 28 17:15:33 volumio wpa_supplicant[918]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:11:32:a5:13:5e status_code=16 Dec 28 17:15:34 volumio volumio-remote-updater[588]: [2025-12-28 17:15:34] [connect] Successful connection Dec 28 17:15:38 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Dec 28 17:15:38 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 28 17:15:39 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 28 17:15:39 volumio nmbd[745]: [2025/12/28 17:15:39.083680, 0] ../source3/libsmb/nmblib.c:917(send_udp) Dec 28 17:15:39 volumio nmbd[745]: Packet send failed to 192.168.1.119(52400) ERRNO=Network is unreachable Dec 28 17:15:39 volumio nmbd[745]: [2025/12/28 17:15:39.083950, 0] ../source3/nmbd/nmbd_packets.c:1027(reply_netbios_packet) Dec 28 17:15:39 volumio nmbd[745]: reply_netbios_packet: send_packet to IP 192.168.1.119 port 52400 failed Dec 28 17:15:39 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 28 17:15:39 volumio nmbd[745]: [2025/12/28 17:15:39.306071, 0] ../source3/libsmb/nmblib.c:917(send_udp) Dec 28 17:15:39 volumio nmbd[745]: Packet send failed to 192.168.1.119(52400) ERRNO=Network is unreachable Dec 28 17:15:39 volumio nmbd[745]: [2025/12/28 17:15:39.306370, 0] ../source3/nmbd/nmbd_packets.c:1027(reply_netbios_packet) Dec 28 17:15:39 volumio nmbd[745]: reply_netbios_packet: send_packet to IP 192.168.1.119 port 52400 failed Dec 28 17:15:40 volumio wpa_supplicant[918]: wlan0: Trying to associate with SSID 'Synology Router' Dec 28 17:15:41 volumio wpa_supplicant[918]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:11:32:a5:13:5e status_code=16 Dec 28 17:15:43 volumio volumio[2070]: info: Loading plugin "ytmusic"... Dec 28 17:15:45 volumio volumio[2070]: info: Loading plugin "autostart"... Dec 28 17:15:45 volumio volumio[2070]: info: Applying required configuration parameters for plugin autostart Dec 28 17:15:45 volumio volumio[2070]: info: AutoStart - onVolumioStart - read config.json Dec 28 17:15:45 volumio volumio[2070]: info: Loading plugin "now_playing"... Dec 28 17:15:47 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Dec 28 17:15:47 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 28 17:15:47 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 28 17:15:47 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 28 17:15:47 volumio volumio[2070]: info: Loading plugin "outputs"... Dec 28 17:15:47 volumio volumio[2070]: info: Loading plugin "albumart"... Dec 28 17:15:47 volumio volumio[2070]: info: Plugin example_plugin is not enabled Dec 28 17:15:47 volumio volumio[2070]: info: Loading plugin "inputs"... Dec 28 17:15:47 volumio volumio[2070]: info: Loading plugin "updater_comm"... Dec 28 17:15:48 volumio volumio[2070]: info: Plugin mpdemulation is not enabled Dec 28 17:15:48 volumio volumio[2070]: info: Loading plugin "rest_api"... Dec 28 17:15:48 volumio volumio[2070]: info: Loading plugin "websocket"... Dec 28 17:15:48 volumio volumio[2070]: info: Starting Socket.io Server version 2.3.0 Dec 28 17:15:48 volumio volumio[2070]: info: Loading plugin "fusiondsp"... Dec 28 17:15:48 volumio volumio[2070]: Forking 3 albumart workers Dec 28 17:15:48 volumio volumio[2070]: info: Applying required configuration parameters for plugin fusiondsp Dec 28 17:15:49 volumio volumio[2070]: info: Plugin 80s80s is not enabled Dec 28 17:15:49 volumio volumio[2070]: info: Plugin RoonBridge is not enabled Dec 28 17:15:49 volumio volumio[2070]: info: Loading plugin "jp_radio"... Dec 28 17:15:49 volumio volumio-remote-updater[588]: [2025-12-28 17:15:49] [connect] Successful connection Dec 28 17:15:49 volumio wpa_supplicant[918]: wlan0: Trying to associate with SSID 'Synology Router' Dec 28 17:15:49 volumio volumio[2070]: Starting albumart workers Dec 28 17:15:49 volumio volumio[2070]: Starting albumart workers Dec 28 17:15:49 volumio volumio[2070]: Starting albumart workers Dec 28 17:15:49 volumio wpa_supplicant[918]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:11:32:a5:13:5e status_code=16 Dec 28 17:15:49 volumio wpa_supplicant[918]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Synology Router" auth_failures=1 duration=10 reason=CONN_FAILED Dec 28 17:15:51 volumio volumio[2070]: info: Loading plugin "lms"... Dec 28 17:15:51 volumio volumio[2070]: info: Loading plugin "minidlna"... Dec 28 17:15:51 volumio volumio[2070]: info: Applying required configuration parameters for plugin minidlna Dec 28 17:15:51 volumio volumio[2070]: info: Plugin motherearthradio is not enabled Dec 28 17:15:51 volumio volumio[2070]: info: Plugin pandora is not enabled Dec 28 17:15:51 volumio volumio[2070]: info: Plugin personal_radio is not enabled Dec 28 17:15:51 volumio volumio[2070]: info: Loading plugin "podcast"... Dec 28 17:15:51 volumio volumio[2070]: info: ControllerPodcast::constructor Dec 28 17:15:52 volumio volumio[2070]: info: Plugin rad357pl is not enabled Dec 28 17:15:52 volumio volumio[2070]: info: Plugin radio_paradise is not enabled Dec 28 17:15:52 volumio volumio[2070]: info: Plugin swarmstation is not enabled Dec 28 17:15:52 volumio volumio[2070]: info: Plugin volusonic is not enabled Dec 28 17:15:52 volumio volumio[2070]: info: Loading plugin "backup_restore"... Dec 28 17:15:52 volumio volumio[2070]: info: Applying required configuration parameters for plugin backup_restore Dec 28 17:15:52 volumio volumio[2070]: info: Loading plugin "Systeminfo"... Dec 28 17:15:52 volumio volumio[2070]: info: Plugin randomizer is not enabled Dec 28 17:15:52 volumio volumio[2070]: info: Loading plugin "touch_display"... Dec 28 17:15:53 volumio volumio[2070]: info: Applying required configuration parameters for plugin touch_display Dec 28 17:15:53 volumio volumio[2070]: info: Loading i18n strings for locale ja Dec 28 17:15:53 volumio volumio[2070]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Dec 28 17:15:53 volumio volumio[2070]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 28 17:15:53 volumio volumio[2070]: Updating browse sources language Dec 28 17:15:53 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 17:15:53 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 17:15:53 volumio volumio[2070]: info: CoreCommandRouter::initPlayerControls Dec 28 17:15:53 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:15:53 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:15:53 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:15:53 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:15:53 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:15:53 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:15:53 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:15:53 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:15:53 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 17:15:53 volumio volumio[2070]: Express server listening on port 3000 Dec 28 17:15:53 volumio volumio[2070]: [Metrics] WebUI: 34s 454.92ms Dec 28 17:15:53 volumio volumio[2070]: info: CoreStateMachine::resetVolumioState Dec 28 17:15:53 volumio volumio[2070]: info: CoreStateMachine::getcurrentVolume Dec 28 17:15:53 volumio volumio[2070]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 17:15:54 volumio volumio[2070]: info: Cannot mount NAS music at system boot, trial number 2 ,retrying in 5 seconds Dec 28 17:15:54 volumio volumio[2070]: info: Volumio Network Manager: Network status updated: 1 Dec 28 17:15:54 volumio sudo[2226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 28 17:15:54 volumio sudo[2226]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:15:54 volumio sudo[2224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 28 17:15:54 volumio sudo[2226]: pam_unix(sudo:session): session closed for user root Dec 28 17:15:54 volumio sudo[2224]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:15:54 volumio sudo[2224]: pam_unix(sudo:session): session closed for user root Dec 28 17:15:54 volumio volumio[2070]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 28 17:15:54 volumio volumio[2070]: wlan0 Interface doesn't support scanning : Resource temporarily unavailable Dec 28 17:15:54 volumio volumio[2070]: info: Cannot use regular scanning, forcing with ap-force Dec 28 17:15:54 volumio sudo[2246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 28 17:15:54 volumio sudo[2246]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:15:57 volumio sudo[2246]: pam_unix(sudo:session): session closed for user root Dec 28 17:15:58 volumio volumio[2070]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 28 17:15:58 volumio volumio[2070]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Dec 28 17:15:58 volumio volumio[2070]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 28 17:15:58 volumio volumio[2070]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Dec 28 17:15:58 volumio volumio[2070]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 28 17:15:58 volumio volumio[2070]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 28 17:15:58 volumio volumio[2070]: info: VolumeController:: Volume=0 Mute =false Dec 28 17:15:58 volumio volumio[2070]: info: CoreStateMachine::pushState Dec 28 17:15:58 volumio volumio[2070]: info: CorePlayQueue::getTrack 0 Dec 28 17:15:58 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 17:15:58 volumio volumio[2070]: info: CoreCommandRouter::volumioPushState Dec 28 17:15:58 volumio volumio[2070]: info: CoreStateMachine::updateTrackBlock Dec 28 17:15:58 volumio volumio[2070]: info: CorePlayQueue::getTrackBlock Dec 28 17:15:58 volumio volumio[2070]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 17:15:58 volumio volumio[2070]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 28 17:15:58 volumio volumio[2070]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 28 17:15:58 volumio volumio[2070]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 28 17:15:58 volumio volumio[2070]: info: Reloading queue from file Dec 28 17:15:58 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:15:58 volumio volumio[2070]: info: CoreStateMachine::setRepeat null single undefined Dec 28 17:15:58 volumio volumio[2070]: info: CoreStateMachine::pushState Dec 28 17:15:58 volumio volumio[2070]: info: CorePlayQueue::getTrack 0 Dec 28 17:15:58 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 17:15:58 volumio volumio[2070]: info: CoreCommandRouter::volumioPushState Dec 28 17:15:58 volumio volumio[2070]: info: CoreStateMachine::setRandom null Dec 28 17:15:58 volumio volumio[2070]: info: CoreStateMachine::pushState Dec 28 17:15:58 volumio volumio[2070]: info: CorePlayQueue::getTrack 0 Dec 28 17:15:58 volumio volumio[2070]: info: CoreCommandRouter::volumioPushState Dec 28 17:15:58 volumio volumio[2070]: info: Setting Device type: Raspberry PI Dec 28 17:15:58 volumio volumio[2070]: info: Completed loading Core Plugins Dec 28 17:15:58 volumio volumio[2070]: info: Preparing to generate the ALSA configuration file Dec 28 17:15:58 volumio volumio[2070]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 28 17:15:58 volumio volumio[2070]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 28 17:15:58 volumio volumio[2070]: info: Reading ALSA contributions from plugins. Dec 28 17:15:58 volumio volumio[2070]: info: VolumeController:: Volume=0 Mute =false Dec 28 17:15:58 volumio volumio[2070]: info: CoreStateMachine::pushState Dec 28 17:15:58 volumio volumio[2070]: info: CorePlayQueue::getTrack 0 Dec 28 17:15:58 volumio volumio[2070]: info: CoreCommandRouter::volumioPushState Dec 28 17:15:58 volumio sudo[2256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 28 17:15:58 volumio sudo[2256]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:15:58 volumio volumio[2070]: info: Discovery: adding 312eef85-7f60-4bdb-9865-815eb2763960 Dec 28 17:15:58 volumio volumio[2070]: info: Discovery: Found device Volumio Dec 28 17:15:58 volumio volumio[2070]: info: CoreCommandRouter::volumioGetState Dec 28 17:15:58 volumio volumio[2070]: info: CorePlayQueue::getTrack 0 Dec 28 17:15:58 volumio sudo[2256]: pam_unix(sudo:session): session closed for user root Dec 28 17:15:58 volumio volumio[2070]: info: Upmpdcli Daemon Started Dec 28 17:15:58 volumio volumio[2070]: info: Asound.conf file unchanged, so no further update is needed Dec 28 17:15:58 volumio volumio[2070]: info: Output device has changed, restarting MPD Dec 28 17:15:58 volumio sudo[2259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 17:15:58 volumio sudo[2259]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:15:58 volumio sudo[2259]: pam_unix(sudo:session): session closed for user root Dec 28 17:15:58 volumio volumio[2070]: info: Output device has changed, restarting Shairport Sync Dec 28 17:15:58 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:15:58 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:15:58 volumio sudo[2263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 17:15:58 volumio sudo[2263]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:15:58 volumio systemd[1]: Stopping Music Player Daemon... Dec 28 17:15:58 volumio volumio[2070]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 17:15:58 volumio volumio[2070]: info: ___________ START PLUGINS ___________ Dec 28 17:15:58 volumio volumio[2070]: info: ControllerMpd::onStart: Initializing MPD Dec 28 17:15:58 volumio volumio[2070]: info: Creating MPD Configuration file Dec 28 17:15:58 volumio sudo[2269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 17:15:58 volumio sudo[2269]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:15:58 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 17:15:58 volumio volumio[2070]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 17:15:58 volumio volumio[2070]: info: [1766909758958] CoreMusicLibrary::Adding element DLNAサーバー Dec 28 17:15:58 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 17:15:58 volumio sudo[2269]: pam_unix(sudo:session): session closed for user root Dec 28 17:15:58 volumio volumio[2070]: info: UPNP Browser: Client initialized successfully Dec 28 17:15:58 volumio sudo[2272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 17:15:58 volumio sudo[2272]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:15:58 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:15:58 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:15:59 volumio volumio[2070]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 17:15:59 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:15:59 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:15:59 volumio volumio[2070]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 17:15:59 volumio volumio[2070]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 17:15:59 volumio volumio[2070]: info: [1766909759133] CoreMusicLibrary::Adding element Last_100 Dec 28 17:15:59 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 17:15:59 volumio volumio[2070]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 17:15:59 volumio volumio[2070]: info: [1766909759139] CoreMusicLibrary::Adding element Webradio Dec 28 17:15:59 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 17:15:59 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 17:15:59 volumio volumio[2070]: info: Initializing BBC Radios Dec 28 17:15:59 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 17:15:59 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:15:59 volumio volumio[2070]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl start jellyfin || true Dec 28 17:15:59 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Dec 28 17:15:59 volumio sudo[2292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start jellyfin Dec 28 17:15:59 volumio sudo[2292]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:15:59 volumio volumio[2070]: info: Creating Spotify config file Dec 28 17:15:59 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:15:59 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 28 17:15:59 volumio sudo[2292]: pam_unix(sudo:session): session closed for user root Dec 28 17:15:59 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 28 17:15:59 volumio volumio[2070]: info: [squeezelite_mc] Starting proxy server... Dec 28 17:15:59 volumio volumio[2070]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 17:15:59 volumio volumio[2070]: info: [1766909759608] CoreMusicLibrary::Adding element YouTube2 Dec 28 17:15:59 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 17:15:59 volumio volumio[2070]: Cannot find translation for source YouTube2 Dec 28 17:15:59 volumio volumio[2070]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 17:15:59 volumio volumio[2070]: info: [1766909759673] CoreMusicLibrary::Adding element YouTube Music Dec 28 17:15:59 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 17:15:59 volumio volumio[2070]: Cannot find translation for source YouTube2 Dec 28 17:15:59 volumio volumio[2070]: Cannot find translation for source YouTube Music Dec 28 17:15:59 volumio volumio[2070]: info: AutoStart - onStart Dec 28 17:15:59 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 28 17:15:59 volumio volumio[2070]: info: [now-playing] ConfigUpdater: config is up to date. Dec 28 17:15:59 volumio volumio[2070]: info: Loading i18n strings for locale ja Dec 28 17:15:59 volumio volumio[2070]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Dec 28 17:15:59 volumio volumio[2070]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 28 17:15:59 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 28 17:15:59 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:15:59 volumio volumio[2070]: info: FusionDsp - mixtype--------------------- Hardware Dec 28 17:15:59 volumio volumio[2070]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 17:15:59 volumio volumio[2070]: info: [1766909759830] CoreMusicLibrary::Adding element RADIKO Dec 28 17:15:59 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 17:15:59 volumio volumio[2070]: Cannot find translation for source YouTube2 Dec 28 17:15:59 volumio volumio[2070]: Cannot find translation for source YouTube Music Dec 28 17:15:59 volumio volumio[2070]: Cannot find translation for source RADIKO Dec 28 17:15:59 volumio volumio[2070]: info: Loading i18n strings for locale ja Dec 28 17:15:59 volumio volumio[2070]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Dec 28 17:15:59 volumio sudo[2295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Dec 28 17:15:59 volumio volumio[2070]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 28 17:15:59 volumio sudo[2295]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:15:59 volumio volumio[2070]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 17:15:59 volumio volumio[2070]: info: [1766909759972] CoreMusicLibrary::Adding element Podcast Dec 28 17:15:59 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 17:15:59 volumio volumio[2070]: Cannot find translation for source YouTube2 Dec 28 17:15:59 volumio volumio[2070]: Cannot find translation for source YouTube Music Dec 28 17:15:59 volumio volumio[2070]: Cannot find translation for source RADIKO Dec 28 17:15:59 volumio volumio[2070]: Cannot find translation for source Podcast Dec 28 17:15:59 volumio volumio[2070]: info: Loading i18n strings for locale ja Dec 28 17:15:59 volumio systemd[1]: Starting Logitech Media Server Daemon... Dec 28 17:16:00 volumio volumio[2070]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Dec 28 17:16:00 volumio volumio[2070]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 28 17:16:00 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 17:16:00 volumio volumio[2070]: info: Volumio Calling Home Dec 28 17:16:00 volumio volumio[2070]: info: [squeezelite_mc] Proxy server started on port 35629 Dec 28 17:16:00 volumio volumio[2070]: info: Preparing to generate the ALSA configuration file Dec 28 17:16:00 volumio systemd[1]: Started Logitech Media Server Daemon. Dec 28 17:16:00 volumio sudo[2295]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:00 volumio systemd[2303]: logitechmediaserver.service: Failed to execute command: No such file or directory Dec 28 17:16:00 volumio systemd[2303]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory Dec 28 17:16:00 volumio systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC Dec 28 17:16:00 volumio systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Dec 28 17:16:00 volumio sudo[2305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Dec 28 17:16:00 volumio sudo[2305]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:00 volumio sudo[2307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Dec 28 17:16:00 volumio sudo[2307]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:00 volumio sudo[2311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 28 17:16:00 volumio sudo[2311]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:00 volumio systemd[1]: Reloading. Dec 28 17:16:00 volumio volumio[2070]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 17:16:01 volumio volumio[2070]: info: [now-playing] App is listening on port 4004. Dec 28 17:16:01 volumio volumio[2070]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Dec 28 17:16:01 volumio volumio[2070]: info: Cannot mount NAS music at system boot, trial number 3 ,retrying in 5 seconds Dec 28 17:16:01 volumio volumio[2070]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Dec 28 17:16:01 volumio volumio[2070]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 28 17:16:01 volumio volumio[2070]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 28 17:16:01 volumio volumio[2070]: info: Reading ALSA contributions from plugins. Dec 28 17:16:01 volumio volumio[2070]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Dec 28 17:16:01 volumio volumio[2070]: error: [yt-cast-receiver] (YouTube) Failed to obtain lounge token with screen Id from stored MDX context (gutves0nlv0b580t004ki60etm): Dec 28 17:16:01 volumio volumio[2070]: (ConnectionError) (YouTube) Connection error in getting lounge token Dec 28 17:16:01 volumio volumio[2070]: Error info: { Dec 28 17:16:01 volumio volumio[2070]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Dec 28 17:16:01 volumio volumio[2070]: } Dec 28 17:16:01 volumio volumio[2070]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo ENOTFOUND www.youtube.com Dec 28 17:16:01 volumio volumio[2070]: Stack trace: Dec 28 17:16:01 volumio volumio[2070]: ConnectionError: (YouTube) Connection error in getting lounge token Dec 28 17:16:01 volumio volumio[2070]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Dec 28 17:16:01 volumio volumio[2070]: at async Promise.all (index 0) Dec 28 17:16:01 volumio volumio[2070]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Dec 28 17:16:01 volumio volumio[2070]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Dec 28 17:16:01 volumio volumio[2070]: Going to generate fresh screen Id and try again... Dec 28 17:16:01 volumio volumio[2070]: error: [yt-cast-receiver] (YouTube Music) Failed to obtain lounge token with screen Id from stored MDX context (of0ds3c7jitaldchtmu7eq2jtj): Dec 28 17:16:01 volumio volumio[2070]: (ConnectionError) (YouTube Music) Connection error in getting lounge token Dec 28 17:16:01 volumio volumio[2070]: Error info: { Dec 28 17:16:01 volumio volumio[2070]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Dec 28 17:16:01 volumio volumio[2070]: } Dec 28 17:16:01 volumio volumio[2070]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo ENOTFOUND www.youtube.com Dec 28 17:16:01 volumio volumio[2070]: Stack trace: Dec 28 17:16:01 volumio volumio[2070]: ConnectionError: (YouTube Music) Connection error in getting lounge token Dec 28 17:16:01 volumio volumio[2070]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Dec 28 17:16:01 volumio volumio[2070]: at async Promise.all (index 1) Dec 28 17:16:01 volumio volumio[2070]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Dec 28 17:16:01 volumio volumio[2070]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Dec 28 17:16:01 volumio volumio[2070]: Going to generate fresh screen Id and try again... Dec 28 17:16:01 volumio volumio[2070]: error: JP_Radio::premium account login error Dec 28 17:16:01 volumio volumio[2070]: info: JP_Radio::premium account not set Dec 28 17:16:01 volumio volumio[2070]: (node:2070) UnhandledPromiseRejectionWarning: TypeError: Expected value which is `predicate returns truthy for any value`, received values of types `null`. Dec 28 17:16:01 volumio volumio[2070]: at assertType (/data/plugins/music_service/jp_radio/node_modules/@sindresorhus/is/dist/index.js:303:15) Dec 28 17:16:01 volumio volumio[2070]: at Object.any (/data/plugins/music_service/jp_radio/node_modules/@sindresorhus/is/dist/index.js:402:16) Dec 28 17:16:01 volumio volumio[2070]: at normalizeArguments (/data/plugins/music_service/jp_radio/node_modules/got/dist/source/core/index.js:389:21) Dec 28 17:16:01 volumio volumio[2070]: at got (/data/plugins/music_service/jp_radio/node_modules/got/dist/source/create.js:112:39) Dec 28 17:16:01 volumio volumio[2070]: at Radiko.#auth1 (/data/plugins/music_service/jp_radio/lib/radiko.js:157:28) Dec 28 17:16:01 volumio volumio[2070]: at Radiko.#getToken (/data/plugins/music_service/jp_radio/lib/radiko.js:76:41) Dec 28 17:16:01 volumio volumio[2070]: at Radiko.init (/data/plugins/music_service/jp_radio/lib/radiko.js:67:53) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: at async JpRadio.#init (/data/plugins/music_service/jp_radio/lib/radio.js:102:5) Dec 28 17:16:01 volumio volumio[2070]: at async /data/plugins/music_service/jp_radio/lib/radio.js:74:7 Dec 28 17:16:01 volumio volumio[2070]: (Use `node --trace-warnings ...` to show where the warning was created) Dec 28 17:16:01 volumio volumio[2070]: (node:2070) 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: 3) Dec 28 17:16:01 volumio volumio[2070]: (node:2070) [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. Dec 28 17:16:01 volumio volumio[2070]: info: logitechmediaserver started Dec 28 17:16:01 volumio volumio[2070]: info: MPD Permissions set Dec 28 17:16:01 volumio volumio[2070]: info: MPD Permissions set Dec 28 17:16:01 volumio volumio[2070]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Dec 28 17:16:01 volumio volumio[2070]: LoungeScreenDisconnected { Dec 28 17:16:01 volumio volumio[2070]: AID: null, Dec 28 17:16:01 volumio volumio[2070]: name: 'loungeScreenDisconnected', Dec 28 17:16:01 volumio volumio[2070]: payload: {} Dec 28 17:16:01 volumio volumio[2070]: } Dec 28 17:16:01 volumio volumio[2070]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Dec 28 17:16:01 volumio volumio[2070]: Stack trace: Dec 28 17:16:01 volumio volumio[2070]: IncompleteAPIDataError: Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Dec 28 17:16:01 volumio volumio[2070]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Dec 28 17:16:01 volumio volumio[2070]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Dec 28 17:16:01 volumio volumio[2070]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Dec 28 17:16:01 volumio volumio[2070]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Dec 28 17:16:01 volumio volumio[2070]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Dec 28 17:16:01 volumio volumio[2070]: at new Promise () Dec 28 17:16:01 volumio volumio[2070]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Dec 28 17:16:01 volumio volumio[2070]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Dec 28 17:16:01 volumio volumio[2070]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:207:26) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: at async Promise.all (index 0) Dec 28 17:16:01 volumio volumio[2070]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Dec 28 17:16:01 volumio volumio[2070]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Dec 28 17:16:01 volumio volumio[2070]: error: [yt-cast-receiver] Failed to start receiver: Dec 28 17:16:01 volumio volumio[2070]: (AppError) Failed to start YouTubeApp Dec 28 17:16:01 volumio volumio[2070]: --->(SessionError) (YouTube) Failed to establish session Dec 28 17:16:01 volumio volumio[2070]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Dec 28 17:16:01 volumio volumio[2070]: Error info: { Dec 28 17:16:01 volumio volumio[2070]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Dec 28 17:16:01 volumio volumio[2070]: } Dec 28 17:16:01 volumio volumio[2070]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo ENOTFOUND www.youtube.com Dec 28 17:16:01 volumio volumio[2070]: Stack trace: Dec 28 17:16:01 volumio volumio[2070]: AppError: Failed to start YouTubeApp Dec 28 17:16:01 volumio volumio[2070]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Dec 28 17:16:01 volumio volumio[2070]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Dec 28 17:16:01 volumio volumio[2070]: LoungeScreenDisconnected { Dec 28 17:16:01 volumio volumio[2070]: AID: null, Dec 28 17:16:01 volumio volumio[2070]: name: 'loungeScreenDisconnected', Dec 28 17:16:01 volumio volumio[2070]: payload: {} Dec 28 17:16:01 volumio volumio[2070]: } Dec 28 17:16:01 volumio volumio[2070]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Dec 28 17:16:01 volumio volumio[2070]: Stack trace: Dec 28 17:16:01 volumio volumio[2070]: IncompleteAPIDataError: Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Dec 28 17:16:01 volumio volumio[2070]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Dec 28 17:16:01 volumio volumio[2070]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Dec 28 17:16:01 volumio volumio[2070]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Dec 28 17:16:01 volumio volumio[2070]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Dec 28 17:16:01 volumio volumio[2070]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Dec 28 17:16:01 volumio volumio[2070]: at new Promise () Dec 28 17:16:01 volumio volumio[2070]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Dec 28 17:16:01 volumio volumio[2070]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Dec 28 17:16:01 volumio volumio[2070]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:35 Dec 28 17:16:01 volumio volumio[2070]: at Array.forEach () Dec 28 17:16:01 volumio volumio[2070]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:24) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Dec 28 17:16:01 volumio volumio[2070]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Dec 28 17:16:01 volumio volumio[2070]: LoungeScreenDisconnected { Dec 28 17:16:01 volumio volumio[2070]: AID: null, Dec 28 17:16:01 volumio volumio[2070]: name: 'loungeScreenDisconnected', Dec 28 17:16:01 volumio volumio[2070]: payload: {} Dec 28 17:16:01 volumio volumio[2070]: } Dec 28 17:16:01 volumio volumio[2070]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Dec 28 17:16:01 volumio volumio[2070]: Stack trace: Dec 28 17:16:01 volumio volumio[2070]: IncompleteAPIDataError: Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Dec 28 17:16:01 volumio volumio[2070]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Dec 28 17:16:01 volumio volumio[2070]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Dec 28 17:16:01 volumio volumio[2070]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Dec 28 17:16:01 volumio volumio[2070]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: error: [ytcr] Failed to start plugin: Dec 28 17:16:01 volumio volumio[2070]: (AppError) Failed to start YouTubeApp Dec 28 17:16:01 volumio volumio[2070]: --->(SessionError) (YouTube) Failed to establish session Dec 28 17:16:01 volumio volumio[2070]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Dec 28 17:16:01 volumio volumio[2070]: Error info: { Dec 28 17:16:01 volumio volumio[2070]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Dec 28 17:16:01 volumio volumio[2070]: } Dec 28 17:16:01 volumio volumio[2070]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo ENOTFOUND www.youtube.com Dec 28 17:16:01 volumio volumio[2070]: Stack trace: Dec 28 17:16:01 volumio volumio[2070]: AppError: Failed to start YouTubeApp Dec 28 17:16:01 volumio volumio[2070]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Dec 28 17:16:01 volumio volumio[2070]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Dec 28 17:16:01 volumio volumio[2070]: LoungeScreenDisconnected { Dec 28 17:16:01 volumio volumio[2070]: AID: null, Dec 28 17:16:01 volumio volumio[2070]: name: 'loungeScreenDisconnected', Dec 28 17:16:01 volumio volumio[2070]: payload: {} Dec 28 17:16:01 volumio volumio[2070]: } Dec 28 17:16:01 volumio volumio[2070]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Dec 28 17:16:01 volumio volumio[2070]: Stack trace: Dec 28 17:16:01 volumio volumio[2070]: IncompleteAPIDataError: Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Dec 28 17:16:01 volumio volumio[2070]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Dec 28 17:16:01 volumio volumio[2070]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Dec 28 17:16:01 volumio volumio[2070]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Dec 28 17:16:01 volumio volumio[2070]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Dec 28 17:16:01 volumio volumio[2070]: info: Spotify config file written Dec 28 17:16:01 volumio volumio[2070]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Dec 28 17:16:01 volumio volumio[2070]: LoungeScreenDisconnected { Dec 28 17:16:01 volumio volumio[2070]: AID: null, Dec 28 17:16:01 volumio volumio[2070]: name: 'loungeScreenDisconnected', Dec 28 17:16:01 volumio volumio[2070]: payload: {} Dec 28 17:16:01 volumio volumio[2070]: } Dec 28 17:16:01 volumio volumio[2070]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Dec 28 17:16:01 volumio volumio[2070]: Stack trace: Dec 28 17:16:01 volumio volumio[2070]: IncompleteAPIDataError: Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Dec 28 17:16:01 volumio volumio[2070]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Dec 28 17:16:01 volumio volumio[2070]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Dec 28 17:16:01 volumio volumio[2070]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Dec 28 17:16:01 volumio volumio[2070]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Dec 28 17:16:01 volumio volumio[2070]: (node:2070) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Dec 28 17:16:01 volumio volumio[2070]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: (node:2070) 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) Dec 28 17:16:01 volumio volumio[2070]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Dec 28 17:16:01 volumio volumio[2070]: LoungeScreenDisconnected { Dec 28 17:16:01 volumio volumio[2070]: AID: null, Dec 28 17:16:01 volumio volumio[2070]: name: 'loungeScreenDisconnected', Dec 28 17:16:01 volumio volumio[2070]: payload: {} Dec 28 17:16:01 volumio volumio[2070]: } Dec 28 17:16:01 volumio volumio[2070]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Dec 28 17:16:01 volumio volumio[2070]: Stack trace: Dec 28 17:16:01 volumio volumio[2070]: IncompleteAPIDataError: Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Dec 28 17:16:01 volumio volumio[2070]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Dec 28 17:16:01 volumio volumio[2070]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Dec 28 17:16:01 volumio volumio[2070]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Dec 28 17:16:01 volumio volumio[2070]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: (node:2070) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Dec 28 17:16:01 volumio volumio[2070]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: (node:2070) 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) Dec 28 17:16:01 volumio volumio[2070]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Dec 28 17:16:01 volumio volumio[2070]: LoungeScreenDisconnected { Dec 28 17:16:01 volumio volumio[2070]: AID: null, Dec 28 17:16:01 volumio volumio[2070]: name: 'loungeScreenDisconnected', Dec 28 17:16:01 volumio volumio[2070]: payload: {} Dec 28 17:16:01 volumio volumio[2070]: } Dec 28 17:16:01 volumio volumio[2070]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Dec 28 17:16:01 volumio volumio[2070]: Stack trace: Dec 28 17:16:01 volumio volumio[2070]: IncompleteAPIDataError: Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Dec 28 17:16:01 volumio volumio[2070]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Dec 28 17:16:01 volumio volumio[2070]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Dec 28 17:16:01 volumio volumio[2070]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Dec 28 17:16:01 volumio volumio[2070]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: (node:2070) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Dec 28 17:16:01 volumio volumio[2070]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: (node:2070) 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) Dec 28 17:16:01 volumio sudo[2333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 28 17:16:01 volumio sudo[2333]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:01 volumio volumio[2070]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Dec 28 17:16:01 volumio volumio[2070]: LoungeScreenDisconnected { Dec 28 17:16:01 volumio volumio[2070]: AID: null, Dec 28 17:16:01 volumio volumio[2070]: name: 'loungeScreenDisconnected', Dec 28 17:16:01 volumio volumio[2070]: payload: {} Dec 28 17:16:01 volumio volumio[2070]: } Dec 28 17:16:01 volumio volumio[2070]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Dec 28 17:16:01 volumio volumio[2070]: Stack trace: Dec 28 17:16:01 volumio volumio[2070]: IncompleteAPIDataError: Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Dec 28 17:16:01 volumio volumio[2070]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Dec 28 17:16:01 volumio volumio[2070]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Dec 28 17:16:01 volumio volumio[2070]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Dec 28 17:16:01 volumio volumio[2070]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: (node:2070) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Dec 28 17:16:01 volumio volumio[2070]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: (node:2070) 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) Dec 28 17:16:01 volumio volumio[2070]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 28 17:16:01 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:01 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:01 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:01 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:01 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:01 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:16:01 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:01 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:01 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:01 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:16:01 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:01 volumio volumio[2070]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Dec 28 17:16:01 volumio volumio[2070]: LoungeScreenDisconnected { Dec 28 17:16:01 volumio volumio[2070]: AID: null, Dec 28 17:16:01 volumio volumio[2070]: name: 'loungeScreenDisconnected', Dec 28 17:16:01 volumio volumio[2070]: payload: {} Dec 28 17:16:01 volumio volumio[2070]: } Dec 28 17:16:01 volumio volumio[2070]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Dec 28 17:16:01 volumio volumio[2070]: Stack trace: Dec 28 17:16:01 volumio volumio[2070]: IncompleteAPIDataError: Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Dec 28 17:16:01 volumio volumio[2070]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Dec 28 17:16:01 volumio volumio[2070]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Dec 28 17:16:01 volumio volumio[2070]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Dec 28 17:16:01 volumio volumio[2070]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: (node:2070) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Dec 28 17:16:01 volumio volumio[2070]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: (node:2070) 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) Dec 28 17:16:01 volumio volumio[2070]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Dec 28 17:16:01 volumio volumio[2070]: LoungeScreenDisconnected { Dec 28 17:16:01 volumio volumio[2070]: AID: null, Dec 28 17:16:01 volumio volumio[2070]: name: 'loungeScreenDisconnected', Dec 28 17:16:01 volumio volumio[2070]: payload: {} Dec 28 17:16:01 volumio volumio[2070]: } Dec 28 17:16:01 volumio volumio[2070]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Dec 28 17:16:01 volumio volumio[2070]: Stack trace: Dec 28 17:16:01 volumio volumio[2070]: IncompleteAPIDataError: Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Dec 28 17:16:01 volumio volumio[2070]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Dec 28 17:16:01 volumio volumio[2070]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Dec 28 17:16:01 volumio volumio[2070]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Dec 28 17:16:01 volumio volumio[2070]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: (node:2070) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Dec 28 17:16:01 volumio volumio[2070]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: (node:2070) 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) Dec 28 17:16:01 volumio volumio[2070]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Dec 28 17:16:01 volumio volumio[2070]: LoungeScreenDisconnected { Dec 28 17:16:01 volumio volumio[2070]: AID: null, Dec 28 17:16:01 volumio volumio[2070]: name: 'loungeScreenDisconnected', Dec 28 17:16:01 volumio volumio[2070]: payload: {} Dec 28 17:16:01 volumio volumio[2070]: } Dec 28 17:16:01 volumio volumio[2070]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Dec 28 17:16:01 volumio volumio[2070]: Stack trace: Dec 28 17:16:01 volumio volumio[2070]: IncompleteAPIDataError: Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Dec 28 17:16:01 volumio volumio[2070]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Dec 28 17:16:01 volumio volumio[2070]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Dec 28 17:16:01 volumio volumio[2070]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Dec 28 17:16:01 volumio volumio[2070]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: (node:2070) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Dec 28 17:16:01 volumio volumio[2070]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: (node:2070) 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) Dec 28 17:16:01 volumio volumio[2070]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Dec 28 17:16:01 volumio volumio[2070]: LoungeScreenDisconnected { Dec 28 17:16:01 volumio volumio[2070]: AID: null, Dec 28 17:16:01 volumio volumio[2070]: name: 'loungeScreenDisconnected', Dec 28 17:16:01 volumio volumio[2070]: payload: {} Dec 28 17:16:01 volumio volumio[2070]: } Dec 28 17:16:01 volumio volumio[2070]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Dec 28 17:16:01 volumio volumio[2070]: Stack trace: Dec 28 17:16:01 volumio volumio[2070]: IncompleteAPIDataError: Missing data required to construct query string from bind params Dec 28 17:16:01 volumio volumio[2070]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Dec 28 17:16:01 volumio volumio[2070]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Dec 28 17:16:01 volumio volumio[2070]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Dec 28 17:16:01 volumio volumio[2070]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Dec 28 17:16:01 volumio volumio[2070]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: (node:2070) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Dec 28 17:16:01 volumio volumio[2070]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:01 volumio volumio[2070]: (node:2070) 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: 23) Dec 28 17:16:01 volumio volumio[2070]: info: VolumeController:: Volume=0 Mute =false Dec 28 17:16:01 volumio volumio[2070]: info: CoreCommandRouter::volumioGetState Dec 28 17:16:01 volumio volumio[2070]: info: CorePlayQueue::getTrack 0 Dec 28 17:16:01 volumio volumio[2070]: info: CoreStateMachine::pushState Dec 28 17:16:01 volumio volumio[2070]: info: CorePlayQueue::getTrack 0 Dec 28 17:16:01 volumio volumio[2070]: info: CoreCommandRouter::volumioPushState Dec 28 17:16:01 volumio volumio[2070]: info: [squeezelite_mc] Server discovery started Dec 28 17:16:01 volumio volumio[2070]: info: [squeezelite_mc] Player finder started Dec 28 17:16:01 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:01 volumio volumio[2070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 17:16:01 volumio volumio[2070]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 28 17:16:01 volumio volumio[2070]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 17:16:01 volumio volumio[2070]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 28 17:16:01 volumio volumio[2070]: at doSend (dgram.js:692:16) Dec 28 17:16:01 volumio volumio[2070]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 28 17:16:01 volumio volumio[2070]: at afterDns (dgram.js:638:5) Dec 28 17:16:01 volumio volumio[2070]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 28 17:16:01 volumio volumio[2070]: errno: -101, Dec 28 17:16:01 volumio volumio[2070]: code: 'ENETUNREACH', Dec 28 17:16:01 volumio volumio[2070]: syscall: 'send', Dec 28 17:16:01 volumio volumio[2070]: address: '255.255.255.255', Dec 28 17:16:01 volumio volumio[2070]: port: 3483 Dec 28 17:16:01 volumio volumio[2070]: } Dec 28 17:16:01 volumio volumio[2070]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 17:16:02 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 28 17:16:02 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Dec 28 17:16:02 volumio sudo[2354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-28 17:15 Dec 28 17:16:02 volumio sudo[2354]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:02 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Dec 28 17:16:02 volumio sudo[2354]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:03 volumio systemd[1]: mpd.service: Succeeded. Dec 28 17:16:03 volumio systemd[1]: Stopped Music Player Daemon. Dec 28 17:16:03 volumio sudo[2311]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:03 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 28 17:16:03 volumio sudo[2307]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:03 volumio sudo[2272]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:03 volumio sudo[2333]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:03 volumio systemd[1]: media-USB\x2dDisk.mount: Succeeded. Dec 28 17:16:03 volumio sudo[2305]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:03 volumio sudo[2263]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:03 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 28 17:16:03 volumio systemd[1]: Started dynamicswap service. Dec 28 17:16:03 volumio systemd[1]: Starting Music Player Daemon... Dec 28 17:16:03 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 28 17:16:03 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Dec 28 17:16:03 volumio systemd[1]: Stopped Volumio Backend Module. Dec 28 17:16:03 volumio systemd[1]: Started Volumio Backend Module. Dec 28 17:16:03 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 28 17:16:03 volumio nmbd[745]: [2025/12/28 17:16:03.349084, 0] ../source3/libsmb/nmblib.c:917(send_udp) Dec 28 17:16:03 volumio nmbd[745]: Packet send failed to 192.168.1.255(138) ERRNO=Network is unreachable Dec 28 17:16:03 volumio sudo[2364]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 17:16:03 volumio sudo[2364]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:03 volumio sudo[2364]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:04 volumio volumio-remote-updater[588]: [2025-12-28 17:16:04] [info] asio async_connect error: system:111 (Connection refused) Dec 28 17:16:04 volumio volumio-remote-updater[588]: [2025-12-28 17:16:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 28 17:16:04 volumio volumio-remote-updater[588]: [2025-12-28 17:16:04] [error] handle_connect error: Underlying Transport Error Dec 28 17:16:05 volumio mpd[2378]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 28 17:16:05 volumio mpd[2378]: output: No 'audio_output' defined in config file Dec 28 17:16:05 volumio mpd[2378]: output: Successfully detected a sndio audio device Dec 28 17:16:05 volumio mpd[2378]: zeroconf: No global port, disabling zeroconf Dec 28 17:16:05 volumio systemd[1]: Started Music Player Daemon. Dec 28 17:16:06 volumio volumio[2365]: info: ------------------------------------------- Dec 28 17:16:06 volumio volumio[2365]: info: ----- Volumio3 ---- Dec 28 17:16:06 volumio volumio[2365]: info: ------------------------------------------- Dec 28 17:16:06 volumio volumio[2365]: info: ----- System startup ---- Dec 28 17:16:06 volumio volumio[2365]: info: ------------------------------------------- Dec 28 17:16:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Dec 28 17:16:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 28 17:16:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 28 17:16:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 28 17:16:07 volumio volumio[2365]: info: MYVOLUMIO Environment detected Dec 28 17:16:07 volumio volumio[2365]: info: Plugin folders cleanup Dec 28 17:16:07 volumio volumio[2365]: info: Scanning into folder /volumio/app/plugins/ Dec 28 17:16:07 volumio volumio[2365]: info: Scanning category audio_interface Dec 28 17:16:07 volumio volumio[2365]: info: Scanning category miscellanea Dec 28 17:16:07 volumio volumio[2365]: info: Scanning category music_service Dec 28 17:16:07 volumio volumio[2365]: info: Scanning category plugins.json Dec 28 17:16:07 volumio volumio[2365]: info: Scanning category system_controller Dec 28 17:16:07 volumio volumio[2365]: info: Scanning category user_interface Dec 28 17:16:07 volumio volumio[2365]: info: Scanning into folder /data/plugins/ Dec 28 17:16:07 volumio volumio[2365]: info: Scanning category audio_interface Dec 28 17:16:07 volumio volumio[2365]: info: Scanning category music_service Dec 28 17:16:07 volumio volumio[2365]: info: Scanning category system_controller Dec 28 17:16:07 volumio volumio[2365]: info: Scanning category user_interface Dec 28 17:16:07 volumio volumio[2365]: info: Plugin folders cleanup completed Dec 28 17:16:07 volumio volumio[2365]: info: ------------------------------------------- Dec 28 17:16:07 volumio volumio[2365]: info: ----- Core plugins startup ---- Dec 28 17:16:07 volumio volumio[2365]: info: ------------------------------------------- Dec 28 17:16:07 volumio volumio[2365]: info: Loading plugins from folder /volumio/app/plugins/ Dec 28 17:16:07 volumio volumio[2365]: info: Adding plugin upnp to MyMusic Plugins Dec 28 17:16:07 volumio volumio[2365]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 28 17:16:07 volumio volumio[2365]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 28 17:16:07 volumio volumio[2365]: info: Loading plugins from folder /data/plugins/ Dec 28 17:16:07 volumio volumio[2365]: info: Loading plugin "system"... Dec 28 17:16:07 volumio volumio[2365]: info: Loading plugin "appearance"... Dec 28 17:16:08 volumio wpa_supplicant[918]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Synology Router" Dec 28 17:16:08 volumio wpa_supplicant[918]: wlan0: Trying to associate with SSID 'Synology Router' Dec 28 17:16:09 volumio volumio[2365]: info: Loading plugin "network"... Dec 28 17:16:09 volumio wpa_supplicant[918]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:11:32:a5:13:5e status_code=16 Dec 28 17:16:09 volumio wpa_supplicant[918]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Synology Router" auth_failures=2 duration=23 reason=CONN_FAILED Dec 28 17:16:09 volumio volumio[2365]: info: Refreshing Cached IP Addresses Dec 28 17:16:09 volumio sudo[2440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 28 17:16:09 volumio sudo[2440]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:09 volumio sudo[2442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 28 17:16:09 volumio sudo[2442]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:09 volumio volumio[2365]: info: Loading plugin "services"... Dec 28 17:16:09 volumio sudo[2440]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:09 volumio volumio[2365]: info: Loading plugin "alsa_controller"... Dec 28 17:16:09 volumio sudo[2442]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:09 volumio sudo[2451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 28 17:16:09 volumio sudo[2451]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:09 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 17:16:09 volumio volumio[2365]: info: Loading plugin "wizard"... Dec 28 17:16:09 volumio volumio[2365]: info: Loading plugin "networkfs"... Dec 28 17:16:09 volumio volumio[2365]: info: Cannot mount NAS music at system boot, trial number 1 ,retrying in 5 seconds Dec 28 17:16:09 volumio volumio[2365]: info: Starting Udev Watcher for removable devices Dec 28 17:16:09 volumio volumio[2365]: info: Ignoring mount for partition: boot Dec 28 17:16:09 volumio volumio[2365]: info: Ignoring mount for partition: volumio Dec 28 17:16:09 volumio volumio[2365]: info: Ignoring mount for partition: volumio_data Dec 28 17:16:09 volumio go-librespot[25202]: time="2025-12-28T17:16:09+09:00" level=error msg="did not receive last pong from dealer, 60s passed" Dec 28 17:16:09 volumio volumio[2365]: info: Mounting Device USB-Disk Dec 28 17:16:09 volumio sudo[2471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/USB-Disk -o noatime Dec 28 17:16:09 volumio sudo[2471]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:10 volumio sudo[2471]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:10 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 17:16:10 volumio volumio[2365]: info: Loading plugin "volumio_command_line_client"... Dec 28 17:16:10 volumio volumio[2365]: info: Loading plugin "upnp"... Dec 28 17:16:10 volumio volumio[2365]: info: [1766909770126] Starting Upmpd Daemon Dec 28 17:16:10 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 17:16:10 volumio volumio[2365]: info: Loading plugin "my_music"... Dec 28 17:16:10 volumio volumio[2365]: info: Loading plugin "mpd"... Dec 28 17:16:10 volumio volumio[2365]: info: Loading plugin "upnp_browser"... Dec 28 17:16:11 volumio volumio[2365]: info: Starting UPNP Browser Dec 28 17:16:11 volumio volumio[2365]: info: Loading plugin "alarm-clock"... Dec 28 17:16:11 volumio volumio[2365]: info: Loading plugin "airplay_emulation"... Dec 28 17:16:11 volumio volumio[2365]: info: Starting Shairport Sync Dec 28 17:16:11 volumio volumio[2365]: info: Loading plugin "last_100"... Dec 28 17:16:11 volumio volumio[2365]: info: Loading plugin "webradio"... Dec 28 17:16:11 volumio volumio[2365]: info: Loading plugin "i2s_dacs"... Dec 28 17:16:11 volumio volumio[2365]: info: Loading plugin "volumiodiscovery"... Dec 28 17:16:11 volumio volumio[2365]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 28 17:16:11 volumio volumio[2365]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 17:16:11 volumio volumio[2365]: *** WARNING *** For more information see Dec 28 17:16:11 volumio volumio[2365]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 28 17:16:11 volumio node[2365]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 28 17:16:11 volumio volumio[2365]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 17:16:11 volumio volumio[2365]: *** WARNING *** For more information see Dec 28 17:16:11 volumio node[2365]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 17:16:11 volumio node[2365]: *** WARNING *** For more information see Dec 28 17:16:11 volumio node[2365]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 28 17:16:11 volumio node[2365]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 17:16:11 volumio node[2365]: *** WARNING *** For more information see Dec 28 17:16:11 volumio volumio[2365]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 28 17:16:11 volumio volumio[2365]: info: Discovery: Started advertising with name: Volumio Dec 28 17:16:11 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 17:16:11 volumio volumio[2365]: info: Plugin bandcamp is not enabled Dec 28 17:16:11 volumio volumio[2365]: info: Plugin calmradio is not enabled Dec 28 17:16:11 volumio volumio[2365]: info: Plugin jellyfin is not enabled Dec 28 17:16:11 volumio volumio[2365]: info: Loading plugin "jellyfin_server"... Dec 28 17:16:12 volumio volumio[2365]: info: Plugin mixcloud is not enabled Dec 28 17:16:12 volumio volumio[2365]: info: Plugin soundcloud is not enabled Dec 28 17:16:12 volumio volumio[2365]: info: Loading plugin "spop"... Dec 28 17:16:12 volumio sudo[2451]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:13 volumio volumio[2365]: info: Loading plugin "squeezelite_mc"... Dec 28 17:16:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Dec 28 17:16:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 28 17:16:14 volumio go-librespot[25202]: time="2025-12-28T17:16:14+09:00" level=error msg="failed receiving dealer message" error="failed to get reader: WebSocket closed: sent close frame: status = StatusServiceRestart and reason = \"\"" Dec 28 17:16:14 volumio go-librespot[25202]: time="2025-12-28T17:16:14+09:00" level=warning msg="failed fetching new endpoint for dealer" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=dealer\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 28 17:16:14 volumio volumio-remote-updater[588]: [2025-12-28 17:16:14] [connect] Successful connection Dec 28 17:16:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 28 17:16:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 28 17:16:14 volumio go-librespot[25202]: time="2025-12-28T17:16:14+09:00" level=warning msg="failed fetching new endpoint for dealer" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=dealer\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 28 17:16:14 volumio volumio[2365]: info: Loading plugin "youtube2"... Dec 28 17:16:15 volumio go-librespot[25202]: time="2025-12-28T17:16:15+09:00" level=warning msg="failed fetching new endpoint for dealer" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=dealer\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 28 17:16:16 volumio volumio[2365]: info: Loading plugin "ytcr"... Dec 28 17:16:17 volumio go-librespot[25202]: time="2025-12-28T17:16:17+09:00" level=warning msg="failed fetching new endpoint for dealer" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=dealer\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 28 17:16:19 volumio go-librespot[25202]: time="2025-12-28T17:16:19+09:00" level=warning msg="failed fetching new endpoint for dealer" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=dealer\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 28 17:16:19 volumio volumio[2365]: info: Loading plugin "ytmusic"... Dec 28 17:16:21 volumio volumio[2365]: info: Loading plugin "autostart"... Dec 28 17:16:21 volumio volumio[2365]: info: Applying required configuration parameters for plugin autostart Dec 28 17:16:21 volumio volumio[2365]: info: AutoStart - onVolumioStart - read config.json Dec 28 17:16:21 volumio volumio[2365]: info: Loading plugin "now_playing"... Dec 28 17:16:21 volumio go-librespot[25202]: time="2025-12-28T17:16:21+09:00" level=warning msg="failed fetching new endpoint for dealer" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=dealer\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 28 17:16:23 volumio volumio[2365]: info: Loading plugin "outputs"... Dec 28 17:16:23 volumio volumio[2365]: info: Loading plugin "albumart"... Dec 28 17:16:23 volumio volumio[2365]: info: Plugin example_plugin is not enabled Dec 28 17:16:23 volumio volumio[2365]: info: Loading plugin "inputs"... Dec 28 17:16:23 volumio volumio[2365]: info: Loading plugin "updater_comm"... Dec 28 17:16:24 volumio volumio[2365]: info: Plugin mpdemulation is not enabled Dec 28 17:16:24 volumio volumio[2365]: info: Loading plugin "rest_api"... Dec 28 17:16:24 volumio volumio[2365]: info: Loading plugin "websocket"... Dec 28 17:16:24 volumio volumio[2365]: info: Starting Socket.io Server version 2.3.0 Dec 28 17:16:24 volumio volumio[2365]: info: Loading plugin "fusiondsp"... Dec 28 17:16:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Dec 28 17:16:24 volumio volumio[2365]: Forking 3 albumart workers Dec 28 17:16:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 28 17:16:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 28 17:16:24 volumio volumio[2365]: info: Applying required configuration parameters for plugin fusiondsp Dec 28 17:16:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 28 17:16:24 volumio volumio[2365]: info: Plugin 80s80s is not enabled Dec 28 17:16:24 volumio volumio[2365]: info: Plugin RoonBridge is not enabled Dec 28 17:16:24 volumio volumio[2365]: info: Loading plugin "jp_radio"... Dec 28 17:16:25 volumio volumio[2365]: Starting albumart workers Dec 28 17:16:25 volumio volumio[2365]: Starting albumart workers Dec 28 17:16:25 volumio volumio[2365]: Starting albumart workers Dec 28 17:16:26 volumio go-librespot[25202]: time="2025-12-28T17:16:26+09:00" level=warning msg="failed fetching new endpoint for dealer" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=dealer\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 28 17:16:26 volumio volumio[2365]: info: Loading plugin "lms"... Dec 28 17:16:27 volumio volumio[2365]: info: Loading plugin "minidlna"... Dec 28 17:16:27 volumio volumio[2365]: info: Applying required configuration parameters for plugin minidlna Dec 28 17:16:27 volumio volumio[2365]: info: Plugin motherearthradio is not enabled Dec 28 17:16:27 volumio volumio[2365]: info: Plugin pandora is not enabled Dec 28 17:16:27 volumio volumio[2365]: info: Plugin personal_radio is not enabled Dec 28 17:16:27 volumio volumio[2365]: info: Loading plugin "podcast"... Dec 28 17:16:27 volumio volumio[2365]: info: ControllerPodcast::constructor Dec 28 17:16:27 volumio volumio[2365]: info: Plugin rad357pl is not enabled Dec 28 17:16:27 volumio volumio[2365]: info: Plugin radio_paradise is not enabled Dec 28 17:16:27 volumio volumio[2365]: info: Plugin swarmstation is not enabled Dec 28 17:16:27 volumio volumio[2365]: info: Plugin volusonic is not enabled Dec 28 17:16:27 volumio volumio[2365]: info: Loading plugin "backup_restore"... Dec 28 17:16:28 volumio volumio[2365]: info: Applying required configuration parameters for plugin backup_restore Dec 28 17:16:28 volumio volumio[2365]: info: Loading plugin "Systeminfo"... Dec 28 17:16:28 volumio volumio[2365]: info: Plugin randomizer is not enabled Dec 28 17:16:28 volumio volumio[2365]: info: Loading plugin "touch_display"... Dec 28 17:16:28 volumio volumio[2365]: info: Applying required configuration parameters for plugin touch_display Dec 28 17:16:28 volumio volumio[2365]: info: Loading i18n strings for locale ja Dec 28 17:16:28 volumio volumio[2365]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Dec 28 17:16:29 volumio volumio[2365]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 28 17:16:29 volumio volumio[2365]: Updating browse sources language Dec 28 17:16:29 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 17:16:29 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 17:16:29 volumio volumio[2365]: info: CoreCommandRouter::initPlayerControls Dec 28 17:16:29 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:29 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:29 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:29 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:29 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:29 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:29 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:29 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:29 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 17:16:29 volumio volumio[2365]: Express server listening on port 3000 Dec 28 17:16:29 volumio volumio[2365]: [Metrics] WebUI: 23s 992.06ms Dec 28 17:16:29 volumio volumio[2365]: info: CoreStateMachine::resetVolumioState Dec 28 17:16:29 volumio volumio[2365]: info: CoreStateMachine::getcurrentVolume Dec 28 17:16:29 volumio volumio[2365]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 17:16:29 volumio volumio[2365]: info: Cannot mount NAS music at system boot, trial number 2 ,retrying in 5 seconds Dec 28 17:16:29 volumio volumio-remote-updater[588]: [2025-12-28 17:16:29] [connect] Successful connection Dec 28 17:16:29 volumio volumio[2365]: info: Volumio Network Manager: Network status updated: 1 Dec 28 17:16:29 volumio sudo[2571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 28 17:16:29 volumio sudo[2569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 28 17:16:29 volumio sudo[2569]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:29 volumio sudo[2571]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:29 volumio sudo[2569]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:29 volumio sudo[2571]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:29 volumio volumio[2365]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 28 17:16:29 volumio volumio[2365]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Dec 28 17:16:29 volumio volumio[2365]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 28 17:16:29 volumio volumio[2365]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Dec 28 17:16:30 volumio volumio[2365]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 28 17:16:30 volumio volumio[2365]: 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: 6 Dec 28 17:16:30 volumio volumio[2365]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 28 17:16:30 volumio volumio-remote-updater[588]: [2025-12-28 17:16:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766909789 101 Dec 28 17:16:30 volumio volumio[2365]: info: Reloading queue from file Dec 28 17:16:30 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:16:30 volumio volumio[2365]: info: CoreStateMachine::setRepeat null single undefined Dec 28 17:16:30 volumio volumio[2365]: info: CoreStateMachine::pushState Dec 28 17:16:30 volumio volumio[2365]: info: CorePlayQueue::getTrack 0 Dec 28 17:16:30 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 17:16:30 volumio volumio[2365]: info: CoreCommandRouter::volumioPushState Dec 28 17:16:30 volumio volumio[2365]: info: CoreStateMachine::setRandom null Dec 28 17:16:30 volumio volumio[2365]: info: CoreStateMachine::pushState Dec 28 17:16:30 volumio volumio[2365]: info: CorePlayQueue::getTrack 0 Dec 28 17:16:30 volumio volumio[2365]: info: CoreCommandRouter::volumioPushState Dec 28 17:16:30 volumio volumio[2365]: info: Setting Device type: Raspberry PI Dec 28 17:16:30 volumio volumio[2365]: info: Completed loading Core Plugins Dec 28 17:16:30 volumio volumio[2365]: info: Preparing to generate the ALSA configuration file Dec 28 17:16:30 volumio volumio[2365]: info: VolumeController:: Volume=0 Mute =false Dec 28 17:16:30 volumio volumio[2365]: info: CoreStateMachine::pushState Dec 28 17:16:30 volumio volumio[2365]: info: CorePlayQueue::getTrack 0 Dec 28 17:16:30 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 17:16:30 volumio volumio[2365]: info: CoreCommandRouter::volumioPushState Dec 28 17:16:30 volumio volumio[2365]: info: CoreStateMachine::updateTrackBlock Dec 28 17:16:30 volumio volumio[2365]: info: CorePlayQueue::getTrackBlock Dec 28 17:16:30 volumio volumio[2365]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 17:16:30 volumio volumio[2365]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 28 17:16:30 volumio volumio[2365]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 28 17:16:30 volumio volumio[2365]: info: Reading ALSA contributions from plugins. Dec 28 17:16:30 volumio sudo[2582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 28 17:16:30 volumio sudo[2582]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:30 volumio volumio[2365]: info: Discovery: adding 312eef85-7f60-4bdb-9865-815eb2763960 Dec 28 17:16:30 volumio volumio[2365]: info: Discovery: Found device Volumio Dec 28 17:16:30 volumio volumio[2365]: info: CoreCommandRouter::volumioGetState Dec 28 17:16:30 volumio volumio[2365]: info: CorePlayQueue::getTrack 0 Dec 28 17:16:30 volumio sudo[2582]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:30 volumio volumio[2365]: info: Upmpdcli Daemon Started Dec 28 17:16:30 volumio volumio[2365]: info: Asound.conf file unchanged, so no further update is needed Dec 28 17:16:30 volumio volumio[2365]: info: Output device has changed, restarting MPD Dec 28 17:16:30 volumio sudo[2586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 17:16:30 volumio sudo[2586]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:30 volumio volumio[2365]: info: Output device has changed, restarting Shairport Sync Dec 28 17:16:30 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:30 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:16:30 volumio sudo[2586]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:30 volumio sudo[2588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 17:16:30 volumio sudo[2588]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:30 volumio systemd[1]: Stopping Music Player Daemon... Dec 28 17:16:30 volumio volumio[2365]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 17:16:30 volumio volumio[2365]: info: ___________ START PLUGINS ___________ Dec 28 17:16:30 volumio volumio[2365]: info: ControllerMpd::onStart: Initializing MPD Dec 28 17:16:30 volumio volumio[2365]: info: Creating MPD Configuration file Dec 28 17:16:30 volumio systemd[1]: mpd.service: Succeeded. Dec 28 17:16:30 volumio systemd[1]: Stopped Music Player Daemon. Dec 28 17:16:30 volumio sudo[2595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 17:16:30 volumio sudo[2595]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:30 volumio sudo[2595]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:30 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 17:16:30 volumio volumio[2365]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 17:16:30 volumio volumio[2365]: info: [1766909790895] CoreMusicLibrary::Adding element DLNAサーバー Dec 28 17:16:30 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 17:16:30 volumio sudo[2598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 17:16:30 volumio volumio[2365]: info: UPNP Browser: Client initialized successfully Dec 28 17:16:30 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:30 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:16:30 volumio systemd[1]: Starting Music Player Daemon... Dec 28 17:16:30 volumio sudo[2598]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:30 volumio volumio[2365]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 17:16:30 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:30 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:16:31 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 28 17:16:31 volumio systemd[1]: mpd.service: Succeeded. Dec 28 17:16:31 volumio systemd[1]: Stopped Music Player Daemon. Dec 28 17:16:31 volumio systemd[1]: Starting Music Player Daemon... Dec 28 17:16:31 volumio volumio[2365]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 17:16:31 volumio volumio[2365]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 17:16:31 volumio volumio[2365]: info: [1766909791074] CoreMusicLibrary::Adding element Last_100 Dec 28 17:16:31 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 17:16:31 volumio volumio[2365]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 17:16:31 volumio volumio[2365]: info: [1766909791078] CoreMusicLibrary::Adding element Webradio Dec 28 17:16:31 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 17:16:31 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 17:16:31 volumio volumio[2365]: info: Initializing BBC Radios Dec 28 17:16:31 volumio sudo[2604]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 17:16:31 volumio sudo[2604]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:31 volumio sudo[2604]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:31 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 17:16:31 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:16:31 volumio volumio[2365]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl start jellyfin || true Dec 28 17:16:31 volumio sudo[2621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start jellyfin Dec 28 17:16:31 volumio sudo[2621]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:31 volumio volumio[2365]: info: Creating Spotify config file Dec 28 17:16:31 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:31 volumio sudo[2621]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:31 volumio volumio[2365]: info: [squeezelite_mc] Starting proxy server... Dec 28 17:16:31 volumio volumio[2365]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 17:16:31 volumio volumio[2365]: info: [1766909791509] CoreMusicLibrary::Adding element YouTube2 Dec 28 17:16:31 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 17:16:31 volumio volumio[2365]: Cannot find translation for source YouTube2 Dec 28 17:16:31 volumio volumio[2365]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 17:16:31 volumio volumio[2365]: info: [1766909791597] CoreMusicLibrary::Adding element YouTube Music Dec 28 17:16:31 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 17:16:31 volumio volumio[2365]: Cannot find translation for source YouTube2 Dec 28 17:16:31 volumio volumio[2365]: Cannot find translation for source YouTube Music Dec 28 17:16:31 volumio volumio[2365]: info: AutoStart - onStart Dec 28 17:16:31 volumio volumio[2365]: info: [now-playing] ConfigUpdater: config is up to date. Dec 28 17:16:31 volumio volumio[2365]: info: Loading i18n strings for locale ja Dec 28 17:16:31 volumio volumio[2365]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Dec 28 17:16:31 volumio volumio[2365]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 28 17:16:31 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 28 17:16:31 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:31 volumio volumio[2365]: info: FusionDsp - mixtype--------------------- Hardware Dec 28 17:16:31 volumio volumio[2365]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 17:16:31 volumio volumio[2365]: info: [1766909791822] CoreMusicLibrary::Adding element RADIKO Dec 28 17:16:31 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 17:16:31 volumio volumio[2365]: Cannot find translation for source YouTube2 Dec 28 17:16:31 volumio volumio[2365]: Cannot find translation for source YouTube Music Dec 28 17:16:31 volumio volumio[2365]: Cannot find translation for source RADIKO Dec 28 17:16:31 volumio volumio[2365]: info: Loading i18n strings for locale ja Dec 28 17:16:31 volumio sudo[2624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Dec 28 17:16:31 volumio volumio[2365]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Dec 28 17:16:31 volumio sudo[2624]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:31 volumio volumio[2365]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 28 17:16:31 volumio volumio[2365]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 17:16:31 volumio volumio[2365]: info: [1766909791947] CoreMusicLibrary::Adding element Podcast Dec 28 17:16:31 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 17:16:31 volumio volumio[2365]: Cannot find translation for source YouTube2 Dec 28 17:16:31 volumio volumio[2365]: Cannot find translation for source YouTube Music Dec 28 17:16:31 volumio volumio[2365]: Cannot find translation for source RADIKO Dec 28 17:16:31 volumio volumio[2365]: Cannot find translation for source Podcast Dec 28 17:16:31 volumio volumio[2365]: info: Loading i18n strings for locale ja Dec 28 17:16:31 volumio systemd[1]: Starting Logitech Media Server Daemon... Dec 28 17:16:31 volumio volumio[2365]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Dec 28 17:16:31 volumio volumio[2365]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 28 17:16:32 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 17:16:32 volumio volumio[2365]: info: Volumio Calling Home Dec 28 17:16:32 volumio systemd[1]: Started Logitech Media Server Daemon. Dec 28 17:16:32 volumio systemd[2630]: logitechmediaserver.service: Failed to execute command: No such file or directory Dec 28 17:16:32 volumio systemd[2630]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory Dec 28 17:16:32 volumio systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC Dec 28 17:16:32 volumio systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Dec 28 17:16:32 volumio sudo[2624]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:32 volumio go-librespot[25202]: time="2025-12-28T17:16:32+09:00" level=warning msg="failed fetching new endpoint for dealer" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=dealer\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 28 17:16:32 volumio volumio[2365]: info: [squeezelite_mc] Proxy server started on port 37583 Dec 28 17:16:32 volumio volumio[2365]: info: Preparing to generate the ALSA configuration file Dec 28 17:16:32 volumio sudo[2634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Dec 28 17:16:32 volumio sudo[2634]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:32 volumio sudo[2637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Dec 28 17:16:32 volumio sudo[2637]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:32 volumio systemd[1]: Reloading. Dec 28 17:16:32 volumio sudo[2641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 28 17:16:32 volumio sudo[2641]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:33 volumio volumio[2365]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 17:16:33 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 28 17:16:33 volumio volumio[2365]: info: [now-playing] App is listening on port 4004. Dec 28 17:16:33 volumio volumio[2365]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Dec 28 17:16:33 volumio volumio[2365]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Dec 28 17:16:33 volumio volumio[2365]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 28 17:16:33 volumio volumio[2365]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 28 17:16:33 volumio volumio[2365]: info: Reading ALSA contributions from plugins. Dec 28 17:16:33 volumio volumio[2365]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Dec 28 17:16:33 volumio volumio[2365]: error: [yt-cast-receiver] (YouTube) Failed to obtain lounge token with screen Id from stored MDX context (gutves0nlv0b580t004ki60etm): Dec 28 17:16:33 volumio volumio[2365]: (ConnectionError) (YouTube) Connection error in getting lounge token Dec 28 17:16:33 volumio volumio[2365]: Error info: { Dec 28 17:16:33 volumio volumio[2365]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Dec 28 17:16:33 volumio volumio[2365]: } Dec 28 17:16:33 volumio volumio[2365]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo ENOTFOUND www.youtube.com Dec 28 17:16:33 volumio volumio[2365]: Stack trace: Dec 28 17:16:33 volumio volumio[2365]: ConnectionError: (YouTube) Connection error in getting lounge token Dec 28 17:16:33 volumio volumio[2365]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Dec 28 17:16:33 volumio volumio[2365]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:33 volumio volumio[2365]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Dec 28 17:16:33 volumio volumio[2365]: at async Promise.all (index 0) Dec 28 17:16:33 volumio volumio[2365]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Dec 28 17:16:33 volumio volumio[2365]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Dec 28 17:16:33 volumio volumio[2365]: Going to generate fresh screen Id and try again... Dec 28 17:16:33 volumio volumio[2365]: error: [yt-cast-receiver] (YouTube Music) Failed to obtain lounge token with screen Id from stored MDX context (of0ds3c7jitaldchtmu7eq2jtj): Dec 28 17:16:33 volumio volumio[2365]: (ConnectionError) (YouTube Music) Connection error in getting lounge token Dec 28 17:16:33 volumio volumio[2365]: Error info: { Dec 28 17:16:33 volumio volumio[2365]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Dec 28 17:16:33 volumio volumio[2365]: } Dec 28 17:16:33 volumio volumio[2365]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo ENOTFOUND www.youtube.com Dec 28 17:16:33 volumio volumio[2365]: Stack trace: Dec 28 17:16:33 volumio volumio[2365]: ConnectionError: (YouTube Music) Connection error in getting lounge token Dec 28 17:16:33 volumio volumio[2365]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Dec 28 17:16:33 volumio volumio[2365]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:33 volumio volumio[2365]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Dec 28 17:16:33 volumio volumio[2365]: at async Promise.all (index 1) Dec 28 17:16:33 volumio volumio[2365]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Dec 28 17:16:33 volumio volumio[2365]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Dec 28 17:16:33 volumio volumio[2365]: Going to generate fresh screen Id and try again... Dec 28 17:16:33 volumio volumio[2365]: error: JP_Radio::premium account login error Dec 28 17:16:33 volumio volumio[2365]: info: JP_Radio::premium account not set Dec 28 17:16:33 volumio volumio[2365]: (node:2365) UnhandledPromiseRejectionWarning: TypeError: Expected value which is `predicate returns truthy for any value`, received values of types `null`. Dec 28 17:16:33 volumio volumio[2365]: at assertType (/data/plugins/music_service/jp_radio/node_modules/@sindresorhus/is/dist/index.js:303:15) Dec 28 17:16:33 volumio volumio[2365]: at Object.any (/data/plugins/music_service/jp_radio/node_modules/@sindresorhus/is/dist/index.js:402:16) Dec 28 17:16:33 volumio volumio[2365]: at normalizeArguments (/data/plugins/music_service/jp_radio/node_modules/got/dist/source/core/index.js:389:21) Dec 28 17:16:33 volumio volumio[2365]: at got (/data/plugins/music_service/jp_radio/node_modules/got/dist/source/create.js:112:39) Dec 28 17:16:33 volumio volumio[2365]: at Radiko.#auth1 (/data/plugins/music_service/jp_radio/lib/radiko.js:157:28) Dec 28 17:16:33 volumio volumio[2365]: at Radiko.#getToken (/data/plugins/music_service/jp_radio/lib/radiko.js:76:41) Dec 28 17:16:33 volumio volumio[2365]: at Radiko.init (/data/plugins/music_service/jp_radio/lib/radiko.js:67:53) Dec 28 17:16:33 volumio volumio[2365]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:33 volumio volumio[2365]: at async JpRadio.#init (/data/plugins/music_service/jp_radio/lib/radio.js:102:5) Dec 28 17:16:33 volumio volumio[2365]: at async /data/plugins/music_service/jp_radio/lib/radio.js:74:7 Dec 28 17:16:33 volumio volumio[2365]: (Use `node --trace-warnings ...` to show where the warning was created) Dec 28 17:16:33 volumio volumio[2365]: (node:2365) 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: 3) Dec 28 17:16:33 volumio volumio[2365]: (node:2365) [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. Dec 28 17:16:33 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 28 17:16:33 volumio volumio[2365]: info: minidlna: Found miniDLNA version 1.2.1 Dec 28 17:16:33 volumio volumio[2365]: info: minidlna: Starting minidlna.service Dec 28 17:16:33 volumio volumio[2365]: info: logitechmediaserver started Dec 28 17:16:33 volumio volumio[2365]: info: MPD Permissions set Dec 28 17:16:33 volumio volumio[2365]: info: MPD Permissions set Dec 28 17:16:33 volumio volumio[2365]: info: VolumeController:: Volume=0 Mute =false Dec 28 17:16:33 volumio volumio[2365]: info: CoreCommandRouter::volumioGetState Dec 28 17:16:33 volumio volumio[2365]: info: CorePlayQueue::getTrack 0 Dec 28 17:16:33 volumio volumio[2365]: info: CoreStateMachine::pushState Dec 28 17:16:33 volumio volumio[2365]: info: CorePlayQueue::getTrack 0 Dec 28 17:16:33 volumio volumio[2365]: info: CoreCommandRouter::volumioPushState Dec 28 17:16:33 volumio sudo[2660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Dec 28 17:16:33 volumio sudo[2660]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:33 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Dec 28 17:16:33 volumio volumio[2365]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Dec 28 17:16:33 volumio volumio[2365]: LoungeScreenDisconnected { Dec 28 17:16:33 volumio volumio[2365]: AID: null, Dec 28 17:16:33 volumio volumio[2365]: name: 'loungeScreenDisconnected', Dec 28 17:16:33 volumio volumio[2365]: payload: {} Dec 28 17:16:33 volumio volumio[2365]: } Dec 28 17:16:33 volumio volumio[2365]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Dec 28 17:16:33 volumio volumio[2365]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Dec 28 17:16:33 volumio volumio[2365]: Stack trace: Dec 28 17:16:33 volumio volumio[2365]: IncompleteAPIDataError: Missing data required to construct query string from bind params Dec 28 17:16:33 volumio volumio[2365]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Dec 28 17:16:33 volumio volumio[2365]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Dec 28 17:16:33 volumio volumio[2365]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Dec 28 17:16:33 volumio volumio[2365]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Dec 28 17:16:33 volumio volumio[2365]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Dec 28 17:16:33 volumio volumio[2365]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Dec 28 17:16:33 volumio volumio[2365]: at new Promise () Dec 28 17:16:33 volumio volumio[2365]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Dec 28 17:16:33 volumio volumio[2365]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Dec 28 17:16:33 volumio volumio[2365]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:207:26) Dec 28 17:16:33 volumio volumio[2365]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:33 volumio volumio[2365]: at async Promise.all (index 0) Dec 28 17:16:33 volumio volumio[2365]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Dec 28 17:16:33 volumio volumio[2365]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Dec 28 17:16:33 volumio volumio[2365]: error: [yt-cast-receiver] Failed to start receiver: Dec 28 17:16:33 volumio volumio[2365]: (AppError) Failed to start YouTubeApp Dec 28 17:16:33 volumio volumio[2365]: --->(SessionError) (YouTube) Failed to establish session Dec 28 17:16:33 volumio volumio[2365]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Dec 28 17:16:33 volumio volumio[2365]: Error info: { Dec 28 17:16:33 volumio volumio[2365]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Dec 28 17:16:33 volumio volumio[2365]: } Dec 28 17:16:33 volumio volumio[2365]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo ENOTFOUND www.youtube.com Dec 28 17:16:33 volumio volumio[2365]: Stack trace: Dec 28 17:16:33 volumio volumio[2365]: AppError: Failed to start YouTubeApp Dec 28 17:16:33 volumio volumio[2365]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Dec 28 17:16:33 volumio volumio[2365]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:33 volumio volumio[2365]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Dec 28 17:16:33 volumio volumio[2365]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Dec 28 17:16:33 volumio volumio[2365]: LoungeScreenDisconnected { Dec 28 17:16:33 volumio volumio[2365]: AID: null, Dec 28 17:16:33 volumio volumio[2365]: name: 'loungeScreenDisconnected', Dec 28 17:16:33 volumio volumio[2365]: payload: {} Dec 28 17:16:33 volumio volumio[2365]: } Dec 28 17:16:33 volumio volumio[2365]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Dec 28 17:16:33 volumio volumio[2365]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Dec 28 17:16:33 volumio volumio[2365]: Stack trace: Dec 28 17:16:33 volumio volumio[2365]: IncompleteAPIDataError: Missing data required to construct query string from bind params Dec 28 17:16:33 volumio volumio[2365]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Dec 28 17:16:33 volumio volumio[2365]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Dec 28 17:16:33 volumio volumio[2365]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Dec 28 17:16:33 volumio volumio[2365]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Dec 28 17:16:33 volumio volumio[2365]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Dec 28 17:16:33 volumio volumio[2365]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Dec 28 17:16:33 volumio volumio[2365]: at new Promise () Dec 28 17:16:33 volumio volumio[2365]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Dec 28 17:16:33 volumio volumio[2365]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Dec 28 17:16:33 volumio volumio[2365]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:35 Dec 28 17:16:33 volumio volumio[2365]: at Array.forEach () Dec 28 17:16:33 volumio volumio[2365]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:24) Dec 28 17:16:33 volumio volumio[2365]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:33 volumio volumio[2365]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Dec 28 17:16:33 volumio volumio[2365]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Dec 28 17:16:33 volumio volumio[2365]: LoungeScreenDisconnected { Dec 28 17:16:33 volumio volumio[2365]: AID: null, Dec 28 17:16:33 volumio volumio[2365]: name: 'loungeScreenDisconnected', Dec 28 17:16:33 volumio volumio[2365]: payload: {} Dec 28 17:16:33 volumio volumio[2365]: } Dec 28 17:16:33 volumio volumio[2365]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Dec 28 17:16:33 volumio volumio[2365]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Dec 28 17:16:33 volumio volumio[2365]: Stack trace: Dec 28 17:16:33 volumio volumio[2365]: IncompleteAPIDataError: Missing data required to construct query string from bind params Dec 28 17:16:33 volumio volumio[2365]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Dec 28 17:16:33 volumio volumio[2365]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Dec 28 17:16:33 volumio volumio[2365]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Dec 28 17:16:33 volumio volumio[2365]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Dec 28 17:16:33 volumio volumio[2365]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Dec 28 17:16:33 volumio volumio[2365]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:33 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Dec 28 17:16:33 volumio volumio[2365]: error: [ytcr] Failed to start plugin: Dec 28 17:16:33 volumio volumio[2365]: (AppError) Failed to start YouTubeApp Dec 28 17:16:33 volumio volumio[2365]: --->(SessionError) (YouTube) Failed to establish session Dec 28 17:16:33 volumio volumio[2365]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Dec 28 17:16:33 volumio volumio[2365]: Error info: { Dec 28 17:16:33 volumio volumio[2365]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Dec 28 17:16:33 volumio volumio[2365]: } Dec 28 17:16:33 volumio volumio[2365]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo ENOTFOUND www.youtube.com Dec 28 17:16:33 volumio volumio[2365]: Stack trace: Dec 28 17:16:33 volumio volumio[2365]: AppError: Failed to start YouTubeApp Dec 28 17:16:33 volumio volumio[2365]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Dec 28 17:16:33 volumio volumio[2365]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:33 volumio volumio[2365]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Dec 28 17:16:33 volumio volumio[2365]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Dec 28 17:16:33 volumio volumio[2365]: LoungeScreenDisconnected { Dec 28 17:16:33 volumio volumio[2365]: AID: null, Dec 28 17:16:33 volumio volumio[2365]: name: 'loungeScreenDisconnected', Dec 28 17:16:33 volumio volumio[2365]: payload: {} Dec 28 17:16:33 volumio volumio[2365]: } Dec 28 17:16:33 volumio volumio[2365]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Dec 28 17:16:33 volumio volumio[2365]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Dec 28 17:16:33 volumio volumio[2365]: Stack trace: Dec 28 17:16:33 volumio volumio[2365]: IncompleteAPIDataError: Missing data required to construct query string from bind params Dec 28 17:16:33 volumio volumio[2365]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Dec 28 17:16:33 volumio volumio[2365]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Dec 28 17:16:33 volumio volumio[2365]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Dec 28 17:16:33 volumio volumio[2365]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Dec 28 17:16:33 volumio volumio[2365]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Dec 28 17:16:33 volumio volumio[2365]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 28 17:16:33 volumio volumio[2365]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Dec 28 17:16:33 volumio volumio[2365]: info: CoreCommandRouter::volumioGetVisibleSources Dec 28 17:16:33 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 17:16:33 volumio volumio[2365]: info: CoreCommandRouter::volumioGetState Dec 28 17:16:33 volumio volumio[2365]: info: CorePlayQueue::getTrack 0 Dec 28 17:16:33 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 28 17:16:33 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 28 17:16:33 volumio volumio[2365]: info: Received Get System Info Dec 28 17:16:33 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 17:16:33 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 17:16:33 volumio volumio[2365]: info: Discovery: Getting this device information Dec 28 17:16:33 volumio volumio[2365]: info: CoreCommandRouter::volumioGetState Dec 28 17:16:33 volumio volumio[2365]: info: CorePlayQueue::getTrack 0 Dec 28 17:16:33 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 17:16:33 volumio volumio[2365]: info: CoreCommandRouter::volumioGetState Dec 28 17:16:33 volumio volumio[2365]: info: CorePlayQueue::getTrack 0 Dec 28 17:16:33 volumio volumio[2365]: info: Listing playlists Dec 28 17:16:33 volumio volumio[2365]: info: VolumeController:: Volume=0 Mute =false Dec 28 17:16:33 volumio volumio[2365]: info: CoreCommandRouter::volumioGetState Dec 28 17:16:33 volumio volumio[2365]: info: CorePlayQueue::getTrack 0 Dec 28 17:16:33 volumio volumio[2365]: info: CoreStateMachine::pushState Dec 28 17:16:33 volumio volumio[2365]: info: CorePlayQueue::getTrack 0 Dec 28 17:16:33 volumio volumio[2365]: info: CoreCommandRouter::volumioPushState Dec 28 17:16:33 volumio volumio[2365]: info: [squeezelite_mc] Server discovery started Dec 28 17:16:33 volumio volumio[2365]: info: [squeezelite_mc] Player finder started Dec 28 17:16:33 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:33 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:33 volumio sudo[2637]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:33 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:16:33 volumio volumio[2365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 17:16:33 volumio volumio[2365]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 28 17:16:33 volumio volumio[2365]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 17:16:33 volumio systemd[1]: Reloading. Dec 28 17:16:33 volumio volumio[2365]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 28 17:16:33 volumio volumio[2365]: at doSend (dgram.js:692:16) Dec 28 17:16:33 volumio volumio[2365]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 28 17:16:33 volumio volumio[2365]: at afterDns (dgram.js:638:5) Dec 28 17:16:33 volumio volumio[2365]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 28 17:16:33 volumio volumio[2365]: errno: -101, Dec 28 17:16:33 volumio volumio[2365]: code: 'ENETUNREACH', Dec 28 17:16:33 volumio volumio[2365]: syscall: 'send', Dec 28 17:16:33 volumio volumio[2365]: address: '255.255.255.255', Dec 28 17:16:33 volumio volumio[2365]: port: 3483 Dec 28 17:16:33 volumio volumio[2365]: } Dec 28 17:16:33 volumio volumio[2365]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 17:16:34 volumio mpd[2612]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 28 17:16:34 volumio mpd[2612]: output: No 'audio_output' defined in config file Dec 28 17:16:34 volumio mpd[2612]: output: Successfully detected a sndio audio device Dec 28 17:16:34 volumio mpd[2612]: zeroconf: No global port, disabling zeroconf Dec 28 17:16:34 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 28 17:16:34 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Dec 28 17:16:34 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Dec 28 17:16:34 volumio sudo[2688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-28 17:15 Dec 28 17:16:34 volumio sudo[2688]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:16:34 volumio dhcpcd[965]: eth0: dhcp_sendudp: Network is unreachable Dec 28 17:16:34 volumio dhcpcd[965]: eth0: adding route to 192.168.1.0/24 Dec 28 17:16:34 volumio dhcpcd[965]: eth0: adding default route via 192.168.1.1 Dec 28 17:16:35 volumio systemd[1]: Started Music Player Daemon. Dec 28 17:16:35 volumio sudo[2641]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:35 volumio sudo[2588]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:35 volumio sudo[2598]: pam_unix(sudo:session): session closed for user root Dec 28 17:16:35 volumio sudo[2660]: pam_unix(sudo:session): session closed for user root 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"