-- Logs begin at Fri 2025-05-09 22:23:07 UTC, end at Sun 2025-05-11 15:23:53 UTC. -- May 11 15:22:01 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioGetState May 11 15:22:01 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:04 volumiodespacho volumio[3328]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 11 15:22:04 volumiodespacho volumio[3328]: info: CURURI: artists://Dire Straits May 11 15:22:06 volumiodespacho volumio[3328]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 11 15:22:06 volumiodespacho volumio[3328]: info: CURURI: artists://Dire Straits/Alchemy: Dire Straits Live Disc 1 May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioReplaceandPlayItems May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::ClearQueue May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::stop May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 15:22:08 volumiodespacho volumio[3328]: info: CorePlayQueue::clearPlayQueue May 11 15:22:08 volumiodespacho volumio[3328]: info: CorePlayQueue::saveQueue May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioPushQueue May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::addQueueItems May 11 15:22:08 volumiodespacho volumio[3328]: info: CorePlayQueue::addQueueItems May 11 15:22:08 volumiodespacho volumio[3328]: info: Preload queue cleared May 11 15:22:08 volumiodespacho volumio[3328]: info: Adding Item to queue: artists://Dire%20Straits/Alchemy%3A%20Dire%20Straits%20Live%20Disc%201 May 11 15:22:08 volumiodespacho volumio[3328]: info: Exploding uri artists://Dire%20Straits/Alchemy%3A%20Dire%20Straits%20Live%20Disc%201 in service mpd May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioPushQueue May 11 15:22:08 volumiodespacho volumio[3328]: info: CorePlayQueue::saveQueue May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::updateTrackBlock May 11 15:22:08 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrackBlock May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioPlay May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::play index 0 May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::stop May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::play index undefined May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 15:22:08 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::startPlaybackTimer May 11 15:22:08 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:08 volumiodespacho volumio[3328]: verbose: ControllerMpd::clearAddPlayTracks NAS/Musica/Dire Straits/Alchemy Part One/01 - Once Upon A Time In The West.mp3 May 11 15:22:08 volumiodespacho volumio[3328]: verbose: ControllerMpd::sendMpdCommand stop May 11 15:22:08 volumiodespacho volumio[3328]: info: sendMpdCommand stop took 4 milliseconds May 11 15:22:08 volumiodespacho volumio[3328]: verbose: ControllerMpd::sendMpdCommand clear May 11 15:22:08 volumiodespacho volumio[3328]: info: May 11 15:22:08 volumiodespacho volumio[3328]: ---------------------------- MPD announces system playlist update May 11 15:22:08 volumiodespacho volumio[3328]: info: Ignoring MPD Status Update May 11 15:22:08 volumiodespacho volumio[3328]: info: sendMpdCommand clear took 7 milliseconds May 11 15:22:08 volumiodespacho volumio[3328]: verbose: ControllerMpd::sendMpdCommand add "NAS/Musica/Dire Straits/Alchemy Part One/01 - Once Upon A Time In The West.mp3" May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioGetState May 11 15:22:08 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:08 volumiodespacho volumio[3328]: info: May 11 15:22:08 volumiodespacho volumio[3328]: ---------------------------- MPD announces system playlist update May 11 15:22:08 volumiodespacho volumio[3328]: info: Ignoring MPD Status Update May 11 15:22:08 volumiodespacho volumio[3328]: error: updateQueue error: null May 11 15:22:08 volumiodespacho volumio[3328]: info: May 11 15:22:08 volumiodespacho volumio[3328]: ---------------------------- MPD announces system playlist update May 11 15:22:08 volumiodespacho volumio[3328]: info: Ignoring MPD Status Update May 11 15:22:08 volumiodespacho volumio[3328]: info: ------------------------------ 19ms May 11 15:22:08 volumiodespacho volumio[3328]: info: sendMpdCommand add "NAS/Musica/Dire Straits/Alchemy Part One/01 - Once Upon A Time In The West.mp3" took 16 milliseconds May 11 15:22:08 volumiodespacho volumio[3328]: info: ------------------------------ 8ms May 11 15:22:08 volumiodespacho volumio[3328]: verbose: ControllerMpd::sendMpdCommand play May 11 15:22:08 volumiodespacho volumio[3328]: info: May 11 15:22:08 volumiodespacho volumio[3328]: ---------------------------- MPD announces system playlist update May 11 15:22:08 volumiodespacho volumio[3328]: info: Ignoring MPD Status Update May 11 15:22:08 volumiodespacho volumio[3328]: info: ------------------------------ 19ms May 11 15:22:08 volumiodespacho volumio[3328]: info: sendMpdCommand play took 14 milliseconds May 11 15:22:08 volumiodespacho volumio[3328]: info: ------------------------------ 13ms May 11 15:22:08 volumiodespacho volumio[3328]: info: Listing playlists May 11 15:22:08 volumiodespacho volumio[3328]: info: Listing playlists May 11 15:22:08 volumiodespacho volumio[3328]: info: May 11 15:22:08 volumiodespacho volumio[3328]: ---------------------------- MPD announces state update: player May 11 15:22:08 volumiodespacho volumio[3328]: info: ControllerMpd::getState May 11 15:22:08 volumiodespacho volumio[3328]: verbose: ControllerMpd::sendMpdCommand status May 11 15:22:08 volumiodespacho volumio[3328]: info: May 11 15:22:08 volumiodespacho volumio[3328]: ---------------------------- MPD announces state update: player May 11 15:22:08 volumiodespacho volumio[3328]: info: ControllerMpd::getState May 11 15:22:08 volumiodespacho volumio[3328]: verbose: ControllerMpd::sendMpdCommand status May 11 15:22:08 volumiodespacho kernel: enter odroid_hw_params rate=48000, format=6 May 11 15:22:08 volumiodespacho kernel: runtime dma_bytes 65536,stream type 0 May 11 15:22:08 volumiodespacho kernel: [aml-i2s-dai]enterd aml_dai_i2s_prepare,old_samplerate:44100,sample_rate=48000 May 11 15:22:08 volumiodespacho kernel: [aml-i2s-dai]i2s dma f0753000,phy addr 3c920000,mode 2,ch 2 May 11 15:22:08 volumiodespacho volumio[3328]: info: May 11 15:22:08 volumiodespacho volumio[3328]: ---------------------------- MPD announces state update: player May 11 15:22:08 volumiodespacho volumio[3328]: info: sendMpdCommand status took 24 milliseconds May 11 15:22:08 volumiodespacho volumio[3328]: info: ControllerMpd::getState May 11 15:22:08 volumiodespacho volumio[3328]: verbose: ControllerMpd::sendMpdCommand status May 11 15:22:08 volumiodespacho volumio[3328]: info: sendMpdCommand status took 23 milliseconds May 11 15:22:08 volumiodespacho volumio[3328]: verbose: ControllerMpd::parseState May 11 15:22:08 volumiodespacho volumio[3328]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 15:22:08 volumiodespacho volumio[3328]: verbose: ControllerMpd::parseState May 11 15:22:08 volumiodespacho volumio[3328]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 15:22:08 volumiodespacho volumio[3328]: info: May 11 15:22:08 volumiodespacho volumio[3328]: ---------------------------- MPD announces state update: player May 11 15:22:08 volumiodespacho volumio[3328]: info: ControllerMpd::getState May 11 15:22:08 volumiodespacho volumio[3328]: verbose: ControllerMpd::sendMpdCommand status May 11 15:22:08 volumiodespacho volumio[3328]: info: sendMpdCommand status took 10 milliseconds May 11 15:22:08 volumiodespacho volumio[3328]: info: sendMpdCommand playlistinfo took 10 milliseconds May 11 15:22:08 volumiodespacho volumio[3328]: info: sendMpdCommand playlistinfo took 8 milliseconds May 11 15:22:08 volumiodespacho volumio[3328]: info: sendMpdCommand status took 4 milliseconds May 11 15:22:08 volumiodespacho volumio[3328]: verbose: ControllerMpd::parseState May 11 15:22:08 volumiodespacho volumio[3328]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 15:22:08 volumiodespacho volumio[3328]: verbose: ControllerMpd::parseTrackInfo May 11 15:22:08 volumiodespacho volumio[3328]: verbose: ControllerMpd::parseTrackInfo May 11 15:22:08 volumiodespacho volumio[3328]: verbose: ControllerMpd::parseState May 11 15:22:08 volumiodespacho volumio[3328]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 15:22:08 volumiodespacho kernel: aiu i2s playback enable May 11 15:22:08 volumiodespacho volumio[3328]: info: ControllerMpd::pushState May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreCommandRouter::servicePushState May 11 15:22:08 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:08 volumiodespacho volumio[3328]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":782,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Once Upon A Time In The West","artist":"Dire Straits","album":"Alchemy: Dire Straits Live Disc 1","uri":"NAS/Musica/Dire Straits/Alchemy Part One/01 - Once Upon A Time In The West.mp3","trackType":"mp3"} May 11 15:22:08 volumiodespacho volumio[3328]: verbose: CURRENT POSITION 0 May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::syncState stateService play May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::syncState currentStatus stop May 11 15:22:08 volumiodespacho volumio[3328]: info: ControllerMpd::pushState May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreCommandRouter::servicePushState May 11 15:22:08 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:08 volumiodespacho volumio[3328]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":782,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Once Upon A Time In The West","artist":"Dire Straits","album":"Alchemy: Dire Straits Live Disc 1","uri":"NAS/Musica/Dire Straits/Alchemy Part One/01 - Once Upon A Time In The West.mp3","trackType":"mp3"} May 11 15:22:08 volumiodespacho volumio[3328]: verbose: CURRENT POSITION 0 May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::syncState stateService play May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::syncState currentStatus play May 11 15:22:08 volumiodespacho volumio[3328]: info: Received an update from plugin. extracting info from payload May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::pushState May 11 15:22:08 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioPushState May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::pushState May 11 15:22:08 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioPushState May 11 15:22:08 volumiodespacho volumio[3328]: info: ------------------------------ 60ms May 11 15:22:08 volumiodespacho volumio[3328]: info: ------------------------------ 63ms May 11 15:22:08 volumiodespacho volumio[3328]: info: sendMpdCommand playlistinfo took 28 milliseconds May 11 15:22:08 volumiodespacho volumio[3328]: info: sendMpdCommand playlistinfo took 27 milliseconds May 11 15:22:08 volumiodespacho volumio[3328]: verbose: ControllerMpd::parseTrackInfo May 11 15:22:08 volumiodespacho volumio[3328]: verbose: ControllerMpd::parseTrackInfo May 11 15:22:08 volumiodespacho volumio[3328]: info: ControllerMpd::pushState May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreCommandRouter::servicePushState May 11 15:22:08 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:08 volumiodespacho volumio[3328]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":782,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Once Upon A Time In The West","artist":"Dire Straits","album":"Alchemy: Dire Straits Live Disc 1","uri":"NAS/Musica/Dire Straits/Alchemy Part One/01 - Once Upon A Time In The West.mp3","trackType":"mp3"} May 11 15:22:08 volumiodespacho volumio[3328]: verbose: CURRENT POSITION 0 May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::syncState stateService play May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::syncState currentStatus play May 11 15:22:08 volumiodespacho volumio[3328]: info: Received an update from plugin. extracting info from payload May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::pushState May 11 15:22:08 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioPushState May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::pushState May 11 15:22:08 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioPushState May 11 15:22:08 volumiodespacho volumio[3328]: info: ControllerMpd::pushState May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreCommandRouter::servicePushState May 11 15:22:08 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:08 volumiodespacho volumio[3328]: verbose: STATE SERVICE {"status":"play","position":0,"seek":325,"duration":782,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Once Upon A Time In The West","artist":"Dire Straits","album":"Alchemy: Dire Straits Live Disc 1","uri":"NAS/Musica/Dire Straits/Alchemy Part One/01 - Once Upon A Time In The West.mp3","trackType":"mp3"} May 11 15:22:08 volumiodespacho volumio[3328]: verbose: CURRENT POSITION 0 May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::syncState stateService play May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::syncState currentStatus play May 11 15:22:08 volumiodespacho volumio[3328]: info: Received an update from plugin. extracting info from payload May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::pushState May 11 15:22:08 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioPushState May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreStateMachine::pushState May 11 15:22:08 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:08 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioPushState May 11 15:22:08 volumiodespacho volumio[3328]: info: ------------------------------ 83ms May 11 15:22:08 volumiodespacho volumio[3328]: info: ------------------------------ 75ms May 11 15:22:11 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioGetState May 11 15:22:11 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:21 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioGetState May 11 15:22:21 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:27 volumiodespacho volumio[3328]: info: May 11 15:22:27 volumiodespacho volumio[3328]: ---------------------------- Client requests Volumio pause May 11 15:22:27 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioPause May 11 15:22:27 volumiodespacho volumio[3328]: info: CoreStateMachine::pause May 11 15:22:27 volumiodespacho volumio[3328]: info: CoreStateMachine::stPlaybackTimer May 11 15:22:27 volumiodespacho volumio[3328]: info: CoreStateMachine::servicePause May 11 15:22:27 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:27 volumiodespacho volumio[3328]: info: CoreCommandRouter::servicePause May 11 15:22:27 volumiodespacho volumio[3328]: info: ControllerMpd::pause May 11 15:22:27 volumiodespacho volumio[3328]: verbose: ControllerMpd::sendMpdCommand pause May 11 15:22:27 volumiodespacho volumio[3328]: info: May 11 15:22:27 volumiodespacho volumio[3328]: ---------------------------- MPD announces state update: player May 11 15:22:27 volumiodespacho volumio[3328]: info: sendMpdCommand pause took 31 milliseconds May 11 15:22:27 volumiodespacho volumio[3328]: info: ControllerMpd::getState May 11 15:22:27 volumiodespacho volumio[3328]: verbose: ControllerMpd::sendMpdCommand status May 11 15:22:27 volumiodespacho volumio[3328]: info: May 11 15:22:27 volumiodespacho volumio[3328]: ---------------------------- MPD announces state update: player May 11 15:22:27 volumiodespacho volumio[3328]: info: ControllerMpd::getState May 11 15:22:27 volumiodespacho volumio[3328]: verbose: ControllerMpd::sendMpdCommand status May 11 15:22:27 volumiodespacho kernel: aiu i2s playback disable May 11 15:22:27 volumiodespacho volumio[3328]: info: sendMpdCommand status took 8 milliseconds May 11 15:22:27 volumiodespacho volumio[3328]: info: sendMpdCommand status took 2 milliseconds May 11 15:22:27 volumiodespacho volumio[3328]: verbose: ControllerMpd::parseState May 11 15:22:27 volumiodespacho volumio[3328]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 15:22:27 volumiodespacho volumio[3328]: verbose: ControllerMpd::parseState May 11 15:22:27 volumiodespacho volumio[3328]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 15:22:27 volumiodespacho volumio[3328]: info: sendMpdCommand playlistinfo took 5 milliseconds May 11 15:22:27 volumiodespacho volumio[3328]: info: sendMpdCommand playlistinfo took 5 milliseconds May 11 15:22:27 volumiodespacho volumio[3328]: verbose: ControllerMpd::parseTrackInfo May 11 15:22:27 volumiodespacho volumio[3328]: verbose: ControllerMpd::parseTrackInfo May 11 15:22:27 volumiodespacho volumio[3328]: info: ControllerMpd::pushState May 11 15:22:27 volumiodespacho volumio[3328]: info: CoreCommandRouter::servicePushState May 11 15:22:27 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:27 volumiodespacho volumio[3328]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":18889,"duration":782,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Once Upon A Time In The West","artist":"Dire Straits","album":"Alchemy: Dire Straits Live Disc 1","uri":"NAS/Musica/Dire Straits/Alchemy Part One/01 - Once Upon A Time In The West.mp3","trackType":"mp3"} May 11 15:22:27 volumiodespacho volumio[3328]: verbose: CURRENT POSITION 0 May 11 15:22:27 volumiodespacho volumio[3328]: info: CoreStateMachine::syncState stateService pause May 11 15:22:27 volumiodespacho volumio[3328]: info: CoreStateMachine::syncState currentStatus pause May 11 15:22:27 volumiodespacho volumio[3328]: info: CoreStateMachine::pushState May 11 15:22:27 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:27 volumiodespacho volumio[3328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 15:22:27 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioPushState May 11 15:22:27 volumiodespacho volumio[3328]: info: CoreStateMachine::stPlaybackTimer May 11 15:22:27 volumiodespacho volumio[3328]: info: ControllerMpd::pushState May 11 15:22:27 volumiodespacho volumio[3328]: info: CoreCommandRouter::servicePushState May 11 15:22:27 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:27 volumiodespacho volumio[3328]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":18889,"duration":782,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Once Upon A Time In The West","artist":"Dire Straits","album":"Alchemy: Dire Straits Live Disc 1","uri":"NAS/Musica/Dire Straits/Alchemy Part One/01 - Once Upon A Time In The West.mp3","trackType":"mp3"} May 11 15:22:27 volumiodespacho volumio[3328]: verbose: CURRENT POSITION 0 May 11 15:22:27 volumiodespacho volumio[3328]: info: CoreStateMachine::syncState stateService pause May 11 15:22:27 volumiodespacho volumio[3328]: info: CoreStateMachine::syncState currentStatus pause May 11 15:22:27 volumiodespacho volumio[3328]: info: CoreStateMachine::pushState May 11 15:22:27 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:27 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioPushState May 11 15:22:27 volumiodespacho volumio[3328]: info: CoreStateMachine::stPlaybackTimer May 11 15:22:27 volumiodespacho volumio[3328]: info: ------------------------------ 38ms May 11 15:22:27 volumiodespacho volumio[3328]: info: ------------------------------ 33ms May 11 15:22:27 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioGetState May 11 15:22:27 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:27 volumiodespacho volumio[3328]: info: Listing playlists May 11 15:22:27 volumiodespacho volumio[3328]: info: Listing playlists May 11 15:22:28 volumiodespacho volumio[3328]: info: May 11 15:22:28 volumiodespacho volumio[3328]: ---------------------------- Client requests Volumio play May 11 15:22:28 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioPlay May 11 15:22:28 volumiodespacho volumio[3328]: info: CoreStateMachine::play index undefined May 11 15:22:28 volumiodespacho volumio[3328]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 15:22:28 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:28 volumiodespacho volumio[3328]: info: CoreStateMachine::startPlaybackTimer May 11 15:22:28 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:28 volumiodespacho volumio[3328]: info: ControllerMpd::resume May 11 15:22:28 volumiodespacho volumio[3328]: verbose: ControllerMpd::sendMpdCommand play May 11 15:22:28 volumiodespacho kernel: enter odroid_hw_params rate=48000, format=6 May 11 15:22:28 volumiodespacho kernel: runtime dma_bytes 65536,stream type 0 May 11 15:22:28 volumiodespacho kernel: [aml-i2s-dai]i2s dma f0753000,phy addr 3c920000,mode 2,ch 2 May 11 15:22:28 volumiodespacho volumio[3328]: info: May 11 15:22:28 volumiodespacho volumio[3328]: ---------------------------- MPD announces state update: player May 11 15:22:28 volumiodespacho volumio[3328]: info: sendMpdCommand play took 23 milliseconds May 11 15:22:28 volumiodespacho volumio[3328]: info: ControllerMpd::getState May 11 15:22:28 volumiodespacho volumio[3328]: verbose: ControllerMpd::sendMpdCommand status May 11 15:22:28 volumiodespacho kernel: aiu i2s playback enable May 11 15:22:28 volumiodespacho volumio[3328]: info: May 11 15:22:28 volumiodespacho volumio[3328]: ---------------------------- MPD announces state update: player May 11 15:22:28 volumiodespacho volumio[3328]: info: ControllerMpd::getState May 11 15:22:28 volumiodespacho volumio[3328]: verbose: ControllerMpd::sendMpdCommand status May 11 15:22:28 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioGetState May 11 15:22:28 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:28 volumiodespacho volumio[3328]: info: sendMpdCommand status took 9 milliseconds May 11 15:22:28 volumiodespacho volumio[3328]: info: sendMpdCommand status took 8 milliseconds May 11 15:22:28 volumiodespacho volumio[3328]: verbose: ControllerMpd::parseState May 11 15:22:28 volumiodespacho volumio[3328]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 15:22:28 volumiodespacho volumio[3328]: verbose: ControllerMpd::parseState May 11 15:22:28 volumiodespacho volumio[3328]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 15:22:28 volumiodespacho volumio[3328]: info: sendMpdCommand playlistinfo took 3 milliseconds May 11 15:22:28 volumiodespacho volumio[3328]: info: sendMpdCommand playlistinfo took 3 milliseconds May 11 15:22:28 volumiodespacho volumio[3328]: verbose: ControllerMpd::parseTrackInfo May 11 15:22:28 volumiodespacho volumio[3328]: verbose: ControllerMpd::parseTrackInfo May 11 15:22:28 volumiodespacho volumio[3328]: info: ControllerMpd::pushState May 11 15:22:28 volumiodespacho volumio[3328]: info: CoreCommandRouter::servicePushState May 11 15:22:28 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:28 volumiodespacho volumio[3328]: verbose: STATE SERVICE {"status":"play","position":0,"seek":19225,"duration":782,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Once Upon A Time In The West","artist":"Dire Straits","album":"Alchemy: Dire Straits Live Disc 1","uri":"NAS/Musica/Dire Straits/Alchemy Part One/01 - Once Upon A Time In The West.mp3","trackType":"mp3"} May 11 15:22:28 volumiodespacho volumio[3328]: verbose: CURRENT POSITION 0 May 11 15:22:28 volumiodespacho volumio[3328]: info: CoreStateMachine::syncState stateService play May 11 15:22:28 volumiodespacho volumio[3328]: info: CoreStateMachine::syncState currentStatus pause May 11 15:22:28 volumiodespacho volumio[3328]: info: CoreStateMachine::pushState May 11 15:22:28 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:28 volumiodespacho volumio[3328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 15:22:28 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioPushState May 11 15:22:28 volumiodespacho volumio[3328]: info: ControllerMpd::pushState May 11 15:22:28 volumiodespacho volumio[3328]: info: CoreCommandRouter::servicePushState May 11 15:22:28 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:28 volumiodespacho volumio[3328]: verbose: STATE SERVICE {"status":"play","position":0,"seek":19225,"duration":782,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Once Upon A Time In The West","artist":"Dire Straits","album":"Alchemy: Dire Straits Live Disc 1","uri":"NAS/Musica/Dire Straits/Alchemy Part One/01 - Once Upon A Time In The West.mp3","trackType":"mp3"} May 11 15:22:28 volumiodespacho volumio[3328]: verbose: CURRENT POSITION 0 May 11 15:22:28 volumiodespacho volumio[3328]: info: CoreStateMachine::syncState stateService play May 11 15:22:28 volumiodespacho volumio[3328]: info: CoreStateMachine::syncState currentStatus play May 11 15:22:28 volumiodespacho volumio[3328]: info: Received an update from plugin. extracting info from payload May 11 15:22:28 volumiodespacho volumio[3328]: info: CoreStateMachine::pushState May 11 15:22:28 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:28 volumiodespacho volumio[3328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 15:22:28 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioPushState May 11 15:22:28 volumiodespacho volumio[3328]: info: CoreStateMachine::pushState May 11 15:22:28 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:28 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioPushState May 11 15:22:28 volumiodespacho volumio[3328]: info: ------------------------------ 44ms May 11 15:22:28 volumiodespacho volumio[3328]: info: ------------------------------ 43ms May 11 15:22:31 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioGetState May 11 15:22:31 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:41 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioGetState May 11 15:22:41 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:51 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioGetState May 11 15:22:51 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:22:51 volumiodespacho volumio[3328]: info: Listing playlists May 11 15:22:51 volumiodespacho volumio[3328]: info: Listing playlists May 11 15:23:01 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioGetState May 11 15:23:01 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:23:11 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioGetState May 11 15:23:11 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:23:11 volumiodespacho volumio[3328]: info: Listing playlists May 11 15:23:11 volumiodespacho volumio[3328]: info: Listing playlists May 11 15:23:21 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioGetState May 11 15:23:21 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:23:31 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioGetState May 11 15:23:31 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:23:31 volumiodespacho volumio[3328]: info: Listing playlists May 11 15:23:31 volumiodespacho volumio[3328]: info: Listing playlists May 11 15:23:41 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioGetState May 11 15:23:41 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:23:51 volumiodespacho volumio[3328]: info: CoreCommandRouter::volumioGetState May 11 15:23:51 volumiodespacho volumio[3328]: info: CorePlayQueue::getTrack 0 May 11 15:23:51 volumiodespacho volumio[3328]: info: Listing playlists May 11 15:23:51 volumiodespacho volumio[3328]: info: Listing playlists May 11 15:23:52 volumiodespacho volumio[3328]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 15:23:52 volumiodespacho volumio[3328]: Error: connect ETIMEDOUT 151.101.134.79:80 May 11 15:23:52 volumiodespacho volumio[3328]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 11 15:23:52 volumiodespacho volumio[3328]: errno: -110, May 11 15:23:52 volumiodespacho volumio[3328]: code: 'ETIMEDOUT', May 11 15:23:52 volumiodespacho volumio[3328]: syscall: 'connect', May 11 15:23:52 volumiodespacho volumio[3328]: address: '151.101.134.79', May 11 15:23:52 volumiodespacho volumio[3328]: port: 80 May 11 15:23:52 volumiodespacho volumio[3328]: } May 11 15:23:52 volumiodespacho volumio[3328]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 15:23:53 volumiodespacho sudo[26459]: volumio : unable to resolve host volumiodespacho May 11 15:23:53 volumiodespacho sudo[26459]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 11 15:23:53 volumiodespacho sudo[26459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-11 15:22 May 11 15:23:53 volumiodespacho sudo[26459]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a89bd62ec6362775dacd2a1210dd0d7fd278d49f" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="89a9d5254c41926abc738c3a5cb276d02612dcf5" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 22 Apr 2023 09:32:42 PM CEST" VOLUMIO_VERSION="3.449" VOLUMIO_HARDWARE="odroidc1" VOLUMIO_DEVICENAME="Odroid-C1" VOLUMIO_HASH="94d31f73f1c2cd47504d4af3f0a2f0be"