-- Logs begin at Sun 2025-12-14 14:29:04 UTC, end at Tue 2025-12-23 15:34:27 UTC. -- Dec 23 15:33:01 volumioibm volumio[878]: info: Dec 23 15:33:01 volumioibm volumio[878]: ---------------------------- MPD announces system playlist update Dec 23 15:33:01 volumioibm volumio[878]: info: Ignoring MPD Status Update Dec 23 15:33:01 volumioibm volumio[878]: info: Dec 23 15:33:01 volumioibm volumio[878]: ---------------------------- MPD announces state update: player Dec 23 15:33:01 volumioibm volumio[878]: info: ControllerMpd::getState Dec 23 15:33:01 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand status Dec 23 15:33:01 volumioibm volumio[878]: info: ------------------------------ 4ms Dec 23 15:33:01 volumioibm volumio[878]: info: sendMpdCommand status took 4 milliseconds Dec 23 15:33:01 volumioibm volumio[878]: verbose: ControllerMpd::parseState Dec 23 15:33:01 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 15:33:01 volumioibm volumio[878]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 15:33:01 volumioibm volumio[878]: verbose: ControllerMpd::parseTrackInfo Dec 23 15:33:01 volumioibm volumio[878]: info: ControllerMpd::pushState Dec 23 15:33:01 volumioibm volumio[878]: info: CoreCommandRouter::servicePushState Dec 23 15:33:01 volumioibm volumio[878]: info: CorePlayQueue::getTrack 0 Dec 23 15:33:01 volumioibm volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":829717,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"126 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi","album":null,"uri":"https://icecast.live.yle.fi/radio/YleRS/icecast.audio","trackType":"audio"} Dec 23 15:33:01 volumioibm volumio[878]: verbose: CURRENT POSITION 0 Dec 23 15:33:01 volumioibm volumio[878]: info: CoreStateMachine::syncState stateService play Dec 23 15:33:01 volumioibm volumio[878]: info: CoreStateMachine::syncState currentStatus play Dec 23 15:33:01 volumioibm volumio[878]: info: Received an update from plugin. extracting info from payload Dec 23 15:33:01 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 15:33:01 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:33:01 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:33:01 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:33:01 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:33:01 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:33:01 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:33:01 volumioibm volumio[878]: info: ------------------------------ 151ms Dec 23 15:33:06 volumioibm volumio[878]: info: Dec 23 15:33:06 volumioibm volumio[878]: ---------------------------- MPD announces system playlist update Dec 23 15:33:06 volumioibm volumio[878]: info: Ignoring MPD Status Update Dec 23 15:33:06 volumioibm volumio[878]: info: Dec 23 15:33:06 volumioibm volumio[878]: ---------------------------- MPD announces state update: player Dec 23 15:33:06 volumioibm volumio[878]: info: ControllerMpd::getState Dec 23 15:33:06 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand status Dec 23 15:33:06 volumioibm volumio[878]: info: ------------------------------ 8ms Dec 23 15:33:06 volumioibm volumio[878]: info: sendMpdCommand status took 2 milliseconds Dec 23 15:33:06 volumioibm volumio[878]: verbose: ControllerMpd::parseState Dec 23 15:33:06 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 15:33:06 volumioibm volumio[878]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 23 15:33:06 volumioibm volumio[878]: verbose: ControllerMpd::parseTrackInfo Dec 23 15:33:06 volumioibm volumio[878]: info: ControllerMpd::pushState Dec 23 15:33:06 volumioibm volumio[878]: info: CoreCommandRouter::servicePushState Dec 23 15:33:06 volumioibm volumio[878]: info: CorePlayQueue::getTrack 0 Dec 23 15:33:06 volumioibm volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":835477,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"131 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi","album":null,"uri":"https://icecast.live.yle.fi/radio/YleRS/icecast.audio","trackType":"audio"} Dec 23 15:33:06 volumioibm volumio[878]: verbose: CURRENT POSITION 0 Dec 23 15:33:06 volumioibm volumio[878]: info: CoreStateMachine::syncState stateService play Dec 23 15:33:06 volumioibm volumio[878]: info: CoreStateMachine::syncState currentStatus play Dec 23 15:33:06 volumioibm volumio[878]: info: Received an update from plugin. extracting info from payload Dec 23 15:33:06 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 15:33:06 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:33:06 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:33:06 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:33:06 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:33:06 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:33:06 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:33:06 volumioibm volumio[878]: info: ------------------------------ 159ms Dec 23 15:33:12 volumioibm volumio[878]: info: Dec 23 15:33:12 volumioibm volumio[878]: ---------------------------- MPD announces system playlist update Dec 23 15:33:12 volumioibm volumio[878]: info: Ignoring MPD Status Update Dec 23 15:33:12 volumioibm volumio[878]: info: Dec 23 15:33:12 volumioibm volumio[878]: ---------------------------- MPD announces state update: player Dec 23 15:33:12 volumioibm volumio[878]: info: ControllerMpd::getState Dec 23 15:33:12 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand status Dec 23 15:33:12 volumioibm volumio[878]: info: ------------------------------ 4ms Dec 23 15:33:12 volumioibm volumio[878]: info: sendMpdCommand status took 4 milliseconds Dec 23 15:33:12 volumioibm volumio[878]: verbose: ControllerMpd::parseState Dec 23 15:33:12 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 15:33:12 volumioibm volumio[878]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 23 15:33:12 volumioibm volumio[878]: verbose: ControllerMpd::parseTrackInfo Dec 23 15:33:12 volumioibm volumio[878]: info: ControllerMpd::pushState Dec 23 15:33:12 volumioibm volumio[878]: info: CoreCommandRouter::servicePushState Dec 23 15:33:12 volumioibm volumio[878]: info: CorePlayQueue::getTrack 0 Dec 23 15:33:12 volumioibm volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":841088,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"123 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi","album":null,"uri":"https://icecast.live.yle.fi/radio/YleRS/icecast.audio","trackType":"audio"} Dec 23 15:33:12 volumioibm volumio[878]: verbose: CURRENT POSITION 0 Dec 23 15:33:12 volumioibm volumio[878]: info: CoreStateMachine::syncState stateService play Dec 23 15:33:12 volumioibm volumio[878]: info: CoreStateMachine::syncState currentStatus play Dec 23 15:33:12 volumioibm volumio[878]: info: Received an update from plugin. extracting info from payload Dec 23 15:33:12 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 15:33:12 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:33:12 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:33:12 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:33:12 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:33:12 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:33:12 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:33:12 volumioibm volumio[878]: info: ------------------------------ 156ms Dec 23 15:33:18 volumioibm volumio[878]: info: Dec 23 15:33:18 volumioibm volumio[878]: ---------------------------- MPD announces system playlist update Dec 23 15:33:18 volumioibm volumio[878]: info: Ignoring MPD Status Update Dec 23 15:33:18 volumioibm volumio[878]: info: Dec 23 15:33:18 volumioibm volumio[878]: ---------------------------- MPD announces state update: player Dec 23 15:33:18 volumioibm volumio[878]: info: ControllerMpd::getState Dec 23 15:33:18 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand status Dec 23 15:33:18 volumioibm volumio[878]: info: ------------------------------ 4ms Dec 23 15:33:18 volumioibm volumio[878]: info: sendMpdCommand status took 4 milliseconds Dec 23 15:33:18 volumioibm volumio[878]: verbose: ControllerMpd::parseState Dec 23 15:33:18 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 15:33:18 volumioibm volumio[878]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 23 15:33:18 volumioibm volumio[878]: verbose: ControllerMpd::parseTrackInfo Dec 23 15:33:18 volumioibm volumio[878]: info: ControllerMpd::pushState Dec 23 15:33:18 volumioibm volumio[878]: info: CoreCommandRouter::servicePushState Dec 23 15:33:18 volumioibm volumio[878]: info: CorePlayQueue::getTrack 0 Dec 23 15:33:18 volumioibm volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":847082,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"126 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi","album":null,"uri":"https://icecast.live.yle.fi/radio/YleRS/icecast.audio","trackType":"audio"} Dec 23 15:33:18 volumioibm volumio[878]: verbose: CURRENT POSITION 0 Dec 23 15:33:18 volumioibm volumio[878]: info: CoreStateMachine::syncState stateService play Dec 23 15:33:18 volumioibm volumio[878]: info: CoreStateMachine::syncState currentStatus play Dec 23 15:33:18 volumioibm volumio[878]: info: Received an update from plugin. extracting info from payload Dec 23 15:33:18 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 15:33:18 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:33:18 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:33:18 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:33:18 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:33:18 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:33:18 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:33:18 volumioibm volumio[878]: info: ------------------------------ 156ms Dec 23 15:33:19 volumioibm volumio[878]: info: Dec 23 15:33:19 volumioibm volumio[878]: ---------------------------- MPD announces system playlist update Dec 23 15:33:19 volumioibm volumio[878]: info: Ignoring MPD Status Update Dec 23 15:33:19 volumioibm volumio[878]: info: Dec 23 15:33:19 volumioibm volumio[878]: ---------------------------- MPD announces state update: player Dec 23 15:33:19 volumioibm volumio[878]: info: ControllerMpd::getState Dec 23 15:33:19 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand status Dec 23 15:33:19 volumioibm volumio[878]: info: ------------------------------ 5ms Dec 23 15:33:19 volumioibm volumio[878]: info: sendMpdCommand status took 5 milliseconds Dec 23 15:33:19 volumioibm volumio[878]: verbose: ControllerMpd::parseState Dec 23 15:33:19 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 15:33:19 volumioibm volumio[878]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 23 15:33:19 volumioibm volumio[878]: verbose: ControllerMpd::parseTrackInfo Dec 23 15:33:19 volumioibm volumio[878]: info: ControllerMpd::pushState Dec 23 15:33:19 volumioibm volumio[878]: info: CoreCommandRouter::servicePushState Dec 23 15:33:19 volumioibm volumio[878]: info: CorePlayQueue::getTrack 0 Dec 23 15:33:19 volumioibm volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":847978,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi","album":null,"uri":"https://icecast.live.yle.fi/radio/YleRS/icecast.audio","trackType":"audio"} Dec 23 15:33:19 volumioibm volumio[878]: verbose: CURRENT POSITION 0 Dec 23 15:33:19 volumioibm volumio[878]: info: CoreStateMachine::syncState stateService play Dec 23 15:33:19 volumioibm volumio[878]: info: CoreStateMachine::syncState currentStatus play Dec 23 15:33:19 volumioibm volumio[878]: info: Received an update from plugin. extracting info from payload Dec 23 15:33:19 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 15:33:19 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:33:19 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:33:19 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:33:19 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:33:19 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:33:19 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:33:19 volumioibm volumio[878]: info: ------------------------------ 152ms Dec 23 15:33:24 volumioibm volumio[878]: info: Dec 23 15:33:24 volumioibm volumio[878]: ---------------------------- MPD announces system playlist update Dec 23 15:33:24 volumioibm volumio[878]: info: Ignoring MPD Status Update Dec 23 15:33:24 volumioibm volumio[878]: info: Dec 23 15:33:24 volumioibm volumio[878]: ---------------------------- MPD announces state update: player Dec 23 15:33:24 volumioibm volumio[878]: info: ControllerMpd::getState Dec 23 15:33:24 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand status Dec 23 15:33:24 volumioibm volumio[878]: info: ------------------------------ 4ms Dec 23 15:33:24 volumioibm volumio[878]: info: sendMpdCommand status took 3 milliseconds Dec 23 15:33:24 volumioibm volumio[878]: verbose: ControllerMpd::parseState Dec 23 15:33:24 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 15:33:24 volumioibm volumio[878]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 15:33:24 volumioibm volumio[878]: verbose: ControllerMpd::parseTrackInfo Dec 23 15:33:24 volumioibm volumio[878]: info: ControllerMpd::pushState Dec 23 15:33:24 volumioibm volumio[878]: info: CoreCommandRouter::servicePushState Dec 23 15:33:24 volumioibm volumio[878]: info: CorePlayQueue::getTrack 0 Dec 23 15:33:24 volumioibm volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853333,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"137 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi","album":null,"uri":"https://icecast.live.yle.fi/radio/YleRS/icecast.audio","trackType":"audio"} Dec 23 15:33:24 volumioibm volumio[878]: verbose: CURRENT POSITION 0 Dec 23 15:33:24 volumioibm volumio[878]: info: CoreStateMachine::syncState stateService play Dec 23 15:33:24 volumioibm volumio[878]: info: CoreStateMachine::syncState currentStatus play Dec 23 15:33:24 volumioibm volumio[878]: info: Received an update from plugin. extracting info from payload Dec 23 15:33:24 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 15:33:24 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:33:24 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:33:24 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:33:24 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:33:24 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:33:24 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:33:24 volumioibm volumio[878]: info: ------------------------------ 203ms Dec 23 15:33:31 volumioibm volumio[878]: info: Dec 23 15:33:31 volumioibm volumio[878]: ---------------------------- MPD announces system playlist update Dec 23 15:33:31 volumioibm volumio[878]: info: Ignoring MPD Status Update Dec 23 15:33:31 volumioibm volumio[878]: info: Dec 23 15:33:31 volumioibm volumio[878]: ---------------------------- MPD announces state update: player Dec 23 15:33:31 volumioibm volumio[878]: info: ControllerMpd::getState Dec 23 15:33:31 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand status Dec 23 15:33:31 volumioibm volumio[878]: info: ------------------------------ 7ms Dec 23 15:33:31 volumioibm volumio[878]: info: sendMpdCommand status took 3 milliseconds Dec 23 15:33:31 volumioibm volumio[878]: verbose: ControllerMpd::parseState Dec 23 15:33:31 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 15:33:31 volumioibm volumio[878]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 15:33:31 volumioibm volumio[878]: verbose: ControllerMpd::parseTrackInfo Dec 23 15:33:31 volumioibm volumio[878]: info: ControllerMpd::pushState Dec 23 15:33:31 volumioibm volumio[878]: info: CoreCommandRouter::servicePushState Dec 23 15:33:31 volumioibm volumio[878]: info: CorePlayQueue::getTrack 0 Dec 23 15:33:31 volumioibm volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":859968,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"126 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi","album":null,"uri":"https://icecast.live.yle.fi/radio/YleRS/icecast.audio","trackType":"audio"} Dec 23 15:33:31 volumioibm volumio[878]: verbose: CURRENT POSITION 0 Dec 23 15:33:31 volumioibm volumio[878]: info: CoreStateMachine::syncState stateService play Dec 23 15:33:31 volumioibm volumio[878]: info: CoreStateMachine::syncState currentStatus play Dec 23 15:33:31 volumioibm volumio[878]: info: Received an update from plugin. extracting info from payload Dec 23 15:33:31 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 15:33:31 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:33:31 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:33:31 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:33:31 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:33:31 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:33:31 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:33:31 volumioibm volumio[878]: info: ------------------------------ 146ms Dec 23 15:33:36 volumioibm volumio[878]: info: Dec 23 15:33:36 volumioibm volumio[878]: ---------------------------- MPD announces system playlist update Dec 23 15:33:36 volumioibm volumio[878]: info: Ignoring MPD Status Update Dec 23 15:33:36 volumioibm volumio[878]: info: Dec 23 15:33:36 volumioibm volumio[878]: ---------------------------- MPD announces state update: player Dec 23 15:33:36 volumioibm volumio[878]: info: ControllerMpd::getState Dec 23 15:33:36 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand status Dec 23 15:33:36 volumioibm volumio[878]: info: ------------------------------ 8ms Dec 23 15:33:36 volumioibm volumio[878]: info: sendMpdCommand status took 8 milliseconds Dec 23 15:33:36 volumioibm volumio[878]: verbose: ControllerMpd::parseState Dec 23 15:33:36 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 15:33:36 volumioibm volumio[878]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 15:33:36 volumioibm volumio[878]: verbose: ControllerMpd::parseTrackInfo Dec 23 15:33:36 volumioibm volumio[878]: info: ControllerMpd::pushState Dec 23 15:33:36 volumioibm volumio[878]: info: CoreCommandRouter::servicePushState Dec 23 15:33:36 volumioibm volumio[878]: info: CorePlayQueue::getTrack 0 Dec 23 15:33:36 volumioibm volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":865472,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"170 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi","album":null,"uri":"https://icecast.live.yle.fi/radio/YleRS/icecast.audio","trackType":"audio"} Dec 23 15:33:36 volumioibm volumio[878]: verbose: CURRENT POSITION 0 Dec 23 15:33:36 volumioibm volumio[878]: info: CoreStateMachine::syncState stateService play Dec 23 15:33:36 volumioibm volumio[878]: info: CoreStateMachine::syncState currentStatus play Dec 23 15:33:36 volumioibm volumio[878]: info: Received an update from plugin. extracting info from payload Dec 23 15:33:36 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 15:33:36 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:33:36 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:33:36 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:33:36 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:33:36 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:33:36 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:33:37 volumioibm volumio[878]: info: ------------------------------ 167ms Dec 23 15:33:42 volumioibm volumio[878]: info: Dec 23 15:33:42 volumioibm volumio[878]: ---------------------------- MPD announces system playlist update Dec 23 15:33:42 volumioibm volumio[878]: info: Ignoring MPD Status Update Dec 23 15:33:42 volumioibm volumio[878]: info: Dec 23 15:33:42 volumioibm volumio[878]: ---------------------------- MPD announces state update: player Dec 23 15:33:42 volumioibm volumio[878]: info: ControllerMpd::getState Dec 23 15:33:42 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand status Dec 23 15:33:42 volumioibm volumio[878]: info: ------------------------------ 4ms Dec 23 15:33:42 volumioibm volumio[878]: info: sendMpdCommand status took 1 milliseconds Dec 23 15:33:42 volumioibm volumio[878]: verbose: ControllerMpd::parseState Dec 23 15:33:42 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 15:33:42 volumioibm volumio[878]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 15:33:42 volumioibm volumio[878]: verbose: ControllerMpd::parseTrackInfo Dec 23 15:33:42 volumioibm volumio[878]: info: ControllerMpd::pushState Dec 23 15:33:42 volumioibm volumio[878]: info: CoreCommandRouter::servicePushState Dec 23 15:33:42 volumioibm volumio[878]: info: CorePlayQueue::getTrack 0 Dec 23 15:33:42 volumioibm volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871082,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi","album":null,"uri":"https://icecast.live.yle.fi/radio/YleRS/icecast.audio","trackType":"audio"} Dec 23 15:33:42 volumioibm volumio[878]: verbose: CURRENT POSITION 0 Dec 23 15:33:42 volumioibm volumio[878]: info: CoreStateMachine::syncState stateService play Dec 23 15:33:42 volumioibm volumio[878]: info: CoreStateMachine::syncState currentStatus play Dec 23 15:33:42 volumioibm volumio[878]: info: Received an update from plugin. extracting info from payload Dec 23 15:33:42 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 15:33:42 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:33:42 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:33:42 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:33:42 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:33:42 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:33:42 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:33:42 volumioibm volumio[878]: info: ------------------------------ 160ms Dec 23 15:33:49 volumioibm volumio[878]: info: Dec 23 15:33:49 volumioibm volumio[878]: ---------------------------- MPD announces system playlist update Dec 23 15:33:49 volumioibm volumio[878]: info: Ignoring MPD Status Update Dec 23 15:33:49 volumioibm volumio[878]: info: Dec 23 15:33:49 volumioibm volumio[878]: ---------------------------- MPD announces state update: player Dec 23 15:33:49 volumioibm volumio[878]: info: ControllerMpd::getState Dec 23 15:33:49 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand status Dec 23 15:33:49 volumioibm volumio[878]: info: ------------------------------ 7ms Dec 23 15:33:49 volumioibm volumio[878]: info: sendMpdCommand status took 3 milliseconds Dec 23 15:33:49 volumioibm volumio[878]: verbose: ControllerMpd::parseState Dec 23 15:33:49 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 15:33:49 volumioibm volumio[878]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 15:33:49 volumioibm volumio[878]: verbose: ControllerMpd::parseTrackInfo Dec 23 15:33:49 volumioibm volumio[878]: info: ControllerMpd::pushState Dec 23 15:33:49 volumioibm volumio[878]: info: CoreCommandRouter::servicePushState Dec 23 15:33:49 volumioibm volumio[878]: info: CorePlayQueue::getTrack 0 Dec 23 15:33:49 volumioibm volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":877717,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"125 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi","album":null,"uri":"https://icecast.live.yle.fi/radio/YleRS/icecast.audio","trackType":"audio"} Dec 23 15:33:49 volumioibm volumio[878]: verbose: CURRENT POSITION 0 Dec 23 15:33:49 volumioibm volumio[878]: info: CoreStateMachine::syncState stateService play Dec 23 15:33:49 volumioibm volumio[878]: info: CoreStateMachine::syncState currentStatus play Dec 23 15:33:49 volumioibm volumio[878]: info: Received an update from plugin. extracting info from payload Dec 23 15:33:49 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 15:33:49 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:33:49 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:33:49 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:33:49 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:33:49 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:33:49 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:33:49 volumioibm volumio[878]: info: ------------------------------ 152ms Dec 23 15:33:54 volumioibm volumio[878]: info: Dec 23 15:33:54 volumioibm volumio[878]: ---------------------------- MPD announces system playlist update Dec 23 15:33:54 volumioibm volumio[878]: info: Ignoring MPD Status Update Dec 23 15:33:54 volumioibm volumio[878]: info: Dec 23 15:33:54 volumioibm volumio[878]: ---------------------------- MPD announces state update: player Dec 23 15:33:54 volumioibm volumio[878]: info: ControllerMpd::getState Dec 23 15:33:54 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand status Dec 23 15:33:54 volumioibm volumio[878]: info: ------------------------------ 4ms Dec 23 15:33:54 volumioibm volumio[878]: info: sendMpdCommand status took 3 milliseconds Dec 23 15:33:54 volumioibm volumio[878]: verbose: ControllerMpd::parseState Dec 23 15:33:54 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 15:33:54 volumioibm volumio[878]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 23 15:33:54 volumioibm volumio[878]: verbose: ControllerMpd::parseTrackInfo Dec 23 15:33:54 volumioibm volumio[878]: info: ControllerMpd::pushState Dec 23 15:33:54 volumioibm volumio[878]: info: CoreCommandRouter::servicePushState Dec 23 15:33:54 volumioibm volumio[878]: info: CorePlayQueue::getTrack 0 Dec 23 15:33:54 volumioibm volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":883221,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"80 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi","album":null,"uri":"https://icecast.live.yle.fi/radio/YleRS/icecast.audio","trackType":"audio"} Dec 23 15:33:54 volumioibm volumio[878]: verbose: CURRENT POSITION 0 Dec 23 15:33:54 volumioibm volumio[878]: info: CoreStateMachine::syncState stateService play Dec 23 15:33:54 volumioibm volumio[878]: info: CoreStateMachine::syncState currentStatus play Dec 23 15:33:54 volumioibm volumio[878]: info: Received an update from plugin. extracting info from payload Dec 23 15:33:54 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 15:33:54 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:33:54 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:33:54 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:33:54 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:33:54 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:33:54 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:33:54 volumioibm volumio[878]: info: ------------------------------ 127ms Dec 23 15:34:00 volumioibm volumio[878]: info: Dec 23 15:34:00 volumioibm volumio[878]: ---------------------------- MPD announces system playlist update Dec 23 15:34:00 volumioibm volumio[878]: info: Ignoring MPD Status Update Dec 23 15:34:00 volumioibm volumio[878]: info: Dec 23 15:34:00 volumioibm volumio[878]: ---------------------------- MPD announces state update: player Dec 23 15:34:00 volumioibm volumio[878]: info: ControllerMpd::getState Dec 23 15:34:00 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand status Dec 23 15:34:00 volumioibm volumio[878]: info: ------------------------------ 5ms Dec 23 15:34:00 volumioibm volumio[878]: info: sendMpdCommand status took 3 milliseconds Dec 23 15:34:00 volumioibm volumio[878]: verbose: ControllerMpd::parseState Dec 23 15:34:00 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 15:34:00 volumioibm volumio[878]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 23 15:34:00 volumioibm volumio[878]: verbose: ControllerMpd::parseTrackInfo Dec 23 15:34:00 volumioibm volumio[878]: info: ControllerMpd::pushState Dec 23 15:34:00 volumioibm volumio[878]: info: CoreCommandRouter::servicePushState Dec 23 15:34:00 volumioibm volumio[878]: info: CorePlayQueue::getTrack 0 Dec 23 15:34:00 volumioibm volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888853,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"126 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi","album":null,"uri":"https://icecast.live.yle.fi/radio/YleRS/icecast.audio","trackType":"audio"} Dec 23 15:34:00 volumioibm volumio[878]: verbose: CURRENT POSITION 0 Dec 23 15:34:00 volumioibm volumio[878]: info: CoreStateMachine::syncState stateService play Dec 23 15:34:00 volumioibm volumio[878]: info: CoreStateMachine::syncState currentStatus play Dec 23 15:34:00 volumioibm volumio[878]: info: Received an update from plugin. extracting info from payload Dec 23 15:34:00 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 15:34:00 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:34:00 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:34:00 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:34:00 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:34:00 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:34:00 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:34:00 volumioibm volumio[878]: info: ------------------------------ 140ms Dec 23 15:34:01 volumioibm volumio[878]: info: Dec 23 15:34:01 volumioibm volumio[878]: ---------------------------- MPD announces system playlist update Dec 23 15:34:01 volumioibm volumio[878]: info: Ignoring MPD Status Update Dec 23 15:34:01 volumioibm volumio[878]: info: Dec 23 15:34:01 volumioibm volumio[878]: ---------------------------- MPD announces state update: player Dec 23 15:34:01 volumioibm volumio[878]: info: ControllerMpd::getState Dec 23 15:34:01 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand status Dec 23 15:34:01 volumioibm volumio[878]: info: ------------------------------ 18ms Dec 23 15:34:01 volumioibm volumio[878]: info: sendMpdCommand status took 5 milliseconds Dec 23 15:34:01 volumioibm volumio[878]: verbose: ControllerMpd::parseState Dec 23 15:34:01 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 15:34:01 volumioibm volumio[878]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 23 15:34:01 volumioibm volumio[878]: verbose: ControllerMpd::parseTrackInfo Dec 23 15:34:01 volumioibm volumio[878]: info: ControllerMpd::pushState Dec 23 15:34:01 volumioibm volumio[878]: info: CoreCommandRouter::servicePushState Dec 23 15:34:01 volumioibm volumio[878]: info: CorePlayQueue::getTrack 0 Dec 23 15:34:01 volumioibm volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":890474,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"121 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi","album":null,"uri":"https://icecast.live.yle.fi/radio/YleRS/icecast.audio","trackType":"audio"} Dec 23 15:34:01 volumioibm volumio[878]: verbose: CURRENT POSITION 0 Dec 23 15:34:01 volumioibm volumio[878]: info: CoreStateMachine::syncState stateService play Dec 23 15:34:01 volumioibm volumio[878]: info: CoreStateMachine::syncState currentStatus play Dec 23 15:34:01 volumioibm volumio[878]: info: Received an update from plugin. extracting info from payload Dec 23 15:34:01 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 15:34:01 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:34:01 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:34:01 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:34:02 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:34:02 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:34:02 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:34:02 volumioibm volumio[878]: info: ------------------------------ 387ms Dec 23 15:34:06 volumioibm volumio[878]: info: Dec 23 15:34:06 volumioibm volumio[878]: ---------------------------- MPD announces system playlist update Dec 23 15:34:06 volumioibm volumio[878]: info: Ignoring MPD Status Update Dec 23 15:34:06 volumioibm volumio[878]: info: Dec 23 15:34:06 volumioibm volumio[878]: ---------------------------- MPD announces state update: player Dec 23 15:34:06 volumioibm volumio[878]: info: ControllerMpd::getState Dec 23 15:34:06 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand status Dec 23 15:34:06 volumioibm volumio[878]: info: ------------------------------ 4ms Dec 23 15:34:06 volumioibm volumio[878]: info: sendMpdCommand status took 4 milliseconds Dec 23 15:34:06 volumioibm volumio[878]: verbose: ControllerMpd::parseState Dec 23 15:34:06 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 15:34:06 volumioibm volumio[878]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 15:34:06 volumioibm volumio[878]: verbose: ControllerMpd::parseTrackInfo Dec 23 15:34:06 volumioibm volumio[878]: info: ControllerMpd::pushState Dec 23 15:34:06 volumioibm volumio[878]: info: CoreCommandRouter::servicePushState Dec 23 15:34:06 volumioibm volumio[878]: info: CorePlayQueue::getTrack 0 Dec 23 15:34:06 volumioibm volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":895594,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"159 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi","album":null,"uri":"https://icecast.live.yle.fi/radio/YleRS/icecast.audio","trackType":"audio"} Dec 23 15:34:06 volumioibm volumio[878]: verbose: CURRENT POSITION 0 Dec 23 15:34:06 volumioibm volumio[878]: info: CoreStateMachine::syncState stateService play Dec 23 15:34:06 volumioibm volumio[878]: info: CoreStateMachine::syncState currentStatus play Dec 23 15:34:06 volumioibm volumio[878]: info: Received an update from plugin. extracting info from payload Dec 23 15:34:06 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 15:34:06 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:34:06 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:34:06 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:34:06 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:34:06 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:34:06 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:34:07 volumioibm volumio[878]: info: ------------------------------ 147ms Dec 23 15:34:12 volumioibm volumio[878]: info: Dec 23 15:34:12 volumioibm volumio[878]: ---------------------------- MPD announces system playlist update Dec 23 15:34:12 volumioibm volumio[878]: info: Ignoring MPD Status Update Dec 23 15:34:12 volumioibm volumio[878]: info: Dec 23 15:34:12 volumioibm volumio[878]: ---------------------------- MPD announces state update: player Dec 23 15:34:12 volumioibm volumio[878]: info: ControllerMpd::getState Dec 23 15:34:12 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand status Dec 23 15:34:12 volumioibm volumio[878]: info: ------------------------------ 4ms Dec 23 15:34:12 volumioibm volumio[878]: info: sendMpdCommand status took 4 milliseconds Dec 23 15:34:12 volumioibm volumio[878]: verbose: ControllerMpd::parseState Dec 23 15:34:12 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 15:34:12 volumioibm volumio[878]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 15:34:12 volumioibm volumio[878]: verbose: ControllerMpd::parseTrackInfo Dec 23 15:34:12 volumioibm volumio[878]: info: ControllerMpd::pushState Dec 23 15:34:12 volumioibm volumio[878]: info: CoreCommandRouter::servicePushState Dec 23 15:34:12 volumioibm volumio[878]: info: CorePlayQueue::getTrack 0 Dec 23 15:34:12 volumioibm volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":901226,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi","album":null,"uri":"https://icecast.live.yle.fi/radio/YleRS/icecast.audio","trackType":"audio"} Dec 23 15:34:12 volumioibm volumio[878]: verbose: CURRENT POSITION 0 Dec 23 15:34:12 volumioibm volumio[878]: info: CoreStateMachine::syncState stateService play Dec 23 15:34:12 volumioibm volumio[878]: info: CoreStateMachine::syncState currentStatus play Dec 23 15:34:12 volumioibm volumio[878]: info: Received an update from plugin. extracting info from payload Dec 23 15:34:12 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 15:34:12 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:34:12 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:34:12 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:34:12 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:34:12 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:34:12 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:34:12 volumioibm volumio[878]: info: ------------------------------ 167ms Dec 23 15:34:19 volumioibm volumio[878]: info: Dec 23 15:34:19 volumioibm volumio[878]: ---------------------------- MPD announces system playlist update Dec 23 15:34:19 volumioibm volumio[878]: info: Ignoring MPD Status Update Dec 23 15:34:19 volumioibm volumio[878]: info: Dec 23 15:34:19 volumioibm volumio[878]: ---------------------------- MPD announces state update: player Dec 23 15:34:19 volumioibm volumio[878]: info: ControllerMpd::getState Dec 23 15:34:19 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand status Dec 23 15:34:19 volumioibm volumio[878]: info: ------------------------------ 8ms Dec 23 15:34:19 volumioibm volumio[878]: info: sendMpdCommand status took 9 milliseconds Dec 23 15:34:19 volumioibm volumio[878]: verbose: ControllerMpd::parseState Dec 23 15:34:19 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 15:34:19 volumioibm volumio[878]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 23 15:34:19 volumioibm volumio[878]: verbose: ControllerMpd::parseTrackInfo Dec 23 15:34:19 volumioibm volumio[878]: info: ControllerMpd::pushState Dec 23 15:34:19 volumioibm volumio[878]: info: CoreCommandRouter::servicePushState Dec 23 15:34:19 volumioibm volumio[878]: info: CorePlayQueue::getTrack 0 Dec 23 15:34:19 volumioibm volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":907840,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"121 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi","album":null,"uri":"https://icecast.live.yle.fi/radio/YleRS/icecast.audio","trackType":"audio"} Dec 23 15:34:19 volumioibm volumio[878]: verbose: CURRENT POSITION 0 Dec 23 15:34:19 volumioibm volumio[878]: info: CoreStateMachine::syncState stateService play Dec 23 15:34:19 volumioibm volumio[878]: info: CoreStateMachine::syncState currentStatus play Dec 23 15:34:19 volumioibm volumio[878]: info: Received an update from plugin. extracting info from payload Dec 23 15:34:19 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 15:34:19 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:34:19 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:34:19 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:34:19 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:34:19 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:34:19 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:34:19 volumioibm volumio[878]: info: ------------------------------ 190ms Dec 23 15:34:24 volumioibm volumio[878]: info: Dec 23 15:34:24 volumioibm volumio[878]: ---------------------------- MPD announces system playlist update Dec 23 15:34:24 volumioibm volumio[878]: info: Ignoring MPD Status Update Dec 23 15:34:24 volumioibm volumio[878]: info: Dec 23 15:34:24 volumioibm volumio[878]: ---------------------------- MPD announces state update: player Dec 23 15:34:24 volumioibm volumio[878]: info: ControllerMpd::getState Dec 23 15:34:24 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand status Dec 23 15:34:24 volumioibm volumio[878]: info: ------------------------------ 3ms Dec 23 15:34:24 volumioibm volumio[878]: info: sendMpdCommand status took 4 milliseconds Dec 23 15:34:24 volumioibm volumio[878]: verbose: ControllerMpd::parseState Dec 23 15:34:24 volumioibm volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 15:34:24 volumioibm volumio[878]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 23 15:34:24 volumioibm volumio[878]: verbose: ControllerMpd::parseTrackInfo Dec 23 15:34:24 volumioibm volumio[878]: info: ControllerMpd::pushState Dec 23 15:34:24 volumioibm volumio[878]: info: CoreCommandRouter::servicePushState Dec 23 15:34:24 volumioibm volumio[878]: info: CorePlayQueue::getTrack 0 Dec 23 15:34:24 volumioibm volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":913472,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"134 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi","album":null,"uri":"https://icecast.live.yle.fi/radio/YleRS/icecast.audio","trackType":"audio"} Dec 23 15:34:24 volumioibm volumio[878]: verbose: CURRENT POSITION 0 Dec 23 15:34:24 volumioibm volumio[878]: info: CoreStateMachine::syncState stateService play Dec 23 15:34:24 volumioibm volumio[878]: info: CoreStateMachine::syncState currentStatus play Dec 23 15:34:24 volumioibm volumio[878]: info: Received an update from plugin. extracting info from payload Dec 23 15:34:24 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 15:34:24 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:34:24 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:34:24 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:34:24 volumioibm volumio[878]: info: CoreStateMachine::pushState Dec 23 15:34:24 volumioibm volumio[878]: info: CoreCommandRouter::volumioPushState Dec 23 15:34:24 volumioibm volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 15:34:24 volumioibm volumio[878]: info: ------------------------------ 159ms Dec 23 15:34:26 volumioibm volumio[878]: info: Tunnel connection is inactive, restarting it Dec 23 15:34:26 volumioibm volumio[878]: info: Starting Tunnel 1 Dec 23 15:34:26 volumioibm volumio[878]: info: Starting Tunnel Connection Checker Dec 23 15:34:26 volumioibm volumio[878]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 15:34:26 volumioibm volumio[878]: { Error: spawn ENOMEM Dec 23 15:34:26 volumioibm volumio[878]: at _errnoException (util.js:1022:11) Dec 23 15:34:26 volumioibm volumio[878]: at ChildProcess.spawn (internal/child_process.js:323:11) Dec 23 15:34:26 volumioibm volumio[878]: at exports.spawn (child_process.js:502:9) Dec 23 15:34:26 volumioibm volumio[878]: at Object.exports.execFile (child_process.js:212:15) Dec 23 15:34:26 volumioibm volumio[878]: at exports.exec (child_process.js:142:18) Dec 23 15:34:26 volumioibm volumio[878]: at /myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:18706 Dec 23 15:34:26 volumioibm volumio[878]: at /volumio/node_modules/graceful-fs/graceful-fs.js:144:16 Dec 23 15:34:26 volumioibm volumio[878]: at /data/plugins/music_service/personal_radio/node_modules/graceful-fs/graceful-fs.js:43:10 Dec 23 15:34:26 volumioibm volumio[878]: at /volumio/node_modules/graceful-fs/graceful-fs.js:61:14 Dec 23 15:34:26 volumioibm volumio[878]: at FSReqWrap.oncomplete (fs.js:135:15) code: 'ENOMEM', errno: 'ENOMEM', syscall: 'spawn' } Dec 23 15:34:26 volumioibm volumio[878]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 15:34:27 volumioibm sudo[27054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-23 15:33 Dec 23 15:34:27 volumioibm sudo[27054]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"