-- Logs begin at Thu 2024-11-28 15:18:11 CST, end at Thu 2024-11-28 15:32:40 CST. -- Nov 28 15:31:06 volumio volumio[3348]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Bob%20Dylan/Rolling%20Stone%20Magazine%27s%20500%20Greatest%20Songs%20Of%20All%20Time/cc431418-4695-4172-837b-6a910eb56828.jpg' Nov 28 15:31:06 volumio wpa_supplicant[905]: TDLS: Creating peer entry for 22:c8:f4:d5:e2:91 Nov 28 15:31:07 volumio wpa_supplicant[905]: TDLS: Dialog Token in TPK M1 54 Nov 28 15:31:07 volumio wpa_supplicant[905]: nl80211: kernel reports: key addition failed Nov 28 15:31:07 volumio wpa_supplicant[905]: TDLS: Failed to set TPK to the driver Nov 28 15:31:32 volumio sudo[4410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 28 15:31:32 volumio sudo[4410]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:31:32 volumio sudo[4410]: pam_unix(sudo:session): session closed for user root Nov 28 15:31:32 volumio sudo[4413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 15:31:32 volumio sudo[4413]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:31:32 volumio sudo[4413]: pam_unix(sudo:session): session closed for user root Nov 28 15:31:32 volumio volumio[3348]: verbose: New Socket.io Connection to 192.168.0.254 from 192.168.0.100 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/27.1 Chrome/125.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Nov 28 15:31:32 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 28 15:31:32 volumio volumio[3348]: info: CoreCommandRouter::volumioGetVisibleSources Nov 28 15:31:32 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 15:31:32 volumio volumio[3348]: info: CoreCommandRouter::volumioGetState Nov 28 15:31:32 volumio volumio[3348]: info: CorePlayQueue::getTrack 1 Nov 28 15:31:32 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 28 15:31:32 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 28 15:31:32 volumio volumio[3348]: info: Received Get System Info Nov 28 15:31:32 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 15:31:32 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 15:31:32 volumio volumio[3348]: info: Discovery: Getting this device information Nov 28 15:31:32 volumio volumio[3348]: info: CoreCommandRouter::volumioGetState Nov 28 15:31:32 volumio volumio[3348]: info: CorePlayQueue::getTrack 1 Nov 28 15:31:32 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 15:31:32 volumio volumio[3348]: info: CoreCommandRouter::volumioGetState Nov 28 15:31:32 volumio volumio[3348]: info: CorePlayQueue::getTrack 1 Nov 28 15:31:32 volumio volumio[3348]: info: Listing playlists Nov 28 15:31:32 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 28 15:31:32 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 28 15:31:32 volumio volumio[3348]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Rolling%20Stones/Rolling%20Stone%20Magazine%27s%20500%20Greatest%20Songs%20Of%20All%20Time/ee913080-8e8b-4bf7-a97b-ee8d0d40c414.jpg' Nov 28 15:31:33 volumio wpa_supplicant[905]: TDLS: Creating peer entry for 22:c8:f4:d5:e2:91 Nov 28 15:31:33 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 28 15:31:34 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 28 15:31:34 volumio volumio[3348]: info: Received Get System Info Nov 28 15:31:34 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 15:31:34 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 15:31:34 volumio volumio[3348]: info: Discovery: Getting this device information Nov 28 15:31:34 volumio volumio[3348]: info: CoreCommandRouter::volumioGetState Nov 28 15:31:34 volumio volumio[3348]: info: CorePlayQueue::getTrack 1 Nov 28 15:31:34 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 15:31:34 volumio wpa_supplicant[905]: TDLS: Dialog Token in TPK M1 56 Nov 28 15:31:34 volumio volumio[3348]: info: CoreCommandRouter::volumioGetQueue Nov 28 15:31:34 volumio volumio[3348]: info: CoreStateMachine::getQueue Nov 28 15:31:34 volumio volumio[3348]: info: CorePlayQueue::getQueue Nov 28 15:31:34 volumio wpa_supplicant[905]: nl80211: kernel reports: key addition failed Nov 28 15:31:34 volumio wpa_supplicant[905]: TDLS: Failed to set TPK to the driver Nov 28 15:31:35 volumio volumio[3348]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/John%20Lennon/Rolling%20Stone%20Magazine%27s%20500%20Greatest%20Songs%20Of%20All%20Time/85531d89-b48a-4676-8284-843a4a380f43.jpg' Nov 28 15:31:35 volumio volumio[3348]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Marvin%20Gaye/Rolling%20Stone%20Magazine%27s%20500%20Greatest%20Songs%20Of%20All%20Time/2522fb9c-578a-4de5-99b5-3a54fa8606b8.jpg' Nov 28 15:31:35 volumio volumio[3348]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Aretha%20Franklin/Rolling%20Stone%20Magazine%27s%20500%20Greatest%20Songs%20Of%20All%20Time/e44a4efb-5a43-4784-9e6d-5bf49979a686.jpg' Nov 28 15:31:35 volumio volumio[3348]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Beach%20Boys/Rolling%20Stone%20Magazine%27s%20500%20Greatest%20Songs%20Of%20All%20Time/5718ecd7-9c4a-46f3-b163-f0bc10da4202.jpg' Nov 28 15:31:35 volumio volumio[3348]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Chuck%20Berry/Rolling%20Stone%20Magazine%27s%20500%20Greatest%20Songs%20Of%20All%20Time/39c4e158-05f1-4ac2-bab5-6211056d69fc.jpg' Nov 28 15:31:42 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 28 15:31:42 volumio volumio[3348]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 15:31:42 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 28 15:31:43 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Nov 28 15:31:43 volumio sudo[4437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Nov 28 15:31:43 volumio sudo[4437]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:31:43 volumio sudo[4442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Nov 28 15:31:43 volumio sudo[4442]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:31:43 volumio sudo[4437]: pam_unix(sudo:session): session closed for user root Nov 28 15:31:43 volumio sudo[4442]: pam_unix(sudo:session): session closed for user root Nov 28 15:31:43 volumio sudo[4451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Nov 28 15:31:43 volumio sudo[4451]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:31:43 volumio sudo[4451]: pam_unix(sudo:session): session closed for user root Nov 28 15:31:43 volumio sudo[4456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Nov 28 15:31:43 volumio sudo[4456]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:31:43 volumio sudo[4456]: pam_unix(sudo:session): session closed for user root Nov 28 15:31:43 volumio sudo[4463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 28 15:31:43 volumio sudo[4463]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:31:43 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Nov 28 15:31:43 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Nov 28 15:31:43 volumio sudo[4463]: pam_unix(sudo:session): session closed for user root Nov 28 15:31:43 volumio sudo[4466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 15:31:43 volumio sudo[4466]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:31:43 volumio sudo[4466]: pam_unix(sudo:session): session closed for user root Nov 28 15:31:43 volumio sudo[4469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 28 15:31:43 volumio sudo[4469]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:31:48 volumio sudo[4469]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:03 volumio volumio[3348]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] Nov 28 15:32:03 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Nov 28 15:32:04 volumio sudo[4507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Nov 28 15:32:04 volumio sudo[4507]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:04 volumio sudo[4507]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:04 volumio volumio[3348]: info: Permissions for /etc/hostapd/hostapd.conf Nov 28 15:32:04 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Nov 28 15:32:04 volumio volumio[3348]: info: Discovery: Restarting Advertising due to device name change Nov 28 15:32:04 volumio sudo[4510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Nov 28 15:32:04 volumio sudo[4510]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:04 volumio systemd[1]: Stopping Wireless Services... Nov 28 15:32:04 volumio dhcpcd[761]: wlan0: carrier lost Nov 28 15:32:04 volumio kernel: wlan0: deauthenticating from c8:3a:35:13:98:78 by local choice (Reason: 3=DEAUTH_LEAVING) Nov 28 15:32:04 volumio avahi-daemon[655]: Withdrawing address record for 192.168.0.254 on wlan0. Nov 28 15:32:04 volumio avahi-daemon[655]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.254. Nov 28 15:32:04 volumio avahi-daemon[655]: Interface wlan0.IPv4 no longer relevant for mDNS. Nov 28 15:32:04 volumio wpa_supplicant[905]: wlan0: CTRL-EVENT-DISCONNECTED bssid=c8:3a:35:13:98:78 reason=3 locally_generated=1 Nov 28 15:32:04 volumio volumio[3348]: info: Discovery: A device disappeared from network Nov 28 15:32:04 volumio volumio[3348]: info: Discovery: Device volumio disappeared from network Nov 28 15:32:04 volumio dhcpcd[761]: wlan0: deleting route to 192.168.0.0/24 Nov 28 15:32:04 volumio dhcpcd[761]: wlan0: deleting default route via 192.168.0.1 Nov 28 15:32:04 volumio wpa_supplicant[905]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Nov 28 15:32:04 volumio wpa_supplicant[905]: wlan0: CTRL-EVENT-TERMINATING Nov 28 15:32:04 volumio systemd[1]: wireless.service: Succeeded. Nov 28 15:32:04 volumio systemd[1]: Stopped Wireless Services. Nov 28 15:32:04 volumio systemd[1]: Starting Wireless Services... Nov 28 15:32:05 volumio wireless.js[4538]: Cleaning previous... Nov 28 15:32:05 volumio sudo[4552]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 28 15:32:05 volumio sudo[4552]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:05 volumio sudo[4552]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:05 volumio sudo[4554]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 28 15:32:05 volumio sudo[4554]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:05 volumio sudo[4554]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:05 volumio wireless.js[4538]: Stopped aP Nov 28 15:32:05 volumio sudo[4561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 28 15:32:05 volumio sudo[4561]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:05 volumio sudo[4561]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:05 volumio sudo[4563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Nov 28 15:32:05 volumio sudo[4563]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:05 volumio sudo[4563]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:05 volumio sudo[4570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 28 15:32:05 volumio sudo[4570]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:05 volumio sudo[4570]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:05 volumio sudo[4572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Nov 28 15:32:05 volumio sudo[4572]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:06 volumio ntpd[816]: Deleting interface #3 wlan0, 192.168.0.254#123, interface stats: received=186, sent=193, dropped=0, active_time=817 secs Nov 28 15:32:06 volumio ntpd[816]: 84.16.67.12 local addr 192.168.0.254 -> Nov 28 15:32:06 volumio ntpd[816]: 162.159.200.123 local addr 192.168.0.254 -> Nov 28 15:32:06 volumio ntpd[816]: 202.118.1.130 local addr 192.168.0.254 -> Nov 28 15:32:06 volumio ntpd[816]: 78.46.102.180 local addr 192.168.0.254 -> Nov 28 15:32:06 volumio ntpd[816]: 116.203.151.74 local addr 192.168.0.254 -> Nov 28 15:32:06 volumio ntpd[816]: 202.118.1.81 local addr 192.168.0.254 -> Nov 28 15:32:06 volumio ntpd[816]: 193.182.111.142 local addr 192.168.0.254 -> Nov 28 15:32:06 volumio ntpd[816]: 119.28.206.193 local addr 192.168.0.254 -> Nov 28 15:32:06 volumio ntpd[816]: 193.182.111.12 local addr 192.168.0.254 -> Nov 28 15:32:06 volumio ntpd[816]: 185.209.85.222 local addr 192.168.0.254 -> Nov 28 15:32:06 volumio sudo[4572]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:06 volumio wireless.js[4538]: Start wireless flow Nov 28 15:32:06 volumio wireless.js[4538]: Stopped hotspot (if there).. Nov 28 15:32:06 volumio sudo[4580]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 28 15:32:06 volumio sudo[4580]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:06 volumio sudo[4580]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:06 volumio sudo[4582]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 28 15:32:06 volumio sudo[4582]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:06 volumio sudo[4582]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:06 volumio wireless.js[4538]: DHCP IP Nov 28 15:32:06 volumio wireless.js[4538]: Start ap Nov 28 15:32:06 volumio wpa_supplicant[4584]: Successfully initialized wpa_supplicant Nov 28 15:32:06 volumio sudo[4585]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Nov 28 15:32:06 volumio sudo[4585]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:06 volumio dhcpcd[4586]: sending commands to master dhcpcd process Nov 28 15:32:06 volumio dhcpcd[761]: control command: /sbin/dhcpcd Nov 28 15:32:06 volumio sudo[4585]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:07 volumio wireless.js[4538]: trying... Nov 28 15:32:07 volumio sudo[4589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 15:32:07 volumio sudo[4589]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:07 volumio sudo[4589]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:08 volumio wpa_supplicant[4587]: wlan0: SME: Trying to authenticate with c8:3a:35:13:98:78 (SSID='FAST_D334' freq=2412 MHz) Nov 28 15:32:08 volumio kernel: wlan0: authenticate with c8:3a:35:13:98:78 Nov 28 15:32:08 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Nov 28 15:32:08 volumio kernel: wlan0: send auth to c8:3a:35:13:98:78 (try 1/3) Nov 28 15:32:08 volumio wpa_supplicant[4587]: wlan0: Trying to associate with c8:3a:35:13:98:78 (SSID='FAST_D334' freq=2412 MHz) Nov 28 15:32:08 volumio kernel: wlan0: authenticated Nov 28 15:32:08 volumio kernel: wlan0: associate with c8:3a:35:13:98:78 (try 1/3) Nov 28 15:32:08 volumio kernel: wlan0: RX AssocResp from c8:3a:35:13:98:78 (capab=0x411 status=0 aid=3) Nov 28 15:32:08 volumio kernel: wlan0: associated Nov 28 15:32:08 volumio wpa_supplicant[4587]: wlan0: Associated with c8:3a:35:13:98:78 Nov 28 15:32:08 volumio wpa_supplicant[4587]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 28 15:32:08 volumio wpa_supplicant[4587]: wlan0: WPA: Key negotiation completed with c8:3a:35:13:98:78 [PTK=CCMP GTK=CCMP] Nov 28 15:32:08 volumio wpa_supplicant[4587]: wlan0: CTRL-EVENT-CONNECTED - Connection to c8:3a:35:13:98:78 completed [id=0 id_str=] Nov 28 15:32:08 volumio dhcpcd[761]: wlan0: carrier acquired Nov 28 15:32:08 volumio dhcpcd[761]: wlan0: IAID c2:ec:3b:fe Nov 28 15:32:08 volumio dhcpcd[761]: wlan0: probing address 192.168.0.254/24 Nov 28 15:32:08 volumio dhcpcd[761]: wlan0: soliciting an IPv6 router Nov 28 15:32:08 volumio wireless.js[4538]: trying... Nov 28 15:32:08 volumio sudo[4593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 15:32:08 volumio sudo[4593]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:08 volumio sudo[4593]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:09 volumio volumio[3348]: info: Discovery: Started advertising with name: Volumio Nov 28 15:32:09 volumio wireless.js[4538]: trying... Nov 28 15:32:09 volumio sudo[4597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 15:32:09 volumio sudo[4597]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:09 volumio sudo[4597]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:10 volumio wireless.js[4538]: trying... Nov 28 15:32:10 volumio sudo[4600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 15:32:10 volumio sudo[4600]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:10 volumio sudo[4600]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:11 volumio volumio[3348]: info: Volumio Network Manager: Network status updated: 0 Nov 28 15:32:11 volumio wireless.js[4538]: trying... Nov 28 15:32:11 volumio sudo[4617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 15:32:11 volumio sudo[4617]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:11 volumio sudo[4617]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:12 volumio wireless.js[4538]: trying... Nov 28 15:32:12 volumio sudo[4620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 15:32:12 volumio sudo[4620]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:12 volumio sudo[4620]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:13 volumio dhcpcd[761]: wlan0: using static address 192.168.0.254/24 Nov 28 15:32:13 volumio dhcpcd[761]: wlan0: adding route to 192.168.0.0/24 Nov 28 15:32:13 volumio dhcpcd[761]: wlan0: adding default route via 192.168.0.1 Nov 28 15:32:13 volumio avahi-daemon[655]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.254. Nov 28 15:32:13 volumio avahi-daemon[655]: New relevant interface wlan0.IPv4 for mDNS. Nov 28 15:32:13 volumio avahi-daemon[655]: Registering new address record for 192.168.0.254 on wlan0.IPv4. Nov 28 15:32:13 volumio wireless.js[4538]: trying... Nov 28 15:32:13 volumio sudo[4644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 15:32:13 volumio sudo[4644]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:13 volumio sudo[4644]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:13 volumio wireless.js[4538]: Connected to: ----FAST_D334 Nov 28 15:32:13 volumio wireless.js[4538]: ---- Nov 28 15:32:13 volumio sudo[4647]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 15:32:13 volumio sudo[4647]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:13 volumio sudo[4647]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:13 volumio wireless.js[4538]: ... joined AP, wlan0 IPv4 is 192.168.0.254, ipV6 is undefined Nov 28 15:32:13 volumio wireless.js[4538]: It's done! AP Nov 28 15:32:14 volumio iwconfig[4651]: Error for wireless request "Set Power Management" (8B2C) : Nov 28 15:32:14 volumio iwconfig[4651]: SET failed on device wlan0 ; Operation not supported. Nov 28 15:32:14 volumio systemd[1]: Started Wireless Services. Nov 28 15:32:14 volumio sudo[4510]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:14 volumio volumio[3348]: info: Discovery: adding 9f143448-ad0e-4a59-b94b-5d8191c9e7b8 Nov 28 15:32:14 volumio volumio[3348]: info: Discovery: Found device Volumio Nov 28 15:32:14 volumio volumio[3348]: info: CoreCommandRouter::volumioGetState Nov 28 15:32:14 volumio volumio[3348]: info: CorePlayQueue::getTrack 1 Nov 28 15:32:14 volumio ntpd[816]: Listen normally on 4 wlan0 192.168.0.254:123 Nov 28 15:32:14 volumio ntpd[816]: new interface(s) found: waking up resolver Nov 28 15:32:16 volumio volumio[3348]: info: CoreCommandRouter::volumioNext Nov 28 15:32:16 volumio volumio[3348]: info: CoreStateMachine::next Nov 28 15:32:16 volumio volumio[3348]: info: CoreStateMachine::stop Nov 28 15:32:16 volumio volumio[3348]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 15:32:16 volumio volumio[3348]: info: CoreStateMachine::stPlaybackTimer Nov 28 15:32:16 volumio volumio[3348]: info: CoreStateMachine::updateTrackBlock Nov 28 15:32:16 volumio volumio[3348]: info: CorePlayQueue::getTrackBlock Nov 28 15:32:16 volumio volumio[3348]: info: CoreStateMachine::pushState Nov 28 15:32:16 volumio volumio[3348]: info: CorePlayQueue::getTrack 1 Nov 28 15:32:16 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 15:32:16 volumio volumio[3348]: info: CoreCommandRouter::volumioPushState Nov 28 15:32:16 volumio volumio[3348]: info: CoreStateMachine::serviceStop Nov 28 15:32:16 volumio volumio[3348]: info: CorePlayQueue::getTrack 1 Nov 28 15:32:16 volumio volumio[3348]: info: CoreCommandRouter::serviceStop Nov 28 15:32:16 volumio volumio[3348]: info: ControllerMpd::stop Nov 28 15:32:16 volumio volumio[3348]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 15:32:16 volumio volumio[3348]: info: Nov 28 15:32:16 volumio volumio[3348]: ---------------------------- MPD announces state update: player Nov 28 15:32:16 volumio volumio[3348]: info: sendMpdCommand stop took 32 milliseconds Nov 28 15:32:16 volumio volumio[3348]: info: ControllerMpd::getState Nov 28 15:32:16 volumio volumio[3348]: verbose: ControllerMpd::sendMpdCommand status Nov 28 15:32:16 volumio volumio[3348]: info: CoreStateMachine::play index undefined Nov 28 15:32:16 volumio volumio[3348]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 15:32:16 volumio volumio[3348]: info: CorePlayQueue::getTrack 2 Nov 28 15:32:16 volumio volumio[3348]: info: CoreStateMachine::startPlaybackTimer Nov 28 15:32:16 volumio volumio[3348]: info: CorePlayQueue::getTrack 2 Nov 28 15:32:16 volumio volumio[3348]: verbose: ControllerMpd::clearAddPlayTracks USB/F2C2-882F/滚石杂志评选500首最伟大的歌曲/001-100/003 - John Lennon - Imagine (1971).flac Nov 28 15:32:16 volumio volumio[3348]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 15:32:16 volumio volumio[3348]: info: CoreStateMachine::updateTrackBlock Nov 28 15:32:16 volumio volumio[3348]: info: CorePlayQueue::getTrackBlock Nov 28 15:32:16 volumio volumio[3348]: info: sendMpdCommand status took 8 milliseconds Nov 28 15:32:16 volumio volumio[3348]: info: sendMpdCommand stop took 3 milliseconds Nov 28 15:32:16 volumio volumio[3348]: verbose: ControllerMpd::parseState Nov 28 15:32:16 volumio volumio[3348]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 15:32:16 volumio volumio[3348]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 15:32:16 volumio volumio[3348]: info: Nov 28 15:32:16 volumio volumio[3348]: ---------------------------- MPD announces system playlist update Nov 28 15:32:16 volumio volumio[3348]: info: Ignoring MPD Status Update Nov 28 15:32:16 volumio volumio[3348]: info: sendMpdCommand playlistinfo took 8 milliseconds Nov 28 15:32:16 volumio volumio[3348]: info: sendMpdCommand clear took 10 milliseconds Nov 28 15:32:16 volumio volumio[3348]: verbose: ControllerMpd::parseTrackInfo Nov 28 15:32:16 volumio volumio[3348]: verbose: ControllerMpd::sendMpdCommand add "USB/F2C2-882F/滚石杂志评选500首最伟大的歌曲/001-100/003 - John Lennon - Imagine (1971).flac" Nov 28 15:32:16 volumio volumio[3348]: info: ControllerMpd::pushState Nov 28 15:32:16 volumio volumio[3348]: info: CoreCommandRouter::servicePushState Nov 28 15:32:16 volumio volumio[3348]: info: CoreStateMachine::pushState Nov 28 15:32:16 volumio volumio[3348]: info: CorePlayQueue::getTrack 2 Nov 28 15:32:16 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 15:32:16 volumio volumio[3348]: info: CoreCommandRouter::volumioPushState Nov 28 15:32:16 volumio volumio[3348]: info: CorePlayQueue::getTrack 2 Nov 28 15:32:16 volumio volumio[3348]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Satisfaction","artist":"The Rolling Stones","album":"Rolling Stone Magazine's 500 Greatest Songs Of All Time","uri":"USB/F2C2-882F/滚石杂志评选500首最伟大的歌曲/001-100/002 - The Rolling Stones - Satisfaction (1965).flac","trackType":"flac"} Nov 28 15:32:16 volumio volumio[3348]: verbose: CURRENT POSITION 2 Nov 28 15:32:16 volumio volumio[3348]: info: CoreStateMachine::syncState stateService stop Nov 28 15:32:16 volumio volumio[3348]: info: CoreStateMachine::syncState currentStatus stop Nov 28 15:32:16 volumio volumio[3348]: info: CoreStateMachine::pushState Nov 28 15:32:16 volumio volumio[3348]: info: CorePlayQueue::getTrack 2 Nov 28 15:32:16 volumio volumio[3348]: info: CoreCommandRouter::volumioPushState Nov 28 15:32:16 volumio volumio[3348]: info: No code Nov 28 15:32:16 volumio volumio[3348]: info: CoreStateMachine::pushState Nov 28 15:32:16 volumio volumio[3348]: info: CorePlayQueue::getTrack 2 Nov 28 15:32:16 volumio volumio[3348]: info: CoreCommandRouter::volumioPushState Nov 28 15:32:16 volumio volumio[3348]: info: ------------------------------ 81ms Nov 28 15:32:16 volumio volumio[3348]: error: updateQueue error: null Nov 28 15:32:16 volumio volumio[3348]: info: Nov 28 15:32:16 volumio volumio[3348]: ---------------------------- MPD announces system playlist update Nov 28 15:32:16 volumio volumio[3348]: info: Ignoring MPD Status Update Nov 28 15:32:16 volumio volumio[3348]: info: ------------------------------ 69ms Nov 28 15:32:16 volumio volumio[3348]: info: sendMpdCommand add "USB/F2C2-882F/滚石杂志评选500首最伟大的歌曲/001-100/003 - John Lennon - Imagine (1971).flac" took 61 milliseconds Nov 28 15:32:16 volumio volumio[3348]: verbose: ControllerMpd::sendMpdCommand play Nov 28 15:32:16 volumio volumio[3348]: info: Nov 28 15:32:16 volumio volumio[3348]: ---------------------------- MPD announces state update: player Nov 28 15:32:16 volumio volumio[3348]: info: ------------------------------ 15ms Nov 28 15:32:16 volumio volumio[3348]: info: sendMpdCommand play took 13 milliseconds Nov 28 15:32:16 volumio volumio[3348]: info: ControllerMpd::getState Nov 28 15:32:16 volumio volumio[3348]: verbose: ControllerMpd::sendMpdCommand status Nov 28 15:32:16 volumio volumio[3348]: info: Nov 28 15:32:16 volumio volumio[3348]: ---------------------------- MPD announces state update: player Nov 28 15:32:16 volumio volumio[3348]: info: ControllerMpd::getState Nov 28 15:32:16 volumio volumio[3348]: verbose: ControllerMpd::sendMpdCommand status Nov 28 15:32:16 volumio volumio[3348]: info: sendMpdCommand status took 15 milliseconds Nov 28 15:32:16 volumio volumio[3348]: verbose: ControllerMpd::parseState Nov 28 15:32:16 volumio volumio[3348]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 15:32:16 volumio volumio[3348]: info: sendMpdCommand status took 12 milliseconds Nov 28 15:32:16 volumio volumio[3348]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 28 15:32:16 volumio volumio[3348]: verbose: ControllerMpd::parseState Nov 28 15:32:16 volumio volumio[3348]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 15:32:16 volumio volumio[3348]: verbose: ControllerMpd::parseTrackInfo Nov 28 15:32:16 volumio volumio[3348]: info: ControllerMpd::pushState Nov 28 15:32:16 volumio volumio[3348]: info: CoreCommandRouter::servicePushState Nov 28 15:32:16 volumio volumio[3348]: info: CorePlayQueue::getTrack 2 Nov 28 15:32:16 volumio volumio[3348]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":184,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Imagine","artist":"John Lennon","album":"Rolling Stone Magazine's 500 Greatest Songs Of All Time","uri":"USB/F2C2-882F/滚石杂志评选500首最伟大的歌曲/001-100/003 - John Lennon - Imagine (1971).flac","trackType":"flac"} Nov 28 15:32:16 volumio volumio[3348]: verbose: CURRENT POSITION 2 Nov 28 15:32:16 volumio volumio[3348]: info: CoreStateMachine::syncState stateService play Nov 28 15:32:16 volumio volumio[3348]: info: CoreStateMachine::syncState currentStatus stop Nov 28 15:32:16 volumio volumio[3348]: info: ------------------------------ 48ms Nov 28 15:32:16 volumio volumio[3348]: info: sendMpdCommand playlistinfo took 31 milliseconds Nov 28 15:32:16 volumio volumio[3348]: verbose: ControllerMpd::parseTrackInfo Nov 28 15:32:16 volumio volumio[3348]: info: ControllerMpd::pushState Nov 28 15:32:16 volumio volumio[3348]: info: CoreCommandRouter::servicePushState Nov 28 15:32:16 volumio volumio[3348]: info: CorePlayQueue::getTrack 2 Nov 28 15:32:16 volumio volumio[3348]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":184,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"513 Kbps","isStreaming":false,"title":"Imagine","artist":"John Lennon","album":"Rolling Stone Magazine's 500 Greatest Songs Of All Time","uri":"USB/F2C2-882F/滚石杂志评选500首最伟大的歌曲/001-100/003 - John Lennon - Imagine (1971).flac","trackType":"flac"} Nov 28 15:32:16 volumio volumio[3348]: verbose: CURRENT POSITION 2 Nov 28 15:32:16 volumio volumio[3348]: info: CoreStateMachine::syncState stateService play Nov 28 15:32:16 volumio volumio[3348]: info: CoreStateMachine::syncState currentStatus play Nov 28 15:32:16 volumio volumio[3348]: info: Received an update from plugin. extracting info from payload Nov 28 15:32:16 volumio volumio[3348]: info: CoreStateMachine::pushState Nov 28 15:32:16 volumio volumio[3348]: info: CorePlayQueue::getTrack 2 Nov 28 15:32:16 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 15:32:16 volumio volumio[3348]: info: CoreCommandRouter::volumioPushState Nov 28 15:32:16 volumio volumio[3348]: info: CoreStateMachine::pushState Nov 28 15:32:16 volumio volumio[3348]: info: CorePlayQueue::getTrack 2 Nov 28 15:32:16 volumio volumio[3348]: info: CoreCommandRouter::volumioPushState Nov 28 15:32:16 volumio volumio[3348]: info: ------------------------------ 122ms Nov 28 15:32:16 volumio volumio[3348]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/John%20Lennon/Rolling%20Stone%20Magazine%27s%20500%20Greatest%20Songs%20Of%20All%20Time/85531d89-b48a-4676-8284-843a4a380f43.jpg' Nov 28 15:32:19 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Nov 28 15:32:19 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Nov 28 15:32:19 volumio sudo[4669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 15:32:19 volumio sudo[4669]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:19 volumio sudo[4669]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:19 volumio sudo[4683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Nov 28 15:32:19 volumio sudo[4683]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:19 volumio sudo[4683]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:21 volumio volumio[3348]: info: Volumio Network Manager: Network status updated: 2 Nov 28 15:32:23 volumio volumio[3348]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 15:32:23 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 28 15:32:23 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 28 15:32:23 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 28 15:32:23 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Nov 28 15:32:24 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Nov 28 15:32:24 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Nov 28 15:32:24 volumio volumio[3348]: info: Refreshing Cached IP Addresses Nov 28 15:32:24 volumio sudo[4731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Nov 28 15:32:24 volumio sudo[4731]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:24 volumio sudo[4731]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:24 volumio sudo[4734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 28 15:32:24 volumio sudo[4734]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:24 volumio sudo[4734]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:24 volumio volumio[3348]: info: Received Get System Version Nov 28 15:32:24 volumio volumio[3348]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 28 15:32:24 volumio systemd[1]: upmpdcli.service: Succeeded. Nov 28 15:32:24 volumio sudo[4737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 15:32:24 volumio sudo[4737]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:24 volumio sudo[4737]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:34 volumio sudo[4758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 28 15:32:34 volumio sudo[4758]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:34 volumio sudo[4758]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:34 volumio sudo[4761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 15:32:34 volumio sudo[4761]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:34 volumio sudo[4761]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:34 volumio sudo[4764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 28 15:32:34 volumio sudo[4764]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:32:34 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Nov 28 15:32:34 volumio sudo[4764]: pam_unix(sudo:session): session closed for user root Nov 28 15:32:34 volumio volumio[3348]: info: Upmpdcli Daemon Started Nov 28 15:32:34 volumio volumio[4766]: writing RSA key Nov 28 15:32:40 volumio volumio[3348]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 15:32:40 volumio volumio[3348]: Error: connect ETIMEDOUT 103.39.76.66:443 Nov 28 15:32:40 volumio volumio[3348]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Nov 28 15:32:40 volumio volumio[3348]: errno: -110, Nov 28 15:32:40 volumio volumio[3348]: code: 'ETIMEDOUT', Nov 28 15:32:40 volumio volumio[3348]: syscall: 'connect', Nov 28 15:32:40 volumio volumio[3348]: address: '103.39.76.66', Nov 28 15:32:40 volumio volumio[3348]: port: 443 Nov 28 15:32:40 volumio volumio[3348]: } Nov 28 15:32:40 volumio volumio[3348]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 15:32:40 volumio sudo[4819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-28 15:31 Nov 28 15:32:40 volumio sudo[4819]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"