-- Logs begin at Sun 2025-03-16 06:40:32 UTC, end at Sun 2025-03-16 07:06:33 UTC. -- Mar 16 07:05:36 volumio sudo[8050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 16 07:05:36 volumio sudo[8050]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 07:05:36 volumio sudo[8050]: pam_unix(sudo:session): session closed for user root Mar 16 07:05:36 volumio sudo[8052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 16 07:05:36 volumio sudo[8052]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 07:05:36 volumio sudo[8052]: pam_unix(sudo:session): session closed for user root Mar 16 07:05:36 volumio volumio[1057]: verbose: New Socket.io Connection to 192.168.18.24 from 192.168.18.29 UA: Mozilla/5.0 (Linux; Android 10; LIO-AN00; HMSCore 6.15.0.302) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/114.0.5735.196 HuaweiBrowser/16.0.1.302 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 16 07:05:36 volumio volumio[1057]: info: CoreCommandRouter::volumioGetVisibleSources Mar 16 07:05:36 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 07:05:36 volumio volumio[1057]: info: CoreCommandRouter::volumioGetState Mar 16 07:05:36 volumio volumio[1057]: info: CorePlayQueue::getTrack 4 Mar 16 07:05:36 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 16 07:05:36 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 16 07:05:36 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 16 07:05:36 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 16 07:05:37 volumio sudo[8056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 16 07:05:37 volumio sudo[8056]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 07:05:37 volumio sudo[8056]: pam_unix(sudo:session): session closed for user root Mar 16 07:05:37 volumio sudo[8059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 16 07:05:37 volumio sudo[8059]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 07:05:37 volumio sudo[8059]: pam_unix(sudo:session): session closed for user root Mar 16 07:05:37 volumio volumio[1057]: verbose: New Socket.io Connection to 192.168.18.24 from 192.168.18.29 UA: Mozilla/5.0 (Linux; Android 10; LIO-AN00; HMSCore 6.15.0.302) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/114.0.5735.196 HuaweiBrowser/16.0.1.302 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 16 07:05:37 volumio volumio[1057]: info: CoreCommandRouter::volumioGetState Mar 16 07:05:37 volumio volumio[1057]: info: CorePlayQueue::getTrack 4 Mar 16 07:05:37 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 16 07:05:37 volumio volumio[1057]: info: Listing playlists Mar 16 07:05:37 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 16 07:05:37 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 16 07:05:37 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 16 07:05:37 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 16 07:05:39 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 16 07:05:39 volumio volumio[1057]: info: Received Get System Info Mar 16 07:05:39 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 16 07:05:39 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 16 07:05:39 volumio volumio[1057]: info: Discovery: Getting this device information Mar 16 07:05:39 volumio volumio[1057]: info: CoreCommandRouter::volumioGetState Mar 16 07:05:39 volumio volumio[1057]: info: CorePlayQueue::getTrack 4 Mar 16 07:05:39 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 16 07:05:39 volumio volumio[1057]: info: CoreCommandRouter::volumioNext Mar 16 07:05:39 volumio volumio[1057]: info: CoreStateMachine::next Mar 16 07:05:39 volumio volumio[1057]: info: CoreStateMachine::stop Mar 16 07:05:39 volumio volumio[1057]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 07:05:39 volumio volumio[1057]: info: CoreStateMachine::stPlaybackTimer Mar 16 07:05:39 volumio volumio[1057]: info: CoreStateMachine::updateTrackBlock Mar 16 07:05:39 volumio volumio[1057]: info: CorePlayQueue::getTrackBlock Mar 16 07:05:39 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:05:39 volumio volumio[1057]: info: CorePlayQueue::getTrack 4 Mar 16 07:05:39 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:05:39 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:05:39 volumio volumio[1057]: info: CoreStateMachine::serviceStop Mar 16 07:05:39 volumio volumio[1057]: info: CorePlayQueue::getTrack 4 Mar 16 07:05:39 volumio volumio[1057]: info: CoreCommandRouter::serviceStop Mar 16 07:05:39 volumio volumio[1057]: info: ControllerMpd::stop Mar 16 07:05:39 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 07:05:39 volumio volumio[1057]: info: Mar 16 07:05:39 volumio volumio[1057]: ---------------------------- MPD announces state update: player Mar 16 07:05:39 volumio volumio[1057]: info: sendMpdCommand stop took 11 milliseconds Mar 16 07:05:39 volumio volumio[1057]: info: ControllerMpd::getState Mar 16 07:05:39 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Mar 16 07:05:39 volumio volumio[1057]: info: CoreStateMachine::play index undefined Mar 16 07:05:39 volumio volumio[1057]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 07:05:39 volumio volumio[1057]: info: CorePlayQueue::getTrack 5 Mar 16 07:05:39 volumio volumio[1057]: info: CoreStateMachine::startPlaybackTimer Mar 16 07:05:39 volumio volumio[1057]: info: CorePlayQueue::getTrack 5 Mar 16 07:05:39 volumio volumio[1057]: verbose: ControllerMpd::clearAddPlayTracks USB/F00D-AFDC/Answering The Door.mp3 Mar 16 07:05:39 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 07:05:39 volumio volumio[1057]: info: CoreStateMachine::updateTrackBlock Mar 16 07:05:39 volumio volumio[1057]: info: CorePlayQueue::getTrackBlock Mar 16 07:05:39 volumio volumio[1057]: info: sendMpdCommand status took 4 milliseconds Mar 16 07:05:39 volumio volumio[1057]: info: sendMpdCommand stop took 2 milliseconds Mar 16 07:05:39 volumio volumio[1057]: verbose: ControllerMpd::parseState Mar 16 07:05:39 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 07:05:39 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand clear Mar 16 07:05:39 volumio volumio[1057]: info: Mar 16 07:05:39 volumio volumio[1057]: ---------------------------- MPD announces system playlist update Mar 16 07:05:39 volumio volumio[1057]: info: Ignoring MPD Status Update Mar 16 07:05:39 volumio volumio[1057]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 16 07:05:39 volumio volumio[1057]: info: sendMpdCommand clear took 2 milliseconds Mar 16 07:05:39 volumio volumio[1057]: verbose: ControllerMpd::parseTrackInfo Mar 16 07:05:39 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand add "USB/F00D-AFDC/Answering The Door.mp3" Mar 16 07:05:39 volumio volumio[1057]: info: ControllerMpd::pushState Mar 16 07:05:39 volumio volumio[1057]: info: CoreCommandRouter::servicePushState Mar 16 07:05:39 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:05:39 volumio volumio[1057]: info: CorePlayQueue::getTrack 5 Mar 16 07:05:39 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:05:39 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:05:39 volumio volumio[1057]: info: CorePlayQueue::getTrack 5 Mar 16 07:05:39 volumio volumio[1057]: 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":"Angel - 天使 - 电影 多情 主题曲 莎拉 克劳克兰.mp3","artist":null,"album":null,"uri":"USB/F00D-AFDC/Angel - 天使 - 电影 多情 主题曲 莎拉 克劳克兰.mp3","trackType":"mp3"} Mar 16 07:05:39 volumio volumio[1057]: verbose: CURRENT POSITION 5 Mar 16 07:05:39 volumio volumio[1057]: info: CoreStateMachine::syncState stateService stop Mar 16 07:05:39 volumio volumio[1057]: info: CoreStateMachine::syncState currentStatus stop Mar 16 07:05:39 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:05:39 volumio volumio[1057]: info: CorePlayQueue::getTrack 5 Mar 16 07:05:39 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:05:39 volumio volumio[1057]: info: No code Mar 16 07:05:39 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:05:39 volumio volumio[1057]: info: CorePlayQueue::getTrack 5 Mar 16 07:05:39 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:05:39 volumio volumio[1057]: info: ------------------------------ 52ms Mar 16 07:05:39 volumio volumio[1057]: error: updateQueue error: null Mar 16 07:05:39 volumio volumio[1057]: info: Mar 16 07:05:39 volumio volumio[1057]: ---------------------------- MPD announces system playlist update Mar 16 07:05:39 volumio volumio[1057]: info: Ignoring MPD Status Update Mar 16 07:05:39 volumio volumio[1057]: info: ------------------------------ 42ms Mar 16 07:05:39 volumio volumio[1057]: info: sendMpdCommand add "USB/F00D-AFDC/Answering The Door.mp3" took 41 milliseconds Mar 16 07:05:39 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand play Mar 16 07:05:39 volumio volumio[1057]: info: ------------------------------ 8ms Mar 16 07:05:39 volumio volumio[1057]: info: Mar 16 07:05:39 volumio volumio[1057]: ---------------------------- MPD announces state update: player Mar 16 07:05:39 volumio volumio[1057]: info: sendMpdCommand play took 15 milliseconds Mar 16 07:05:39 volumio volumio[1057]: info: ControllerMpd::getState Mar 16 07:05:39 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Mar 16 07:05:39 volumio volumio[1057]: info: Mar 16 07:05:39 volumio volumio[1057]: ---------------------------- MPD announces state update: player Mar 16 07:05:39 volumio volumio[1057]: info: ControllerMpd::getState Mar 16 07:05:39 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Mar 16 07:05:39 volumio volumio[1057]: info: sendMpdCommand status took 3 milliseconds Mar 16 07:05:39 volumio volumio[1057]: verbose: ControllerMpd::parseState Mar 16 07:05:39 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 07:05:39 volumio volumio[1057]: info: sendMpdCommand status took 57 milliseconds Mar 16 07:05:39 volumio volumio[1057]: info: sendMpdCommand playlistinfo took 54 milliseconds Mar 16 07:05:39 volumio volumio[1057]: verbose: ControllerMpd::parseState Mar 16 07:05:39 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 07:05:39 volumio volumio[1057]: verbose: ControllerMpd::parseTrackInfo Mar 16 07:05:39 volumio volumio[1057]: info: ControllerMpd::pushState Mar 16 07:05:39 volumio volumio[1057]: info: CoreCommandRouter::servicePushState Mar 16 07:05:39 volumio volumio[1057]: info: CorePlayQueue::getTrack 5 Mar 16 07:05:39 volumio volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":248,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Answering The Door.mp3","artist":"Rachael Yamagata","album":null,"uri":"USB/F00D-AFDC/Answering The Door.mp3","trackType":"mp3"} Mar 16 07:05:39 volumio volumio[1057]: verbose: CURRENT POSITION 5 Mar 16 07:05:39 volumio volumio[1057]: info: CoreStateMachine::syncState stateService play Mar 16 07:05:39 volumio volumio[1057]: info: CoreStateMachine::syncState currentStatus stop Mar 16 07:05:39 volumio volumio[1057]: info: ------------------------------ 81ms Mar 16 07:05:39 volumio volumio[1057]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 16 07:05:39 volumio volumio[1057]: verbose: ControllerMpd::parseTrackInfo Mar 16 07:05:39 volumio volumio[1057]: info: ControllerMpd::pushState Mar 16 07:05:39 volumio volumio[1057]: info: CoreCommandRouter::servicePushState Mar 16 07:05:39 volumio volumio[1057]: info: CorePlayQueue::getTrack 5 Mar 16 07:05:39 volumio volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":248,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Answering The Door.mp3","artist":"Rachael Yamagata","album":null,"uri":"USB/F00D-AFDC/Answering The Door.mp3","trackType":"mp3"} Mar 16 07:05:39 volumio volumio[1057]: verbose: CURRENT POSITION 5 Mar 16 07:05:39 volumio volumio[1057]: info: CoreStateMachine::syncState stateService play Mar 16 07:05:39 volumio volumio[1057]: info: CoreStateMachine::syncState currentStatus play Mar 16 07:05:39 volumio volumio[1057]: info: Received an update from plugin. extracting info from payload Mar 16 07:05:39 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:05:39 volumio volumio[1057]: info: CorePlayQueue::getTrack 5 Mar 16 07:05:39 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:05:39 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:05:39 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:05:39 volumio volumio[1057]: info: CorePlayQueue::getTrack 5 Mar 16 07:05:39 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:05:39 volumio volumio[1057]: info: ------------------------------ 115ms Mar 16 07:05:42 volumio ntpd[5788]: 81.16.177.123 local addr 192.168.18.24 -> Mar 16 07:05:43 volumio ntpd[5788]: 95.111.202.5 local addr 192.168.18.24 -> Mar 16 07:05:53 volumio volumio[1057]: info: CoreCommandRouter::volumioNext Mar 16 07:05:53 volumio volumio[1057]: info: CoreStateMachine::next Mar 16 07:05:53 volumio volumio[1057]: info: CoreStateMachine::stop Mar 16 07:05:53 volumio volumio[1057]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 07:05:53 volumio volumio[1057]: info: CoreStateMachine::stPlaybackTimer Mar 16 07:05:53 volumio volumio[1057]: info: CoreStateMachine::updateTrackBlock Mar 16 07:05:53 volumio volumio[1057]: info: CorePlayQueue::getTrackBlock Mar 16 07:05:53 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:05:53 volumio volumio[1057]: info: CorePlayQueue::getTrack 5 Mar 16 07:05:53 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:05:53 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:05:53 volumio volumio[1057]: info: CoreStateMachine::serviceStop Mar 16 07:05:53 volumio volumio[1057]: info: CorePlayQueue::getTrack 5 Mar 16 07:05:53 volumio volumio[1057]: info: CoreCommandRouter::serviceStop Mar 16 07:05:53 volumio volumio[1057]: info: ControllerMpd::stop Mar 16 07:05:53 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 07:05:53 volumio volumio[1057]: info: Mar 16 07:05:53 volumio volumio[1057]: ---------------------------- MPD announces state update: player Mar 16 07:05:53 volumio volumio[1057]: info: sendMpdCommand stop took 10 milliseconds Mar 16 07:05:53 volumio volumio[1057]: info: ControllerMpd::getState Mar 16 07:05:53 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Mar 16 07:05:53 volumio volumio[1057]: info: CoreStateMachine::play index undefined Mar 16 07:05:53 volumio volumio[1057]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 07:05:53 volumio volumio[1057]: info: CorePlayQueue::getTrack 6 Mar 16 07:05:53 volumio volumio[1057]: info: CoreStateMachine::startPlaybackTimer Mar 16 07:05:53 volumio volumio[1057]: info: CorePlayQueue::getTrack 6 Mar 16 07:05:53 volumio volumio[1057]: verbose: ControllerMpd::clearAddPlayTracks USB/F00D-AFDC/Asher Monroe - Here With You.mp3 Mar 16 07:05:53 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 07:05:53 volumio volumio[1057]: info: CoreStateMachine::updateTrackBlock Mar 16 07:05:53 volumio volumio[1057]: info: CorePlayQueue::getTrackBlock Mar 16 07:05:53 volumio volumio[1057]: info: sendMpdCommand status took 3 milliseconds Mar 16 07:05:53 volumio volumio[1057]: info: sendMpdCommand stop took 1 milliseconds Mar 16 07:05:53 volumio volumio[1057]: verbose: ControllerMpd::parseState Mar 16 07:05:53 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 07:05:53 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand clear Mar 16 07:05:53 volumio volumio[1057]: info: Mar 16 07:05:53 volumio volumio[1057]: ---------------------------- MPD announces system playlist update Mar 16 07:05:53 volumio volumio[1057]: info: Ignoring MPD Status Update Mar 16 07:05:53 volumio volumio[1057]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 16 07:05:53 volumio volumio[1057]: info: sendMpdCommand clear took 2 milliseconds Mar 16 07:05:53 volumio volumio[1057]: verbose: ControllerMpd::parseTrackInfo Mar 16 07:05:53 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand add "USB/F00D-AFDC/Asher Monroe - Here With You.mp3" Mar 16 07:05:53 volumio volumio[1057]: info: ControllerMpd::pushState Mar 16 07:05:53 volumio volumio[1057]: info: CoreCommandRouter::servicePushState Mar 16 07:05:53 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:05:53 volumio volumio[1057]: info: CorePlayQueue::getTrack 6 Mar 16 07:05:53 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:05:53 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:05:53 volumio volumio[1057]: info: CorePlayQueue::getTrack 6 Mar 16 07:05:53 volumio volumio[1057]: 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":"Answering The Door.mp3","artist":"Rachael Yamagata","album":null,"uri":"USB/F00D-AFDC/Answering The Door.mp3","trackType":"mp3"} Mar 16 07:05:53 volumio volumio[1057]: verbose: CURRENT POSITION 6 Mar 16 07:05:53 volumio volumio[1057]: info: CoreStateMachine::syncState stateService stop Mar 16 07:05:53 volumio volumio[1057]: info: CoreStateMachine::syncState currentStatus stop Mar 16 07:05:53 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:05:53 volumio volumio[1057]: info: CorePlayQueue::getTrack 6 Mar 16 07:05:53 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:05:53 volumio volumio[1057]: info: No code Mar 16 07:05:53 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:05:53 volumio volumio[1057]: info: CorePlayQueue::getTrack 6 Mar 16 07:05:53 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:05:53 volumio volumio[1057]: info: ------------------------------ 37ms Mar 16 07:05:53 volumio volumio[1057]: error: updateQueue error: null Mar 16 07:05:53 volumio volumio[1057]: info: Mar 16 07:05:53 volumio volumio[1057]: ---------------------------- MPD announces system playlist update Mar 16 07:05:53 volumio volumio[1057]: info: Ignoring MPD Status Update Mar 16 07:05:53 volumio volumio[1057]: info: ------------------------------ 37ms Mar 16 07:05:53 volumio volumio[1057]: info: sendMpdCommand add "USB/F00D-AFDC/Asher Monroe - Here With You.mp3" took 36 milliseconds Mar 16 07:05:53 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand play Mar 16 07:05:53 volumio volumio[1057]: info: ------------------------------ 9ms Mar 16 07:05:53 volumio volumio[1057]: info: Mar 16 07:05:53 volumio volumio[1057]: ---------------------------- MPD announces state update: player Mar 16 07:05:53 volumio volumio[1057]: info: sendMpdCommand play took 8 milliseconds Mar 16 07:05:53 volumio volumio[1057]: info: ControllerMpd::getState Mar 16 07:05:53 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Mar 16 07:05:53 volumio volumio[1057]: info: Mar 16 07:05:53 volumio volumio[1057]: ---------------------------- MPD announces state update: player Mar 16 07:05:53 volumio volumio[1057]: info: ControllerMpd::getState Mar 16 07:05:53 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Mar 16 07:05:53 volumio volumio[1057]: info: sendMpdCommand status took 27 milliseconds Mar 16 07:05:53 volumio volumio[1057]: verbose: ControllerMpd::parseState Mar 16 07:05:53 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 07:05:53 volumio volumio[1057]: info: sendMpdCommand status took 4 milliseconds Mar 16 07:05:53 volumio volumio[1057]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 16 07:05:53 volumio volumio[1057]: verbose: ControllerMpd::parseState Mar 16 07:05:53 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 07:05:53 volumio volumio[1057]: verbose: ControllerMpd::parseTrackInfo Mar 16 07:05:53 volumio volumio[1057]: info: ControllerMpd::pushState Mar 16 07:05:53 volumio volumio[1057]: info: CoreCommandRouter::servicePushState Mar 16 07:05:53 volumio volumio[1057]: info: CorePlayQueue::getTrack 6 Mar 16 07:05:53 volumio volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Here With You","artist":"Asher Book","album":null,"uri":"USB/F00D-AFDC/Asher Monroe - Here With You.mp3","trackType":"mp3"} Mar 16 07:05:53 volumio volumio[1057]: verbose: CURRENT POSITION 6 Mar 16 07:05:53 volumio volumio[1057]: info: CoreStateMachine::syncState stateService play Mar 16 07:05:53 volumio volumio[1057]: info: CoreStateMachine::syncState currentStatus stop Mar 16 07:05:53 volumio volumio[1057]: info: ------------------------------ 36ms Mar 16 07:05:53 volumio volumio[1057]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 16 07:05:53 volumio volumio[1057]: verbose: ControllerMpd::parseTrackInfo Mar 16 07:05:53 volumio volumio[1057]: info: ControllerMpd::pushState Mar 16 07:05:53 volumio volumio[1057]: info: CoreCommandRouter::servicePushState Mar 16 07:05:53 volumio volumio[1057]: info: CorePlayQueue::getTrack 6 Mar 16 07:05:53 volumio volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":226,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Here With You","artist":"Asher Book","album":null,"uri":"USB/F00D-AFDC/Asher Monroe - Here With You.mp3","trackType":"mp3"} Mar 16 07:05:53 volumio volumio[1057]: verbose: CURRENT POSITION 6 Mar 16 07:05:53 volumio volumio[1057]: info: CoreStateMachine::syncState stateService play Mar 16 07:05:53 volumio volumio[1057]: info: CoreStateMachine::syncState currentStatus play Mar 16 07:05:53 volumio volumio[1057]: info: Received an update from plugin. extracting info from payload Mar 16 07:05:53 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:05:53 volumio volumio[1057]: info: CorePlayQueue::getTrack 6 Mar 16 07:05:53 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:05:53 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:05:53 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:05:53 volumio volumio[1057]: info: CorePlayQueue::getTrack 6 Mar 16 07:05:53 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:05:53 volumio volumio[1057]: info: ------------------------------ 43ms Mar 16 07:06:02 volumio volumio[1057]: info: CoreCommandRouter::volumioNext Mar 16 07:06:02 volumio volumio[1057]: info: CoreStateMachine::next Mar 16 07:06:02 volumio volumio[1057]: info: CoreStateMachine::stop Mar 16 07:06:02 volumio volumio[1057]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 07:06:02 volumio volumio[1057]: info: CoreStateMachine::stPlaybackTimer Mar 16 07:06:02 volumio volumio[1057]: info: CoreStateMachine::updateTrackBlock Mar 16 07:06:02 volumio volumio[1057]: info: CorePlayQueue::getTrackBlock Mar 16 07:06:02 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:02 volumio volumio[1057]: info: CorePlayQueue::getTrack 6 Mar 16 07:06:02 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:06:02 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:02 volumio volumio[1057]: info: CoreStateMachine::serviceStop Mar 16 07:06:02 volumio volumio[1057]: info: CorePlayQueue::getTrack 6 Mar 16 07:06:02 volumio volumio[1057]: info: CoreCommandRouter::serviceStop Mar 16 07:06:02 volumio volumio[1057]: info: ControllerMpd::stop Mar 16 07:06:02 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 07:06:02 volumio volumio[1057]: info: Mar 16 07:06:02 volumio volumio[1057]: ---------------------------- MPD announces state update: player Mar 16 07:06:02 volumio volumio[1057]: info: sendMpdCommand stop took 7 milliseconds Mar 16 07:06:02 volumio volumio[1057]: info: ControllerMpd::getState Mar 16 07:06:02 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Mar 16 07:06:02 volumio volumio[1057]: info: CoreStateMachine::play index undefined Mar 16 07:06:02 volumio volumio[1057]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 07:06:02 volumio volumio[1057]: info: CorePlayQueue::getTrack 7 Mar 16 07:06:02 volumio volumio[1057]: info: CoreStateMachine::startPlaybackTimer Mar 16 07:06:02 volumio volumio[1057]: info: CorePlayQueue::getTrack 7 Mar 16 07:06:02 volumio volumio[1057]: verbose: ControllerMpd::clearAddPlayTracks USB/F00D-AFDC/aziatix - go.mp3 Mar 16 07:06:02 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 07:06:02 volumio volumio[1057]: info: CoreStateMachine::updateTrackBlock Mar 16 07:06:02 volumio volumio[1057]: info: CorePlayQueue::getTrackBlock Mar 16 07:06:02 volumio volumio[1057]: info: sendMpdCommand status took 2 milliseconds Mar 16 07:06:02 volumio volumio[1057]: verbose: ControllerMpd::parseState Mar 16 07:06:02 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 07:06:02 volumio volumio[1057]: info: sendMpdCommand stop took 2 milliseconds Mar 16 07:06:02 volumio volumio[1057]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 16 07:06:02 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand clear Mar 16 07:06:02 volumio volumio[1057]: verbose: ControllerMpd::parseTrackInfo Mar 16 07:06:02 volumio volumio[1057]: info: ControllerMpd::pushState Mar 16 07:06:02 volumio volumio[1057]: info: CoreCommandRouter::servicePushState Mar 16 07:06:02 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:02 volumio volumio[1057]: info: CorePlayQueue::getTrack 7 Mar 16 07:06:02 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:06:02 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:02 volumio volumio[1057]: info: CorePlayQueue::getTrack 7 Mar 16 07:06:02 volumio volumio[1057]: 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":"Here With You","artist":"Asher Book","album":null,"uri":"USB/F00D-AFDC/Asher Monroe - Here With You.mp3","trackType":"mp3"} Mar 16 07:06:02 volumio volumio[1057]: verbose: CURRENT POSITION 7 Mar 16 07:06:02 volumio volumio[1057]: info: CoreStateMachine::syncState stateService stop Mar 16 07:06:02 volumio volumio[1057]: info: CoreStateMachine::syncState currentStatus stop Mar 16 07:06:02 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:02 volumio volumio[1057]: info: CorePlayQueue::getTrack 7 Mar 16 07:06:02 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:02 volumio volumio[1057]: info: No code Mar 16 07:06:02 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:02 volumio volumio[1057]: info: CorePlayQueue::getTrack 7 Mar 16 07:06:02 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:02 volumio volumio[1057]: info: ------------------------------ 35ms Mar 16 07:06:02 volumio volumio[1057]: info: Mar 16 07:06:02 volumio volumio[1057]: ---------------------------- MPD announces system playlist update Mar 16 07:06:02 volumio volumio[1057]: info: Ignoring MPD Status Update Mar 16 07:06:02 volumio volumio[1057]: info: sendMpdCommand clear took 34 milliseconds Mar 16 07:06:02 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand add "USB/F00D-AFDC/aziatix - go.mp3" Mar 16 07:06:02 volumio volumio[1057]: error: updateQueue error: null Mar 16 07:06:02 volumio volumio[1057]: info: Mar 16 07:06:02 volumio volumio[1057]: ---------------------------- MPD announces system playlist update Mar 16 07:06:02 volumio volumio[1057]: info: Ignoring MPD Status Update Mar 16 07:06:02 volumio volumio[1057]: info: ------------------------------ 8ms Mar 16 07:06:02 volumio volumio[1057]: info: sendMpdCommand add "USB/F00D-AFDC/aziatix - go.mp3" took 5 milliseconds Mar 16 07:06:02 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand play Mar 16 07:06:02 volumio volumio[1057]: info: ------------------------------ 3ms Mar 16 07:06:02 volumio volumio[1057]: info: Mar 16 07:06:02 volumio volumio[1057]: ---------------------------- MPD announces state update: player Mar 16 07:06:02 volumio volumio[1057]: info: sendMpdCommand play took 11 milliseconds Mar 16 07:06:02 volumio volumio[1057]: info: ControllerMpd::getState Mar 16 07:06:02 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Mar 16 07:06:02 volumio volumio[1057]: info: Mar 16 07:06:02 volumio volumio[1057]: ---------------------------- MPD announces state update: player Mar 16 07:06:02 volumio volumio[1057]: info: ControllerMpd::getState Mar 16 07:06:02 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Mar 16 07:06:02 volumio volumio[1057]: info: sendMpdCommand status took 3 milliseconds Mar 16 07:06:02 volumio volumio[1057]: verbose: ControllerMpd::parseState Mar 16 07:06:02 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 07:06:02 volumio volumio[1057]: info: sendMpdCommand status took 5 milliseconds Mar 16 07:06:02 volumio volumio[1057]: verbose: ControllerMpd::parseState Mar 16 07:06:02 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 07:06:02 volumio volumio[1057]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 16 07:06:02 volumio volumio[1057]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 16 07:06:02 volumio volumio[1057]: verbose: ControllerMpd::parseTrackInfo Mar 16 07:06:02 volumio volumio[1057]: verbose: ControllerMpd::parseTrackInfo Mar 16 07:06:02 volumio volumio[1057]: info: ControllerMpd::pushState Mar 16 07:06:02 volumio volumio[1057]: info: CoreCommandRouter::servicePushState Mar 16 07:06:02 volumio volumio[1057]: info: CorePlayQueue::getTrack 7 Mar 16 07:06:02 volumio volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"aziatix - go.mp3","artist":null,"album":null,"uri":"USB/F00D-AFDC/aziatix - go.mp3","trackType":"mp3"} Mar 16 07:06:02 volumio volumio[1057]: verbose: CURRENT POSITION 7 Mar 16 07:06:02 volumio volumio[1057]: info: CoreStateMachine::syncState stateService play Mar 16 07:06:02 volumio volumio[1057]: info: CoreStateMachine::syncState currentStatus stop Mar 16 07:06:02 volumio volumio[1057]: info: ControllerMpd::pushState Mar 16 07:06:02 volumio volumio[1057]: info: CoreCommandRouter::servicePushState Mar 16 07:06:02 volumio volumio[1057]: info: CorePlayQueue::getTrack 7 Mar 16 07:06:02 volumio volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"aziatix - go.mp3","artist":null,"album":null,"uri":"USB/F00D-AFDC/aziatix - go.mp3","trackType":"mp3"} Mar 16 07:06:02 volumio volumio[1057]: verbose: CURRENT POSITION 7 Mar 16 07:06:02 volumio volumio[1057]: info: CoreStateMachine::syncState stateService play Mar 16 07:06:02 volumio volumio[1057]: info: CoreStateMachine::syncState currentStatus play Mar 16 07:06:02 volumio volumio[1057]: info: Received an update from plugin. extracting info from payload Mar 16 07:06:02 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:02 volumio volumio[1057]: info: CorePlayQueue::getTrack 7 Mar 16 07:06:02 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:06:02 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:02 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:02 volumio volumio[1057]: info: CorePlayQueue::getTrack 7 Mar 16 07:06:02 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:02 volumio volumio[1057]: info: ------------------------------ 36ms Mar 16 07:06:02 volumio volumio[1057]: info: ------------------------------ 43ms Mar 16 07:06:04 volumio volumio[1057]: info: CoreCommandRouter::volumioNext Mar 16 07:06:04 volumio volumio[1057]: info: CoreStateMachine::next Mar 16 07:06:04 volumio volumio[1057]: info: CoreStateMachine::stop Mar 16 07:06:04 volumio volumio[1057]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 07:06:04 volumio volumio[1057]: info: CoreStateMachine::stPlaybackTimer Mar 16 07:06:04 volumio volumio[1057]: info: CoreStateMachine::updateTrackBlock Mar 16 07:06:04 volumio volumio[1057]: info: CorePlayQueue::getTrackBlock Mar 16 07:06:04 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:04 volumio volumio[1057]: info: CorePlayQueue::getTrack 7 Mar 16 07:06:04 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:06:04 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:04 volumio volumio[1057]: info: CoreStateMachine::serviceStop Mar 16 07:06:04 volumio volumio[1057]: info: CorePlayQueue::getTrack 7 Mar 16 07:06:04 volumio volumio[1057]: info: CoreCommandRouter::serviceStop Mar 16 07:06:04 volumio volumio[1057]: info: ControllerMpd::stop Mar 16 07:06:04 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 07:06:04 volumio volumio[1057]: info: Mar 16 07:06:04 volumio volumio[1057]: ---------------------------- MPD announces state update: player Mar 16 07:06:04 volumio volumio[1057]: info: sendMpdCommand stop took 7 milliseconds Mar 16 07:06:04 volumio volumio[1057]: info: ControllerMpd::getState Mar 16 07:06:04 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Mar 16 07:06:04 volumio volumio[1057]: info: CoreStateMachine::play index undefined Mar 16 07:06:04 volumio volumio[1057]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 07:06:04 volumio volumio[1057]: info: CorePlayQueue::getTrack 8 Mar 16 07:06:04 volumio volumio[1057]: info: CoreStateMachine::startPlaybackTimer Mar 16 07:06:04 volumio volumio[1057]: info: CorePlayQueue::getTrack 8 Mar 16 07:06:04 volumio volumio[1057]: verbose: ControllerMpd::clearAddPlayTracks USB/F00D-AFDC/backstreetboy - i'll never break your heart.mp3 Mar 16 07:06:04 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 07:06:04 volumio volumio[1057]: info: CoreStateMachine::updateTrackBlock Mar 16 07:06:04 volumio volumio[1057]: info: CorePlayQueue::getTrackBlock Mar 16 07:06:04 volumio volumio[1057]: info: sendMpdCommand status took 4 milliseconds Mar 16 07:06:04 volumio volumio[1057]: info: sendMpdCommand stop took 2 milliseconds Mar 16 07:06:04 volumio volumio[1057]: verbose: ControllerMpd::parseState Mar 16 07:06:04 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 07:06:04 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand clear Mar 16 07:06:04 volumio volumio[1057]: info: Mar 16 07:06:04 volumio volumio[1057]: ---------------------------- MPD announces system playlist update Mar 16 07:06:04 volumio volumio[1057]: info: Ignoring MPD Status Update Mar 16 07:06:04 volumio volumio[1057]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 16 07:06:04 volumio volumio[1057]: info: sendMpdCommand clear took 2 milliseconds Mar 16 07:06:04 volumio volumio[1057]: verbose: ControllerMpd::parseTrackInfo Mar 16 07:06:04 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand add "USB/F00D-AFDC/backstreetboy - i'll never break your heart.mp3" Mar 16 07:06:04 volumio volumio[1057]: info: ControllerMpd::pushState Mar 16 07:06:04 volumio volumio[1057]: info: CoreCommandRouter::servicePushState Mar 16 07:06:04 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:04 volumio volumio[1057]: info: CorePlayQueue::getTrack 8 Mar 16 07:06:04 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:06:04 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:04 volumio volumio[1057]: info: CorePlayQueue::getTrack 8 Mar 16 07:06:04 volumio volumio[1057]: 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":"aziatix - go.mp3","artist":null,"album":null,"uri":"USB/F00D-AFDC/aziatix - go.mp3","trackType":"mp3"} Mar 16 07:06:04 volumio volumio[1057]: verbose: CURRENT POSITION 8 Mar 16 07:06:04 volumio volumio[1057]: info: CoreStateMachine::syncState stateService stop Mar 16 07:06:04 volumio volumio[1057]: info: CoreStateMachine::syncState currentStatus stop Mar 16 07:06:04 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:04 volumio volumio[1057]: info: CorePlayQueue::getTrack 8 Mar 16 07:06:04 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:04 volumio volumio[1057]: info: No code Mar 16 07:06:04 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:04 volumio volumio[1057]: info: CorePlayQueue::getTrack 8 Mar 16 07:06:04 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:04 volumio volumio[1057]: info: ------------------------------ 38ms Mar 16 07:06:04 volumio volumio[1057]: error: updateQueue error: null Mar 16 07:06:04 volumio volumio[1057]: info: Mar 16 07:06:04 volumio volumio[1057]: ---------------------------- MPD announces system playlist update Mar 16 07:06:04 volumio volumio[1057]: info: Ignoring MPD Status Update Mar 16 07:06:04 volumio volumio[1057]: info: ------------------------------ 32ms Mar 16 07:06:04 volumio volumio[1057]: info: sendMpdCommand add "USB/F00D-AFDC/backstreetboy - i'll never break your heart.mp3" took 31 milliseconds Mar 16 07:06:04 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand play Mar 16 07:06:04 volumio volumio[1057]: info: Mar 16 07:06:04 volumio volumio[1057]: ---------------------------- MPD announces state update: player Mar 16 07:06:04 volumio volumio[1057]: info: ------------------------------ 9ms Mar 16 07:06:04 volumio volumio[1057]: info: sendMpdCommand play took 8 milliseconds Mar 16 07:06:04 volumio volumio[1057]: info: ControllerMpd::getState Mar 16 07:06:04 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Mar 16 07:06:04 volumio volumio[1057]: info: Mar 16 07:06:04 volumio volumio[1057]: ---------------------------- MPD announces state update: player Mar 16 07:06:04 volumio volumio[1057]: info: sendMpdCommand status took 8 milliseconds Mar 16 07:06:04 volumio volumio[1057]: info: ControllerMpd::getState Mar 16 07:06:04 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Mar 16 07:06:04 volumio volumio[1057]: verbose: ControllerMpd::parseState Mar 16 07:06:04 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 07:06:04 volumio volumio[1057]: info: sendMpdCommand status took 1 milliseconds Mar 16 07:06:04 volumio volumio[1057]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 16 07:06:04 volumio volumio[1057]: verbose: ControllerMpd::parseState Mar 16 07:06:04 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 07:06:04 volumio volumio[1057]: verbose: ControllerMpd::parseTrackInfo Mar 16 07:06:04 volumio volumio[1057]: info: ControllerMpd::pushState Mar 16 07:06:04 volumio volumio[1057]: info: CoreCommandRouter::servicePushState Mar 16 07:06:04 volumio volumio[1057]: info: CorePlayQueue::getTrack 8 Mar 16 07:06:04 volumio volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":291,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"backstreetboy - i'll never break your heart.mp3","artist":null,"album":null,"uri":"USB/F00D-AFDC/backstreetboy - i'll never break your heart.mp3","trackType":"mp3"} Mar 16 07:06:04 volumio volumio[1057]: verbose: CURRENT POSITION 8 Mar 16 07:06:04 volumio volumio[1057]: info: CoreStateMachine::syncState stateService play Mar 16 07:06:04 volumio volumio[1057]: info: CoreStateMachine::syncState currentStatus stop Mar 16 07:06:04 volumio volumio[1057]: info: ------------------------------ 21ms Mar 16 07:06:04 volumio volumio[1057]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 16 07:06:04 volumio volumio[1057]: verbose: ControllerMpd::parseTrackInfo Mar 16 07:06:04 volumio volumio[1057]: info: ControllerMpd::pushState Mar 16 07:06:04 volumio volumio[1057]: info: CoreCommandRouter::servicePushState Mar 16 07:06:04 volumio volumio[1057]: info: CorePlayQueue::getTrack 8 Mar 16 07:06:04 volumio volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":291,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"backstreetboy - i'll never break your heart.mp3","artist":null,"album":null,"uri":"USB/F00D-AFDC/backstreetboy - i'll never break your heart.mp3","trackType":"mp3"} Mar 16 07:06:04 volumio volumio[1057]: verbose: CURRENT POSITION 8 Mar 16 07:06:04 volumio volumio[1057]: info: CoreStateMachine::syncState stateService play Mar 16 07:06:04 volumio volumio[1057]: info: CoreStateMachine::syncState currentStatus play Mar 16 07:06:04 volumio volumio[1057]: info: Received an update from plugin. extracting info from payload Mar 16 07:06:04 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:04 volumio volumio[1057]: info: CorePlayQueue::getTrack 8 Mar 16 07:06:04 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:06:04 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:04 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:04 volumio volumio[1057]: info: CorePlayQueue::getTrack 8 Mar 16 07:06:04 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:04 volumio volumio[1057]: info: ------------------------------ 48ms Mar 16 07:06:08 volumio volumio[1057]: info: CoreCommandRouter::volumioNext Mar 16 07:06:08 volumio volumio[1057]: info: CoreStateMachine::next Mar 16 07:06:08 volumio volumio[1057]: info: CoreStateMachine::stop Mar 16 07:06:08 volumio volumio[1057]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 07:06:08 volumio volumio[1057]: info: CoreStateMachine::stPlaybackTimer Mar 16 07:06:08 volumio volumio[1057]: info: CoreStateMachine::updateTrackBlock Mar 16 07:06:08 volumio volumio[1057]: info: CorePlayQueue::getTrackBlock Mar 16 07:06:08 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:08 volumio volumio[1057]: info: CorePlayQueue::getTrack 8 Mar 16 07:06:08 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:06:08 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:08 volumio volumio[1057]: info: CoreStateMachine::serviceStop Mar 16 07:06:08 volumio volumio[1057]: info: CorePlayQueue::getTrack 8 Mar 16 07:06:08 volumio volumio[1057]: info: CoreCommandRouter::serviceStop Mar 16 07:06:08 volumio volumio[1057]: info: ControllerMpd::stop Mar 16 07:06:08 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 07:06:08 volumio volumio[1057]: info: Mar 16 07:06:08 volumio volumio[1057]: ---------------------------- MPD announces state update: player Mar 16 07:06:08 volumio volumio[1057]: info: sendMpdCommand stop took 10 milliseconds Mar 16 07:06:08 volumio volumio[1057]: info: ControllerMpd::getState Mar 16 07:06:08 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Mar 16 07:06:08 volumio volumio[1057]: info: CoreStateMachine::play index undefined Mar 16 07:06:08 volumio volumio[1057]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 07:06:08 volumio volumio[1057]: info: CorePlayQueue::getTrack 9 Mar 16 07:06:08 volumio volumio[1057]: info: CoreStateMachine::startPlaybackTimer Mar 16 07:06:08 volumio volumio[1057]: info: CorePlayQueue::getTrack 9 Mar 16 07:06:08 volumio volumio[1057]: verbose: ControllerMpd::clearAddPlayTracks USB/F00D-AFDC/Beast - 下雨的日子.mp3 Mar 16 07:06:08 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 07:06:08 volumio volumio[1057]: info: CoreStateMachine::updateTrackBlock Mar 16 07:06:08 volumio volumio[1057]: info: CorePlayQueue::getTrackBlock Mar 16 07:06:08 volumio volumio[1057]: info: sendMpdCommand status took 4 milliseconds Mar 16 07:06:08 volumio volumio[1057]: info: sendMpdCommand stop took 1 milliseconds Mar 16 07:06:08 volumio volumio[1057]: verbose: ControllerMpd::parseState Mar 16 07:06:08 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 07:06:08 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand clear Mar 16 07:06:08 volumio volumio[1057]: info: Mar 16 07:06:08 volumio volumio[1057]: ---------------------------- MPD announces system playlist update Mar 16 07:06:08 volumio volumio[1057]: info: Ignoring MPD Status Update Mar 16 07:06:08 volumio volumio[1057]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 16 07:06:08 volumio volumio[1057]: info: sendMpdCommand clear took 3 milliseconds Mar 16 07:06:08 volumio volumio[1057]: verbose: ControllerMpd::parseTrackInfo Mar 16 07:06:08 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand add "USB/F00D-AFDC/Beast - 下雨的日子.mp3" Mar 16 07:06:08 volumio volumio[1057]: info: ControllerMpd::pushState Mar 16 07:06:08 volumio volumio[1057]: info: CoreCommandRouter::servicePushState Mar 16 07:06:08 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:08 volumio volumio[1057]: info: CorePlayQueue::getTrack 9 Mar 16 07:06:08 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:06:08 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:08 volumio volumio[1057]: info: CorePlayQueue::getTrack 9 Mar 16 07:06:08 volumio volumio[1057]: 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":"backstreetboy - i'll never break your heart.mp3","artist":null,"album":null,"uri":"USB/F00D-AFDC/backstreetboy - i'll never break your heart.mp3","trackType":"mp3"} Mar 16 07:06:08 volumio volumio[1057]: verbose: CURRENT POSITION 9 Mar 16 07:06:08 volumio volumio[1057]: info: CoreStateMachine::syncState stateService stop Mar 16 07:06:08 volumio volumio[1057]: info: CoreStateMachine::syncState currentStatus stop Mar 16 07:06:08 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:08 volumio volumio[1057]: info: CorePlayQueue::getTrack 9 Mar 16 07:06:08 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:08 volumio volumio[1057]: info: No code Mar 16 07:06:08 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:08 volumio volumio[1057]: info: CorePlayQueue::getTrack 9 Mar 16 07:06:08 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:08 volumio volumio[1057]: info: ------------------------------ 38ms Mar 16 07:06:08 volumio volumio[1057]: error: updateQueue error: null Mar 16 07:06:08 volumio volumio[1057]: info: Mar 16 07:06:08 volumio volumio[1057]: ---------------------------- MPD announces system playlist update Mar 16 07:06:08 volumio volumio[1057]: info: Ignoring MPD Status Update Mar 16 07:06:08 volumio volumio[1057]: info: ------------------------------ 33ms Mar 16 07:06:08 volumio volumio[1057]: info: sendMpdCommand add "USB/F00D-AFDC/Beast - 下雨的日子.mp3" took 29 milliseconds Mar 16 07:06:08 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand play Mar 16 07:06:08 volumio volumio[1057]: info: ------------------------------ 5ms Mar 16 07:06:08 volumio volumio[1057]: info: sendMpdCommand play took 4 milliseconds Mar 16 07:06:08 volumio volumio[1057]: info: Mar 16 07:06:08 volumio volumio[1057]: ---------------------------- MPD announces state update: player Mar 16 07:06:08 volumio volumio[1057]: info: ControllerMpd::getState Mar 16 07:06:08 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Mar 16 07:06:08 volumio volumio[1057]: info: Mar 16 07:06:08 volumio volumio[1057]: ---------------------------- MPD announces state update: player Mar 16 07:06:08 volumio volumio[1057]: info: sendMpdCommand status took 9 milliseconds Mar 16 07:06:08 volumio volumio[1057]: info: ControllerMpd::getState Mar 16 07:06:08 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Mar 16 07:06:08 volumio volumio[1057]: verbose: ControllerMpd::parseState Mar 16 07:06:08 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 07:06:08 volumio volumio[1057]: info: sendMpdCommand status took 7 milliseconds Mar 16 07:06:08 volumio volumio[1057]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 16 07:06:08 volumio volumio[1057]: verbose: ControllerMpd::parseState Mar 16 07:06:08 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 07:06:08 volumio volumio[1057]: verbose: ControllerMpd::parseTrackInfo Mar 16 07:06:08 volumio volumio[1057]: info: ControllerMpd::pushState Mar 16 07:06:08 volumio volumio[1057]: info: CoreCommandRouter::servicePushState Mar 16 07:06:08 volumio volumio[1057]: info: CorePlayQueue::getTrack 9 Mar 16 07:06:08 volumio volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Beast - 下雨的日子.mp3","artist":null,"album":null,"uri":"USB/F00D-AFDC/Beast - 下雨的日子.mp3","trackType":"mp3"} Mar 16 07:06:08 volumio volumio[1057]: verbose: CURRENT POSITION 9 Mar 16 07:06:08 volumio volumio[1057]: info: CoreStateMachine::syncState stateService play Mar 16 07:06:08 volumio volumio[1057]: info: CoreStateMachine::syncState currentStatus stop Mar 16 07:06:08 volumio volumio[1057]: info: ------------------------------ 18ms Mar 16 07:06:08 volumio volumio[1057]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 16 07:06:08 volumio volumio[1057]: verbose: ControllerMpd::parseTrackInfo Mar 16 07:06:08 volumio volumio[1057]: info: ControllerMpd::pushState Mar 16 07:06:08 volumio volumio[1057]: info: CoreCommandRouter::servicePushState Mar 16 07:06:08 volumio volumio[1057]: info: CorePlayQueue::getTrack 9 Mar 16 07:06:08 volumio volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":307,"duration":226,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Beast - 下雨的日子.mp3","artist":null,"album":null,"uri":"USB/F00D-AFDC/Beast - 下雨的日子.mp3","trackType":"mp3"} Mar 16 07:06:08 volumio volumio[1057]: verbose: CURRENT POSITION 9 Mar 16 07:06:08 volumio volumio[1057]: info: CoreStateMachine::syncState stateService play Mar 16 07:06:08 volumio volumio[1057]: info: CoreStateMachine::syncState currentStatus play Mar 16 07:06:08 volumio volumio[1057]: info: Received an update from plugin. extracting info from payload Mar 16 07:06:08 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:09 volumio volumio[1057]: info: CorePlayQueue::getTrack 9 Mar 16 07:06:09 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:06:09 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:09 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:09 volumio volumio[1057]: info: CorePlayQueue::getTrack 9 Mar 16 07:06:09 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:09 volumio volumio[1057]: info: ------------------------------ 31ms Mar 16 07:06:13 volumio volumio[1057]: info: CoreCommandRouter::volumioSeek Mar 16 07:06:13 volumio volumio[1057]: info: CoreStateMachine::seek Mar 16 07:06:13 volumio volumio[1057]: info: CorePlayQueue::getTrack 9 Mar 16 07:06:13 volumio volumio[1057]: info: TRACKBLOCK {"uri":"mnt/USB/F00D-AFDC/Beast - 下雨的日子.mp3","service":"mpd","name":"Beast - 下雨的日子.mp3","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=887&web=//extralarge&path=%2FUSB%2FF00D-AFDC&metadata=false","duration":226,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2} Mar 16 07:06:13 volumio volumio[1057]: info: CoreStateMachine::startPlaybackTimer Mar 16 07:06:13 volumio volumio[1057]: info: CorePlayQueue::getTrack 9 Mar 16 07:06:13 volumio volumio[1057]: info: ControllerMpd::seek Mar 16 07:06:13 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:13 volumio volumio[1057]: info: CorePlayQueue::getTrack 9 Mar 16 07:06:13 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:06:13 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:13 volumio volumio[1057]: error: null Mar 16 07:06:13 volumio volumio[1057]: info: Mar 16 07:06:13 volumio volumio[1057]: ---------------------------- MPD announces state update: player Mar 16 07:06:13 volumio volumio[1057]: info: ControllerMpd::getState Mar 16 07:06:13 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Mar 16 07:06:13 volumio volumio[1057]: info: sendMpdCommand status took 1 milliseconds Mar 16 07:06:13 volumio volumio[1057]: verbose: ControllerMpd::parseState Mar 16 07:06:13 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 07:06:13 volumio volumio[1057]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 16 07:06:13 volumio volumio[1057]: verbose: ControllerMpd::parseTrackInfo Mar 16 07:06:13 volumio volumio[1057]: info: ControllerMpd::pushState Mar 16 07:06:13 volumio volumio[1057]: info: CoreCommandRouter::servicePushState Mar 16 07:06:13 volumio volumio[1057]: info: CorePlayQueue::getTrack 9 Mar 16 07:06:13 volumio volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45000,"duration":226,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Beast - 下雨的日子.mp3","artist":null,"album":null,"uri":"USB/F00D-AFDC/Beast - 下雨的日子.mp3","trackType":"mp3"} Mar 16 07:06:13 volumio volumio[1057]: verbose: CURRENT POSITION 9 Mar 16 07:06:13 volumio volumio[1057]: info: CoreStateMachine::syncState stateService play Mar 16 07:06:13 volumio volumio[1057]: info: CoreStateMachine::syncState currentStatus play Mar 16 07:06:13 volumio volumio[1057]: info: Received an update from plugin. extracting info from payload Mar 16 07:06:13 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:13 volumio volumio[1057]: info: CorePlayQueue::getTrack 9 Mar 16 07:06:13 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:13 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:13 volumio volumio[1057]: info: CorePlayQueue::getTrack 9 Mar 16 07:06:13 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:13 volumio volumio[1057]: info: ------------------------------ 19ms Mar 16 07:06:14 volumio volumio[1057]: info: CoreCommandRouter::volumioNext Mar 16 07:06:14 volumio volumio[1057]: info: CoreStateMachine::next Mar 16 07:06:14 volumio volumio[1057]: info: CoreStateMachine::stop Mar 16 07:06:14 volumio volumio[1057]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 07:06:14 volumio volumio[1057]: info: CoreStateMachine::stPlaybackTimer Mar 16 07:06:14 volumio volumio[1057]: info: CoreStateMachine::updateTrackBlock Mar 16 07:06:14 volumio volumio[1057]: info: CorePlayQueue::getTrackBlock Mar 16 07:06:14 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:14 volumio volumio[1057]: info: CorePlayQueue::getTrack 9 Mar 16 07:06:14 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:06:14 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:14 volumio volumio[1057]: info: CoreStateMachine::serviceStop Mar 16 07:06:14 volumio volumio[1057]: info: CorePlayQueue::getTrack 9 Mar 16 07:06:14 volumio volumio[1057]: info: CoreCommandRouter::serviceStop Mar 16 07:06:14 volumio volumio[1057]: info: ControllerMpd::stop Mar 16 07:06:14 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 07:06:14 volumio volumio[1057]: info: Mar 16 07:06:14 volumio volumio[1057]: ---------------------------- MPD announces state update: player Mar 16 07:06:14 volumio volumio[1057]: info: sendMpdCommand stop took 8 milliseconds Mar 16 07:06:14 volumio volumio[1057]: info: ControllerMpd::getState Mar 16 07:06:14 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Mar 16 07:06:14 volumio volumio[1057]: info: CoreStateMachine::play index undefined Mar 16 07:06:14 volumio volumio[1057]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 07:06:14 volumio volumio[1057]: info: CorePlayQueue::getTrack 10 Mar 16 07:06:14 volumio volumio[1057]: info: CoreStateMachine::startPlaybackTimer Mar 16 07:06:14 volumio volumio[1057]: info: CorePlayQueue::getTrack 10 Mar 16 07:06:14 volumio volumio[1057]: verbose: ControllerMpd::clearAddPlayTracks USB/F00D-AFDC/bPDiAdDp.mp3 Mar 16 07:06:14 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 07:06:14 volumio volumio[1057]: info: CoreStateMachine::updateTrackBlock Mar 16 07:06:14 volumio volumio[1057]: info: CorePlayQueue::getTrackBlock Mar 16 07:06:14 volumio volumio[1057]: info: sendMpdCommand status took 4 milliseconds Mar 16 07:06:14 volumio volumio[1057]: info: sendMpdCommand stop took 1 milliseconds Mar 16 07:06:14 volumio volumio[1057]: verbose: ControllerMpd::parseState Mar 16 07:06:14 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 07:06:14 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand clear Mar 16 07:06:14 volumio volumio[1057]: info: Mar 16 07:06:14 volumio volumio[1057]: ---------------------------- MPD announces system playlist update Mar 16 07:06:14 volumio volumio[1057]: info: Ignoring MPD Status Update Mar 16 07:06:14 volumio volumio[1057]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 16 07:06:14 volumio volumio[1057]: info: sendMpdCommand clear took 2 milliseconds Mar 16 07:06:14 volumio volumio[1057]: verbose: ControllerMpd::parseTrackInfo Mar 16 07:06:14 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand add "USB/F00D-AFDC/bPDiAdDp.mp3" Mar 16 07:06:14 volumio volumio[1057]: info: ControllerMpd::pushState Mar 16 07:06:14 volumio volumio[1057]: info: CoreCommandRouter::servicePushState Mar 16 07:06:14 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:14 volumio volumio[1057]: info: CorePlayQueue::getTrack 10 Mar 16 07:06:14 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:06:14 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:14 volumio volumio[1057]: info: CorePlayQueue::getTrack 10 Mar 16 07:06:14 volumio volumio[1057]: 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":"Beast - 下雨的日子.mp3","artist":null,"album":null,"uri":"USB/F00D-AFDC/Beast - 下雨的日子.mp3","trackType":"mp3"} Mar 16 07:06:14 volumio volumio[1057]: verbose: CURRENT POSITION 10 Mar 16 07:06:14 volumio volumio[1057]: info: CoreStateMachine::syncState stateService stop Mar 16 07:06:14 volumio volumio[1057]: info: CoreStateMachine::syncState currentStatus stop Mar 16 07:06:14 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:14 volumio volumio[1057]: info: CorePlayQueue::getTrack 10 Mar 16 07:06:14 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:14 volumio volumio[1057]: info: No code Mar 16 07:06:14 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:14 volumio volumio[1057]: info: CorePlayQueue::getTrack 10 Mar 16 07:06:14 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:14 volumio volumio[1057]: info: ------------------------------ 36ms Mar 16 07:06:14 volumio volumio[1057]: error: updateQueue error: null Mar 16 07:06:14 volumio volumio[1057]: info: Mar 16 07:06:14 volumio volumio[1057]: ---------------------------- MPD announces system playlist update Mar 16 07:06:14 volumio volumio[1057]: info: Ignoring MPD Status Update Mar 16 07:06:14 volumio volumio[1057]: info: ------------------------------ 32ms Mar 16 07:06:14 volumio volumio[1057]: info: sendMpdCommand add "USB/F00D-AFDC/bPDiAdDp.mp3" took 32 milliseconds Mar 16 07:06:14 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand play Mar 16 07:06:14 volumio volumio[1057]: info: Mar 16 07:06:14 volumio volumio[1057]: ---------------------------- MPD announces state update: player Mar 16 07:06:14 volumio volumio[1057]: info: ------------------------------ 12ms Mar 16 07:06:14 volumio volumio[1057]: info: sendMpdCommand play took 9 milliseconds Mar 16 07:06:14 volumio volumio[1057]: info: ControllerMpd::getState Mar 16 07:06:14 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Mar 16 07:06:14 volumio volumio[1057]: info: Mar 16 07:06:14 volumio volumio[1057]: ---------------------------- MPD announces state update: player Mar 16 07:06:14 volumio volumio[1057]: info: sendMpdCommand status took 5 milliseconds Mar 16 07:06:14 volumio volumio[1057]: info: ControllerMpd::getState Mar 16 07:06:14 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Mar 16 07:06:14 volumio volumio[1057]: verbose: ControllerMpd::parseState Mar 16 07:06:14 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 07:06:14 volumio volumio[1057]: info: sendMpdCommand status took 10 milliseconds Mar 16 07:06:14 volumio volumio[1057]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 16 07:06:14 volumio volumio[1057]: verbose: ControllerMpd::parseState Mar 16 07:06:14 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 07:06:14 volumio volumio[1057]: verbose: ControllerMpd::parseTrackInfo Mar 16 07:06:14 volumio volumio[1057]: info: ControllerMpd::pushState Mar 16 07:06:14 volumio volumio[1057]: info: CoreCommandRouter::servicePushState Mar 16 07:06:14 volumio volumio[1057]: info: CorePlayQueue::getTrack 10 Mar 16 07:06:14 volumio volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bPDiAdDp.mp3","artist":null,"album":null,"uri":"USB/F00D-AFDC/bPDiAdDp.mp3","trackType":"mp3"} Mar 16 07:06:14 volumio volumio[1057]: verbose: CURRENT POSITION 10 Mar 16 07:06:14 volumio volumio[1057]: info: CoreStateMachine::syncState stateService play Mar 16 07:06:14 volumio volumio[1057]: info: CoreStateMachine::syncState currentStatus stop Mar 16 07:06:14 volumio volumio[1057]: info: ------------------------------ 21ms Mar 16 07:06:14 volumio volumio[1057]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 16 07:06:14 volumio volumio[1057]: verbose: ControllerMpd::parseTrackInfo Mar 16 07:06:14 volumio volumio[1057]: info: ControllerMpd::pushState Mar 16 07:06:14 volumio volumio[1057]: info: CoreCommandRouter::servicePushState Mar 16 07:06:14 volumio volumio[1057]: info: CorePlayQueue::getTrack 10 Mar 16 07:06:14 volumio volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":364,"duration":276,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"bPDiAdDp.mp3","artist":null,"album":null,"uri":"USB/F00D-AFDC/bPDiAdDp.mp3","trackType":"mp3"} Mar 16 07:06:14 volumio volumio[1057]: verbose: CURRENT POSITION 10 Mar 16 07:06:14 volumio volumio[1057]: info: CoreStateMachine::syncState stateService play Mar 16 07:06:14 volumio volumio[1057]: info: CoreStateMachine::syncState currentStatus play Mar 16 07:06:14 volumio volumio[1057]: info: Received an update from plugin. extracting info from payload Mar 16 07:06:14 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:14 volumio volumio[1057]: info: CorePlayQueue::getTrack 10 Mar 16 07:06:14 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:06:14 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:14 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:14 volumio volumio[1057]: info: CorePlayQueue::getTrack 10 Mar 16 07:06:14 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:14 volumio volumio[1057]: info: ------------------------------ 40ms Mar 16 07:06:21 volumio volumio[1057]: info: CoreCommandRouter::volumioSeek Mar 16 07:06:21 volumio volumio[1057]: info: CoreStateMachine::seek Mar 16 07:06:21 volumio volumio[1057]: info: CorePlayQueue::getTrack 10 Mar 16 07:06:21 volumio volumio[1057]: info: TRACKBLOCK {"uri":"mnt/USB/F00D-AFDC/bPDiAdDp.mp3","service":"mpd","name":"bPDiAdDp.mp3","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=887&web=//extralarge&path=%2FUSB%2FF00D-AFDC&metadata=false","duration":276,"samplerate":"22.05 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2} Mar 16 07:06:21 volumio volumio[1057]: info: CoreStateMachine::startPlaybackTimer Mar 16 07:06:21 volumio volumio[1057]: info: CorePlayQueue::getTrack 10 Mar 16 07:06:21 volumio volumio[1057]: info: ControllerMpd::seek Mar 16 07:06:21 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:21 volumio volumio[1057]: info: CorePlayQueue::getTrack 10 Mar 16 07:06:21 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:06:21 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:21 volumio volumio[1057]: error: null Mar 16 07:06:21 volumio volumio[1057]: info: Mar 16 07:06:21 volumio volumio[1057]: ---------------------------- MPD announces state update: player Mar 16 07:06:21 volumio volumio[1057]: info: ControllerMpd::getState Mar 16 07:06:21 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Mar 16 07:06:21 volumio volumio[1057]: info: sendMpdCommand status took 2 milliseconds Mar 16 07:06:21 volumio volumio[1057]: verbose: ControllerMpd::parseState Mar 16 07:06:21 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 07:06:21 volumio volumio[1057]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 16 07:06:21 volumio volumio[1057]: verbose: ControllerMpd::parseTrackInfo Mar 16 07:06:21 volumio volumio[1057]: info: ControllerMpd::pushState Mar 16 07:06:21 volumio volumio[1057]: info: CoreCommandRouter::servicePushState Mar 16 07:06:21 volumio volumio[1057]: info: CorePlayQueue::getTrack 10 Mar 16 07:06:21 volumio volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":233000,"duration":276,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"bPDiAdDp.mp3","artist":null,"album":null,"uri":"USB/F00D-AFDC/bPDiAdDp.mp3","trackType":"mp3"} Mar 16 07:06:21 volumio volumio[1057]: verbose: CURRENT POSITION 10 Mar 16 07:06:21 volumio volumio[1057]: info: CoreStateMachine::syncState stateService play Mar 16 07:06:21 volumio volumio[1057]: info: CoreStateMachine::syncState currentStatus play Mar 16 07:06:21 volumio volumio[1057]: info: Received an update from plugin. extracting info from payload Mar 16 07:06:21 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:21 volumio volumio[1057]: info: CorePlayQueue::getTrack 10 Mar 16 07:06:21 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:21 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:21 volumio volumio[1057]: info: CorePlayQueue::getTrack 10 Mar 16 07:06:21 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:21 volumio volumio[1057]: info: ------------------------------ 21ms Mar 16 07:06:22 volumio ntpd[5788]: 45.76.221.157 local addr 192.168.18.24 -> Mar 16 07:06:22 volumio volumio[1057]: info: CoreCommandRouter::volumioNext Mar 16 07:06:22 volumio volumio[1057]: info: CoreStateMachine::next Mar 16 07:06:22 volumio volumio[1057]: info: CoreStateMachine::stop Mar 16 07:06:22 volumio volumio[1057]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 07:06:22 volumio volumio[1057]: info: CoreStateMachine::stPlaybackTimer Mar 16 07:06:22 volumio volumio[1057]: info: CoreStateMachine::updateTrackBlock Mar 16 07:06:22 volumio volumio[1057]: info: CorePlayQueue::getTrackBlock Mar 16 07:06:22 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:22 volumio volumio[1057]: info: CorePlayQueue::getTrack 10 Mar 16 07:06:22 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:06:22 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:22 volumio volumio[1057]: info: CoreStateMachine::serviceStop Mar 16 07:06:22 volumio volumio[1057]: info: CorePlayQueue::getTrack 10 Mar 16 07:06:22 volumio volumio[1057]: info: CoreCommandRouter::serviceStop Mar 16 07:06:22 volumio volumio[1057]: info: ControllerMpd::stop Mar 16 07:06:22 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 07:06:22 volumio volumio[1057]: info: Mar 16 07:06:22 volumio volumio[1057]: ---------------------------- MPD announces state update: player Mar 16 07:06:22 volumio volumio[1057]: info: sendMpdCommand stop took 9 milliseconds Mar 16 07:06:22 volumio volumio[1057]: info: ControllerMpd::getState Mar 16 07:06:22 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Mar 16 07:06:22 volumio volumio[1057]: info: CoreStateMachine::play index undefined Mar 16 07:06:22 volumio volumio[1057]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 16 07:06:22 volumio volumio[1057]: info: CorePlayQueue::getTrack 11 Mar 16 07:06:22 volumio volumio[1057]: info: CoreStateMachine::startPlaybackTimer Mar 16 07:06:22 volumio volumio[1057]: info: CorePlayQueue::getTrack 11 Mar 16 07:06:22 volumio volumio[1057]: verbose: ControllerMpd::clearAddPlayTracks USB/F00D-AFDC/Carolina Liar - Coming To Terms.mp3 Mar 16 07:06:22 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand stop Mar 16 07:06:22 volumio volumio[1057]: info: CoreStateMachine::updateTrackBlock Mar 16 07:06:22 volumio volumio[1057]: info: CorePlayQueue::getTrackBlock Mar 16 07:06:22 volumio volumio[1057]: info: sendMpdCommand status took 4 milliseconds Mar 16 07:06:22 volumio volumio[1057]: info: sendMpdCommand stop took 4 milliseconds Mar 16 07:06:22 volumio volumio[1057]: verbose: ControllerMpd::parseState Mar 16 07:06:22 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 07:06:22 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand clear Mar 16 07:06:22 volumio volumio[1057]: info: Mar 16 07:06:22 volumio volumio[1057]: ---------------------------- MPD announces system playlist update Mar 16 07:06:22 volumio volumio[1057]: info: Ignoring MPD Status Update Mar 16 07:06:22 volumio volumio[1057]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 16 07:06:22 volumio volumio[1057]: info: sendMpdCommand clear took 4 milliseconds Mar 16 07:06:22 volumio volumio[1057]: verbose: ControllerMpd::parseTrackInfo Mar 16 07:06:22 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand add "USB/F00D-AFDC/Carolina Liar - Coming To Terms.mp3" Mar 16 07:06:22 volumio volumio[1057]: info: ControllerMpd::pushState Mar 16 07:06:22 volumio volumio[1057]: info: CoreCommandRouter::servicePushState Mar 16 07:06:22 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:22 volumio volumio[1057]: info: CorePlayQueue::getTrack 11 Mar 16 07:06:22 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:06:22 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:22 volumio volumio[1057]: info: CorePlayQueue::getTrack 11 Mar 16 07:06:22 volumio volumio[1057]: 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":"bPDiAdDp.mp3","artist":null,"album":null,"uri":"USB/F00D-AFDC/bPDiAdDp.mp3","trackType":"mp3"} Mar 16 07:06:22 volumio volumio[1057]: verbose: CURRENT POSITION 11 Mar 16 07:06:22 volumio volumio[1057]: info: CoreStateMachine::syncState stateService stop Mar 16 07:06:22 volumio volumio[1057]: info: CoreStateMachine::syncState currentStatus stop Mar 16 07:06:22 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:22 volumio volumio[1057]: info: CorePlayQueue::getTrack 11 Mar 16 07:06:22 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:22 volumio volumio[1057]: info: No code Mar 16 07:06:22 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:22 volumio volumio[1057]: info: CorePlayQueue::getTrack 11 Mar 16 07:06:22 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:22 volumio volumio[1057]: info: ------------------------------ 36ms Mar 16 07:06:22 volumio volumio[1057]: error: updateQueue error: null Mar 16 07:06:22 volumio volumio[1057]: info: Mar 16 07:06:22 volumio volumio[1057]: ---------------------------- MPD announces system playlist update Mar 16 07:06:22 volumio volumio[1057]: info: Ignoring MPD Status Update Mar 16 07:06:22 volumio volumio[1057]: info: ------------------------------ 28ms Mar 16 07:06:22 volumio volumio[1057]: info: sendMpdCommand add "USB/F00D-AFDC/Carolina Liar - Coming To Terms.mp3" took 26 milliseconds Mar 16 07:06:22 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand play Mar 16 07:06:22 volumio volumio[1057]: info: Mar 16 07:06:22 volumio volumio[1057]: ---------------------------- MPD announces state update: player Mar 16 07:06:22 volumio volumio[1057]: info: ------------------------------ 12ms Mar 16 07:06:22 volumio volumio[1057]: info: sendMpdCommand play took 11 milliseconds Mar 16 07:06:22 volumio volumio[1057]: info: ControllerMpd::getState Mar 16 07:06:22 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Mar 16 07:06:22 volumio volumio[1057]: info: Mar 16 07:06:22 volumio volumio[1057]: ---------------------------- MPD announces state update: player Mar 16 07:06:22 volumio volumio[1057]: info: ControllerMpd::getState Mar 16 07:06:22 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand status Mar 16 07:06:22 volumio volumio[1057]: info: sendMpdCommand status took 12 milliseconds Mar 16 07:06:22 volumio volumio[1057]: verbose: ControllerMpd::parseState Mar 16 07:06:22 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 07:06:22 volumio volumio[1057]: info: sendMpdCommand status took 1 milliseconds Mar 16 07:06:22 volumio volumio[1057]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 16 07:06:22 volumio volumio[1057]: verbose: ControllerMpd::parseState Mar 16 07:06:22 volumio volumio[1057]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 16 07:06:22 volumio volumio[1057]: verbose: ControllerMpd::parseTrackInfo Mar 16 07:06:22 volumio volumio[1057]: info: ControllerMpd::pushState Mar 16 07:06:22 volumio volumio[1057]: info: CoreCommandRouter::servicePushState Mar 16 07:06:22 volumio volumio[1057]: info: CorePlayQueue::getTrack 11 Mar 16 07:06:22 volumio volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":209,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Carolina Liar - Coming To Terms.mp3","artist":null,"album":null,"uri":"USB/F00D-AFDC/Carolina Liar - Coming To Terms.mp3","trackType":"mp3"} Mar 16 07:06:22 volumio volumio[1057]: verbose: CURRENT POSITION 11 Mar 16 07:06:22 volumio volumio[1057]: info: CoreStateMachine::syncState stateService play Mar 16 07:06:22 volumio volumio[1057]: info: CoreStateMachine::syncState currentStatus stop Mar 16 07:06:22 volumio volumio[1057]: info: ------------------------------ 17ms Mar 16 07:06:22 volumio volumio[1057]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 16 07:06:22 volumio volumio[1057]: verbose: ControllerMpd::parseTrackInfo Mar 16 07:06:22 volumio volumio[1057]: info: ControllerMpd::pushState Mar 16 07:06:22 volumio volumio[1057]: info: CoreCommandRouter::servicePushState Mar 16 07:06:22 volumio volumio[1057]: info: CorePlayQueue::getTrack 11 Mar 16 07:06:22 volumio volumio[1057]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":209,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Carolina Liar - Coming To Terms.mp3","artist":null,"album":null,"uri":"USB/F00D-AFDC/Carolina Liar - Coming To Terms.mp3","trackType":"mp3"} Mar 16 07:06:22 volumio volumio[1057]: verbose: CURRENT POSITION 11 Mar 16 07:06:22 volumio volumio[1057]: info: CoreStateMachine::syncState stateService play Mar 16 07:06:22 volumio volumio[1057]: info: CoreStateMachine::syncState currentStatus play Mar 16 07:06:22 volumio volumio[1057]: info: Received an update from plugin. extracting info from payload Mar 16 07:06:22 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:22 volumio volumio[1057]: info: CorePlayQueue::getTrack 11 Mar 16 07:06:22 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:06:22 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:22 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:22 volumio volumio[1057]: info: CorePlayQueue::getTrack 11 Mar 16 07:06:22 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:22 volumio volumio[1057]: info: ------------------------------ 24ms Mar 16 07:06:26 volumio volumio[1057]: info: VolumeController::SetAlsaVolume25 Mar 16 07:06:26 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:26 volumio volumio[1057]: info: CorePlayQueue::getTrack 11 Mar 16 07:06:26 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:06:26 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:27 volumio volumio[1057]: info: VolumeController::SetAlsaVolume0 Mar 16 07:06:27 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:27 volumio volumio[1057]: info: CorePlayQueue::getTrack 11 Mar 16 07:06:27 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:06:27 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:28 volumio volumio[1057]: info: VolumeController::SetAlsaVolume5 Mar 16 07:06:28 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:28 volumio volumio[1057]: info: CorePlayQueue::getTrack 11 Mar 16 07:06:28 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:06:28 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:29 volumio volumio[1057]: info: VolumeController::SetAlsaVolume20 Mar 16 07:06:29 volumio volumio[1057]: info: CoreStateMachine::pushState Mar 16 07:06:29 volumio volumio[1057]: info: CorePlayQueue::getTrack 11 Mar 16 07:06:29 volumio volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 07:06:29 volumio volumio[1057]: info: CoreCommandRouter::volumioPushState Mar 16 07:06:32 volumio volumio[1057]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 07:06:32 volumio volumio[1057]: Error: connect ETIMEDOUT 199.59.149.204:80 Mar 16 07:06:32 volumio volumio[1057]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 16 07:06:32 volumio volumio[1057]: errno: -110, Mar 16 07:06:32 volumio volumio[1057]: code: 'ETIMEDOUT', Mar 16 07:06:32 volumio volumio[1057]: syscall: 'connect', Mar 16 07:06:32 volumio volumio[1057]: address: '199.59.149.204', Mar 16 07:06:32 volumio volumio[1057]: port: 80 Mar 16 07:06:32 volumio volumio[1057]: } Mar 16 07:06:32 volumio volumio[1057]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 07:06:33 volumio sudo[8220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-16 07:05 Mar 16 07:06:33 volumio sudo[8220]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:15:38 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9e0e048b157c8fb0f19240808f3da58b"