-- Logs begin at Thu 2024-03-07 16:05:52 UTC, end at Fri 2024-03-08 11:20:15 UTC. -- Mar 08 11:19:00 usbridge volumio[690]: info: Mar 08 11:19:00 usbridge volumio[690]: ---------------------------- MPD announces state update: player Mar 08 11:19:00 usbridge volumio[690]: info: ControllerMpd::getState Mar 08 11:19:00 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand status Mar 08 11:19:00 usbridge volumio[690]: info: sendMpdCommand status took 2 milliseconds Mar 08 11:19:00 usbridge volumio[690]: verbose: ControllerMpd::parseState Mar 08 11:19:00 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 11:19:00 usbridge kernel: aotg_hub_endpoint_disable Mar 08 11:19:00 usbridge kernel: aotg_hcd aotg_hcd.1: ep5 index 1 from ep [out] Mar 08 11:19:00 usbridge kernel: aotg_hub_endpoint_disable Mar 08 11:19:00 usbridge kernel: aotg_hcd aotg_hcd.1: ep1 index 2 from ep [in] Mar 08 11:19:00 usbridge volumio[690]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 08 11:19:00 usbridge volumio[690]: verbose: ControllerMpd::parseTrackInfo Mar 08 11:19:00 usbridge volumio[690]: info: ControllerMpd::pushState Mar 08 11:19:00 usbridge volumio[690]: info: CoreCommandRouter::servicePushState Mar 08 11:19:00 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:19:00 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:00 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:00 usbridge volumio[690]: info: CorePlayQueue::getTrack 0 Mar 08 11:19:00 usbridge volumio[690]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sonata No. 1 in G Major: I. Moderato","artist":"Gioachino Rossini","album":"Rossini: Six Sonatas for Strings","uri":"http://192.168.10.87:14562/qobuz/2047426.flac","trackType":"flac"} Mar 08 11:19:00 usbridge volumio[690]: verbose: CURRENT POSITION 0 Mar 08 11:19:00 usbridge volumio[690]: info: CoreStateMachine::syncState stateService stop Mar 08 11:19:00 usbridge volumio[690]: info: CoreStateMachine::syncState currentStatus play Mar 08 11:19:00 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:00 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:00 usbridge volumio[690]: info: CoreStateMachine::stPlaybackTimer Mar 08 11:19:00 usbridge volumio[690]: info: ------------------------------ 38ms Mar 08 11:19:00 usbridge volumio[690]: info: CoreCommandRouter::volumioGetQueue Mar 08 11:19:00 usbridge volumio[690]: info: CoreStateMachine::getQueue Mar 08 11:19:00 usbridge volumio[690]: info: CorePlayQueue::getQueue Mar 08 11:19:01 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 11:19:02 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 11:19:02 usbridge volumio[690]: info: Clearing queue after UPNP request Mar 08 11:19:02 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 11:19:03 usbridge volumio[690]: info: CoreStateMachine::ClearQueue Mar 08 11:19:03 usbridge volumio[690]: info: CoreStateMachine::stop Mar 08 11:19:03 usbridge volumio[690]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 11:19:03 usbridge volumio[690]: verbose: UNSET VOLATILE Mar 08 11:19:03 usbridge volumio[690]: info: CorePlayQueue::clearPlayQueue Mar 08 11:19:03 usbridge volumio[690]: info: CorePlayQueue::saveQueue Mar 08 11:19:03 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:03 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:03 usbridge volumio[690]: info: CoreCommandRouter::volumioPushQueue Mar 08 11:19:03 usbridge volumio[690]: info: Mar 08 11:19:03 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:03 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:03 usbridge volumio[690]: error: updateQueue error: null Mar 08 11:19:03 usbridge volumio[690]: info: ------------------------------ 4ms Mar 08 11:19:03 usbridge volumio[690]: info: Starting UPNP Playback Mar 08 11:19:03 usbridge volumio[690]: info: Preparing playback through UPNP Mar 08 11:19:03 usbridge volumio[690]: info: CoreCommandRouter::volumioGetState Mar 08 11:19:03 usbridge volumio[690]: info: CorePlayQueue::getTrack 0 Mar 08 11:19:03 usbridge volumio[690]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 11:19:03 usbridge volumio[690]: info: Mar 08 11:19:03 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:03 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:03 usbridge volumio[690]: info: Mar 08 11:19:03 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:03 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:03 usbridge volumio[690]: info: ------------------------------ 5ms Mar 08 11:19:03 usbridge volumio[690]: info: ------------------------------ 4ms Mar 08 11:19:03 usbridge volumio[690]: info: Mar 08 11:19:03 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:03 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:03 usbridge volumio[690]: info: ------------------------------ 4ms Mar 08 11:19:03 usbridge volumio[690]: info: Mar 08 11:19:03 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:03 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:03 usbridge volumio[690]: info: ------------------------------ 5ms Mar 08 11:19:03 usbridge volumio[690]: info: Mar 08 11:19:03 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:03 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:03 usbridge volumio[690]: info: ------------------------------ 4ms Mar 08 11:19:03 usbridge volumio[690]: info: Mar 08 11:19:03 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:03 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:03 usbridge volumio[690]: info: ------------------------------ 3ms Mar 08 11:19:04 usbridge volumio[690]: info: Mar 08 11:19:04 usbridge volumio[690]: ---------------------------- MPD announces state update: player Mar 08 11:19:04 usbridge volumio[690]: info: ControllerMpd::getState Mar 08 11:19:04 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand status Mar 08 11:19:04 usbridge kernel: iso_packets:0, bInterval:1, urb_interval:1, reg_con:0x4 Mar 08 11:19:04 usbridge kernel: iso_packets:0, bInterval:4, urb_interval:8, reg_con:0x4 Mar 08 11:19:04 usbridge volumio[690]: info: Mar 08 11:19:04 usbridge volumio[690]: ---------------------------- MPD announces state update: player Mar 08 11:19:04 usbridge volumio[690]: info: sendMpdCommand status took 50 milliseconds Mar 08 11:19:04 usbridge volumio[690]: info: ControllerMpd::getState Mar 08 11:19:04 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand status Mar 08 11:19:04 usbridge volumio[690]: verbose: ControllerMpd::parseState Mar 08 11:19:04 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 11:19:04 usbridge volumio[690]: info: sendMpdCommand status took 10 milliseconds Mar 08 11:19:04 usbridge volumio[690]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 08 11:19:04 usbridge volumio[690]: verbose: ControllerMpd::parseState Mar 08 11:19:04 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 11:19:04 usbridge volumio[690]: verbose: ControllerMpd::parseTrackInfo Mar 08 11:19:04 usbridge volumio[690]: info: ControllerMpd::pushState Mar 08 11:19:04 usbridge volumio[690]: info: CoreCommandRouter::servicePushState Mar 08 11:19:04 usbridge volumio[690]: verbose: In UPNP mode Mar 08 11:19:04 usbridge volumio[690]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":605,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Poem of Chinese Drum","artist":"Hok-man Yim","album":"Master of Chinese Percussion","uri":"http://192.168.10.87:14562/qobuz/5381610.flac","trackType":"flac"} Mar 08 11:19:04 usbridge volumio[690]: verbose: CURRENT POSITION 0 Mar 08 11:19:04 usbridge volumio[690]: info: CoreStateMachine::syncState stateService play Mar 08 11:19:04 usbridge volumio[690]: info: CoreStateMachine::syncState currentStatus stop Mar 08 11:19:04 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:19:04 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:04 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:04 usbridge kernel: TX ERROR status: 0x007e0000 Mar 08 11:19:04 usbridge volumio[690]: info: ------------------------------ 111ms Mar 08 11:19:04 usbridge volumio[690]: info: sendMpdCommand playlistinfo took 41 milliseconds Mar 08 11:19:04 usbridge volumio[690]: verbose: ControllerMpd::parseTrackInfo Mar 08 11:19:04 usbridge volumio[690]: info: ControllerMpd::pushState Mar 08 11:19:04 usbridge volumio[690]: info: CoreCommandRouter::servicePushState Mar 08 11:19:04 usbridge volumio[690]: verbose: In UPNP mode Mar 08 11:19:04 usbridge volumio[690]: verbose: STATE SERVICE {"status":"play","position":0,"seek":92,"duration":605,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Poem of Chinese Drum","artist":"Hok-man Yim","album":"Master of Chinese Percussion","uri":"http://192.168.10.87:14562/qobuz/5381610.flac","trackType":"flac"} Mar 08 11:19:04 usbridge volumio[690]: verbose: CURRENT POSITION 0 Mar 08 11:19:04 usbridge volumio[690]: info: CoreStateMachine::syncState stateService play Mar 08 11:19:04 usbridge volumio[690]: info: CoreStateMachine::syncState currentStatus play Mar 08 11:19:04 usbridge volumio[690]: info: Received an update from plugin. extracting info from payload Mar 08 11:19:04 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 11:19:04 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:19:04 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:04 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:04 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:19:04 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:04 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:04 usbridge volumio[690]: info: ------------------------------ 139ms Mar 08 11:19:04 usbridge volumio[690]: info: CoreCommandRouter::volumioGetQueue Mar 08 11:19:04 usbridge volumio[690]: info: CoreStateMachine::getQueue Mar 08 11:19:04 usbridge volumio[690]: info: CorePlayQueue::getQueue Mar 08 11:19:04 usbridge volumio[690]: info: CoreCommandRouter::volumioGetQueue Mar 08 11:19:04 usbridge volumio[690]: info: CoreStateMachine::getQueue Mar 08 11:19:04 usbridge volumio[690]: info: CorePlayQueue::getQueue Mar 08 11:19:04 usbridge volumio[690]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Hok-man%20Yim/Master%20of%20Chinese%20Percussion/6470ce43-1bcd-415f-960a-a54cfb6563ff.jpg' Mar 08 11:19:07 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 11:19:07 usbridge volumio[690]: info: Mar 08 11:19:07 usbridge volumio[690]: ---------------------------- MPD announces state update: player Mar 08 11:19:07 usbridge volumio[690]: info: ControllerMpd::getState Mar 08 11:19:07 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand status Mar 08 11:19:07 usbridge volumio[690]: info: sendMpdCommand status took 15 milliseconds Mar 08 11:19:07 usbridge volumio[690]: verbose: ControllerMpd::parseState Mar 08 11:19:07 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 11:19:07 usbridge volumio[690]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 08 11:19:07 usbridge volumio[690]: verbose: ControllerMpd::parseTrackInfo Mar 08 11:19:07 usbridge volumio[690]: info: ControllerMpd::pushState Mar 08 11:19:07 usbridge volumio[690]: info: CoreCommandRouter::servicePushState Mar 08 11:19:07 usbridge volumio[690]: verbose: In UPNP mode Mar 08 11:19:07 usbridge volumio[690]: verbose: STATE SERVICE {"status":"play","position":0,"seek":336000,"duration":605,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"387 Kbps","isStreaming":false,"title":"Poem of Chinese Drum","artist":"Hok-man Yim","album":"Master of Chinese Percussion","uri":"http://192.168.10.87:14562/qobuz/5381610.flac","trackType":"flac"} Mar 08 11:19:07 usbridge volumio[690]: verbose: CURRENT POSITION 0 Mar 08 11:19:07 usbridge volumio[690]: info: CoreStateMachine::syncState stateService play Mar 08 11:19:07 usbridge volumio[690]: info: CoreStateMachine::syncState currentStatus play Mar 08 11:19:07 usbridge volumio[690]: info: Received an update from plugin. extracting info from payload Mar 08 11:19:07 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 11:19:07 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:19:07 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:07 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:07 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:19:07 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:07 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:07 usbridge volumio[690]: info: ------------------------------ 97ms Mar 08 11:19:07 usbridge volumio[690]: info: CoreCommandRouter::volumioGetQueue Mar 08 11:19:07 usbridge volumio[690]: info: CoreStateMachine::getQueue Mar 08 11:19:07 usbridge volumio[690]: info: CorePlayQueue::getQueue Mar 08 11:19:07 usbridge volumio[690]: info: CoreCommandRouter::volumioGetQueue Mar 08 11:19:07 usbridge volumio[690]: info: CoreStateMachine::getQueue Mar 08 11:19:07 usbridge volumio[690]: info: CorePlayQueue::getQueue Mar 08 11:19:08 usbridge volumio[690]: info: Mar 08 11:19:08 usbridge volumio[690]: ---------------------------- MPD announces state update: player Mar 08 11:19:08 usbridge volumio[690]: info: ControllerMpd::getState Mar 08 11:19:08 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand status Mar 08 11:19:08 usbridge volumio[690]: info: sendMpdCommand status took 7 milliseconds Mar 08 11:19:08 usbridge volumio[690]: verbose: ControllerMpd::parseState Mar 08 11:19:08 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 11:19:08 usbridge volumio[690]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 08 11:19:08 usbridge volumio[690]: verbose: ControllerMpd::parseTrackInfo Mar 08 11:19:08 usbridge volumio[690]: info: ControllerMpd::pushState Mar 08 11:19:08 usbridge volumio[690]: info: CoreCommandRouter::servicePushState Mar 08 11:19:08 usbridge volumio[690]: verbose: In UPNP mode Mar 08 11:19:08 usbridge volumio[690]: verbose: STATE SERVICE {"status":"play","position":0,"seek":388000,"duration":605,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"493 Kbps","isStreaming":false,"title":"Poem of Chinese Drum","artist":"Hok-man Yim","album":"Master of Chinese Percussion","uri":"http://192.168.10.87:14562/qobuz/5381610.flac","trackType":"flac"} Mar 08 11:19:08 usbridge volumio[690]: verbose: CURRENT POSITION 0 Mar 08 11:19:08 usbridge volumio[690]: info: CoreStateMachine::syncState stateService play Mar 08 11:19:08 usbridge volumio[690]: info: CoreStateMachine::syncState currentStatus play Mar 08 11:19:08 usbridge volumio[690]: info: Received an update from plugin. extracting info from payload Mar 08 11:19:08 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 11:19:08 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:19:08 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:08 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:08 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:19:08 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:08 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:08 usbridge volumio[690]: info: ------------------------------ 73ms Mar 08 11:19:08 usbridge volumio[690]: info: CoreCommandRouter::volumioGetQueue Mar 08 11:19:08 usbridge volumio[690]: info: CoreStateMachine::getQueue Mar 08 11:19:08 usbridge volumio[690]: info: CorePlayQueue::getQueue Mar 08 11:19:08 usbridge volumio[690]: info: CoreCommandRouter::volumioGetQueue Mar 08 11:19:08 usbridge volumio[690]: info: CoreStateMachine::getQueue Mar 08 11:19:08 usbridge volumio[690]: info: CorePlayQueue::getQueue Mar 08 11:19:10 usbridge volumio[690]: info: Mar 08 11:19:10 usbridge volumio[690]: ---------------------------- MPD announces state update: player Mar 08 11:19:10 usbridge volumio[690]: info: ControllerMpd::getState Mar 08 11:19:10 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand status Mar 08 11:19:10 usbridge volumio[690]: info: sendMpdCommand status took 4 milliseconds Mar 08 11:19:10 usbridge volumio[690]: verbose: ControllerMpd::parseState Mar 08 11:19:10 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 11:19:10 usbridge volumio[690]: info: sendMpdCommand playlistinfo took 22 milliseconds Mar 08 11:19:10 usbridge volumio[690]: verbose: ControllerMpd::parseTrackInfo Mar 08 11:19:10 usbridge volumio[690]: info: ControllerMpd::pushState Mar 08 11:19:10 usbridge volumio[690]: info: CoreCommandRouter::servicePushState Mar 08 11:19:10 usbridge volumio[690]: verbose: In UPNP mode Mar 08 11:19:10 usbridge volumio[690]: verbose: STATE SERVICE {"status":"play","position":0,"seek":437000,"duration":605,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"439 Kbps","isStreaming":false,"title":"Poem of Chinese Drum","artist":"Hok-man Yim","album":"Master of Chinese Percussion","uri":"http://192.168.10.87:14562/qobuz/5381610.flac","trackType":"flac"} Mar 08 11:19:10 usbridge volumio[690]: verbose: CURRENT POSITION 0 Mar 08 11:19:10 usbridge volumio[690]: info: CoreStateMachine::syncState stateService play Mar 08 11:19:10 usbridge volumio[690]: info: CoreStateMachine::syncState currentStatus play Mar 08 11:19:10 usbridge volumio[690]: info: Received an update from plugin. extracting info from payload Mar 08 11:19:10 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 11:19:10 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:19:10 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:10 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:10 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:19:10 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:10 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:10 usbridge volumio[690]: info: ------------------------------ 83ms Mar 08 11:19:10 usbridge volumio[690]: info: CoreCommandRouter::volumioGetQueue Mar 08 11:19:10 usbridge volumio[690]: info: CoreStateMachine::getQueue Mar 08 11:19:10 usbridge volumio[690]: info: CorePlayQueue::getQueue Mar 08 11:19:10 usbridge volumio[690]: info: CoreCommandRouter::volumioGetQueue Mar 08 11:19:10 usbridge volumio[690]: info: CoreStateMachine::getQueue Mar 08 11:19:10 usbridge volumio[690]: info: CorePlayQueue::getQueue Mar 08 11:19:23 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 11:19:23 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 11:19:23 usbridge volumio[690]: info: Discovery: Getting this device information Mar 08 11:19:23 usbridge volumio[690]: info: CoreCommandRouter::volumioGetState Mar 08 11:19:23 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Mar 08 11:19:23 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: system , getConf Mar 08 11:19:23 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: system , getConf Mar 08 11:19:34 usbridge volumio[690]: info: Mar 08 11:19:34 usbridge volumio[690]: ---------------------------- MPD announces state update: player Mar 08 11:19:34 usbridge volumio[690]: info: ControllerMpd::getState Mar 08 11:19:34 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand status Mar 08 11:19:34 usbridge volumio[690]: info: sendMpdCommand status took 1 milliseconds Mar 08 11:19:34 usbridge volumio[690]: verbose: ControllerMpd::parseState Mar 08 11:19:34 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 11:19:34 usbridge kernel: aotg_hub_endpoint_disable Mar 08 11:19:34 usbridge kernel: aotg_hcd aotg_hcd.1: ep5 index 1 from ep [out] Mar 08 11:19:34 usbridge kernel: aotg_hub_endpoint_disable Mar 08 11:19:34 usbridge kernel: aotg_hcd aotg_hcd.1: ep1 index 2 from ep [in] Mar 08 11:19:34 usbridge volumio[690]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 08 11:19:34 usbridge volumio[690]: verbose: ControllerMpd::parseTrackInfo Mar 08 11:19:34 usbridge volumio[690]: info: ControllerMpd::pushState Mar 08 11:19:34 usbridge volumio[690]: info: CoreCommandRouter::servicePushState Mar 08 11:19:34 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:19:34 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:34 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:34 usbridge volumio[690]: info: CorePlayQueue::getTrack 0 Mar 08 11:19:34 usbridge volumio[690]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Poem of Chinese Drum","artist":"Hok-man Yim","album":"Master of Chinese Percussion","uri":"http://192.168.10.87:14562/qobuz/5381610.flac","trackType":"flac"} Mar 08 11:19:34 usbridge volumio[690]: verbose: CURRENT POSITION 0 Mar 08 11:19:34 usbridge volumio[690]: info: CoreStateMachine::syncState stateService stop Mar 08 11:19:34 usbridge volumio[690]: info: CoreStateMachine::syncState currentStatus play Mar 08 11:19:34 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:34 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:34 usbridge volumio[690]: info: CoreStateMachine::stPlaybackTimer Mar 08 11:19:34 usbridge volumio[690]: info: ------------------------------ 36ms Mar 08 11:19:34 usbridge volumio[690]: info: CoreCommandRouter::volumioGetQueue Mar 08 11:19:34 usbridge volumio[690]: info: CoreStateMachine::getQueue Mar 08 11:19:34 usbridge volumio[690]: info: CorePlayQueue::getQueue Mar 08 11:19:35 usbridge volumio[690]: info: Clearing queue after UPNP request Mar 08 11:19:35 usbridge volumio[690]: info: CoreStateMachine::ClearQueue Mar 08 11:19:35 usbridge volumio[690]: info: CoreStateMachine::stop Mar 08 11:19:35 usbridge volumio[690]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 11:19:35 usbridge volumio[690]: verbose: UNSET VOLATILE Mar 08 11:19:35 usbridge volumio[690]: info: CorePlayQueue::clearPlayQueue Mar 08 11:19:35 usbridge volumio[690]: info: CorePlayQueue::saveQueue Mar 08 11:19:35 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:35 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:35 usbridge volumio[690]: info: CoreCommandRouter::volumioPushQueue Mar 08 11:19:35 usbridge volumio[690]: info: Mar 08 11:19:35 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:35 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:35 usbridge volumio[690]: error: updateQueue error: null Mar 08 11:19:35 usbridge volumio[690]: info: ------------------------------ 4ms Mar 08 11:19:35 usbridge volumio[690]: info: Starting UPNP Playback Mar 08 11:19:35 usbridge volumio[690]: info: Preparing playback through UPNP Mar 08 11:19:35 usbridge volumio[690]: info: CoreCommandRouter::volumioGetState Mar 08 11:19:35 usbridge volumio[690]: info: CorePlayQueue::getTrack 0 Mar 08 11:19:35 usbridge volumio[690]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 11:19:36 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 11:19:36 usbridge volumio[690]: info: Mar 08 11:19:36 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:36 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:36 usbridge volumio[690]: info: Mar 08 11:19:36 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:36 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:36 usbridge volumio[690]: info: ------------------------------ 16ms Mar 08 11:19:36 usbridge volumio[690]: info: ------------------------------ 4ms Mar 08 11:19:36 usbridge volumio[690]: info: Mar 08 11:19:36 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:36 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:36 usbridge volumio[690]: info: Mar 08 11:19:36 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:36 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:36 usbridge volumio[690]: info: ------------------------------ 5ms Mar 08 11:19:36 usbridge volumio[690]: info: ------------------------------ 4ms Mar 08 11:19:36 usbridge volumio[690]: info: Mar 08 11:19:36 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:36 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:36 usbridge volumio[690]: info: Mar 08 11:19:36 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:36 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:36 usbridge volumio[690]: info: ------------------------------ 6ms Mar 08 11:19:36 usbridge volumio[690]: info: ------------------------------ 4ms Mar 08 11:19:36 usbridge volumio[690]: info: Mar 08 11:19:36 usbridge volumio[690]: ---------------------------- MPD announces state update: player Mar 08 11:19:36 usbridge volumio[690]: info: ControllerMpd::getState Mar 08 11:19:36 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand status Mar 08 11:19:36 usbridge kernel: iso_packets:0, bInterval:1, urb_interval:1, reg_con:0x4 Mar 08 11:19:36 usbridge kernel: iso_packets:0, bInterval:4, urb_interval:8, reg_con:0x4 Mar 08 11:19:36 usbridge volumio[690]: info: sendMpdCommand status took 23 milliseconds Mar 08 11:19:36 usbridge volumio[690]: verbose: ControllerMpd::parseState Mar 08 11:19:36 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 11:19:36 usbridge volumio[690]: info: Mar 08 11:19:36 usbridge volumio[690]: ---------------------------- MPD announces state update: player Mar 08 11:19:36 usbridge volumio[690]: info: ControllerMpd::getState Mar 08 11:19:36 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand status Mar 08 11:19:36 usbridge volumio[690]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 08 11:19:36 usbridge volumio[690]: verbose: ControllerMpd::parseTrackInfo Mar 08 11:19:36 usbridge volumio[690]: info: ControllerMpd::pushState Mar 08 11:19:36 usbridge volumio[690]: info: CoreCommandRouter::servicePushState Mar 08 11:19:36 usbridge volumio[690]: verbose: In UPNP mode Mar 08 11:19:36 usbridge volumio[690]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":325,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Take Five","artist":"Dave Brubeck","album":"Time Out (Legacy Edition)","uri":"http://192.168.10.87:14562/qobuz/458302.flac","trackType":"flac"} Mar 08 11:19:36 usbridge volumio[690]: verbose: CURRENT POSITION 0 Mar 08 11:19:36 usbridge volumio[690]: info: CoreStateMachine::syncState stateService play Mar 08 11:19:36 usbridge volumio[690]: info: CoreStateMachine::syncState currentStatus stop Mar 08 11:19:36 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:19:36 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:36 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:36 usbridge volumio[690]: info: ------------------------------ 73ms Mar 08 11:19:36 usbridge volumio[690]: info: sendMpdCommand status took 39 milliseconds Mar 08 11:19:36 usbridge volumio[690]: verbose: ControllerMpd::parseState Mar 08 11:19:36 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 11:19:36 usbridge volumio[690]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 08 11:19:36 usbridge volumio[690]: verbose: ControllerMpd::parseTrackInfo Mar 08 11:19:36 usbridge volumio[690]: info: ControllerMpd::pushState Mar 08 11:19:36 usbridge volumio[690]: info: CoreCommandRouter::servicePushState Mar 08 11:19:36 usbridge volumio[690]: verbose: In UPNP mode Mar 08 11:19:36 usbridge volumio[690]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":325,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Take Five","artist":"Dave Brubeck","album":"Time Out (Legacy Edition)","uri":"http://192.168.10.87:14562/qobuz/458302.flac","trackType":"flac"} Mar 08 11:19:36 usbridge volumio[690]: verbose: CURRENT POSITION 0 Mar 08 11:19:36 usbridge volumio[690]: info: CoreStateMachine::syncState stateService play Mar 08 11:19:36 usbridge volumio[690]: info: CoreStateMachine::syncState currentStatus play Mar 08 11:19:36 usbridge volumio[690]: info: Received an update from plugin. extracting info from payload Mar 08 11:19:36 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 11:19:36 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:19:36 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:36 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:36 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:19:36 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:36 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:36 usbridge volumio[690]: info: ------------------------------ 108ms Mar 08 11:19:36 usbridge volumio[690]: info: CoreCommandRouter::volumioGetQueue Mar 08 11:19:36 usbridge volumio[690]: info: CoreStateMachine::getQueue Mar 08 11:19:36 usbridge volumio[690]: info: CorePlayQueue::getQueue Mar 08 11:19:36 usbridge volumio[690]: info: CoreCommandRouter::volumioGetQueue Mar 08 11:19:36 usbridge volumio[690]: info: CoreStateMachine::getQueue Mar 08 11:19:36 usbridge volumio[690]: info: CorePlayQueue::getQueue Mar 08 11:19:36 usbridge kernel: TX ERROR status: 0x007e0000 Mar 08 11:19:39 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 11:19:39 usbridge volumio[690]: info: Mar 08 11:19:39 usbridge volumio[690]: ---------------------------- MPD announces state update: player Mar 08 11:19:39 usbridge volumio[690]: info: ControllerMpd::getState Mar 08 11:19:39 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand status Mar 08 11:19:39 usbridge volumio[690]: info: sendMpdCommand status took 3 milliseconds Mar 08 11:19:39 usbridge volumio[690]: verbose: ControllerMpd::parseState Mar 08 11:19:39 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 11:19:39 usbridge volumio[690]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 08 11:19:39 usbridge volumio[690]: verbose: ControllerMpd::parseTrackInfo Mar 08 11:19:39 usbridge volumio[690]: info: ControllerMpd::pushState Mar 08 11:19:39 usbridge volumio[690]: info: CoreCommandRouter::servicePushState Mar 08 11:19:39 usbridge volumio[690]: verbose: In UPNP mode Mar 08 11:19:39 usbridge volumio[690]: verbose: STATE SERVICE {"status":"play","position":0,"seek":26000,"duration":325,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"726 Kbps","isStreaming":false,"title":"Take Five","artist":"Dave Brubeck","album":"Time Out (Legacy Edition)","uri":"http://192.168.10.87:14562/qobuz/458302.flac","trackType":"flac"} Mar 08 11:19:39 usbridge volumio[690]: verbose: CURRENT POSITION 0 Mar 08 11:19:39 usbridge volumio[690]: info: CoreStateMachine::syncState stateService play Mar 08 11:19:39 usbridge volumio[690]: info: CoreStateMachine::syncState currentStatus play Mar 08 11:19:39 usbridge volumio[690]: info: Received an update from plugin. extracting info from payload Mar 08 11:19:39 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 11:19:39 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:19:39 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:39 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:39 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:19:39 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:39 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:39 usbridge volumio[690]: info: ------------------------------ 98ms Mar 08 11:19:39 usbridge volumio[690]: info: CoreCommandRouter::volumioGetQueue Mar 08 11:19:39 usbridge volumio[690]: info: CoreStateMachine::getQueue Mar 08 11:19:39 usbridge volumio[690]: info: CorePlayQueue::getQueue Mar 08 11:19:39 usbridge volumio[690]: info: CoreCommandRouter::volumioGetQueue Mar 08 11:19:39 usbridge volumio[690]: info: CoreStateMachine::getQueue Mar 08 11:19:39 usbridge volumio[690]: info: CorePlayQueue::getQueue Mar 08 11:19:39 usbridge kernel: TX ERROR status: 0x007e0000 Mar 08 11:19:47 usbridge volumio[690]: info: Mar 08 11:19:47 usbridge volumio[690]: ---------------------------- MPD announces state update: player Mar 08 11:19:47 usbridge volumio[690]: info: ControllerMpd::getState Mar 08 11:19:47 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand status Mar 08 11:19:47 usbridge volumio[690]: info: sendMpdCommand status took 2 milliseconds Mar 08 11:19:47 usbridge volumio[690]: verbose: ControllerMpd::parseState Mar 08 11:19:47 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 11:19:47 usbridge kernel: aotg_hub_endpoint_disable Mar 08 11:19:47 usbridge kernel: aotg_hcd aotg_hcd.1: ep5 index 1 from ep [out] Mar 08 11:19:47 usbridge kernel: aotg_hub_endpoint_disable Mar 08 11:19:47 usbridge kernel: aotg_hcd aotg_hcd.1: ep1 index 2 from ep [in] Mar 08 11:19:47 usbridge volumio[690]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 08 11:19:47 usbridge volumio[690]: verbose: ControllerMpd::parseTrackInfo Mar 08 11:19:47 usbridge volumio[690]: info: ControllerMpd::pushState Mar 08 11:19:47 usbridge volumio[690]: info: CoreCommandRouter::servicePushState Mar 08 11:19:47 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:19:47 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:47 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:47 usbridge volumio[690]: info: CorePlayQueue::getTrack 0 Mar 08 11:19:47 usbridge volumio[690]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Take Five","artist":"Dave Brubeck","album":"Time Out (Legacy Edition)","uri":"http://192.168.10.87:14562/qobuz/458302.flac","trackType":"flac"} Mar 08 11:19:47 usbridge volumio[690]: verbose: CURRENT POSITION 0 Mar 08 11:19:47 usbridge volumio[690]: info: CoreStateMachine::syncState stateService stop Mar 08 11:19:47 usbridge volumio[690]: info: CoreStateMachine::syncState currentStatus play Mar 08 11:19:47 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:47 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:47 usbridge volumio[690]: info: CoreStateMachine::stPlaybackTimer Mar 08 11:19:47 usbridge volumio[690]: info: ------------------------------ 45ms Mar 08 11:19:47 usbridge volumio[690]: info: CoreCommandRouter::volumioGetQueue Mar 08 11:19:47 usbridge volumio[690]: info: CoreStateMachine::getQueue Mar 08 11:19:47 usbridge volumio[690]: info: CorePlayQueue::getQueue Mar 08 11:19:47 usbridge volumio[690]: info: Clearing queue after UPNP request Mar 08 11:19:47 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 11:19:48 usbridge volumio[690]: info: CoreStateMachine::ClearQueue Mar 08 11:19:48 usbridge volumio[690]: info: CoreStateMachine::stop Mar 08 11:19:48 usbridge volumio[690]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 11:19:48 usbridge volumio[690]: verbose: UNSET VOLATILE Mar 08 11:19:48 usbridge volumio[690]: info: CorePlayQueue::clearPlayQueue Mar 08 11:19:48 usbridge volumio[690]: info: CorePlayQueue::saveQueue Mar 08 11:19:48 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:48 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:48 usbridge volumio[690]: info: CoreCommandRouter::volumioPushQueue Mar 08 11:19:48 usbridge volumio[690]: info: Mar 08 11:19:48 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:48 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:48 usbridge volumio[690]: error: updateQueue error: null Mar 08 11:19:48 usbridge volumio[690]: info: ------------------------------ 3ms Mar 08 11:19:48 usbridge volumio[690]: info: Starting UPNP Playback Mar 08 11:19:48 usbridge volumio[690]: info: Preparing playback through UPNP Mar 08 11:19:48 usbridge volumio[690]: info: CoreCommandRouter::volumioGetState Mar 08 11:19:48 usbridge volumio[690]: info: CorePlayQueue::getTrack 0 Mar 08 11:19:48 usbridge volumio[690]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 11:19:48 usbridge volumio[690]: info: Mar 08 11:19:48 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:48 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:48 usbridge volumio[690]: info: Mar 08 11:19:48 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:48 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:48 usbridge volumio[690]: info: ------------------------------ 6ms Mar 08 11:19:48 usbridge volumio[690]: info: ------------------------------ 5ms Mar 08 11:19:48 usbridge volumio[690]: info: Mar 08 11:19:48 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:48 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:48 usbridge volumio[690]: info: ------------------------------ 4ms Mar 08 11:19:48 usbridge volumio[690]: info: Mar 08 11:19:48 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:48 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:48 usbridge volumio[690]: info: ------------------------------ 4ms Mar 08 11:19:48 usbridge volumio[690]: info: Mar 08 11:19:48 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:48 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:48 usbridge volumio[690]: info: ------------------------------ 3ms Mar 08 11:19:48 usbridge volumio[690]: info: Mar 08 11:19:48 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:48 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:48 usbridge volumio[690]: info: ------------------------------ 4ms Mar 08 11:19:48 usbridge volumio[690]: info: Clearing queue after UPNP request Mar 08 11:19:49 usbridge volumio[690]: info: CoreStateMachine::ClearQueue Mar 08 11:19:49 usbridge volumio[690]: info: CoreStateMachine::stop Mar 08 11:19:49 usbridge volumio[690]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 11:19:49 usbridge volumio[690]: verbose: UNSET VOLATILE Mar 08 11:19:49 usbridge volumio[690]: info: CorePlayQueue::clearPlayQueue Mar 08 11:19:49 usbridge volumio[690]: info: CorePlayQueue::saveQueue Mar 08 11:19:49 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:49 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:49 usbridge volumio[690]: info: CoreCommandRouter::volumioPushQueue Mar 08 11:19:49 usbridge volumio[690]: info: Mar 08 11:19:49 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:49 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:49 usbridge volumio[690]: error: updateQueue error: null Mar 08 11:19:49 usbridge volumio[690]: info: ------------------------------ 4ms Mar 08 11:19:49 usbridge volumio[690]: info: Starting UPNP Playback Mar 08 11:19:49 usbridge volumio[690]: info: Preparing playback through UPNP Mar 08 11:19:49 usbridge volumio[690]: info: CoreCommandRouter::volumioGetState Mar 08 11:19:49 usbridge volumio[690]: info: CorePlayQueue::getTrack 0 Mar 08 11:19:49 usbridge volumio[690]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 11:19:49 usbridge volumio[690]: info: Mar 08 11:19:49 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:49 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:49 usbridge volumio[690]: info: Mar 08 11:19:49 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:49 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:49 usbridge volumio[690]: info: ------------------------------ 5ms Mar 08 11:19:49 usbridge volumio[690]: info: ------------------------------ 4ms Mar 08 11:19:49 usbridge volumio[690]: info: Mar 08 11:19:49 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:49 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:49 usbridge volumio[690]: info: ------------------------------ 4ms Mar 08 11:19:49 usbridge volumio[690]: info: Mar 08 11:19:49 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:49 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:49 usbridge volumio[690]: info: ------------------------------ 3ms Mar 08 11:19:49 usbridge volumio[690]: info: Mar 08 11:19:49 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:49 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:49 usbridge volumio[690]: info: ------------------------------ 4ms Mar 08 11:19:49 usbridge volumio[690]: info: Mar 08 11:19:49 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:19:49 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:19:49 usbridge volumio[690]: info: ------------------------------ 3ms Mar 08 11:19:49 usbridge volumio[690]: info: Mar 08 11:19:49 usbridge volumio[690]: ---------------------------- MPD announces state update: player Mar 08 11:19:49 usbridge volumio[690]: info: ControllerMpd::getState Mar 08 11:19:49 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand status Mar 08 11:19:49 usbridge kernel: iso_packets:0, bInterval:1, urb_interval:1, reg_con:0x4 Mar 08 11:19:49 usbridge kernel: iso_packets:0, bInterval:4, urb_interval:8, reg_con:0x4 Mar 08 11:19:49 usbridge volumio[690]: info: sendMpdCommand status took 22 milliseconds Mar 08 11:19:49 usbridge volumio[690]: verbose: ControllerMpd::parseState Mar 08 11:19:49 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 11:19:49 usbridge volumio[690]: info: Mar 08 11:19:49 usbridge volumio[690]: ---------------------------- MPD announces state update: player Mar 08 11:19:49 usbridge volumio[690]: info: ControllerMpd::getState Mar 08 11:19:49 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand status Mar 08 11:19:49 usbridge volumio[690]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 08 11:19:49 usbridge volumio[690]: verbose: ControllerMpd::parseTrackInfo Mar 08 11:19:49 usbridge volumio[690]: info: ControllerMpd::pushState Mar 08 11:19:49 usbridge volumio[690]: info: CoreCommandRouter::servicePushState Mar 08 11:19:49 usbridge volumio[690]: verbose: In UPNP mode Mar 08 11:19:50 usbridge volumio[690]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":605,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Poem of Chinese Drum","artist":"Hok-man Yim","album":"Master of Chinese Percussion","uri":"http://192.168.10.87:14562/qobuz/5381610.flac","trackType":"flac"} Mar 08 11:19:50 usbridge volumio[690]: verbose: CURRENT POSITION 0 Mar 08 11:19:50 usbridge volumio[690]: info: CoreStateMachine::syncState stateService play Mar 08 11:19:50 usbridge volumio[690]: info: CoreStateMachine::syncState currentStatus stop Mar 08 11:19:50 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:19:50 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:50 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:50 usbridge volumio[690]: info: ------------------------------ 73ms Mar 08 11:19:50 usbridge volumio[690]: info: sendMpdCommand status took 41 milliseconds Mar 08 11:19:50 usbridge volumio[690]: verbose: ControllerMpd::parseState Mar 08 11:19:50 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 11:19:50 usbridge volumio[690]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 08 11:19:50 usbridge volumio[690]: verbose: ControllerMpd::parseTrackInfo Mar 08 11:19:50 usbridge volumio[690]: info: ControllerMpd::pushState Mar 08 11:19:50 usbridge volumio[690]: info: CoreCommandRouter::servicePushState Mar 08 11:19:50 usbridge volumio[690]: verbose: In UPNP mode Mar 08 11:19:50 usbridge volumio[690]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":605,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"488 Kbps","isStreaming":false,"title":"Poem of Chinese Drum","artist":"Hok-man Yim","album":"Master of Chinese Percussion","uri":"http://192.168.10.87:14562/qobuz/5381610.flac","trackType":"flac"} Mar 08 11:19:50 usbridge volumio[690]: verbose: CURRENT POSITION 0 Mar 08 11:19:50 usbridge volumio[690]: info: CoreStateMachine::syncState stateService play Mar 08 11:19:50 usbridge volumio[690]: info: CoreStateMachine::syncState currentStatus play Mar 08 11:19:50 usbridge volumio[690]: info: Received an update from plugin. extracting info from payload Mar 08 11:19:50 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 11:19:50 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:19:50 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:50 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:50 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:19:50 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:19:50 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:19:50 usbridge volumio[690]: info: ------------------------------ 106ms Mar 08 11:19:50 usbridge volumio[690]: info: CoreCommandRouter::volumioGetQueue Mar 08 11:19:50 usbridge volumio[690]: info: CoreStateMachine::getQueue Mar 08 11:19:50 usbridge volumio[690]: info: CorePlayQueue::getQueue Mar 08 11:19:50 usbridge volumio[690]: info: CoreCommandRouter::volumioGetQueue Mar 08 11:19:50 usbridge volumio[690]: info: CoreStateMachine::getQueue Mar 08 11:19:50 usbridge volumio[690]: info: CorePlayQueue::getQueue Mar 08 11:19:50 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 11:19:53 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 11:19:53 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 11:19:53 usbridge volumio[690]: info: Discovery: Getting this device information Mar 08 11:19:53 usbridge volumio[690]: info: CoreCommandRouter::volumioGetState Mar 08 11:19:53 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Mar 08 11:19:53 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: system , getConf Mar 08 11:19:53 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: system , getConf Mar 08 11:20:01 usbridge volumio[690]: info: Mar 08 11:20:01 usbridge volumio[690]: ---------------------------- MPD announces state update: player Mar 08 11:20:01 usbridge volumio[690]: info: ControllerMpd::getState Mar 08 11:20:01 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand status Mar 08 11:20:01 usbridge volumio[690]: info: sendMpdCommand status took 1 milliseconds Mar 08 11:20:01 usbridge volumio[690]: verbose: ControllerMpd::parseState Mar 08 11:20:01 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 11:20:01 usbridge kernel: aotg_hub_endpoint_disable Mar 08 11:20:01 usbridge kernel: aotg_hcd aotg_hcd.1: ep5 index 1 from ep [out] Mar 08 11:20:01 usbridge kernel: aotg_hub_endpoint_disable Mar 08 11:20:01 usbridge kernel: aotg_hcd aotg_hcd.1: ep1 index 2 from ep [in] Mar 08 11:20:01 usbridge volumio[690]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 08 11:20:01 usbridge volumio[690]: verbose: ControllerMpd::parseTrackInfo Mar 08 11:20:01 usbridge volumio[690]: info: ControllerMpd::pushState Mar 08 11:20:01 usbridge volumio[690]: info: CoreCommandRouter::servicePushState Mar 08 11:20:01 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:20:01 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:20:01 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:20:01 usbridge volumio[690]: info: CorePlayQueue::getTrack 0 Mar 08 11:20:01 usbridge volumio[690]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Poem of Chinese Drum","artist":"Hok-man Yim","album":"Master of Chinese Percussion","uri":"http://192.168.10.87:14562/qobuz/5381610.flac","trackType":"flac"} Mar 08 11:20:01 usbridge volumio[690]: verbose: CURRENT POSITION 0 Mar 08 11:20:01 usbridge volumio[690]: info: CoreStateMachine::syncState stateService stop Mar 08 11:20:01 usbridge volumio[690]: info: CoreStateMachine::syncState currentStatus play Mar 08 11:20:01 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:20:01 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:20:01 usbridge volumio[690]: info: CoreStateMachine::stPlaybackTimer Mar 08 11:20:01 usbridge volumio[690]: info: ------------------------------ 33ms Mar 08 11:20:01 usbridge volumio[690]: info: CoreCommandRouter::volumioGetQueue Mar 08 11:20:01 usbridge volumio[690]: info: CoreStateMachine::getQueue Mar 08 11:20:01 usbridge volumio[690]: info: CorePlayQueue::getQueue Mar 08 11:20:01 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 11:20:03 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 11:20:03 usbridge volumio[690]: info: Clearing queue after UPNP request Mar 08 11:20:03 usbridge volumio[690]: info: CoreStateMachine::ClearQueue Mar 08 11:20:03 usbridge volumio[690]: info: CoreStateMachine::stop Mar 08 11:20:03 usbridge volumio[690]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 11:20:03 usbridge volumio[690]: verbose: UNSET VOLATILE Mar 08 11:20:03 usbridge volumio[690]: info: CorePlayQueue::clearPlayQueue Mar 08 11:20:03 usbridge volumio[690]: info: CorePlayQueue::saveQueue Mar 08 11:20:03 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:20:03 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:20:03 usbridge volumio[690]: info: CoreCommandRouter::volumioPushQueue Mar 08 11:20:03 usbridge volumio[690]: info: Mar 08 11:20:03 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:20:03 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:20:03 usbridge volumio[690]: error: updateQueue error: null Mar 08 11:20:03 usbridge volumio[690]: info: ------------------------------ 4ms Mar 08 11:20:03 usbridge volumio[690]: info: Starting UPNP Playback Mar 08 11:20:03 usbridge volumio[690]: info: Preparing playback through UPNP Mar 08 11:20:03 usbridge volumio[690]: info: CoreCommandRouter::volumioGetState Mar 08 11:20:03 usbridge volumio[690]: info: CorePlayQueue::getTrack 0 Mar 08 11:20:03 usbridge volumio[690]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 11:20:04 usbridge volumio[690]: info: Mar 08 11:20:04 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:20:04 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:20:04 usbridge volumio[690]: info: Mar 08 11:20:04 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:20:04 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:20:04 usbridge volumio[690]: info: ------------------------------ 6ms Mar 08 11:20:04 usbridge volumio[690]: info: ------------------------------ 6ms Mar 08 11:20:04 usbridge volumio[690]: info: Mar 08 11:20:04 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:20:04 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:20:04 usbridge volumio[690]: info: Mar 08 11:20:04 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:20:04 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:20:04 usbridge volumio[690]: info: ------------------------------ 5ms Mar 08 11:20:04 usbridge volumio[690]: info: ------------------------------ 4ms Mar 08 11:20:04 usbridge volumio[690]: info: Mar 08 11:20:04 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:20:04 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:20:04 usbridge volumio[690]: info: ------------------------------ 3ms Mar 08 11:20:04 usbridge volumio[690]: info: Mar 08 11:20:04 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:20:04 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:20:04 usbridge volumio[690]: info: ------------------------------ 4ms Mar 08 11:20:06 usbridge volumio[690]: info: Mar 08 11:20:06 usbridge volumio[690]: ---------------------------- MPD announces state update: player Mar 08 11:20:06 usbridge volumio[690]: info: ControllerMpd::getState Mar 08 11:20:06 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand status Mar 08 11:20:06 usbridge kernel: iso_packets:0, bInterval:1, urb_interval:1, reg_con:0x4 Mar 08 11:20:06 usbridge kernel: iso_packets:0, bInterval:4, urb_interval:8, reg_con:0x4 Mar 08 11:20:06 usbridge volumio[690]: info: Mar 08 11:20:06 usbridge volumio[690]: ---------------------------- MPD announces state update: player Mar 08 11:20:06 usbridge volumio[690]: info: sendMpdCommand status took 43 milliseconds Mar 08 11:20:06 usbridge volumio[690]: info: ControllerMpd::getState Mar 08 11:20:06 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand status Mar 08 11:20:06 usbridge volumio[690]: verbose: ControllerMpd::parseState Mar 08 11:20:06 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 11:20:06 usbridge volumio[690]: info: sendMpdCommand status took 11 milliseconds Mar 08 11:20:06 usbridge volumio[690]: verbose: ControllerMpd::parseState Mar 08 11:20:06 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 11:20:06 usbridge volumio[690]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 08 11:20:06 usbridge volumio[690]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 08 11:20:06 usbridge volumio[690]: verbose: ControllerMpd::parseTrackInfo Mar 08 11:20:06 usbridge volumio[690]: verbose: ControllerMpd::parseTrackInfo Mar 08 11:20:06 usbridge volumio[690]: info: ControllerMpd::pushState Mar 08 11:20:06 usbridge volumio[690]: info: CoreCommandRouter::servicePushState Mar 08 11:20:06 usbridge volumio[690]: verbose: In UPNP mode Mar 08 11:20:06 usbridge volumio[690]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1008,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Violin Concerto in D Major, Op. 6 - I. Allegro maestoso (Eugene Goossens) ","artist":"Michäel Rabin","album":"Paganini: Violin Concerto in D Major, Op. 6 by Michael Rabin","uri":"http://192.168.10.87:14562/qobuz/147994481.flac","trackType":"flac"} Mar 08 11:20:06 usbridge volumio[690]: verbose: CURRENT POSITION 0 Mar 08 11:20:06 usbridge volumio[690]: info: CoreStateMachine::syncState stateService play Mar 08 11:20:06 usbridge volumio[690]: info: CoreStateMachine::syncState currentStatus stop Mar 08 11:20:06 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:20:06 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:20:06 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:20:06 usbridge volumio[690]: info: ControllerMpd::pushState Mar 08 11:20:06 usbridge volumio[690]: info: CoreCommandRouter::servicePushState Mar 08 11:20:06 usbridge volumio[690]: verbose: In UPNP mode Mar 08 11:20:06 usbridge volumio[690]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1008,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Violin Concerto in D Major, Op. 6 - I. Allegro maestoso (Eugene Goossens) ","artist":"Michäel Rabin","album":"Paganini: Violin Concerto in D Major, Op. 6 by Michael Rabin","uri":"http://192.168.10.87:14562/qobuz/147994481.flac","trackType":"flac"} Mar 08 11:20:06 usbridge volumio[690]: verbose: CURRENT POSITION 0 Mar 08 11:20:06 usbridge volumio[690]: info: CoreStateMachine::syncState stateService play Mar 08 11:20:06 usbridge volumio[690]: info: CoreStateMachine::syncState currentStatus play Mar 08 11:20:06 usbridge volumio[690]: info: Received an update from plugin. extracting info from payload Mar 08 11:20:06 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 11:20:06 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:20:06 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:20:06 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:20:06 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:20:06 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:20:06 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:20:06 usbridge volumio[690]: info: ------------------------------ 157ms Mar 08 11:20:06 usbridge volumio[690]: info: ------------------------------ 116ms Mar 08 11:20:06 usbridge volumio[690]: info: CoreCommandRouter::volumioGetQueue Mar 08 11:20:06 usbridge volumio[690]: info: CoreStateMachine::getQueue Mar 08 11:20:06 usbridge volumio[690]: info: CorePlayQueue::getQueue Mar 08 11:20:06 usbridge volumio[690]: info: CoreCommandRouter::volumioGetQueue Mar 08 11:20:06 usbridge volumio[690]: info: CoreStateMachine::getQueue Mar 08 11:20:06 usbridge volumio[690]: info: CorePlayQueue::getQueue Mar 08 11:20:07 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 11:20:10 usbridge volumio[690]: info: Mar 08 11:20:10 usbridge volumio[690]: ---------------------------- MPD announces state update: player Mar 08 11:20:10 usbridge volumio[690]: info: ControllerMpd::getState Mar 08 11:20:10 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand status Mar 08 11:20:10 usbridge volumio[690]: info: sendMpdCommand status took 1 milliseconds Mar 08 11:20:10 usbridge volumio[690]: verbose: ControllerMpd::parseState Mar 08 11:20:10 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 11:20:10 usbridge kernel: aotg_hub_endpoint_disable Mar 08 11:20:10 usbridge kernel: aotg_hcd aotg_hcd.1: ep5 index 1 from ep [out] Mar 08 11:20:10 usbridge kernel: aotg_hub_endpoint_disable Mar 08 11:20:10 usbridge kernel: aotg_hcd aotg_hcd.1: ep1 index 2 from ep [in] Mar 08 11:20:10 usbridge volumio[690]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 08 11:20:10 usbridge volumio[690]: verbose: ControllerMpd::parseTrackInfo Mar 08 11:20:10 usbridge volumio[690]: info: ControllerMpd::pushState Mar 08 11:20:10 usbridge volumio[690]: info: CoreCommandRouter::servicePushState Mar 08 11:20:10 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:20:10 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:20:10 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:20:10 usbridge volumio[690]: info: CorePlayQueue::getTrack 0 Mar 08 11:20:10 usbridge volumio[690]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Violin Concerto in D Major, Op. 6 - I. Allegro maestoso (Eugene Goossens) ","artist":"Michäel Rabin","album":"Paganini: Violin Concerto in D Major, Op. 6 by Michael Rabin","uri":"http://192.168.10.87:14562/qobuz/147994481.flac","trackType":"flac"} Mar 08 11:20:10 usbridge volumio[690]: verbose: CURRENT POSITION 0 Mar 08 11:20:10 usbridge volumio[690]: info: CoreStateMachine::syncState stateService stop Mar 08 11:20:10 usbridge volumio[690]: info: CoreStateMachine::syncState currentStatus play Mar 08 11:20:10 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:20:10 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:20:10 usbridge volumio[690]: info: CoreStateMachine::stPlaybackTimer Mar 08 11:20:10 usbridge volumio[690]: info: ------------------------------ 34ms Mar 08 11:20:10 usbridge volumio[690]: info: CoreCommandRouter::volumioGetQueue Mar 08 11:20:10 usbridge volumio[690]: info: CoreStateMachine::getQueue Mar 08 11:20:10 usbridge volumio[690]: info: CorePlayQueue::getQueue Mar 08 11:20:11 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 11:20:13 usbridge volumio[690]: info: Clearing queue after UPNP request Mar 08 11:20:13 usbridge volumio[690]: info: CoreStateMachine::ClearQueue Mar 08 11:20:13 usbridge volumio[690]: info: CoreStateMachine::stop Mar 08 11:20:13 usbridge volumio[690]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 11:20:13 usbridge volumio[690]: verbose: UNSET VOLATILE Mar 08 11:20:13 usbridge volumio[690]: info: CorePlayQueue::clearPlayQueue Mar 08 11:20:13 usbridge volumio[690]: info: CorePlayQueue::saveQueue Mar 08 11:20:13 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:20:13 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:20:13 usbridge volumio[690]: info: CoreCommandRouter::volumioPushQueue Mar 08 11:20:13 usbridge volumio[690]: info: Mar 08 11:20:13 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:20:13 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:20:13 usbridge volumio[690]: error: updateQueue error: null Mar 08 11:20:13 usbridge volumio[690]: info: ------------------------------ 4ms Mar 08 11:20:13 usbridge volumio[690]: info: Starting UPNP Playback Mar 08 11:20:13 usbridge volumio[690]: info: Preparing playback through UPNP Mar 08 11:20:13 usbridge volumio[690]: info: CoreCommandRouter::volumioGetState Mar 08 11:20:13 usbridge volumio[690]: info: CorePlayQueue::getTrack 0 Mar 08 11:20:13 usbridge volumio[690]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 11:20:13 usbridge volumio[690]: info: Mar 08 11:20:13 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:20:13 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:20:13 usbridge volumio[690]: info: Mar 08 11:20:13 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:20:13 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:20:13 usbridge volumio[690]: info: ------------------------------ 5ms Mar 08 11:20:13 usbridge volumio[690]: info: ------------------------------ 5ms Mar 08 11:20:13 usbridge volumio[690]: info: Mar 08 11:20:13 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:20:13 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:20:13 usbridge volumio[690]: info: ------------------------------ 3ms Mar 08 11:20:13 usbridge volumio[690]: info: Mar 08 11:20:13 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:20:13 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:20:13 usbridge volumio[690]: info: ------------------------------ 4ms Mar 08 11:20:13 usbridge volumio[690]: info: Mar 08 11:20:13 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:20:13 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:20:13 usbridge volumio[690]: info: ------------------------------ 4ms Mar 08 11:20:13 usbridge volumio[690]: info: Mar 08 11:20:13 usbridge volumio[690]: ---------------------------- MPD announces system playlist update Mar 08 11:20:13 usbridge volumio[690]: info: Ignoring MPD Status Update Mar 08 11:20:13 usbridge volumio[690]: info: ------------------------------ 4ms Mar 08 11:20:14 usbridge volumio[690]: info: Mar 08 11:20:14 usbridge volumio[690]: ---------------------------- MPD announces state update: player Mar 08 11:20:14 usbridge volumio[690]: info: ControllerMpd::getState Mar 08 11:20:14 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand status Mar 08 11:20:14 usbridge kernel: iso_packets:0, bInterval:1, urb_interval:1, reg_con:0x4 Mar 08 11:20:14 usbridge kernel: iso_packets:0, bInterval:4, urb_interval:8, reg_con:0x4 Mar 08 11:20:14 usbridge volumio[690]: info: sendMpdCommand status took 28 milliseconds Mar 08 11:20:14 usbridge volumio[690]: verbose: ControllerMpd::parseState Mar 08 11:20:14 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 11:20:14 usbridge volumio[690]: info: Mar 08 11:20:14 usbridge volumio[690]: ---------------------------- MPD announces state update: player Mar 08 11:20:14 usbridge volumio[690]: info: ControllerMpd::getState Mar 08 11:20:14 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand status Mar 08 11:20:14 usbridge volumio[690]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 08 11:20:14 usbridge volumio[690]: verbose: ControllerMpd::parseTrackInfo Mar 08 11:20:14 usbridge volumio[690]: info: ControllerMpd::pushState Mar 08 11:20:14 usbridge volumio[690]: info: CoreCommandRouter::servicePushState Mar 08 11:20:14 usbridge volumio[690]: verbose: In UPNP mode Mar 08 11:20:14 usbridge volumio[690]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Toccata","artist":"Marcin","album":"Toccata","uri":"http://192.168.10.87:14562/qobuz/145095727.flac","trackType":"flac"} Mar 08 11:20:14 usbridge volumio[690]: verbose: CURRENT POSITION 0 Mar 08 11:20:14 usbridge volumio[690]: info: CoreStateMachine::syncState stateService play Mar 08 11:20:14 usbridge volumio[690]: info: CoreStateMachine::syncState currentStatus stop Mar 08 11:20:14 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:20:14 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:20:14 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:20:14 usbridge volumio[690]: info: ------------------------------ 76ms Mar 08 11:20:14 usbridge volumio[690]: info: sendMpdCommand status took 39 milliseconds Mar 08 11:20:14 usbridge volumio[690]: verbose: ControllerMpd::parseState Mar 08 11:20:14 usbridge volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 11:20:14 usbridge volumio[690]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 08 11:20:14 usbridge volumio[690]: verbose: ControllerMpd::parseTrackInfo Mar 08 11:20:14 usbridge volumio[690]: info: ControllerMpd::pushState Mar 08 11:20:14 usbridge volumio[690]: info: CoreCommandRouter::servicePushState Mar 08 11:20:14 usbridge volumio[690]: verbose: In UPNP mode Mar 08 11:20:14 usbridge volumio[690]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1645 Kbps","isStreaming":false,"title":"Toccata","artist":"Marcin","album":"Toccata","uri":"http://192.168.10.87:14562/qobuz/145095727.flac","trackType":"flac"} Mar 08 11:20:14 usbridge volumio[690]: verbose: CURRENT POSITION 0 Mar 08 11:20:14 usbridge volumio[690]: info: CoreStateMachine::syncState stateService play Mar 08 11:20:14 usbridge volumio[690]: info: CoreStateMachine::syncState currentStatus play Mar 08 11:20:14 usbridge volumio[690]: info: Received an update from plugin. extracting info from payload Mar 08 11:20:14 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 11:20:14 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:20:14 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:20:14 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:20:14 usbridge volumio[690]: info: CoreStateMachine::pushState Mar 08 11:20:14 usbridge volumio[690]: info: CoreCommandRouter::volumioPushState Mar 08 11:20:14 usbridge volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 11:20:14 usbridge volumio[690]: info: ------------------------------ 119ms Mar 08 11:20:14 usbridge volumio[690]: info: CoreCommandRouter::volumioGetQueue Mar 08 11:20:14 usbridge volumio[690]: info: CoreStateMachine::getQueue Mar 08 11:20:14 usbridge volumio[690]: info: CorePlayQueue::getQueue Mar 08 11:20:14 usbridge volumio[690]: info: CoreCommandRouter::volumioGetQueue Mar 08 11:20:14 usbridge volumio[690]: info: CoreStateMachine::getQueue Mar 08 11:20:14 usbridge volumio[690]: info: CorePlayQueue::getQueue Mar 08 11:20:14 usbridge volumio[690]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 11:20:14 usbridge volumio[690]: { Error: certificate is not yet valid Mar 08 11:20:14 usbridge volumio[690]: at TLSSocket. (_tls_wrap.js:1105:38) Mar 08 11:20:14 usbridge volumio[690]: at emitNone (events.js:106:13) Mar 08 11:20:14 usbridge volumio[690]: at TLSSocket.emit (events.js:208:7) Mar 08 11:20:14 usbridge volumio[690]: at TLSSocket._finishInit (_tls_wrap.js:639:8) Mar 08 11:20:14 usbridge volumio[690]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:469:38) code: 'CERT_NOT_YET_VALID' } Mar 08 11:20:14 usbridge volumio[690]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 11:20:15 usbridge sudo[6031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-08 11:19 Mar 08 11:20:15 usbridge sudo[6031]: pam_unix(sudo:session): session opened for user root by (uid=0) 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="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 12:12:00 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="sparky" VOLUMIO_HASH="1507c65028120e16c14994c066bc5dd4"