-- Logs begin at Mon 2025-03-10 00:13:51 CST, end at Sun 2025-03-16 20:13:12 CST. -- Mar 16 20:12:02 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 16 20:12:02 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 16 20:12:02 volumio-ld784 volumio[779]: info: Discovery: Getting this device information Mar 16 20:12:02 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioGetState Mar 16 20:12:02 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 16 20:12:02 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 16 20:12:02 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 16 20:12:02 volumio-ld784 volumio[779]: info: Discovery: Getting this device information Mar 16 20:12:02 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioGetState Mar 16 20:12:02 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 16 20:12:04 volumio-ld784 volumio[779]: verbose: New Socket.io Connection to 192.168.1.235:3000 from 192.168.1.178 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Mar 16 20:12:06 volumio-ld784 volumio[779]: info: CoreCommandRouter::servicePushState Mar 16 20:12:06 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:06 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 20:12:06 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:06 volumio-ld784 volumio[779]: info: CoreCommandRouter::servicePushState Mar 16 20:12:06 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:06 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:06 volumio-ld784 volumio[779]: info: CoreCommandRouter::servicePushState Mar 16 20:12:06 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:06 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:06 volumio-ld784 shairport-sync[1092]: {"time":1742127010567,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Mar 16 20:12:06 volumio-ld784 shairport-sync[1092]: Dload Upload Total Spent Left Speed Mar 16 20:12:06 volumio-ld784 volumio[779]: info: Mar 16 20:12:06 volumio-ld784 volumio[779]: ---------------------------- Client requests Stop Airplay Playback Mar 16 20:12:06 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Mar 16 20:12:06 volumio-ld784 volumio[779]: info: Airplay playback stop Mar 16 20:12:06 volumio-ld784 volumio[779]: info: Airplay Playback Stopped, clearing state Mar 16 20:12:06 volumio-ld784 volumio[779]: info: CoreStateMachine::resetVolumioState Mar 16 20:12:06 volumio-ld784 volumio[779]: info: CoreStateMachine::getcurrentVolume Mar 16 20:12:06 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioRetrievevolume Mar 16 20:12:06 volumio-ld784 shairport-sync[1092]: [158B blob data] Mar 16 20:12:06 volumio-ld784 volumio[779]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Mar 16 20:12:06 volumio-ld784 volumio[779]: info: VolumeController:: Volume=undefined Mute =false Mar 16 20:12:06 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:06 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:06 volumio-ld784 volumio[779]: info: CoreStateMachine::updateTrackBlock Mar 16 20:12:06 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrackBlock Mar 16 20:12:06 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioRetrievevolume Mar 16 20:12:06 volumio-ld784 volumio[779]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Mar 16 20:12:06 volumio-ld784 volumio[779]: info: VolumeController:: Volume=undefined Mute =false Mar 16 20:12:06 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:06 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:07 volumio-ld784 ntpd[741]: 193.182.111.14 local addr 192.168.1.235 -> Mar 16 20:12:08 volumio-ld784 volumio[779]: verbose: New Socket.io Connection to 192.168.1.235 from 192.168.1.178 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_0_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Mar 16 20:12:08 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 16 20:12:08 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioGetVisibleSources Mar 16 20:12:08 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 20:12:08 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioGetState Mar 16 20:12:08 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 16 20:12:08 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 16 20:12:08 volumio-ld784 volumio[779]: info: Received Get System Info Mar 16 20:12:08 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 16 20:12:08 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 16 20:12:08 volumio-ld784 volumio[779]: info: Discovery: Getting this device information Mar 16 20:12:08 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioGetState Mar 16 20:12:08 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 16 20:12:08 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioGetState Mar 16 20:12:08 volumio-ld784 volumio[779]: info: Listing playlists Mar 16 20:12:08 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioGetQueue Mar 16 20:12:08 volumio-ld784 volumio[779]: info: CoreStateMachine::getQueue Mar 16 20:12:08 volumio-ld784 volumio[779]: info: CorePlayQueue::getQueue Mar 16 20:12:09 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 16 20:12:17 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 16 20:12:17 volumio-ld784 volumio[779]: info: CURURI: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③ Mar 16 20:12:17 volumio-ld784 volumio[779]: info: Preload queue cleared Mar 16 20:12:17 volumio-ld784 volumio[779]: info: Preloading song: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/01 追忆.wav Mar 16 20:12:17 volumio-ld784 volumio[779]: info: Preloading song: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/02 顺流逆流.wav Mar 16 20:12:17 volumio-ld784 volumio[779]: info: Preloading song: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/03 是雨是泪.wav Mar 16 20:12:17 volumio-ld784 volumio[779]: info: Preloading song: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/04 四季歌.wav Mar 16 20:12:17 volumio-ld784 volumio[779]: info: Preloading song: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/05 天才与白痴.wav Mar 16 20:12:17 volumio-ld784 volumio[779]: info: Preloading song: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/06 长街的一角.wav Mar 16 20:12:17 volumio-ld784 volumio[779]: info: Preloading song: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/07 叹息桥.wav Mar 16 20:12:17 volumio-ld784 volumio[779]: info: Preloading song: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/08 纸船.wav Mar 16 20:12:17 volumio-ld784 volumio[779]: info: Preloading song: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/09 夜夜念奴娇.wav Mar 16 20:12:17 volumio-ld784 volumio[779]: info: Preloading song: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/10 一生所爱.wav Mar 16 20:12:17 volumio-ld784 volumio[779]: info: Preloading song: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/11 依然.wav Mar 16 20:12:17 volumio-ld784 volumio[779]: info: Preloading song: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/12 伴侣.wav Mar 16 20:12:17 volumio-ld784 volumio[779]: info: Exploding uri music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/01 追忆.wav in service mpd Mar 16 20:12:17 volumio-ld784 volumio[779]: info: ALBUMART /albumart?cacheid=367&web=%3F%3F%C2%84/%3F%3F%3F%3F%3F%3F%CA%AE%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F%E6%97%B6%E9%92%9F%E8%B5%84%E6%96%99%2F%E7%8E%8B%E5%98%89%E6%96%87%EF%BC%88%E6%9C%89%E8%B0%81%E5%85%B1%E9%B8%A3%EF%BC%89%2F%E7%94%B7%E4%BA%BA%E5%9B%9B%E5%8D%81%E2%91%A2%2F01%20%E8%BF%BD%E5%BF%86.wav&metadata=false Mar 16 20:12:17 volumio-ld784 volumio[779]: info: URI /mnt/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/01 追忆.wav Mar 16 20:12:17 volumio-ld784 volumio[779]: info: Exploding uri music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/02 顺流逆流.wav in service mpd Mar 16 20:12:17 volumio-ld784 volumio[779]: info: ALBUMART /albumart?cacheid=367&web=%3F%3F%C2%84/%3F%3F%3F%3F%3F%3F%CA%AE%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F%E6%97%B6%E9%92%9F%E8%B5%84%E6%96%99%2F%E7%8E%8B%E5%98%89%E6%96%87%EF%BC%88%E6%9C%89%E8%B0%81%E5%85%B1%E9%B8%A3%EF%BC%89%2F%E7%94%B7%E4%BA%BA%E5%9B%9B%E5%8D%81%E2%91%A2%2F02%20%E9%A1%BA%E6%B5%81%E9%80%86%E6%B5%81.wav&metadata=false Mar 16 20:12:17 volumio-ld784 volumio[779]: info: URI /mnt/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/02 顺流逆流.wav Mar 16 20:12:17 volumio-ld784 volumio[779]: info: Exploding uri music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/03 是雨是泪.wav in service mpd Mar 16 20:12:17 volumio-ld784 volumio[779]: info: ALBUMART /albumart?cacheid=367&web=%3F%3F%C2%84/%3F%3F%3F%3F%3F%3F%CA%AE%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F%E6%97%B6%E9%92%9F%E8%B5%84%E6%96%99%2F%E7%8E%8B%E5%98%89%E6%96%87%EF%BC%88%E6%9C%89%E8%B0%81%E5%85%B1%E9%B8%A3%EF%BC%89%2F%E7%94%B7%E4%BA%BA%E5%9B%9B%E5%8D%81%E2%91%A2%2F03%20%E6%98%AF%E9%9B%A8%E6%98%AF%E6%B3%AA.wav&metadata=false Mar 16 20:12:17 volumio-ld784 volumio[779]: info: URI /mnt/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/03 是雨是泪.wav Mar 16 20:12:17 volumio-ld784 volumio[779]: info: Exploding uri music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/04 四季歌.wav in service mpd Mar 16 20:12:17 volumio-ld784 volumio[779]: info: ALBUMART /albumart?cacheid=367&web=%3F%3F%C2%84/%3F%3F%3F%3F%3F%3F%CA%AE%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F%E6%97%B6%E9%92%9F%E8%B5%84%E6%96%99%2F%E7%8E%8B%E5%98%89%E6%96%87%EF%BC%88%E6%9C%89%E8%B0%81%E5%85%B1%E9%B8%A3%EF%BC%89%2F%E7%94%B7%E4%BA%BA%E5%9B%9B%E5%8D%81%E2%91%A2%2F04%20%E5%9B%9B%E5%AD%A3%E6%AD%8C.wav&metadata=false Mar 16 20:12:17 volumio-ld784 volumio[779]: info: URI /mnt/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/04 四季歌.wav Mar 16 20:12:17 volumio-ld784 volumio[779]: info: Exploding uri music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/05 天才与白痴.wav in service mpd Mar 16 20:12:17 volumio-ld784 volumio[779]: info: ALBUMART /albumart?cacheid=367&web=%3F%3F%C2%84/%3F%3F%3F%3F%3F%3F%CA%AE%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F%E6%97%B6%E9%92%9F%E8%B5%84%E6%96%99%2F%E7%8E%8B%E5%98%89%E6%96%87%EF%BC%88%E6%9C%89%E8%B0%81%E5%85%B1%E9%B8%A3%EF%BC%89%2F%E7%94%B7%E4%BA%BA%E5%9B%9B%E5%8D%81%E2%91%A2%2F05%20%E5%A4%A9%E6%89%8D%E4%B8%8E%E7%99%BD%E7%97%B4.wav&metadata=false Mar 16 20:12:17 volumio-ld784 volumio[779]: info: URI /mnt/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/05 天才与白痴.wav Mar 16 20:12:18 volumio-ld784 volumio[779]: info: Exploding uri music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/06 长街的一角.wav in service mpd Mar 16 20:12:18 volumio-ld784 volumio[779]: info: ALBUMART /albumart?cacheid=367&web=%3F%3F%C2%84/%3F%3F%3F%3F%3F%3F%CA%AE%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F%E6%97%B6%E9%92%9F%E8%B5%84%E6%96%99%2F%E7%8E%8B%E5%98%89%E6%96%87%EF%BC%88%E6%9C%89%E8%B0%81%E5%85%B1%E9%B8%A3%EF%BC%89%2F%E7%94%B7%E4%BA%BA%E5%9B%9B%E5%8D%81%E2%91%A2%2F06%20%E9%95%BF%E8%A1%97%E7%9A%84%E4%B8%80%E8%A7%92.wav&metadata=false Mar 16 20:12:18 volumio-ld784 volumio[779]: info: URI /mnt/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/06 长街的一角.wav Mar 16 20:12:18 volumio-ld784 volumio[779]: info: Exploding uri music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/07 叹息桥.wav in service mpd Mar 16 20:12:18 volumio-ld784 volumio[779]: info: ALBUMART /albumart?cacheid=367&web=%3F%3F%C2%84/%3F%3F%3F%3F%3F%3F%CA%AE%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F%E6%97%B6%E9%92%9F%E8%B5%84%E6%96%99%2F%E7%8E%8B%E5%98%89%E6%96%87%EF%BC%88%E6%9C%89%E8%B0%81%E5%85%B1%E9%B8%A3%EF%BC%89%2F%E7%94%B7%E4%BA%BA%E5%9B%9B%E5%8D%81%E2%91%A2%2F07%20%E5%8F%B9%E6%81%AF%E6%A1%A5.wav&metadata=false Mar 16 20:12:18 volumio-ld784 volumio[779]: info: URI /mnt/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/07 叹息桥.wav Mar 16 20:12:18 volumio-ld784 volumio[779]: info: Exploding uri music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/08 纸船.wav in service mpd Mar 16 20:12:18 volumio-ld784 volumio[779]: info: ALBUMART /albumart?cacheid=367&web=%3F%3F%C2%84/%3F%3F%3F%3F%3F%3F%CA%AE%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F%E6%97%B6%E9%92%9F%E8%B5%84%E6%96%99%2F%E7%8E%8B%E5%98%89%E6%96%87%EF%BC%88%E6%9C%89%E8%B0%81%E5%85%B1%E9%B8%A3%EF%BC%89%2F%E7%94%B7%E4%BA%BA%E5%9B%9B%E5%8D%81%E2%91%A2%2F08%20%E7%BA%B8%E8%88%B9.wav&metadata=false Mar 16 20:12:18 volumio-ld784 volumio[779]: info: URI /mnt/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/08 纸船.wav Mar 16 20:12:18 volumio-ld784 volumio[779]: info: Exploding uri music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/09 夜夜念奴娇.wav in service mpd Mar 16 20:12:18 volumio-ld784 volumio[779]: info: ALBUMART /albumart?cacheid=367&web=%3F%3F%C2%84/%3F%3F%3F%3F%3F%3F%CA%AE%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F%E6%97%B6%E9%92%9F%E8%B5%84%E6%96%99%2F%E7%8E%8B%E5%98%89%E6%96%87%EF%BC%88%E6%9C%89%E8%B0%81%E5%85%B1%E9%B8%A3%EF%BC%89%2F%E7%94%B7%E4%BA%BA%E5%9B%9B%E5%8D%81%E2%91%A2%2F09%20%E5%A4%9C%E5%A4%9C%E5%BF%B5%E5%A5%B4%E5%A8%87.wav&metadata=false Mar 16 20:12:18 volumio-ld784 volumio[779]: info: URI /mnt/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/09 夜夜念奴娇.wav Mar 16 20:12:18 volumio-ld784 volumio[779]: info: Exploding uri music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/10 一生所爱.wav in service mpd Mar 16 20:12:18 volumio-ld784 volumio[779]: info: ALBUMART /albumart?cacheid=367&web=%3F%3F%C2%84/%3F%3F%3F%3F%3F%3F%CA%AE%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F%E6%97%B6%E9%92%9F%E8%B5%84%E6%96%99%2F%E7%8E%8B%E5%98%89%E6%96%87%EF%BC%88%E6%9C%89%E8%B0%81%E5%85%B1%E9%B8%A3%EF%BC%89%2F%E7%94%B7%E4%BA%BA%E5%9B%9B%E5%8D%81%E2%91%A2%2F10%20%E4%B8%80%E7%94%9F%E6%89%80%E7%88%B1.wav&metadata=false Mar 16 20:12:18 volumio-ld784 volumio[779]: info: URI /mnt/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/10 一生所爱.wav Mar 16 20:12:18 volumio-ld784 volumio[779]: info: Exploding uri music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/11 依然.wav in service mpd Mar 16 20:12:18 volumio-ld784 volumio[779]: info: ALBUMART /albumart?cacheid=367&web=%3F%3F%C2%84/%3F%3F%3F%3F%3F%3F%CA%AE%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F%E6%97%B6%E9%92%9F%E8%B5%84%E6%96%99%2F%E7%8E%8B%E5%98%89%E6%96%87%EF%BC%88%E6%9C%89%E8%B0%81%E5%85%B1%E9%B8%A3%EF%BC%89%2F%E7%94%B7%E4%BA%BA%E5%9B%9B%E5%8D%81%E2%91%A2%2F11%20%E4%BE%9D%E7%84%B6.wav&metadata=false Mar 16 20:12:18 volumio-ld784 volumio[779]: info: URI /mnt/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/11 依然.wav Mar 16 20:12:18 volumio-ld784 volumio[779]: info: Exploding uri music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/12 伴侣.wav in service mpd Mar 16 20:12:18 volumio-ld784 volumio[779]: info: ALBUMART /albumart?cacheid=367&web=%3F%3F%C2%84/%3F%3F%3F%3F%3F%3F%CA%AE%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F%E6%97%B6%E9%92%9F%E8%B5%84%E6%96%99%2F%E7%8E%8B%E5%98%89%E6%96%87%EF%BC%88%E6%9C%89%E8%B0%81%E5%85%B1%E9%B8%A3%EF%BC%89%2F%E7%94%B7%E4%BA%BA%E5%9B%9B%E5%8D%81%E2%91%A2%2F12%20%E4%BC%B4%E4%BE%A3.wav&metadata=false Mar 16 20:12:18 volumio-ld784 volumio[779]: info: URI /mnt/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/12 伴侣.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Preload queue cleared Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::ClearQueue Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::stop Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::serviceStop Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreCommandRouter::serviceStop Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Airplay Stop Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Stopping Airplay Playback and sending pause command to client via USR2 Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CorePlayQueue::clearPlayQueue Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CorePlayQueue::saveQueue Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushQueue Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::addQueueItems Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CorePlayQueue::addQueueItems Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Preload queue cleared Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Adding Item to queue: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/01 追忆.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Using cached record of: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/01 追忆.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Adding Item to queue: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/02 顺流逆流.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Using cached record of: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/02 顺流逆流.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Adding Item to queue: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/03 是雨是泪.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Using cached record of: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/03 是雨是泪.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Adding Item to queue: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/04 四季歌.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Using cached record of: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/04 四季歌.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Adding Item to queue: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/05 天才与白痴.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Using cached record of: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/05 天才与白痴.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Adding Item to queue: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/06 长街的一角.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Using cached record of: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/06 长街的一角.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Adding Item to queue: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/07 叹息桥.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Using cached record of: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/07 叹息桥.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Adding Item to queue: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/08 纸船.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Using cached record of: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/08 纸船.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Adding Item to queue: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/09 夜夜念奴娇.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Using cached record of: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/09 夜夜念奴娇.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Adding Item to queue: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/10 一生所爱.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Using cached record of: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/10 一生所爱.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Adding Item to queue: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/11 依然.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Using cached record of: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/11 依然.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Adding Item to queue: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/12 伴侣.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Using cached record of: music-library/USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/12 伴侣.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushQueue Mar 16 20:12:20 volumio-ld784 sudo[3501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1092 Mar 16 20:12:20 volumio-ld784 sudo[3501]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CorePlayQueue::saveQueue Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::updateTrackBlock Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrackBlock Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPlay Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: UNSET VOLATILE: Service: airplay_emulation Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Stopping Airplay Playback and sending pause command to client via USR2 Mar 16 20:12:20 volumio-ld784 sudo[3501]: pam_unix(sudo:session): session closed for user root Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::play index 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::stop Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::play index undefined Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::startPlaybackTimer Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::clearAddPlayTracks USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/01 追忆.wav Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 20:12:20 volumio-ld784 volumio[779]: info: sendMpdCommand stop took 14 milliseconds Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand clear Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Shairport-Sync paused with USR2 Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Mar 16 20:12:20 volumio-ld784 volumio[779]: ---------------------------- MPD announces system playlist update Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Ignoring MPD Status Update Mar 16 20:12:20 volumio-ld784 volumio[779]: info: sendMpdCommand clear took 5 milliseconds Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand add "USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/01 追忆.wav" Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Mar 16 20:12:20 volumio-ld784 volumio[779]: ---------------------------- MPD announces system playlist update Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Ignoring MPD Status Update Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Mar 16 20:12:20 volumio-ld784 volumio[779]: ---------------------------- MPD announces system playlist update Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Ignoring MPD Status Update Mar 16 20:12:20 volumio-ld784 volumio[779]: error: updateQueue error: null Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Mar 16 20:12:20 volumio-ld784 volumio[779]: ---------------------------- MPD announces system playlist update Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Ignoring MPD Status Update Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ------------------------------ 10ms Mar 16 20:12:20 volumio-ld784 volumio[779]: info: sendMpdCommand add "USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/01 追忆.wav" took 9 milliseconds Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ------------------------------ 8ms Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ------------------------------ 7ms Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand play Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Mar 16 20:12:20 volumio-ld784 volumio[779]: ---------------------------- MPD announces system playlist update Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Ignoring MPD Status Update Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Mar 16 20:12:20 volumio-ld784 volumio[779]: ---------------------------- MPD announces system playlist update Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Ignoring MPD Status Update Mar 16 20:12:20 volumio-ld784 volumio[779]: error: Upnp client error: Error: This socket has been ended by the other party Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ------------------------------ 10ms Mar 16 20:12:20 volumio-ld784 volumio[779]: info: sendMpdCommand play took 7 milliseconds Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ------------------------------ 5ms Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ------------------------------ 7ms Mar 16 20:12:20 volumio-ld784 sudo[3505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1092 Mar 16 20:12:20 volumio-ld784 sudo[3505]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Mar 16 20:12:20 volumio-ld784 volumio[779]: ---------------------------- MPD announces state update: player Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ControllerMpd::getState Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Mar 16 20:12:20 volumio-ld784 volumio[779]: ---------------------------- MPD announces state update: player Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ControllerMpd::getState Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Mar 16 20:12:20 volumio-ld784 volumio[779]: ---------------------------- MPD announces state update: player Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ControllerMpd::getState Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Mar 16 20:12:20 volumio-ld784 volumio[779]: ---------------------------- MPD announces state update: player Mar 16 20:12:20 volumio-ld784 sudo[3505]: pam_unix(sudo:session): session closed for user root Mar 16 20:12:20 volumio-ld784 volumio[779]: info: sendMpdCommand status took 7 milliseconds Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ControllerMpd::getState Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:12:20 volumio-ld784 volumio[779]: info: sendMpdCommand status took 6 milliseconds Mar 16 20:12:20 volumio-ld784 volumio[779]: info: sendMpdCommand status took 5 milliseconds Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseState Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseState Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseState Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Mar 16 20:12:20 volumio-ld784 volumio[779]: ---------------------------- MPD announces state update: player Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ControllerMpd::getState Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Mar 16 20:12:20 volumio-ld784 volumio[779]: ---------------------------- MPD announces state update: player Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ControllerMpd::getState Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:12:20 volumio-ld784 volumio[779]: info: sendMpdCommand status took 7 milliseconds Mar 16 20:12:20 volumio-ld784 volumio[779]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 16 20:12:20 volumio-ld784 volumio[779]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 16 20:12:20 volumio-ld784 volumio[779]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 16 20:12:20 volumio-ld784 volumio[779]: info: sendMpdCommand status took 4 milliseconds Mar 16 20:12:20 volumio-ld784 volumio[779]: info: sendMpdCommand status took 2 milliseconds Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseState Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseTrackInfo Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseTrackInfo Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseTrackInfo Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseState Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseState Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ControllerMpd::pushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreCommandRouter::servicePushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: [369B blob data] Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: CURRENT POSITION 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState stateService play Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState currentStatus stop Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ControllerMpd::pushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreCommandRouter::servicePushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: [369B blob data] Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: CURRENT POSITION 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState stateService play Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState currentStatus play Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Received an update from plugin. extracting info from payload Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ControllerMpd::pushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreCommandRouter::servicePushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: [369B blob data] Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: CURRENT POSITION 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState stateService play Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState currentStatus play Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Received an update from plugin. extracting info from payload Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ------------------------------ 36ms Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ------------------------------ 75ms Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ------------------------------ 74ms Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Shairport-Sync paused with USR2 Mar 16 20:12:20 volumio-ld784 volumio[779]: info: sendMpdCommand playlistinfo took 64 milliseconds Mar 16 20:12:20 volumio-ld784 volumio[779]: info: sendMpdCommand playlistinfo took 63 milliseconds Mar 16 20:12:20 volumio-ld784 volumio[779]: info: sendMpdCommand playlistinfo took 64 milliseconds Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseTrackInfo Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseTrackInfo Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseTrackInfo Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ControllerMpd::pushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreCommandRouter::servicePushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: [369B blob data] Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: CURRENT POSITION 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState stateService play Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState currentStatus play Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Received an update from plugin. extracting info from payload Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ControllerMpd::pushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreCommandRouter::servicePushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: [369B blob data] Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: CURRENT POSITION 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState stateService play Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState currentStatus play Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Received an update from plugin. extracting info from payload Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ControllerMpd::pushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreCommandRouter::servicePushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: [369B blob data] Mar 16 20:12:20 volumio-ld784 volumio[779]: verbose: CURRENT POSITION 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState stateService play Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState currentStatus play Mar 16 20:12:20 volumio-ld784 volumio[779]: info: Received an update from plugin. extracting info from payload Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 0 Mar 16 20:12:20 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ------------------------------ 172ms Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ------------------------------ 167ms Mar 16 20:12:20 volumio-ld784 volumio[779]: info: ------------------------------ 166ms Mar 16 20:12:23 volumio-ld784 volumio[779]: info: Restarting Shairport-Sync after stop Mar 16 20:12:23 volumio-ld784 volumio[779]: info: Restarting Shairport-Sync after stop Mar 16 20:12:23 volumio-ld784 sudo[3509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 16 20:12:23 volumio-ld784 sudo[3509]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 20:12:23 volumio-ld784 sudo[3511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 16 20:12:23 volumio-ld784 sudo[3511]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 20:12:23 volumio-ld784 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 16 20:12:23 volumio-ld784 shairport-sync[1092]: {"time":1742127126223,"response":"stopAirplayPlayback Success"} Mar 16 20:12:23 volumio-ld784 systemd[1]: shairport-sync.service: Succeeded. Mar 16 20:12:23 volumio-ld784 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 16 20:12:23 volumio-ld784 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 16 20:12:23 volumio-ld784 sudo[3509]: pam_unix(sudo:session): session closed for user root Mar 16 20:12:23 volumio-ld784 volumio[779]: info: Shairport-Sync retarted Mar 16 20:12:23 volumio-ld784 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 16 20:12:23 volumio-ld784 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 16 20:12:23 volumio-ld784 systemd[1]: shairport-sync.service: Succeeded. Mar 16 20:12:23 volumio-ld784 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 16 20:12:23 volumio-ld784 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 16 20:12:23 volumio-ld784 sudo[3511]: pam_unix(sudo:session): session closed for user root Mar 16 20:12:23 volumio-ld784 volumio[779]: info: Shairport-Sync retarted Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioNext Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::next Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::stop Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::stPlaybackTimer Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::updateTrackBlock Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrackBlock Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 0 Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::serviceStop Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 0 Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::serviceStop Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ControllerMpd::stop Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Mar 16 20:12:25 volumio-ld784 volumio[779]: ---------------------------- MPD announces state update: player Mar 16 20:12:25 volumio-ld784 volumio[779]: info: sendMpdCommand stop took 74 milliseconds Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ControllerMpd::getState Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::play index undefined Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::startPlaybackTimer Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::clearAddPlayTracks USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/02 顺流逆流.wav Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::updateTrackBlock Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrackBlock Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Mar 16 20:12:25 volumio-ld784 volumio[779]: ---------------------------- MPD announces state update: player Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ControllerMpd::getState Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Mar 16 20:12:25 volumio-ld784 volumio[779]: ---------------------------- MPD announces state update: player Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ControllerMpd::getState Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:12:25 volumio-ld784 volumio[779]: info: sendMpdCommand status took 17 milliseconds Mar 16 20:12:25 volumio-ld784 volumio[779]: info: sendMpdCommand stop took 9 milliseconds Mar 16 20:12:25 volumio-ld784 volumio[779]: info: sendMpdCommand status took 8 milliseconds Mar 16 20:12:25 volumio-ld784 volumio[779]: info: sendMpdCommand status took 6 milliseconds Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseState Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand clear Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseState Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseState Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 20:12:25 volumio-ld784 volumio[779]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Mar 16 20:12:25 volumio-ld784 volumio[779]: ---------------------------- MPD announces system playlist update Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Ignoring MPD Status Update Mar 16 20:12:25 volumio-ld784 volumio[779]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 16 20:12:25 volumio-ld784 volumio[779]: info: sendMpdCommand clear took 17 milliseconds Mar 16 20:12:25 volumio-ld784 volumio[779]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 16 20:12:25 volumio-ld784 volumio[779]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseTrackInfo Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand add "USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/02 顺流逆流.wav" Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseTrackInfo Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseTrackInfo Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ControllerMpd::pushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::servicePushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: [366B blob data] Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: CURRENT POSITION 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState stateService stop Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState currentStatus stop Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: No code Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:25 volumio-ld784 volumio[779]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 16 20:12:25 volumio-ld784 volumio[779]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ------------------------------ 107ms Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ------------------------------ 92ms Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ------------------------------ 91ms Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Mar 16 20:12:25 volumio-ld784 volumio[779]: ---------------------------- MPD announces system playlist update Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Ignoring MPD Status Update Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Mar 16 20:12:25 volumio-ld784 volumio[779]: ---------------------------- MPD announces system playlist update Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Ignoring MPD Status Update Mar 16 20:12:25 volumio-ld784 volumio[779]: error: updateQueue error: null Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Mar 16 20:12:25 volumio-ld784 volumio[779]: ---------------------------- MPD announces system playlist update Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Ignoring MPD Status Update Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ------------------------------ 73ms Mar 16 20:12:25 volumio-ld784 volumio[779]: info: sendMpdCommand add "USB/时钟资料/王嘉文（有谁共鸣）/男人四十③/02 顺流逆流.wav" took 67 milliseconds Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ------------------------------ 7ms Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ------------------------------ 6ms Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand play Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Mar 16 20:12:25 volumio-ld784 volumio[779]: ---------------------------- MPD announces system playlist update Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Ignoring MPD Status Update Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Mar 16 20:12:25 volumio-ld784 volumio[779]: ---------------------------- MPD announces system playlist update Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Ignoring MPD Status Update Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Mar 16 20:12:25 volumio-ld784 volumio[779]: ---------------------------- MPD announces state update: player Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ------------------------------ 37ms Mar 16 20:12:25 volumio-ld784 volumio[779]: info: sendMpdCommand play took 35 milliseconds Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ------------------------------ 34ms Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ------------------------------ 33ms Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ControllerMpd::getState Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Mar 16 20:12:25 volumio-ld784 volumio[779]: ---------------------------- MPD announces state update: player Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ControllerMpd::getState Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Mar 16 20:12:25 volumio-ld784 volumio[779]: ---------------------------- MPD announces state update: player Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ControllerMpd::getState Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Mar 16 20:12:25 volumio-ld784 volumio[779]: ---------------------------- MPD announces state update: player Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ControllerMpd::getState Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:12:25 volumio-ld784 volumio[779]: info: sendMpdCommand status took 10 milliseconds Mar 16 20:12:25 volumio-ld784 volumio[779]: info: sendMpdCommand status took 9 milliseconds Mar 16 20:12:25 volumio-ld784 volumio[779]: info: sendMpdCommand status took 7 milliseconds Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseState Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseState Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseState Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Mar 16 20:12:25 volumio-ld784 volumio[779]: ---------------------------- MPD announces state update: player Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ControllerMpd::getState Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Mar 16 20:12:25 volumio-ld784 volumio[779]: ---------------------------- MPD announces state update: player Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ControllerMpd::getState Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand status Mar 16 20:12:25 volumio-ld784 volumio[779]: info: sendMpdCommand status took 7 milliseconds Mar 16 20:12:25 volumio-ld784 volumio[779]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 16 20:12:25 volumio-ld784 volumio[779]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 16 20:12:25 volumio-ld784 volumio[779]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 16 20:12:25 volumio-ld784 volumio[779]: info: sendMpdCommand status took 3 milliseconds Mar 16 20:12:25 volumio-ld784 volumio[779]: info: sendMpdCommand status took 2 milliseconds Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseState Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseTrackInfo Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseTrackInfo Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseTrackInfo Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseState Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseState Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ControllerMpd::pushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::servicePushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: [379B blob data] Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: CURRENT POSITION 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState stateService play Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState currentStatus stop Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ControllerMpd::pushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::servicePushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: [379B blob data] Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: CURRENT POSITION 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState stateService play Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState currentStatus play Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Received an update from plugin. extracting info from payload Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ControllerMpd::pushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::servicePushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: [379B blob data] Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: CURRENT POSITION 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState stateService play Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState currentStatus play Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Received an update from plugin. extracting info from payload Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ------------------------------ 38ms Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ------------------------------ 67ms Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ------------------------------ 66ms Mar 16 20:12:25 volumio-ld784 volumio[779]: info: sendMpdCommand playlistinfo took 57 milliseconds Mar 16 20:12:25 volumio-ld784 volumio[779]: info: sendMpdCommand playlistinfo took 56 milliseconds Mar 16 20:12:25 volumio-ld784 volumio[779]: info: sendMpdCommand playlistinfo took 57 milliseconds Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseTrackInfo Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseTrackInfo Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: ControllerMpd::parseTrackInfo Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ControllerMpd::pushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::servicePushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: [379B blob data] Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: CURRENT POSITION 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState stateService play Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState currentStatus play Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Received an update from plugin. extracting info from payload Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ControllerMpd::pushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::servicePushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: [379B blob data] Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: CURRENT POSITION 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState stateService play Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState currentStatus play Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Received an update from plugin. extracting info from payload Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ControllerMpd::pushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::servicePushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: [379B blob data] Mar 16 20:12:25 volumio-ld784 volumio[779]: verbose: CURRENT POSITION 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState stateService play Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::syncState currentStatus play Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Received an update from plugin. extracting info from payload Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreStateMachine::pushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 1 Mar 16 20:12:25 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioPushState Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ------------------------------ 142ms Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ------------------------------ 137ms Mar 16 20:12:25 volumio-ld784 volumio[779]: info: ------------------------------ 136ms Mar 16 20:12:25 volumio-ld784 volumio[779]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 16 20:12:25 volumio-ld784 volumio[779]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 16 20:12:25 volumio-ld784 volumio[779]: info: Retrying Login Due to Network Error Mar 16 20:12:30 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 16 20:12:30 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 16 20:12:30 volumio-ld784 volumio[779]: info: Discovery: Getting this device information Mar 16 20:12:30 volumio-ld784 volumio[779]: info: CoreCommandRouter::volumioGetState Mar 16 20:12:30 volumio-ld784 volumio[779]: info: CorePlayQueue::getTrack 1 Mar 16 20:12:30 volumio-ld784 volumio[779]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 16 20:12:33 volumio-ld784 ntpd[741]: 162.159.200.1 local addr 192.168.1.235 -> Mar 16 20:12:35 volumio-ld784 volumio[779]: info: MyVolumio login type: Token Mar 16 20:13:05 volumio-ld784 volumio[779]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 16 20:13:05 volumio-ld784 volumio[779]: info: Retrying Login Due to Network Error Mar 16 20:13:12 volumio-ld784 volumio[779]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 20:13:12 volumio-ld784 volumio[779]: Error: connect ETIMEDOUT 128.242.245.43:80 Mar 16 20:13:12 volumio-ld784 volumio[779]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Mar 16 20:13:12 volumio-ld784 volumio[779]: errno: -110, Mar 16 20:13:12 volumio-ld784 volumio[779]: code: 'ETIMEDOUT', Mar 16 20:13:12 volumio-ld784 volumio[779]: syscall: 'connect', Mar 16 20:13:12 volumio-ld784 volumio[779]: address: '128.242.245.43', Mar 16 20:13:12 volumio-ld784 volumio[779]: port: 80 Mar 16 20:13:12 volumio-ld784 volumio[779]: } Mar 16 20:13:12 volumio-ld784 volumio[779]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 20:13:12 volumio-ld784 sudo[3686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-16 20:12 Mar 16 20:13:12 volumio-ld784 sudo[3686]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"