-- Logs begin at Fri 2024-10-11 21:12:40 CST, end at Fri 2024-10-11 21:14:46 CST. -- Oct 11 21:13:00 volumio sudo[1584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Oct 11 21:13:00 volumio sudo[1584]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:00 volumio sudo[1584]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:00 volumio volumio[978]: info: Volumio called home Oct 11 21:13:00 volumio volumio[978]: info: minidlna: Found miniDLNA version 1.2.1 Oct 11 21:13:00 volumio volumio[978]: info: minidlna: Starting minidlna.service Oct 11 21:13:00 volumio volumio[978]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Oct 11 21:13:00 volumio ntpd[1404]: Soliciting pool server 210.243.152.152 Oct 11 21:13:00 volumio ntpd[1404]: Soliciting pool server 118.163.81.61 Oct 11 21:13:00 volumio ntpd[1404]: Soliciting pool server 183.177.72.202 Oct 11 21:13:00 volumio sudo[1587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Oct 11 21:13:00 volumio sudo[1587]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:00 volumio nmbd[905]: [2024/10/11 21:13:00.259852, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 11 21:13:00 volumio nmbd[905]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 11 21:13:00 volumio systemd[1]: Started Samba NMB Daemon. Oct 11 21:13:00 volumio ntpd[1404]: Soliciting pool server 218.35.163.245 Oct 11 21:13:00 volumio sudo[1589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Oct 11 21:13:00 volumio systemd[1]: Starting Samba Winbind Daemon... Oct 11 21:13:00 volumio sudo[1589]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:00 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Oct 11 21:13:00 volumio volumio[978]: info: Starting Shairport Sync Oct 11 21:13:00 volumio sudo[1595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 21:13:00 volumio sudo[1595]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:00 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Oct 11 21:13:00 volumio sudo[1595]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:00 volumio volumio[978]: info: Starting Shairport Sync Oct 11 21:13:00 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Oct 11 21:13:00 volumio sudo[1587]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:00 volumio sudo[1599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 21:13:00 volumio sudo[1599]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:00 volumio volumio[978]: info: Starting Shairport Sync Oct 11 21:13:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 11 21:13:00 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 11 21:13:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 11 21:13:00 volumio sudo[1589]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:00 volumio volumio[978]: info: Starting Shairport Sync Oct 11 21:13:00 volumio sudo[1602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 21:13:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 11 21:13:00 volumio sudo[1602]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:00 volumio sudo[1604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 21:13:00 volumio sudo[1604]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:00 volumio sudo[1609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 21:13:00 volumio sudo[1609]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:00 volumio sudo[1599]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:00 volumio winbindd[1591]: [2024/10/11 21:13:00.809809, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Oct 11 21:13:00 volumio winbindd[1591]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 11 21:13:00 volumio systemd[1]: minidlna.service: Succeeded. Oct 11 21:13:00 volumio winbindd[1591]: [2024/10/11 21:13:00.837472, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 11 21:13:00 volumio winbindd[1591]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 11 21:13:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 11 21:13:00 volumio systemd[1]: Started Samba Winbind Daemon. Oct 11 21:13:00 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 11 21:13:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 11 21:13:00 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:00 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 11 21:13:00 volumio sudo[1602]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:00 volumio systemd[1]: Starting Samba SMB Daemon... Oct 11 21:13:00 volumio sudo[1604]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:00 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Oct 11 21:13:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 11 21:13:00 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 11 21:13:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 11 21:13:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 11 21:13:01 volumio sudo[1621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 21:13:01 volumio sudo[1621]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:01 volumio sudo[1609]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:01 volumio volumio[978]: info: Shairport-Sync Started Oct 11 21:13:01 volumio volumio[978]: Error adding Membership: Error: addMembership EINVAL Oct 11 21:13:01 volumio volumio[978]: info: Shairport-Sync Started Oct 11 21:13:01 volumio volumio[978]: info: Shairport-Sync Started Oct 11 21:13:01 volumio volumio[978]: info: minidlna: systemctl start minidlna.service succeeded. Oct 11 21:13:01 volumio volumio[978]: info: MPD Permissions set Oct 11 21:13:01 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 11 21:13:01 volumio systemd[1]: mpd.service: Succeeded. Oct 11 21:13:01 volumio systemd[1]: Stopped Music Player Daemon. Oct 11 21:13:01 volumio volumio[978]: info: Shairport-Sync Started Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 21:13:01 volumio systemd[1]: Starting Music Player Daemon... Oct 11 21:13:01 volumio ntpd[1404]: Soliciting pool server 183.177.72.201 Oct 11 21:13:01 volumio ntpd[1404]: Soliciting pool server 114.35.131.27 Oct 11 21:13:01 volumio ntpd[1404]: Soliciting pool server 123.204.232.128 Oct 11 21:13:01 volumio RoonBridge[1550]: 00:00:00.026 Warn: get lock file path: /tmp/.rnbgem0- Oct 11 21:13:01 volumio sudo[1627]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 11 21:13:01 volumio sudo[1627]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 11 21:13:01 volumio sudo[1627]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 11 21:13:01 volumio sudo[1627]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 21:13:01 volumio volumio[978]: verbose: New Socket.io Connection to 192.168.31.200 from 192.168.31.71 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:01 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:01 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 21:13:01 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:01 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio volumio[978]: info: Listing playlists Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetQueue Oct 11 21:13:01 volumio volumio[978]: info: CoreStateMachine::getQueue Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getQueue Oct 11 21:13:01 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:01 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio systemd[1]: Started Samba SMB Daemon. Oct 11 21:13:01 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:01 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio smbd[1620]: [2024/10/11 21:13:01.709803, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 11 21:13:01 volumio systemd[1]: Reached target Multi-User System. Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio smbd[1620]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 11 21:13:01 volumio systemd[1]: Reached target Graphical Interface. Oct 11 21:13:01 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 21:13:01 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:01 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 11 21:13:01 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 11 21:13:01 volumio volumio[978]: info: Listing playlists Oct 11 21:13:01 volumio RoonBridge[1550]: 00:00:00.972 Trace: [childprocess] using unix child process Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetQueue Oct 11 21:13:01 volumio volumio[978]: info: CoreStateMachine::getQueue Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getQueue Oct 11 21:13:01 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:01 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:01 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 21:13:01 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:01 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:01 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:01 volumio volumio[978]: info: Listing playlists Oct 11 21:13:02 volumio RoonBridge[1550]: 00:00:01.120 Debug: PathForResource, filename: ../.update Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetQueue Oct 11 21:13:02 volumio volumio[978]: info: CoreStateMachine::getQueue Oct 11 21:13:02 volumio volumio[978]: info: CorePlayQueue::getQueue Oct 11 21:13:02 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:02 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:02 volumio volumio-remote-updater[739]: [2024-10-11 21:13:02] [connect] Successful connection Oct 11 21:13:02 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:02 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:02 volumio RoonBridge[1550]: 00:00:01.212 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Oct 11 21:13:02 volumio RoonBridge[1550]: 00:00:01.223 Debug: PathForResource, filename: RAATServer Oct 11 21:13:02 volumio RoonBridge[1550]: 00:00:01.223 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 11 21:13:02 volumio RoonBridge[1550]: 00:00:01.224 Debug: PathForResource, filename: RoonBridgeHelper Oct 11 21:13:02 volumio RoonBridge[1550]: 00:00:01.224 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 21:13:02 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:02 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:02 volumio RoonBridge[1550]: Initializing Oct 11 21:13:02 volumio volumio[978]: info: Listing playlists Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetQueue Oct 11 21:13:02 volumio volumio[978]: info: CoreStateMachine::getQueue Oct 11 21:13:02 volumio volumio[978]: info: CorePlayQueue::getQueue Oct 11 21:13:02 volumio ntpd[1404]: Soliciting pool server 118.163.81.63 Oct 11 21:13:02 volumio ntpd[1404]: Soliciting pool server 122.117.253.246 Oct 11 21:13:02 volumio RoonBridge[1550]: 00:00:01.342 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Oct 11 21:13:02 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:02 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:02 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:02 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:02 volumio RoonBridge[1550]: 00:00:01.416 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 21:13:02 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:02 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:02 volumio volumio[978]: info: Listing playlists Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetQueue Oct 11 21:13:02 volumio volumio[978]: info: CoreStateMachine::getQueue Oct 11 21:13:02 volumio volumio[978]: info: CorePlayQueue::getQueue Oct 11 21:13:02 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:02 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:02 volumio RoonBridge[1550]: Not Running (.o) Oct 11 21:13:02 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:02 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:02 volumio volumio[978]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Oct 11 21:13:02 volumio jellyfin[1517]: [21:13:02] [INF] [1] Main: Jellyfin version: 10.9.6 Oct 11 21:13:02 volumio jellyfin[1517]: [21:13:02] [INF] [1] Main: Environment Variables: ["[JELLYFIN_LOG_DIR, /opt/jellyfin/log]"] Oct 11 21:13:02 volumio jellyfin[1517]: [21:13:02] [INF] [1] Main: Arguments: ["/opt/jellyfin/jellyfin_10.9.6/jellyfin.dll", "-d", "/opt/jellyfin/data", "-C", "/opt/jellyfin/cache", "-c", "/opt/jellyfin/config", "-l", "/opt/jellyfin/log", "--ffmpeg", "/usr/lib/jellyfin-ffmpeg/ffmpeg"] Oct 11 21:13:02 volumio jellyfin[1517]: [21:13:02] [INF] [1] Main: Operating system: Debian GNU/Linux 10 (buster) Oct 11 21:13:02 volumio jellyfin[1517]: [21:13:02] [INF] [1] Main: Architecture: X64 Oct 11 21:13:02 volumio jellyfin[1517]: [21:13:02] [INF] [1] Main: 64-Bit Process: True Oct 11 21:13:02 volumio jellyfin[1517]: [21:13:02] [INF] [1] Main: User Interactive: True Oct 11 21:13:02 volumio jellyfin[1517]: [21:13:02] [INF] [1] Main: Processor count: 4 Oct 11 21:13:02 volumio jellyfin[1517]: [21:13:02] [INF] [1] Main: Program data path: /opt/jellyfin/data Oct 11 21:13:02 volumio jellyfin[1517]: [21:13:02] [INF] [1] Main: Log directory path: /opt/jellyfin/log Oct 11 21:13:02 volumio jellyfin[1517]: [21:13:02] [INF] [1] Main: Config directory path: /opt/jellyfin/config Oct 11 21:13:02 volumio jellyfin[1517]: [21:13:02] [INF] [1] Main: Cache path: /opt/jellyfin/cache Oct 11 21:13:02 volumio jellyfin[1517]: [21:13:02] [INF] [1] Main: Web resources path: /opt/jellyfin/jellyfin_10.9.6/jellyfin-web Oct 11 21:13:02 volumio jellyfin[1517]: [21:13:02] [INF] [1] Main: Application directory: /opt/jellyfin/jellyfin_10.9.6/ Oct 11 21:13:03 volumio sudo[1663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 21:13:03 volumio sudo[1663]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:03 volumio sudo[1661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Oct 11 21:13:03 volumio sudo[1661]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:03 volumio sudo[1663]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:03 volumio sudo[1666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 21:13:03 volumio sudo[1666]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:03 volumio sudo[1666]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:03 volumio ntpd[1404]: Soliciting pool server 220.134.230.37 Oct 11 21:13:03 volumio ntpd[1404]: Soliciting pool server 2406:2000:fc:437::1001 Oct 11 21:13:03 volumio sudo[1661]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:03 volumio volumio-remote-updater[739]: [2024-10-11 21:13:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728652382 101 Oct 11 21:13:03 volumio mpd[1634]: Oct 11 21:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 11 21:13:03 volumio volumio[978]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 9 Oct 11 21:13:03 volumio systemd[1]: Started Music Player Daemon. Oct 11 21:13:03 volumio sudo[1621]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:03 volumio systemd[1]: Startup finished in 9.179s (firmware) + 8.909s (loader) + 10.879s (kernel) + 23.468s (userspace) = 52.437s. Oct 11 21:13:03 volumio sudo[1478]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:03 volumio sudo[1488]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:03 volumio RoonBridge[1550]: Running Oct 11 21:13:04 volumio jellyfin[1517]: [21:13:04] [INF] [1] Jellyfin.Server.Migrations.MigrationRunner: Marking following migrations as applied because this is a fresh install: ["CreateNetworkConfiguration", "MigrateMusicBrainzTimeout", "MigrateNetworkConfiguration"] Oct 11 21:13:04 volumio RoonBridge[1550]: 00:00:00.075 Warn: get lock file path: /tmp/.rnbhgem0- Oct 11 21:13:04 volumio jellyfin[1517]: [21:13:04] [INF] [1] Emby.Server.Implementations.AppBase.BaseConfigurationManager: Setting cache path: /opt/jellyfin/cache Oct 11 21:13:04 volumio volumio[978]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Oct 11 21:13:04 volumio sudo[1684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Oct 11 21:13:04 volumio sudo[1684]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:04 volumio sudo[1684]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:04 volumio volumio[978]: error: MPD error: The expression evaluated to a falsy value: Oct 11 21:13:04 volumio volumio[978]: assert.ok(self.idling) Oct 11 21:13:04 volumio volumio[978]: error: The expression evaluated to a falsy value: Oct 11 21:13:04 volumio volumio[978]: assert.ok(self.idling) Oct 11 21:13:04 volumio volumio[978]: error: MPD error: The expression evaluated to a falsy value: Oct 11 21:13:04 volumio volumio[978]: assert.ok(self.idling) Oct 11 21:13:04 volumio volumio[978]: error: The expression evaluated to a falsy value: Oct 11 21:13:04 volumio volumio[978]: assert.ok(self.idling) Oct 11 21:13:04 volumio volumio[978]: error: updateQueue error: null Oct 11 21:13:04 volumio volumio[978]: info: MPD running with PID1634 Oct 11 21:13:04 volumio volumio[978]: ,establishing connection Oct 11 21:13:04 volumio volumio[978]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Oct 11 21:13:04 volumio volumio[978]: error: updateQueue error: null Oct 11 21:13:04 volumio sudo[1692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 11 21:13:04 volumio sudo[1692]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:04 volumio jellyfin[1517]: [21:13:04] [INF] [1] Emby.Server.Implementations.ApplicationHost: Loading assemblies Oct 11 21:13:04 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 11 21:13:04 volumio sudo[1692]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:04 volumio volumio[978]: info: Upmpdcli Daemon Started Oct 11 21:13:05 volumio volumio[978]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Oct 11 21:13:05 volumio sudo[1710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Oct 11 21:13:05 volumio sudo[1710]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:05 volumio volumio[1695]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 11 21:13:05 volumio sudo[1710]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:05 volumio jellyfin[1517]: [21:13:05] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN subnets: ["127.0.0.1/8", "10.0.0.0/8", "172.16.0.0/12", "192.168.0.0/16"] Oct 11 21:13:05 volumio jellyfin[1517]: [21:13:05] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN exclusions: [] Oct 11 21:13:05 volumio jellyfin[1517]: [21:13:05] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Used LAN subnets: ["127.0.0.1/8", "10.0.0.0/8", "172.16.0.0/12", "192.168.0.0/16"] Oct 11 21:13:05 volumio jellyfin[1517]: [21:13:05] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Filtered interface addresses: ["127.0.0.1", "192.168.31.200"] Oct 11 21:13:05 volumio jellyfin[1517]: [21:13:05] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Bind Addresses ["0.0.0.0"] Oct 11 21:13:05 volumio jellyfin[1517]: [21:13:05] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Remote IP filter is Allowlist Oct 11 21:13:05 volumio jellyfin[1517]: [21:13:05] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Filtered subnets: [] Oct 11 21:13:05 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 21:13:05 volumio volumio[978]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Oct 11 21:13:05 volumio sudo[1722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Oct 11 21:13:05 volumio sudo[1722]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:05 volumio sudo[1722]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:05 volumio volumio[978]: info: Completed starting Core Plugins Oct 11 21:13:05 volumio volumio[978]: info: ------------------------------------------- Oct 11 21:13:05 volumio volumio[978]: info: ----- MyVolumio plugins startup ---- Oct 11 21:13:05 volumio volumio[978]: info: ------------------------------------------- Oct 11 21:13:05 volumio volumio[978]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 11 21:13:07 volumio sudo[1733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 21:13:07 volumio sudo[1733]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:07 volumio sudo[1735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 21:13:07 volumio sudo[1735]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:07 volumio sudo[1733]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:07 volumio sudo[1735]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:07 volumio volumio[978]: verbose: New Socket.io Connection to 192.168.31.200 from 192.168.31.71 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:07 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:07 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:07 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:07 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:07 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:07 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:07 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:07 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:07 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:07 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:07 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:07 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:07 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:07 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:07 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 21:13:07 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:07 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:07 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:07 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:07 volumio volumio[978]: info: Listing playlists Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetQueue Oct 11 21:13:07 volumio volumio[978]: info: CoreStateMachine::getQueue Oct 11 21:13:07 volumio volumio[978]: info: CorePlayQueue::getQueue Oct 11 21:13:07 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:07 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:07 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:07 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:07 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:07 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:07 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:07 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:07 volumio sudo[1744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 21:13:07 volumio sudo[1744]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:08 volumio sudo[1744]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:08 volumio sudo[1746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 21:13:08 volumio sudo[1746]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:08 volumio sudo[1746]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:08 volumio volumio[978]: verbose: New Socket.io Connection to 192.168.31.200 from 192.168.31.71 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Oct 11 21:13:08 volumio volumio[978]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:08 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:08 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:08 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:08 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:08 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:08 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:08 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:08 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:08 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:08 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:08 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:08 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:08 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:08 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:08 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 21:13:08 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:08 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:08 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:08 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:08 volumio volumio[978]: info: Listing playlists Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::volumioGetQueue Oct 11 21:13:08 volumio volumio[978]: info: CoreStateMachine::getQueue Oct 11 21:13:08 volumio volumio[978]: info: CorePlayQueue::getQueue Oct 11 21:13:08 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:08 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:08 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:08 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:08 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:08 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:08 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:08 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:08 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:09 volumio volumio[1695]: ...............................................................................................................++++ Oct 11 21:13:09 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:09 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:10 volumio volumio[1695]: .......................++++ Oct 11 21:13:10 volumio volumio[1695]: e is 65537 (0x010001) Oct 11 21:13:10 volumio volumio[1695]: writing RSA key Oct 11 21:13:10 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 21:13:10 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:10 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:10 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:10 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:10 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:10 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:10 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:10 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 21:13:10 volumio volumio[978]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 21:13:10 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 21:13:10 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:10 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:10 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 21:13:10 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 21:13:10 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:10 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:10 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:10 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:10 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:10 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:10 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:10 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:10 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:10 volumio volumio[978]: info: Listing playlists Oct 11 21:13:10 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 21:13:10 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:10 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:10 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:10 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:10 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:10 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:10 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:10 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:10 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:10 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 11 21:13:11 volumio sudo[1962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 21:13:11 volumio sudo[1962]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:11 volumio sudo[1964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 21:13:11 volumio sudo[1964]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:11 volumio sudo[1962]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:11 volumio sudo[1964]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:11 volumio volumio[978]: verbose: New Socket.io Connection to 192.168.31.200 from 192.168.31.71 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:11 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:11 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:11 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:11 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:11 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:11 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:11 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:11 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:11 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:11 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:11 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:11 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:11 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:11 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:11 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 21:13:11 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:11 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:11 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:11 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:11 volumio volumio[978]: info: Listing playlists Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::volumioGetQueue Oct 11 21:13:11 volumio volumio[978]: info: CoreStateMachine::getQueue Oct 11 21:13:11 volumio volumio[978]: info: CorePlayQueue::getQueue Oct 11 21:13:11 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:11 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:11 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:11 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:11 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:11 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:11 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:11 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:11 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:11 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:11 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:11 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:11 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:12 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:12 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:12 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 11 21:13:13 volumio systemd[1]: systemd-fsckd.service: Succeeded. Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 11 21:13:14 volumio volumio[978]: info: Adding plugin bluetooth to MyMusic Plugins Oct 11 21:13:14 volumio volumio[978]: info: Adding plugin multiroom to MyMusic Plugins Oct 11 21:13:14 volumio volumio[978]: info: Adding plugin metavolumio to MyMusic Plugins Oct 11 21:13:14 volumio volumio[978]: info: Adding plugin cd_controller to MyMusic Plugins Oct 11 21:13:14 volumio volumio[978]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 11 21:13:14 volumio volumio[978]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 11 21:13:14 volumio volumio[978]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 11 21:13:15 volumio jellyfin[1517]: [21:13:15] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: TMDb 10.9.6.0 Oct 11 21:13:15 volumio jellyfin[1517]: [21:13:15] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: Studio Images 10.9.6.0 Oct 11 21:13:15 volumio jellyfin[1517]: [21:13:15] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: OMDb 10.9.6.0 Oct 11 21:13:15 volumio jellyfin[1517]: [21:13:15] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: MusicBrainz 10.9.6.0 Oct 11 21:13:15 volumio jellyfin[1517]: [21:13:15] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: AudioDB 10.9.6.0 Oct 11 21:13:15 volumio volumio[978]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 11 21:13:15 volumio volumio[978]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 11 21:13:15 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 21:13:15 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 21:13:15 volumio volumio[978]: info: Starting MyVolumio Remote Streaming Endpoints Oct 11 21:13:15 volumio volumio[978]: info: MyVolumio login type: Token Oct 11 21:13:15 volumio volumio[978]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 11 21:13:15 volumio volumio[978]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 11 21:13:16 volumio jellyfin[1517]: [21:13:16] [INF] [1] Jellyfin.Server.Migrations.MigrationRunner: Marking following migrations as applied because this is a fresh install: ["DisableTranscodingThrottling", "CreateLoggingConfigHeirarchy", "MigrateActivityLogDatabase", "RemoveDuplicateExtras", "MigrateUserDatabase", "MigrateDisplayPreferencesDatabase", "RemoveDownloadImagesInAdvance", "MigrateAuthenticationDatabase", "FixPlaylistOwner", "MigrateRatingLevels", "FixAudioData"] Oct 11 21:13:16 volumio jellyfin[1517]: [21:13:16] [INF] [1] Main: Kestrel is listening on 0.0.0.0 Oct 11 21:13:17 volumio volumio[978]: info: Starting Streaming Service Transparent Proxy Oct 11 21:13:17 volumio volumio[978]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 11 21:13:17 volumio volumio[978]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 11 21:13:17 volumio volumio[978]: info: Streaming services startup Oct 11 21:13:17 volumio volumio[978]: info: Starting Streaming Daemon Oct 11 21:13:17 volumio sudo[1995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 11 21:13:17 volumio sudo[1995]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:17 volumio sudo[1995]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:17 volumio volumio[978]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 11 21:13:17 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 21:13:17 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:17 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:17 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:17 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:17 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:17 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:17 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:17 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 21:13:17 volumio volumio[978]: error: Cannot start Volumio Streaming Daemon Oct 11 21:13:17 volumio volumio[978]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 11 21:13:17 volumio volumio[978]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 11 21:13:17 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 21:13:17 volumio volumio[978]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 11 21:13:17 volumio volumio[978]: STREAMING PROXY: Starting server on port 3245 Oct 11 21:13:17 volumio volumio[978]: Node JS runtime: 14 Oct 11 21:13:18 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 11 21:13:18 volumio volumio[978]: info: MyVolumio login type: Token Oct 11 21:13:18 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 21:13:18 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 21:13:18 volumio volumio[978]: info: MyVolumio token set successfully Oct 11 21:13:18 volumio volumio[978]: info: MYVOLUMIO: Adding device Oct 11 21:13:18 volumio volumio[978]: info: MYVOLUMIO: Evaluating Server Oct 11 21:13:19 volumio volumio[978]: info: AutoStart - Plugin is starting Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetQueue Oct 11 21:13:19 volumio volumio[978]: info: CoreStateMachine::getQueue Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getQueue Oct 11 21:13:19 volumio volumio[978]: info: AutoStart - start playing Oct 11 21:13:19 volumio volumio[978]: info: AutoStart - start playing with no specific position Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioPlay Oct 11 21:13:19 volumio volumio[978]: info: CoreStateMachine::play index 0 Oct 11 21:13:19 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 21:13:19 volumio volumio[978]: info: CoreStateMachine::stop Oct 11 21:13:19 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 21:13:19 volumio volumio[978]: info: CoreStateMachine::play index undefined Oct 11 21:13:19 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio volumio[978]: info: CoreStateMachine::startPlaybackTimer Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::clearAddPlayTracks USB/赵鹏 - 乌兰巴托的夜晚.flac Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 21:13:19 volumio volumio[978]: info: MyVolumio status changed Oct 11 21:13:19 volumio volumio[978]: info: Streaming services startup Oct 11 21:13:19 volumio volumio[978]: info: Starting Streaming Daemon Oct 11 21:13:19 volumio volumio[978]: info: Removing browser output: myVolumio user plan is not superstar Oct 11 21:13:19 volumio volumio[978]: info: Removing audio output: Oct 11 21:13:19 volumio volumio[978]: info: Stoppping Tunnel 1 Oct 11 21:13:19 volumio sudo[2019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 11 21:13:19 volumio sudo[2019]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:19 volumio volumio[978]: info: sendMpdCommand stop took 61 milliseconds Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand clear Oct 11 21:13:19 volumio sudo[2019]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:19 volumio volumio[978]: info: Oct 11 21:13:19 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:19 volumio sudo[2022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 11 21:13:19 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:19 volumio volumio[978]: info: sendMpdCommand clear took 12 milliseconds Oct 11 21:13:19 volumio sudo[2022]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac" Oct 11 21:13:19 volumio sudo[2022]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:19 volumio sudo[2025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 21:13:19 volumio sudo[2025]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:19 volumio sudo[2025]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:19 volumio volumio[978]: info: Oct 11 21:13:19 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:19 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:19 volumio volumio[978]: info: Oct 11 21:13:19 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:19 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:19 volumio volumio[978]: info: Oct 11 21:13:19 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:19 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:19 volumio volumio[978]: error: Cannot start Volumio Streaming Daemon Oct 11 21:13:19 volumio volumio[978]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 11 21:13:19 volumio volumio[978]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 11 21:13:19 volumio sudo[2028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 21:13:19 volumio volumio[978]: error: updateQueue error: null Oct 11 21:13:19 volumio volumio[978]: info: Oct 11 21:13:19 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:19 volumio sudo[2028]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:19 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:19 volumio volumio[978]: info: ------------------------------ 75ms Oct 11 21:13:19 volumio volumio[978]: info: sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac" took 73 milliseconds Oct 11 21:13:19 volumio volumio[978]: info: ------------------------------ 20ms Oct 11 21:13:19 volumio volumio[978]: info: ------------------------------ 18ms Oct 11 21:13:19 volumio volumio[978]: info: ------------------------------ 17ms Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand play Oct 11 21:13:19 volumio volumio[978]: info: Oct 11 21:13:19 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:19 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:19 volumio volumio[978]: info: Oct 11 21:13:19 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:19 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:19 volumio volumio[978]: info: Oct 11 21:13:19 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:19 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:19 volumio volumio[978]: info: Remote SSH Stopped Oct 11 21:13:19 volumio volumio[978]: info: Oct 11 21:13:19 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:19 volumio volumio[978]: info: ------------------------------ 23ms Oct 11 21:13:19 volumio volumio[978]: info: sendMpdCommand play took 19 milliseconds Oct 11 21:13:19 volumio volumio[978]: info: ------------------------------ 19ms Oct 11 21:13:19 volumio volumio[978]: info: ------------------------------ 18ms Oct 11 21:13:19 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:19 volumio volumio[978]: info: ------------------------------ 20ms Oct 11 21:13:19 volumio volumio[978]: info: Oct 11 21:13:19 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:19 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:19 volumio volumio[978]: info: Oct 11 21:13:19 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:19 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:19 volumio volumio[978]: info: Oct 11 21:13:19 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:19 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:19 volumio sudo[2028]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:19 volumio volumio[978]: info: Setting Geolocation for MyVolumio to as1 Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 21:13:19 volumio volumio[978]: verbose: New Socket.io Connection to 192.168.31.200 from 192.168.31.71 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Oct 11 21:13:19 volumio kernel: intel_sst_acpi 808622A8:00: FW Version 01.0b.02.02 Oct 11 21:13:19 volumio sudo[2038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 21:13:19 volumio sudo[2038]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio sudo[2038]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:19 volumio sudo[2041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 21:13:19 volumio sudo[2041]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:19 volumio jellyfin[1517]: [21:13:19] [WRN] [1] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The WebRootPath was not found: /wwwroot. Static files may be unavailable. Oct 11 21:13:19 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:19 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:19 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:19 volumio sudo[2041]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:19 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:19 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 21:13:19 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:19 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio volumio[978]: info: Listing playlists Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetQueue Oct 11 21:13:19 volumio volumio[978]: info: CoreStateMachine::getQueue Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getQueue Oct 11 21:13:19 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:19 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:19 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:19 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio volumio[978]: info: Oct 11 21:13:19 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:19 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:19 volumio volumio[978]: info: sendMpdCommand status took 477 milliseconds Oct 11 21:13:19 volumio volumio[978]: info: sendMpdCommand status took 473 milliseconds Oct 11 21:13:19 volumio volumio[978]: info: sendMpdCommand status took 470 milliseconds Oct 11 21:13:19 volumio volumio[978]: info: sendMpdCommand status took 469 milliseconds Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:19 volumio volumio[978]: info: Oct 11 21:13:19 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:19 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:19 volumio volumio[978]: info: Oct 11 21:13:19 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:19 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:19 volumio volumio[978]: info: Oct 11 21:13:19 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:19 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:19 volumio volumio[978]: info: sendMpdCommand status took 59 milliseconds Oct 11 21:13:19 volumio volumio[978]: info: sendMpdCommand playlistinfo took 36 milliseconds Oct 11 21:13:19 volumio volumio[978]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 11 21:13:19 volumio jellyfin[1517]: [21:13:19] [INF] [1] Emby.Server.Implementations.ApplicationHost: Running startup tasks Oct 11 21:13:19 volumio volumio[978]: info: sendMpdCommand playlistinfo took 59 milliseconds Oct 11 21:13:19 volumio volumio[978]: info: sendMpdCommand status took 52 milliseconds Oct 11 21:13:19 volumio volumio[978]: info: sendMpdCommand status took 45 milliseconds Oct 11 21:13:19 volumio volumio[978]: info: sendMpdCommand status took 42 milliseconds Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:19 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:19 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":113,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"301 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 11 21:13:19 volumio volumio[978]: verbose: CURRENT POSITION 0 Oct 11 21:13:19 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:13:19 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Oct 11 21:13:19 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":136,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"301 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 11 21:13:19 volumio volumio[978]: verbose: CURRENT POSITION 0 Oct 11 21:13:19 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:13:19 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:13:19 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:13:19 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:13:19 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:20 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":204,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"305 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 11 21:13:20 volumio volumio[978]: verbose: CURRENT POSITION 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:13:20 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:20 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":250,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"305 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 11 21:13:20 volumio volumio[978]: verbose: CURRENT POSITION 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:13:20 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:20 volumio volumio[978]: info: ------------------------------ 617ms Oct 11 21:13:20 volumio volumio[978]: info: ------------------------------ 629ms Oct 11 21:13:20 volumio volumio[978]: info: ------------------------------ 626ms Oct 11 21:13:20 volumio volumio[978]: info: ------------------------------ 624ms Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 11 21:13:20 volumio jellyfin[1517]: [21:13:20] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Generate Trickplay Images set to fire at 2024-10-12 03:00:00.000 +08:00, which is 05:46:39.8697436 from now. Oct 11 21:13:20 volumio jellyfin[1517]: [21:13:20] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Extract Chapter Images set to fire at 2024-10-12 02:00:00.000 +08:00, which is 04:46:39.8450179 from now. Oct 11 21:13:20 volumio volumio[978]: info: sendMpdCommand playlistinfo took 251 milliseconds Oct 11 21:13:20 volumio volumio[978]: info: sendMpdCommand playlistinfo took 242 milliseconds Oct 11 21:13:20 volumio volumio[978]: info: sendMpdCommand playlistinfo took 242 milliseconds Oct 11 21:13:20 volumio volumio[978]: info: sendMpdCommand playlistinfo took 240 milliseconds Oct 11 21:13:20 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:20 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:20 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:20 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:20 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":796,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"664 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 11 21:13:20 volumio volumio[978]: verbose: CURRENT POSITION 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:13:20 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:20 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"664 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 11 21:13:20 volumio volumio[978]: verbose: CURRENT POSITION 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:13:20 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:20 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"551 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 11 21:13:20 volumio volumio[978]: verbose: CURRENT POSITION 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:13:20 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:20 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"551 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 11 21:13:20 volumio volumio[978]: verbose: CURRENT POSITION 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:13:20 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:20 volumio volumio[978]: info: ------------------------------ 406ms Oct 11 21:13:20 volumio volumio[978]: info: ------------------------------ 372ms Oct 11 21:13:20 volumio volumio[978]: info: ------------------------------ 367ms Oct 11 21:13:20 volumio volumio[978]: info: ------------------------------ 362ms Oct 11 21:13:20 volumio volumio[978]: verbose: New Socket.io Connection to 192.168.31.200 from 192.168.31.71 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 11 21:13:20 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:20 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:20 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:20 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:20 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 21:13:20 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:20 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: Listing playlists Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioGetQueue Oct 11 21:13:20 volumio volumio[978]: info: CoreStateMachine::getQueue Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getQueue Oct 11 21:13:20 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:20 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:20 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:20 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: Updating MyVolumio device info Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:20 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:20 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 11 21:13:20 volumio sudo[2054]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Oct 11 21:13:20 volumio sudo[2054]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:20 volumio jellyfin[1517]: [21:13:20] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Found ffmpeg version 6.0.1 Oct 11 21:13:21 volumio jellyfin[1517]: [21:13:21] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available decoders: ["libdav1d", "av1", "av1_cuvid", "av1_qsv", "h264", "h264_qsv", "h264_cuvid", "hevc", "hevc_qsv", "hevc_cuvid", "mpeg2video", "mpeg2_qsv", "mpeg2_cuvid", "mpeg4", "mpeg4_cuvid", "msmpeg4", "vc1_qsv", "vc1_cuvid", "vp8", "libvpx", "vp8_cuvid", "vp8_qsv", "vp9", "libvpx-vp9", "vp9_cuvid", "vp9_qsv", "aac", "ac3", "dca", "flac", "mp3", "truehd"] Oct 11 21:13:21 volumio volumio[978]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 11 21:13:21 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 21:13:21 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:21 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:21 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:21 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:21 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:21 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:21 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:21 volumio jellyfin[1517]: [21:13:21] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available encoders: ["libsvtav1", "av1_nvenc", "av1_qsv", "av1_amf", "av1_vaapi", "libx264", "h264_amf", "h264_nvenc", "h264_qsv", "h264_v4l2m2m", "h264_vaapi", "libx265", "hevc_amf", "hevc_nvenc", "hevc_qsv", "hevc_vaapi", "mjpeg_qsv", "mjpeg_vaapi", "mpeg4", "msmpeg4", "libvpx", "libvpx-vp9", "aac", "libfdk_aac", "ac3", "alac", "dca", "flac", "libmp3lame", "libopus", "truehd", "libvorbis", "srt"] Oct 11 21:13:21 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 21:13:21 volumio jellyfin[1517]: [21:13:21] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available filters: ["deinterlace_qsv", "deinterlace_vaapi", "hwupload_cuda", "hwupload_vaapi", "libplacebo", "overlay_opencl", "overlay_qsv", "overlay_vaapi", "overlay_vulkan", "overlay_cuda", "procamp_vaapi", "scale_cuda", "scale_opencl", "scale_qsv", "scale_vaapi", "scale_vulkan", "tonemap_cuda", "tonemap_opencl", "tonemap_vaapi", "vpp_qsv", "yadif_cuda", "zscale", "alphasrc"] Oct 11 21:13:21 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 21:13:21 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:21 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:21 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:21 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:21 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:21 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:21 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:22 volumio jellyfin[1517]: [21:13:22] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available hwaccel types: ["cuda", "vaapi", "qsv", "drm", "opencl", "vulkan"] Oct 11 21:13:22 volumio sudo[2077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 21:13:22 volumio sudo[2077]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:22 volumio sudo[2077]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:23 volumio sudo[2080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 21:13:23 volumio sudo[2080]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 21:13:23 volumio sudo[2080]: pam_unix(sudo:session): session closed for user root Oct 11 21:13:23 volumio volumio[978]: verbose: New Socket.io Connection to 192.168.31.200 from 192.168.31.71 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:23 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:23 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:23 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:23 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:23 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:23 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:23 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:23 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:23 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:23 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:23 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:23 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:23 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:23 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:23 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:23 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:23 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:23 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:23 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 21:13:23 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:23 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:23 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:23 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:23 volumio volumio[978]: info: Listing playlists Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::volumioGetQueue Oct 11 21:13:23 volumio volumio[978]: info: CoreStateMachine::getQueue Oct 11 21:13:23 volumio volumio[978]: info: CorePlayQueue::getQueue Oct 11 21:13:23 volumio jellyfin[1517]: [21:13:23] [INF] [11] Emby.Server.Implementations.ScheduledTasks.TaskManager: Clean up collections and playlists Completed after 0 minute(s) and 0 seconds Oct 11 21:13:23 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:23 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:23 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:23 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:23 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:23 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:23 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:23 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:23 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:23 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:23 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:23 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:23 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:23 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:23 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:23 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:23 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 11 21:13:24 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:24 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:24 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Oct 11 21:13:24 volumio jellyfin[1517]: [21:13:24] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: FFmpeg: /usr/lib/jellyfin-ffmpeg/ffmpeg Oct 11 21:13:24 volumio jellyfin[1517]: [21:13:24] [INF] [1] Emby.Server.Implementations.ApplicationHost: ServerId: b1cfc0d663974d88a7e5ad9e731e53ae Oct 11 21:13:24 volumio jellyfin[1517]: [21:13:24] [INF] [1] Emby.Server.Implementations.ApplicationHost: Core startup complete Oct 11 21:13:24 volumio jellyfin[1517]: [21:13:24] [INF] [1] Main: Startup complete 0:00:23.8163236 Oct 11 21:13:24 volumio volumio[978]: info: MYVOLUMIO: Adding device Oct 11 21:13:24 volumio volumio[978]: info: MYVOLUMIO: Evaluating Server Oct 11 21:13:25 volumio volumio[978]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 12 Oct 11 21:13:25 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:25 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:25 volumio volumio[978]: info: Setting Geolocation for MyVolumio to as1 Oct 11 21:13:25 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 21:13:25 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 21:13:25 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 21:13:25 volumio jellyfin[1517]: [21:13:25] [INF] [11] Emby.Server.Implementations.ScheduledTasks.TaskManager: Update Plugins Completed after 0 minute(s) and 2 seconds Oct 11 21:13:25 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 11 21:13:25 volumio volumio[978]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 11 21:13:25 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 11 21:13:25 volumio volumio[978]: info: Received Get System Version Oct 11 21:13:25 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 21:13:25 volumio volumio[978]: info: Received Get System Info Oct 11 21:13:25 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 21:13:25 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 21:13:25 volumio volumio[978]: info: Discovery: Getting this device information Oct 11 21:13:25 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:25 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:25 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 21:13:26 volumio volumio[978]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 11 21:13:26 volumio volumio[978]: info: Updating MyVolumio device info Oct 11 21:13:26 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 21:13:26 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 21:13:26 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 11 21:13:28 volumio volumio[978]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 11 21:13:29 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 21:13:29 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 21:13:29 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 21:13:29 volumio volumio-remote-updater[739]: No test mode Oct 11 21:13:29 volumio volumio-remote-updater[739]: No alpha test mode Oct 11 21:13:29 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 11 21:13:29 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 21:13:29 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 21:13:29 volumio volumio[978]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 11 21:13:29 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 11 21:13:30 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 11 21:13:30 volumio volumio[978]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 11 21:13:30 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:13:30 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:32 volumio volumio[978]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 21:13:32 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 21:13:33 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 21:13:35 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 21:13:35 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 21:13:35 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 11 21:13:35 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 11 21:13:35 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 11 21:13:35 volumio volumio[978]: info: CoreCommandRouter::volumioGetBrowseSources Oct 11 21:13:35 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 21:13:37 volumio volumio[978]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 11 21:13:37 volumio volumio[978]: info: Completed starting MyVolumio Plugin Oct 11 21:13:37 volumio volumio[978]: info: BOOT COMPLETED Oct 11 21:13:37 volumio volumio[978]: [Metrics] CommandRouter: 49s 385.32ms Oct 11 21:13:37 volumio volumio[978]: info: CoreCommandRouter::volumiosetStartupVolume Oct 11 21:13:37 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 21:13:37 volumio volumio[978]: info: VolumeController:: Setting startup Volume 5 Oct 11 21:13:37 volumio volumio[978]: info: VolumeController::SetAlsaVolume5 Oct 11 21:13:37 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 21:13:37 volumio volumio[978]: info: CoreCommandRouter::Close All Modals sent Oct 11 21:13:37 volumio volumio[978]: info: CoreCommandRouter::Close All Modals sent Oct 11 21:13:37 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:37 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:37 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:13:37 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:37 volumio volumio[978]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Oct 11 21:13:37 volumio volumio[978]: aplay: main:828: audio open error: Device or resource busy Oct 11 21:13:38 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 11 21:13:38 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 11 21:13:38 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 11 21:13:42 volumio volumio[978]: info: CALLMETHOD: user_interface peppy_screensaver saveVUMeterConf [object Object] Oct 11 21:13:42 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , saveVUMeterConf Oct 11 21:13:42 volumio volumio[978]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 21:13:42 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 21:13:50 volumio volumio[978]: info: CoreCommandRouter::volumioNext Oct 11 21:13:50 volumio volumio[978]: info: CoreStateMachine::next Oct 11 21:13:50 volumio volumio[978]: info: CoreStateMachine::stop Oct 11 21:13:50 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 21:13:50 volumio volumio[978]: info: CoreStateMachine::stPlaybackTimer Oct 11 21:13:50 volumio volumio[978]: info: CoreStateMachine::updateTrackBlock Oct 11 21:13:50 volumio volumio[978]: info: CorePlayQueue::getTrackBlock Oct 11 21:13:50 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:50 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:50 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:13:50 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:50 volumio volumio[978]: info: CoreStateMachine::serviceStop Oct 11 21:13:50 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Oct 11 21:13:50 volumio volumio[978]: info: CoreCommandRouter::serviceStop Oct 11 21:13:50 volumio volumio[978]: info: ControllerMpd::stop Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 21:13:50 volumio volumio[978]: info: Oct 11 21:13:50 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand stop took 193 milliseconds Oct 11 21:13:50 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:50 volumio volumio[978]: info: CoreStateMachine::play index undefined Oct 11 21:13:50 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 21:13:50 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:50 volumio volumio[978]: info: CoreStateMachine::startPlaybackTimer Oct 11 21:13:50 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::clearAddPlayTracks USB/赵鹏 - 北国之春.flac Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 21:13:50 volumio volumio[978]: info: CoreStateMachine::updateTrackBlock Oct 11 21:13:50 volumio volumio[978]: info: CorePlayQueue::getTrackBlock Oct 11 21:13:50 volumio volumio[978]: info: Oct 11 21:13:50 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:50 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:50 volumio volumio[978]: info: Oct 11 21:13:50 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:50 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:50 volumio volumio[978]: info: Oct 11 21:13:50 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:50 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand status took 15 milliseconds Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand stop took 11 milliseconds Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand status took 8 milliseconds Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand status took 7 milliseconds Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand clear Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:50 volumio volumio[978]: info: Oct 11 21:13:50 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:50 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand status took 12 milliseconds Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand clear took 7 milliseconds Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand add "USB/赵鹏 - 北国之春.flac" Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:50 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:13:50 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:13:50 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:50 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:50 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:13:50 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:50 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:50 volumio volumio[978]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 11 21:13:50 volumio volumio[978]: verbose: CURRENT POSITION 1 Oct 11 21:13:50 volumio volumio[978]: info: CoreStateMachine::syncState stateService stop Oct 11 21:13:50 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Oct 11 21:13:50 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:50 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:50 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:50 volumio volumio[978]: info: No code Oct 11 21:13:50 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:50 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:50 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:50 volumio volumio[978]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 11 21:13:50 volumio volumio[978]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 11 21:13:50 volumio volumio[978]: info: ------------------------------ 100ms Oct 11 21:13:50 volumio volumio[978]: info: ------------------------------ 92ms Oct 11 21:13:50 volumio volumio[978]: info: ------------------------------ 90ms Oct 11 21:13:50 volumio volumio[978]: info: Oct 11 21:13:50 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:50 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:50 volumio volumio[978]: info: Oct 11 21:13:50 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:50 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:50 volumio volumio[978]: info: Oct 11 21:13:50 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:50 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:50 volumio volumio[978]: error: updateQueue error: null Oct 11 21:13:50 volumio volumio[978]: info: Oct 11 21:13:50 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:50 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:50 volumio volumio[978]: info: ------------------------------ 83ms Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand playlistinfo took 78 milliseconds Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand add "USB/赵鹏 - 北国之春.flac" took 77 milliseconds Oct 11 21:13:50 volumio volumio[978]: info: ------------------------------ 6ms Oct 11 21:13:50 volumio volumio[978]: info: ------------------------------ 5ms Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand play Oct 11 21:13:50 volumio volumio[978]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 11 21:13:50 volumio volumio[978]: info: ------------------------------ 99ms Oct 11 21:13:50 volumio volumio[978]: info: Oct 11 21:13:50 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:50 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:50 volumio volumio[978]: info: Oct 11 21:13:50 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:50 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:50 volumio volumio[978]: info: Oct 11 21:13:50 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:50 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:50 volumio volumio[978]: info: ------------------------------ 33ms Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand play took 29 milliseconds Oct 11 21:13:50 volumio volumio[978]: info: ------------------------------ 29ms Oct 11 21:13:50 volumio volumio[978]: info: ------------------------------ 28ms Oct 11 21:13:50 volumio volumio[978]: info: ------------------------------ 14ms Oct 11 21:13:50 volumio volumio[978]: info: Oct 11 21:13:50 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:50 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:50 volumio volumio[978]: info: Oct 11 21:13:50 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:50 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:50 volumio volumio[978]: info: Oct 11 21:13:50 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:50 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:50 volumio volumio[978]: info: Oct 11 21:13:50 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:50 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:50 volumio volumio[978]: info: Oct 11 21:13:50 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand status took 162 milliseconds Oct 11 21:13:50 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand status took 152 milliseconds Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand status took 151 milliseconds Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand status took 149 milliseconds Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand status took 2 milliseconds Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:50 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:13:50 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:13:50 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:50 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"北国之春","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 北国之春.flac","trackType":"flac"} Oct 11 21:13:50 volumio volumio[978]: verbose: CURRENT POSITION 1 Oct 11 21:13:50 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:13:50 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Oct 11 21:13:50 volumio volumio[978]: info: ------------------------------ 171ms Oct 11 21:13:50 volumio volumio[978]: info: Oct 11 21:13:50 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:50 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:50 volumio volumio[978]: info: Oct 11 21:13:50 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:50 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:50 volumio volumio[978]: info: Oct 11 21:13:50 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:50 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand status took 7 milliseconds Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand status took 5 milliseconds Oct 11 21:13:50 volumio volumio[978]: info: sendMpdCommand status took 4 milliseconds Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:50 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:50 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:13:50 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:13:50 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:50 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":318,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"北国之春","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 北国之春.flac","trackType":"flac"} Oct 11 21:13:50 volumio volumio[978]: verbose: CURRENT POSITION 1 Oct 11 21:13:50 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:13:50 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:13:50 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:13:50 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:50 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:50 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:13:50 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:51 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:51 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:51 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:13:51 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:13:51 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:51 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":432,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"784 Kbps","isStreaming":false,"title":"北国之春","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 北国之春.flac","trackType":"flac"} Oct 11 21:13:51 volumio volumio[978]: verbose: CURRENT POSITION 1 Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:13:51 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:51 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:51 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:13:51 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:51 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:51 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:51 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:13:51 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:13:51 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:51 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":432,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"784 Kbps","isStreaming":false,"title":"北国之春","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 北国之春.flac","trackType":"flac"} Oct 11 21:13:51 volumio volumio[978]: verbose: CURRENT POSITION 1 Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:13:51 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:51 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:51 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:51 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:51 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:51 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:13:51 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:13:51 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:51 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":432,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"784 Kbps","isStreaming":false,"title":"北国之春","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 北国之春.flac","trackType":"flac"} Oct 11 21:13:51 volumio volumio[978]: verbose: CURRENT POSITION 1 Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:13:51 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:51 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:51 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:51 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:51 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:51 volumio volumio[978]: info: ------------------------------ 276ms Oct 11 21:13:51 volumio volumio[978]: info: ------------------------------ 265ms Oct 11 21:13:51 volumio volumio[978]: info: ------------------------------ 264ms Oct 11 21:13:51 volumio volumio[978]: info: ------------------------------ 119ms Oct 11 21:13:51 volumio volumio[978]: info: sendMpdCommand playlistinfo took 103 milliseconds Oct 11 21:13:51 volumio volumio[978]: info: sendMpdCommand playlistinfo took 104 milliseconds Oct 11 21:13:51 volumio volumio[978]: info: sendMpdCommand playlistinfo took 102 milliseconds Oct 11 21:13:51 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:51 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:51 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:51 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:13:51 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:13:51 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:51 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":523,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"784 Kbps","isStreaming":false,"title":"北国之春","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 北国之春.flac","trackType":"flac"} Oct 11 21:13:51 volumio volumio[978]: verbose: CURRENT POSITION 1 Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:13:51 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:51 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:51 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:13:51 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:51 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:51 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:51 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:13:51 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:13:51 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:51 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"850 Kbps","isStreaming":false,"title":"北国之春","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 北国之春.flac","trackType":"flac"} Oct 11 21:13:51 volumio volumio[978]: verbose: CURRENT POSITION 1 Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:13:51 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:51 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:51 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:13:51 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:51 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:51 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:51 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:13:51 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:13:51 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:51 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"850 Kbps","isStreaming":false,"title":"北国之春","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 北国之春.flac","trackType":"flac"} Oct 11 21:13:51 volumio volumio[978]: verbose: CURRENT POSITION 1 Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:13:51 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:51 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:51 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:51 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:51 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:51 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:51 volumio volumio[978]: info: ------------------------------ 221ms Oct 11 21:13:51 volumio volumio[978]: info: ------------------------------ 221ms Oct 11 21:13:51 volumio volumio[978]: info: ------------------------------ 221ms Oct 11 21:13:59 volumio volumio[978]: info: CoreCommandRouter::volumioNext Oct 11 21:13:59 volumio volumio[978]: info: CoreStateMachine::next Oct 11 21:13:59 volumio volumio[978]: info: CoreStateMachine::stop Oct 11 21:13:59 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 21:13:59 volumio volumio[978]: info: CoreStateMachine::stPlaybackTimer Oct 11 21:13:59 volumio volumio[978]: info: CoreStateMachine::updateTrackBlock Oct 11 21:13:59 volumio volumio[978]: info: CorePlayQueue::getTrackBlock Oct 11 21:13:59 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:59 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:59 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:13:59 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:59 volumio volumio[978]: info: CoreStateMachine::serviceStop Oct 11 21:13:59 volumio volumio[978]: info: CorePlayQueue::getTrack 1 Oct 11 21:13:59 volumio volumio[978]: info: CoreCommandRouter::serviceStop Oct 11 21:13:59 volumio volumio[978]: info: ControllerMpd::stop Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 21:13:59 volumio volumio[978]: info: Oct 11 21:13:59 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand stop took 170 milliseconds Oct 11 21:13:59 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:59 volumio volumio[978]: info: CoreStateMachine::play index undefined Oct 11 21:13:59 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 21:13:59 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:13:59 volumio volumio[978]: info: CoreStateMachine::startPlaybackTimer Oct 11 21:13:59 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::clearAddPlayTracks USB/江智民,周虹 - 有没有人告诉你.flac Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 21:13:59 volumio volumio[978]: info: CoreStateMachine::updateTrackBlock Oct 11 21:13:59 volumio volumio[978]: info: CorePlayQueue::getTrackBlock Oct 11 21:13:59 volumio volumio[978]: info: Oct 11 21:13:59 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:59 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:59 volumio volumio[978]: info: Oct 11 21:13:59 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:59 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:59 volumio volumio[978]: info: Oct 11 21:13:59 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:59 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand status took 16 milliseconds Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand stop took 12 milliseconds Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand status took 9 milliseconds Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand status took 6 milliseconds Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand clear Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:59 volumio volumio[978]: info: Oct 11 21:13:59 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:59 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand status took 12 milliseconds Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand clear took 8 milliseconds Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand add "USB/江智民,周虹 - 有没有人告诉你.flac" Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:59 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:13:59 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:13:59 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:59 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:13:59 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:13:59 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:59 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:13:59 volumio volumio[978]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"北国之春","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 北国之春.flac","trackType":"flac"} Oct 11 21:13:59 volumio volumio[978]: verbose: CURRENT POSITION 2 Oct 11 21:13:59 volumio volumio[978]: info: CoreStateMachine::syncState stateService stop Oct 11 21:13:59 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Oct 11 21:13:59 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:59 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:13:59 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:59 volumio volumio[978]: info: No code Oct 11 21:13:59 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:59 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:13:59 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:59 volumio volumio[978]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 11 21:13:59 volumio volumio[978]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 11 21:13:59 volumio volumio[978]: info: ------------------------------ 89ms Oct 11 21:13:59 volumio volumio[978]: info: ------------------------------ 81ms Oct 11 21:13:59 volumio volumio[978]: info: ------------------------------ 78ms Oct 11 21:13:59 volumio volumio[978]: info: Oct 11 21:13:59 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:59 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:59 volumio volumio[978]: info: Oct 11 21:13:59 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:59 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:59 volumio volumio[978]: info: Oct 11 21:13:59 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:59 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:59 volumio volumio[978]: error: updateQueue error: null Oct 11 21:13:59 volumio volumio[978]: info: Oct 11 21:13:59 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:59 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:59 volumio volumio[978]: info: ------------------------------ 73ms Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand playlistinfo took 68 milliseconds Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand add "USB/江智民,周虹 - 有没有人告诉你.flac" took 67 milliseconds Oct 11 21:13:59 volumio volumio[978]: info: ------------------------------ 10ms Oct 11 21:13:59 volumio volumio[978]: info: ------------------------------ 8ms Oct 11 21:13:59 volumio volumio[978]: info: ------------------------------ 6ms Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand play Oct 11 21:13:59 volumio volumio[978]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 11 21:13:59 volumio volumio[978]: info: ------------------------------ 89ms Oct 11 21:13:59 volumio volumio[978]: info: Oct 11 21:13:59 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:59 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:59 volumio volumio[978]: info: Oct 11 21:13:59 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:59 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:59 volumio volumio[978]: info: Oct 11 21:13:59 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:13:59 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:13:59 volumio volumio[978]: info: Oct 11 21:13:59 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:59 volumio volumio[978]: info: ------------------------------ 38ms Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand play took 33 milliseconds Oct 11 21:13:59 volumio volumio[978]: info: ------------------------------ 31ms Oct 11 21:13:59 volumio volumio[978]: info: ------------------------------ 27ms Oct 11 21:13:59 volumio volumio[978]: info: ------------------------------ 25ms Oct 11 21:13:59 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:59 volumio volumio[978]: info: Oct 11 21:13:59 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:59 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:59 volumio volumio[978]: info: Oct 11 21:13:59 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:59 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:59 volumio volumio[978]: info: Oct 11 21:13:59 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:59 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:59 volumio volumio[978]: info: Oct 11 21:13:59 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand status took 143 milliseconds Oct 11 21:13:59 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand status took 143 milliseconds Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand status took 140 milliseconds Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand status took 136 milliseconds Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand status took 5 milliseconds Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:59 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:13:59 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:13:59 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:13:59 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"181 Kbps","isStreaming":false,"title":"有没有人告诉你","artist":"江智民/周虹","album":"当流行遇上发烧时","uri":"USB/江智民,周虹 - 有没有人告诉你.flac","trackType":"flac"} Oct 11 21:13:59 volumio volumio[978]: verbose: CURRENT POSITION 2 Oct 11 21:13:59 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:13:59 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Oct 11 21:13:59 volumio volumio[978]: info: ------------------------------ 163ms Oct 11 21:13:59 volumio volumio[978]: info: Oct 11 21:13:59 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:59 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:59 volumio volumio[978]: info: Oct 11 21:13:59 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:59 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:59 volumio volumio[978]: info: Oct 11 21:13:59 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:13:59 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand playlistinfo took 17 milliseconds Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand status took 8 milliseconds Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand status took 7 milliseconds Oct 11 21:13:59 volumio volumio[978]: info: sendMpdCommand status took 5 milliseconds Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:13:59 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:13:59 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:13:59 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:13:59 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:13:59 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":432,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"185 Kbps","isStreaming":false,"title":"有没有人告诉你","artist":"江智民/周虹","album":"当流行遇上发烧时","uri":"USB/江智民,周虹 - 有没有人告诉你.flac","trackType":"flac"} Oct 11 21:13:59 volumio volumio[978]: verbose: CURRENT POSITION 2 Oct 11 21:13:59 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:13:59 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:13:59 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:13:59 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:59 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:13:59 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:13:59 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:13:59 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:13:59 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:14:00 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:00 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:00 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:00 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:14:00 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":432,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"185 Kbps","isStreaming":false,"title":"有没有人告诉你","artist":"江智民/周虹","album":"当流行遇上发烧时","uri":"USB/江智民,周虹 - 有没有人告诉你.flac","trackType":"flac"} Oct 11 21:14:00 volumio volumio[978]: verbose: CURRENT POSITION 2 Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:14:00 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:00 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:14:00 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:00 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:14:00 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:00 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:00 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:00 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:14:00 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":432,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"185 Kbps","isStreaming":false,"title":"有没有人告诉你","artist":"江智民/周虹","album":"当流行遇上发烧时","uri":"USB/江智民,周虹 - 有没有人告诉你.flac","trackType":"flac"} Oct 11 21:14:00 volumio volumio[978]: verbose: CURRENT POSITION 2 Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:14:00 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:00 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:14:00 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:00 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:14:00 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:00 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:00 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:00 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:14:00 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":432,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"185 Kbps","isStreaming":false,"title":"有没有人告诉你","artist":"江智民/周虹","album":"当流行遇上发烧时","uri":"USB/江智民,周虹 - 有没有人告诉你.flac","trackType":"flac"} Oct 11 21:14:00 volumio volumio[978]: verbose: CURRENT POSITION 2 Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:14:00 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:00 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:14:00 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:00 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:14:00 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:00 volumio volumio[978]: info: ------------------------------ 290ms Oct 11 21:14:00 volumio volumio[978]: info: ------------------------------ 288ms Oct 11 21:14:00 volumio volumio[978]: info: ------------------------------ 285ms Oct 11 21:14:00 volumio volumio[978]: info: ------------------------------ 154ms Oct 11 21:14:00 volumio volumio[978]: info: sendMpdCommand playlistinfo took 127 milliseconds Oct 11 21:14:00 volumio volumio[978]: info: sendMpdCommand playlistinfo took 127 milliseconds Oct 11 21:14:00 volumio volumio[978]: info: sendMpdCommand playlistinfo took 127 milliseconds Oct 11 21:14:00 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:14:00 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:14:00 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:00 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:00 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:14:00 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"181 Kbps","isStreaming":false,"title":"有没有人告诉你","artist":"江智民/周虹","album":"当流行遇上发烧时","uri":"USB/江智民,周虹 - 有没有人告诉你.flac","trackType":"flac"} Oct 11 21:14:00 volumio volumio[978]: verbose: CURRENT POSITION 2 Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:14:00 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:00 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:14:00 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:14:00 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:00 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:14:00 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:00 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:00 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:00 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:14:00 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"181 Kbps","isStreaming":false,"title":"有没有人告诉你","artist":"江智民/周虹","album":"当流行遇上发烧时","uri":"USB/江智民,周虹 - 有没有人告诉你.flac","trackType":"flac"} Oct 11 21:14:00 volumio volumio[978]: verbose: CURRENT POSITION 2 Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:14:00 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:00 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:14:00 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:00 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:14:00 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:00 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:00 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:00 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:14:00 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"181 Kbps","isStreaming":false,"title":"有没有人告诉你","artist":"江智民/周虹","album":"当流行遇上发烧时","uri":"USB/江智民,周虹 - 有没有人告诉你.flac","trackType":"flac"} Oct 11 21:14:00 volumio volumio[978]: verbose: CURRENT POSITION 2 Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:14:00 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:00 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:14:00 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:00 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:00 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:14:00 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:00 volumio volumio[978]: info: ------------------------------ 257ms Oct 11 21:14:00 volumio volumio[978]: info: ------------------------------ 256ms Oct 11 21:14:00 volumio volumio[978]: info: ------------------------------ 255ms Oct 11 21:14:05 volumio volumio[978]: info: CoreCommandRouter::volumioNext Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::next Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::stop Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::stPlaybackTimer Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::updateTrackBlock Oct 11 21:14:05 volumio volumio[978]: info: CorePlayQueue::getTrackBlock Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:05 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:14:05 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:14:05 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::serviceStop Oct 11 21:14:05 volumio volumio[978]: info: CorePlayQueue::getTrack 2 Oct 11 21:14:05 volumio volumio[978]: info: CoreCommandRouter::serviceStop Oct 11 21:14:05 volumio volumio[978]: info: ControllerMpd::stop Oct 11 21:14:05 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 21:14:05 volumio volumio[978]: info: sendMpdCommand stop took 159 milliseconds Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::play index undefined Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 21:14:05 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::startPlaybackTimer Oct 11 21:14:05 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:05 volumio volumio[978]: verbose: ControllerMpd::clearAddPlayTracks USB/区瑞强 - 偏偏喜欢你.flac Oct 11 21:14:05 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::updateTrackBlock Oct 11 21:14:05 volumio volumio[978]: info: CorePlayQueue::getTrackBlock Oct 11 21:14:05 volumio volumio[978]: info: Oct 11 21:14:05 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:05 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:05 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:05 volumio volumio[978]: info: sendMpdCommand stop took 4 milliseconds Oct 11 21:14:05 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand clear Oct 11 21:14:05 volumio volumio[978]: info: Oct 11 21:14:05 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:05 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:05 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:05 volumio volumio[978]: info: Oct 11 21:14:05 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:05 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:05 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:05 volumio volumio[978]: info: Oct 11 21:14:05 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:05 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:05 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:05 volumio volumio[978]: info: Oct 11 21:14:05 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:14:05 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:14:05 volumio volumio[978]: info: sendMpdCommand status took 23 milliseconds Oct 11 21:14:05 volumio volumio[978]: info: sendMpdCommand clear took 21 milliseconds Oct 11 21:14:05 volumio volumio[978]: info: sendMpdCommand status took 18 milliseconds Oct 11 21:14:05 volumio volumio[978]: info: sendMpdCommand status took 16 milliseconds Oct 11 21:14:05 volumio volumio[978]: info: sendMpdCommand status took 5 milliseconds Oct 11 21:14:05 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:05 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:14:05 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand add "USB/区瑞强 - 偏偏喜欢你.flac" Oct 11 21:14:05 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:05 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:05 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:05 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:05 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:05 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:05 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:14:05 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:05 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:05 volumio volumio[978]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 11 21:14:05 volumio volumio[978]: verbose: CURRENT POSITION 3 Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::syncState stateService stop Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:05 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:05 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:05 volumio volumio[978]: info: No code Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:05 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:05 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:05 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:05 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:05 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:05 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:05 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:05 volumio volumio[978]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 11 21:14:05 volumio volumio[978]: verbose: CURRENT POSITION 3 Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::syncState stateService stop Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:05 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:05 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:05 volumio volumio[978]: info: No code Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:05 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:05 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:05 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:05 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:05 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:05 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:05 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:05 volumio volumio[978]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 11 21:14:05 volumio volumio[978]: verbose: CURRENT POSITION 3 Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::syncState stateService stop Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:05 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:05 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:05 volumio volumio[978]: info: No code Oct 11 21:14:05 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:05 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:05 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:05 volumio volumio[978]: info: ------------------------------ 148ms Oct 11 21:14:05 volumio volumio[978]: info: ------------------------------ 147ms Oct 11 21:14:05 volumio volumio[978]: info: ------------------------------ 146ms Oct 11 21:14:05 volumio volumio[978]: info: Oct 11 21:14:05 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:14:05 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:14:05 volumio volumio[978]: info: Oct 11 21:14:05 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:14:05 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:14:05 volumio volumio[978]: info: Oct 11 21:14:05 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:14:05 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:14:05 volumio volumio[978]: error: updateQueue error: null Oct 11 21:14:05 volumio volumio[978]: info: Oct 11 21:14:05 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:14:05 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:14:05 volumio volumio[978]: info: ------------------------------ 158ms Oct 11 21:14:05 volumio volumio[978]: info: sendMpdCommand playlistinfo took 154 milliseconds Oct 11 21:14:05 volumio volumio[978]: info: sendMpdCommand add "USB/区瑞强 - 偏偏喜欢你.flac" took 153 milliseconds Oct 11 21:14:05 volumio volumio[978]: info: ------------------------------ 24ms Oct 11 21:14:05 volumio volumio[978]: info: ------------------------------ 13ms Oct 11 21:14:05 volumio volumio[978]: info: ------------------------------ 10ms Oct 11 21:14:05 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:14:05 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand play Oct 11 21:14:05 volumio volumio[978]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 11 21:14:05 volumio volumio[978]: info: ------------------------------ 189ms Oct 11 21:14:05 volumio volumio[978]: info: Oct 11 21:14:05 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:14:05 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:14:05 volumio volumio[978]: info: Oct 11 21:14:05 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:14:05 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:14:05 volumio volumio[978]: info: Oct 11 21:14:05 volumio volumio[978]: ---------------------------- MPD announces system playlist update Oct 11 21:14:05 volumio volumio[978]: info: Ignoring MPD Status Update Oct 11 21:14:05 volumio volumio[978]: info: Oct 11 21:14:05 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:05 volumio volumio[978]: info: ------------------------------ 84ms Oct 11 21:14:05 volumio volumio[978]: info: sendMpdCommand play took 78 milliseconds Oct 11 21:14:05 volumio volumio[978]: info: ------------------------------ 75ms Oct 11 21:14:05 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:05 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:05 volumio volumio[978]: info: ------------------------------ 75ms Oct 11 21:14:05 volumio volumio[978]: info: ------------------------------ 69ms Oct 11 21:14:05 volumio volumio[978]: info: Oct 11 21:14:05 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:05 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:05 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:05 volumio volumio[978]: info: Oct 11 21:14:05 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:05 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:05 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:05 volumio volumio[978]: info: Oct 11 21:14:05 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:05 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:05 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:06 volumio volumio[978]: info: Oct 11 21:14:06 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:06 volumio volumio[978]: info: sendMpdCommand status took 79 milliseconds Oct 11 21:14:06 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:14:06 volumio volumio[978]: info: Oct 11 21:14:06 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:06 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:06 volumio volumio[978]: info: Oct 11 21:14:06 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:06 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:06 volumio volumio[978]: info: Oct 11 21:14:06 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:06 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:06 volumio volumio[978]: info: sendMpdCommand status took 91 milliseconds Oct 11 21:14:06 volumio volumio[978]: info: sendMpdCommand status took 93 milliseconds Oct 11 21:14:06 volumio volumio[978]: info: sendMpdCommand status took 93 milliseconds Oct 11 21:14:06 volumio volumio[978]: info: sendMpdCommand status took 21 milliseconds Oct 11 21:14:06 volumio volumio[978]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 11 21:14:06 volumio volumio[978]: info: sendMpdCommand status took 17 milliseconds Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:14:06 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:06 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:06 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Oct 11 21:14:06 volumio volumio[978]: verbose: CURRENT POSITION 3 Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Oct 11 21:14:06 volumio volumio[978]: info: ------------------------------ 115ms Oct 11 21:14:06 volumio volumio[978]: info: sendMpdCommand status took 24 milliseconds Oct 11 21:14:06 volumio volumio[978]: info: sendMpdCommand status took 22 milliseconds Oct 11 21:14:06 volumio volumio[978]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 11 21:14:06 volumio volumio[978]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 11 21:14:06 volumio volumio[978]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 11 21:14:06 volumio volumio[978]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 11 21:14:06 volumio volumio[978]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:14:06 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:06 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:06 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":478,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"117 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Oct 11 21:14:06 volumio volumio[978]: verbose: CURRENT POSITION 3 Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:14:06 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:06 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:06 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:06 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:06 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:06 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":500,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"120 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Oct 11 21:14:06 volumio volumio[978]: verbose: CURRENT POSITION 3 Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:14:06 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:06 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:06 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:06 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:06 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:06 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":523,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"119 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Oct 11 21:14:06 volumio volumio[978]: verbose: CURRENT POSITION 3 Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:14:06 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:06 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:06 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:06 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:06 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:06 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":523,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"779 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Oct 11 21:14:06 volumio volumio[978]: verbose: CURRENT POSITION 3 Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:14:06 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:06 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:06 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:06 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:06 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:06 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"742 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Oct 11 21:14:06 volumio volumio[978]: verbose: CURRENT POSITION 3 Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:14:06 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:06 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:06 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:06 volumio volumio[978]: info: ------------------------------ 257ms Oct 11 21:14:06 volumio volumio[978]: info: ------------------------------ 256ms Oct 11 21:14:06 volumio volumio[978]: info: ------------------------------ 256ms Oct 11 21:14:06 volumio volumio[978]: info: ------------------------------ 186ms Oct 11 21:14:06 volumio volumio[978]: info: ------------------------------ 181ms Oct 11 21:14:06 volumio volumio[978]: info: sendMpdCommand playlistinfo took 159 milliseconds Oct 11 21:14:06 volumio volumio[978]: info: sendMpdCommand playlistinfo took 160 milliseconds Oct 11 21:14:06 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:14:06 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:06 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:06 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"729 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Oct 11 21:14:06 volumio volumio[978]: verbose: CURRENT POSITION 3 Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:14:06 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:06 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:06 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:06 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:06 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:06 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"729 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Oct 11 21:14:06 volumio volumio[978]: verbose: CURRENT POSITION 3 Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:14:06 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:06 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:06 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:06 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:06 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:06 volumio volumio[978]: info: ------------------------------ 271ms Oct 11 21:14:06 volumio volumio[978]: info: ------------------------------ 270ms Oct 11 21:14:13 volumio volumio[978]: info: CALLMETHOD: user_interface peppy_screensaver saveVUMeterConf [object Object] Oct 11 21:14:13 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , saveVUMeterConf Oct 11 21:14:22 volumio volumio[978]: info: CoreCommandRouter::volumioPrevious Oct 11 21:14:22 volumio volumio[978]: info: CoreStateMachine::previous Oct 11 21:14:22 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:22 volumio volumio[978]: info: ControllerMpd::seek Oct 11 21:14:22 volumio volumio[978]: error: null Oct 11 21:14:22 volumio volumio[978]: info: Oct 11 21:14:22 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:22 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:22 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:22 volumio volumio[978]: info: Oct 11 21:14:22 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:22 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:22 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:22 volumio volumio[978]: info: Oct 11 21:14:22 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:22 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:22 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:22 volumio volumio[978]: info: Oct 11 21:14:22 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:22 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:22 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:22 volumio volumio[978]: info: sendMpdCommand status took 6 milliseconds Oct 11 21:14:22 volumio volumio[978]: info: sendMpdCommand status took 5 milliseconds Oct 11 21:14:22 volumio volumio[978]: info: sendMpdCommand status took 3 milliseconds Oct 11 21:14:22 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:22 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:14:22 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:22 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:14:22 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:22 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:14:22 volumio volumio[978]: info: sendMpdCommand status took 5 milliseconds Oct 11 21:14:22 volumio volumio[978]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 11 21:14:22 volumio volumio[978]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 11 21:14:22 volumio volumio[978]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 11 21:14:22 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:22 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:14:22 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:14:22 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:14:22 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:14:22 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:22 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:22 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:22 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"870 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Oct 11 21:14:22 volumio volumio[978]: verbose: CURRENT POSITION 3 Oct 11 21:14:22 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:14:22 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:14:22 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:14:22 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:22 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:22 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:14:22 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:22 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:22 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:22 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:22 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:22 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:22 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:22 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":295,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"122 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Oct 11 21:14:22 volumio volumio[978]: verbose: CURRENT POSITION 3 Oct 11 21:14:22 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:14:22 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:14:22 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:14:22 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:22 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:22 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:14:22 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:22 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:22 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:22 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:22 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:22 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:22 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:22 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":341,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"490 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Oct 11 21:14:22 volumio volumio[978]: verbose: CURRENT POSITION 3 Oct 11 21:14:22 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:14:22 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:14:22 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:14:22 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:22 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:22 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:14:22 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:22 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:22 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:22 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:22 volumio volumio[978]: info: ------------------------------ 105ms Oct 11 21:14:22 volumio volumio[978]: info: ------------------------------ 106ms Oct 11 21:14:22 volumio volumio[978]: info: ------------------------------ 104ms Oct 11 21:14:22 volumio volumio[978]: info: sendMpdCommand playlistinfo took 98 milliseconds Oct 11 21:14:22 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:14:22 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:22 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:22 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:22 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":341,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"464 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Oct 11 21:14:22 volumio volumio[978]: verbose: CURRENT POSITION 3 Oct 11 21:14:22 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:14:22 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:14:22 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:14:22 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:22 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:22 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:22 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:22 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:22 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:22 volumio volumio[978]: info: ------------------------------ 143ms Oct 11 21:14:28 volumio volumio[978]: info: CoreCommandRouter::volumioPrevious Oct 11 21:14:28 volumio volumio[978]: info: CoreStateMachine::previous Oct 11 21:14:28 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:28 volumio volumio[978]: info: ControllerMpd::seek Oct 11 21:14:28 volumio volumio[978]: error: null Oct 11 21:14:28 volumio volumio[978]: info: Oct 11 21:14:28 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:28 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:28 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:28 volumio volumio[978]: info: Oct 11 21:14:28 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:28 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:28 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:28 volumio volumio[978]: info: Oct 11 21:14:28 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:28 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:28 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:28 volumio volumio[978]: info: Oct 11 21:14:28 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:28 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:28 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:28 volumio volumio[978]: info: sendMpdCommand status took 6 milliseconds Oct 11 21:14:28 volumio volumio[978]: info: sendMpdCommand status took 5 milliseconds Oct 11 21:14:28 volumio volumio[978]: info: sendMpdCommand status took 4 milliseconds Oct 11 21:14:28 volumio volumio[978]: info: sendMpdCommand status took 2 milliseconds Oct 11 21:14:28 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:28 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:14:28 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:28 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:14:28 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:28 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:14:28 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:28 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:14:28 volumio volumio[978]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 11 21:14:28 volumio volumio[978]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 11 21:14:28 volumio volumio[978]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 11 21:14:28 volumio volumio[978]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 11 21:14:28 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:14:28 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:14:28 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:14:28 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:14:28 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:28 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:28 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:28 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"694 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Oct 11 21:14:28 volumio volumio[978]: verbose: CURRENT POSITION 3 Oct 11 21:14:28 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:14:28 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:14:28 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:14:28 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:28 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:14:28 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:28 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:28 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:28 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:28 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:28 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:28 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:28 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"694 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Oct 11 21:14:28 volumio volumio[978]: verbose: CURRENT POSITION 3 Oct 11 21:14:28 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:14:28 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:14:28 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:14:28 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:28 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:28 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:28 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:28 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:28 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:28 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:28 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:28 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:28 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":273,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"122 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Oct 11 21:14:28 volumio volumio[978]: verbose: CURRENT POSITION 3 Oct 11 21:14:28 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:14:28 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:14:28 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:14:28 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:28 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:14:28 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:28 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:28 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:28 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:28 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:28 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:28 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:28 volumio volumio[978]: verbose: STATE SERVICE {"status":"play","position":0,"seek":341,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"464 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Oct 11 21:14:28 volumio volumio[978]: verbose: CURRENT POSITION 3 Oct 11 21:14:28 volumio volumio[978]: info: CoreStateMachine::syncState stateService play Oct 11 21:14:28 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus play Oct 11 21:14:28 volumio volumio[978]: info: Received an update from plugin. extracting info from payload Oct 11 21:14:28 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:28 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:14:28 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:28 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:28 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:28 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:28 volumio volumio[978]: info: ------------------------------ 136ms Oct 11 21:14:28 volumio volumio[978]: info: ------------------------------ 135ms Oct 11 21:14:28 volumio volumio[978]: info: ------------------------------ 135ms Oct 11 21:14:28 volumio volumio[978]: info: ------------------------------ 135ms Oct 11 21:14:33 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Oct 11 21:14:33 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:36 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 21:14:36 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 11 21:14:45 volumio volumio[978]: info: CoreCommandRouter::volumioPause Oct 11 21:14:45 volumio volumio[978]: info: CoreStateMachine::pause Oct 11 21:14:45 volumio volumio[978]: info: CoreStateMachine::stPlaybackTimer Oct 11 21:14:45 volumio volumio[978]: info: CoreStateMachine::servicePause Oct 11 21:14:45 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:45 volumio volumio[978]: info: CoreCommandRouter::servicePause Oct 11 21:14:45 volumio volumio[978]: info: ControllerMpd::pause Oct 11 21:14:45 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand pause Oct 11 21:14:45 volumio volumio[978]: info: sendMpdCommand pause took 2 milliseconds Oct 11 21:14:45 volumio volumio[978]: info: Oct 11 21:14:45 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:45 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:45 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:45 volumio volumio[978]: info: Oct 11 21:14:45 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:45 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:45 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:45 volumio volumio[978]: info: Oct 11 21:14:45 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:45 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:45 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:45 volumio volumio[978]: info: Oct 11 21:14:45 volumio volumio[978]: ---------------------------- MPD announces state update: player Oct 11 21:14:45 volumio volumio[978]: info: ControllerMpd::getState Oct 11 21:14:45 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Oct 11 21:14:45 volumio volumio[978]: info: sendMpdCommand status took 5 milliseconds Oct 11 21:14:45 volumio volumio[978]: info: sendMpdCommand status took 4 milliseconds Oct 11 21:14:45 volumio volumio[978]: info: sendMpdCommand status took 3 milliseconds Oct 11 21:14:45 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:45 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:14:45 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:45 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:14:45 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:45 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:14:45 volumio volumio[978]: info: sendMpdCommand status took 8 milliseconds Oct 11 21:14:45 volumio volumio[978]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 11 21:14:45 volumio volumio[978]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 11 21:14:45 volumio volumio[978]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 11 21:14:45 volumio volumio[978]: verbose: ControllerMpd::parseState Oct 11 21:14:45 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 21:14:45 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:14:45 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:14:45 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:14:45 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:45 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:45 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:45 volumio volumio[978]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17416,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"745 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Oct 11 21:14:45 volumio volumio[978]: verbose: CURRENT POSITION 3 Oct 11 21:14:45 volumio volumio[978]: info: CoreStateMachine::syncState stateService pause Oct 11 21:14:45 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus pause Oct 11 21:14:45 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:45 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:45 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 21:14:45 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:45 volumio volumio[978]: info: CoreStateMachine::stPlaybackTimer Oct 11 21:14:45 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:45 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:45 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:45 volumio volumio[978]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17416,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"745 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Oct 11 21:14:45 volumio volumio[978]: verbose: CURRENT POSITION 3 Oct 11 21:14:45 volumio volumio[978]: info: CoreStateMachine::syncState stateService pause Oct 11 21:14:45 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus pause Oct 11 21:14:45 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:45 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:45 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:45 volumio volumio[978]: info: CoreStateMachine::stPlaybackTimer Oct 11 21:14:45 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:45 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:45 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:45 volumio volumio[978]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17416,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"745 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Oct 11 21:14:45 volumio volumio[978]: verbose: CURRENT POSITION 3 Oct 11 21:14:45 volumio volumio[978]: info: CoreStateMachine::syncState stateService pause Oct 11 21:14:45 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus pause Oct 11 21:14:45 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:45 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:45 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:45 volumio volumio[978]: info: CoreStateMachine::stPlaybackTimer Oct 11 21:14:45 volumio volumio[978]: info: ------------------------------ 61ms Oct 11 21:14:45 volumio volumio[978]: info: ------------------------------ 60ms Oct 11 21:14:45 volumio volumio[978]: info: ------------------------------ 59ms Oct 11 21:14:45 volumio volumio[978]: info: sendMpdCommand playlistinfo took 49 milliseconds Oct 11 21:14:45 volumio volumio[978]: verbose: ControllerMpd::parseTrackInfo Oct 11 21:14:45 volumio volumio[978]: info: ControllerMpd::pushState Oct 11 21:14:45 volumio volumio[978]: info: CoreCommandRouter::servicePushState Oct 11 21:14:45 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:45 volumio volumio[978]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17416,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"745 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Oct 11 21:14:45 volumio volumio[978]: verbose: CURRENT POSITION 3 Oct 11 21:14:45 volumio volumio[978]: info: CoreStateMachine::syncState stateService pause Oct 11 21:14:45 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus pause Oct 11 21:14:45 volumio volumio[978]: info: CoreStateMachine::pushState Oct 11 21:14:45 volumio volumio[978]: info: CorePlayQueue::getTrack 3 Oct 11 21:14:45 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Oct 11 21:14:45 volumio volumio[978]: info: CoreStateMachine::stPlaybackTimer Oct 11 21:14:45 volumio volumio[978]: info: ------------------------------ 78ms Oct 11 21:14:45 volumio volumio[978]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 21:14:45 volumio volumio[978]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Oct 11 21:14:45 volumio volumio[978]: at Object.readdirSync (fs.js:1048:3) Oct 11 21:14:45 volumio volumio[978]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Oct 11 21:14:45 volumio volumio[978]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Oct 11 21:14:45 volumio volumio[978]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Oct 11 21:14:45 volumio volumio[978]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Oct 11 21:14:45 volumio volumio[978]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Oct 11 21:14:45 volumio volumio[978]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 11 21:14:45 volumio volumio[978]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Oct 11 21:14:45 volumio volumio[978]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Oct 11 21:14:45 volumio volumio[978]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 11 21:14:45 volumio volumio[978]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 11 21:14:45 volumio volumio[978]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Oct 11 21:14:45 volumio volumio[978]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 11 21:14:45 volumio volumio[978]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 11 21:14:45 volumio volumio[978]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Oct 11 21:14:45 volumio volumio[978]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Oct 11 21:14:45 volumio volumio[978]: errno: -20, Oct 11 21:14:45 volumio volumio[978]: syscall: 'scandir', Oct 11 21:14:45 volumio volumio[978]: code: 'ENOTDIR', Oct 11 21:14:45 volumio volumio[978]: path: '/tmp/peppyrunning' Oct 11 21:14:45 volumio volumio[978]: } Oct 11 21:14:45 volumio volumio[978]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 21:14:46 volumio sudo[2619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-11 21:13 Oct 11 21:14:46 volumio sudo[2619]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"