-- Logs begin at Thu 2024-11-21 14:59:56 UTC, end at Thu 2024-11-21 15:48:54 UTC. -- Nov 21 15:48:00 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 21 15:48:07 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 21 15:48:09 volumio sudo[13417]: pam_unix(sudo:session): session closed for user root Nov 21 15:48:09 volumio systemd[1]: Started Music Player Daemon. Nov 21 15:48:09 volumio volumio[12602]: Upnp client error: Error: This socket has been ended by the other party Nov 21 15:48:09 volumio volumio[12602]: error: MPD error: false == true Nov 21 15:48:09 volumio volumio[12602]: error: MPD error: false == true Nov 21 15:48:09 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:09 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:14 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:14 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:16 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 21 15:48:17 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 21 15:48:18 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 21 15:48:22 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 21 15:48:24 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 21 15:48:25 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 21 15:48:27 volumio volumio[12602]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Aimer/BEST%20SELECTION%20%22blanc%22/aab1613a-b66e-45ff-a73f-b49e90e7d6cd.jpg' Nov 21 15:48:30 volumio volumio[12602]: info: CoreCommandRouter::volumioAddQueueItems Nov 21 15:48:30 volumio volumio[12602]: info: CoreStateMachine::addQueueItems Nov 21 15:48:30 volumio volumio[12602]: info: CorePlayQueue::addQueueItems Nov 21 15:48:30 volumio volumio[12602]: info: Adding Item to queue: upnp/folder/http://192.168.31.1:8200/ctl/ContentDir@1$14$6 Nov 21 15:48:30 volumio volumio[12602]: info: Exploding uri upnp/folder/http://192.168.31.1:8200/ctl/ContentDir@1$14$6 in service upnp_browser Nov 21 15:48:31 volumio volumio[12602]: info: CorePlayQueue::saveQueue Nov 21 15:48:31 volumio volumio[12602]: info: CoreCommandRouter::volumioPushQueue Nov 21 15:48:31 volumio volumio[12602]: info: CoreStateMachine::updateTrackBlock Nov 21 15:48:31 volumio volumio[12602]: info: CorePlayQueue::getTrackBlock Nov 21 15:48:31 volumio volumio[12602]: info: CoreCommandRouter::volumioPlay Nov 21 15:48:31 volumio volumio[12602]: verbose: UNSET VOLATILE Nov 21 15:48:31 volumio volumio[12602]: info: CoreStateMachine::play index 319 Nov 21 15:48:31 volumio volumio[12602]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 15:48:31 volumio volumio[12602]: info: CoreStateMachine::stop Nov 21 15:48:31 volumio volumio[12602]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 15:48:31 volumio volumio[12602]: verbose: UNSET VOLATILE Nov 21 15:48:31 volumio volumio[12602]: info: CoreStateMachine::stPlaybackTimer Nov 21 15:48:31 volumio volumio[12602]: info: CoreStateMachine::updateTrackBlock Nov 21 15:48:31 volumio volumio[12602]: info: CorePlayQueue::getTrackBlock Nov 21 15:48:31 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:31 volumio volumio[12602]: info: CorePlayQueue::getTrack 11 Nov 21 15:48:31 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:31 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:31 volumio volumio[12602]: info: CoreStateMachine::serviceStop Nov 21 15:48:31 volumio volumio[12602]: info: CorePlayQueue::getTrack 11 Nov 21 15:48:31 volumio volumio[12602]: info: CoreCommandRouter::serviceStop Nov 21 15:48:31 volumio volumio[12602]: info: ControllerMpd::stop Nov 21 15:48:31 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 15:48:31 volumio volumio[12602]: info: sendMpdCommand stop took 14 milliseconds Nov 21 15:48:31 volumio volumio[12602]: info: CoreStateMachine::play index undefined Nov 21 15:48:31 volumio volumio[12602]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 15:48:31 volumio volumio[12602]: info: CorePlayQueue::getTrack 319 Nov 21 15:48:31 volumio volumio[12602]: info: CoreStateMachine::startPlaybackTimer Nov 21 15:48:31 volumio volumio[12602]: info: CorePlayQueue::getTrack 319 Nov 21 15:48:31 volumio volumio[12602]: info: [1732204111966] ControllerUPNPBrowser::clearAddPlayTrack Nov 21 15:48:31 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 15:48:31 volumio volumio[12602]: info: sendMpdCommand stop took 2 milliseconds Nov 21 15:48:31 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand clear Nov 21 15:48:31 volumio volumio[12602]: info: Nov 21 15:48:31 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:31 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:31 volumio volumio[12602]: info: sendMpdCommand clear took 3 milliseconds Nov 21 15:48:31 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.31.1:8200/MediaItems/1302.mp3" Nov 21 15:48:31 volumio volumio[12602]: info: Nov 21 15:48:31 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:31 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:31 volumio volumio[12602]: info: Nov 21 15:48:31 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:31 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:31 volumio volumio[12602]: info: Nov 21 15:48:31 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:31 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:31 volumio volumio[12602]: info: Nov 21 15:48:31 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:31 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:31 volumio volumio[12602]: info: Nov 21 15:48:31 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:31 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:32 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:32 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:32 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:32 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:32 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:32 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:32 volumio volumio[12602]: info: ------------------------------ 237ms Nov 21 15:48:32 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.1:8200/MediaItems/1302.mp3" Nov 21 15:48:32 volumio volumio[12602]: info: ------------------------------ 235ms Nov 21 15:48:32 volumio volumio[12602]: info: ------------------------------ 234ms Nov 21 15:48:32 volumio volumio[12602]: info: ------------------------------ 233ms Nov 21 15:48:32 volumio volumio[12602]: info: ------------------------------ 233ms Nov 21 15:48:32 volumio volumio[12602]: info: ------------------------------ 232ms Nov 21 15:48:32 volumio volumio[12602]: info: Nov 21 15:48:32 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:32 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:32 volumio volumio[12602]: info: sendMpdCommand add "http://192.168.31.1:8200/MediaItems/1302.mp3" took 6 milliseconds Nov 21 15:48:32 volumio volumio[12602]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 21 15:48:32 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand play Nov 21 15:48:32 volumio volumio[12602]: info: Nov 21 15:48:32 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:32 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:32 volumio volumio[12602]: info: Nov 21 15:48:32 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:32 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:32 volumio volumio[12602]: info: Nov 21 15:48:32 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:32 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:32 volumio volumio[12602]: info: Nov 21 15:48:32 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:32 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:32 volumio volumio[12602]: info: Nov 21 15:48:32 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:32 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:32 volumio volumio[12602]: info: ------------------------------ 11ms Nov 21 15:48:32 volumio volumio[12602]: info: sendMpdCommand play took 9 milliseconds Nov 21 15:48:32 volumio volumio[12602]: info: ------------------------------ 8ms Nov 21 15:48:32 volumio volumio[12602]: info: ------------------------------ 6ms Nov 21 15:48:32 volumio volumio[12602]: info: ------------------------------ 5ms Nov 21 15:48:32 volumio volumio[12602]: info: ------------------------------ 4ms Nov 21 15:48:32 volumio volumio[12602]: info: ------------------------------ 2ms Nov 21 15:48:32 volumio volumio[12602]: info: Nov 21 15:48:32 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:32 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:32 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:32 volumio volumio[12602]: info: Nov 21 15:48:32 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:32 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:32 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:32 volumio volumio[12602]: info: Nov 21 15:48:32 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:32 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:32 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:32 volumio volumio[12602]: info: Nov 21 15:48:32 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:32 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:32 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:32 volumio volumio[12602]: info: Nov 21 15:48:32 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:32 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:32 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:32 volumio volumio[12602]: info: Nov 21 15:48:32 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:32 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:32 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:32 volumio volumio[12602]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 21 15:48:32 volumio volumio[12602]: info: CoreCommandRouter::volumioGetState Nov 21 15:48:32 volumio volumio[12602]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 21 15:48:32 volumio volumio[12602]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 21 15:48:33 volumio volumio[12602]: info: Nov 21 15:48:33 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:33 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:33 volumio volumio[12602]: info: Nov 21 15:48:33 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand status took 946 milliseconds Nov 21 15:48:33 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand status took 945 milliseconds Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand status took 939 milliseconds Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand status took 938 milliseconds Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand status took 938 milliseconds Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand status took 937 milliseconds Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:33 volumio volumio[12602]: info: Nov 21 15:48:33 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:33 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:33 volumio volumio[12602]: info: Nov 21 15:48:33 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:33 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:33 volumio volumio[12602]: info: Nov 21 15:48:33 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:33 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:33 volumio volumio[12602]: info: Nov 21 15:48:33 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:33 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:33 volumio volumio[12602]: info: Nov 21 15:48:33 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:33 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:33 volumio volumio[12602]: info: Nov 21 15:48:33 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:33 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:33 volumio volumio[12602]: info: Nov 21 15:48:33 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:33 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:33 volumio volumio[12602]: info: Nov 21 15:48:33 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:33 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:33 volumio volumio[12602]: info: Nov 21 15:48:33 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:33 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:33 volumio volumio[12602]: info: Nov 21 15:48:33 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:33 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:33 volumio volumio[12602]: info: ------------------------------ 17ms Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand status took 16 milliseconds Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 14 milliseconds Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 14 milliseconds Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 21 15:48:33 volumio volumio[12602]: info: ------------------------------ 12ms Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand status took 11 milliseconds Nov 21 15:48:33 volumio volumio[12602]: info: ------------------------------ 11ms Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand status took 10 milliseconds Nov 21 15:48:33 volumio volumio[12602]: info: ------------------------------ 9ms Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand status took 9 milliseconds Nov 21 15:48:33 volumio volumio[12602]: info: ------------------------------ 9ms Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand status took 8 milliseconds Nov 21 15:48:33 volumio volumio[12602]: info: ------------------------------ 7ms Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand status took 7 milliseconds Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:33 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getTrack 319 Nov 21 15:48:33 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":386,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"SUMMER","artist":"明治記念館","album":"セラトーン","uri":"http://192.168.31.1:8200/MediaItems/1302.mp3","trackType":"mp3"} Nov 21 15:48:33 volumio volumio[12602]: verbose: CURRENT POSITION 319 Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:33 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getTrack 319 Nov 21 15:48:33 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":21,"duration":386,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"SUMMER","artist":"明治記念館","album":"セラトーン","uri":"http://192.168.31.1:8200/MediaItems/1302.mp3","trackType":"mp3"} Nov 21 15:48:33 volumio volumio[12602]: verbose: CURRENT POSITION 319 Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:33 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:33 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getTrack 319 Nov 21 15:48:33 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":32,"duration":386,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"SUMMER","artist":"明治記念館","album":"セラトーン","uri":"http://192.168.31.1:8200/MediaItems/1302.mp3","trackType":"mp3"} Nov 21 15:48:33 volumio volumio[12602]: verbose: CURRENT POSITION 319 Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:33 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:33 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getTrack 319 Nov 21 15:48:33 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":42,"duration":386,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"SUMMER","artist":"明治記念館","album":"セラトーン","uri":"http://192.168.31.1:8200/MediaItems/1302.mp3","trackType":"mp3"} Nov 21 15:48:33 volumio volumio[12602]: verbose: CURRENT POSITION 319 Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:33 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:33 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getTrack 319 Nov 21 15:48:33 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":53,"duration":386,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"SUMMER","artist":"明治記念館","album":"セラトーン","uri":"http://192.168.31.1:8200/MediaItems/1302.mp3","trackType":"mp3"} Nov 21 15:48:33 volumio volumio[12602]: verbose: CURRENT POSITION 319 Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:33 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:33 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getTrack 319 Nov 21 15:48:33 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":64,"duration":386,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"SUMMER","artist":"明治記念館","album":"セラトーン","uri":"http://192.168.31.1:8200/MediaItems/1302.mp3","trackType":"mp3"} Nov 21 15:48:33 volumio volumio[12602]: verbose: CURRENT POSITION 319 Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:33 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:33 volumio volumio[12602]: info: ------------------------------ 1031ms Nov 21 15:48:33 volumio volumio[12602]: info: ------------------------------ 1031ms Nov 21 15:48:33 volumio volumio[12602]: info: ------------------------------ 1026ms Nov 21 15:48:33 volumio volumio[12602]: info: ------------------------------ 1025ms Nov 21 15:48:33 volumio volumio[12602]: info: ------------------------------ 1025ms Nov 21 15:48:33 volumio volumio[12602]: info: ------------------------------ 1024ms Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 73 milliseconds Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 72 milliseconds Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 71 milliseconds Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 72 milliseconds Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 71 milliseconds Nov 21 15:48:33 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 72 milliseconds Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:33 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:33 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getTrack 319 Nov 21 15:48:33 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":386,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"SUMMER","artist":"明治記念館","album":"セラトーン","uri":"http://192.168.31.1:8200/MediaItems/1302.mp3","trackType":"mp3"} Nov 21 15:48:33 volumio volumio[12602]: verbose: CURRENT POSITION 319 Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:33 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:33 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getTrack 319 Nov 21 15:48:33 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":386,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"SUMMER","artist":"明治記念館","album":"セラトーン","uri":"http://192.168.31.1:8200/MediaItems/1302.mp3","trackType":"mp3"} Nov 21 15:48:33 volumio volumio[12602]: verbose: CURRENT POSITION 319 Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:33 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:33 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getTrack 319 Nov 21 15:48:33 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":386,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"SUMMER","artist":"明治記念館","album":"セラトーン","uri":"http://192.168.31.1:8200/MediaItems/1302.mp3","trackType":"mp3"} Nov 21 15:48:33 volumio volumio[12602]: verbose: CURRENT POSITION 319 Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:33 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:33 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getTrack 319 Nov 21 15:48:33 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":386,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"SUMMER","artist":"明治記念館","album":"セラトーン","uri":"http://192.168.31.1:8200/MediaItems/1302.mp3","trackType":"mp3"} Nov 21 15:48:33 volumio volumio[12602]: verbose: CURRENT POSITION 319 Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:33 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:33 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getTrack 319 Nov 21 15:48:33 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":386,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"SUMMER","artist":"明治記念館","album":"セラトーン","uri":"http://192.168.31.1:8200/MediaItems/1302.mp3","trackType":"mp3"} Nov 21 15:48:33 volumio volumio[12602]: verbose: CURRENT POSITION 319 Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:33 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:33 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getTrack 319 Nov 21 15:48:33 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":386,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"SUMMER","artist":"明治記念館","album":"セラトーン","uri":"http://192.168.31.1:8200/MediaItems/1302.mp3","trackType":"mp3"} Nov 21 15:48:33 volumio volumio[12602]: verbose: CURRENT POSITION 319 Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:33 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:33 volumio volumio[12602]: info: ------------------------------ 159ms Nov 21 15:48:33 volumio volumio[12602]: info: ------------------------------ 152ms Nov 21 15:48:33 volumio volumio[12602]: info: ------------------------------ 151ms Nov 21 15:48:33 volumio volumio[12602]: info: ------------------------------ 151ms Nov 21 15:48:33 volumio volumio[12602]: info: ------------------------------ 149ms Nov 21 15:48:33 volumio volumio[12602]: info: ------------------------------ 149ms Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:33 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:33 volumio volumio[12602]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::volumioNext Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::next Nov 21 15:48:39 volumio volumio[12602]: info: ControllerMpd::next Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand next Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:39 volumio volumio[12602]: info: sendMpdCommand next took 202 milliseconds Nov 21 15:48:39 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:39 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:39 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:39 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:39 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:39 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:39 volumio volumio[12602]: info: sendMpdCommand status took 7 milliseconds Nov 21 15:48:39 volumio volumio[12602]: info: sendMpdCommand status took 6 milliseconds Nov 21 15:48:39 volumio volumio[12602]: info: sendMpdCommand status took 6 milliseconds Nov 21 15:48:39 volumio volumio[12602]: info: sendMpdCommand status took 6 milliseconds Nov 21 15:48:39 volumio volumio[12602]: info: sendMpdCommand status took 5 milliseconds Nov 21 15:48:39 volumio volumio[12602]: info: sendMpdCommand status took 4 milliseconds Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:39 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 319 Nov 21 15:48:39 volumio volumio[12602]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 15:48:39 volumio volumio[12602]: verbose: CURRENT POSITION 319 Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::syncState stateService stop Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::play index undefined Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:39 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 15:48:39 volumio volumio[12602]: verbose: CURRENT POSITION 320 Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::syncState stateService stop Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:39 volumio volumio[12602]: info: No code Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:39 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 15:48:39 volumio volumio[12602]: verbose: CURRENT POSITION 320 Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::syncState stateService stop Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:39 volumio volumio[12602]: info: No code Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:39 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 15:48:39 volumio volumio[12602]: verbose: CURRENT POSITION 320 Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::syncState stateService stop Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:39 volumio volumio[12602]: info: No code Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:39 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 15:48:39 volumio volumio[12602]: verbose: CURRENT POSITION 320 Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::syncState stateService stop Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:39 volumio volumio[12602]: info: No code Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:39 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 15:48:39 volumio volumio[12602]: verbose: CURRENT POSITION 320 Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::syncState stateService stop Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:39 volumio volumio[12602]: info: No code Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::startPlaybackTimer Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: info: [1732204119767] ControllerUPNPBrowser::clearAddPlayTrack Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 15:48:39 volumio volumio[12602]: info: ------------------------------ 89ms Nov 21 15:48:39 volumio volumio[12602]: info: ------------------------------ 89ms Nov 21 15:48:39 volumio volumio[12602]: info: ------------------------------ 89ms Nov 21 15:48:39 volumio volumio[12602]: info: ------------------------------ 90ms Nov 21 15:48:39 volumio volumio[12602]: info: ------------------------------ 90ms Nov 21 15:48:39 volumio volumio[12602]: info: ------------------------------ 90ms Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:39 volumio volumio[12602]: info: sendMpdCommand stop took 16 milliseconds Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand clear Nov 21 15:48:39 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:39 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:39 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:39 volumio volumio[12602]: info: sendMpdCommand clear took 41 milliseconds Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.31.1:8200/MediaItems/1355.mp3" Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:39 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:39 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:39 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:39 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:39 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:39 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:39 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:39 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:39 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:39 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:39 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:39 volumio volumio[12602]: info: ------------------------------ 33ms Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.1:8200/MediaItems/1355.mp3" Nov 21 15:48:39 volumio volumio[12602]: info: ------------------------------ 31ms Nov 21 15:48:39 volumio volumio[12602]: info: ------------------------------ 30ms Nov 21 15:48:39 volumio volumio[12602]: info: ------------------------------ 30ms Nov 21 15:48:39 volumio volumio[12602]: info: ------------------------------ 29ms Nov 21 15:48:39 volumio volumio[12602]: info: ------------------------------ 27ms Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:39 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:39 volumio volumio[12602]: info: sendMpdCommand add "http://192.168.31.1:8200/MediaItems/1355.mp3" took 3 milliseconds Nov 21 15:48:39 volumio volumio[12602]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand play Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:39 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:39 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:39 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:39 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:39 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:39 volumio volumio[12602]: info: ------------------------------ 11ms Nov 21 15:48:39 volumio volumio[12602]: info: sendMpdCommand play took 10 milliseconds Nov 21 15:48:39 volumio volumio[12602]: info: ------------------------------ 9ms Nov 21 15:48:39 volumio volumio[12602]: info: ------------------------------ 7ms Nov 21 15:48:39 volumio volumio[12602]: info: ------------------------------ 6ms Nov 21 15:48:39 volumio volumio[12602]: info: ------------------------------ 4ms Nov 21 15:48:39 volumio volumio[12602]: info: ------------------------------ 3ms Nov 21 15:48:39 volumio volumio[12602]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:39 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:39 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:39 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:39 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:39 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:39 volumio volumio[12602]: info: Nov 21 15:48:39 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:39 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:39 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:40 volumio volumio[12602]: info: Nov 21 15:48:40 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand status took 299 milliseconds Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand status took 298 milliseconds Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand status took 297 milliseconds Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand status took 296 milliseconds Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand status took 294 milliseconds Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand status took 294 milliseconds Nov 21 15:48:40 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:40 volumio volumio[12602]: info: Nov 21 15:48:40 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:40 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:40 volumio volumio[12602]: info: Nov 21 15:48:40 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:40 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:40 volumio volumio[12602]: info: Nov 21 15:48:40 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:40 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:40 volumio volumio[12602]: info: Nov 21 15:48:40 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:40 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:40 volumio volumio[12602]: info: Nov 21 15:48:40 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:40 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:40 volumio volumio[12602]: info: Nov 21 15:48:40 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:40 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand status took 12 milliseconds Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 14 milliseconds Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 14 milliseconds Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 14 milliseconds Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand status took 12 milliseconds Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand status took 11 milliseconds Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand status took 10 milliseconds Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand status took 9 milliseconds Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand status took 8 milliseconds Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:40 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:40 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:40 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"響喜乱舞","artist":"GARNiDELiA(가르니델리아/ガルニデリア)","album":"Kyokiranbu","uri":"http://192.168.31.1:8200/MediaItems/1355.mp3","trackType":"mp3"} Nov 21 15:48:40 volumio volumio[12602]: verbose: CURRENT POSITION 320 Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:40 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:40 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:40 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"響喜乱舞","artist":"GARNiDELiA(가르니델리아/ガルニデリア)","album":"Kyokiranbu","uri":"http://192.168.31.1:8200/MediaItems/1355.mp3","trackType":"mp3"} Nov 21 15:48:40 volumio volumio[12602]: verbose: CURRENT POSITION 320 Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:40 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:40 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:40 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:40 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":21,"duration":259,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"響喜乱舞","artist":"GARNiDELiA(가르니델리아/ガルニデリア)","album":"Kyokiranbu","uri":"http://192.168.31.1:8200/MediaItems/1355.mp3","trackType":"mp3"} Nov 21 15:48:40 volumio volumio[12602]: verbose: CURRENT POSITION 320 Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:40 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:40 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:40 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:40 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":53,"duration":259,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"響喜乱舞","artist":"GARNiDELiA(가르니델리아/ガルニデリア)","album":"Kyokiranbu","uri":"http://192.168.31.1:8200/MediaItems/1355.mp3","trackType":"mp3"} Nov 21 15:48:40 volumio volumio[12602]: verbose: CURRENT POSITION 320 Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:40 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:40 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:40 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:40 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":85,"duration":259,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"響喜乱舞","artist":"GARNiDELiA(가르니델리아/ガルニデリア)","album":"Kyokiranbu","uri":"http://192.168.31.1:8200/MediaItems/1355.mp3","trackType":"mp3"} Nov 21 15:48:40 volumio volumio[12602]: verbose: CURRENT POSITION 320 Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:40 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:40 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:40 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:40 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":117,"duration":259,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"響喜乱舞","artist":"GARNiDELiA(가르니델리아/ガルニデリア)","album":"Kyokiranbu","uri":"http://192.168.31.1:8200/MediaItems/1355.mp3","trackType":"mp3"} Nov 21 15:48:40 volumio volumio[12602]: verbose: CURRENT POSITION 320 Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:40 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:40 volumio volumio[12602]: info: ------------------------------ 372ms Nov 21 15:48:40 volumio volumio[12602]: info: ------------------------------ 371ms Nov 21 15:48:40 volumio volumio[12602]: info: ------------------------------ 371ms Nov 21 15:48:40 volumio volumio[12602]: info: ------------------------------ 371ms Nov 21 15:48:40 volumio volumio[12602]: info: ------------------------------ 370ms Nov 21 15:48:40 volumio volumio[12602]: info: ------------------------------ 370ms Nov 21 15:48:40 volumio volumio[12602]: info: Nov 21 15:48:40 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:40 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:40 volumio volumio[12602]: info: Nov 21 15:48:40 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:40 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:40 volumio volumio[12602]: info: Nov 21 15:48:40 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:40 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:40 volumio volumio[12602]: info: Nov 21 15:48:40 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:40 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:40 volumio volumio[12602]: info: Nov 21 15:48:40 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:40 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:40 volumio volumio[12602]: info: ------------------------------ 100ms Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 92 milliseconds Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 90 milliseconds Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 90 milliseconds Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 90 milliseconds Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 90 milliseconds Nov 21 15:48:40 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 90 milliseconds Nov 21 15:48:40 volumio volumio[12602]: info: ------------------------------ 36ms Nov 21 15:48:40 volumio volumio[12602]: info: ------------------------------ 35ms Nov 21 15:48:40 volumio volumio[12602]: info: ------------------------------ 35ms Nov 21 15:48:40 volumio volumio[12602]: info: ------------------------------ 33ms Nov 21 15:48:40 volumio volumio[12602]: info: ------------------------------ 31ms Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:40 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:40 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:40 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:40 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":160,"duration":259,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"響喜乱舞","artist":"GARNiDELiA(가르니델리아/ガルニデリア)","album":"Kyokiranbu","uri":"http://192.168.31.1:8200/MediaItems/1355.mp3","trackType":"mp3"} Nov 21 15:48:40 volumio volumio[12602]: verbose: CURRENT POSITION 320 Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:40 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:40 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:40 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:40 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":259,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"響喜乱舞","artist":"GARNiDELiA(가르니델리아/ガルニデリア)","album":"Kyokiranbu","uri":"http://192.168.31.1:8200/MediaItems/1355.mp3","trackType":"mp3"} Nov 21 15:48:40 volumio volumio[12602]: verbose: CURRENT POSITION 320 Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:40 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:40 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:40 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:40 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":259,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"響喜乱舞","artist":"GARNiDELiA(가르니델리아/ガルニデリア)","album":"Kyokiranbu","uri":"http://192.168.31.1:8200/MediaItems/1355.mp3","trackType":"mp3"} Nov 21 15:48:40 volumio volumio[12602]: verbose: CURRENT POSITION 320 Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:40 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:40 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:40 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:40 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":259,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"響喜乱舞","artist":"GARNiDELiA(가르니델리아/ガルニデリア)","album":"Kyokiranbu","uri":"http://192.168.31.1:8200/MediaItems/1355.mp3","trackType":"mp3"} Nov 21 15:48:40 volumio volumio[12602]: verbose: CURRENT POSITION 320 Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:40 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:40 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:40 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:40 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:40 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:40 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":259,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"響喜乱舞","artist":"GARNiDELiA(가르니델리아/ガルニデリア)","album":"Kyokiranbu","uri":"http://192.168.31.1:8200/MediaItems/1355.mp3","trackType":"mp3"} Nov 21 15:48:40 volumio volumio[12602]: verbose: CURRENT POSITION 320 Nov 21 15:48:48 volumio systemd-journal[186]: Suppressed 100 messages from /system.slice/volumio.service Nov 21 15:48:48 volumio volumio[12602]: info: sendMpdCommand next took 187 milliseconds Nov 21 15:48:48 volumio volumio[12602]: info: Nov 21 15:48:48 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:48 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:48 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:48 volumio volumio[12602]: info: Nov 21 15:48:48 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:48 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:48 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:48 volumio volumio[12602]: info: Nov 21 15:48:48 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:48 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:48 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:48 volumio volumio[12602]: info: Nov 21 15:48:48 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:48 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:48 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:48 volumio volumio[12602]: info: Nov 21 15:48:48 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:48 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:48 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:48 volumio volumio[12602]: info: Nov 21 15:48:48 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:48 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:48 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:48 volumio volumio[12602]: info: sendMpdCommand status took 8 milliseconds Nov 21 15:48:48 volumio volumio[12602]: info: sendMpdCommand status took 7 milliseconds Nov 21 15:48:48 volumio volumio[12602]: info: sendMpdCommand status took 6 milliseconds Nov 21 15:48:48 volumio volumio[12602]: info: sendMpdCommand status took 6 milliseconds Nov 21 15:48:48 volumio volumio[12602]: info: sendMpdCommand status took 5 milliseconds Nov 21 15:48:48 volumio volumio[12602]: info: sendMpdCommand status took 4 milliseconds Nov 21 15:48:48 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:48 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:48 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:48 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:48 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:48 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:48 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 320 Nov 21 15:48:48 volumio volumio[12602]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 15:48:48 volumio volumio[12602]: verbose: CURRENT POSITION 320 Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::syncState stateService stop Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::play index undefined Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:48 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 15:48:48 volumio volumio[12602]: verbose: CURRENT POSITION 321 Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::syncState stateService stop Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:48 volumio volumio[12602]: info: No code Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:48 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 15:48:48 volumio volumio[12602]: verbose: CURRENT POSITION 321 Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::syncState stateService stop Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:48 volumio volumio[12602]: info: No code Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:48 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 15:48:48 volumio volumio[12602]: verbose: CURRENT POSITION 321 Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::syncState stateService stop Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:48 volumio volumio[12602]: info: No code Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:48 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 15:48:48 volumio volumio[12602]: verbose: CURRENT POSITION 321 Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::syncState stateService stop Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:48 volumio volumio[12602]: info: No code Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:48 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 15:48:48 volumio volumio[12602]: verbose: CURRENT POSITION 321 Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::syncState stateService stop Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:48 volumio volumio[12602]: info: No code Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::startPlaybackTimer Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: info: [1732204128967] ControllerUPNPBrowser::clearAddPlayTrack Nov 21 15:48:48 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 15:48:48 volumio volumio[12602]: info: ------------------------------ 94ms Nov 21 15:48:48 volumio volumio[12602]: info: ------------------------------ 95ms Nov 21 15:48:48 volumio volumio[12602]: info: ------------------------------ 96ms Nov 21 15:48:48 volumio volumio[12602]: info: ------------------------------ 96ms Nov 21 15:48:48 volumio volumio[12602]: info: ------------------------------ 97ms Nov 21 15:48:48 volumio volumio[12602]: info: ------------------------------ 98ms Nov 21 15:48:48 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:48 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:48 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:48 volumio volumio[12602]: info: sendMpdCommand stop took 19 milliseconds Nov 21 15:48:48 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand clear Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:49 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand clear took 38 milliseconds Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.31.1:8200/MediaItems/1359.mp3" Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:49 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:49 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:49 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:49 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:49 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:49 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 32ms Nov 21 15:48:49 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:49 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:49 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:49 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:49 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.1:8200/MediaItems/1359.mp3" Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 30ms Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 29ms Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 29ms Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 27ms Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 26ms Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:49 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand add "http://192.168.31.1:8200/MediaItems/1359.mp3" took 2 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand play Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:49 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:49 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:49 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:49 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:49 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 8ms Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand play took 7 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 6ms Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 5ms Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 5ms Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 4ms Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 3ms Nov 21 15:48:49 volumio volumio[12602]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand status took 319 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand status took 317 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand status took 316 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand status took 316 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand status took 311 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand status took 310 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:49 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand status took 4 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:49 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":221,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"アブラカダブラ 〜avra K'Davarah〜","artist":"GARNiDELiA(가르니델리아/ガルニデリア)","album":"Kyokiranbu","uri":"http://192.168.31.1:8200/MediaItems/1359.mp3","trackType":"mp3"} Nov 21 15:48:49 volumio volumio[12602]: verbose: CURRENT POSITION 321 Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:49 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":221,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"アブラカダブラ 〜avra K'Davarah〜","artist":"GARNiDELiA(가르니델리아/ガルニデリア)","album":"Kyokiranbu","uri":"http://192.168.31.1:8200/MediaItems/1359.mp3","trackType":"mp3"} Nov 21 15:48:49 volumio volumio[12602]: verbose: CURRENT POSITION 321 Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:49 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:49 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":221,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"アブラカダブラ 〜avra K'Davarah〜","artist":"GARNiDELiA(가르니델리아/ガルニデリア)","album":"Kyokiranbu","uri":"http://192.168.31.1:8200/MediaItems/1359.mp3","trackType":"mp3"} Nov 21 15:48:49 volumio volumio[12602]: verbose: CURRENT POSITION 321 Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:49 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:49 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":21,"duration":221,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"アブラカダブラ 〜avra K'Davarah〜","artist":"GARNiDELiA(가르니델리아/ガルニデリア)","album":"Kyokiranbu","uri":"http://192.168.31.1:8200/MediaItems/1359.mp3","trackType":"mp3"} Nov 21 15:48:49 volumio volumio[12602]: verbose: CURRENT POSITION 321 Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:49 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:49 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":32,"duration":221,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"アブラカダブラ 〜avra K'Davarah〜","artist":"GARNiDELiA(가르니델리아/ガルニデリア)","album":"Kyokiranbu","uri":"http://192.168.31.1:8200/MediaItems/1359.mp3","trackType":"mp3"} Nov 21 15:48:49 volumio volumio[12602]: verbose: CURRENT POSITION 321 Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:49 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:49 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":42,"duration":221,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"アブラカダブラ 〜avra K'Davarah〜","artist":"GARNiDELiA(가르니델리아/ガルニデリア)","album":"Kyokiranbu","uri":"http://192.168.31.1:8200/MediaItems/1359.mp3","trackType":"mp3"} Nov 21 15:48:49 volumio volumio[12602]: verbose: CURRENT POSITION 321 Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:49 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 378ms Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 377ms Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 377ms Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 377ms Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 376ms Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 373ms Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 70ms Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 68 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand status took 11 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand status took 10 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand status took 8 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand status took 7 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand status took 5 milliseconds Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:49 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":221,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"アブラカダブラ 〜avra K'Davarah〜","artist":"GARNiDELiA(가르니델리아/ガルニデリア)","album":"Kyokiranbu","uri":"http://192.168.31.1:8200/MediaItems/1359.mp3","trackType":"mp3"} Nov 21 15:48:49 volumio volumio[12602]: verbose: CURRENT POSITION 321 Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:49 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 95ms Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:49 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:49 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:49 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:49 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:49 volumio volumio[12602]: info: Nov 21 15:48:49 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:49 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 142 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 141 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 142 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 143 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 142 milliseconds Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 128ms Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 128ms Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 127ms Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 126ms Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 125ms Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:49 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:49 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":221,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"アブラカダブラ 〜avra K'Davarah〜","artist":"GARNiDELiA(가르니델리아/ガルニデリア)","album":"Kyokiranbu","uri":"http://192.168.31.1:8200/MediaItems/1359.mp3","trackType":"mp3"} Nov 21 15:48:49 volumio volumio[12602]: verbose: CURRENT POSITION 321 Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:49 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:49 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":221,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"アブラカダブラ 〜avra K'Davarah〜","artist":"GARNiDELiA(가르니델리아/ガルニデリア)","album":"Kyokiranbu","uri":"http://192.168.31.1:8200/MediaItems/1359.mp3","trackType":"mp3"} Nov 21 15:48:49 volumio volumio[12602]: verbose: CURRENT POSITION 321 Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:49 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:49 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":221,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"アブラカダブラ 〜avra K'Davarah〜","artist":"GARNiDELiA(가르니델리아/ガルニデリア)","album":"Kyokiranbu","uri":"http://192.168.31.1:8200/MediaItems/1359.mp3","trackType":"mp3"} Nov 21 15:48:49 volumio volumio[12602]: verbose: CURRENT POSITION 321 Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:49 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:49 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":221,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"アブラカダブラ 〜avra K'Davarah〜","artist":"GARNiDELiA(가르니델리아/ガルニデリア)","album":"Kyokiranbu","uri":"http://192.168.31.1:8200/MediaItems/1359.mp3","trackType":"mp3"} Nov 21 15:48:49 volumio volumio[12602]: verbose: CURRENT POSITION 321 Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:49 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:49 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:49 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":221,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"アブラカダブラ 〜avra K'Davarah〜","artist":"GARNiDELiA(가르니델리아/ガルニデリア)","album":"Kyokiranbu","uri":"http://192.168.31.1:8200/MediaItems/1359.mp3","trackType":"mp3"} Nov 21 15:48:49 volumio volumio[12602]: verbose: CURRENT POSITION 321 Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:49 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 218ms Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 217ms Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 216ms Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 214ms Nov 21 15:48:49 volumio volumio[12602]: info: ------------------------------ 213ms Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:48:49 volumio volumio[12602]: info: CoreStateMachine::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: CorePlayQueue::getQueue Nov 21 15:48:49 volumio volumio[12602]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 21 15:48:53 volumio volumio[12602]: info: CoreCommandRouter::volumioAddQueueItems Nov 21 15:48:53 volumio volumio[12602]: info: CoreStateMachine::addQueueItems Nov 21 15:48:53 volumio volumio[12602]: info: CorePlayQueue::addQueueItems Nov 21 15:48:53 volumio volumio[12602]: info: Adding Item to queue: upnp/http://192.168.31.1:8200/ctl/ContentDir@1$14$6$C Nov 21 15:48:53 volumio volumio[12602]: info: Exploding uri upnp/http://192.168.31.1:8200/ctl/ContentDir@1$14$6$C in service upnp_browser Nov 21 15:48:53 volumio volumio[12602]: info: CorePlayQueue::saveQueue Nov 21 15:48:53 volumio volumio[12602]: info: CoreCommandRouter::volumioPushQueue Nov 21 15:48:53 volumio volumio[12602]: info: CoreStateMachine::updateTrackBlock Nov 21 15:48:53 volumio volumio[12602]: info: CorePlayQueue::getTrackBlock Nov 21 15:48:53 volumio volumio[12602]: info: CoreCommandRouter::volumioPlay Nov 21 15:48:53 volumio volumio[12602]: verbose: UNSET VOLATILE Nov 21 15:48:53 volumio volumio[12602]: info: CoreStateMachine::play index 960 Nov 21 15:48:53 volumio volumio[12602]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 15:48:53 volumio volumio[12602]: info: CoreStateMachine::stop Nov 21 15:48:53 volumio volumio[12602]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 15:48:53 volumio volumio[12602]: verbose: UNSET VOLATILE Nov 21 15:48:53 volumio volumio[12602]: info: CoreStateMachine::stPlaybackTimer Nov 21 15:48:53 volumio volumio[12602]: info: CoreStateMachine::updateTrackBlock Nov 21 15:48:53 volumio volumio[12602]: info: CorePlayQueue::getTrackBlock Nov 21 15:48:53 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:53 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:53 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:53 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:53 volumio volumio[12602]: info: CoreStateMachine::serviceStop Nov 21 15:48:53 volumio volumio[12602]: info: CorePlayQueue::getTrack 321 Nov 21 15:48:53 volumio volumio[12602]: info: CoreCommandRouter::serviceStop Nov 21 15:48:53 volumio volumio[12602]: info: [1732204133984] ControllerUPNPBrowser::stop Nov 21 15:48:53 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand stop took 216 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::play index undefined Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::startPlaybackTimer Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: info: [1732204134206] ControllerUPNPBrowser::clearAddPlayTrack Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand stop took 6 milliseconds Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand clear Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:54 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand status took 15 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand clear took 13 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand status took 13 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand status took 12 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand status took 11 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand status took 10 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand status took 9 milliseconds Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.31.1:8200/MediaItems/56.flac" Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 15:48:54 volumio volumio[12602]: verbose: CURRENT POSITION 960 Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::syncState stateService stop Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:54 volumio volumio[12602]: info: No code Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 15:48:54 volumio volumio[12602]: verbose: CURRENT POSITION 960 Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::syncState stateService stop Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:54 volumio volumio[12602]: info: No code Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 15:48:54 volumio volumio[12602]: verbose: CURRENT POSITION 960 Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::syncState stateService stop Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:54 volumio volumio[12602]: info: No code Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 15:48:54 volumio volumio[12602]: verbose: CURRENT POSITION 960 Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::syncState stateService stop Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:54 volumio volumio[12602]: info: No code Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 15:48:54 volumio volumio[12602]: verbose: CURRENT POSITION 960 Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::syncState stateService stop Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:54 volumio volumio[12602]: info: No code Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 97ms Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 97ms Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 98ms Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 98ms Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 97ms Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:54 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:54 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:54 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:54 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:54 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:54 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:54 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:54 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:54 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:54 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:54 volumio volumio[12602]: error: updateQueue error: null Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 100ms Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 96 milliseconds Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.1:8200/MediaItems/56.flac" Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 7ms Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 8ms Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 7ms Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 6ms Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 5ms Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:54 volumio volumio[12602]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 119ms Nov 21 15:48:54 volumio volumio[12602]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:54 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand add "http://192.168.31.1:8200/MediaItems/56.flac" took 43 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand play Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:54 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:54 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:54 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:54 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:54 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 15ms Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand play took 15 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 9ms Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 8ms Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 7ms Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 5ms Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 4ms Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand status took 316 milliseconds Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:54 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand status took 320 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand status took 319 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand status took 319 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand status took 317 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand status took 316 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":196,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"祝福","artist":"YOASOBI","album":"祝福","uri":"http://192.168.31.1:8200/MediaItems/56.flac","trackType":"flac"} Nov 21 15:48:54 volumio volumio[12602]: verbose: CURRENT POSITION 960 Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 345ms Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:54 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:54 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:54 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:54 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces system playlist update Nov 21 15:48:54 volumio volumio[12602]: info: Ignoring MPD Status Update Nov 21 15:48:54 volumio volumio[12602]: info: Nov 21 15:48:54 volumio volumio[12602]: ---------------------------- MPD announces state update: player Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::getState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 53ms Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand status took 53 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 49 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 50 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 49 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 51 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand playlistinfo took 50 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 33ms Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand status took 33 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 32ms Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand status took 37 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 35ms Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand status took 33 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 31ms Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand status took 28 milliseconds Nov 21 15:48:54 volumio volumio[12602]: info: ------------------------------ 27ms Nov 21 15:48:54 volumio volumio[12602]: info: sendMpdCommand status took 24 milliseconds Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::parseState Nov 21 15:48:54 volumio volumio[12602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:48:54 volumio volumio[12602]: info: ControllerMpd::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::servicePushState Nov 21 15:48:54 volumio volumio[12602]: info: CorePlayQueue::getTrack 960 Nov 21 15:48:54 volumio volumio[12602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":196,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"664 Kbps","isStreaming":false,"title":"祝福","artist":"YOASOBI","album":"祝福","uri":"http://192.168.31.1:8200/MediaItems/56.flac","trackType":"flac"} Nov 21 15:48:54 volumio volumio[12602]: verbose: CURRENT POSITION 960 Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::syncState stateService play Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:48:54 volumio volumio[12602]: info: Received an update from plugin. extracting info from payload Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:48:54 volumio volumio[12602]: info: CoreStateMachine::pushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::volumioPushState Nov 21 15:48:54 volumio volumio[12602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"