-- Logs begin at Thu 2019-02-14 18:12:00 CST, end at Mon 2025-05-26 15:57:18 CST. -- May 26 15:56:00 volumio volumio[840]: verbose: New Socket.io Connection to 192.168.2.7 from 192.168.2.5 UA: Mozilla/5.0 (Linux; Android 15; 23127PN0CC Build/AQ3A.240627.003; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.86 Mobile Safari/537.36 Total Clients: 7 May 26 15:56:00 volumio volumio[840]: info: CoreCommandRouter::volumioGetState May 26 15:56:00 volumio volumio[840]: info: CorePlayQueue::getTrack 0 May 26 15:56:00 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 26 15:56:00 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 26 15:56:00 volumio volumio[840]: info: CoreCommandRouter::volumioGetVisibleSources May 26 15:56:00 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 15:56:00 volumio volumio[840]: info: CoreCommandRouter::volumioGetState May 26 15:56:00 volumio volumio[840]: info: CorePlayQueue::getTrack 0 May 26 15:56:00 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 26 15:56:00 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 26 15:56:00 volumio volumio[840]: info: Received Get System Info May 26 15:56:00 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:56:00 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:56:00 volumio volumio[840]: info: Discovery: Getting this device information May 26 15:56:00 volumio volumio[840]: info: CoreCommandRouter::volumioGetState May 26 15:56:00 volumio volumio[840]: info: CorePlayQueue::getTrack 0 May 26 15:56:00 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:56:00 volumio volumio[840]: info: CoreCommandRouter::volumioGetState May 26 15:56:00 volumio volumio[840]: info: CorePlayQueue::getTrack 0 May 26 15:56:00 volumio volumio[840]: info: Listing playlists May 26 15:56:07 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 26 15:56:07 volumio volumio[840]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 May 26 15:56:07 volumio volumio[840]: info: CoreCommandRouter::volumioGetState May 26 15:56:07 volumio volumio[840]: info: CorePlayQueue::getTrack 0 May 26 15:56:12 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:56:12 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:56:12 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 26 15:56:12 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 26 15:56:12 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 26 15:56:12 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 26 15:56:12 volumio volumio[840]: info: CoreCommandRouter::volumioGetBrowseSources May 26 15:56:12 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 15:56:14 volumio go-librespot[3145]: time="2025-05-26T15:56:14+08:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp 35.186.224.24:443: i/o timeout" May 26 15:56:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 15:56:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 15:56:14 volumio volumio[840]: (node:840) UnhandledPromiseRejectionWarning: Error: socket hang up May 26 15:56:14 volumio volumio[840]: at connResetException (internal/errors.js:607:14) May 26 15:56:14 volumio volumio[840]: at Socket.socketOnEnd (_http_client.js:493:23) May 26 15:56:14 volumio volumio[840]: at Socket.emit (events.js:327:22) May 26 15:56:14 volumio volumio[840]: at endReadableNT (internal/streams/readable.js:1327:12) May 26 15:56:14 volumio volumio[840]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 26 15:56:14 volumio volumio[840]: (node:840) 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: 2712) May 26 15:56:14 volumio volumio[840]: info: Connection to go-librespot Websocket closed May 26 15:56:17 volumio volumio[840]: info: Initializing connection to go-librespot Websocket May 26 15:56:17 volumio volumio[840]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 26 15:56:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 26 15:56:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. May 26 15:56:17 volumio systemd[1]: Stopped go-librespot Daemon. May 26 15:56:17 volumio systemd[1]: Started go-librespot Daemon. May 26 15:56:18 volumio go-librespot[3257]: Librespot-go daemon starting... May 26 15:56:18 volumio go-librespot[3257]: time="2025-05-26T15:56:18+08:00" level=info msg="generated new device id: 5b3eb2a994a4fa5ebfb7ac9ed80c4d6dd738fb0c" May 26 15:56:18 volumio go-librespot[3257]: time="2025-05-26T15:56:18+08:00" level=debug msg="stored credentials not found" May 26 15:56:20 volumio volumio[840]: info: Initializing connection to go-librespot Websocket May 26 15:56:20 volumio go-librespot[3257]: time="2025-05-26T15:56:20+08:00" level=debug msg="new websocket client" May 26 15:56:20 volumio volumio[840]: info: Connection to go-librespot Websocket established May 26 15:56:23 volumio volumio[840]: info: Getting Spotify volume May 26 15:56:23 volumio volumio[840]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 May 26 15:56:23 volumio volumio[840]: info: CoreCommandRouter::volumioGetState May 26 15:56:23 volumio volumio[840]: info: CorePlayQueue::getTrack 0 May 26 15:56:23 volumio volumio[840]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 26 15:56:24 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 26 15:56:28 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings May 26 15:56:28 volumio volumio[840]: info: Saving new wireless network May 26 15:56:28 volumio ntpd[669]: Soliciting pool server 95.111.202.5 May 26 15:56:28 volumio sudo[3307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf May 26 15:56:28 volumio sudo[3307]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:28 volumio sudo[3307]: pam_unix(sudo:session): session closed for user root May 26 15:56:28 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 26 15:56:28 volumio volumio[840]: info: Discovery: Restarting Advertising due to device name change May 26 15:56:28 volumio sudo[3310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 26 15:56:28 volumio sudo[3310]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:28 volumio systemd[1]: wireless.service: Killing process 2719 (wpa_supplicant) with signal SIGKILL. May 26 15:56:28 volumio systemd[1]: wireless.service: Killing process 2737 (dhcpcd) with signal SIGKILL. May 26 15:56:28 volumio systemd[1]: Stopping Wireless Services... May 26 15:56:28 volumio systemd[1]: wireless.service: Succeeded. May 26 15:56:28 volumio systemd[1]: Stopped Wireless Services. May 26 15:56:28 volumio systemd[1]: Starting Wireless Services... May 26 15:56:29 volumio wireless.js[3312]: Cleaning previous... May 26 15:56:29 volumio sudo[3327]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 26 15:56:29 volumio sudo[3327]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:29 volumio avahi-daemon[510]: Withdrawing address record for 192.168.2.7 on wlan0. May 26 15:56:29 volumio avahi-daemon[510]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.7. May 26 15:56:29 volumio avahi-daemon[510]: Interface wlan0.IPv4 no longer relevant for mDNS. May 26 15:56:29 volumio sudo[3327]: pam_unix(sudo:session): session closed for user root May 26 15:56:29 volumio volumio[840]: info: Discovery: A device disappeared from network May 26 15:56:29 volumio volumio[840]: info: Discovery: Device volumio disappeared from network May 26 15:56:29 volumio sudo[3330]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 26 15:56:29 volumio sudo[3330]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:30 volumio sudo[3330]: pam_unix(sudo:session): session closed for user root May 26 15:56:30 volumio wireless.js[3312]: Stopped aP May 26 15:56:30 volumio sudo[3338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 26 15:56:30 volumio sudo[3338]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:30 volumio sudo[3338]: pam_unix(sudo:session): session closed for user root May 26 15:56:30 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 26 15:56:30 volumio sudo[3340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 26 15:56:30 volumio sudo[3340]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:30 volumio sudo[3340]: pam_unix(sudo:session): session closed for user root May 26 15:56:30 volumio sudo[3347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 26 15:56:30 volumio sudo[3347]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:30 volumio sudo[3347]: pam_unix(sudo:session): session closed for user root May 26 15:56:30 volumio sudo[3349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 26 15:56:30 volumio sudo[3349]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:31 volumio sudo[3349]: pam_unix(sudo:session): session closed for user root May 26 15:56:31 volumio wireless.js[3312]: SETTING APPROPRIATE REG DOMAIN: CN May 26 15:56:31 volumio sudo[3354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 26 15:56:31 volumio sudo[3354]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:31 volumio sudo[3354]: pam_unix(sudo:session): session closed for user root May 26 15:56:31 volumio sudo[3356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CN May 26 15:56:31 volumio sudo[3356]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:31 volumio sudo[3356]: pam_unix(sudo:session): session closed for user root May 26 15:56:31 volumio wireless.js[3312]: SUCCESSFULLY SET NEW REGDOMAIN: CN May 26 15:56:31 volumio wireless.js[3312]: Start wireless flow May 26 15:56:31 volumio wireless.js[3312]: Stopped hotspot (if there).. May 26 15:56:31 volumio sudo[3361]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 26 15:56:31 volumio sudo[3361]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:31 volumio sudo[3361]: pam_unix(sudo:session): session closed for user root May 26 15:56:31 volumio sudo[3363]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 26 15:56:31 volumio sudo[3363]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:31 volumio sudo[3363]: pam_unix(sudo:session): session closed for user root May 26 15:56:31 volumio wireless.js[3312]: DHCP IP May 26 15:56:31 volumio wireless.js[3312]: Start ap May 26 15:56:31 volumio wpa_supplicant[3365]: Successfully initialized wpa_supplicant May 26 15:56:31 volumio sudo[3366]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd May 26 15:56:31 volumio sudo[3366]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:31 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 26 15:56:31 volumio dhcpcd[3367]: main: control_open: Connection refused May 26 15:56:31 volumio dhcpcd[3367]: dev: loaded udev May 26 15:56:31 volumio dhcpcd-run-hooks[3392]: wlan0: starting wpa_supplicant May 26 15:56:32 volumio dhcpcd-run-hooks[3397]: wlan0: failed to start wpa_supplicant May 26 15:56:32 volumio dhcpcd-run-hooks[3398]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma May 26 15:56:32 volumio dhcpcd[3367]: wlan0: connected to Access Point `' May 26 15:56:32 volumio dhcpcd[3367]: no interfaces have a carrier May 26 15:56:32 volumio dhcpcd[3367]: forked to background, child pid 3416 May 26 15:56:32 volumio dhcpcd[3416]: eth0: waiting for carrier May 26 15:56:32 volumio dhcpcd[3416]: wlan0: waiting for carrier May 26 15:56:32 volumio sudo[3366]: pam_unix(sudo:session): session closed for user root May 26 15:56:32 volumio wireless.js[3312]: trying... May 26 15:56:32 volumio sudo[3426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 26 15:56:32 volumio sudo[3426]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:32 volumio sudo[3426]: pam_unix(sudo:session): session closed for user root May 26 15:56:32 volumio wpa_supplicant[3396]: wlan0: Trying to associate with SSID 'mosen-5G' May 26 15:56:33 volumio volumio[840]: info: Discovery: Started advertising with name: Volumio May 26 15:56:33 volumio wireless.js[3312]: trying... May 26 15:56:33 volumio sudo[3430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 26 15:56:33 volumio sudo[3430]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:33 volumio sudo[3430]: pam_unix(sudo:session): session closed for user root May 26 15:56:34 volumio dhcpcd[3416]: wlan0: carrier acquired May 26 15:56:34 volumio dhcpcd[3416]: wlan0: connected to Access Point `mosen-5G' May 26 15:56:34 volumio wpa_supplicant[3396]: wlan0: Associated with 0a:10:7b:87:c7:2c May 26 15:56:34 volumio wpa_supplicant[3396]: wlan0: CTRL-EVENT-CONNECTED - Connection to 0a:10:7b:87:c7:2c completed [id=0 id_str=] May 26 15:56:34 volumio wpa_supplicant[3396]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 26 15:56:34 volumio wpa_supplicant[3396]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN May 26 15:56:34 volumio dhcpcd[3416]: DUID 00:01:00:01:2d:69:1d:45:dc:a6:32:ed:43:1d May 26 15:56:34 volumio dhcpcd[3416]: wlan0: IAID 32:46:51:26 May 26 15:56:34 volumio dhcpcd[3416]: wlan0: adding address fe80::dea6:32ff:fe46:5126 May 26 15:56:34 volumio dhcpcd[3416]: ipv6_addaddr1: Permission denied May 26 15:56:34 volumio dhcpcd[3416]: wlan0: carrier lost May 26 15:56:34 volumio dhcpcd[3416]: wlan0: carrier acquired May 26 15:56:34 volumio dhcpcd[3416]: wlan0: IAID 32:46:51:26 May 26 15:56:34 volumio ntpd[669]: Soliciting pool server 162.159.200.1 May 26 15:56:34 volumio wireless.js[3312]: trying... May 26 15:56:34 volumio sudo[3452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 26 15:56:34 volumio sudo[3452]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:34 volumio sudo[3452]: pam_unix(sudo:session): session closed for user root May 26 15:56:35 volumio dhcpcd[3416]: wlan0: soliciting a DHCP lease May 26 15:56:35 volumio dhcpcd[3416]: wlan0: soliciting an IPv6 router May 26 15:56:35 volumio dhcpcd[3416]: wlan0: offered 192.168.2.7 from 192.168.2.1 May 26 15:56:35 volumio dhcpcd[3416]: wlan0: probing address 192.168.2.7/24 May 26 15:56:35 volumio ntpd[669]: Deleting interface #4 wlan0, 192.168.2.7#123, interface stats: received=31, sent=47, dropped=0, active_time=117 secs May 26 15:56:35 volumio ntpd[669]: 117.80.231.60 local addr 192.168.2.7 -> May 26 15:56:35 volumio ntpd[669]: 185.209.85.222 local addr 192.168.2.7 -> May 26 15:56:35 volumio ntpd[669]: 162.159.200.123 local addr 192.168.2.7 -> May 26 15:56:35 volumio ntpd[669]: 211.68.71.26 local addr 192.168.2.7 -> May 26 15:56:35 volumio ntpd[669]: 193.182.111.141 local addr 192.168.2.7 -> May 26 15:56:35 volumio ntpd[669]: 193.182.111.143 local addr 192.168.2.7 -> May 26 15:56:35 volumio wireless.js[3312]: trying... May 26 15:56:35 volumio sudo[3455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 26 15:56:35 volumio sudo[3455]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:35 volumio sudo[3455]: pam_unix(sudo:session): session closed for user root May 26 15:56:36 volumio wireless.js[3312]: trying... May 26 15:56:36 volumio sudo[3458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 26 15:56:36 volumio sudo[3458]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:36 volumio sudo[3458]: pam_unix(sudo:session): session closed for user root May 26 15:56:37 volumio wireless.js[3312]: trying... May 26 15:56:37 volumio sudo[3465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 26 15:56:37 volumio sudo[3465]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:37 volumio sudo[3465]: pam_unix(sudo:session): session closed for user root May 26 15:56:38 volumio wireless.js[3312]: trying... May 26 15:56:38 volumio sudo[3468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 26 15:56:38 volumio sudo[3468]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:38 volumio sudo[3468]: pam_unix(sudo:session): session closed for user root May 26 15:56:39 volumio wireless.js[3312]: trying... May 26 15:56:39 volumio sudo[3471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 26 15:56:39 volumio sudo[3471]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:39 volumio sudo[3471]: pam_unix(sudo:session): session closed for user root May 26 15:56:40 volumio dhcpcd[3416]: wlan0: leased 192.168.2.7 for 86400 seconds May 26 15:56:40 volumio avahi-daemon[510]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.7. May 26 15:56:40 volumio dhcpcd[3416]: wlan0: adding route to 192.168.2.0/24 May 26 15:56:40 volumio avahi-daemon[510]: New relevant interface wlan0.IPv4 for mDNS. May 26 15:56:40 volumio dhcpcd[3416]: wlan0: adding default route via 192.168.2.1 May 26 15:56:40 volumio avahi-daemon[510]: Registering new address record for 192.168.2.7 on wlan0.IPv4. May 26 15:56:40 volumio wireless.js[3312]: trying... May 26 15:56:40 volumio sudo[3495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 26 15:56:40 volumio sudo[3495]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:40 volumio sudo[3495]: pam_unix(sudo:session): session closed for user root May 26 15:56:40 volumio wireless.js[3312]: Connected to: ----mosen-5G May 26 15:56:40 volumio wireless.js[3312]: ---- May 26 15:56:40 volumio sudo[3498]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 15:56:40 volumio sudo[3498]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:40 volumio sudo[3498]: pam_unix(sudo:session): session closed for user root May 26 15:56:40 volumio wireless.js[3312]: ... joined AP, wlan0 IPv4 is 192.168.2.7, ipV6 is undefined May 26 15:56:40 volumio wireless.js[3312]: It's done! AP May 26 15:56:41 volumio systemd[1]: Started Wireless Services. May 26 15:56:41 volumio sudo[3310]: pam_unix(sudo:session): session closed for user root May 26 15:56:41 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 26 15:56:41 volumio volumio[840]: info: Discovery: adding eaa2afdc-3fa0-4a8a-925d-a9b0c99b88c0 May 26 15:56:41 volumio volumio[840]: info: Discovery: Found device Volumio May 26 15:56:41 volumio volumio[840]: info: CoreCommandRouter::volumioGetState May 26 15:56:41 volumio volumio[840]: info: CorePlayQueue::getTrack 0 May 26 15:56:41 volumio ntpd[669]: Soliciting pool server 116.203.151.74 May 26 15:56:41 volumio ntpd[669]: Listen normally on 5 wlan0 192.168.2.7:123 May 26 15:56:41 volumio ntpd[669]: new interface(s) found: waking up resolver May 26 15:56:42 volumio ntpd[669]: Soliciting pool server 95.111.202.5 May 26 15:56:44 volumio ntpd[669]: Soliciting pool server 2001:470:1d:281::123 May 26 15:56:46 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection May 26 15:56:46 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo May 26 15:56:46 volumio sudo[3513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 15:56:46 volumio sudo[3513]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:46 volumio sudo[3513]: pam_unix(sudo:session): session closed for user root May 26 15:56:46 volumio sudo[3516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 26 15:56:46 volumio sudo[3516]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:46 volumio sudo[3516]: pam_unix(sudo:session): session closed for user root May 26 15:56:48 volumio go-librespot[3257]: time="2025-05-26T15:56:48+08:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp 35.186.224.24:443: i/o timeout" May 26 15:56:48 volumio volumio[840]: (node:840) UnhandledPromiseRejectionWarning: Error: socket hang up May 26 15:56:48 volumio volumio[840]: at connResetException (internal/errors.js:607:14) May 26 15:56:48 volumio volumio[840]: at Socket.socketOnEnd (_http_client.js:493:23) May 26 15:56:48 volumio volumio[840]: at Socket.emit (events.js:327:22) May 26 15:56:48 volumio volumio[840]: at endReadableNT (internal/streams/readable.js:1327:12) May 26 15:56:48 volumio volumio[840]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 26 15:56:48 volumio volumio[840]: (node:840) 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: 2713) May 26 15:56:48 volumio volumio[840]: info: Connection to go-librespot Websocket closed May 26 15:56:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 15:56:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 15:56:51 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart May 26 15:56:51 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart May 26 15:56:51 volumio volumio[840]: info: Refreshing Cached IP Addresses May 26 15:56:51 volumio sudo[3525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli May 26 15:56:51 volumio sudo[3525]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:51 volumio sudo[3525]: pam_unix(sudo:session): session closed for user root May 26 15:56:51 volumio sudo[3528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 26 15:56:51 volumio sudo[3528]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:51 volumio sudo[3528]: pam_unix(sudo:session): session closed for user root May 26 15:56:51 volumio sudo[3531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 15:56:51 volumio sudo[3531]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:51 volumio volumio[840]: info: Initializing connection to go-librespot Websocket May 26 15:56:51 volumio volumio[840]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 26 15:56:51 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 26 15:56:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. May 26 15:56:51 volumio sudo[3531]: pam_unix(sudo:session): session closed for user root May 26 15:56:51 volumio systemd[1]: upmpdcli.service: Succeeded. May 26 15:56:51 volumio systemd[1]: Stopped go-librespot Daemon. May 26 15:56:51 volumio systemd[1]: Started go-librespot Daemon. May 26 15:56:51 volumio go-librespot[3533]: Librespot-go daemon starting... May 26 15:56:51 volumio go-librespot[3533]: time="2025-05-26T15:56:51+08:00" level=info msg="generated new device id: 87acbac2b4975d7b433ae903a5f4a47297b76c85" May 26 15:56:51 volumio go-librespot[3533]: time="2025-05-26T15:56:51+08:00" level=debug msg="stored credentials not found" May 26 15:56:51 volumio sudo[3542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 26 15:56:51 volumio sudo[3542]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:51 volumio sudo[3542]: pam_unix(sudo:session): session closed for user root May 26 15:56:51 volumio sudo[3545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 15:56:51 volumio sudo[3545]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:51 volumio sudo[3545]: pam_unix(sudo:session): session closed for user root May 26 15:56:51 volumio volumio[840]: verbose: New Socket.io Connection to 192.168.2.7 from 192.168.2.5 UA: Mozilla/5.0 (Linux; Android 15; 23127PN0CC Build/AQ3A.240627.003; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.86 Mobile Safari/537.36 Total Clients: 8 May 26 15:56:51 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 26 15:56:51 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 26 15:56:51 volumio volumio[840]: info: CoreCommandRouter::volumioGetVisibleSources May 26 15:56:51 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 15:56:51 volumio volumio[840]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 15:56:51 volumio volumio[840]: info: CoreCommandRouter::volumioGetState May 26 15:56:51 volumio volumio[840]: info: CorePlayQueue::getTrack 0 May 26 15:56:51 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 26 15:56:51 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 26 15:56:51 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 26 15:56:51 volumio volumio[840]: info: Received Get System Info May 26 15:56:51 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:56:51 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:56:51 volumio volumio[840]: info: Discovery: Getting this device information May 26 15:56:51 volumio volumio[840]: info: CoreCommandRouter::volumioGetState May 26 15:56:51 volumio volumio[840]: info: CorePlayQueue::getTrack 0 May 26 15:56:51 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:56:51 volumio volumio[840]: info: CoreCommandRouter::volumioGetState May 26 15:56:51 volumio volumio[840]: info: CorePlayQueue::getTrack 0 May 26 15:56:51 volumio volumio[840]: info: Listing playlists May 26 15:56:51 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 26 15:56:51 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 26 15:56:51 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 26 15:56:51 volumio sudo[3550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 26 15:56:51 volumio sudo[3550]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:51 volumio sudo[3550]: pam_unix(sudo:session): session closed for user root May 26 15:56:51 volumio sudo[3556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 26 15:56:51 volumio sudo[3556]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:51 volumio sudo[3556]: pam_unix(sudo:session): session closed for user root May 26 15:56:52 volumio sudo[3564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 26 15:56:52 volumio sudo[3564]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:52 volumio sudo[3564]: pam_unix(sudo:session): session closed for user root May 26 15:56:52 volumio sudo[3570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 26 15:56:52 volumio sudo[3570]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:52 volumio sudo[3570]: pam_unix(sudo:session): session closed for user root May 26 15:56:52 volumio sudo[3576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 26 15:56:52 volumio sudo[3576]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:52 volumio sudo[3576]: pam_unix(sudo:session): session closed for user root May 26 15:56:52 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 26 15:56:52 volumio sudo[3579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 15:56:52 volumio sudo[3579]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:52 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 26 15:56:52 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 26 15:56:52 volumio sudo[3579]: pam_unix(sudo:session): session closed for user root May 26 15:56:52 volumio sudo[3582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 26 15:56:52 volumio sudo[3582]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:56:52 volumio volumio[840]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: May 26 15:56:52 volumio volumio[840]: LoungeScreenDisconnected { May 26 15:56:52 volumio volumio[840]: AID: null, May 26 15:56:52 volumio volumio[840]: name: 'loungeScreenDisconnected', May 26 15:56:52 volumio volumio[840]: payload: {} May 26 15:56:52 volumio volumio[840]: } May 26 15:56:52 volumio volumio[840]: (IncompleteAPIDataError) Missing data required to construct query string from bind params May 26 15:56:52 volumio volumio[840]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } May 26 15:56:52 volumio volumio[840]: Stack trace: May 26 15:56:52 volumio volumio[840]: IncompleteAPIDataError: Missing data required to construct query string from bind params May 26 15:56:52 volumio volumio[840]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) May 26 15:56:52 volumio volumio[840]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) May 26 15:56:52 volumio volumio[840]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) May 26 15:56:52 volumio volumio[840]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) May 26 15:56:52 volumio volumio[840]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) May 26 15:56:52 volumio volumio[840]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 26 15:56:52 volumio volumio[840]: (node:840) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined May 26 15:56:52 volumio volumio[840]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) May 26 15:56:52 volumio volumio[840]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 26 15:56:52 volumio volumio[840]: (node:840) 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: 2715) May 26 15:56:52 volumio volumio[840]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: May 26 15:56:52 volumio volumio[840]: LoungeScreenDisconnected { May 26 15:56:52 volumio volumio[840]: AID: null, May 26 15:56:52 volumio volumio[840]: name: 'loungeScreenDisconnected', May 26 15:56:52 volumio volumio[840]: payload: {} May 26 15:56:52 volumio volumio[840]: } May 26 15:56:52 volumio volumio[840]: (IncompleteAPIDataError) Missing data required to construct query string from bind params May 26 15:56:52 volumio volumio[840]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } May 26 15:56:52 volumio volumio[840]: Stack trace: May 26 15:56:52 volumio volumio[840]: IncompleteAPIDataError: Missing data required to construct query string from bind params May 26 15:56:52 volumio volumio[840]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) May 26 15:56:52 volumio volumio[840]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) May 26 15:56:52 volumio volumio[840]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) May 26 15:56:52 volumio volumio[840]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) May 26 15:56:52 volumio volumio[840]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) May 26 15:56:52 volumio volumio[840]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 26 15:56:52 volumio volumio[840]: (node:840) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined May 26 15:56:52 volumio volumio[840]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) May 26 15:56:52 volumio volumio[840]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 26 15:56:52 volumio volumio[840]: (node:840) 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: 2717) May 26 15:56:52 volumio volumio[840]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: May 26 15:56:52 volumio volumio[840]: LoungeScreenDisconnected { May 26 15:56:52 volumio volumio[840]: AID: null, May 26 15:56:52 volumio volumio[840]: name: 'loungeScreenDisconnected', May 26 15:56:52 volumio volumio[840]: payload: {} May 26 15:56:52 volumio volumio[840]: } May 26 15:56:52 volumio volumio[840]: (IncompleteAPIDataError) Missing data required to construct query string from bind params May 26 15:56:52 volumio volumio[840]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } May 26 15:56:52 volumio volumio[840]: Stack trace: May 26 15:56:52 volumio volumio[840]: IncompleteAPIDataError: Missing data required to construct query string from bind params May 26 15:56:52 volumio volumio[840]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) May 26 15:56:52 volumio volumio[840]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) May 26 15:56:52 volumio volumio[840]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) May 26 15:56:52 volumio volumio[840]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) May 26 15:56:52 volumio volumio[840]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) May 26 15:56:52 volumio volumio[840]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 26 15:56:52 volumio volumio[840]: (node:840) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined May 26 15:56:52 volumio volumio[840]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) May 26 15:56:52 volumio volumio[840]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 26 15:56:52 volumio volumio[840]: (node:840) 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: 2719) May 26 15:56:52 volumio volumio[840]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: May 26 15:56:52 volumio volumio[840]: LoungeScreenDisconnected { May 26 15:56:52 volumio volumio[840]: AID: null, May 26 15:56:52 volumio volumio[840]: name: 'loungeScreenDisconnected', May 26 15:56:52 volumio volumio[840]: payload: {} May 26 15:56:52 volumio volumio[840]: } May 26 15:56:52 volumio volumio[840]: (IncompleteAPIDataError) Missing data required to construct query string from bind params May 26 15:56:52 volumio volumio[840]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } May 26 15:56:52 volumio volumio[840]: Stack trace: May 26 15:56:52 volumio volumio[840]: IncompleteAPIDataError: Missing data required to construct query string from bind params May 26 15:56:52 volumio volumio[840]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) May 26 15:56:52 volumio volumio[840]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) May 26 15:56:52 volumio volumio[840]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) May 26 15:56:52 volumio volumio[840]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) May 26 15:56:52 volumio volumio[840]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) May 26 15:56:52 volumio volumio[840]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 26 15:56:52 volumio volumio[840]: (node:840) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined May 26 15:56:52 volumio volumio[840]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) May 26 15:56:52 volumio volumio[840]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 26 15:56:52 volumio volumio[840]: (node:840) 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: 2721) May 26 15:56:53 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 15:56:53 volumio volumio[840]: info: Received Get System Info May 26 15:56:53 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:56:53 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:56:53 volumio volumio[840]: info: Discovery: Getting this device information May 26 15:56:53 volumio volumio[840]: info: CoreCommandRouter::volumioGetState May 26 15:56:53 volumio volumio[840]: info: CorePlayQueue::getTrack 0 May 26 15:56:53 volumio volumio[840]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:56:53 volumio sudo[3582]: pam_unix(sudo:session): session closed for user root May 26 15:56:54 volumio volumio[840]: info: Initializing connection to go-librespot Websocket May 26 15:56:54 volumio go-librespot[3533]: time="2025-05-26T15:56:54+08:00" level=debug msg="new websocket client" May 26 15:56:54 volumio volumio[840]: info: Connection to go-librespot Websocket established May 26 15:56:57 volumio volumio[840]: info: Getting Spotify volume May 26 15:56:57 volumio volumio[840]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 May 26 15:56:57 volumio volumio[840]: info: CoreCommandRouter::volumioGetState May 26 15:56:57 volumio volumio[840]: info: CorePlayQueue::getTrack 0 May 26 15:56:57 volumio volumio[840]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 26 15:57:01 volumio sudo[3585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 26 15:57:01 volumio sudo[3585]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:57:01 volumio sudo[3585]: pam_unix(sudo:session): session closed for user root May 26 15:57:01 volumio sudo[3588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 15:57:01 volumio sudo[3588]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:57:01 volumio sudo[3588]: pam_unix(sudo:session): session closed for user root May 26 15:57:01 volumio sudo[3591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 26 15:57:01 volumio sudo[3591]: pam_unix(sudo:session): session opened for user root by (uid=0) May 26 15:57:01 volumio systemd[1]: Starting Cleanup of Temporary Directories... May 26 15:57:01 volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 26 15:57:01 volumio sudo[3591]: pam_unix(sudo:session): session closed for user root May 26 15:57:01 volumio volumio[840]: info: Upmpdcli Daemon Started May 26 15:57:01 volumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded. May 26 15:57:01 volumio systemd[1]: Started Cleanup of Temporary Directories. May 26 15:57:01 volumio volumio[3594]: writing RSA key May 26 15:57:18 volumio volumio[840]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 15:57:18 volumio volumio[840]: Error: connect ETIMEDOUT 199.16.156.71:443 May 26 15:57:18 volumio volumio[840]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { May 26 15:57:18 volumio volumio[840]: errno: -110, May 26 15:57:18 volumio volumio[840]: code: 'ETIMEDOUT', May 26 15:57:18 volumio volumio[840]: syscall: 'connect', May 26 15:57:18 volumio volumio[840]: address: '199.16.156.71', May 26 15:57:18 volumio volumio[840]: port: 443 May 26 15:57:18 volumio volumio[840]: } May 26 15:57:18 volumio volumio[840]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 15:57:18 volumio sudo[3637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-26 15:56 May 26 15:57:18 volumio sudo[3637]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"