-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Sun 2025-05-04 22:22:46 CST. -- May 04 22:21:00 volumio-1 sudo[27244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf May 04 22:21:00 volumio-1 sudo[27244]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:00 volumio-1 sudo[27244]: pam_unix(sudo:session): session closed for user root May 04 22:21:00 volumio-1 volumio[886]: info: Permissions for /etc/hostapd/hostapd.conf May 04 22:21:00 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 04 22:21:00 volumio-1 volumio[886]: info: Discovery: Restarting Advertising due to device name change May 04 22:21:00 volumio-1 sudo[27247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 04 22:21:00 volumio-1 sudo[27247]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:00 volumio-1 systemd[1]: Starting Wireless Services... May 04 22:21:00 volumio-1 wireless.js[27249]: Cleaning previous... May 04 22:21:00 volumio-1 systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... May 04 22:21:01 volumio-1 systemd[1]: hostapd.service: Succeeded. May 04 22:21:01 volumio-1 systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. May 04 22:21:01 volumio-1 sudo[27265]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 04 22:21:01 volumio-1 sudo[27265]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:01 volumio-1 avahi-daemon[629]: Withdrawing address record for 192.168.211.1 on wlan0. May 04 22:21:01 volumio-1 avahi-daemon[629]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. May 04 22:21:01 volumio-1 avahi-daemon[629]: Interface wlan0.IPv4 no longer relevant for mDNS. May 04 22:21:01 volumio-1 sudo[27265]: pam_unix(sudo:session): session closed for user root May 04 22:21:01 volumio-1 volumio[886]: info: Discovery: A device disappeared from network May 04 22:21:01 volumio-1 volumio[886]: info: Discovery: Device volumio-1 disappeared from network May 04 22:21:01 volumio-1 sudo[27267]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 04 22:21:01 volumio-1 sudo[27267]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:01 volumio-1 volumio[886]: info: Discovery: A device disappeared from network May 04 22:21:01 volumio-1 sudo[27267]: pam_unix(sudo:session): session closed for user root May 04 22:21:01 volumio-1 wireless.js[27249]: Stopped aP May 04 22:21:01 volumio-1 sudo[27274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 04 22:21:01 volumio-1 sudo[27274]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:01 volumio-1 sudo[27274]: pam_unix(sudo:session): session closed for user root May 04 22:21:01 volumio-1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 04 22:21:01 volumio-1 sudo[27276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 04 22:21:01 volumio-1 sudo[27276]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:01 volumio-1 sudo[27276]: pam_unix(sudo:session): session closed for user root May 04 22:21:01 volumio-1 sudo[27283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 04 22:21:01 volumio-1 sudo[27283]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:01 volumio-1 sudo[27283]: pam_unix(sudo:session): session closed for user root May 04 22:21:01 volumio-1 sudo[27285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 04 22:21:01 volumio-1 sudo[27285]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:02 volumio-1 sudo[27285]: pam_unix(sudo:session): session closed for user root May 04 22:21:02 volumio-1 wireless.js[27249]: SETTING APPROPRIATE REG DOMAIN: CN May 04 22:21:02 volumio-1 sudo[27290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 04 22:21:02 volumio-1 sudo[27290]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:02 volumio-1 sudo[27290]: pam_unix(sudo:session): session closed for user root May 04 22:21:02 volumio-1 sudo[27292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CN May 04 22:21:02 volumio-1 sudo[27292]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:02 volumio-1 sudo[27292]: pam_unix(sudo:session): session closed for user root May 04 22:21:02 volumio-1 wireless.js[27249]: SUCCESSFULLY SET NEW REGDOMAIN: CN May 04 22:21:02 volumio-1 sudo[27299]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 04 22:21:02 volumio-1 sudo[27299]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:02 volumio-1 sudo[27299]: pam_unix(sudo:session): session closed for user root May 04 22:21:02 volumio-1 sudo[27301]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 04 22:21:02 volumio-1 sudo[27301]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:03 volumio-1 sudo[27301]: pam_unix(sudo:session): session closed for user root May 04 22:21:03 volumio-1 avahi-daemon[629]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. May 04 22:21:03 volumio-1 avahi-daemon[629]: New relevant interface wlan0.IPv4 for mDNS. May 04 22:21:03 volumio-1 avahi-daemon[629]: Registering new address record for 192.168.211.1 on wlan0.IPv4. May 04 22:21:03 volumio-1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 04 22:21:03 volumio-1 systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... May 04 22:21:03 volumio-1 hostapd[27307]: Configuration file: /etc/hostapd/hostapd.conf May 04 22:21:03 volumio-1 hostapd[27307]: wlan0: Could not connect to kernel driver May 04 22:21:03 volumio-1 hostapd[27307]: Using interface wlan0 with hwaddr d8:3a:dd:7a:50:e2 and ssid "Volumio-D3414" May 04 22:21:03 volumio-1 hostapd[27307]: wlan0: interface state UNINITIALIZED->ENABLED May 04 22:21:03 volumio-1 hostapd[27307]: wlan0: AP-ENABLED May 04 22:21:03 volumio-1 systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. May 04 22:21:03 volumio-1 iwconfig[27310]: Error for wireless request "Set Power Management" (8B2C) : May 04 22:21:03 volumio-1 iwconfig[27310]: SET failed on device wlan0 ; Invalid argument. May 04 22:21:03 volumio-1 systemd[1]: wireless.service: Succeeded. May 04 22:21:03 volumio-1 systemd[1]: Started Wireless Services. May 04 22:21:03 volumio-1 sudo[27247]: pam_unix(sudo:session): session closed for user root May 04 22:21:05 volumio-1 volumio[886]: info: Discovery: Started advertising with name: volumio-1 May 04 22:21:08 volumio-1 volumio[886]: info: Discovery: adding 8e7ea0cd-046c-4488-879f-e6127dff9ce7 May 04 22:21:08 volumio-1 volumio[886]: info: Discovery: Found device volumio-1 May 04 22:21:08 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetState May 04 22:21:08 volumio-1 volumio[886]: info: Discovery: this is already registered, 8e7ea0cd-046c-4488-879f-e6127dff9ce7 May 04 22:21:08 volumio-1 volumio[886]: info: Discovery: Found device volumio-1 May 04 22:21:08 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetState May 04 22:21:08 volumio-1 volumio[886]: verbose: New Socket.io Connection to 192.168.8.110:3000 from 192.168.8.111 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 04 22:21:08 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 04 22:21:08 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetState May 04 22:21:08 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection May 04 22:21:08 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo May 04 22:21:08 volumio-1 sudo[27318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 04 22:21:08 volumio-1 sudo[27318]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:08 volumio-1 sudo[27318]: pam_unix(sudo:session): session closed for user root May 04 22:21:13 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart May 04 22:21:13 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart May 04 22:21:13 volumio-1 volumio[886]: info: Refreshing Cached IP Addresses May 04 22:21:13 volumio-1 sudo[27335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli May 04 22:21:13 volumio-1 sudo[27335]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:13 volumio-1 sudo[27335]: pam_unix(sudo:session): session closed for user root May 04 22:21:13 volumio-1 sudo[27337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 04 22:21:13 volumio-1 sudo[27337]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:13 volumio-1 sudo[27337]: pam_unix(sudo:session): session closed for user root May 04 22:21:13 volumio-1 volumio[886]: error: Cannot kill upmpdcli Error: Command failed: /usr/bin/sudo /usr/bin/killall upmpdcli May 04 22:21:13 volumio-1 volumio[886]: upmpdcli: no process found May 04 22:21:13 volumio-1 sudo[27341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 04 22:21:13 volumio-1 sudo[27341]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:13 volumio-1 sudo[27341]: pam_unix(sudo:session): session closed for user root May 04 22:21:14 volumio-1 sudo[27344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 04 22:21:14 volumio-1 sudo[27344]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:14 volumio-1 sudo[27344]: pam_unix(sudo:session): session closed for user root May 04 22:21:14 volumio-1 sudo[27347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 04 22:21:14 volumio-1 sudo[27347]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:14 volumio-1 sudo[27347]: pam_unix(sudo:session): session closed for user root May 04 22:21:14 volumio-1 volumio[886]: verbose: New Socket.io Connection to 192.168.8.110 from 192.168.8.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 8 May 04 22:21:14 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 04 22:21:14 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetVisibleSources May 04 22:21:14 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 22:21:14 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetState May 04 22:21:14 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 04 22:21:14 volumio-1 volumio[886]: info: CoreCommandRouter::getUIConfigOnPlugin May 04 22:21:14 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 04 22:21:14 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 04 22:21:14 volumio-1 volumio[886]: info: Received Get System Info May 04 22:21:14 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 04 22:21:14 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 04 22:21:14 volumio-1 volumio[886]: info: Discovery: Getting this device information May 04 22:21:14 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetState May 04 22:21:14 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 04 22:21:14 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetState May 04 22:21:14 volumio-1 volumio[886]: info: Listing playlists May 04 22:21:14 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 04 22:21:14 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 04 22:21:14 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 04 22:21:14 volumio-1 sudo[27352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 04 22:21:14 volumio-1 sudo[27352]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:14 volumio-1 sudo[27352]: pam_unix(sudo:session): session closed for user root May 04 22:21:14 volumio-1 sudo[27358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 04 22:21:14 volumio-1 sudo[27358]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:14 volumio-1 sudo[27358]: pam_unix(sudo:session): session closed for user root May 04 22:21:14 volumio-1 sudo[27366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 04 22:21:14 volumio-1 sudo[27366]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:14 volumio-1 sudo[27366]: pam_unix(sudo:session): session closed for user root May 04 22:21:14 volumio-1 sudo[27372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 04 22:21:14 volumio-1 sudo[27372]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:14 volumio-1 sudo[27372]: pam_unix(sudo:session): session closed for user root May 04 22:21:14 volumio-1 sudo[27377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 04 22:21:14 volumio-1 sudo[27377]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:14 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 04 22:21:14 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 04 22:21:14 volumio-1 sudo[27377]: pam_unix(sudo:session): session closed for user root May 04 22:21:14 volumio-1 sudo[27381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 04 22:21:14 volumio-1 sudo[27381]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:14 volumio-1 sudo[27381]: pam_unix(sudo:session): session closed for user root May 04 22:21:14 volumio-1 sudo[27384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 04 22:21:14 volumio-1 sudo[27384]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:15 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 04 22:21:16 volumio-1 sudo[27384]: pam_unix(sudo:session): session closed for user root May 04 22:21:16 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 04 22:21:16 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 04 22:21:16 volumio-1 volumio[886]: info: Received Get System Info May 04 22:21:16 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 04 22:21:16 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 04 22:21:16 volumio-1 volumio[886]: info: Discovery: Getting this device information May 04 22:21:16 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetState May 04 22:21:16 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 04 22:21:21 volumio-1 volumio[886]: verbose: New Socket.io Connection to 192.168.8.110 from 192.168.8.3 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 7 May 04 22:21:23 volumio-1 sudo[27405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 04 22:21:23 volumio-1 sudo[27405]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:23 volumio-1 sudo[27405]: pam_unix(sudo:session): session closed for user root May 04 22:21:23 volumio-1 sudo[27408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 04 22:21:23 volumio-1 sudo[27408]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:23 volumio-1 sudo[27408]: pam_unix(sudo:session): session closed for user root May 04 22:21:23 volumio-1 sudo[27411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 04 22:21:23 volumio-1 sudo[27411]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 22:21:24 volumio-1 systemd[1]: Started UPnP Renderer front-end to MPD. May 04 22:21:24 volumio-1 sudo[27411]: pam_unix(sudo:session): session closed for user root May 04 22:21:24 volumio-1 volumio[886]: info: Upmpdcli Daemon Started May 04 22:21:24 volumio-1 volumio[27413]: writing RSA key May 04 22:21:24 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 04 22:21:26 volumio-1 volumio[886]: info: Clearing queue after UPNP request May 04 22:21:27 volumio-1 volumio[886]: info: CoreStateMachine::ClearQueue May 04 22:21:27 volumio-1 volumio[886]: info: CoreStateMachine::stop May 04 22:21:27 volumio-1 volumio[886]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 22:21:27 volumio-1 volumio[886]: info: CorePlayQueue::clearPlayQueue May 04 22:21:27 volumio-1 volumio[886]: info: CorePlayQueue::saveQueue May 04 22:21:27 volumio-1 volumio[886]: info: CoreCommandRouter::volumioPushState May 04 22:21:27 volumio-1 volumio[886]: info: CoreCommandRouter::volumioPushQueue May 04 22:21:27 volumio-1 volumio[886]: info: May 04 22:21:27 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:27 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:27 volumio-1 volumio[886]: info: May 04 22:21:27 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:27 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:27 volumio-1 volumio[886]: info: May 04 22:21:27 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:27 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:27 volumio-1 volumio[886]: error: updateQueue error: null May 04 22:21:27 volumio-1 volumio[886]: error: updateQueue error: null May 04 22:21:27 volumio-1 volumio[886]: error: updateQueue error: null May 04 22:21:27 volumio-1 volumio[886]: info: ------------------------------ 3ms May 04 22:21:27 volumio-1 volumio[886]: info: ------------------------------ 2ms May 04 22:21:27 volumio-1 volumio[886]: info: ------------------------------ 1ms May 04 22:21:27 volumio-1 volumio[886]: info: Starting UPNP Playback May 04 22:21:27 volumio-1 volumio[886]: info: Preparing playback through UPNP May 04 22:21:27 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetState May 04 22:21:27 volumio-1 volumio[886]: info: CorePlayQueue::getTrack 0 May 04 22:21:27 volumio-1 volumio[886]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 22:21:27 volumio-1 volumio[886]: info: May 04 22:21:27 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:27 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:27 volumio-1 volumio[886]: info: May 04 22:21:27 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:27 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:27 volumio-1 volumio[886]: info: May 04 22:21:27 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:27 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:27 volumio-1 volumio[886]: info: May 04 22:21:27 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:27 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:27 volumio-1 volumio[886]: info: ------------------------------ 4ms May 04 22:21:27 volumio-1 volumio[886]: info: ------------------------------ 3ms May 04 22:21:27 volumio-1 volumio[886]: info: ------------------------------ 2ms May 04 22:21:27 volumio-1 volumio[886]: info: May 04 22:21:27 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:27 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:27 volumio-1 volumio[886]: info: May 04 22:21:27 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:27 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:27 volumio-1 volumio[886]: info: ------------------------------ 1ms May 04 22:21:27 volumio-1 volumio[886]: info: May 04 22:21:27 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:27 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:27 volumio-1 volumio[886]: info: May 04 22:21:27 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:27 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:27 volumio-1 volumio[886]: info: May 04 22:21:27 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:27 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:27 volumio-1 volumio[886]: info: ------------------------------ 3ms May 04 22:21:27 volumio-1 volumio[886]: info: ------------------------------ 2ms May 04 22:21:27 volumio-1 volumio[886]: info: May 04 22:21:27 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:27 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:27 volumio-1 volumio[886]: info: ------------------------------ 3ms May 04 22:21:27 volumio-1 volumio[886]: info: May 04 22:21:27 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:27 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:27 volumio-1 volumio[886]: info: May 04 22:21:27 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:27 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:27 volumio-1 volumio[886]: info: ------------------------------ 3ms May 04 22:21:27 volumio-1 volumio[886]: info: ------------------------------ 1ms May 04 22:21:27 volumio-1 volumio[886]: info: May 04 22:21:27 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:27 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:27 volumio-1 volumio[886]: info: ------------------------------ 2ms May 04 22:21:27 volumio-1 volumio[886]: info: May 04 22:21:27 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:27 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:27 volumio-1 volumio[886]: info: May 04 22:21:27 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:27 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:27 volumio-1 volumio[886]: info: ------------------------------ 4ms May 04 22:21:27 volumio-1 volumio[886]: info: ------------------------------ 3ms May 04 22:21:27 volumio-1 volumio[886]: info: ------------------------------ 2ms May 04 22:21:27 volumio-1 volumio[886]: info: May 04 22:21:27 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:27 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:27 volumio-1 volumio[886]: info: May 04 22:21:27 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:27 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:27 volumio-1 volumio[886]: info: May 04 22:21:27 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:27 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:27 volumio-1 volumio[886]: info: ------------------------------ 2ms May 04 22:21:27 volumio-1 volumio[886]: info: ------------------------------ 4ms May 04 22:21:27 volumio-1 volumio[886]: info: ------------------------------ 4ms May 04 22:21:28 volumio-1 volumio[886]: info: May 04 22:21:28 volumio-1 volumio[886]: ---------------------------- MPD announces state update: player May 04 22:21:28 volumio-1 volumio[886]: info: ControllerMpd::getState May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::sendMpdCommand status May 04 22:21:28 volumio-1 volumio[886]: info: May 04 22:21:28 volumio-1 volumio[886]: ---------------------------- MPD announces state update: player May 04 22:21:28 volumio-1 volumio[886]: info: ControllerMpd::getState May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::sendMpdCommand status May 04 22:21:28 volumio-1 volumio[886]: info: May 04 22:21:28 volumio-1 volumio[886]: ---------------------------- MPD announces state update: player May 04 22:21:28 volumio-1 volumio[886]: info: ControllerMpd::getState May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::sendMpdCommand status May 04 22:21:28 volumio-1 volumio[886]: info: May 04 22:21:28 volumio-1 volumio[886]: ---------------------------- MPD announces state update: player May 04 22:21:28 volumio-1 volumio[886]: info: ControllerMpd::getState May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::sendMpdCommand status May 04 22:21:28 volumio-1 volumio[886]: info: May 04 22:21:28 volumio-1 volumio[886]: ---------------------------- MPD announces state update: player May 04 22:21:28 volumio-1 volumio[886]: info: ControllerMpd::getState May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::sendMpdCommand status May 04 22:21:28 volumio-1 volumio[886]: info: May 04 22:21:28 volumio-1 volumio[886]: ---------------------------- MPD announces state update: player May 04 22:21:28 volumio-1 volumio[886]: info: sendMpdCommand status took 5 milliseconds May 04 22:21:28 volumio-1 volumio[886]: info: ControllerMpd::getState May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::sendMpdCommand status May 04 22:21:28 volumio-1 volumio[886]: info: sendMpdCommand status took 5 milliseconds May 04 22:21:28 volumio-1 volumio[886]: info: sendMpdCommand status took 4 milliseconds May 04 22:21:28 volumio-1 volumio[886]: info: sendMpdCommand status took 3 milliseconds May 04 22:21:28 volumio-1 volumio[886]: info: sendMpdCommand status took 2 milliseconds May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::parseState May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::parseState May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::parseState May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::parseState May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::parseState May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 22:21:28 volumio-1 volumio[886]: info: sendMpdCommand status took 3 milliseconds May 04 22:21:28 volumio-1 volumio[886]: info: sendMpdCommand playlistinfo took 2 milliseconds May 04 22:21:28 volumio-1 volumio[886]: info: sendMpdCommand playlistinfo took 2 milliseconds May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::parseState May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::parseTrackInfo May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::parseTrackInfo May 04 22:21:28 volumio-1 volumio[886]: info: ControllerMpd::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::servicePushState May 04 22:21:28 volumio-1 volumio[886]: verbose: In UPNP mode May 04 22:21:28 volumio-1 volumio[886]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"想起","artist":"韩雪","album":"飘雪","uri":"http://aqqmusic.tc.qq.com/F000000sVN7U1tca62.flac?guid=00000000220dc7310000018e057bcdd7&vkey=F0F8397352F687ED0DFDC52153736FEFC4196D33210D52E4CD2568A633F2D0A2739F4A490161036EAC4C500B5EFCF00AC4AAD4B4F88FCA54__v21e2a171d&uin=335238641&redirect=1&fromtag=111114","trackType":""} May 04 22:21:28 volumio-1 volumio[886]: verbose: CURRENT POSITION 0 May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::syncState stateService play May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::syncState currentStatus stop May 04 22:21:28 volumio-1 volumio[886]: info: ControllerMpd::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::servicePushState May 04 22:21:28 volumio-1 volumio[886]: verbose: In UPNP mode May 04 22:21:28 volumio-1 volumio[886]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"想起","artist":"韩雪","album":"飘雪","uri":"http://aqqmusic.tc.qq.com/F000000sVN7U1tca62.flac?guid=00000000220dc7310000018e057bcdd7&vkey=F0F8397352F687ED0DFDC52153736FEFC4196D33210D52E4CD2568A633F2D0A2739F4A490161036EAC4C500B5EFCF00AC4AAD4B4F88FCA54__v21e2a171d&uin=335238641&redirect=1&fromtag=111114","trackType":""} May 04 22:21:28 volumio-1 volumio[886]: verbose: CURRENT POSITION 0 May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::syncState stateService play May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::syncState currentStatus play May 04 22:21:28 volumio-1 volumio[886]: info: Received an update from plugin. extracting info from payload May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioPushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioPushState May 04 22:21:28 volumio-1 volumio[886]: info: ------------------------------ 14ms May 04 22:21:28 volumio-1 volumio[886]: info: ------------------------------ 15ms May 04 22:21:28 volumio-1 volumio[886]: info: sendMpdCommand playlistinfo took 9 milliseconds May 04 22:21:28 volumio-1 volumio[886]: info: sendMpdCommand playlistinfo took 10 milliseconds May 04 22:21:28 volumio-1 volumio[886]: info: sendMpdCommand playlistinfo took 9 milliseconds May 04 22:21:28 volumio-1 volumio[886]: info: sendMpdCommand playlistinfo took 7 milliseconds May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::parseTrackInfo May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::parseTrackInfo May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::parseTrackInfo May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::parseTrackInfo May 04 22:21:28 volumio-1 volumio[886]: info: ControllerMpd::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::servicePushState May 04 22:21:28 volumio-1 volumio[886]: verbose: In UPNP mode May 04 22:21:28 volumio-1 volumio[886]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"想起","artist":"韩雪","album":"飘雪","uri":"http://aqqmusic.tc.qq.com/F000000sVN7U1tca62.flac?guid=00000000220dc7310000018e057bcdd7&vkey=F0F8397352F687ED0DFDC52153736FEFC4196D33210D52E4CD2568A633F2D0A2739F4A490161036EAC4C500B5EFCF00AC4AAD4B4F88FCA54__v21e2a171d&uin=335238641&redirect=1&fromtag=111114","trackType":""} May 04 22:21:28 volumio-1 volumio[886]: verbose: CURRENT POSITION 0 May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::syncState stateService play May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::syncState currentStatus play May 04 22:21:28 volumio-1 volumio[886]: info: Received an update from plugin. extracting info from payload May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioPushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioPushState May 04 22:21:28 volumio-1 volumio[886]: info: ControllerMpd::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::servicePushState May 04 22:21:28 volumio-1 volumio[886]: verbose: In UPNP mode May 04 22:21:28 volumio-1 volumio[886]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"想起","artist":"韩雪","album":"飘雪","uri":"http://aqqmusic.tc.qq.com/F000000sVN7U1tca62.flac?guid=00000000220dc7310000018e057bcdd7&vkey=F0F8397352F687ED0DFDC52153736FEFC4196D33210D52E4CD2568A633F2D0A2739F4A490161036EAC4C500B5EFCF00AC4AAD4B4F88FCA54__v21e2a171d&uin=335238641&redirect=1&fromtag=111114","trackType":""} May 04 22:21:28 volumio-1 volumio[886]: verbose: CURRENT POSITION 0 May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::syncState stateService play May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::syncState currentStatus play May 04 22:21:28 volumio-1 volumio[886]: info: Received an update from plugin. extracting info from payload May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioPushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioPushState May 04 22:21:28 volumio-1 volumio[886]: info: ControllerMpd::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::servicePushState May 04 22:21:28 volumio-1 volumio[886]: verbose: In UPNP mode May 04 22:21:28 volumio-1 volumio[886]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"想起","artist":"韩雪","album":"飘雪","uri":"http://aqqmusic.tc.qq.com/F000000sVN7U1tca62.flac?guid=00000000220dc7310000018e057bcdd7&vkey=F0F8397352F687ED0DFDC52153736FEFC4196D33210D52E4CD2568A633F2D0A2739F4A490161036EAC4C500B5EFCF00AC4AAD4B4F88FCA54__v21e2a171d&uin=335238641&redirect=1&fromtag=111114","trackType":""} May 04 22:21:28 volumio-1 volumio[886]: verbose: CURRENT POSITION 0 May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::syncState stateService play May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::syncState currentStatus play May 04 22:21:28 volumio-1 volumio[886]: info: Received an update from plugin. extracting info from payload May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioPushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioPushState May 04 22:21:28 volumio-1 volumio[886]: info: ControllerMpd::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::servicePushState May 04 22:21:28 volumio-1 volumio[886]: verbose: In UPNP mode May 04 22:21:28 volumio-1 volumio[886]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"想起","artist":"韩雪","album":"飘雪","uri":"http://aqqmusic.tc.qq.com/F000000sVN7U1tca62.flac?guid=00000000220dc7310000018e057bcdd7&vkey=F0F8397352F687ED0DFDC52153736FEFC4196D33210D52E4CD2568A633F2D0A2739F4A490161036EAC4C500B5EFCF00AC4AAD4B4F88FCA54__v21e2a171d&uin=335238641&redirect=1&fromtag=111114","trackType":""} May 04 22:21:28 volumio-1 volumio[886]: verbose: CURRENT POSITION 0 May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::syncState stateService play May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::syncState currentStatus play May 04 22:21:28 volumio-1 volumio[886]: info: Received an update from plugin. extracting info from payload May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioPushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioPushState May 04 22:21:28 volumio-1 volumio[886]: info: ------------------------------ 35ms May 04 22:21:28 volumio-1 volumio[886]: info: ------------------------------ 35ms May 04 22:21:28 volumio-1 volumio[886]: info: ------------------------------ 34ms May 04 22:21:28 volumio-1 volumio[886]: info: ------------------------------ 33ms May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CorePlayQueue::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CorePlayQueue::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CorePlayQueue::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CorePlayQueue::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CorePlayQueue::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CorePlayQueue::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CorePlayQueue::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CorePlayQueue::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CorePlayQueue::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CorePlayQueue::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: May 04 22:21:28 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:28 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:28 volumio-1 volumio[886]: info: May 04 22:21:28 volumio-1 volumio[886]: ---------------------------- MPD announces state update: player May 04 22:21:28 volumio-1 volumio[886]: info: ControllerMpd::getState May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::sendMpdCommand status May 04 22:21:28 volumio-1 volumio[886]: info: May 04 22:21:28 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:28 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:28 volumio-1 volumio[886]: info: May 04 22:21:28 volumio-1 volumio[886]: ---------------------------- MPD announces state update: player May 04 22:21:28 volumio-1 volumio[886]: info: ControllerMpd::getState May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::sendMpdCommand status May 04 22:21:28 volumio-1 volumio[886]: info: ------------------------------ 3ms May 04 22:21:28 volumio-1 volumio[886]: info: sendMpdCommand status took 2 milliseconds May 04 22:21:28 volumio-1 volumio[886]: info: ------------------------------ 2ms May 04 22:21:28 volumio-1 volumio[886]: info: sendMpdCommand status took 1 milliseconds May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::parseState May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::parseState May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 22:21:28 volumio-1 volumio[886]: info: May 04 22:21:28 volumio-1 volumio[886]: ---------------------------- MPD announces system playlist update May 04 22:21:28 volumio-1 volumio[886]: info: Ignoring MPD Status Update May 04 22:21:28 volumio-1 volumio[886]: info: May 04 22:21:28 volumio-1 volumio[886]: ---------------------------- MPD announces state update: player May 04 22:21:28 volumio-1 volumio[886]: info: ControllerMpd::getState May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::sendMpdCommand status May 04 22:21:28 volumio-1 volumio[886]: info: sendMpdCommand playlistinfo took 2 milliseconds May 04 22:21:28 volumio-1 volumio[886]: info: sendMpdCommand playlistinfo took 2 milliseconds May 04 22:21:28 volumio-1 volumio[886]: info: ------------------------------ 2ms May 04 22:21:28 volumio-1 volumio[886]: info: sendMpdCommand status took 2 milliseconds May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::parseTrackInfo May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::parseTrackInfo May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::parseState May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 22:21:28 volumio-1 volumio[886]: info: ControllerMpd::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::servicePushState May 04 22:21:28 volumio-1 volumio[886]: verbose: In UPNP mode May 04 22:21:28 volumio-1 volumio[886]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"620 Kbps","isStreaming":false,"title":"想起","artist":"韩雪","album":"飘雪","uri":"http://aqqmusic.tc.qq.com/F000000sVN7U1tca62.flac?guid=00000000220dc7310000018e057bcdd7&vkey=F0F8397352F687ED0DFDC52153736FEFC4196D33210D52E4CD2568A633F2D0A2739F4A490161036EAC4C500B5EFCF00AC4AAD4B4F88FCA54__v21e2a171d&uin=335238641&redirect=1&fromtag=111114","trackType":""} May 04 22:21:28 volumio-1 volumio[886]: verbose: CURRENT POSITION 0 May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::syncState stateService play May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::syncState currentStatus play May 04 22:21:28 volumio-1 volumio[886]: info: Received an update from plugin. extracting info from payload May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioPushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioPushState May 04 22:21:28 volumio-1 volumio[886]: info: ControllerMpd::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::servicePushState May 04 22:21:28 volumio-1 volumio[886]: verbose: In UPNP mode May 04 22:21:28 volumio-1 volumio[886]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"755 Kbps","isStreaming":false,"title":"想起","artist":"韩雪","album":"飘雪","uri":"http://aqqmusic.tc.qq.com/F000000sVN7U1tca62.flac?guid=00000000220dc7310000018e057bcdd7&vkey=F0F8397352F687ED0DFDC52153736FEFC4196D33210D52E4CD2568A633F2D0A2739F4A490161036EAC4C500B5EFCF00AC4AAD4B4F88FCA54__v21e2a171d&uin=335238641&redirect=1&fromtag=111114","trackType":""} May 04 22:21:28 volumio-1 volumio[886]: verbose: CURRENT POSITION 0 May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::syncState stateService play May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::syncState currentStatus play May 04 22:21:28 volumio-1 volumio[886]: info: Received an update from plugin. extracting info from payload May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioPushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioPushState May 04 22:21:28 volumio-1 volumio[886]: info: ------------------------------ 18ms May 04 22:21:28 volumio-1 volumio[886]: info: ------------------------------ 16ms May 04 22:21:28 volumio-1 volumio[886]: info: sendMpdCommand playlistinfo took 13 milliseconds May 04 22:21:28 volumio-1 volumio[886]: verbose: ControllerMpd::parseTrackInfo May 04 22:21:28 volumio-1 volumio[886]: info: ControllerMpd::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::servicePushState May 04 22:21:28 volumio-1 volumio[886]: verbose: In UPNP mode May 04 22:21:28 volumio-1 volumio[886]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"755 Kbps","isStreaming":false,"title":"想起","artist":"韩雪","album":"飘雪","uri":"http://aqqmusic.tc.qq.com/F000000sVN7U1tca62.flac?guid=00000000220dc7310000018e057bcdd7&vkey=F0F8397352F687ED0DFDC52153736FEFC4196D33210D52E4CD2568A633F2D0A2739F4A490161036EAC4C500B5EFCF00AC4AAD4B4F88FCA54__v21e2a171d&uin=335238641&redirect=1&fromtag=111114","trackType":""} May 04 22:21:28 volumio-1 volumio[886]: verbose: CURRENT POSITION 0 May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::syncState stateService play May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::syncState currentStatus play May 04 22:21:28 volumio-1 volumio[886]: info: Received an update from plugin. extracting info from payload May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioPushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::pushState May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioPushState May 04 22:21:28 volumio-1 volumio[886]: info: ------------------------------ 20ms May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CorePlayQueue::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CorePlayQueue::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CorePlayQueue::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CorePlayQueue::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CorePlayQueue::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreCommandRouter::volumioGetQueue May 04 22:21:28 volumio-1 volumio[886]: info: CoreStateMachine::getQueue May 04 22:21:28 volumio-1 volumio[886]: info: CorePlayQueue::getQueue May 04 22:22:46 volumio-1 volumio[886]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 22:22:46 volumio-1 volumio[886]: Error: connect ETIMEDOUT 128.242.245.253:443 May 04 22:22:46 volumio-1 volumio[886]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { May 04 22:22:46 volumio-1 volumio[886]: errno: -110, May 04 22:22:46 volumio-1 volumio[886]: code: 'ETIMEDOUT', May 04 22:22:46 volumio-1 volumio[886]: syscall: 'connect', May 04 22:22:46 volumio-1 volumio[886]: address: '128.242.245.253', May 04 22:22:46 volumio-1 volumio[886]: port: 443 May 04 22:22:46 volumio-1 volumio[886]: } May 04 22:22:46 volumio-1 volumio[886]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 22:22:46 volumio-1 sudo[27813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-04 22:21 May 04 22:22:46 volumio-1 sudo[27813]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"