-- Logs begin at Thu 2019-02-14 21:11:59 AEDT, end at Tue 2024-07-23 03:34:59 AEST. -- Jul 23 03:33:31 volumio ntpd[795]: Soliciting pool server 119.18.6.37 Jul 23 03:33:32 volumio ntpd[795]: Soliciting pool server 194.195.249.28 Jul 23 03:33:33 volumio ntpd[795]: Soliciting pool server 67.219.100.202 Jul 23 03:33:33 volumio ntpd[795]: Soliciting pool server 103.152.64.212 Jul 23 03:33:45 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:33:45 volumio volumio[1007]: info: CorePlayQueue::getTrack 1 Jul 23 03:33:45 volumio volumio[1007]: info: Prefetching next song Jul 23 03:33:45 volumio volumio[1007]: info: DOING PREFETCH IN MPD Jul 23 03:33:45 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand add "USB/1C02-385B/Steely Dan/Countdown To Ecstasy (MCD 01654)/02 Razor Boy.flac" Jul 23 03:33:45 volumio volumio[1007]: info: sendMpdCommand add "USB/1C02-385B/Steely Dan/Countdown To Ecstasy (MCD 01654)/02 Razor Boy.flac" took 1 milliseconds Jul 23 03:33:45 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 23 03:33:45 volumio volumio[1007]: info: Jul 23 03:33:45 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Jul 23 03:33:45 volumio volumio[1007]: info: Ignoring MPD Status Update Jul 23 03:33:45 volumio volumio[1007]: info: Jul 23 03:33:45 volumio volumio[1007]: ---------------------------- MPD announces state update: options Jul 23 03:33:45 volumio volumio[1007]: info: sendMpdCommand consume 1 took 2 milliseconds Jul 23 03:33:45 volumio volumio[1007]: info: ControllerMpd::getState Jul 23 03:33:45 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Jul 23 03:33:45 volumio volumio[1007]: info: Jul 23 03:33:45 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Jul 23 03:33:45 volumio volumio[1007]: info: Ignoring MPD Status Update Jul 23 03:33:45 volumio volumio[1007]: info: Jul 23 03:33:45 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Jul 23 03:33:45 volumio volumio[1007]: info: Ignoring MPD Status Update Jul 23 03:33:45 volumio volumio[1007]: info: ------------------------------ 4ms Jul 23 03:33:45 volumio volumio[1007]: info: sendMpdCommand status took 4 milliseconds Jul 23 03:33:45 volumio volumio[1007]: info: ------------------------------ 3ms Jul 23 03:33:45 volumio volumio[1007]: verbose: ControllerMpd::parseState Jul 23 03:33:45 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 03:33:45 volumio volumio[1007]: info: Jul 23 03:33:45 volumio volumio[1007]: ---------------------------- MPD announces state update: options Jul 23 03:33:45 volumio volumio[1007]: info: ControllerMpd::getState Jul 23 03:33:45 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Jul 23 03:33:45 volumio volumio[1007]: info: ------------------------------ 4ms Jul 23 03:33:45 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 03:33:45 volumio volumio[1007]: info: sendMpdCommand status took 2 milliseconds Jul 23 03:33:45 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Jul 23 03:33:45 volumio volumio[1007]: verbose: ControllerMpd::parseState Jul 23 03:33:45 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 03:33:45 volumio volumio[1007]: info: ControllerMpd::pushState Jul 23 03:33:45 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Jul 23 03:33:45 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:33:45 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":315238,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"283 Kbps","isStreaming":false,"title":"Bodhisattva","artist":"Steely Dan","album":"Countdown To Ecstasy (MCD 01654)","uri":"USB/1C02-385B/Steely Dan/Countdown To Ecstasy (MCD 01654)/01 Bodhisattva.flac","trackType":"flac"} Jul 23 03:33:45 volumio volumio[1007]: verbose: CURRENT POSITION 0 Jul 23 03:33:45 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Jul 23 03:33:45 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Jul 23 03:33:45 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Jul 23 03:33:45 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:33:45 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:33:45 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 03:33:45 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:33:45 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:33:45 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:33:45 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:33:45 volumio volumio[1007]: info: ------------------------------ 16ms Jul 23 03:33:45 volumio volumio[1007]: info: Jul 23 03:33:45 volumio volumio[1007]: ---------------------------- MPD announces state update: options Jul 23 03:33:45 volumio volumio[1007]: info: ControllerMpd::getState Jul 23 03:33:45 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Jul 23 03:33:45 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 23 03:33:45 volumio volumio[1007]: info: sendMpdCommand status took 2 milliseconds Jul 23 03:33:45 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Jul 23 03:33:45 volumio volumio[1007]: verbose: ControllerMpd::parseState Jul 23 03:33:45 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 03:33:45 volumio volumio[1007]: info: ControllerMpd::pushState Jul 23 03:33:45 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Jul 23 03:33:45 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:33:45 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":315238,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"283 Kbps","isStreaming":false,"title":"Bodhisattva","artist":"Steely Dan","album":"Countdown To Ecstasy (MCD 01654)","uri":"USB/1C02-385B/Steely Dan/Countdown To Ecstasy (MCD 01654)/01 Bodhisattva.flac","trackType":"flac"} Jul 23 03:33:45 volumio volumio[1007]: verbose: CURRENT POSITION 0 Jul 23 03:33:45 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Jul 23 03:33:45 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Jul 23 03:33:45 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Jul 23 03:33:45 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:33:45 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:33:45 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:33:45 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:33:45 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:33:45 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:33:45 volumio volumio[1007]: info: ------------------------------ 26ms Jul 23 03:33:45 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 14 milliseconds Jul 23 03:33:45 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Jul 23 03:33:45 volumio volumio[1007]: info: ControllerMpd::pushState Jul 23 03:33:45 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Jul 23 03:33:45 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:33:45 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":315238,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"283 Kbps","isStreaming":false,"title":"Bodhisattva","artist":"Steely Dan","album":"Countdown To Ecstasy (MCD 01654)","uri":"USB/1C02-385B/Steely Dan/Countdown To Ecstasy (MCD 01654)/01 Bodhisattva.flac","trackType":"flac"} Jul 23 03:33:45 volumio volumio[1007]: verbose: CURRENT POSITION 0 Jul 23 03:33:45 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Jul 23 03:33:45 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Jul 23 03:33:45 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Jul 23 03:33:45 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:33:45 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:33:45 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:33:45 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:33:45 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:33:45 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:33:45 volumio volumio[1007]: info: ------------------------------ 28ms Jul 23 03:33:49 volumio volumio[1007]: info: CoreStateMachine::startPlaybackTimer Jul 23 03:33:49 volumio volumio[1007]: info: CorePlayQueue::getTrack 1 Jul 23 03:33:50 volumio volumio[1007]: info: Jul 23 03:33:50 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Jul 23 03:33:50 volumio volumio[1007]: info: Ignoring MPD Status Update Jul 23 03:33:50 volumio volumio[1007]: info: Jul 23 03:33:50 volumio volumio[1007]: ---------------------------- MPD announces state update: player Jul 23 03:33:50 volumio volumio[1007]: info: ControllerMpd::getState Jul 23 03:33:50 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Jul 23 03:33:50 volumio volumio[1007]: info: Jul 23 03:33:50 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Jul 23 03:33:50 volumio volumio[1007]: info: Ignoring MPD Status Update Jul 23 03:33:50 volumio volumio[1007]: info: Jul 23 03:33:50 volumio volumio[1007]: ---------------------------- MPD announces state update: player Jul 23 03:33:50 volumio volumio[1007]: info: ControllerMpd::getState Jul 23 03:33:50 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Jul 23 03:33:50 volumio volumio[1007]: info: Jul 23 03:33:50 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Jul 23 03:33:50 volumio volumio[1007]: info: Ignoring MPD Status Update Jul 23 03:33:50 volumio volumio[1007]: info: Jul 23 03:33:50 volumio volumio[1007]: ---------------------------- MPD announces state update: player Jul 23 03:33:50 volumio volumio[1007]: info: ControllerMpd::getState Jul 23 03:33:50 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Jul 23 03:33:50 volumio volumio[1007]: info: ------------------------------ 4ms Jul 23 03:33:50 volumio volumio[1007]: info: sendMpdCommand status took 4 milliseconds Jul 23 03:33:50 volumio volumio[1007]: info: ------------------------------ 4ms Jul 23 03:33:50 volumio volumio[1007]: info: sendMpdCommand status took 3 milliseconds Jul 23 03:33:50 volumio volumio[1007]: info: ------------------------------ 3ms Jul 23 03:33:50 volumio volumio[1007]: info: sendMpdCommand status took 2 milliseconds Jul 23 03:33:50 volumio volumio[1007]: verbose: ControllerMpd::parseState Jul 23 03:33:50 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 03:33:50 volumio volumio[1007]: verbose: ControllerMpd::parseState Jul 23 03:33:50 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 03:33:50 volumio volumio[1007]: verbose: ControllerMpd::parseState Jul 23 03:33:50 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 03:33:50 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:33:50 volumio volumio[1007]: info: CorePlayQueue::getTrack 1 Jul 23 03:33:50 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 03:33:50 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:33:50 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 23 03:33:50 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 23 03:33:50 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 23 03:33:50 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Jul 23 03:33:50 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Jul 23 03:33:50 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Jul 23 03:33:50 volumio volumio[1007]: info: ControllerMpd::pushState Jul 23 03:33:50 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Jul 23 03:33:50 volumio volumio[1007]: info: CorePlayQueue::getTrack 1 Jul 23 03:33:50 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"760 Kbps","isStreaming":false,"title":"Razor Boy","artist":"Steely Dan","album":"Countdown To Ecstasy (MCD 01654)","uri":"USB/1C02-385B/Steely Dan/Countdown To Ecstasy (MCD 01654)/02 Razor Boy.flac","trackType":"flac"} Jul 23 03:33:50 volumio volumio[1007]: verbose: CURRENT POSITION 1 Jul 23 03:33:50 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Jul 23 03:33:50 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Jul 23 03:33:50 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Jul 23 03:33:50 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:33:50 volumio volumio[1007]: info: CorePlayQueue::getTrack 1 Jul 23 03:33:50 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 03:33:50 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:33:50 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:33:50 volumio volumio[1007]: info: CorePlayQueue::getTrack 1 Jul 23 03:33:50 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:33:50 volumio volumio[1007]: info: ControllerMpd::pushState Jul 23 03:33:50 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Jul 23 03:33:50 volumio volumio[1007]: info: CorePlayQueue::getTrack 1 Jul 23 03:33:50 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"760 Kbps","isStreaming":false,"title":"Razor Boy","artist":"Steely Dan","album":"Countdown To Ecstasy (MCD 01654)","uri":"USB/1C02-385B/Steely Dan/Countdown To Ecstasy (MCD 01654)/02 Razor Boy.flac","trackType":"flac"} Jul 23 03:33:50 volumio volumio[1007]: verbose: CURRENT POSITION 1 Jul 23 03:33:50 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Jul 23 03:33:50 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Jul 23 03:33:50 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Jul 23 03:33:50 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:33:50 volumio volumio[1007]: info: CorePlayQueue::getTrack 1 Jul 23 03:33:50 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:33:50 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:33:50 volumio volumio[1007]: info: CorePlayQueue::getTrack 1 Jul 23 03:33:50 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:33:50 volumio volumio[1007]: info: ControllerMpd::pushState Jul 23 03:33:50 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Jul 23 03:33:50 volumio volumio[1007]: info: CorePlayQueue::getTrack 1 Jul 23 03:33:50 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"760 Kbps","isStreaming":false,"title":"Razor Boy","artist":"Steely Dan","album":"Countdown To Ecstasy (MCD 01654)","uri":"USB/1C02-385B/Steely Dan/Countdown To Ecstasy (MCD 01654)/02 Razor Boy.flac","trackType":"flac"} Jul 23 03:33:50 volumio volumio[1007]: verbose: CURRENT POSITION 1 Jul 23 03:33:50 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Jul 23 03:33:50 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Jul 23 03:33:50 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Jul 23 03:33:50 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:33:50 volumio volumio[1007]: info: CorePlayQueue::getTrack 1 Jul 23 03:33:50 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:33:50 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:33:50 volumio volumio[1007]: info: CorePlayQueue::getTrack 1 Jul 23 03:33:50 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:33:50 volumio volumio[1007]: info: ------------------------------ 34ms Jul 23 03:33:50 volumio volumio[1007]: info: ------------------------------ 33ms Jul 23 03:33:50 volumio volumio[1007]: info: ------------------------------ 32ms Jul 23 03:34:31 volumio volumio[1007]: verbose: New Socket.io Connection to 192.168.1.125 from 192.168.1.107 UA: Mozilla/5.0 (Linux; Android 14; Pixel 8 Build/AP2A.240905.003; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/128.0.6613.148 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jul 23 03:34:31 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 23 03:34:31 volumio volumio[1007]: info: CoreCommandRouter::volumioGetVisibleSources Jul 23 03:34:31 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 23 03:34:31 volumio volumio[1007]: info: CoreCommandRouter::volumioGetState Jul 23 03:34:31 volumio volumio[1007]: info: CorePlayQueue::getTrack 1 Jul 23 03:34:31 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 23 03:34:31 volumio volumio[1007]: info: CoreCommandRouter::volumioGetQueue Jul 23 03:34:31 volumio volumio[1007]: info: CoreStateMachine::getQueue Jul 23 03:34:31 volumio volumio[1007]: info: CorePlayQueue::getQueue Jul 23 03:34:31 volumio volumio[1007]: info: Listing playlists Jul 23 03:34:31 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 23 03:34:31 volumio volumio[1007]: info: Received Get System Info Jul 23 03:34:31 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 23 03:34:31 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 23 03:34:31 volumio volumio[1007]: info: Discovery: Getting this device information Jul 23 03:34:31 volumio volumio[1007]: info: CoreCommandRouter::volumioGetState Jul 23 03:34:31 volumio volumio[1007]: info: CorePlayQueue::getTrack 1 Jul 23 03:34:31 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 23 03:34:31 volumio volumio[1007]: info: CoreCommandRouter::volumioGetState Jul 23 03:34:31 volumio volumio[1007]: info: CorePlayQueue::getTrack 1 Jul 23 03:34:36 volumio ntpd[795]: Soliciting pool server 159.196.3.239 Jul 23 03:34:36 volumio ntpd[795]: Soliciting pool server 180.150.8.191 Jul 23 03:34:37 volumio ntpd[795]: Soliciting pool server 162.159.200.1 Jul 23 03:34:38 volumio ntpd[795]: Soliciting pool server 159.196.44.158 Jul 23 03:34:55 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 23 03:34:55 volumio volumio[1007]: info: CURURI: music-library/USB/1C02-385B/The Velvet Underground Jul 23 03:34:55 volumio volumio[1007]: info: Preload queue cleared Jul 23 03:34:56 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 23 03:34:56 volumio volumio[1007]: info: CURURI: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1) Jul 23 03:34:56 volumio volumio[1007]: info: Preload queue cleared Jul 23 03:34:56 volumio volumio[1007]: info: Preloading song: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/01 Sunday Morning.flac Jul 23 03:34:56 volumio volumio[1007]: info: Preloading song: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/02 I'm Waiting For The Man.flac Jul 23 03:34:56 volumio volumio[1007]: info: Preloading song: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/03 Femme Fatale.flac Jul 23 03:34:56 volumio volumio[1007]: info: Preloading song: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/04 Venus In Furs.flac Jul 23 03:34:56 volumio volumio[1007]: info: Preloading song: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/05 Run Run Run.flac Jul 23 03:34:56 volumio volumio[1007]: info: Preloading song: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/06 All Tomorrow's Parties.flac Jul 23 03:34:56 volumio volumio[1007]: info: Preloading song: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/07 Heroin.flac Jul 23 03:34:56 volumio volumio[1007]: info: Preloading song: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/08 There She Goes Again.flac Jul 23 03:34:56 volumio volumio[1007]: info: Preloading song: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/09 I'll Be Your Mirror.flac Jul 23 03:34:56 volumio volumio[1007]: info: Preloading song: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/10 The Black Angel's Death Song.flac Jul 23 03:34:56 volumio volumio[1007]: info: Preloading song: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/11 European Son.flac Jul 23 03:34:56 volumio volumio[1007]: info: Preloading song: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/12 Little Sister Nico.flac Jul 23 03:34:56 volumio volumio[1007]: info: Preloading song: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/13 Winter Song Nico.flac Jul 23 03:34:56 volumio volumio[1007]: info: Preloading song: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/14 It Was A Pleasure Then Nico.flac Jul 23 03:34:56 volumio volumio[1007]: info: Preloading song: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/15 Chelsea Girls Nico.flac Jul 23 03:34:56 volumio volumio[1007]: info: Preloading song: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/16 Wrap Your Troubles In Dreams Nico.flac Jul 23 03:34:56 volumio volumio[1007]: info: Exploding uri music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/01 Sunday Morning.flac in service mpd Jul 23 03:34:56 volumio volumio[1007]: info: ALBUMART /albumart?cacheid=982&web=The%20Velvet%20Underground/The%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)/extralarge&path=%2Fmnt%2FUSB%2F1C02-385B%2FThe%20Velvet%20Underground%2FThe%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)%2F01%20Sunday%20Morning.flac&metadata=false Jul 23 03:34:56 volumio volumio[1007]: info: URI /mnt/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/01 Sunday Morning.flac Jul 23 03:34:56 volumio volumio[1007]: info: Exploding uri music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/02 I'm Waiting For The Man.flac in service mpd Jul 23 03:34:56 volumio volumio[1007]: info: ALBUMART /albumart?cacheid=982&web=The%20Velvet%20Underground/The%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)/extralarge&path=%2Fmnt%2FUSB%2F1C02-385B%2FThe%20Velvet%20Underground%2FThe%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)%2F02%20I'm%20Waiting%20For%20The%20Man.flac&metadata=false Jul 23 03:34:56 volumio volumio[1007]: info: URI /mnt/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/02 I'm Waiting For The Man.flac Jul 23 03:34:56 volumio volumio[1007]: info: Exploding uri music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/03 Femme Fatale.flac in service mpd Jul 23 03:34:56 volumio volumio[1007]: info: ALBUMART /albumart?cacheid=982&web=The%20Velvet%20Underground/The%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)/extralarge&path=%2Fmnt%2FUSB%2F1C02-385B%2FThe%20Velvet%20Underground%2FThe%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)%2F03%20Femme%20Fatale.flac&metadata=false Jul 23 03:34:56 volumio volumio[1007]: info: URI /mnt/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/03 Femme Fatale.flac Jul 23 03:34:56 volumio volumio[1007]: info: Exploding uri music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/04 Venus In Furs.flac in service mpd Jul 23 03:34:56 volumio volumio[1007]: info: ALBUMART /albumart?cacheid=982&web=The%20Velvet%20Underground/The%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)/extralarge&path=%2Fmnt%2FUSB%2F1C02-385B%2FThe%20Velvet%20Underground%2FThe%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)%2F04%20Venus%20In%20Furs.flac&metadata=false Jul 23 03:34:56 volumio volumio[1007]: info: URI /mnt/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/04 Venus In Furs.flac Jul 23 03:34:56 volumio volumio[1007]: info: Exploding uri music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/05 Run Run Run.flac in service mpd Jul 23 03:34:56 volumio volumio[1007]: info: ALBUMART /albumart?cacheid=982&web=The%20Velvet%20Underground/The%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)/extralarge&path=%2Fmnt%2FUSB%2F1C02-385B%2FThe%20Velvet%20Underground%2FThe%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)%2F05%20Run%20Run%20Run.flac&metadata=false Jul 23 03:34:56 volumio volumio[1007]: info: URI /mnt/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/05 Run Run Run.flac Jul 23 03:34:56 volumio volumio[1007]: info: Exploding uri music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/06 All Tomorrow's Parties.flac in service mpd Jul 23 03:34:56 volumio volumio[1007]: info: ALBUMART /albumart?cacheid=982&web=The%20Velvet%20Underground/The%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)/extralarge&path=%2Fmnt%2FUSB%2F1C02-385B%2FThe%20Velvet%20Underground%2FThe%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)%2F06%20All%20Tomorrow's%20Parties.flac&metadata=false Jul 23 03:34:56 volumio volumio[1007]: info: URI /mnt/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/06 All Tomorrow's Parties.flac Jul 23 03:34:56 volumio volumio[1007]: info: Exploding uri music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/07 Heroin.flac in service mpd Jul 23 03:34:56 volumio volumio[1007]: info: ALBUMART /albumart?cacheid=982&web=The%20Velvet%20Underground/The%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)/extralarge&path=%2Fmnt%2FUSB%2F1C02-385B%2FThe%20Velvet%20Underground%2FThe%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)%2F07%20Heroin.flac&metadata=false Jul 23 03:34:56 volumio volumio[1007]: info: URI /mnt/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/07 Heroin.flac Jul 23 03:34:56 volumio volumio[1007]: info: Exploding uri music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/08 There She Goes Again.flac in service mpd Jul 23 03:34:56 volumio volumio[1007]: info: ALBUMART /albumart?cacheid=982&web=The%20Velvet%20Underground/The%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)/extralarge&path=%2Fmnt%2FUSB%2F1C02-385B%2FThe%20Velvet%20Underground%2FThe%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)%2F08%20There%20She%20Goes%20Again.flac&metadata=false Jul 23 03:34:56 volumio volumio[1007]: info: URI /mnt/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/08 There She Goes Again.flac Jul 23 03:34:56 volumio volumio[1007]: info: Exploding uri music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/09 I'll Be Your Mirror.flac in service mpd Jul 23 03:34:56 volumio volumio[1007]: info: ALBUMART /albumart?cacheid=982&web=The%20Velvet%20Underground/The%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)/extralarge&path=%2Fmnt%2FUSB%2F1C02-385B%2FThe%20Velvet%20Underground%2FThe%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)%2F09%20I'll%20Be%20Your%20Mirror.flac&metadata=false Jul 23 03:34:56 volumio volumio[1007]: info: URI /mnt/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/09 I'll Be Your Mirror.flac Jul 23 03:34:57 volumio volumio[1007]: info: Exploding uri music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/10 The Black Angel's Death Song.flac in service mpd Jul 23 03:34:57 volumio volumio[1007]: info: ALBUMART /albumart?cacheid=982&web=The%20Velvet%20Underground/The%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)/extralarge&path=%2Fmnt%2FUSB%2F1C02-385B%2FThe%20Velvet%20Underground%2FThe%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)%2F10%20The%20Black%20Angel's%20Death%20Song.flac&metadata=false Jul 23 03:34:57 volumio volumio[1007]: info: URI /mnt/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/10 The Black Angel's Death Song.flac Jul 23 03:34:57 volumio volumio[1007]: info: Exploding uri music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/11 European Son.flac in service mpd Jul 23 03:34:57 volumio volumio[1007]: info: ALBUMART /albumart?cacheid=982&web=The%20Velvet%20Underground/The%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)/extralarge&path=%2Fmnt%2FUSB%2F1C02-385B%2FThe%20Velvet%20Underground%2FThe%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)%2F11%20European%20Son.flac&metadata=false Jul 23 03:34:57 volumio volumio[1007]: info: URI /mnt/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/11 European Son.flac Jul 23 03:34:57 volumio volumio[1007]: info: Exploding uri music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/12 Little Sister Nico.flac in service mpd Jul 23 03:34:57 volumio volumio[1007]: info: ALBUMART /albumart?cacheid=982&web=The%20Velvet%20Underground/The%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)/extralarge&path=%2Fmnt%2FUSB%2F1C02-385B%2FThe%20Velvet%20Underground%2FThe%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)%2F12%20Little%20Sister%20%20%20Nico.flac&metadata=false Jul 23 03:34:57 volumio volumio[1007]: info: URI /mnt/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/12 Little Sister Nico.flac Jul 23 03:34:57 volumio volumio[1007]: info: Exploding uri music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/13 Winter Song Nico.flac in service mpd Jul 23 03:34:57 volumio volumio[1007]: info: ALBUMART /albumart?cacheid=982&web=The%20Velvet%20Underground/The%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)/extralarge&path=%2Fmnt%2FUSB%2F1C02-385B%2FThe%20Velvet%20Underground%2FThe%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)%2F13%20Winter%20Song%20%20%20Nico.flac&metadata=false Jul 23 03:34:57 volumio volumio[1007]: info: URI /mnt/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/13 Winter Song Nico.flac Jul 23 03:34:57 volumio volumio[1007]: info: Exploding uri music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/14 It Was A Pleasure Then Nico.flac in service mpd Jul 23 03:34:57 volumio volumio[1007]: info: ALBUMART /albumart?cacheid=982&web=The%20Velvet%20Underground/The%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)/extralarge&path=%2Fmnt%2FUSB%2F1C02-385B%2FThe%20Velvet%20Underground%2FThe%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)%2F14%20It%20Was%20A%20Pleasure%20Then%20%20%20Nico.flac&metadata=false Jul 23 03:34:57 volumio volumio[1007]: info: URI /mnt/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/14 It Was A Pleasure Then Nico.flac Jul 23 03:34:57 volumio volumio[1007]: info: Exploding uri music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/15 Chelsea Girls Nico.flac in service mpd Jul 23 03:34:57 volumio volumio[1007]: info: ALBUMART /albumart?cacheid=982&web=The%20Velvet%20Underground/The%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)/extralarge&path=%2Fmnt%2FUSB%2F1C02-385B%2FThe%20Velvet%20Underground%2FThe%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)%2F15%20Chelsea%20Girls%20%20%20Nico.flac&metadata=false Jul 23 03:34:57 volumio volumio[1007]: info: URI /mnt/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/15 Chelsea Girls Nico.flac Jul 23 03:34:57 volumio volumio[1007]: info: Exploding uri music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/16 Wrap Your Troubles In Dreams Nico.flac in service mpd Jul 23 03:34:57 volumio volumio[1007]: info: ALBUMART /albumart?cacheid=982&web=The%20Velvet%20Underground/The%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)/extralarge&path=%2Fmnt%2FUSB%2F1C02-385B%2FThe%20Velvet%20Underground%2FThe%20Velvet%20Underground%20%26%20Nico%20%5BDeluxe%20Edition%5D%20(Disc%201)%2F16%20Wrap%20Your%20Troubles%20In%20Dreams%20%20%20Nico.flac&metadata=false Jul 23 03:34:57 volumio volumio[1007]: info: URI /mnt/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/16 Wrap Your Troubles In Dreams Nico.flac Jul 23 03:34:58 volumio volumio[1007]: info: Preload queue cleared Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::ClearQueue Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::stop Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::stPlaybackTimer Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::updateTrackBlock Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrackBlock Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 1 Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::serviceStop Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 1 Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::serviceStop Jul 23 03:34:58 volumio volumio[1007]: info: ControllerMpd::stop Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::clearPlayQueue Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::saveQueue Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::volumioPushQueue Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::addQueueItems Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::addQueueItems Jul 23 03:34:58 volumio volumio[1007]: info: Preload queue cleared Jul 23 03:34:58 volumio volumio[1007]: info: Adding Item to queue: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/01 Sunday Morning.flac Jul 23 03:34:58 volumio volumio[1007]: info: Using cached record of: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/01 Sunday Morning.flac Jul 23 03:34:58 volumio volumio[1007]: info: Adding Item to queue: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/02 I'm Waiting For The Man.flac Jul 23 03:34:58 volumio volumio[1007]: info: Using cached record of: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/02 I'm Waiting For The Man.flac Jul 23 03:34:58 volumio volumio[1007]: info: Adding Item to queue: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/03 Femme Fatale.flac Jul 23 03:34:58 volumio volumio[1007]: info: Using cached record of: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/03 Femme Fatale.flac Jul 23 03:34:58 volumio volumio[1007]: info: Adding Item to queue: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/04 Venus In Furs.flac Jul 23 03:34:58 volumio volumio[1007]: info: Using cached record of: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/04 Venus In Furs.flac Jul 23 03:34:58 volumio volumio[1007]: info: Adding Item to queue: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/05 Run Run Run.flac Jul 23 03:34:58 volumio volumio[1007]: info: Using cached record of: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/05 Run Run Run.flac Jul 23 03:34:58 volumio volumio[1007]: info: Adding Item to queue: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/06 All Tomorrow's Parties.flac Jul 23 03:34:58 volumio volumio[1007]: info: Using cached record of: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/06 All Tomorrow's Parties.flac Jul 23 03:34:58 volumio volumio[1007]: info: Adding Item to queue: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/07 Heroin.flac Jul 23 03:34:58 volumio volumio[1007]: info: Using cached record of: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/07 Heroin.flac Jul 23 03:34:58 volumio volumio[1007]: info: Adding Item to queue: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/08 There She Goes Again.flac Jul 23 03:34:58 volumio volumio[1007]: info: Using cached record of: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/08 There She Goes Again.flac Jul 23 03:34:58 volumio volumio[1007]: info: Adding Item to queue: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/09 I'll Be Your Mirror.flac Jul 23 03:34:58 volumio volumio[1007]: info: Using cached record of: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/09 I'll Be Your Mirror.flac Jul 23 03:34:58 volumio volumio[1007]: info: Adding Item to queue: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/10 The Black Angel's Death Song.flac Jul 23 03:34:58 volumio volumio[1007]: info: Using cached record of: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/10 The Black Angel's Death Song.flac Jul 23 03:34:58 volumio volumio[1007]: info: Adding Item to queue: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/11 European Son.flac Jul 23 03:34:58 volumio volumio[1007]: info: Using cached record of: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/11 European Son.flac Jul 23 03:34:58 volumio volumio[1007]: info: Adding Item to queue: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/12 Little Sister Nico.flac Jul 23 03:34:58 volumio volumio[1007]: info: Using cached record of: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/12 Little Sister Nico.flac Jul 23 03:34:58 volumio volumio[1007]: info: Adding Item to queue: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/13 Winter Song Nico.flac Jul 23 03:34:58 volumio volumio[1007]: info: Using cached record of: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/13 Winter Song Nico.flac Jul 23 03:34:58 volumio volumio[1007]: info: Adding Item to queue: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/14 It Was A Pleasure Then Nico.flac Jul 23 03:34:58 volumio volumio[1007]: info: Using cached record of: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/14 It Was A Pleasure Then Nico.flac Jul 23 03:34:58 volumio volumio[1007]: info: Adding Item to queue: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/15 Chelsea Girls Nico.flac Jul 23 03:34:58 volumio volumio[1007]: info: Using cached record of: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/15 Chelsea Girls Nico.flac Jul 23 03:34:58 volumio volumio[1007]: info: Adding Item to queue: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/16 Wrap Your Troubles In Dreams Nico.flac Jul 23 03:34:58 volumio volumio[1007]: info: Using cached record of: music-library/USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/16 Wrap Your Troubles In Dreams Nico.flac Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::volumioPushQueue Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::saveQueue Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::updateTrackBlock Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrackBlock Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::volumioPlay Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::play index 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::stop Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::play index undefined Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::startPlaybackTimer Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::clearAddPlayTracks USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/01 Sunday Morning.flac Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 03:34:58 volumio volumio[1007]: info: sendMpdCommand stop took 34 milliseconds Jul 23 03:34:58 volumio volumio[1007]: info: Jul 23 03:34:58 volumio volumio[1007]: ---------------------------- MPD announces state update: player Jul 23 03:34:58 volumio volumio[1007]: info: ControllerMpd::getState Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Jul 23 03:34:58 volumio volumio[1007]: info: sendMpdCommand stop took 15 milliseconds Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand clear Jul 23 03:34:58 volumio volumio[1007]: info: Jul 23 03:34:58 volumio volumio[1007]: ---------------------------- MPD announces state update: player Jul 23 03:34:58 volumio volumio[1007]: info: ControllerMpd::getState Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Jul 23 03:34:58 volumio volumio[1007]: info: Jul 23 03:34:58 volumio volumio[1007]: ---------------------------- MPD announces state update: player Jul 23 03:34:58 volumio volumio[1007]: info: ControllerMpd::getState Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Jul 23 03:34:58 volumio volumio[1007]: info: Jul 23 03:34:58 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Jul 23 03:34:58 volumio volumio[1007]: info: Ignoring MPD Status Update Jul 23 03:34:58 volumio volumio[1007]: info: sendMpdCommand status took 4 milliseconds Jul 23 03:34:58 volumio volumio[1007]: info: sendMpdCommand clear took 3 milliseconds Jul 23 03:34:58 volumio volumio[1007]: info: sendMpdCommand status took 2 milliseconds Jul 23 03:34:58 volumio volumio[1007]: info: sendMpdCommand status took 2 milliseconds Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::parseState Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand add "USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/01 Sunday Morning.flac" Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::parseState Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::parseState Jul 23 03:34:58 volumio volumio[1007]: info: ControllerMpd::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: 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} Jul 23 03:34:58 volumio volumio[1007]: verbose: CURRENT POSITION 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::syncState stateService stop Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus stop Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:34:58 volumio volumio[1007]: info: No code Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:34:58 volumio volumio[1007]: info: ControllerMpd::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: 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} Jul 23 03:34:58 volumio volumio[1007]: verbose: CURRENT POSITION 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::syncState stateService stop Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus stop Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:34:58 volumio volumio[1007]: info: No code Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:34:58 volumio volumio[1007]: info: ------------------------------ 33ms Jul 23 03:34:58 volumio volumio[1007]: info: ------------------------------ 33ms Jul 23 03:34:58 volumio volumio[1007]: info: Jul 23 03:34:58 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Jul 23 03:34:58 volumio volumio[1007]: info: Ignoring MPD Status Update Jul 23 03:34:58 volumio volumio[1007]: info: Jul 23 03:34:58 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Jul 23 03:34:58 volumio volumio[1007]: info: Ignoring MPD Status Update Jul 23 03:34:58 volumio volumio[1007]: error: updateQueue error: null Jul 23 03:34:58 volumio volumio[1007]: info: Jul 23 03:34:58 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Jul 23 03:34:58 volumio volumio[1007]: info: Ignoring MPD Status Update Jul 23 03:34:58 volumio volumio[1007]: info: ------------------------------ 38ms Jul 23 03:34:58 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 36 milliseconds Jul 23 03:34:58 volumio volumio[1007]: info: sendMpdCommand add "USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/01 Sunday Morning.flac" took 37 milliseconds Jul 23 03:34:58 volumio volumio[1007]: info: ------------------------------ 6ms Jul 23 03:34:58 volumio volumio[1007]: info: ------------------------------ 5ms Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand play Jul 23 03:34:58 volumio volumio[1007]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 23 03:34:58 volumio volumio[1007]: info: ------------------------------ 43ms Jul 23 03:34:58 volumio volumio[1007]: info: Jul 23 03:34:58 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Jul 23 03:34:58 volumio volumio[1007]: info: Ignoring MPD Status Update Jul 23 03:34:58 volumio volumio[1007]: info: Jul 23 03:34:58 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Jul 23 03:34:58 volumio volumio[1007]: info: Ignoring MPD Status Update Jul 23 03:34:58 volumio volumio[1007]: info: Jul 23 03:34:58 volumio volumio[1007]: ---------------------------- MPD announces state update: player Jul 23 03:34:58 volumio volumio[1007]: info: ------------------------------ 11ms Jul 23 03:34:58 volumio volumio[1007]: info: sendMpdCommand play took 9 milliseconds Jul 23 03:34:58 volumio volumio[1007]: info: ------------------------------ 8ms Jul 23 03:34:58 volumio volumio[1007]: info: ------------------------------ 7ms Jul 23 03:34:58 volumio volumio[1007]: info: ControllerMpd::getState Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Jul 23 03:34:58 volumio volumio[1007]: info: Jul 23 03:34:58 volumio volumio[1007]: ---------------------------- MPD announces state update: player Jul 23 03:34:58 volumio volumio[1007]: info: ControllerMpd::getState Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Jul 23 03:34:58 volumio volumio[1007]: info: Jul 23 03:34:58 volumio volumio[1007]: ---------------------------- MPD announces state update: player Jul 23 03:34:58 volumio volumio[1007]: info: ControllerMpd::getState Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Jul 23 03:34:58 volumio volumio[1007]: info: Jul 23 03:34:58 volumio volumio[1007]: ---------------------------- MPD announces state update: player Jul 23 03:34:58 volumio volumio[1007]: info: sendMpdCommand status took 94 milliseconds Jul 23 03:34:58 volumio volumio[1007]: info: ControllerMpd::getState Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Jul 23 03:34:58 volumio volumio[1007]: info: sendMpdCommand status took 94 milliseconds Jul 23 03:34:58 volumio volumio[1007]: info: sendMpdCommand status took 93 milliseconds Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::parseState Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::parseState Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::parseState Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 03:34:58 volumio volumio[1007]: info: Jul 23 03:34:58 volumio volumio[1007]: ---------------------------- MPD announces state update: player Jul 23 03:34:58 volumio volumio[1007]: info: ControllerMpd::getState Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Jul 23 03:34:58 volumio volumio[1007]: info: Jul 23 03:34:58 volumio volumio[1007]: ---------------------------- MPD announces state update: player Jul 23 03:34:58 volumio volumio[1007]: info: ControllerMpd::getState Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Jul 23 03:34:58 volumio volumio[1007]: info: sendMpdCommand status took 6 milliseconds Jul 23 03:34:58 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 23 03:34:58 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 23 03:34:58 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 23 03:34:58 volumio volumio[1007]: info: sendMpdCommand status took 4 milliseconds Jul 23 03:34:58 volumio volumio[1007]: info: sendMpdCommand status took 2 milliseconds Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::parseState Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::parseState Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::parseState Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 03:34:58 volumio volumio[1007]: info: ControllerMpd::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":176,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"627 Kbps","isStreaming":false,"title":"Sunday Morning","artist":"The Velvet Underground","album":"The Velvet Underground & Nico [Deluxe Edition] (Disc 1)","uri":"USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/01 Sunday Morning.flac","trackType":"flac"} Jul 23 03:34:58 volumio volumio[1007]: verbose: CURRENT POSITION 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus stop Jul 23 03:34:58 volumio volumio[1007]: info: ControllerMpd::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":176,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"669 Kbps","isStreaming":false,"title":"Sunday Morning","artist":"The Velvet Underground","album":"The Velvet Underground & Nico [Deluxe Edition] (Disc 1)","uri":"USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/01 Sunday Morning.flac","trackType":"flac"} Jul 23 03:34:58 volumio volumio[1007]: verbose: CURRENT POSITION 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Jul 23 03:34:58 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:34:58 volumio volumio[1007]: info: ControllerMpd::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":176,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"675 Kbps","isStreaming":false,"title":"Sunday Morning","artist":"The Velvet Underground","album":"The Velvet Underground & Nico [Deluxe Edition] (Disc 1)","uri":"USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/01 Sunday Morning.flac","trackType":"flac"} Jul 23 03:34:58 volumio volumio[1007]: verbose: CURRENT POSITION 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Jul 23 03:34:58 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:34:58 volumio volumio[1007]: info: ------------------------------ 116ms Jul 23 03:34:58 volumio volumio[1007]: info: ------------------------------ 127ms Jul 23 03:34:58 volumio volumio[1007]: info: ------------------------------ 127ms Jul 23 03:34:58 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 27 milliseconds Jul 23 03:34:58 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 26 milliseconds Jul 23 03:34:58 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 26 milliseconds Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Jul 23 03:34:58 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Jul 23 03:34:58 volumio volumio[1007]: info: ControllerMpd::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":176,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"692 Kbps","isStreaming":false,"title":"Sunday Morning","artist":"The Velvet Underground","album":"The Velvet Underground & Nico [Deluxe Edition] (Disc 1)","uri":"USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/01 Sunday Morning.flac","trackType":"flac"} Jul 23 03:34:58 volumio volumio[1007]: verbose: CURRENT POSITION 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Jul 23 03:34:58 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:34:58 volumio volumio[1007]: info: ControllerMpd::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":176,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"692 Kbps","isStreaming":false,"title":"Sunday Morning","artist":"The Velvet Underground","album":"The Velvet Underground & Nico [Deluxe Edition] (Disc 1)","uri":"USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/01 Sunday Morning.flac","trackType":"flac"} Jul 23 03:34:58 volumio volumio[1007]: verbose: CURRENT POSITION 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Jul 23 03:34:58 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:34:58 volumio volumio[1007]: info: ControllerMpd::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":176,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"692 Kbps","isStreaming":false,"title":"Sunday Morning","artist":"The Velvet Underground","album":"The Velvet Underground & Nico [Deluxe Edition] (Disc 1)","uri":"USB/1C02-385B/The Velvet Underground/The Velvet Underground & Nico [Deluxe Edition] (Disc 1)/01 Sunday Morning.flac","trackType":"flac"} Jul 23 03:34:58 volumio volumio[1007]: verbose: CURRENT POSITION 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Jul 23 03:34:58 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:34:58 volumio volumio[1007]: info: CoreStateMachine::pushState Jul 23 03:34:58 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Jul 23 03:34:58 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Jul 23 03:34:58 volumio volumio[1007]: info: ------------------------------ 62ms Jul 23 03:34:58 volumio volumio[1007]: info: ------------------------------ 58ms Jul 23 03:34:58 volumio volumio[1007]: info: ------------------------------ 57ms Jul 23 03:34:59 volumio volumio[1007]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 23 03:34:59 volumio volumio[1007]: Error: certificate is not yet valid Jul 23 03:34:59 volumio volumio[1007]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Jul 23 03:34:59 volumio volumio[1007]: at TLSSocket.emit (events.js:315:20) Jul 23 03:34:59 volumio volumio[1007]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Jul 23 03:34:59 volumio volumio[1007]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Jul 23 03:34:59 volumio volumio[1007]: code: 'CERT_NOT_YET_VALID' Jul 23 03:34:59 volumio volumio[1007]: } Jul 23 03:34:59 volumio volumio[1007]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 23 03:34:59 volumio sudo[3293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-23 03:33 Jul 23 03:34:59 volumio sudo[3293]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"