-- Logs begin at Sat 2025-05-17 10:48:11 CST, end at Sat 2025-05-17 15:15:44 CST. -- May 17 15:14:02 volumio-2w volumio[18463]: verbose: New Socket.io Connection to 192.168.4.50 from 192.168.4.88 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.95 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 17 15:14:02 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetState May 17 15:14:02 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 30 May 17 15:14:02 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetQueue May 17 15:14:02 volumio-2w volumio[18463]: info: CoreStateMachine::getQueue May 17 15:14:02 volumio-2w volumio[18463]: info: CorePlayQueue::getQueue May 17 15:14:02 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid May 17 15:14:02 volumio-2w volumio[18463]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] May 17 15:14:02 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction May 17 15:14:02 volumio-2w volumio[18463]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs May 17 15:14:02 volumio-2w volumio[18463]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] May 17 15:14:02 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection May 17 15:14:04 volumio-2w volumio[18463]: info: CALLMETHOD: system_controller system setTestSystem true May 17 15:14:04 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: system , setTestSystem May 17 15:14:04 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: updater_comm , checkUpdates May 17 15:14:04 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 17 15:14:04 volumio-2w volumio[18463]: info: Device is now in test mode May 17 15:14:04 volumio-2w volumio-remote-updater[476]: Test mode enabled May 17 15:14:04 volumio-2w volumio-remote-updater[476]: No alpha test mode May 17 15:14:04 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 17 15:14:05 volumio-2w volumio[18463]: info: CALLMETHOD: system_controller system setTestPlugins true May 17 15:14:05 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: system , setTestPlugins May 17 15:14:05 volumio-2w volumio[18463]: info: Plugins store is now in test mode May 17 15:14:06 volumio-2w volumio[18463]: info: CALLMETHOD: system_controller system enableSSH true May 17 15:14:06 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: system , enableSSH May 17 15:14:06 volumio-2w sudo[21274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ssh.service May 17 15:14:06 volumio-2w sudo[21274]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 15:14:06 volumio-2w volumio[18463]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 17 15:14:06 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 17 15:14:06 volumio-2w systemd[1]: Starting OpenBSD Secure Shell server... May 17 15:14:07 volumio-2w sshd[21277]: Server listening on 0.0.0.0 port 22. May 17 15:14:07 volumio-2w sshd[21277]: Server listening on :: port 22. May 17 15:14:07 volumio-2w systemd[1]: Started OpenBSD Secure Shell server. May 17 15:14:07 volumio-2w sudo[21274]: pam_unix(sudo:session): session closed for user root May 17 15:14:07 volumio-2w sudo[21278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable ssh.service May 17 15:14:07 volumio-2w sudo[21278]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 15:14:07 volumio-2w systemd[1]: Reloading. May 17 15:14:08 volumio-2w systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 17 15:14:09 volumio-2w systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. May 17 15:14:09 volumio-2w systemd[1]: Reloading. May 17 15:14:09 volumio-2w systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 17 15:14:10 volumio-2w systemd[1]: Reloading. May 17 15:14:10 volumio-2w systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 17 15:14:11 volumio-2w sudo[21278]: pam_unix(sudo:session): session closed for user root May 17 15:14:11 volumio-2w volumio[18463]: info: enable SSH service success May 17 15:14:19 volumio-2w volumio[18463]: info: CALLMETHOD: system_controller system enableSSH true May 17 15:14:19 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: system , enableSSH May 17 15:14:19 volumio-2w sudo[21344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ssh.service May 17 15:14:19 volumio-2w sudo[21344]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 15:14:19 volumio-2w sudo[21344]: pam_unix(sudo:session): session closed for user root May 17 15:14:19 volumio-2w sudo[21346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable ssh.service May 17 15:14:19 volumio-2w sudo[21346]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 15:14:19 volumio-2w systemd[1]: Reloading. May 17 15:14:20 volumio-2w systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 17 15:14:20 volumio-2w volumio[18463]: info: CALLMETHOD: system_controller system setTestPlugins true May 17 15:14:20 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: system , setTestPlugins May 17 15:14:20 volumio-2w volumio[18463]: info: Plugins store is now in test mode May 17 15:14:20 volumio-2w systemd[1]: Reloading. May 17 15:14:21 volumio-2w volumio[18463]: info: CALLMETHOD: system_controller system setTestSystem true May 17 15:14:21 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: system , setTestSystem May 17 15:14:21 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: updater_comm , checkUpdates May 17 15:14:21 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 17 15:14:21 volumio-2w volumio[18463]: info: Device is now in test mode May 17 15:14:21 volumio-2w systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 17 15:14:21 volumio-2w volumio-remote-updater[476]: Test mode enabled May 17 15:14:21 volumio-2w volumio-remote-updater[476]: No alpha test mode May 17 15:14:21 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 17 15:14:21 volumio-2w systemd[1]: Reloading. May 17 15:14:21 volumio-2w volumio[18463]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 17 15:14:21 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 17 15:14:22 volumio-2w systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 17 15:14:22 volumio-2w sudo[21346]: pam_unix(sudo:session): session closed for user root May 17 15:14:22 volumio-2w volumio[18463]: info: enable SSH service success May 17 15:14:25 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 17 15:14:25 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 17 15:14:25 volumio-2w volumio[18463]: info: Discovery: Getting this device information May 17 15:14:25 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetState May 17 15:14:25 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 30 May 17 15:14:25 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 17 15:14:41 volumio-2w volumio[18463]: verbose: New Socket.io Connection to 192.168.4.50 from 192.168.4.88 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.95 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 17 15:14:41 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetState May 17 15:14:41 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 30 May 17 15:14:41 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 17 15:14:41 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetVisibleSources May 17 15:14:41 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 17 15:14:41 volumio-2w volumio[18463]: info: Listing playlists May 17 15:14:41 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 17 15:14:41 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 17 15:14:41 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 17 15:14:41 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 17 15:14:44 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 17 15:14:44 volumio-2w volumio[18463]: info: Received Get System Info May 17 15:14:44 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 17 15:14:44 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 17 15:14:44 volumio-2w volumio[18463]: info: Discovery: Getting this device information May 17 15:14:44 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetState May 17 15:14:44 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 30 May 17 15:14:44 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 17 15:14:55 volumio-2w volumio[18463]: info: CALLMETHOD: miscellanea appearance setVolumio3UI [object Object] May 17 15:14:55 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: appearance , setVolumio3UI May 17 15:14:55 volumio-2w volumio[18463]: info: Setting active UI to: {"uiPrettyName":"Manifest","uiName":"manifest","uiPath":"/volumio/http/www4"} May 17 15:14:55 volumio-2w sudo[21466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/active_volumio_ui May 17 15:14:55 volumio-2w sudo[21466]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 15:14:55 volumio-2w sudo[21466]: pam_unix(sudo:session): session closed for user root May 17 15:14:55 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 17 15:14:55 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 17 15:14:55 volumio-2w volumio[18463]: info: Discovery: Getting this device information May 17 15:14:55 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetState May 17 15:14:55 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 30 May 17 15:14:55 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 17 15:14:56 volumio-2w volumio[18463]: info: CoreCommandRouter::Reload Ui May 17 15:14:58 volumio-2w sudo[21470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 17 15:14:58 volumio-2w sudo[21470]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 15:14:58 volumio-2w sudo[21472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 17 15:14:58 volumio-2w sudo[21472]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 15:14:58 volumio-2w sudo[21470]: pam_unix(sudo:session): session closed for user root May 17 15:14:58 volumio-2w sudo[21472]: pam_unix(sudo:session): session closed for user root May 17 15:14:58 volumio-2w sudo[21478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 17 15:14:58 volumio-2w sudo[21478]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 15:14:58 volumio-2w sudo[21478]: pam_unix(sudo:session): session closed for user root May 17 15:14:58 volumio-2w sudo[21480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 17 15:14:58 volumio-2w sudo[21480]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 15:14:58 volumio-2w volumio[18463]: verbose: New Socket.io Connection to 192.168.4.50 from 192.168.4.88 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.95 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 17 15:14:58 volumio-2w sudo[21480]: pam_unix(sudo:session): session closed for user root May 17 15:14:58 volumio-2w volumio[18463]: verbose: New Socket.io Connection to 192.168.4.50 from 192.168.4.7 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 8 May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetVisibleSources May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetState May 17 15:14:58 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 30 May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::getUIConfigOnPlugin May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetVisibleSources May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetState May 17 15:14:58 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 30 May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 17 15:14:58 volumio-2w volumio[18463]: info: Received Get System Info May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 17 15:14:58 volumio-2w volumio[18463]: info: Discovery: Getting this device information May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetState May 17 15:14:58 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 30 May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetState May 17 15:14:58 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 30 May 17 15:14:58 volumio-2w volumio[18463]: info: Listing playlists May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 17 15:14:58 volumio-2w volumio[18463]: info: Received Get System Info May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 17 15:14:58 volumio-2w volumio[18463]: info: Discovery: Getting this device information May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetState May 17 15:14:58 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 30 May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetState May 17 15:14:58 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 30 May 17 15:14:58 volumio-2w volumio[18463]: info: Listing playlists May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 17 15:14:58 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 17 15:14:59 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 17 15:14:59 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 17 15:14:59 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 17 15:14:59 volumio-2w sudo[21504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 17 15:14:59 volumio-2w sudo[21504]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 15:14:59 volumio-2w volumio[18463]: info: Received Get System Version May 17 15:14:59 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 17 15:14:59 volumio-2w sudo[21504]: pam_unix(sudo:session): session closed for user root May 17 15:14:59 volumio-2w sudo[21512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 17 15:14:59 volumio-2w sudo[21512]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 15:14:59 volumio-2w sudo[21512]: pam_unix(sudo:session): session closed for user root May 17 15:14:59 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetState May 17 15:14:59 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 30 May 17 15:14:59 volumio-2w volumio[18463]: verbose: New Socket.io Connection to 192.168.4.50 from 192.168.4.11 UA: Mozilla/5.0 (iPad; CPU OS 15_8_4 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 17 15:15:00 volumio-2w volumio[18463]: info: Received Get System Info May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 17 15:15:00 volumio-2w volumio[18463]: info: Discovery: Getting this device information May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetState May 17 15:15:00 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 30 May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 17 15:15:00 volumio-2w volumio[18463]: info: Received Get System Info May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 17 15:15:00 volumio-2w volumio[18463]: info: Discovery: Getting this device information May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetState May 17 15:15:00 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 30 May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetVisibleSources May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetState May 17 15:15:00 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 30 May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 17 15:15:00 volumio-2w volumio[18463]: info: Received Get System Info May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 17 15:15:00 volumio-2w volumio[18463]: info: Discovery: Getting this device information May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetState May 17 15:15:00 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 30 May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetState May 17 15:15:00 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 30 May 17 15:15:00 volumio-2w volumio[18463]: info: Listing playlists May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 17 15:15:00 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 17 15:15:01 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 17 15:15:01 volumio-2w volumio[18463]: info: Received Get System Info May 17 15:15:01 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 17 15:15:01 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 17 15:15:01 volumio-2w volumio[18463]: info: Discovery: Getting this device information May 17 15:15:01 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetState May 17 15:15:01 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 30 May 17 15:15:01 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 17 15:15:02 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 17 15:15:08 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 17 15:15:08 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 17 15:15:09 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 17 15:15:21 volumio-2w sshd[21580]: Accepted password for volumio from 192.168.4.88 port 53523 ssh2 May 17 15:15:21 volumio-2w sshd[21580]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 17 15:15:21 volumio-2w systemd[1]: Created slice User Slice of UID 1000. May 17 15:15:21 volumio-2w systemd[1]: Starting User Runtime Directory /run/user/1000... May 17 15:15:21 volumio-2w systemd-logind[483]: New session 1 of user volumio. May 17 15:15:21 volumio-2w systemd[1]: Started User Runtime Directory /run/user/1000. May 17 15:15:21 volumio-2w systemd[1]: Starting User Manager for UID 1000... May 17 15:15:22 volumio-2w systemd[21585]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) May 17 15:15:22 volumio-2w sshd[21582]: Accepted password for volumio from 192.168.4.88 port 53524 ssh2 May 17 15:15:22 volumio-2w sshd[21582]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 17 15:15:22 volumio-2w systemd-logind[483]: New session 3 of user volumio. May 17 15:15:22 volumio-2w systemd[21585]: Reached target Timers. May 17 15:15:22 volumio-2w systemd[21585]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). May 17 15:15:22 volumio-2w systemd[21585]: Listening on GnuPG cryptographic agent (ssh-agent emulation). May 17 15:15:22 volumio-2w systemd[21585]: Reached target Paths. May 17 15:15:22 volumio-2w systemd[21585]: Listening on GnuPG cryptographic agent and passphrase cache. May 17 15:15:22 volumio-2w systemd[21585]: Listening on Sound System. May 17 15:15:22 volumio-2w systemd[21585]: Starting D-Bus User Message Bus Socket. May 17 15:15:22 volumio-2w systemd[21585]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). May 17 15:15:22 volumio-2w systemd[21585]: Listening on GnuPG network certificate management daemon. May 17 15:15:22 volumio-2w systemd[21585]: Listening on D-Bus User Message Bus Socket. May 17 15:15:22 volumio-2w systemd[21585]: Reached target Sockets. May 17 15:15:22 volumio-2w systemd[21585]: Reached target Basic System. May 17 15:15:22 volumio-2w systemd[21585]: Reached target Default. May 17 15:15:22 volumio-2w systemd[21585]: Startup finished in 494ms. May 17 15:15:22 volumio-2w systemd[1]: Started User Manager for UID 1000. May 17 15:15:22 volumio-2w systemd[1]: Started Session 1 of user volumio. May 17 15:15:22 volumio-2w systemd[1]: Started Session 3 of user volumio. May 17 15:15:25 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 17 15:15:25 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 17 15:15:25 volumio-2w volumio[18463]: info: Discovery: Getting this device information May 17 15:15:25 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioGetState May 17 15:15:25 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 30 May 17 15:15:25 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 17 15:15:36 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 30 May 17 15:15:36 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 52 May 17 15:15:36 volumio-2w volumio[18463]: info: Prefetching next song May 17 15:15:36 volumio-2w volumio[18463]: info: DOING PREFETCH IN MPD May 17 15:15:36 volumio-2w volumio[18463]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/黎明-情深说话未曾讲.flac" May 17 15:15:36 volumio-2w volumio[18463]: info: May 17 15:15:36 volumio-2w volumio[18463]: ---------------------------- MPD announces system playlist update May 17 15:15:36 volumio-2w volumio[18463]: info: Ignoring MPD Status Update May 17 15:15:36 volumio-2w volumio[18463]: info: sendMpdCommand add "INTERNAL/黎明-情深说话未曾讲.flac" took 3 milliseconds May 17 15:15:36 volumio-2w volumio[18463]: verbose: ControllerMpd::sendMpdCommand consume 1 May 17 15:15:36 volumio-2w volumio[18463]: info: May 17 15:15:36 volumio-2w volumio[18463]: ---------------------------- MPD announces system playlist update May 17 15:15:36 volumio-2w volumio[18463]: info: Ignoring MPD Status Update May 17 15:15:36 volumio-2w volumio[18463]: info: May 17 15:15:36 volumio-2w volumio[18463]: ---------------------------- MPD announces system playlist update May 17 15:15:36 volumio-2w volumio[18463]: info: Ignoring MPD Status Update May 17 15:15:36 volumio-2w volumio[18463]: info: ------------------------------ 8ms May 17 15:15:36 volumio-2w volumio[18463]: info: sendMpdCommand consume 1 took 6 milliseconds May 17 15:15:36 volumio-2w volumio[18463]: info: ------------------------------ 7ms May 17 15:15:36 volumio-2w volumio[18463]: info: ------------------------------ 5ms May 17 15:15:40 volumio-2w volumio[18463]: info: CoreStateMachine::startPlaybackTimer May 17 15:15:40 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 52 May 17 15:15:41 volumio-2w volumio[18463]: info: May 17 15:15:41 volumio-2w volumio[18463]: ---------------------------- MPD announces system playlist update May 17 15:15:41 volumio-2w volumio[18463]: info: Ignoring MPD Status Update May 17 15:15:41 volumio-2w volumio[18463]: info: May 17 15:15:41 volumio-2w volumio[18463]: ---------------------------- MPD announces state update: player May 17 15:15:41 volumio-2w volumio[18463]: info: ControllerMpd::getState May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::sendMpdCommand status May 17 15:15:41 volumio-2w volumio[18463]: info: May 17 15:15:41 volumio-2w volumio[18463]: ---------------------------- MPD announces system playlist update May 17 15:15:41 volumio-2w volumio[18463]: info: Ignoring MPD Status Update May 17 15:15:41 volumio-2w volumio[18463]: info: May 17 15:15:41 volumio-2w volumio[18463]: ---------------------------- MPD announces state update: player May 17 15:15:41 volumio-2w volumio[18463]: info: ControllerMpd::getState May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::sendMpdCommand status May 17 15:15:41 volumio-2w volumio[18463]: info: ------------------------------ 5ms May 17 15:15:41 volumio-2w volumio[18463]: info: May 17 15:15:41 volumio-2w volumio[18463]: ---------------------------- MPD announces system playlist update May 17 15:15:41 volumio-2w volumio[18463]: info: Ignoring MPD Status Update May 17 15:15:41 volumio-2w volumio[18463]: info: May 17 15:15:41 volumio-2w volumio[18463]: ---------------------------- MPD announces state update: player May 17 15:15:41 volumio-2w volumio[18463]: info: ControllerMpd::getState May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::sendMpdCommand status May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::pushState May 17 15:15:41 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 52 May 17 15:15:41 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 15:15:41 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioPushState May 17 15:15:41 volumio-2w volumio[18463]: info: sendMpdCommand status took 386 milliseconds May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::parseState May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 15:15:41 volumio-2w volumio[18463]: info: May 17 15:15:41 volumio-2w volumio[18463]: ---------------------------- MPD announces state update: player May 17 15:15:41 volumio-2w volumio[18463]: info: ControllerMpd::getState May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::sendMpdCommand status May 17 15:15:41 volumio-2w volumio[18463]: info: ------------------------------ 389ms May 17 15:15:41 volumio-2w volumio[18463]: info: sendMpdCommand status took 387 milliseconds May 17 15:15:41 volumio-2w volumio[18463]: info: ------------------------------ 387ms May 17 15:15:41 volumio-2w volumio[18463]: info: sendMpdCommand status took 385 milliseconds May 17 15:15:41 volumio-2w volumio[18463]: info: sendMpdCommand playlistinfo took 4 milliseconds May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::parseState May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::parseState May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::parseTrackInfo May 17 15:15:41 volumio-2w volumio[18463]: info: ControllerMpd::pushState May 17 15:15:41 volumio-2w volumio[18463]: info: CoreCommandRouter::servicePushState May 17 15:15:41 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 52 May 17 15:15:41 volumio-2w volumio[18463]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"情深说话未曾讲","artist":"黎明","album":"你爱不爱精选","uri":"INTERNAL/黎明-情深说话未曾讲.flac","trackType":"flac"} May 17 15:15:41 volumio-2w volumio[18463]: verbose: CURRENT POSITION 52 May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::syncState stateService play May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::syncState currentStatus play May 17 15:15:41 volumio-2w volumio[18463]: info: Received an update from plugin. extracting info from payload May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::pushState May 17 15:15:41 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 52 May 17 15:15:41 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 15:15:41 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioPushState May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::pushState May 17 15:15:41 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 52 May 17 15:15:41 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioPushState May 17 15:15:41 volumio-2w volumio[18463]: info: ------------------------------ 437ms May 17 15:15:41 volumio-2w volumio[18463]: info: May 17 15:15:41 volumio-2w volumio[18463]: ---------------------------- MPD announces state update: player May 17 15:15:41 volumio-2w volumio[18463]: info: ControllerMpd::getState May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::sendMpdCommand status May 17 15:15:41 volumio-2w volumio[18463]: info: May 17 15:15:41 volumio-2w volumio[18463]: ---------------------------- MPD announces state update: player May 17 15:15:41 volumio-2w volumio[18463]: info: ControllerMpd::getState May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::sendMpdCommand status May 17 15:15:41 volumio-2w volumio[18463]: info: sendMpdCommand status took 52 milliseconds May 17 15:15:41 volumio-2w volumio[18463]: info: sendMpdCommand playlistinfo took 49 milliseconds May 17 15:15:41 volumio-2w volumio[18463]: info: sendMpdCommand playlistinfo took 50 milliseconds May 17 15:15:41 volumio-2w volumio[18463]: info: sendMpdCommand status took 5 milliseconds May 17 15:15:41 volumio-2w volumio[18463]: info: sendMpdCommand status took 3 milliseconds May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::parseState May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::parseTrackInfo May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::parseTrackInfo May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::parseState May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::parseState May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 15:15:41 volumio-2w volumio[18463]: info: ControllerMpd::pushState May 17 15:15:41 volumio-2w volumio[18463]: info: CoreCommandRouter::servicePushState May 17 15:15:41 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 52 May 17 15:15:41 volumio-2w volumio[18463]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"537 Kbps","isStreaming":false,"title":"情深说话未曾讲","artist":"黎明","album":"你爱不爱精选","uri":"INTERNAL/黎明-情深说话未曾讲.flac","trackType":"flac"} May 17 15:15:41 volumio-2w volumio[18463]: verbose: CURRENT POSITION 52 May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::syncState stateService play May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::syncState currentStatus play May 17 15:15:41 volumio-2w volumio[18463]: info: Received an update from plugin. extracting info from payload May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::pushState May 17 15:15:41 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 52 May 17 15:15:41 volumio-2w volumio[18463]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 15:15:41 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioPushState May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::pushState May 17 15:15:41 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 52 May 17 15:15:41 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioPushState May 17 15:15:41 volumio-2w volumio[18463]: info: ControllerMpd::pushState May 17 15:15:41 volumio-2w volumio[18463]: info: CoreCommandRouter::servicePushState May 17 15:15:41 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 52 May 17 15:15:41 volumio-2w volumio[18463]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"537 Kbps","isStreaming":false,"title":"情深说话未曾讲","artist":"黎明","album":"你爱不爱精选","uri":"INTERNAL/黎明-情深说话未曾讲.flac","trackType":"flac"} May 17 15:15:41 volumio-2w volumio[18463]: verbose: CURRENT POSITION 52 May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::syncState stateService play May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::syncState currentStatus play May 17 15:15:41 volumio-2w volumio[18463]: info: Received an update from plugin. extracting info from payload May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::pushState May 17 15:15:41 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 52 May 17 15:15:41 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioPushState May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::pushState May 17 15:15:41 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 52 May 17 15:15:41 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioPushState May 17 15:15:41 volumio-2w volumio[18463]: info: ------------------------------ 515ms May 17 15:15:41 volumio-2w volumio[18463]: info: ------------------------------ 513ms May 17 15:15:41 volumio-2w volumio[18463]: info: sendMpdCommand playlistinfo took 85 milliseconds May 17 15:15:41 volumio-2w volumio[18463]: info: sendMpdCommand playlistinfo took 84 milliseconds May 17 15:15:41 volumio-2w volumio[18463]: info: sendMpdCommand playlistinfo took 84 milliseconds May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::parseTrackInfo May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::parseTrackInfo May 17 15:15:41 volumio-2w volumio[18463]: verbose: ControllerMpd::parseTrackInfo May 17 15:15:41 volumio-2w volumio[18463]: info: ControllerMpd::pushState May 17 15:15:41 volumio-2w volumio[18463]: info: CoreCommandRouter::servicePushState May 17 15:15:41 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 52 May 17 15:15:41 volumio-2w volumio[18463]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"537 Kbps","isStreaming":false,"title":"情深说话未曾讲","artist":"黎明","album":"你爱不爱精选","uri":"INTERNAL/黎明-情深说话未曾讲.flac","trackType":"flac"} May 17 15:15:41 volumio-2w volumio[18463]: verbose: CURRENT POSITION 52 May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::syncState stateService play May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::syncState currentStatus play May 17 15:15:41 volumio-2w volumio[18463]: info: Received an update from plugin. extracting info from payload May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::pushState May 17 15:15:41 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 52 May 17 15:15:41 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioPushState May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::pushState May 17 15:15:41 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 52 May 17 15:15:41 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioPushState May 17 15:15:41 volumio-2w volumio[18463]: info: ControllerMpd::pushState May 17 15:15:41 volumio-2w volumio[18463]: info: CoreCommandRouter::servicePushState May 17 15:15:41 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 52 May 17 15:15:41 volumio-2w volumio[18463]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"537 Kbps","isStreaming":false,"title":"情深说话未曾讲","artist":"黎明","album":"你爱不爱精选","uri":"INTERNAL/黎明-情深说话未曾讲.flac","trackType":"flac"} May 17 15:15:41 volumio-2w volumio[18463]: verbose: CURRENT POSITION 52 May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::syncState stateService play May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::syncState currentStatus play May 17 15:15:41 volumio-2w volumio[18463]: info: Received an update from plugin. extracting info from payload May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::pushState May 17 15:15:41 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 52 May 17 15:15:41 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioPushState May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::pushState May 17 15:15:41 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 52 May 17 15:15:41 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioPushState May 17 15:15:41 volumio-2w volumio[18463]: info: ControllerMpd::pushState May 17 15:15:41 volumio-2w volumio[18463]: info: CoreCommandRouter::servicePushState May 17 15:15:41 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 52 May 17 15:15:41 volumio-2w volumio[18463]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"537 Kbps","isStreaming":false,"title":"情深说话未曾讲","artist":"黎明","album":"你爱不爱精选","uri":"INTERNAL/黎明-情深说话未曾讲.flac","trackType":"flac"} May 17 15:15:41 volumio-2w volumio[18463]: verbose: CURRENT POSITION 52 May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::syncState stateService play May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::syncState currentStatus play May 17 15:15:41 volumio-2w volumio[18463]: info: Received an update from plugin. extracting info from payload May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::pushState May 17 15:15:41 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 52 May 17 15:15:41 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioPushState May 17 15:15:41 volumio-2w volumio[18463]: info: CoreStateMachine::pushState May 17 15:15:41 volumio-2w volumio[18463]: info: CorePlayQueue::getTrack 52 May 17 15:15:41 volumio-2w volumio[18463]: info: CoreCommandRouter::volumioPushState May 17 15:15:41 volumio-2w volumio[18463]: info: ------------------------------ 272ms May 17 15:15:41 volumio-2w volumio[18463]: info: ------------------------------ 224ms May 17 15:15:41 volumio-2w volumio[18463]: info: ------------------------------ 223ms May 17 15:15:43 volumio-2w volumio[18463]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 17 15:15:43 volumio-2w volumio[18463]: Error: connect ETIMEDOUT 202.160.128.195:443 May 17 15:15:43 volumio-2w volumio[18463]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { May 17 15:15:43 volumio-2w volumio[18463]: errno: -110, May 17 15:15:43 volumio-2w volumio[18463]: code: 'ETIMEDOUT', May 17 15:15:43 volumio-2w volumio[18463]: syscall: 'connect', May 17 15:15:43 volumio-2w volumio[18463]: address: '202.160.128.195', May 17 15:15:43 volumio-2w volumio[18463]: port: 443 May 17 15:15:43 volumio-2w volumio[18463]: } May 17 15:15:43 volumio-2w volumio[18463]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 17 15:15:44 volumio-2w sudo[21802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-17 15:14 May 17 15:15:44 volumio-2w sudo[21802]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"