Oct 12 23:03:01 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:03:02 22d0024r sudo[6438]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 23:03:02 22d0024r sudo[6438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 23:03:02 22d0024r sudo[6438]: pam_unix(sudo:session): session closed for user root Oct 12 23:03:02 22d0024r sudo[6440]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 23:03:02 22d0024r sudo[6440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 23:03:02 22d0024r sudo[6440]: pam_unix(sudo:session): session closed for user root Oct 12 23:03:02 22d0024r volumio[1211]: verbose: New Socket.io Connection to 192.168.2.90 from 192.168.2.131 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/154.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 12 23:03:02 22d0024r sudo[6444]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 23:03:02 22d0024r sudo[6444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 23:03:02 22d0024r sudo[6444]: pam_unix(sudo:session): session closed for user root Oct 12 23:03:02 22d0024r sudo[6446]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 23:03:02 22d0024r sudo[6446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 23:03:02 22d0024r sudo[6446]: pam_unix(sudo:session): session closed for user root Oct 12 23:03:03 22d0024r volumio[1211]: verbose: New Socket.io Connection to 192.168.2.90 from 192.168.2.131 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/154.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 12 23:03:03 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 12 23:03:03 22d0024r volumio[1211]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 23:03:03 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 23:03:03 22d0024r volumio[1211]: info: CoreCommandRouter::volumioGetState Oct 12 23:03:03 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:03 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 12 23:03:03 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 23:03:03 22d0024r volumio[1211]: info: Received Get System Info Oct 12 23:03:03 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 23:03:03 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 23:03:03 22d0024r volumio[1211]: info: Discovery: Getting this device information Oct 12 23:03:03 22d0024r volumio[1211]: info: CoreCommandRouter::volumioGetState Oct 12 23:03:03 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:03 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 23:03:03 22d0024r volumio[1211]: info: CoreCommandRouter::volumioGetState Oct 12 23:03:03 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:03 22d0024r volumio[1211]: info: Listing playlists Oct 12 23:03:03 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 12 23:03:03 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 23:03:03 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 12 23:03:04 22d0024r volumio[1211]: info: CoreCommandRouter::volumioGetState Oct 12 23:03:04 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:04 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 12 23:03:04 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 23:03:04 22d0024r volumio[1211]: info: Received Get System Info Oct 12 23:03:04 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 23:03:04 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 23:03:04 22d0024r volumio[1211]: info: Discovery: Getting this device information Oct 12 23:03:04 22d0024r volumio[1211]: info: CoreCommandRouter::volumioGetState Oct 12 23:03:04 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:04 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 23:03:05 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:03:06 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 23:03:06 22d0024r volumio[1211]: info: Received Get System Info Oct 12 23:03:06 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 23:03:06 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 23:03:06 22d0024r volumio[1211]: info: Discovery: Getting this device information Oct 12 23:03:06 22d0024r volumio[1211]: info: CoreCommandRouter::volumioGetState Oct 12 23:03:06 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:06 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 23:03:13 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:03:16 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:03:20 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:03:25 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreCommandRouter::volumioNext Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::next Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::stop Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::stPlaybackTimer Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::updateTrackBlock Oct 12 23:03:29 22d0024r volumio[1211]: info: CorePlayQueue::getTrackBlock Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:29 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::serviceStop Oct 12 23:03:29 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreCommandRouter::serviceStop Oct 12 23:03:29 22d0024r volumio[1211]: info: ControllerMpd::stop Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 23:03:29 22d0024r volumio[1211]: info: Oct 12 23:03:29 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:29 22d0024r volumio[1211]: info: sendMpdCommand stop took 6 milliseconds Oct 12 23:03:29 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::play index undefined Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 23:03:29 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::startPlaybackTimer Oct 12 23:03:29 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::clearAddPlayTracks USB/6To_WDC_Elements/Musique/[BO] John Carpenter/12 Dark Star.mp3 Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::updateTrackBlock Oct 12 23:03:29 22d0024r volumio[1211]: info: CorePlayQueue::getTrackBlock Oct 12 23:03:29 22d0024r volumio[1211]: info: Oct 12 23:03:29 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:29 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:29 22d0024r volumio[1211]: info: Oct 12 23:03:29 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:29 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:29 22d0024r volumio[1211]: info: sendMpdCommand status took 3 milliseconds Oct 12 23:03:29 22d0024r volumio[1211]: info: sendMpdCommand stop took 2 milliseconds Oct 12 23:03:29 22d0024r volumio[1211]: info: sendMpdCommand status took 1 milliseconds Oct 12 23:03:29 22d0024r volumio[1211]: info: sendMpdCommand status took 1 milliseconds Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand clear Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:29 22d0024r volumio[1211]: info: Oct 12 23:03:29 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:29 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:29 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 12 23:03:29 22d0024r volumio[1211]: info: sendMpdCommand clear took 2 milliseconds Oct 12 23:03:29 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 12 23:03:29 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand add "USB/6To_WDC_Elements/Musique/[BO] John Carpenter/12 Dark Star.mp3" Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:29 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:29 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:29 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:29 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Starman","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/11 Starman.mp3","trackType":"mp3"} Oct 12 23:03:29 22d0024r volumio[1211]: verbose: CURRENT POSITION 11 Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService stop Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus stop Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:29 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:29 22d0024r volumio[1211]: info: No code Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:29 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:29 22d0024r volumio[1211]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 12 23:03:29 22d0024r volumio[1211]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 12 23:03:29 22d0024r volumio[1211]: info: ------------------------------ 26ms Oct 12 23:03:29 22d0024r volumio[1211]: info: ------------------------------ 25ms Oct 12 23:03:29 22d0024r volumio[1211]: info: ------------------------------ 24ms Oct 12 23:03:29 22d0024r volumio[1211]: info: Oct 12 23:03:29 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:29 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:29 22d0024r volumio[1211]: info: Oct 12 23:03:29 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:29 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:29 22d0024r volumio[1211]: error: updateQueue error: null Oct 12 23:03:29 22d0024r volumio[1211]: info: Oct 12 23:03:29 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:29 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:29 22d0024r volumio[1211]: info: ------------------------------ 23ms Oct 12 23:03:29 22d0024r volumio[1211]: info: sendMpdCommand add "USB/6To_WDC_Elements/Musique/[BO] John Carpenter/12 Dark Star.mp3" took 22 milliseconds Oct 12 23:03:29 22d0024r volumio[1211]: info: ------------------------------ 2ms Oct 12 23:03:29 22d0024r volumio[1211]: info: ------------------------------ 2ms Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand play Oct 12 23:03:29 22d0024r volumio[1211]: info: Oct 12 23:03:29 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:29 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:29 22d0024r volumio[1211]: info: Oct 12 23:03:29 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:29 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:29 22d0024r volumio[1211]: info: ------------------------------ 9ms Oct 12 23:03:29 22d0024r volumio[1211]: info: sendMpdCommand play took 8 milliseconds Oct 12 23:03:29 22d0024r volumio[1211]: info: ------------------------------ 8ms Oct 12 23:03:29 22d0024r volumio[1211]: info: ------------------------------ 8ms Oct 12 23:03:29 22d0024r volumio[1211]: info: Oct 12 23:03:29 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:29 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:29 22d0024r volumio[1211]: info: Oct 12 23:03:29 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:29 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:29 22d0024r volumio[1211]: info: Oct 12 23:03:29 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:29 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:29 22d0024r volumio[1211]: info: sendMpdCommand status took 5 milliseconds Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:29 22d0024r volumio[1211]: info: Oct 12 23:03:29 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:29 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:29 22d0024r volumio[1211]: info: Oct 12 23:03:29 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:29 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:29 22d0024r volumio[1211]: info: sendMpdCommand status took 6 milliseconds Oct 12 23:03:29 22d0024r volumio[1211]: info: sendMpdCommand status took 5 milliseconds Oct 12 23:03:29 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:29 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:29 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:29 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":88,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dark Star","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/12 Dark Star.mp3","trackType":"mp3"} Oct 12 23:03:29 22d0024r volumio[1211]: verbose: CURRENT POSITION 11 Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus stop Oct 12 23:03:29 22d0024r volumio[1211]: info: ------------------------------ 9ms Oct 12 23:03:29 22d0024r volumio[1211]: info: Oct 12 23:03:29 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:29 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:29 22d0024r volumio[1211]: info: sendMpdCommand status took 4 milliseconds Oct 12 23:03:29 22d0024r volumio[1211]: info: sendMpdCommand status took 3 milliseconds Oct 12 23:03:29 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 12 23:03:29 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 12 23:03:29 22d0024r volumio[1211]: info: sendMpdCommand status took 1 milliseconds Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:29 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:29 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:29 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":88,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dark Star","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/12 Dark Star.mp3","trackType":"mp3"} Oct 12 23:03:29 22d0024r volumio[1211]: verbose: CURRENT POSITION 11 Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:03:29 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:29 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:29 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:29 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:29 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:29 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":88,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dark Star","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/12 Dark Star.mp3","trackType":"mp3"} Oct 12 23:03:29 22d0024r volumio[1211]: verbose: CURRENT POSITION 11 Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:03:29 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:29 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:29 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:29 22d0024r volumio[1211]: info: ------------------------------ 39ms Oct 12 23:03:29 22d0024r volumio[1211]: info: ------------------------------ 39ms Oct 12 23:03:29 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 30 milliseconds Oct 12 23:03:29 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 30 milliseconds Oct 12 23:03:29 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 29 milliseconds Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:29 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:29 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:29 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:29 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":88,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dark Star","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/12 Dark Star.mp3","trackType":"mp3"} Oct 12 23:03:29 22d0024r volumio[1211]: verbose: CURRENT POSITION 11 Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:03:29 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:03:29 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:30 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:30 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:30 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:30 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:30 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:30 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:30 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:30 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:30 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":968,"duration":88,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dark Star","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/12 Dark Star.mp3","trackType":"mp3"} Oct 12 23:03:30 22d0024r volumio[1211]: verbose: CURRENT POSITION 11 Oct 12 23:03:30 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:30 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:03:30 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:03:30 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:30 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:30 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 23:03:30 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:30 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:30 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:30 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:30 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:30 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:30 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:30 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":968,"duration":88,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dark Star","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/12 Dark Star.mp3","trackType":"mp3"} Oct 12 23:03:30 22d0024r volumio[1211]: verbose: CURRENT POSITION 11 Oct 12 23:03:30 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:30 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:03:30 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:03:30 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:30 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:30 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:30 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:30 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:30 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:30 22d0024r volumio[1211]: info: ------------------------------ 73ms Oct 12 23:03:30 22d0024r volumio[1211]: info: ------------------------------ 74ms Oct 12 23:03:30 22d0024r volumio[1211]: info: ------------------------------ 71ms Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPrevious Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::previous Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: ControllerMpd::seek Oct 12 23:03:33 22d0024r volumio[1211]: error: null Oct 12 23:03:33 22d0024r volumio[1211]: info: Oct 12 23:03:33 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:33 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:33 22d0024r volumio[1211]: info: Oct 12 23:03:33 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:33 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:33 22d0024r volumio[1211]: info: Oct 12 23:03:33 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:33 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:33 22d0024r volumio[1211]: info: sendMpdCommand status took 3 milliseconds Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:33 22d0024r volumio[1211]: info: sendMpdCommand status took 4 milliseconds Oct 12 23:03:33 22d0024r volumio[1211]: info: sendMpdCommand status took 3 milliseconds Oct 12 23:03:33 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:33 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":88,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dark Star","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/12 Dark Star.mp3","trackType":"mp3"} Oct 12 23:03:33 22d0024r volumio[1211]: verbose: CURRENT POSITION 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:03:33 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:33 22d0024r volumio[1211]: info: ------------------------------ 23ms Oct 12 23:03:33 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 12 23:03:33 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:33 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":88,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dark Star","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/12 Dark Star.mp3","trackType":"mp3"} Oct 12 23:03:33 22d0024r volumio[1211]: verbose: CURRENT POSITION 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:03:33 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:33 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":88,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dark Star","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/12 Dark Star.mp3","trackType":"mp3"} Oct 12 23:03:33 22d0024r volumio[1211]: verbose: CURRENT POSITION 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:03:33 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:33 22d0024r volumio[1211]: info: ------------------------------ 51ms Oct 12 23:03:33 22d0024r volumio[1211]: info: ------------------------------ 50ms Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPrevious Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::previous Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::stop Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::stPlaybackTimer Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::updateTrackBlock Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrackBlock Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::serviceStop Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::serviceStop Oct 12 23:03:33 22d0024r volumio[1211]: info: ControllerMpd::stop Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 23:03:33 22d0024r volumio[1211]: info: Oct 12 23:03:33 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:33 22d0024r volumio[1211]: info: sendMpdCommand stop took 6 milliseconds Oct 12 23:03:33 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:33 22d0024r volumio[1211]: info: Oct 12 23:03:33 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:33 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:33 22d0024r volumio[1211]: info: Oct 12 23:03:33 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:33 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:33 22d0024r volumio[1211]: info: sendMpdCommand status took 2 milliseconds Oct 12 23:03:33 22d0024r volumio[1211]: info: sendMpdCommand status took 1 milliseconds Oct 12 23:03:33 22d0024r volumio[1211]: info: sendMpdCommand status took 1 milliseconds Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:33 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 23:03:33 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:33 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dark Star","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/12 Dark Star.mp3","trackType":"mp3"} Oct 12 23:03:33 22d0024r volumio[1211]: verbose: CURRENT POSITION 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService stop Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus stop Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:33 22d0024r volumio[1211]: info: No code Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:33 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dark Star","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/12 Dark Star.mp3","trackType":"mp3"} Oct 12 23:03:33 22d0024r volumio[1211]: verbose: CURRENT POSITION 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService stop Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus stop Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:33 22d0024r volumio[1211]: info: No code Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:33 22d0024r volumio[1211]: info: ------------------------------ 36ms Oct 12 23:03:33 22d0024r volumio[1211]: info: ------------------------------ 36ms Oct 12 23:03:33 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 36 milliseconds Oct 12 23:03:33 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:33 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dark Star","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/12 Dark Star.mp3","trackType":"mp3"} Oct 12 23:03:33 22d0024r volumio[1211]: verbose: CURRENT POSITION 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService stop Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus stop Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:33 22d0024r volumio[1211]: info: No code Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:33 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 11 Oct 12 23:03:33 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:33 22d0024r volumio[1211]: info: ------------------------------ 56ms Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::play index undefined Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 23:03:34 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::startPlaybackTimer Oct 12 23:03:34 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::clearAddPlayTracks USB/6To_WDC_Elements/Musique/[BO] John Carpenter/11 Starman.mp3 Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 23:03:34 22d0024r volumio[1211]: info: sendMpdCommand stop took 1 milliseconds Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand clear Oct 12 23:03:34 22d0024r volumio[1211]: info: Oct 12 23:03:34 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:34 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:34 22d0024r volumio[1211]: info: sendMpdCommand clear took 0 milliseconds Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand add "USB/6To_WDC_Elements/Musique/[BO] John Carpenter/11 Starman.mp3" Oct 12 23:03:34 22d0024r volumio[1211]: info: Oct 12 23:03:34 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:34 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:34 22d0024r volumio[1211]: info: Oct 12 23:03:34 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:34 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:34 22d0024r volumio[1211]: error: updateQueue error: null Oct 12 23:03:34 22d0024r volumio[1211]: info: Oct 12 23:03:34 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:34 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:34 22d0024r volumio[1211]: info: ------------------------------ 2ms Oct 12 23:03:34 22d0024r volumio[1211]: info: sendMpdCommand add "USB/6To_WDC_Elements/Musique/[BO] John Carpenter/11 Starman.mp3" took 2 milliseconds Oct 12 23:03:34 22d0024r volumio[1211]: info: ------------------------------ 2ms Oct 12 23:03:34 22d0024r volumio[1211]: info: ------------------------------ 2ms Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand play Oct 12 23:03:34 22d0024r volumio[1211]: info: Oct 12 23:03:34 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:34 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:34 22d0024r volumio[1211]: info: Oct 12 23:03:34 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:34 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:34 22d0024r volumio[1211]: info: ------------------------------ 3ms Oct 12 23:03:34 22d0024r volumio[1211]: info: sendMpdCommand play took 2 milliseconds Oct 12 23:03:34 22d0024r volumio[1211]: info: ------------------------------ 2ms Oct 12 23:03:34 22d0024r volumio[1211]: info: ------------------------------ 1ms Oct 12 23:03:34 22d0024r volumio[1211]: info: Oct 12 23:03:34 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:34 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:34 22d0024r volumio[1211]: info: Oct 12 23:03:34 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:34 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:34 22d0024r volumio[1211]: info: Oct 12 23:03:34 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:34 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:34 22d0024r volumio[1211]: info: Oct 12 23:03:34 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:34 22d0024r volumio[1211]: info: sendMpdCommand status took 3 milliseconds Oct 12 23:03:34 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:34 22d0024r volumio[1211]: info: sendMpdCommand status took 3 milliseconds Oct 12 23:03:34 22d0024r volumio[1211]: info: sendMpdCommand status took 2 milliseconds Oct 12 23:03:34 22d0024r volumio[1211]: info: sendMpdCommand status took 1 milliseconds Oct 12 23:03:34 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:34 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:34 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":156,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Starman","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/11 Starman.mp3","trackType":"mp3"} Oct 12 23:03:34 22d0024r volumio[1211]: verbose: CURRENT POSITION 10 Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus stop Oct 12 23:03:34 22d0024r volumio[1211]: info: ------------------------------ 6ms Oct 12 23:03:34 22d0024r volumio[1211]: info: Oct 12 23:03:34 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:34 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:34 22d0024r volumio[1211]: info: Oct 12 23:03:34 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:34 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:34 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 12 23:03:34 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 12 23:03:34 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 12 23:03:34 22d0024r volumio[1211]: info: sendMpdCommand status took 2 milliseconds Oct 12 23:03:34 22d0024r volumio[1211]: info: sendMpdCommand status took 1 milliseconds Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:34 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:34 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":156,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Starman","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/11 Starman.mp3","trackType":"mp3"} Oct 12 23:03:34 22d0024r volumio[1211]: verbose: CURRENT POSITION 10 Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:03:34 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:34 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:34 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":156,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Starman","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/11 Starman.mp3","trackType":"mp3"} Oct 12 23:03:34 22d0024r volumio[1211]: verbose: CURRENT POSITION 10 Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:03:34 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:34 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:34 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":156,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Starman","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/11 Starman.mp3","trackType":"mp3"} Oct 12 23:03:34 22d0024r volumio[1211]: verbose: CURRENT POSITION 10 Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:03:34 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:34 22d0024r volumio[1211]: info: ------------------------------ 44ms Oct 12 23:03:34 22d0024r volumio[1211]: info: ------------------------------ 44ms Oct 12 23:03:34 22d0024r volumio[1211]: info: ------------------------------ 44ms Oct 12 23:03:34 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 12 23:03:34 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:34 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:34 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:34 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":156,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Starman","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/11 Starman.mp3","trackType":"mp3"} Oct 12 23:03:34 22d0024r volumio[1211]: verbose: CURRENT POSITION 10 Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:03:34 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:34 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:34 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":156,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Starman","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/11 Starman.mp3","trackType":"mp3"} Oct 12 23:03:34 22d0024r volumio[1211]: verbose: CURRENT POSITION 10 Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:03:34 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:34 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:34 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:34 22d0024r volumio[1211]: info: ------------------------------ 64ms Oct 12 23:03:34 22d0024r volumio[1211]: info: ------------------------------ 64ms Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPrevious Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::previous Oct 12 23:03:35 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::stop Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::stPlaybackTimer Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::updateTrackBlock Oct 12 23:03:35 22d0024r volumio[1211]: info: CorePlayQueue::getTrackBlock Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:35 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::serviceStop Oct 12 23:03:35 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreCommandRouter::serviceStop Oct 12 23:03:35 22d0024r volumio[1211]: info: ControllerMpd::stop Oct 12 23:03:35 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 23:03:35 22d0024r volumio[1211]: info: Oct 12 23:03:35 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:35 22d0024r volumio[1211]: info: sendMpdCommand stop took 6 milliseconds Oct 12 23:03:35 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:35 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:35 22d0024r volumio[1211]: info: Oct 12 23:03:35 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:35 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:35 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:35 22d0024r volumio[1211]: info: Oct 12 23:03:35 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:35 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:35 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:35 22d0024r volumio[1211]: info: sendMpdCommand status took 2 milliseconds Oct 12 23:03:35 22d0024r volumio[1211]: info: sendMpdCommand status took 1 milliseconds Oct 12 23:03:35 22d0024r volumio[1211]: info: sendMpdCommand status took 1 milliseconds Oct 12 23:03:35 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:35 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:35 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:35 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:35 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:35 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:35 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 23:03:35 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 23:03:35 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:35 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:35 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:35 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:35 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:35 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Starman","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/11 Starman.mp3","trackType":"mp3"} Oct 12 23:03:35 22d0024r volumio[1211]: verbose: CURRENT POSITION 10 Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService stop Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus stop Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:35 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:35 22d0024r volumio[1211]: info: No code Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:35 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:35 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:35 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:35 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:35 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Starman","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/11 Starman.mp3","trackType":"mp3"} Oct 12 23:03:35 22d0024r volumio[1211]: verbose: CURRENT POSITION 10 Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService stop Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus stop Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:35 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:35 22d0024r volumio[1211]: info: No code Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:35 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:35 22d0024r volumio[1211]: info: ------------------------------ 33ms Oct 12 23:03:35 22d0024r volumio[1211]: info: ------------------------------ 33ms Oct 12 23:03:35 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 32 milliseconds Oct 12 23:03:35 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:35 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:35 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:35 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:35 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Starman","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/11 Starman.mp3","trackType":"mp3"} Oct 12 23:03:35 22d0024r volumio[1211]: verbose: CURRENT POSITION 10 Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService stop Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus stop Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:35 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:35 22d0024r volumio[1211]: info: No code Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:35 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 10 Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:35 22d0024r volumio[1211]: info: ------------------------------ 43ms Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::play index undefined Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 23:03:35 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:35 22d0024r volumio[1211]: info: CoreStateMachine::startPlaybackTimer Oct 12 23:03:35 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:35 22d0024r volumio[1211]: verbose: ControllerMpd::clearAddPlayTracks USB/6To_WDC_Elements/Musique/[BO] John Carpenter/10 The Thing.mp3 Oct 12 23:03:35 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 23:03:35 22d0024r volumio[1211]: info: sendMpdCommand stop took 1 milliseconds Oct 12 23:03:35 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand clear Oct 12 23:03:35 22d0024r volumio[1211]: info: Oct 12 23:03:35 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:35 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:35 22d0024r volumio[1211]: info: sendMpdCommand clear took 1 milliseconds Oct 12 23:03:35 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand add "USB/6To_WDC_Elements/Musique/[BO] John Carpenter/10 The Thing.mp3" Oct 12 23:03:35 22d0024r volumio[1211]: info: Oct 12 23:03:35 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:35 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:35 22d0024r volumio[1211]: info: Oct 12 23:03:35 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:35 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:35 22d0024r volumio[1211]: info: Oct 12 23:03:35 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:35 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:35 22d0024r volumio[1211]: info: Oct 12 23:03:35 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:35 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:35 22d0024r volumio[1211]: error: updateQueue error: null Oct 12 23:03:35 22d0024r volumio[1211]: info: Oct 12 23:03:35 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:35 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:35 22d0024r volumio[1211]: info: ------------------------------ 3ms Oct 12 23:03:35 22d0024r volumio[1211]: info: sendMpdCommand add "USB/6To_WDC_Elements/Musique/[BO] John Carpenter/10 The Thing.mp3" took 3 milliseconds Oct 12 23:03:35 22d0024r volumio[1211]: info: ------------------------------ 3ms Oct 12 23:03:35 22d0024r volumio[1211]: info: ------------------------------ 2ms Oct 12 23:03:35 22d0024r volumio[1211]: info: ------------------------------ 2ms Oct 12 23:03:35 22d0024r volumio[1211]: info: ------------------------------ 1ms Oct 12 23:03:35 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand play Oct 12 23:03:35 22d0024r volumio[1211]: info: ------------------------------ 2ms Oct 12 23:03:35 22d0024r volumio[1211]: info: sendMpdCommand play took 1 milliseconds Oct 12 23:03:36 22d0024r volumio[1211]: info: Oct 12 23:03:36 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:36 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:36 22d0024r volumio[1211]: info: Oct 12 23:03:36 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:36 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:36 22d0024r volumio[1211]: info: Oct 12 23:03:36 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:36 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:36 22d0024r volumio[1211]: info: sendMpdCommand status took 2 milliseconds Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:36 22d0024r volumio[1211]: info: Oct 12 23:03:36 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:36 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:36 22d0024r volumio[1211]: info: Oct 12 23:03:36 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:36 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:36 22d0024r volumio[1211]: info: sendMpdCommand status took 4 milliseconds Oct 12 23:03:36 22d0024r volumio[1211]: info: sendMpdCommand status took 3 milliseconds Oct 12 23:03:36 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:36 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:36 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Thing","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/10 The Thing.mp3","trackType":"mp3"} Oct 12 23:03:36 22d0024r volumio[1211]: verbose: CURRENT POSITION 9 Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus stop Oct 12 23:03:36 22d0024r volumio[1211]: info: ------------------------------ 7ms Oct 12 23:03:36 22d0024r volumio[1211]: info: Oct 12 23:03:36 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:36 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:36 22d0024r volumio[1211]: info: sendMpdCommand status took 4 milliseconds Oct 12 23:03:36 22d0024r volumio[1211]: info: sendMpdCommand status took 3 milliseconds Oct 12 23:03:36 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 12 23:03:36 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 12 23:03:36 22d0024r volumio[1211]: info: sendMpdCommand status took 2 milliseconds Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:36 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:36 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Thing","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/10 The Thing.mp3","trackType":"mp3"} Oct 12 23:03:36 22d0024r volumio[1211]: verbose: CURRENT POSITION 9 Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:03:36 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:36 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:36 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Thing","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/10 The Thing.mp3","trackType":"mp3"} Oct 12 23:03:36 22d0024r volumio[1211]: verbose: CURRENT POSITION 9 Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:03:36 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:36 22d0024r volumio[1211]: info: ------------------------------ 17ms Oct 12 23:03:36 22d0024r volumio[1211]: info: ------------------------------ 17ms Oct 12 23:03:36 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 12 23:03:36 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 12 23:03:36 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:36 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:36 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:36 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Thing","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/10 The Thing.mp3","trackType":"mp3"} Oct 12 23:03:36 22d0024r volumio[1211]: verbose: CURRENT POSITION 9 Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:03:36 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:36 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:36 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Thing","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/10 The Thing.mp3","trackType":"mp3"} Oct 12 23:03:36 22d0024r volumio[1211]: verbose: CURRENT POSITION 9 Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:03:36 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:36 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:36 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Thing","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/10 The Thing.mp3","trackType":"mp3"} Oct 12 23:03:36 22d0024r volumio[1211]: verbose: CURRENT POSITION 9 Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:03:36 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:36 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:36 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:36 22d0024r volumio[1211]: info: ------------------------------ 29ms Oct 12 23:03:36 22d0024r volumio[1211]: info: ------------------------------ 29ms Oct 12 23:03:36 22d0024r volumio[1211]: info: ------------------------------ 27ms Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPrevious Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreStateMachine::previous Oct 12 23:03:37 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:37 22d0024r volumio[1211]: info: ControllerMpd::seek Oct 12 23:03:37 22d0024r volumio[1211]: error: null Oct 12 23:03:37 22d0024r volumio[1211]: info: Oct 12 23:03:37 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:37 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:37 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:37 22d0024r volumio[1211]: info: Oct 12 23:03:37 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:37 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:37 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:37 22d0024r volumio[1211]: info: Oct 12 23:03:37 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:37 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:37 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:03:37 22d0024r volumio[1211]: info: sendMpdCommand status took 4 milliseconds Oct 12 23:03:37 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:37 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:37 22d0024r volumio[1211]: info: sendMpdCommand status took 35 milliseconds Oct 12 23:03:37 22d0024r volumio[1211]: info: sendMpdCommand status took 34 milliseconds Oct 12 23:03:37 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:37 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:37 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:37 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:37 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 40 milliseconds Oct 12 23:03:37 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 12 23:03:37 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 12 23:03:37 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:37 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:37 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:37 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:37 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:37 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Thing","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/10 The Thing.mp3","trackType":"mp3"} Oct 12 23:03:37 22d0024r volumio[1211]: verbose: CURRENT POSITION 9 Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:03:37 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:37 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:37 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:37 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:37 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:37 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Thing","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/10 The Thing.mp3","trackType":"mp3"} Oct 12 23:03:37 22d0024r volumio[1211]: verbose: CURRENT POSITION 9 Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:03:37 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:37 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:37 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:37 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:37 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:37 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Thing","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/10 The Thing.mp3","trackType":"mp3"} Oct 12 23:03:37 22d0024r volumio[1211]: verbose: CURRENT POSITION 9 Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:03:37 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:37 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:37 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:37 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:37 22d0024r volumio[1211]: info: ------------------------------ 60ms Oct 12 23:03:37 22d0024r volumio[1211]: info: ------------------------------ 59ms Oct 12 23:03:37 22d0024r volumio[1211]: info: ------------------------------ 59ms Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPrevious Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreStateMachine::previous Oct 12 23:03:39 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreStateMachine::stop Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreStateMachine::stPlaybackTimer Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreStateMachine::updateTrackBlock Oct 12 23:03:39 22d0024r volumio[1211]: info: CorePlayQueue::getTrackBlock Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:39 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreStateMachine::serviceStop Oct 12 23:03:39 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreCommandRouter::serviceStop Oct 12 23:03:39 22d0024r volumio[1211]: info: ControllerMpd::stop Oct 12 23:03:39 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 23:03:39 22d0024r volumio[1211]: info: Oct 12 23:03:39 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:39 22d0024r volumio[1211]: info: sendMpdCommand stop took 4 milliseconds Oct 12 23:03:39 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:39 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:39 22d0024r volumio[1211]: info: Oct 12 23:03:39 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:39 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:39 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:39 22d0024r volumio[1211]: info: Oct 12 23:03:39 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:39 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:39 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:39 22d0024r volumio[1211]: info: sendMpdCommand status took 4 milliseconds Oct 12 23:03:39 22d0024r volumio[1211]: info: sendMpdCommand status took 2 milliseconds Oct 12 23:03:39 22d0024r volumio[1211]: info: sendMpdCommand status took 1 milliseconds Oct 12 23:03:39 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:39 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:39 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:39 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:39 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:39 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:39 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 23:03:39 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 23:03:39 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 23:03:39 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:39 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:39 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:39 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:39 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:39 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:39 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Thing","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/10 The Thing.mp3","trackType":"mp3"} Oct 12 23:03:39 22d0024r volumio[1211]: verbose: CURRENT POSITION 9 Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService stop Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus stop Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:39 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:39 22d0024r volumio[1211]: info: No code Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:39 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:39 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:39 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:39 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:39 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Thing","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/10 The Thing.mp3","trackType":"mp3"} Oct 12 23:03:39 22d0024r volumio[1211]: verbose: CURRENT POSITION 9 Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService stop Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus stop Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:39 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:39 22d0024r volumio[1211]: info: No code Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:39 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:39 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:39 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:39 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:39 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Thing","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/10 The Thing.mp3","trackType":"mp3"} Oct 12 23:03:39 22d0024r volumio[1211]: verbose: CURRENT POSITION 9 Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService stop Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus stop Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:39 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:39 22d0024r volumio[1211]: info: No code Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:39 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 9 Oct 12 23:03:39 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:39 22d0024r volumio[1211]: info: ------------------------------ 22ms Oct 12 23:03:39 22d0024r volumio[1211]: info: ------------------------------ 20ms Oct 12 23:03:39 22d0024r volumio[1211]: info: ------------------------------ 20ms Oct 12 23:03:40 22d0024r volumio[1211]: info: CoreStateMachine::play index undefined Oct 12 23:03:40 22d0024r volumio[1211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 23:03:40 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 8 Oct 12 23:03:40 22d0024r volumio[1211]: info: CoreStateMachine::startPlaybackTimer Oct 12 23:03:40 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 8 Oct 12 23:03:40 22d0024r volumio[1211]: verbose: ControllerMpd::clearAddPlayTracks USB/6To_WDC_Elements/Musique/[BO] John Carpenter/09 They Live.mp3 Oct 12 23:03:40 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 23:03:40 22d0024r volumio[1211]: info: sendMpdCommand stop took 1 milliseconds Oct 12 23:03:40 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand clear Oct 12 23:03:40 22d0024r volumio[1211]: info: Oct 12 23:03:40 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:40 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:40 22d0024r volumio[1211]: info: sendMpdCommand clear took 1 milliseconds Oct 12 23:03:40 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand add "USB/6To_WDC_Elements/Musique/[BO] John Carpenter/09 They Live.mp3" Oct 12 23:03:40 22d0024r volumio[1211]: info: Oct 12 23:03:40 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:40 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:40 22d0024r volumio[1211]: info: Oct 12 23:03:40 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:40 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:40 22d0024r volumio[1211]: error: updateQueue error: null Oct 12 23:03:40 22d0024r volumio[1211]: info: Oct 12 23:03:40 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:40 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:40 22d0024r volumio[1211]: info: ------------------------------ 4ms Oct 12 23:03:40 22d0024r volumio[1211]: info: sendMpdCommand add "USB/6To_WDC_Elements/Musique/[BO] John Carpenter/09 They Live.mp3" took 4 milliseconds Oct 12 23:03:40 22d0024r volumio[1211]: info: ------------------------------ 3ms Oct 12 23:03:40 22d0024r volumio[1211]: info: ------------------------------ 3ms Oct 12 23:03:40 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand play Oct 12 23:03:40 22d0024r volumio[1211]: info: Oct 12 23:03:40 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:40 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:40 22d0024r volumio[1211]: info: Oct 12 23:03:40 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:03:40 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:03:40 22d0024r volumio[1211]: info: ------------------------------ 4ms Oct 12 23:03:40 22d0024r volumio[1211]: info: sendMpdCommand play took 3 milliseconds Oct 12 23:03:40 22d0024r volumio[1211]: info: ------------------------------ 2ms Oct 12 23:03:40 22d0024r volumio[1211]: info: ------------------------------ 1ms Oct 12 23:03:40 22d0024r volumio[1211]: info: Oct 12 23:03:40 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:40 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:40 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:40 22d0024r volumio[1211]: info: Oct 12 23:03:40 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:40 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:40 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:40 22d0024r volumio[1211]: info: Oct 12 23:03:40 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:03:40 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:03:40 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:03:40 22d0024r volumio[1211]: info: sendMpdCommand status took 3 milliseconds Oct 12 23:03:40 22d0024r volumio[1211]: info: sendMpdCommand status took 3 milliseconds Oct 12 23:03:40 22d0024r volumio[1211]: info: sendMpdCommand status took 2 milliseconds Oct 12 23:03:40 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:40 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:40 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:40 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:40 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:03:40 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:03:40 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 12 23:03:40 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 12 23:03:40 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 12 23:03:40 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:40 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:40 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:03:40 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:40 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:40 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 8 Oct 12 23:03:40 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"They Live","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/09 They Live.mp3","trackType":"mp3"} Oct 12 23:03:40 22d0024r volumio[1211]: verbose: CURRENT POSITION 8 Oct 12 23:03:40 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:40 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus stop Oct 12 23:03:40 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:40 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:40 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 8 Oct 12 23:03:40 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"They Live","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/09 They Live.mp3","trackType":"mp3"} Oct 12 23:03:40 22d0024r volumio[1211]: verbose: CURRENT POSITION 8 Oct 12 23:03:40 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:40 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:03:40 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:03:40 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:40 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 8 Oct 12 23:03:40 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 23:03:40 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:40 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:40 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 8 Oct 12 23:03:40 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:40 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:03:40 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:03:40 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 8 Oct 12 23:03:40 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"They Live","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/09 They Live.mp3","trackType":"mp3"} Oct 12 23:03:40 22d0024r volumio[1211]: verbose: CURRENT POSITION 8 Oct 12 23:03:40 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:03:40 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:03:40 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:03:40 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:40 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 8 Oct 12 23:03:40 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:40 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:03:40 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 8 Oct 12 23:03:40 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:03:40 22d0024r volumio[1211]: info: ------------------------------ 13ms Oct 12 23:03:40 22d0024r volumio[1211]: info: ------------------------------ 28ms Oct 12 23:03:40 22d0024r volumio[1211]: info: ------------------------------ 29ms Oct 12 23:03:41 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:03:44 22d0024r ntpd[1008]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 12 23:03:44 22d0024r ntpd[1008]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Oct 12 23:03:44 22d0024r ntpd[1008]: DNS: Pool skipping: 103.221.223.143 Oct 12 23:03:44 22d0024r ntpd[1008]: DNS: Pool skipping: 160.22.74.161 Oct 12 23:03:44 22d0024r ntpd[1008]: DNS: Pool skipping: 115.165.161.155 Oct 12 23:03:44 22d0024r ntpd[1008]: DNS: Pool skipping: 103.184.124.254 Oct 12 23:03:44 22d0024r ntpd[1008]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Oct 12 23:03:45 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:03:45 22d0024r ntpd[1008]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 12 23:03:45 22d0024r ntpd[1008]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Oct 12 23:03:45 22d0024r ntpd[1008]: DNS: Pool skipping: 103.221.223.143 Oct 12 23:03:45 22d0024r ntpd[1008]: DNS: Pool skipping: 160.22.74.161 Oct 12 23:03:45 22d0024r ntpd[1008]: DNS: Pool skipping: 115.165.161.155 Oct 12 23:03:45 22d0024r ntpd[1008]: DNS: Pool skipping: 103.184.124.254 Oct 12 23:03:45 22d0024r ntpd[1008]: DNS: Pool skipping: 2001:df4:bac0::e35b:f223 Oct 12 23:03:45 22d0024r ntpd[1008]: DNS: Pool skipping: 2001:df7:c600:13:f816:3eff:fe1d:b3b5 Oct 12 23:03:45 22d0024r ntpd[1008]: DNS: Pool taking: 2400:e920:0:5::14 Oct 12 23:03:45 22d0024r ntpd[1008]: DNS: Pool skipping: 2400:d760:0:ff09::123 Oct 12 23:03:45 22d0024r ntpd[1008]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Oct 12 23:03:46 22d0024r ntpd[1008]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 12 23:03:46 22d0024r ntpd[1008]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Oct 12 23:03:46 22d0024r ntpd[1008]: DNS: Pool skipping: 103.221.223.143 Oct 12 23:03:46 22d0024r ntpd[1008]: DNS: Pool skipping: 160.22.74.161 Oct 12 23:03:46 22d0024r ntpd[1008]: DNS: Pool skipping: 115.165.161.155 Oct 12 23:03:46 22d0024r ntpd[1008]: DNS: Pool skipping: 103.184.124.254 Oct 12 23:03:46 22d0024r ntpd[1008]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Oct 12 23:03:47 22d0024r ntpd[1008]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 12 23:03:47 22d0024r ntpd[1008]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Oct 12 23:03:47 22d0024r ntpd[1008]: DNS: Pool skipping: 103.221.223.143 Oct 12 23:03:47 22d0024r ntpd[1008]: DNS: Pool skipping: 160.22.74.161 Oct 12 23:03:47 22d0024r ntpd[1008]: DNS: Pool skipping: 103.184.124.254 Oct 12 23:03:47 22d0024r ntpd[1008]: DNS: Pool skipping: 115.165.161.155 Oct 12 23:03:47 22d0024r ntpd[1008]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Oct 12 23:03:49 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:03:54 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:03:57 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:04:01 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:04:05 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:04:09 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:04:11 22d0024r volumio[1211]: info: Preload queue cleared Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::ClearQueue Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::stop Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::stPlaybackTimer Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::updateTrackBlock Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrackBlock Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 8 Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::serviceStop Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 8 Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::serviceStop Oct 12 23:04:11 22d0024r volumio[1211]: info: ControllerMpd::stop Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::clearPlayQueue Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::saveQueue Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushQueue Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::addQueueItems Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::addQueueItems Oct 12 23:04:11 22d0024r volumio[1211]: info: Preload queue cleared Oct 12 23:04:11 22d0024r volumio[1211]: info: Adding Item to queue: music-library/USB/6To_WDC_Elements/Musique/[BO] Outland Oct 12 23:04:11 22d0024r volumio[1211]: info: Exploding uri music-library/USB/6To_WDC_Elements/Musique/[BO] Outland in service mpd Oct 12 23:04:11 22d0024r volumio[1211]: info: sendMpdCommand stop took 222 milliseconds Oct 12 23:04:11 22d0024r volumio[1211]: info: Oct 12 23:04:11 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:04:11 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:04:11 22d0024r volumio[1211]: info: Oct 12 23:04:11 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:04:11 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:04:11 22d0024r volumio[1211]: info: Oct 12 23:04:11 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:04:11 22d0024r volumio[1211]: info: ALBUMART /albumart?cacheid=663&web=Jerry%20Glodsmith/Outland%2C%20remastered/extralarge&path=%2Fmnt%2FUSB%2F6To_WDC_Elements%2FMusique%2F%5BBO%5D%20Outland%2F(01)%20%5BJerry%20Glodsmith%5D%20The%20Mine.ogg&metadata=false Oct 12 23:04:11 22d0024r volumio[1211]: info: URI /mnt/USB/6To_WDC_Elements/Musique/[BO] Outland/(01) [Jerry Glodsmith] The Mine.ogg Oct 12 23:04:11 22d0024r volumio[1211]: info: ALBUMART /albumart?cacheid=663&web=Jerry%20Glodsmith/Outland%2C%20remastered/extralarge&path=%2Fmnt%2FUSB%2F6To_WDC_Elements%2FMusique%2F%5BBO%5D%20Outland%2F(02)%20%5BJerry%20Glodsmith%5D%20Eral%20Arrival.ogg&metadata=false Oct 12 23:04:11 22d0024r volumio[1211]: info: URI /mnt/USB/6To_WDC_Elements/Musique/[BO] Outland/(02) [Jerry Glodsmith] Eral Arrival.ogg Oct 12 23:04:11 22d0024r volumio[1211]: info: ALBUMART /albumart?cacheid=663&web=Jerry%20Glodsmith/Outland%2C%20remastered/extralarge&path=%2Fmnt%2FUSB%2F6To_WDC_Elements%2FMusique%2F%5BBO%5D%20Outland%2F(03)%20%5BJerry%20Glodsmith%5D%20The%20Message.ogg&metadata=false Oct 12 23:04:11 22d0024r volumio[1211]: info: URI /mnt/USB/6To_WDC_Elements/Musique/[BO] Outland/(03) [Jerry Glodsmith] The Message.ogg Oct 12 23:04:11 22d0024r volumio[1211]: info: ALBUMART /albumart?cacheid=663&web=Jerry%20Glodsmith/Outland%2C%20remastered/extralarge&path=%2Fmnt%2FUSB%2F6To_WDC_Elements%2FMusique%2F%5BBO%5D%20Outland%2F(04)%20%5BJerry%20Glodsmith%5D%20The%20Air%20Lock.ogg&metadata=false Oct 12 23:04:11 22d0024r volumio[1211]: info: URI /mnt/USB/6To_WDC_Elements/Musique/[BO] Outland/(04) [Jerry Glodsmith] The Air Lock.ogg Oct 12 23:04:11 22d0024r volumio[1211]: info: ALBUMART /albumart?cacheid=663&web=Jerry%20Glodsmith/Outland%2C%20remastered/extralarge&path=%2Fmnt%2FUSB%2F6To_WDC_Elements%2FMusique%2F%5BBO%5D%20Outland%2F(05)%20%5BJerry%20Glodsmith%5D%20Hot%20Water.ogg&metadata=false Oct 12 23:04:11 22d0024r volumio[1211]: info: URI /mnt/USB/6To_WDC_Elements/Musique/[BO] Outland/(05) [Jerry Glodsmith] Hot Water.ogg Oct 12 23:04:11 22d0024r volumio[1211]: info: ALBUMART /albumart?cacheid=663&web=Jerry%20Glodsmith/Outland%2C%20remastered/extralarge&path=%2Fmnt%2FUSB%2F6To_WDC_Elements%2FMusique%2F%5BBO%5D%20Outland%2F(06)%20%5BJerry%20Glodsmith%5D%20The%20Hunted.ogg&metadata=false Oct 12 23:04:11 22d0024r volumio[1211]: info: URI /mnt/USB/6To_WDC_Elements/Musique/[BO] Outland/(06) [Jerry Glodsmith] The Hunted.ogg Oct 12 23:04:11 22d0024r volumio[1211]: info: ALBUMART /albumart?cacheid=663&web=Jerry%20Glodsmith/Outland%2C%20remastered/extralarge&path=%2Fmnt%2FUSB%2F6To_WDC_Elements%2FMusique%2F%5BBO%5D%20Outland%2F(07)%20%5BJerry%20Glodsmith%5D%20Spiders.ogg&metadata=false Oct 12 23:04:11 22d0024r volumio[1211]: info: URI /mnt/USB/6To_WDC_Elements/Musique/[BO] Outland/(07) [Jerry Glodsmith] Spiders.ogg Oct 12 23:04:11 22d0024r volumio[1211]: info: ALBUMART /albumart?cacheid=663&web=Jerry%20Glodsmith/Outland%2C%20remastered/extralarge&path=%2Fmnt%2FUSB%2F6To_WDC_Elements%2FMusique%2F%5BBO%5D%20Outland%2F(08)%20%5BJerry%20Glodsmith%5D%20The%20Rec%20Room.ogg&metadata=false Oct 12 23:04:11 22d0024r volumio[1211]: info: URI /mnt/USB/6To_WDC_Elements/Musique/[BO] Outland/(08) [Jerry Glodsmith] The Rec Room.ogg Oct 12 23:04:11 22d0024r volumio[1211]: info: ALBUMART /albumart?cacheid=663&web=Jerry%20Glodsmith/Outland%2C%20remastered/extralarge&path=%2Fmnt%2FUSB%2F6To_WDC_Elements%2FMusique%2F%5BBO%5D%20Outland%2F(09)%20%5BJerry%20Glodsmith%5D%20The%20Hostage.ogg&metadata=false Oct 12 23:04:11 22d0024r volumio[1211]: info: URI /mnt/USB/6To_WDC_Elements/Musique/[BO] Outland/(09) [Jerry Glodsmith] The Hostage.ogg Oct 12 23:04:11 22d0024r volumio[1211]: info: ALBUMART /albumart?cacheid=663&web=Jerry%20Glodsmith/Outland%2C%20remastered/extralarge&path=%2Fmnt%2FUSB%2F6To_WDC_Elements%2FMusique%2F%5BBO%5D%20Outland%2F(10)%20%5BJerry%20Glodsmith%5D%20Final%20Message.ogg&metadata=false Oct 12 23:04:11 22d0024r volumio[1211]: info: URI /mnt/USB/6To_WDC_Elements/Musique/[BO] Outland/(10) [Jerry Glodsmith] Final Message.ogg Oct 12 23:04:11 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:04:11 22d0024r volumio[1211]: info: sendMpdCommand status took 10 milliseconds Oct 12 23:04:11 22d0024r volumio[1211]: info: sendMpdCommand status took 9 milliseconds Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushQueue Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::saveQueue Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::updateTrackBlock Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrackBlock Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPlay Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::play index 0 Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::stop Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::play index undefined Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 0 Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::startPlaybackTimer Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 0 Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::clearAddPlayTracks USB/6To_WDC_Elements/Musique/[BO] Outland/(01) [Jerry Glodsmith] The Mine.ogg Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 23:04:11 22d0024r volumio[1211]: info: sendMpdCommand status took 9 milliseconds Oct 12 23:04:11 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 12 23:04:11 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 12 23:04:11 22d0024r volumio[1211]: info: sendMpdCommand stop took 2 milliseconds Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand clear Oct 12 23:04:11 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 0 Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 0 Oct 12 23:04:11 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"They Live","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/09 They Live.mp3","trackType":"mp3"} Oct 12 23:04:11 22d0024r volumio[1211]: verbose: CURRENT POSITION 0 Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService stop Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus stop Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 0 Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:04:11 22d0024r volumio[1211]: info: No code Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 0 Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:04:11 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 0 Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 0 Oct 12 23:04:11 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"They Live","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/09 They Live.mp3","trackType":"mp3"} Oct 12 23:04:11 22d0024r volumio[1211]: verbose: CURRENT POSITION 0 Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService stop Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus stop Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 0 Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:04:11 22d0024r volumio[1211]: info: No code Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 0 Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:04:11 22d0024r volumio[1211]: info: ------------------------------ 39ms Oct 12 23:04:11 22d0024r volumio[1211]: info: ------------------------------ 39ms Oct 12 23:04:11 22d0024r volumio[1211]: info: Oct 12 23:04:11 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:04:11 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:04:11 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 12 23:04:11 22d0024r volumio[1211]: info: sendMpdCommand clear took 24 milliseconds Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand add "USB/6To_WDC_Elements/Musique/[BO] Outland/(01) [Jerry Glodsmith] The Mine.ogg" Oct 12 23:04:11 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 0 Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 0 Oct 12 23:04:11 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"They Live","artist":"John Carpenter","album":"Anthology: Movie Themes 1974-1998","uri":"USB/6To_WDC_Elements/Musique/[BO] John Carpenter/09 They Live.mp3","trackType":"mp3"} Oct 12 23:04:11 22d0024r volumio[1211]: verbose: CURRENT POSITION 0 Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService stop Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus stop Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 0 Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:04:11 22d0024r volumio[1211]: info: No code Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 0 Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:04:11 22d0024r volumio[1211]: info: ------------------------------ 50ms Oct 12 23:04:11 22d0024r volumio[1211]: info: Oct 12 23:04:11 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:04:11 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:04:11 22d0024r volumio[1211]: info: Oct 12 23:04:11 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:04:11 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:04:11 22d0024r volumio[1211]: error: updateQueue error: null Oct 12 23:04:11 22d0024r volumio[1211]: info: Oct 12 23:04:11 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:04:11 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:04:11 22d0024r volumio[1211]: info: ------------------------------ 14ms Oct 12 23:04:11 22d0024r volumio[1211]: info: sendMpdCommand add "USB/6To_WDC_Elements/Musique/[BO] Outland/(01) [Jerry Glodsmith] The Mine.ogg" took 13 milliseconds Oct 12 23:04:11 22d0024r volumio[1211]: info: ------------------------------ 3ms Oct 12 23:04:11 22d0024r volumio[1211]: info: ------------------------------ 2ms Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand play Oct 12 23:04:11 22d0024r volumio[1211]: info: Oct 12 23:04:11 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:04:11 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:04:11 22d0024r volumio[1211]: info: Oct 12 23:04:11 22d0024r volumio[1211]: ---------------------------- MPD announces system playlist update Oct 12 23:04:11 22d0024r volumio[1211]: info: Ignoring MPD Status Update Oct 12 23:04:11 22d0024r volumio[1211]: info: ------------------------------ 6ms Oct 12 23:04:11 22d0024r volumio[1211]: info: sendMpdCommand play took 5 milliseconds Oct 12 23:04:11 22d0024r volumio[1211]: info: ------------------------------ 5ms Oct 12 23:04:11 22d0024r volumio[1211]: info: ------------------------------ 4ms Oct 12 23:04:11 22d0024r volumio[1211]: info: Oct 12 23:04:11 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:04:11 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:04:11 22d0024r volumio[1211]: info: Oct 12 23:04:11 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:04:11 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:04:11 22d0024r volumio[1211]: info: Oct 12 23:04:11 22d0024r volumio[1211]: ---------------------------- MPD announces state update: player Oct 12 23:04:11 22d0024r volumio[1211]: info: ControllerMpd::getState Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Oct 12 23:04:11 22d0024r volumio[1211]: info: sendMpdCommand status took 8 milliseconds Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:04:11 22d0024r volumio[1211]: info: sendMpdCommand status took 6 milliseconds Oct 12 23:04:11 22d0024r volumio[1211]: info: sendMpdCommand status took 2 milliseconds Oct 12 23:04:11 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::parseState Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:04:11 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 0 Oct 12 23:04:11 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Mine","artist":"Jerry Glodsmith","album":"Outland, remastered","uri":"USB/6To_WDC_Elements/Musique/[BO] Outland/(01) [Jerry Glodsmith] The Mine.ogg","trackType":"ogg"} Oct 12 23:04:11 22d0024r volumio[1211]: verbose: CURRENT POSITION 0 Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus stop Oct 12 23:04:11 22d0024r volumio[1211]: info: ------------------------------ 16ms Oct 12 23:04:11 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 12 23:04:11 22d0024r volumio[1211]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:04:11 22d0024r volumio[1211]: verbose: ControllerMpd::parseTrackInfo Oct 12 23:04:11 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 0 Oct 12 23:04:11 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":286,"duration":233,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Mine","artist":"Jerry Glodsmith","album":"Outland, remastered","uri":"USB/6To_WDC_Elements/Musique/[BO] Outland/(01) [Jerry Glodsmith] The Mine.ogg","trackType":"ogg"} Oct 12 23:04:11 22d0024r volumio[1211]: verbose: CURRENT POSITION 0 Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:04:11 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 0 Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 0 Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:04:11 22d0024r volumio[1211]: info: ControllerMpd::pushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::servicePushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 0 Oct 12 23:04:11 22d0024r volumio[1211]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":233,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Mine","artist":"Jerry Glodsmith","album":"Outland, remastered","uri":"USB/6To_WDC_Elements/Musique/[BO] Outland/(01) [Jerry Glodsmith] The Mine.ogg","trackType":"ogg"} Oct 12 23:04:11 22d0024r volumio[1211]: verbose: CURRENT POSITION 0 Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::syncState stateService play Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::syncState currentStatus play Oct 12 23:04:11 22d0024r volumio[1211]: info: Received an update from plugin. extracting info from payload Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 0 Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:04:11 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 0 Oct 12 23:04:11 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:04:11 22d0024r volumio[1211]: info: ------------------------------ 24ms Oct 12 23:04:11 22d0024r volumio[1211]: info: ------------------------------ 21ms Oct 12 23:04:13 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:04:17 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:04:21 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 23:04:28 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 12 23:04:28 22d0024r volumio[1211]: info: CURURI: music-library/USB/6To_WDC_Elements/Musique/[Classique] 100 Best Oct 12 23:04:28 22d0024r volumio[1211]: info: Preload queue cleared Oct 12 23:04:33 22d0024r volumio[1211]: Upnp client error: Error: This socket has been ended by the other party Oct 12 23:04:51 22d0024r volumio[1211]: info: Preload queue cleared Oct 12 23:04:51 22d0024r volumio[1211]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 12 23:04:51 22d0024r volumio[1211]: info: CoreStateMachine::ClearQueue Oct 12 23:04:51 22d0024r volumio[1211]: info: CoreStateMachine::stop Oct 12 23:04:51 22d0024r volumio[1211]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 23:04:51 22d0024r volumio[1211]: info: CoreStateMachine::stPlaybackTimer Oct 12 23:04:51 22d0024r volumio[1211]: info: CoreStateMachine::updateTrackBlock Oct 12 23:04:51 22d0024r volumio[1211]: info: CorePlayQueue::getTrackBlock Oct 12 23:04:51 22d0024r volumio[1211]: info: CoreStateMachine::pushState Oct 12 23:04:51 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 0 Oct 12 23:04:51 22d0024r volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 23:04:51 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushState Oct 12 23:04:51 22d0024r volumio[1211]: info: CoreStateMachine::serviceStop Oct 12 23:04:51 22d0024r volumio[1211]: info: CorePlayQueue::getTrack 0 Oct 12 23:04:51 22d0024r volumio[1211]: info: CoreCommandRouter::serviceStop Oct 12 23:04:51 22d0024r volumio[1211]: info: ControllerMpd::stop Oct 12 23:04:51 22d0024r volumio[1211]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 23:04:51 22d0024r volumio[1211]: info: CorePlayQueue::clearPlayQueue Oct 12 23:04:51 22d0024r volumio[1211]: info: CorePlayQueue::saveQueue Oct 12 23:04:51 22d0024r volumio[1211]: info: CoreCommandRouter::volumioPushQueue Oct 12 23:04:51 22d0024r volumio[1211]: info: CoreStateMachine::addQueueItems Oct 12 23:04:51 22d0024r volumio[1211]: info: CorePlayQueue::addQueueItems Oct 12 23:04:51 22d0024r volumio[1211]: info: Preload queue cleared Oct 12 23:04:51 22d0024r volumio[1211]: info: Adding Item to queue: music-library/USB/6To_WDC_Elements/Musique/[Classique] 100 Best/100 Best Classics Vol. I Oct 12 23:04:51 22d0024r volumio[1211]: info: Exploding uri music-library/USB/6To_WDC_Elements/Musique/[Classique] 100 Best/100 Best Classics Vol. I in service mpd Oct 12 23:04:51 22d0024r volumio[1211]: info: ALBUMART /albumart?cacheid=663&web=Academy%20of%20St%20Martin%20in%20the%20Fields%20%2F%20Neville%20Marriner/BEST%20CLASSICS%20100%20CD1%20(UPLIFTING%20CLASSICS)/extralarge&path=%2Fmnt%2FUSB%2F6To_WDC_Elements%2FMusique%2F%5BClassique%5D%20100%20Best%2F100%20Best%20Classics%20Vol.%20I%2FVarious.Artistis.-.%5BBest.Classics.100.CD1.(Uplifting.Classics)%5D.cue&metadata=false Oct 12 23:04:51 22d0024r volumio[1211]: info: URI /mnt/USB/6To_WDC_Elements/Musique/[Classique] 100 Best/100 Best Classics Vol. I/Various.Artistis.-.[Best.Classics.100.CD1.(Uplifting.Classics)].cue Oct 12 23:04:51 22d0024r volumio[1211]: info: ALBUMART /albumart?cacheid=663&web=Y.Menuhin%20%2F%20Camerata%20Lysy%20Gstaad%20%2F%20Alberto%20Lysy/BEST%20CLASSICS%20100%20CD1%20(UPLIFTING%20CLASSICS)/extralarge&path=%2Fmnt%2FUSB%2F6To_WDC_Elements%2FMusique%2F%5BClassique%5D%20100%20Best%2F100%20Best%20Classics%20Vol.%20I%2FVarious.Artistis.-.%5BBest.Classics.100.CD1.(Uplifting.Classics)%5D.cue&metadata=false Oct 12 23:04:51 22d0024r volumio[1211]: info: URI /mnt/USB/6To_WDC_Elements/Musique/[Classique] 100 Best/100 Best Classics Vol. I/Various.Artistis.-.[Best.Classics.100.CD1.(Uplifting.Classics)].cue Oct 12 23:04:51 22d0024r volumio[1211]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 23:04:51 22d0024r volumio[1211]: Error: Unable to resolve or reject the same promise twice Oct 12 23:04:51 22d0024r volumio[1211]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 12 23:04:51 22d0024r volumio[1211]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Oct 12 23:04:51 22d0024r volumio[1211]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 12 23:04:51 22d0024r volumio[1211]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 12 23:04:51 22d0024r volumio[1211]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 12 23:04:51 22d0024r volumio[1211]: at Socket.emit (node:events:514:28) Oct 12 23:04:51 22d0024r volumio[1211]: at addChunk (node:internal/streams/readable:343:12) Oct 12 23:04:51 22d0024r volumio[1211]: at readableAddChunk (node:internal/streams/readable:312:11) Oct 12 23:04:51 22d0024r volumio[1211]: at Readable.push (node:internal/streams/readable:253:10) Oct 12 23:04:51 22d0024r volumio[1211]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Oct 12 23:04:51 22d0024r volumio[1211]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 23:04:51 22d0024r sudo[6919]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-12 23:03' Oct 12 23:04:51 22d0024r sudo[6919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"