-- Logs begin at Tue 2026-02-24 18:37:40 CST, end at Tue 2026-02-24 18:40:42 CST. -- Feb 24 18:39:00 volumio volumio[1377]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 18:39:00 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 18:39:03 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 18:39:06 volumio volumio[1377]: info: CALLMETHOD: user_interface peppy_screensaver savePeppyMeterConf [object Object] Feb 24 18:39:06 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , savePeppyMeterConf Feb 24 18:39:06 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 18:39:06 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 18:39:06 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 18:39:06 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 18:39:06 volumio volumio[1377]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 18:39:06 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 18:39:09 volumio volumio[1377]: info: MyVolumio login type: Token Feb 24 18:39:10 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 18:39:12 volumio volumio[1377]: info: CoreCommandRouter::Close All Modals sent Feb 24 18:39:14 volumio nmbd[970]: [2026/02/24 18:39:14.856495, 0] ../source3/libsmb/nmblib.c:917(send_udp) Feb 24 18:39:14 volumio nmbd[970]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Feb 24 18:39:15 volumio volumio[1377]: info: CoreCommandRouter::volumioGetState Feb 24 18:39:15 volumio volumio[1377]: info: CorePlayQueue::getTrack 0 Feb 24 18:39:20 volumio volumio[1377]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 18:39:20 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 24 18:39:20 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Feb 24 18:39:20 volumio sudo[2557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Feb 24 18:39:20 volumio sudo[2557]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:20 volumio sudo[2557]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:20 volumio sudo[2563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 24 18:39:20 volumio sudo[2563]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:20 volumio sudo[2563]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:20 volumio sudo[2571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 24 18:39:20 volumio sudo[2571]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:20 volumio sudo[2571]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:20 volumio sudo[2576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 24 18:39:20 volumio sudo[2576]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:20 volumio sudo[2576]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:20 volumio sudo[2582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 24 18:39:20 volumio sudo[2582]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:20 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Feb 24 18:39:20 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 24 18:39:20 volumio sudo[2582]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:20 volumio sudo[2586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 18:39:20 volumio sudo[2586]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:20 volumio sudo[2586]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:20 volumio sudo[2588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 24 18:39:20 volumio sudo[2588]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:25 volumio sudo[2588]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:25 volumio volumio[1377]: info: VolumeController::SetAlsaVolume100 Feb 24 18:39:25 volumio volumio[1377]: info: CoreStateMachine::pushState Feb 24 18:39:25 volumio volumio[1377]: info: CorePlayQueue::getTrack 0 Feb 24 18:39:25 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:39:25 volumio volumio[1377]: info: CoreCommandRouter::volumioPushState Feb 24 18:39:25 volumio volumio[1377]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 24 18:39:27 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Feb 24 18:39:27 volumio volumio[1377]: info: Saving new wireless network Feb 24 18:39:27 volumio sudo[2631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Feb 24 18:39:27 volumio sudo[2631]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:27 volumio sudo[2631]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:27 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Feb 24 18:39:27 volumio volumio[1377]: info: Discovery: Restarting Advertising due to device name change Feb 24 18:39:27 volumio sudo[2634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Feb 24 18:39:27 volumio sudo[2634]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:27 volumio systemd[1]: Stopping Wireless Services... Feb 24 18:39:27 volumio wpa_supplicant[1127]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Feb 24 18:39:27 volumio wpa_supplicant[1127]: wlan0: CTRL-EVENT-TERMINATING Feb 24 18:39:27 volumio systemd[1]: wireless.service: Succeeded. Feb 24 18:39:27 volumio systemd[1]: Stopped Wireless Services. Feb 24 18:39:27 volumio systemd[1]: Starting Wireless Services... Feb 24 18:39:27 volumio wireless.js[2636]: Cleaning previous... Feb 24 18:39:27 volumio sudo[2650]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 24 18:39:27 volumio sudo[2650]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:27 volumio sudo[2650]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:27 volumio sudo[2652]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 24 18:39:27 volumio sudo[2652]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:27 volumio sudo[2652]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:27 volumio wireless.js[2636]: Stopped aP Feb 24 18:39:27 volumio sudo[2659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 24 18:39:27 volumio sudo[2659]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:27 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement) Feb 24 18:39:27 volumio kernel: brcmsmac bcma0:1: brcms_ops_config: change power-save mode: false (implement) Feb 24 18:39:27 volumio sudo[2659]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:27 volumio sudo[2663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 24 18:39:27 volumio sudo[2663]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:27 volumio sudo[2663]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:27 volumio sudo[2670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 24 18:39:27 volumio sudo[2670]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:27 volumio sudo[2670]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:27 volumio sudo[2672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 24 18:39:27 volumio sudo[2672]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:28 volumio volumio[1377]: info: Discovery: A device disappeared from network Feb 24 18:39:28 volumio volumio[1377]: info: Discovery: Device volumio disappeared from network Feb 24 18:39:30 volumio sudo[2672]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:30 volumio wireless.js[2636]: SETTING APPROPRIATE REG DOMAIN: CN Feb 24 18:39:30 volumio sudo[2684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 24 18:39:30 volumio sudo[2684]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:30 volumio sudo[2684]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:30 volumio sudo[2686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CN Feb 24 18:39:30 volumio sudo[2686]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:30 volumio sudo[2686]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:30 volumio wireless.js[2636]: SUCCESSFULLY SET NEW REGDOMAIN: CN Feb 24 18:39:30 volumio wireless.js[2636]: Start wireless flow Feb 24 18:39:30 volumio wireless.js[2636]: Stopped hotspot (if there).. Feb 24 18:39:30 volumio sudo[2691]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 24 18:39:30 volumio sudo[2691]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:30 volumio sudo[2691]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:30 volumio sudo[2693]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 24 18:39:30 volumio sudo[2693]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:30 volumio sudo[2693]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:30 volumio wireless.js[2636]: DHCP IP Feb 24 18:39:30 volumio wireless.js[2636]: Start ap Feb 24 18:39:30 volumio wpa_supplicant[2695]: Successfully initialized wpa_supplicant Feb 24 18:39:30 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement) Feb 24 18:39:30 volumio kernel: brcmsmac bcma0:1: brcms_ops_config: change power-save mode: false (implement) Feb 24 18:39:30 volumio sudo[2696]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Feb 24 18:39:30 volumio sudo[2696]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:30 volumio dhcpcd[2697]: sending commands to master dhcpcd process Feb 24 18:39:30 volumio dhcpcd[889]: control command: /sbin/dhcpcd Feb 24 18:39:30 volumio sudo[2696]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:31 volumio wireless.js[2636]: trying... Feb 24 18:39:31 volumio sudo[2703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 24 18:39:31 volumio sudo[2703]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:31 volumio sudo[2703]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:32 volumio wireless.js[2636]: trying... Feb 24 18:39:32 volumio sudo[2708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 24 18:39:32 volumio sudo[2708]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:32 volumio volumio[1377]: info: Discovery: Started advertising with name: Volumio Feb 24 18:39:32 volumio sudo[2708]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:32 volumio wpa_supplicant[2698]: wlan0: SME: Trying to authenticate with 50:0f:f5:d6:2f:11 (SSID='Tenda_D62EF0' freq=5240 MHz) Feb 24 18:39:32 volumio kernel: wlan0: authenticate with 50:0f:f5:d6:2f:11 Feb 24 18:39:32 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Feb 24 18:39:32 volumio wpa_supplicant[2698]: wlan0: Trying to associate with 50:0f:f5:d6:2f:11 (SSID='Tenda_D62EF0' freq=5240 MHz) Feb 24 18:39:32 volumio kernel: wlan0: send auth to 50:0f:f5:d6:2f:11 (try 1/3) Feb 24 18:39:32 volumio kernel: wlan0: authenticated Feb 24 18:39:32 volumio wpa_supplicant[2698]: wlan0: Associated with 50:0f:f5:d6:2f:11 Feb 24 18:39:32 volumio wpa_supplicant[2698]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 24 18:39:32 volumio wpa_supplicant[2698]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Feb 24 18:39:32 volumio kernel: wlan0: associate with 50:0f:f5:d6:2f:11 (try 1/3) Feb 24 18:39:32 volumio kernel: wlan0: RX AssocResp from 50:0f:f5:d6:2f:11 (capab=0x1131 status=0 aid=24) Feb 24 18:39:32 volumio kernel: brcmsmac bcma0:1: brcmsmac: brcms_ops_bss_info_changed: associated Feb 24 18:39:32 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: true (implement) Feb 24 18:39:32 volumio kernel: wlan0: associated Feb 24 18:39:32 volumio kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 50:0f:f5:d6:2f:11 Feb 24 18:39:32 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: true (implement) Feb 24 18:39:32 volumio wpa_supplicant[2698]: wlan0: WPA: Key negotiation completed with 50:0f:f5:d6:2f:11 [PTK=CCMP GTK=CCMP] Feb 24 18:39:32 volumio wpa_supplicant[2698]: wlan0: CTRL-EVENT-CONNECTED - Connection to 50:0f:f5:d6:2f:11 completed [id=0 id_str=] Feb 24 18:39:32 volumio dhcpcd[889]: wlan0: carrier acquired Feb 24 18:39:32 volumio dhcpcd[889]: wlan0: IAID 7f:cb:90:80 Feb 24 18:39:33 volumio dhcpcd[889]: wlan0: soliciting an IPv6 router Feb 24 18:39:33 volumio wireless.js[2636]: trying... Feb 24 18:39:33 volumio sudo[2745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 24 18:39:33 volumio sudo[2745]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:33 volumio sudo[2745]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:33 volumio volumio[1377]: info: Discovery: adding 6576be8c-9ca3-4f6e-ad09-3e064e726785 Feb 24 18:39:33 volumio volumio[1377]: info: Discovery: Found device Volumio Feb 24 18:39:33 volumio volumio[1377]: info: CoreCommandRouter::volumioGetState Feb 24 18:39:33 volumio volumio[1377]: info: CorePlayQueue::getTrack 0 Feb 24 18:39:33 volumio dhcpcd[889]: wlan0: soliciting a DHCP lease Feb 24 18:39:33 volumio dhcpcd[889]: wlan0: offered 192.168.0.130 from 192.168.0.1 Feb 24 18:39:33 volumio dhcpcd[889]: wlan0: probing address 192.168.0.130/24 Feb 24 18:39:34 volumio wireless.js[2636]: trying... Feb 24 18:39:34 volumio sudo[2752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 24 18:39:34 volumio sudo[2752]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:34 volumio sudo[2752]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:35 volumio wireless.js[2636]: trying... Feb 24 18:39:35 volumio sudo[2757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 24 18:39:35 volumio sudo[2757]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:35 volumio sudo[2757]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:35 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Feb 24 18:39:35 volumio volumio[1377]: info: Saving new wireless network Feb 24 18:39:35 volumio sudo[2762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Feb 24 18:39:35 volumio sudo[2762]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:35 volumio sudo[2762]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:35 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Feb 24 18:39:35 volumio volumio[1377]: info: Discovery: Restarting Advertising due to device name change Feb 24 18:39:35 volumio sudo[2765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Feb 24 18:39:35 volumio sudo[2765]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:35 volumio systemd[1]: wireless.service: Control process exited, code=killed, status=15/TERM Feb 24 18:39:35 volumio systemd[1]: wireless.service: Killing process 2698 (wpa_supplicant) with signal SIGKILL. Feb 24 18:39:35 volumio systemd[1]: wireless.service: Succeeded. Feb 24 18:39:35 volumio kernel: wlan0: deauthenticating from 50:0f:f5:d6:2f:11 by local choice (Reason: 3=DEAUTH_LEAVING) Feb 24 18:39:35 volumio dhcpcd[889]: wlan0: carrier lost Feb 24 18:39:35 volumio systemd[1]: Stopped Wireless Services. Feb 24 18:39:35 volumio systemd[1]: Starting Wireless Services... Feb 24 18:39:35 volumio kernel: brcmsmac bcma0:1: brcmsmac: brcms_ops_bss_info_changed: disassociated Feb 24 18:39:35 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement) Feb 24 18:39:35 volumio wireless.js[2768]: Cleaning previous... Feb 24 18:39:36 volumio sudo[2811]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 24 18:39:36 volumio sudo[2811]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:36 volumio sudo[2811]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:36 volumio sudo[2813]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 24 18:39:36 volumio sudo[2813]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:36 volumio sudo[2813]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:36 volumio wireless.js[2768]: Stopped aP Feb 24 18:39:36 volumio sudo[2820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 24 18:39:36 volumio sudo[2820]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:36 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement) Feb 24 18:39:36 volumio kernel: brcmsmac bcma0:1: brcms_ops_config: change power-save mode: false (implement) Feb 24 18:39:36 volumio sudo[2820]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:36 volumio sudo[2822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 24 18:39:36 volumio sudo[2822]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:36 volumio sudo[2822]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:36 volumio sudo[2829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 24 18:39:36 volumio sudo[2829]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:36 volumio sudo[2829]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:36 volumio sudo[2831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 24 18:39:36 volumio sudo[2831]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:36 volumio volumio[1377]: info: Discovery: A device disappeared from network Feb 24 18:39:36 volumio volumio[1377]: info: Discovery: Device volumio disappeared from network Feb 24 18:39:38 volumio sudo[2831]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:38 volumio wireless.js[2768]: SETTING APPROPRIATE REG DOMAIN: CN Feb 24 18:39:38 volumio sudo[2842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 24 18:39:38 volumio sudo[2842]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:38 volumio sudo[2842]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:38 volumio sudo[2844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CN Feb 24 18:39:38 volumio sudo[2844]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:38 volumio sudo[2844]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:38 volumio wireless.js[2768]: SUCCESSFULLY SET NEW REGDOMAIN: CN Feb 24 18:39:38 volumio wireless.js[2768]: Start wireless flow Feb 24 18:39:38 volumio wireless.js[2768]: Stopped hotspot (if there).. Feb 24 18:39:38 volumio sudo[2849]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 24 18:39:38 volumio sudo[2849]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:38 volumio sudo[2849]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:38 volumio sudo[2851]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 24 18:39:38 volumio sudo[2851]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:38 volumio sudo[2851]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:38 volumio wireless.js[2768]: DHCP IP Feb 24 18:39:38 volumio wireless.js[2768]: Start ap Feb 24 18:39:38 volumio wpa_supplicant[2853]: Successfully initialized wpa_supplicant Feb 24 18:39:38 volumio sudo[2854]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Feb 24 18:39:38 volumio sudo[2854]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:38 volumio dhcpcd[2855]: sending commands to master dhcpcd process Feb 24 18:39:38 volumio dhcpcd[889]: control command: /sbin/dhcpcd Feb 24 18:39:38 volumio sudo[2854]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:38 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement) Feb 24 18:39:38 volumio kernel: brcmsmac bcma0:1: brcms_ops_config: change power-save mode: false (implement) Feb 24 18:39:39 volumio wireless.js[2768]: trying... Feb 24 18:39:39 volumio sudo[2860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 24 18:39:39 volumio sudo[2860]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:39 volumio sudo[2860]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:39 volumio volumio[1377]: info: CoreCommandRouter::volumioGetState Feb 24 18:39:39 volumio volumio[1377]: info: CorePlayQueue::getTrack 0 Feb 24 18:39:39 volumio volumio[1377]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 24 18:39:39 volumio volumio[1377]: info: Retrying Login Due to Network Error Feb 24 18:39:40 volumio volumio[1377]: info: Discovery: Started advertising with name: Volumio Feb 24 18:39:40 volumio wireless.js[2768]: trying... Feb 24 18:39:40 volumio sudo[2866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 24 18:39:40 volumio sudo[2866]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:40 volumio sudo[2866]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:41 volumio volumio[1377]: info: CoreCommandRouter::volumioNext Feb 24 18:39:41 volumio volumio[1377]: info: CoreStateMachine::next Feb 24 18:39:41 volumio volumio[1377]: info: CoreStateMachine::stop Feb 24 18:39:41 volumio volumio[1377]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:39:41 volumio volumio[1377]: info: CoreStateMachine::stPlaybackTimer Feb 24 18:39:41 volumio volumio[1377]: info: CoreStateMachine::updateTrackBlock Feb 24 18:39:41 volumio volumio[1377]: info: CorePlayQueue::getTrackBlock Feb 24 18:39:41 volumio volumio[1377]: info: CoreStateMachine::pushState Feb 24 18:39:41 volumio volumio[1377]: info: CorePlayQueue::getTrack 0 Feb 24 18:39:41 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:39:41 volumio volumio[1377]: info: CoreCommandRouter::volumioPushState Feb 24 18:39:41 volumio volumio[1377]: info: CoreStateMachine::serviceStop Feb 24 18:39:41 volumio volumio[1377]: info: CorePlayQueue::getTrack 0 Feb 24 18:39:41 volumio volumio[1377]: info: CoreCommandRouter::serviceStop Feb 24 18:39:41 volumio volumio[1377]: info: ControllerMpd::stop Feb 24 18:39:41 volumio volumio[1377]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:39:41 volumio volumio[1377]: info: Feb 24 18:39:41 volumio volumio[1377]: ---------------------------- MPD announces state update: player Feb 24 18:39:41 volumio volumio[1377]: info: sendMpdCommand stop took 28 milliseconds Feb 24 18:39:41 volumio volumio[1377]: info: ControllerMpd::getState Feb 24 18:39:41 volumio volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:39:41 volumio volumio[1377]: info: CoreStateMachine::play index undefined Feb 24 18:39:41 volumio volumio[1377]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:39:41 volumio volumio[1377]: info: CorePlayQueue::getTrack 1 Feb 24 18:39:41 volumio volumio[1377]: info: CoreStateMachine::startPlaybackTimer Feb 24 18:39:41 volumio volumio[1377]: info: CorePlayQueue::getTrack 1 Feb 24 18:39:41 volumio volumio[1377]: verbose: ControllerMpd::clearAddPlayTracks USB/赵鹏 - 北国之春.flac Feb 24 18:39:41 volumio volumio[1377]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:39:41 volumio volumio[1377]: info: CoreStateMachine::updateTrackBlock Feb 24 18:39:41 volumio volumio[1377]: info: CorePlayQueue::getTrackBlock Feb 24 18:39:41 volumio volumio[1377]: info: sendMpdCommand status took 8 milliseconds Feb 24 18:39:41 volumio volumio[1377]: info: sendMpdCommand stop took 3 milliseconds Feb 24 18:39:41 volumio volumio[1377]: verbose: ControllerMpd::parseState Feb 24 18:39:41 volumio volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:39:41 volumio volumio[1377]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 18:39:41 volumio volumio[1377]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 24 18:39:41 volumio volumio[1377]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:39:41 volumio volumio[1377]: info: ControllerMpd::pushState Feb 24 18:39:41 volumio volumio[1377]: info: CoreCommandRouter::servicePushState Feb 24 18:39:41 volumio volumio[1377]: info: CoreStateMachine::pushState Feb 24 18:39:41 volumio volumio[1377]: info: CorePlayQueue::getTrack 1 Feb 24 18:39:41 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:39:41 volumio volumio[1377]: info: CoreCommandRouter::volumioPushState Feb 24 18:39:41 volumio volumio[1377]: info: CorePlayQueue::getTrack 1 Feb 24 18:39:41 volumio volumio[1377]: 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":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Feb 24 18:39:41 volumio volumio[1377]: verbose: CURRENT POSITION 1 Feb 24 18:39:41 volumio volumio[1377]: info: CoreStateMachine::syncState stateService stop Feb 24 18:39:41 volumio volumio[1377]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:39:41 volumio volumio[1377]: info: CoreStateMachine::pushState Feb 24 18:39:41 volumio volumio[1377]: info: CorePlayQueue::getTrack 1 Feb 24 18:39:41 volumio volumio[1377]: info: CoreCommandRouter::volumioPushState Feb 24 18:39:41 volumio volumio[1377]: info: No code Feb 24 18:39:41 volumio volumio[1377]: info: CoreStateMachine::pushState Feb 24 18:39:41 volumio volumio[1377]: info: CorePlayQueue::getTrack 1 Feb 24 18:39:41 volumio volumio[1377]: info: CoreCommandRouter::volumioPushState Feb 24 18:39:41 volumio volumio[1377]: info: ------------------------------ 71ms Feb 24 18:39:41 volumio wpa_supplicant[2856]: wlan0: SME: Trying to authenticate with 50:0f:f5:d6:2f:11 (SSID='Tenda_D62EF0' freq=5240 MHz) Feb 24 18:39:41 volumio volumio[1377]: info: Feb 24 18:39:41 volumio volumio[1377]: ---------------------------- MPD announces system playlist update Feb 24 18:39:41 volumio kernel: wlan0: authenticate with 50:0f:f5:d6:2f:11 Feb 24 18:39:41 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Feb 24 18:39:41 volumio kernel: wlan0: send auth to 50:0f:f5:d6:2f:11 (try 1/3) Feb 24 18:39:41 volumio kernel: wlan0: authenticated Feb 24 18:39:41 volumio volumio[1377]: info: Ignoring MPD Status Update Feb 24 18:39:41 volumio wpa_supplicant[2856]: wlan0: Trying to associate with 50:0f:f5:d6:2f:11 (SSID='Tenda_D62EF0' freq=5240 MHz) Feb 24 18:39:41 volumio volumio[1377]: info: sendMpdCommand clear took 71 milliseconds Feb 24 18:39:41 volumio volumio[1377]: verbose: ControllerMpd::sendMpdCommand add "USB/赵鹏 - 北国之春.flac" Feb 24 18:39:41 volumio kernel: wlan0: associate with 50:0f:f5:d6:2f:11 (try 1/3) Feb 24 18:39:41 volumio kernel: wlan0: RX AssocResp from 50:0f:f5:d6:2f:11 (capab=0x1131 status=0 aid=24) Feb 24 18:39:41 volumio kernel: brcmsmac bcma0:1: brcmsmac: brcms_ops_bss_info_changed: associated Feb 24 18:39:41 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: true (implement) Feb 24 18:39:41 volumio kernel: wlan0: associated Feb 24 18:39:41 volumio wpa_supplicant[2856]: wlan0: Associated with 50:0f:f5:d6:2f:11 Feb 24 18:39:41 volumio wpa_supplicant[2856]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 24 18:39:41 volumio wpa_supplicant[2856]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Feb 24 18:39:41 volumio volumio[1377]: error: updateQueue error: null Feb 24 18:39:41 volumio volumio[1377]: info: Feb 24 18:39:41 volumio volumio[1377]: ---------------------------- MPD announces system playlist update Feb 24 18:39:41 volumio volumio[1377]: info: Ignoring MPD Status Update Feb 24 18:39:41 volumio volumio[1377]: info: ------------------------------ 28ms Feb 24 18:39:41 volumio volumio[1377]: info: sendMpdCommand add "USB/赵鹏 - 北国之春.flac" took 29 milliseconds Feb 24 18:39:41 volumio volumio[1377]: verbose: ControllerMpd::sendMpdCommand play Feb 24 18:39:41 volumio volumio[1377]: info: ------------------------------ 9ms Feb 24 18:39:41 volumio volumio[1377]: info: sendMpdCommand play took 3 milliseconds Feb 24 18:39:41 volumio volumio[1377]: info: Feb 24 18:39:41 volumio volumio[1377]: ---------------------------- MPD announces state update: player Feb 24 18:39:41 volumio volumio[1377]: info: ControllerMpd::getState Feb 24 18:39:41 volumio volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:39:41 volumio volumio[1377]: info: Feb 24 18:39:41 volumio volumio[1377]: ---------------------------- MPD announces state update: player Feb 24 18:39:41 volumio volumio[1377]: info: ControllerMpd::getState Feb 24 18:39:41 volumio volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:39:41 volumio volumio[1377]: info: sendMpdCommand status took 11 milliseconds Feb 24 18:39:41 volumio volumio[1377]: info: sendMpdCommand status took 9 milliseconds Feb 24 18:39:41 volumio volumio[1377]: verbose: ControllerMpd::parseState Feb 24 18:39:41 volumio volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:39:41 volumio volumio[1377]: verbose: ControllerMpd::parseState Feb 24 18:39:41 volumio volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:39:41 volumio volumio[1377]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 24 18:39:41 volumio volumio[1377]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 24 18:39:41 volumio volumio[1377]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:39:41 volumio volumio[1377]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:39:41 volumio volumio[1377]: info: ControllerMpd::pushState Feb 24 18:39:41 volumio volumio[1377]: info: CoreCommandRouter::servicePushState Feb 24 18:39:41 volumio volumio[1377]: info: CorePlayQueue::getTrack 1 Feb 24 18:39:41 volumio volumio[1377]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"864 Kbps","isStreaming":false,"title":"北国之春","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 北国之春.flac","trackType":"flac"} Feb 24 18:39:41 volumio volumio[1377]: verbose: CURRENT POSITION 1 Feb 24 18:39:41 volumio volumio[1377]: info: CoreStateMachine::syncState stateService play Feb 24 18:39:41 volumio volumio[1377]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:39:41 volumio volumio[1377]: info: ControllerMpd::pushState Feb 24 18:39:41 volumio volumio[1377]: info: CoreCommandRouter::servicePushState Feb 24 18:39:41 volumio volumio[1377]: info: CorePlayQueue::getTrack 1 Feb 24 18:39:41 volumio volumio[1377]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"864 Kbps","isStreaming":false,"title":"北国之春","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 北国之春.flac","trackType":"flac"} Feb 24 18:39:41 volumio volumio[1377]: verbose: CURRENT POSITION 1 Feb 24 18:39:41 volumio volumio[1377]: info: CoreStateMachine::syncState stateService play Feb 24 18:39:41 volumio volumio[1377]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:39:41 volumio volumio[1377]: info: Received an update from plugin. extracting info from payload Feb 24 18:39:41 volumio volumio[1377]: info: CoreStateMachine::pushState Feb 24 18:39:41 volumio volumio[1377]: info: CorePlayQueue::getTrack 1 Feb 24 18:39:41 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:39:41 volumio volumio[1377]: info: CoreCommandRouter::volumioPushState Feb 24 18:39:41 volumio kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 50:0f:f5:d6:2f:11 Feb 24 18:39:41 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: true (implement) Feb 24 18:39:41 volumio volumio[1377]: info: CoreStateMachine::pushState Feb 24 18:39:41 volumio volumio[1377]: info: CorePlayQueue::getTrack 1 Feb 24 18:39:41 volumio volumio[1377]: info: CoreCommandRouter::volumioPushState Feb 24 18:39:41 volumio volumio[1377]: info: ------------------------------ 42ms Feb 24 18:39:41 volumio volumio[1377]: info: ------------------------------ 54ms Feb 24 18:39:41 volumio wpa_supplicant[2856]: wlan0: WPA: Key negotiation completed with 50:0f:f5:d6:2f:11 [PTK=CCMP GTK=CCMP] Feb 24 18:39:41 volumio wpa_supplicant[2856]: wlan0: CTRL-EVENT-CONNECTED - Connection to 50:0f:f5:d6:2f:11 completed [id=0 id_str=] Feb 24 18:39:41 volumio dhcpcd[889]: wlan0: carrier acquired Feb 24 18:39:41 volumio dhcpcd[889]: wlan0: IAID 7f:cb:90:80 Feb 24 18:39:41 volumio volumio[1377]: info: Discovery: adding 6576be8c-9ca3-4f6e-ad09-3e064e726785 Feb 24 18:39:41 volumio volumio[1377]: info: Discovery: Found device Volumio Feb 24 18:39:41 volumio volumio[1377]: info: CoreCommandRouter::volumioGetState Feb 24 18:39:41 volumio volumio[1377]: info: CorePlayQueue::getTrack 1 Feb 24 18:39:41 volumio wireless.js[2768]: trying... Feb 24 18:39:41 volumio sudo[2900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 24 18:39:41 volumio sudo[2900]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:41 volumio sudo[2900]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:42 volumio dhcpcd[889]: wlan0: soliciting a DHCP lease Feb 24 18:39:42 volumio dhcpcd[889]: wlan0: offered 192.168.0.130 from 192.168.0.1 Feb 24 18:39:42 volumio dhcpcd[889]: wlan0: probing address 192.168.0.130/24 Feb 24 18:39:42 volumio dhcpcd[889]: wlan0: soliciting an IPv6 router Feb 24 18:39:42 volumio wireless.js[2768]: trying... Feb 24 18:39:42 volumio sudo[2952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 24 18:39:42 volumio sudo[2952]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:42 volumio sudo[2952]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:43 volumio wireless.js[2768]: trying... Feb 24 18:39:43 volumio sudo[2971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 24 18:39:43 volumio sudo[2971]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:43 volumio sudo[2971]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:44 volumio wireless.js[2768]: trying... Feb 24 18:39:44 volumio sudo[2976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 24 18:39:44 volumio sudo[2976]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:44 volumio sudo[2976]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:45 volumio wireless.js[2768]: trying... Feb 24 18:39:45 volumio sudo[2984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 24 18:39:45 volumio sudo[2984]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:45 volumio sudo[2984]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:46 volumio wireless.js[2768]: trying... Feb 24 18:39:46 volumio sudo[2989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 24 18:39:46 volumio sudo[2989]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:46 volumio sudo[2989]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:47 volumio wireless.js[2768]: trying... Feb 24 18:39:47 volumio sudo[2994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 24 18:39:47 volumio sudo[2994]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:47 volumio sudo[2994]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:47 volumio dhcpcd[889]: wlan0: leased 192.168.0.130 for 86400 seconds Feb 24 18:39:47 volumio dhcpcd[889]: wlan0: adding route to 192.168.0.0/24 Feb 24 18:39:47 volumio dhcpcd[889]: wlan0: adding default route via 192.168.0.1 Feb 24 18:39:47 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: arp filtering: 1 addresses (implement) Feb 24 18:39:47 volumio avahi-daemon[773]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.130. Feb 24 18:39:47 volumio avahi-daemon[773]: New relevant interface wlan0.IPv4 for mDNS. Feb 24 18:39:47 volumio avahi-daemon[773]: Registering new address record for 192.168.0.130 on wlan0.IPv4. Feb 24 18:39:47 volumio ntpd[1330]: ntpd exiting on signal 15 (Terminated) Feb 24 18:39:47 volumio systemd[1]: Stopping Network Time Service... Feb 24 18:39:47 volumio ntpd[1330]: 193.182.111.142 local addr 192.168.0.202 -> Feb 24 18:39:47 volumio ntpd[1330]: 111.230.189.174 local addr 192.168.0.202 -> Feb 24 18:39:47 volumio ntpd[1330]: 162.159.200.123 local addr 192.168.0.202 -> Feb 24 18:39:47 volumio ntpd[1330]: 139.199.215.251 local addr 192.168.0.202 -> Feb 24 18:39:47 volumio ntpd[1330]: 119.28.183.184 local addr 192.168.0.202 -> Feb 24 18:39:47 volumio ntpd[1330]: 84.16.73.33 local addr 192.168.0.202 -> Feb 24 18:39:47 volumio ntpd[1330]: 129.204.45.40 local addr 192.168.0.202 -> Feb 24 18:39:47 volumio ntpd[1330]: 120.236.184.252 local addr 192.168.0.202 -> Feb 24 18:39:47 volumio ntpd[1330]: 84.16.67.12 local addr 192.168.0.202 -> Feb 24 18:39:47 volumio ntpd[1330]: 114.66.25.29 local addr 192.168.0.202 -> Feb 24 18:39:47 volumio ntpd[1330]: 116.203.151.74 local addr 192.168.0.202 -> Feb 24 18:39:47 volumio systemd[1]: ntp.service: Succeeded. Feb 24 18:39:47 volumio ntpd[1330]: 117.80.231.60 local addr 192.168.0.202 -> Feb 24 18:39:47 volumio systemd[1]: Stopped Network Time Service. Feb 24 18:39:47 volumio systemd[1]: Starting Network Time Service... Feb 24 18:39:47 volumio ntpd[3031]: ntpd 4.2.8p12@1.3728-o (1): Starting Feb 24 18:39:47 volumio ntpd[3031]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Feb 24 18:39:48 volumio ntpd[3037]: proto: precision = 0.156 usec (-23) Feb 24 18:39:48 volumio systemd[1]: Started Network Time Service. Feb 24 18:39:48 volumio ntpd[3037]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 24 18:39:48 volumio ntpd[3037]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 24 18:39:48 volumio ntpd[3037]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1155 days ago Feb 24 18:39:48 volumio ntpd[3037]: Listen and drop on 0 v6wildcard [::]:123 Feb 24 18:39:48 volumio ntpd[3037]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 24 18:39:48 volumio ntpd[3037]: Listen normally on 2 lo 127.0.0.1:123 Feb 24 18:39:48 volumio ntpd[3037]: Listen normally on 3 eth0 192.168.0.202:123 Feb 24 18:39:48 volumio ntpd[3037]: Listen normally on 4 wlan0 192.168.0.130:123 Feb 24 18:39:48 volumio ntpd[3037]: Listening on routing socket on fd #21 for interface updates Feb 24 18:39:48 volumio ntpd[3037]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 24 18:39:48 volumio ntpd[3037]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 24 18:39:48 volumio volumio[1377]: info: Discovery: this is already registered, 6576be8c-9ca3-4f6e-ad09-3e064e726785 Feb 24 18:39:48 volumio volumio[1377]: info: Discovery: Found device Volumio Feb 24 18:39:48 volumio volumio[1377]: info: CoreCommandRouter::volumioGetState Feb 24 18:39:48 volumio volumio[1377]: info: CorePlayQueue::getTrack 1 Feb 24 18:39:48 volumio wireless.js[2768]: trying... Feb 24 18:39:48 volumio sudo[3043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 24 18:39:48 volumio sudo[3043]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:48 volumio sudo[3043]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:48 volumio wireless.js[2768]: Connected to: ----Tenda_D62EF0 Feb 24 18:39:48 volumio wireless.js[2768]: ---- Feb 24 18:39:48 volumio sudo[3047]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 18:39:48 volumio sudo[3047]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:48 volumio sudo[3047]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:48 volumio wireless.js[2768]: ... joined AP, wlan0 IPv4 is 192.168.0.130, ipV6 is undefined Feb 24 18:39:48 volumio wireless.js[2768]: It's done! AP Feb 24 18:39:48 volumio volumio[1377]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8 Feb 24 18:39:48 volumio iwconfig[3052]: Error for wireless request "Set Power Management" (8B2C) : Feb 24 18:39:48 volumio iwconfig[3052]: SET failed on device wlan0 ; Operation not supported. Feb 24 18:39:48 volumio systemd[1]: Started Wireless Services. Feb 24 18:39:48 volumio sudo[2634]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:48 volumio sudo[2765]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:48 volumio volumio[1377]: info: CoreCommandRouter::volumioGetState Feb 24 18:39:48 volumio volumio[1377]: info: CorePlayQueue::getTrack 1 Feb 24 18:39:49 volumio ntpd[3037]: Soliciting pool server 193.182.111.142 Feb 24 18:39:49 volumio volumio[1377]: info: MyVolumio login type: Token Feb 24 18:39:50 volumio ntpd[3037]: Soliciting pool server 111.230.189.174 Feb 24 18:39:51 volumio ntpd[3037]: Soliciting pool server 119.28.183.184 Feb 24 18:39:51 volumio ntpd[3037]: Soliciting pool server 162.159.200.123 Feb 24 18:39:51 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 18:39:52 volumio ntpd[3037]: Soliciting pool server 193.182.111.143 Feb 24 18:39:52 volumio ntpd[3037]: Soliciting pool server 120.236.184.252 Feb 24 18:39:53 volumio ntpd[3037]: Soliciting pool server 84.16.73.33 Feb 24 18:39:53 volumio ntpd[3037]: Soliciting pool server 116.203.151.74 Feb 24 18:39:53 volumio volumio[1377]: info: Volumio Network Manager: Network status updated: 3 Feb 24 18:39:53 volumio volumio[1377]: info: CoreCommandRouter::Close All Modals sent Feb 24 18:39:53 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Feb 24 18:39:53 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Feb 24 18:39:53 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Feb 24 18:39:53 volumio sudo[3096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 18:39:53 volumio sudo[3096]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:53 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Feb 24 18:39:53 volumio sudo[3096]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:53 volumio sudo[3099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 18:39:53 volumio sudo[3099]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:53 volumio sudo[3101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 24 18:39:53 volumio sudo[3101]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:53 volumio sudo[3101]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:53 volumio sudo[3099]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:53 volumio sudo[3109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 24 18:39:53 volumio sudo[3109]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:39:53 volumio sudo[3109]: pam_unix(sudo:session): session closed for user root Feb 24 18:39:55 volumio volumio[1377]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 18:39:55 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 18:39:58 volumio ntpd[3037]: receive: Unexpected origin timestamp 0xed47fe7d.001e0860 does not match aorg 0000000000.00000000 from server@120.236.184.252 xmt 0xed47fe7e.39eb4868 Feb 24 18:39:58 volumio ntpd[3037]: receive: Unexpected origin timestamp 0xed47fe7d.00190d18 does not match aorg 0000000000.00000000 from server@193.182.111.143 xmt 0xed47fe7e.53d71561 Feb 24 18:39:59 volumio volumio[1377]: info: CALLMETHOD: user_interface peppy_screensaver savePeppyMeterConf [object Object] Feb 24 18:39:59 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , savePeppyMeterConf Feb 24 18:39:59 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 18:39:59 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 18:39:59 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 18:40:00 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Feb 24 18:40:00 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Feb 24 18:40:00 volumio volumio[1377]: info: Refreshing Cached IP Addresses Feb 24 18:40:00 volumio sudo[3129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Feb 24 18:40:00 volumio sudo[3129]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:40:00 volumio sudo[3129]: pam_unix(sudo:session): session closed for user root Feb 24 18:40:00 volumio sudo[3132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 24 18:40:00 volumio sudo[3132]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:40:00 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Feb 24 18:40:00 volumio sudo[3132]: pam_unix(sudo:session): session closed for user root Feb 24 18:40:00 volumio sudo[3134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 18:40:00 volumio sudo[3134]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:40:00 volumio sudo[3134]: pam_unix(sudo:session): session closed for user root Feb 24 18:40:00 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Feb 24 18:40:00 volumio volumio[1377]: info: Refreshing Cached IP Addresses Feb 24 18:40:00 volumio sudo[3140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Feb 24 18:40:00 volumio sudo[3140]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:40:00 volumio sudo[3140]: pam_unix(sudo:session): session closed for user root Feb 24 18:40:00 volumio sudo[3143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 24 18:40:00 volumio sudo[3143]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:40:00 volumio sudo[3143]: pam_unix(sudo:session): session closed for user root Feb 24 18:40:00 volumio systemd[1]: upmpdcli.service: Succeeded. Feb 24 18:40:00 volumio sudo[3146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 18:40:00 volumio sudo[3146]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:40:00 volumio sudo[3146]: pam_unix(sudo:session): session closed for user root Feb 24 18:40:04 volumio sudo[2420]: pam_unix(sudo:session): session closed for user root Feb 24 18:40:10 volumio sudo[3171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 24 18:40:10 volumio sudo[3171]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:40:10 volumio sudo[3171]: pam_unix(sudo:session): session closed for user root Feb 24 18:40:10 volumio sudo[3173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 18:40:10 volumio sudo[3173]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:40:10 volumio sudo[3173]: pam_unix(sudo:session): session closed for user root Feb 24 18:40:10 volumio sudo[3177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 24 18:40:10 volumio sudo[3177]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:40:10 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 24 18:40:10 volumio sudo[3177]: pam_unix(sudo:session): session closed for user root Feb 24 18:40:10 volumio sudo[3181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 24 18:40:10 volumio sudo[3181]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:40:10 volumio volumio[1377]: info: Upmpdcli Daemon Started Feb 24 18:40:10 volumio sudo[3181]: pam_unix(sudo:session): session closed for user root Feb 24 18:40:10 volumio sudo[3184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 18:40:10 volumio sudo[3184]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:40:10 volumio sudo[3184]: pam_unix(sudo:session): session closed for user root Feb 24 18:40:10 volumio sudo[3188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 24 18:40:10 volumio sudo[3188]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:40:10 volumio sudo[3188]: pam_unix(sudo:session): session closed for user root Feb 24 18:40:10 volumio volumio[3179]: writing RSA key Feb 24 18:40:10 volumio volumio[1377]: info: Upmpdcli Daemon Started Feb 24 18:40:15 volumio volumio[1377]: info: CoreCommandRouter::volumioGetState Feb 24 18:40:15 volumio volumio[1377]: info: CorePlayQueue::getTrack 1 Feb 24 18:40:15 volumio sudo[3247]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Feb 24 18:40:15 volumio sudo[3247]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 18:40:16 volumio volumio[1377]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 Feb 24 18:40:16 volumio volumio[1377]: info: CoreCommandRouter::volumioGetState Feb 24 18:40:16 volumio volumio[1377]: info: CorePlayQueue::getTrack 1 Feb 24 18:40:17 volumio volumio[1377]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8 Feb 24 18:40:17 volumio volumio[1377]: info: CoreCommandRouter::volumioGetState Feb 24 18:40:17 volumio volumio[1377]: info: CorePlayQueue::getTrack 1 Feb 24 18:40:21 volumio volumio[1377]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 24 18:40:21 volumio volumio[1377]: info: Retrying Login Due to Network Error Feb 24 18:40:29 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 18:40:30 volumio volumio[1377]: info: CoreCommandRouter::Close All Modals sent Feb 24 18:40:31 volumio volumio[1377]: info: MyVolumio login type: Token Feb 24 18:40:33 volumio volumio[1377]: info: CoreCommandRouter::volumioGetState Feb 24 18:40:33 volumio volumio[1377]: info: CorePlayQueue::getTrack 1 Feb 24 18:40:37 volumio volumio[1377]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 18:40:37 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 24 18:40:37 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 18:40:37 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 24 18:40:37 volumio volumio[1377]: Invalid card number. Feb 24 18:40:37 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 18:40:37 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 18:40:37 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 18:40:37 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 18:40:37 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 18:40:37 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 18:40:37 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 18:40:37 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 24 18:40:37 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 24 18:40:41 volumio volumio[1377]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 24 18:40:41 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 24 18:40:41 volumio volumio[1377]: info: Preparing to save Alsa Options, stopping services first Feb 24 18:40:41 volumio volumio[1377]: info: CoreCommandRouter::volumioGetState Feb 24 18:40:41 volumio volumio[1377]: info: CorePlayQueue::getTrack 1 Feb 24 18:40:41 volumio volumio[1377]: info: CoreCommandRouter::volumioPause Feb 24 18:40:41 volumio volumio[1377]: info: CoreStateMachine::pause Feb 24 18:40:41 volumio volumio[1377]: info: CoreStateMachine::stPlaybackTimer Feb 24 18:40:41 volumio volumio[1377]: info: CoreStateMachine::servicePause Feb 24 18:40:41 volumio volumio[1377]: info: CorePlayQueue::getTrack 1 Feb 24 18:40:41 volumio volumio[1377]: info: CoreCommandRouter::servicePause Feb 24 18:40:41 volumio volumio[1377]: info: ControllerMpd::pause Feb 24 18:40:41 volumio volumio[1377]: verbose: ControllerMpd::sendMpdCommand pause Feb 24 18:40:41 volumio volumio[1377]: info: Saving Audio Output to: {"output_device":{"value":"0,0","label":"Analog Out"}} Feb 24 18:40:41 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 24 18:40:41 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 24 18:40:41 volumio volumio[1377]: info: Found match in Cards Database: setting mixer for card Analog Out Feb 24 18:40:41 volumio volumio[1377]: info: Setting mixer Master for card Analog Out Feb 24 18:40:41 volumio volumio[1377]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 24 18:40:41 volumio volumio[1377]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 24 18:40:41 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 24 18:40:41 volumio volumio[1377]: info: Disabling external Volume Control Feb 24 18:40:41 volumio volumio[1377]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 18:40:41 volumio volumio[1377]: info: Preparing to generate the ALSA configuration file Feb 24 18:40:41 volumio volumio[1377]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Feb 24 18:40:41 volumio volumio[1377]: info: Reading ALSA contributions from plugins. Feb 24 18:40:41 volumio volumio[1377]: info: Feb 24 18:40:41 volumio volumio[1377]: ---------------------------- MPD announces state update: player Feb 24 18:40:41 volumio volumio[1377]: info: sendMpdCommand pause took 170 milliseconds Feb 24 18:40:41 volumio volumio[1377]: info: ControllerMpd::getState Feb 24 18:40:41 volumio volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:40:41 volumio volumio[1377]: info: sendMpdCommand status took 15 milliseconds Feb 24 18:40:41 volumio volumio[1377]: verbose: ControllerMpd::parseState Feb 24 18:40:41 volumio volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:40:41 volumio volumio[1377]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 24 18:40:41 volumio volumio[1377]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:40:41 volumio volumio[1377]: info: ControllerMpd::pushState Feb 24 18:40:41 volumio volumio[1377]: info: CoreCommandRouter::servicePushState Feb 24 18:40:41 volumio volumio[1377]: info: CorePlayQueue::getTrack 1 Feb 24 18:40:41 volumio volumio[1377]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":59966,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"830 Kbps","isStreaming":false,"title":"北国之春","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 北国之春.flac","trackType":"flac"} Feb 24 18:40:41 volumio volumio[1377]: verbose: CURRENT POSITION 1 Feb 24 18:40:41 volumio volumio[1377]: info: CoreStateMachine::syncState stateService pause Feb 24 18:40:41 volumio volumio[1377]: info: CoreStateMachine::syncState currentStatus pause Feb 24 18:40:41 volumio volumio[1377]: info: CoreStateMachine::pushState Feb 24 18:40:41 volumio volumio[1377]: info: CorePlayQueue::getTrack 1 Feb 24 18:40:41 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:40:41 volumio volumio[1377]: info: CoreCommandRouter::volumioPushState Feb 24 18:40:41 volumio volumio[1377]: info: CoreStateMachine::stPlaybackTimer Feb 24 18:40:41 volumio volumio[1377]: info: ------------------------------ 44ms Feb 24 18:40:41 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 24 18:40:41 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 18:40:41 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 24 18:40:41 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 18:40:41 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 18:40:41 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 24 18:40:41 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 24 18:40:41 volumio volumio[1377]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 18:40:41 volumio volumio[1377]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Feb 24 18:40:41 volumio volumio[1377]: at Object.readdirSync (fs.js:1048:3) Feb 24 18:40:41 volumio volumio[1377]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Feb 24 18:40:41 volumio volumio[1377]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Feb 24 18:40:41 volumio volumio[1377]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Feb 24 18:40:41 volumio volumio[1377]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Feb 24 18:40:41 volumio volumio[1377]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Feb 24 18:40:41 volumio volumio[1377]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Feb 24 18:40:41 volumio volumio[1377]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Feb 24 18:40:41 volumio volumio[1377]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Feb 24 18:40:41 volumio volumio[1377]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Feb 24 18:40:41 volumio volumio[1377]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Feb 24 18:40:41 volumio volumio[1377]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Feb 24 18:40:41 volumio volumio[1377]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Feb 24 18:40:41 volumio volumio[1377]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Feb 24 18:40:41 volumio volumio[1377]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Feb 24 18:40:41 volumio volumio[1377]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Feb 24 18:40:41 volumio volumio[1377]: errno: -20, Feb 24 18:40:41 volumio volumio[1377]: syscall: 'scandir', Feb 24 18:40:41 volumio volumio[1377]: code: 'ENOTDIR', Feb 24 18:40:41 volumio volumio[1377]: path: '/tmp/peppyrunning' Feb 24 18:40:41 volumio volumio[1377]: } Feb 24 18:40:41 volumio volumio[1377]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 18:40:42 volumio sudo[3412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-24 18:39 Feb 24 18:40:42 volumio sudo[3412]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 10:07:11 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"