-- Logs begin at Wed 2024-07-10 10:06:16 BST, end at Wed 2024-07-10 12:44:14 BST. -- Jul 10 12:43:05 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Jul 10 12:43:05 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:05 volumio volumio[812]: info: Listing playlists Jul 10 12:43:05 volumio volumio[812]: info: Listing playlists Jul 10 12:43:15 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Jul 10 12:43:15 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:25 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Jul 10 12:43:25 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:25 volumio volumio[812]: info: Listing playlists Jul 10 12:43:25 volumio volumio[812]: info: Listing playlists Jul 10 12:43:33 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Jul 10 12:43:33 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:34 volumio volumio[812]: info: CoreCommandRouter::volumioPlay Jul 10 12:43:34 volumio volumio[812]: info: CoreStateMachine::play index undefined Jul 10 12:43:34 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 12:43:34 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:34 volumio volumio[812]: info: CoreStateMachine::startPlaybackTimer Jul 10 12:43:34 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:34 volumio volumio[812]: info: ControllerMpd::resume Jul 10 12:43:34 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand play Jul 10 12:43:34 volumio volumio[812]: info: Jul 10 12:43:34 volumio volumio[812]: ---------------------------- MPD announces state update: player Jul 10 12:43:34 volumio volumio[812]: info: sendMpdCommand play took 32 milliseconds Jul 10 12:43:34 volumio volumio[812]: info: ControllerMpd::getState Jul 10 12:43:34 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Jul 10 12:43:34 volumio volumio[812]: info: Jul 10 12:43:34 volumio volumio[812]: ---------------------------- MPD announces state update: player Jul 10 12:43:34 volumio volumio[812]: info: ControllerMpd::getState Jul 10 12:43:34 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Jul 10 12:43:34 volumio volumio[812]: info: Jul 10 12:43:34 volumio volumio[812]: ---------------------------- MPD announces state update: player Jul 10 12:43:34 volumio volumio[812]: info: ControllerMpd::getState Jul 10 12:43:34 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Jul 10 12:43:34 volumio volumio[812]: info: sendMpdCommand status took 38 milliseconds Jul 10 12:43:34 volumio volumio[812]: info: sendMpdCommand status took 28 milliseconds Jul 10 12:43:34 volumio volumio[812]: info: sendMpdCommand status took 38 milliseconds Jul 10 12:43:34 volumio volumio[812]: verbose: ControllerMpd::parseState Jul 10 12:43:34 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 12:43:34 volumio volumio[812]: verbose: ControllerMpd::parseState Jul 10 12:43:34 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 12:43:34 volumio volumio[812]: verbose: ControllerMpd::parseState Jul 10 12:43:34 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 12:43:34 volumio volumio[812]: info: sendMpdCommand playlistinfo took 32 milliseconds Jul 10 12:43:34 volumio volumio[812]: info: sendMpdCommand playlistinfo took 36 milliseconds Jul 10 12:43:34 volumio volumio[812]: info: sendMpdCommand playlistinfo took 24 milliseconds Jul 10 12:43:34 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Jul 10 12:43:34 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Jul 10 12:43:34 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Jul 10 12:43:34 volumio volumio[812]: info: ControllerMpd::pushState Jul 10 12:43:34 volumio volumio[812]: info: CoreCommandRouter::servicePushState Jul 10 12:43:34 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:34 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":82138,"duration":14423,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise.wav","artist":null,"album":null,"uri":"INTERNAL/Pink Noise.wav","trackType":"wav"} Jul 10 12:43:34 volumio volumio[812]: verbose: CURRENT POSITION 0 Jul 10 12:43:34 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Jul 10 12:43:34 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Jul 10 12:43:34 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:43:34 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:34 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 12:43:34 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:43:34 volumio volumio[812]: info: ControllerMpd::pushState Jul 10 12:43:34 volumio volumio[812]: info: CoreCommandRouter::servicePushState Jul 10 12:43:34 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:34 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":82138,"duration":14423,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise.wav","artist":null,"album":null,"uri":"INTERNAL/Pink Noise.wav","trackType":"wav"} Jul 10 12:43:34 volumio volumio[812]: verbose: CURRENT POSITION 0 Jul 10 12:43:34 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Jul 10 12:43:34 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus play Jul 10 12:43:34 volumio volumio[812]: info: Received an update from plugin. extracting info from payload Jul 10 12:43:34 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:43:34 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:34 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 12:43:34 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:43:34 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:43:34 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:34 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:43:34 volumio volumio[812]: info: ControllerMpd::pushState Jul 10 12:43:34 volumio volumio[812]: info: CoreCommandRouter::servicePushState Jul 10 12:43:34 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:34 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":82138,"duration":14423,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise.wav","artist":null,"album":null,"uri":"INTERNAL/Pink Noise.wav","trackType":"wav"} Jul 10 12:43:34 volumio volumio[812]: verbose: CURRENT POSITION 0 Jul 10 12:43:34 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Jul 10 12:43:34 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus play Jul 10 12:43:34 volumio volumio[812]: info: Received an update from plugin. extracting info from payload Jul 10 12:43:34 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:43:34 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:34 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:43:34 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:43:34 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:34 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:43:35 volumio volumio[812]: info: ------------------------------ 562ms Jul 10 12:43:35 volumio volumio[812]: info: ------------------------------ 563ms Jul 10 12:43:35 volumio volumio[812]: info: ------------------------------ 555ms Jul 10 12:43:35 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Jul 10 12:43:35 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:45 volumio volumio[812]: info: CoreCommandRouter::volumioPause Jul 10 12:43:45 volumio volumio[812]: info: CoreStateMachine::pause Jul 10 12:43:45 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Jul 10 12:43:45 volumio volumio[812]: info: CoreStateMachine::servicePause Jul 10 12:43:45 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:45 volumio volumio[812]: info: CoreCommandRouter::servicePause Jul 10 12:43:45 volumio volumio[812]: info: ControllerMpd::pause Jul 10 12:43:45 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand pause Jul 10 12:43:45 volumio volumio[812]: info: sendMpdCommand pause took 10 milliseconds Jul 10 12:43:45 volumio volumio[812]: info: Jul 10 12:43:45 volumio volumio[812]: ---------------------------- MPD announces state update: player Jul 10 12:43:45 volumio volumio[812]: info: ControllerMpd::getState Jul 10 12:43:45 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Jul 10 12:43:45 volumio volumio[812]: info: Jul 10 12:43:45 volumio volumio[812]: ---------------------------- MPD announces state update: player Jul 10 12:43:45 volumio volumio[812]: info: ControllerMpd::getState Jul 10 12:43:45 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Jul 10 12:43:45 volumio volumio[812]: info: Jul 10 12:43:45 volumio volumio[812]: ---------------------------- MPD announces state update: player Jul 10 12:43:45 volumio volumio[812]: info: ControllerMpd::getState Jul 10 12:43:45 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Jul 10 12:43:45 volumio volumio[812]: info: sendMpdCommand status took 31 milliseconds Jul 10 12:43:45 volumio volumio[812]: info: sendMpdCommand status took 20 milliseconds Jul 10 12:43:45 volumio volumio[812]: verbose: ControllerMpd::parseState Jul 10 12:43:45 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 12:43:45 volumio volumio[812]: verbose: ControllerMpd::parseState Jul 10 12:43:45 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 12:43:45 volumio volumio[812]: info: sendMpdCommand status took 29 milliseconds Jul 10 12:43:45 volumio volumio[812]: info: sendMpdCommand playlistinfo took 18 milliseconds Jul 10 12:43:45 volumio volumio[812]: info: sendMpdCommand playlistinfo took 16 milliseconds Jul 10 12:43:45 volumio volumio[812]: verbose: ControllerMpd::parseState Jul 10 12:43:45 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 12:43:45 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Jul 10 12:43:45 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Jul 10 12:43:45 volumio volumio[812]: info: ControllerMpd::pushState Jul 10 12:43:45 volumio volumio[812]: info: CoreCommandRouter::servicePushState Jul 10 12:43:45 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:45 volumio volumio[812]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":92800,"duration":14423,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise.wav","artist":null,"album":null,"uri":"INTERNAL/Pink Noise.wav","trackType":"wav"} Jul 10 12:43:45 volumio volumio[812]: verbose: CURRENT POSITION 0 Jul 10 12:43:45 volumio volumio[812]: info: CoreStateMachine::syncState stateService pause Jul 10 12:43:45 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Jul 10 12:43:45 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:43:45 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:45 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 12:43:45 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:43:45 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Jul 10 12:43:45 volumio volumio[812]: info: ControllerMpd::pushState Jul 10 12:43:45 volumio volumio[812]: info: CoreCommandRouter::servicePushState Jul 10 12:43:45 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:45 volumio volumio[812]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":92800,"duration":14423,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise.wav","artist":null,"album":null,"uri":"INTERNAL/Pink Noise.wav","trackType":"wav"} Jul 10 12:43:45 volumio volumio[812]: verbose: CURRENT POSITION 0 Jul 10 12:43:45 volumio volumio[812]: info: CoreStateMachine::syncState stateService pause Jul 10 12:43:45 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Jul 10 12:43:45 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:43:45 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:45 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:43:45 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Jul 10 12:43:45 volumio volumio[812]: info: ------------------------------ 318ms Jul 10 12:43:45 volumio volumio[812]: info: ------------------------------ 309ms Jul 10 12:43:45 volumio volumio[812]: info: sendMpdCommand playlistinfo took 254 milliseconds Jul 10 12:43:45 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Jul 10 12:43:45 volumio volumio[812]: info: ControllerMpd::pushState Jul 10 12:43:45 volumio volumio[812]: info: CoreCommandRouter::servicePushState Jul 10 12:43:45 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:45 volumio volumio[812]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":92800,"duration":14423,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise.wav","artist":null,"album":null,"uri":"INTERNAL/Pink Noise.wav","trackType":"wav"} Jul 10 12:43:45 volumio volumio[812]: verbose: CURRENT POSITION 0 Jul 10 12:43:45 volumio volumio[812]: info: CoreStateMachine::syncState stateService pause Jul 10 12:43:45 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Jul 10 12:43:45 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:43:45 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:45 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:43:45 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Jul 10 12:43:45 volumio volumio[812]: info: ------------------------------ 558ms Jul 10 12:43:46 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Jul 10 12:43:46 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:46 volumio volumio[812]: info: Listing playlists Jul 10 12:43:46 volumio volumio[812]: info: Listing playlists Jul 10 12:43:47 volumio volumio[812]: info: CoreCommandRouter::volumioPlay Jul 10 12:43:47 volumio volumio[812]: info: CoreStateMachine::play index undefined Jul 10 12:43:47 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 12:43:47 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:47 volumio volumio[812]: info: CoreStateMachine::startPlaybackTimer Jul 10 12:43:47 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:47 volumio volumio[812]: info: ControllerMpd::resume Jul 10 12:43:47 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand play Jul 10 12:43:47 volumio volumio[812]: info: Jul 10 12:43:47 volumio volumio[812]: ---------------------------- MPD announces state update: player Jul 10 12:43:47 volumio volumio[812]: info: sendMpdCommand play took 23 milliseconds Jul 10 12:43:47 volumio volumio[812]: info: ControllerMpd::getState Jul 10 12:43:47 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Jul 10 12:43:47 volumio volumio[812]: info: Jul 10 12:43:47 volumio volumio[812]: ---------------------------- MPD announces state update: player Jul 10 12:43:47 volumio volumio[812]: info: ControllerMpd::getState Jul 10 12:43:47 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Jul 10 12:43:47 volumio volumio[812]: info: Jul 10 12:43:47 volumio volumio[812]: ---------------------------- MPD announces state update: player Jul 10 12:43:47 volumio volumio[812]: info: ControllerMpd::getState Jul 10 12:43:47 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Jul 10 12:43:47 volumio volumio[812]: info: sendMpdCommand status took 60 milliseconds Jul 10 12:43:47 volumio volumio[812]: info: sendMpdCommand status took 49 milliseconds Jul 10 12:43:47 volumio volumio[812]: info: sendMpdCommand status took 23 milliseconds Jul 10 12:43:47 volumio volumio[812]: verbose: ControllerMpd::parseState Jul 10 12:43:47 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 12:43:47 volumio volumio[812]: verbose: ControllerMpd::parseState Jul 10 12:43:47 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 12:43:47 volumio volumio[812]: verbose: ControllerMpd::parseState Jul 10 12:43:47 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 12:43:47 volumio volumio[812]: info: sendMpdCommand playlistinfo took 22 milliseconds Jul 10 12:43:47 volumio volumio[812]: info: sendMpdCommand playlistinfo took 21 milliseconds Jul 10 12:43:47 volumio volumio[812]: info: sendMpdCommand playlistinfo took 18 milliseconds Jul 10 12:43:47 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Jul 10 12:43:47 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Jul 10 12:43:47 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Jul 10 12:43:47 volumio volumio[812]: info: ControllerMpd::pushState Jul 10 12:43:47 volumio volumio[812]: info: CoreCommandRouter::servicePushState Jul 10 12:43:47 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:47 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":93761,"duration":14423,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise.wav","artist":null,"album":null,"uri":"INTERNAL/Pink Noise.wav","trackType":"wav"} Jul 10 12:43:47 volumio volumio[812]: verbose: CURRENT POSITION 0 Jul 10 12:43:47 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Jul 10 12:43:47 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Jul 10 12:43:47 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:43:47 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:47 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 12:43:47 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:43:47 volumio volumio[812]: info: ControllerMpd::pushState Jul 10 12:43:47 volumio volumio[812]: info: CoreCommandRouter::servicePushState Jul 10 12:43:47 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:47 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":93761,"duration":14423,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise.wav","artist":null,"album":null,"uri":"INTERNAL/Pink Noise.wav","trackType":"wav"} Jul 10 12:43:47 volumio volumio[812]: verbose: CURRENT POSITION 0 Jul 10 12:43:47 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Jul 10 12:43:47 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus play Jul 10 12:43:47 volumio volumio[812]: info: Received an update from plugin. extracting info from payload Jul 10 12:43:47 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:43:47 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:47 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 12:43:47 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:43:47 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:43:47 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:47 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:43:47 volumio volumio[812]: info: ControllerMpd::pushState Jul 10 12:43:47 volumio volumio[812]: info: CoreCommandRouter::servicePushState Jul 10 12:43:47 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:47 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":93761,"duration":14423,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise.wav","artist":null,"album":null,"uri":"INTERNAL/Pink Noise.wav","trackType":"wav"} Jul 10 12:43:47 volumio volumio[812]: verbose: CURRENT POSITION 0 Jul 10 12:43:47 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Jul 10 12:43:47 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus play Jul 10 12:43:47 volumio volumio[812]: info: Received an update from plugin. extracting info from payload Jul 10 12:43:47 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:43:47 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:47 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:43:47 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:43:47 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:47 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:43:47 volumio volumio[812]: info: ------------------------------ 508ms Jul 10 12:43:47 volumio volumio[812]: info: ------------------------------ 487ms Jul 10 12:43:47 volumio volumio[812]: info: ------------------------------ 470ms Jul 10 12:43:54 volumio volumio[812]: info: CoreCommandRouter::volumioPause Jul 10 12:43:54 volumio volumio[812]: info: CoreStateMachine::pause Jul 10 12:43:54 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Jul 10 12:43:54 volumio volumio[812]: info: CoreStateMachine::servicePause Jul 10 12:43:54 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:54 volumio volumio[812]: info: CoreCommandRouter::servicePause Jul 10 12:43:54 volumio volumio[812]: info: ControllerMpd::pause Jul 10 12:43:54 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand pause Jul 10 12:43:54 volumio volumio[812]: info: sendMpdCommand pause took 10 milliseconds Jul 10 12:43:54 volumio volumio[812]: info: Jul 10 12:43:54 volumio volumio[812]: ---------------------------- MPD announces state update: player Jul 10 12:43:54 volumio volumio[812]: info: ControllerMpd::getState Jul 10 12:43:54 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Jul 10 12:43:54 volumio volumio[812]: info: Jul 10 12:43:54 volumio volumio[812]: ---------------------------- MPD announces state update: player Jul 10 12:43:54 volumio volumio[812]: info: ControllerMpd::getState Jul 10 12:43:54 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Jul 10 12:43:54 volumio volumio[812]: info: Jul 10 12:43:54 volumio volumio[812]: ---------------------------- MPD announces state update: player Jul 10 12:43:54 volumio volumio[812]: info: ControllerMpd::getState Jul 10 12:43:54 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Jul 10 12:43:54 volumio volumio[812]: info: sendMpdCommand status took 38 milliseconds Jul 10 12:43:54 volumio volumio[812]: info: sendMpdCommand status took 22 milliseconds Jul 10 12:43:54 volumio volumio[812]: verbose: ControllerMpd::parseState Jul 10 12:43:54 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 12:43:54 volumio volumio[812]: verbose: ControllerMpd::parseState Jul 10 12:43:54 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 12:43:54 volumio volumio[812]: info: sendMpdCommand status took 31 milliseconds Jul 10 12:43:54 volumio volumio[812]: info: sendMpdCommand playlistinfo took 19 milliseconds Jul 10 12:43:54 volumio volumio[812]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 10 12:43:54 volumio volumio[812]: verbose: ControllerMpd::parseState Jul 10 12:43:54 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 12:43:54 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Jul 10 12:43:54 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Jul 10 12:43:54 volumio volumio[812]: info: ControllerMpd::pushState Jul 10 12:43:54 volumio volumio[812]: info: CoreCommandRouter::servicePushState Jul 10 12:43:54 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:54 volumio volumio[812]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":101037,"duration":14423,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise.wav","artist":null,"album":null,"uri":"INTERNAL/Pink Noise.wav","trackType":"wav"} Jul 10 12:43:54 volumio volumio[812]: verbose: CURRENT POSITION 0 Jul 10 12:43:54 volumio volumio[812]: info: CoreStateMachine::syncState stateService pause Jul 10 12:43:54 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Jul 10 12:43:54 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:43:54 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:54 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 12:43:54 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:43:54 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Jul 10 12:43:54 volumio volumio[812]: info: ControllerMpd::pushState Jul 10 12:43:54 volumio volumio[812]: info: CoreCommandRouter::servicePushState Jul 10 12:43:54 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:54 volumio volumio[812]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":101037,"duration":14423,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise.wav","artist":null,"album":null,"uri":"INTERNAL/Pink Noise.wav","trackType":"wav"} Jul 10 12:43:54 volumio volumio[812]: verbose: CURRENT POSITION 0 Jul 10 12:43:54 volumio volumio[812]: info: CoreStateMachine::syncState stateService pause Jul 10 12:43:54 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Jul 10 12:43:54 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:43:54 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:54 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:43:54 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Jul 10 12:43:54 volumio volumio[812]: info: ------------------------------ 298ms Jul 10 12:43:54 volumio volumio[812]: info: ------------------------------ 283ms Jul 10 12:43:54 volumio volumio[812]: info: sendMpdCommand playlistinfo took 272 milliseconds Jul 10 12:43:54 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Jul 10 12:43:54 volumio volumio[812]: info: ControllerMpd::pushState Jul 10 12:43:54 volumio volumio[812]: info: CoreCommandRouter::servicePushState Jul 10 12:43:54 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:54 volumio volumio[812]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":101037,"duration":14423,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise.wav","artist":null,"album":null,"uri":"INTERNAL/Pink Noise.wav","trackType":"wav"} Jul 10 12:43:54 volumio volumio[812]: verbose: CURRENT POSITION 0 Jul 10 12:43:54 volumio volumio[812]: info: CoreStateMachine::syncState stateService pause Jul 10 12:43:54 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Jul 10 12:43:54 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:43:54 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:54 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:43:54 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Jul 10 12:43:55 volumio volumio[812]: info: ------------------------------ 505ms Jul 10 12:43:55 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Jul 10 12:43:55 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:58 volumio volumio[812]: info: CoreCommandRouter::volumioPlay Jul 10 12:43:58 volumio volumio[812]: info: CoreStateMachine::play index undefined Jul 10 12:43:58 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 12:43:58 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:58 volumio volumio[812]: info: CoreStateMachine::startPlaybackTimer Jul 10 12:43:58 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:58 volumio volumio[812]: info: ControllerMpd::resume Jul 10 12:43:58 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand play Jul 10 12:43:58 volumio volumio[812]: info: Jul 10 12:43:58 volumio volumio[812]: ---------------------------- MPD announces state update: player Jul 10 12:43:58 volumio volumio[812]: info: sendMpdCommand play took 23 milliseconds Jul 10 12:43:58 volumio volumio[812]: info: ControllerMpd::getState Jul 10 12:43:58 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Jul 10 12:43:58 volumio volumio[812]: info: Jul 10 12:43:58 volumio volumio[812]: ---------------------------- MPD announces state update: player Jul 10 12:43:58 volumio volumio[812]: info: ControllerMpd::getState Jul 10 12:43:58 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Jul 10 12:43:58 volumio volumio[812]: info: Jul 10 12:43:58 volumio volumio[812]: ---------------------------- MPD announces state update: player Jul 10 12:43:58 volumio volumio[812]: info: ControllerMpd::getState Jul 10 12:43:58 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Jul 10 12:43:58 volumio volumio[812]: info: sendMpdCommand status took 36 milliseconds Jul 10 12:43:58 volumio volumio[812]: info: sendMpdCommand status took 26 milliseconds Jul 10 12:43:58 volumio volumio[812]: info: sendMpdCommand status took 16 milliseconds Jul 10 12:43:58 volumio volumio[812]: verbose: ControllerMpd::parseState Jul 10 12:43:58 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 12:43:58 volumio volumio[812]: verbose: ControllerMpd::parseState Jul 10 12:43:58 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 12:43:58 volumio volumio[812]: verbose: ControllerMpd::parseState Jul 10 12:43:58 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 12:43:58 volumio volumio[812]: info: sendMpdCommand playlistinfo took 22 milliseconds Jul 10 12:43:58 volumio volumio[812]: info: sendMpdCommand playlistinfo took 20 milliseconds Jul 10 12:43:58 volumio volumio[812]: info: sendMpdCommand playlistinfo took 18 milliseconds Jul 10 12:43:58 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Jul 10 12:43:58 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Jul 10 12:43:58 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Jul 10 12:43:58 volumio volumio[812]: info: ControllerMpd::pushState Jul 10 12:43:58 volumio volumio[812]: info: CoreCommandRouter::servicePushState Jul 10 12:43:58 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:58 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":101998,"duration":14423,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise.wav","artist":null,"album":null,"uri":"INTERNAL/Pink Noise.wav","trackType":"wav"} Jul 10 12:43:58 volumio volumio[812]: verbose: CURRENT POSITION 0 Jul 10 12:43:58 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Jul 10 12:43:58 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Jul 10 12:43:58 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:43:58 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:58 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 12:43:58 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:43:58 volumio volumio[812]: info: ControllerMpd::pushState Jul 10 12:43:58 volumio volumio[812]: info: CoreCommandRouter::servicePushState Jul 10 12:43:58 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:58 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":101998,"duration":14423,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise.wav","artist":null,"album":null,"uri":"INTERNAL/Pink Noise.wav","trackType":"wav"} Jul 10 12:43:58 volumio volumio[812]: verbose: CURRENT POSITION 0 Jul 10 12:43:58 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Jul 10 12:43:58 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus play Jul 10 12:43:58 volumio volumio[812]: info: Received an update from plugin. extracting info from payload Jul 10 12:43:58 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:43:58 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:58 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 12:43:58 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:43:58 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:43:58 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:58 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:43:58 volumio volumio[812]: info: ControllerMpd::pushState Jul 10 12:43:58 volumio volumio[812]: info: CoreCommandRouter::servicePushState Jul 10 12:43:58 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:58 volumio volumio[812]: verbose: STATE SERVICE {"status":"play","position":0,"seek":101998,"duration":14423,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise.wav","artist":null,"album":null,"uri":"INTERNAL/Pink Noise.wav","trackType":"wav"} Jul 10 12:43:58 volumio volumio[812]: verbose: CURRENT POSITION 0 Jul 10 12:43:58 volumio volumio[812]: info: CoreStateMachine::syncState stateService play Jul 10 12:43:58 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus play Jul 10 12:43:58 volumio volumio[812]: info: Received an update from plugin. extracting info from payload Jul 10 12:43:58 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:43:58 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:58 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:43:58 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:43:58 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:43:58 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:43:58 volumio volumio[812]: info: ------------------------------ 662ms Jul 10 12:43:58 volumio volumio[812]: info: ------------------------------ 653ms Jul 10 12:43:58 volumio volumio[812]: info: ------------------------------ 645ms Jul 10 12:44:01 volumio volumio[812]: info: CoreCommandRouter::volumioPause Jul 10 12:44:01 volumio volumio[812]: info: CoreStateMachine::pause Jul 10 12:44:01 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Jul 10 12:44:01 volumio volumio[812]: info: CoreStateMachine::servicePause Jul 10 12:44:01 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:44:01 volumio volumio[812]: info: CoreCommandRouter::servicePause Jul 10 12:44:01 volumio volumio[812]: info: ControllerMpd::pause Jul 10 12:44:01 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand pause Jul 10 12:44:01 volumio volumio[812]: info: Jul 10 12:44:01 volumio volumio[812]: ---------------------------- MPD announces state update: player Jul 10 12:44:01 volumio volumio[812]: info: sendMpdCommand pause took 10 milliseconds Jul 10 12:44:01 volumio volumio[812]: info: ControllerMpd::getState Jul 10 12:44:01 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Jul 10 12:44:01 volumio volumio[812]: info: Jul 10 12:44:01 volumio volumio[812]: ---------------------------- MPD announces state update: player Jul 10 12:44:01 volumio volumio[812]: info: ControllerMpd::getState Jul 10 12:44:01 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Jul 10 12:44:01 volumio volumio[812]: info: Jul 10 12:44:01 volumio volumio[812]: ---------------------------- MPD announces state update: player Jul 10 12:44:01 volumio volumio[812]: info: ControllerMpd::getState Jul 10 12:44:01 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Jul 10 12:44:01 volumio volumio[812]: info: sendMpdCommand status took 31 milliseconds Jul 10 12:44:01 volumio volumio[812]: info: sendMpdCommand status took 22 milliseconds Jul 10 12:44:01 volumio volumio[812]: info: sendMpdCommand status took 13 milliseconds Jul 10 12:44:01 volumio volumio[812]: verbose: ControllerMpd::parseState Jul 10 12:44:01 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 12:44:01 volumio volumio[812]: verbose: ControllerMpd::parseState Jul 10 12:44:01 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 12:44:01 volumio volumio[812]: verbose: ControllerMpd::parseState Jul 10 12:44:01 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 12:44:01 volumio volumio[812]: info: sendMpdCommand playlistinfo took 19 milliseconds Jul 10 12:44:01 volumio volumio[812]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 10 12:44:01 volumio volumio[812]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 10 12:44:01 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Jul 10 12:44:01 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Jul 10 12:44:01 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Jul 10 12:44:01 volumio volumio[812]: info: ControllerMpd::pushState Jul 10 12:44:01 volumio volumio[812]: info: CoreCommandRouter::servicePushState Jul 10 12:44:01 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:44:01 volumio volumio[812]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":105018,"duration":14423,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise.wav","artist":null,"album":null,"uri":"INTERNAL/Pink Noise.wav","trackType":"wav"} Jul 10 12:44:01 volumio volumio[812]: verbose: CURRENT POSITION 0 Jul 10 12:44:01 volumio volumio[812]: info: CoreStateMachine::syncState stateService pause Jul 10 12:44:01 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Jul 10 12:44:01 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:44:01 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:44:01 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 12:44:01 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:44:01 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Jul 10 12:44:01 volumio volumio[812]: info: ControllerMpd::pushState Jul 10 12:44:01 volumio volumio[812]: info: CoreCommandRouter::servicePushState Jul 10 12:44:01 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:44:01 volumio volumio[812]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":105018,"duration":14423,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise.wav","artist":null,"album":null,"uri":"INTERNAL/Pink Noise.wav","trackType":"wav"} Jul 10 12:44:01 volumio volumio[812]: verbose: CURRENT POSITION 0 Jul 10 12:44:01 volumio volumio[812]: info: CoreStateMachine::syncState stateService pause Jul 10 12:44:01 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Jul 10 12:44:01 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:44:01 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:44:01 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:44:01 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Jul 10 12:44:01 volumio volumio[812]: info: ControllerMpd::pushState Jul 10 12:44:01 volumio volumio[812]: info: CoreCommandRouter::servicePushState Jul 10 12:44:01 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:44:01 volumio volumio[812]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":105018,"duration":14423,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink Noise.wav","artist":null,"album":null,"uri":"INTERNAL/Pink Noise.wav","trackType":"wav"} Jul 10 12:44:01 volumio volumio[812]: verbose: CURRENT POSITION 0 Jul 10 12:44:01 volumio volumio[812]: info: CoreStateMachine::syncState stateService pause Jul 10 12:44:01 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus pause Jul 10 12:44:01 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:44:01 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:44:01 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:44:01 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Jul 10 12:44:01 volumio volumio[812]: info: ------------------------------ 319ms Jul 10 12:44:01 volumio volumio[812]: info: ------------------------------ 310ms Jul 10 12:44:01 volumio volumio[812]: info: ------------------------------ 303ms Jul 10 12:44:03 volumio volumio[812]: info: CoreCommandRouter::volumioGetQueue Jul 10 12:44:03 volumio volumio[812]: info: CoreStateMachine::getQueue Jul 10 12:44:03 volumio volumio[812]: info: CorePlayQueue::getQueue Jul 10 12:44:05 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Jul 10 12:44:05 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:44:05 volumio volumio[812]: info: Listing playlists Jul 10 12:44:05 volumio volumio[812]: info: Listing playlists Jul 10 12:44:08 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Jul 10 12:44:08 volumio volumio[812]: info: [1720611848688] [80s80s] handleBrowseUri curUri: 80s80s Jul 10 12:44:08 volumio volumio[812]: info: Preload queue cleared Jul 10 12:44:10 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Jul 10 12:44:10 volumio volumio[812]: info: [1720611850175] [80s80s] handleBrowseUri curUri: 80s80s/eighties Jul 10 12:44:10 volumio volumio[812]: info: [1720611850194] [80s80s] getRadioContent url: eighties Jul 10 12:44:10 volumio volumio[812]: info: Preload queue cleared Jul 10 12:44:11 volumio volumio[812]: info: Preload queue cleared Jul 10 12:44:11 volumio volumio[812]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 10 12:44:11 volumio volumio[812]: info: CoreStateMachine::ClearQueue Jul 10 12:44:11 volumio volumio[812]: info: CoreStateMachine::stop Jul 10 12:44:11 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 12:44:11 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Jul 10 12:44:11 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Jul 10 12:44:11 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Jul 10 12:44:11 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:44:11 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:44:11 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 12:44:11 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:44:11 volumio volumio[812]: info: CoreStateMachine::serviceStop Jul 10 12:44:11 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:44:11 volumio volumio[812]: info: CoreCommandRouter::serviceStop Jul 10 12:44:11 volumio volumio[812]: info: ControllerMpd::stop Jul 10 12:44:11 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 12:44:11 volumio volumio[812]: info: CorePlayQueue::clearPlayQueue Jul 10 12:44:11 volumio volumio[812]: info: CorePlayQueue::saveQueue Jul 10 12:44:11 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Jul 10 12:44:11 volumio volumio[812]: info: CoreStateMachine::addQueueItems Jul 10 12:44:11 volumio volumio[812]: info: CorePlayQueue::addQueueItems Jul 10 12:44:11 volumio volumio[812]: info: Preload queue cleared Jul 10 12:44:11 volumio volumio[812]: info: Adding Item to queue: webeighties/0 Jul 10 12:44:11 volumio volumio[812]: info: Exploding uri webeighties/0 in service 80s80s Jul 10 12:44:11 volumio volumio[812]: info: [1720611851409] [80s80s] explodeUri: webeighties/0 Jul 10 12:44:11 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Jul 10 12:44:11 volumio volumio[812]: info: CorePlayQueue::saveQueue Jul 10 12:44:11 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Jul 10 12:44:11 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Jul 10 12:44:11 volumio volumio[812]: info: CoreCommandRouter::volumioPlay Jul 10 12:44:11 volumio volumio[812]: info: CoreStateMachine::play index 0 Jul 10 12:44:11 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 12:44:11 volumio volumio[812]: info: CoreStateMachine::stop Jul 10 12:44:11 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 12:44:11 volumio volumio[812]: info: CoreStateMachine::play index undefined Jul 10 12:44:11 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 12:44:11 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:44:11 volumio volumio[812]: info: CoreStateMachine::startPlaybackTimer Jul 10 12:44:11 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:44:11 volumio volumio[812]: info: [1720611851554] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/web/mp3-192/volumio Jul 10 12:44:11 volumio volumio[812]: info: [1720611851558] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=62&count=2 Jul 10 12:44:11 volumio volumio[812]: info: Jul 10 12:44:11 volumio volumio[812]: ---------------------------- MPD announces state update: player Jul 10 12:44:11 volumio volumio[812]: info: sendMpdCommand stop took 275 milliseconds Jul 10 12:44:11 volumio volumio[812]: info: ControllerMpd::getState Jul 10 12:44:11 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Jul 10 12:44:11 volumio volumio[812]: info: Jul 10 12:44:11 volumio volumio[812]: ---------------------------- MPD announces state update: player Jul 10 12:44:11 volumio volumio[812]: info: ControllerMpd::getState Jul 10 12:44:11 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Jul 10 12:44:11 volumio volumio[812]: info: Jul 10 12:44:11 volumio volumio[812]: ---------------------------- MPD announces state update: player Jul 10 12:44:11 volumio volumio[812]: info: ControllerMpd::getState Jul 10 12:44:11 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Jul 10 12:44:11 volumio volumio[812]: info: sendMpdCommand status took 31 milliseconds Jul 10 12:44:11 volumio volumio[812]: info: sendMpdCommand status took 22 milliseconds Jul 10 12:44:11 volumio volumio[812]: info: sendMpdCommand status took 13 milliseconds Jul 10 12:44:11 volumio volumio[812]: verbose: ControllerMpd::parseState Jul 10 12:44:11 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 12:44:11 volumio volumio[812]: verbose: ControllerMpd::parseState Jul 10 12:44:11 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 12:44:11 volumio volumio[812]: verbose: ControllerMpd::parseState Jul 10 12:44:11 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 12:44:11 volumio volumio[812]: info: sendMpdCommand playlistinfo took 22 milliseconds Jul 10 12:44:11 volumio volumio[812]: info: sendMpdCommand playlistinfo took 21 milliseconds Jul 10 12:44:11 volumio volumio[812]: info: sendMpdCommand playlistinfo took 19 milliseconds Jul 10 12:44:11 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Jul 10 12:44:11 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Jul 10 12:44:11 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Jul 10 12:44:11 volumio volumio[812]: info: ControllerMpd::pushState Jul 10 12:44:11 volumio volumio[812]: info: CoreCommandRouter::servicePushState Jul 10 12:44:11 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:44:11 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:44:11 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 12:44:11 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:44:11 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:44:11 volumio volumio[812]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 10 12:44:11 volumio volumio[812]: info: ControllerMpd::pushState Jul 10 12:44:11 volumio volumio[812]: info: CoreCommandRouter::servicePushState Jul 10 12:44:11 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:44:11 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:44:11 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:44:11 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:44:11 volumio volumio[812]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 10 12:44:11 volumio volumio[812]: info: ControllerMpd::pushState Jul 10 12:44:11 volumio volumio[812]: info: CoreCommandRouter::servicePushState Jul 10 12:44:11 volumio volumio[812]: info: CoreStateMachine::pushState Jul 10 12:44:11 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:44:11 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Jul 10 12:44:11 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Jul 10 12:44:11 volumio volumio[812]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 10 12:44:11 volumio volumio[812]: info: ------------------------------ 299ms Jul 10 12:44:11 volumio volumio[812]: info: ------------------------------ 290ms Jul 10 12:44:11 volumio volumio[812]: info: ------------------------------ 283ms Jul 10 12:44:12 volumio volumio[812]: info: [1720611852050] [80s80s] Failed to query api, status code: 404 Jul 10 12:44:12 volumio volumio[812]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 12:44:12 volumio volumio[812]: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/80s80s/fake-data.json' Jul 10 12:44:12 volumio volumio[812]: at Object.openSync (fs.js:476:3) Jul 10 12:44:12 volumio volumio[812]: at Object.readFileSync (fs.js:377:35) Jul 10 12:44:12 volumio volumio[812]: at ClientRequest. (/data/plugins/music_service/80s80s/index.js:485:23) Jul 10 12:44:12 volumio volumio[812]: at Object.onceWrapper (events.js:422:26) Jul 10 12:44:12 volumio volumio[812]: at ClientRequest.emit (events.js:315:20) Jul 10 12:44:12 volumio volumio[812]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Jul 10 12:44:12 volumio volumio[812]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Jul 10 12:44:12 volumio volumio[812]: at Socket.socketOnData (_http_client.js:509:22) Jul 10 12:44:12 volumio volumio[812]: at Socket.emit (events.js:315:20) Jul 10 12:44:12 volumio volumio[812]: at addChunk (internal/streams/readable.js:309:12) { Jul 10 12:44:12 volumio volumio[812]: errno: -2, Jul 10 12:44:12 volumio volumio[812]: syscall: 'open', Jul 10 12:44:12 volumio volumio[812]: code: 'ENOENT', Jul 10 12:44:12 volumio volumio[812]: path: '/data/plugins/music_service/80s80s/fake-data.json' Jul 10 12:44:12 volumio volumio[812]: } Jul 10 12:44:12 volumio volumio[812]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 12:44:14 volumio sudo[1386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-10 12:43 Jul 10 12:44:14 volumio sudo[1386]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"