-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-12-07 12:16:00 CET. -- Dec 07 12:15:17 volumio volumio[764]: info: Dec 07 12:15:17 volumio volumio[764]: ---------------------------- MPD announces system playlist update Dec 07 12:15:17 volumio volumio[764]: info: Ignoring MPD Status Update Dec 07 12:15:17 volumio volumio[764]: info: Dec 07 12:15:17 volumio volumio[764]: ---------------------------- MPD announces state update: player Dec 07 12:15:17 volumio volumio[764]: info: ControllerMpd::getState Dec 07 12:15:17 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand status Dec 07 12:15:17 volumio volumio[764]: info: Dec 07 12:15:17 volumio volumio[764]: ---------------------------- MPD announces system playlist update Dec 07 12:15:17 volumio volumio[764]: info: Ignoring MPD Status Update Dec 07 12:15:17 volumio volumio[764]: info: Dec 07 12:15:17 volumio volumio[764]: ---------------------------- MPD announces state update: player Dec 07 12:15:17 volumio volumio[764]: info: ControllerMpd::getState Dec 07 12:15:17 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand status Dec 07 12:15:17 volumio volumio[764]: info: Dec 07 12:15:17 volumio volumio[764]: ---------------------------- MPD announces system playlist update Dec 07 12:15:17 volumio volumio[764]: info: Ignoring MPD Status Update Dec 07 12:15:17 volumio volumio[764]: info: Dec 07 12:15:17 volumio volumio[764]: ---------------------------- MPD announces state update: player Dec 07 12:15:17 volumio volumio[764]: info: ControllerMpd::getState Dec 07 12:15:17 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand status Dec 07 12:15:17 volumio volumio[764]: info: ------------------------------ 4ms Dec 07 12:15:17 volumio volumio[764]: info: sendMpdCommand status took 3 milliseconds Dec 07 12:15:17 volumio volumio[764]: info: ------------------------------ 4ms Dec 07 12:15:17 volumio volumio[764]: info: sendMpdCommand status took 3 milliseconds Dec 07 12:15:17 volumio volumio[764]: info: ------------------------------ 4ms Dec 07 12:15:17 volumio volumio[764]: info: sendMpdCommand status took 4 milliseconds Dec 07 12:15:17 volumio volumio[764]: verbose: ControllerMpd::parseState Dec 07 12:15:17 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 12:15:17 volumio volumio[764]: verbose: ControllerMpd::parseState Dec 07 12:15:17 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 12:15:17 volumio volumio[764]: verbose: ControllerMpd::parseState Dec 07 12:15:17 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 12:15:17 volumio volumio[764]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 07 12:15:17 volumio volumio[764]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 07 12:15:17 volumio volumio[764]: verbose: ControllerMpd::parseTrackInfo Dec 07 12:15:17 volumio volumio[764]: info: ControllerMpd::pushState Dec 07 12:15:17 volumio volumio[764]: info: CoreCommandRouter::servicePushState Dec 07 12:15:17 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:17 volumio volumio[764]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22984,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"INDILA - Derniere danse","artist":"Europe2","album":null,"uri":"https://europe2.lmn.fm/europe2.mp3?aw_0_1st.playerid=lgrdnwsTuneIn","trackType":""} Dec 07 12:15:17 volumio volumio[764]: verbose: CURRENT POSITION 0 Dec 07 12:15:17 volumio volumio[764]: info: CoreStateMachine::syncState stateService play Dec 07 12:15:17 volumio volumio[764]: info: CoreStateMachine::syncState currentStatus play Dec 07 12:15:17 volumio volumio[764]: info: Received an update from plugin. extracting info from payload Dec 07 12:15:17 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:17 volumio volumio[764]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 12:15:17 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:17 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:17 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:17 volumio volumio[764]: info: ControllerMpd::pushState Dec 07 12:15:17 volumio volumio[764]: info: CoreCommandRouter::servicePushState Dec 07 12:15:17 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:17 volumio volumio[764]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22984,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"INDILA - Derniere danse","artist":"Europe2","album":null,"uri":"https://europe2.lmn.fm/europe2.mp3?aw_0_1st.playerid=lgrdnwsTuneIn","trackType":""} Dec 07 12:15:17 volumio volumio[764]: verbose: CURRENT POSITION 0 Dec 07 12:15:17 volumio volumio[764]: info: CoreStateMachine::syncState stateService play Dec 07 12:15:17 volumio volumio[764]: info: CoreStateMachine::syncState currentStatus play Dec 07 12:15:17 volumio volumio[764]: info: Received an update from plugin. extracting info from payload Dec 07 12:15:17 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:17 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:17 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:17 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:17 volumio volumio[764]: info: ------------------------------ 20ms Dec 07 12:15:17 volumio volumio[764]: info: ------------------------------ 19ms Dec 07 12:15:17 volumio volumio[764]: info: sendMpdCommand playlistinfo took 15 milliseconds Dec 07 12:15:17 volumio volumio[764]: verbose: ControllerMpd::parseTrackInfo Dec 07 12:15:17 volumio volumio[764]: info: ControllerMpd::pushState Dec 07 12:15:17 volumio volumio[764]: info: CoreCommandRouter::servicePushState Dec 07 12:15:17 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:17 volumio volumio[764]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22984,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"INDILA - Derniere danse","artist":"Europe2","album":null,"uri":"https://europe2.lmn.fm/europe2.mp3?aw_0_1st.playerid=lgrdnwsTuneIn","trackType":""} Dec 07 12:15:17 volumio volumio[764]: verbose: CURRENT POSITION 0 Dec 07 12:15:17 volumio volumio[764]: info: CoreStateMachine::syncState stateService play Dec 07 12:15:17 volumio volumio[764]: info: CoreStateMachine::syncState currentStatus play Dec 07 12:15:17 volumio volumio[764]: info: Received an update from plugin. extracting info from payload Dec 07 12:15:17 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:17 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:17 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:17 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:17 volumio volumio[764]: info: ------------------------------ 24ms Dec 07 12:15:18 volumio volumio[764]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 12:15:18 volumio volumio[764]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 12:15:18 volumio volumio[764]: info: Discovery: Getting this device information Dec 07 12:15:18 volumio volumio[764]: info: CoreCommandRouter::volumioGetState Dec 07 12:15:18 volumio volumio[764]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 12:15:19 volumio volumio[764]: verbose: New Socket.io Connection to 192.168.211.1:3000 from 192.168.211.155 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 07 12:15:21 volumio volumio[764]: info: Saving local item radio-favourites Dec 07 12:15:21 volumio volumio[764]: info: Pushing Favourites {"uri":"http://opml.radiotime.com/Tune.ashx?id=s10464","favourite":false} Dec 07 12:15:32 volumio volumio[764]: Searching plugin music_service/webradio Dec 07 12:15:32 volumio volumio[764]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 07 12:15:32 volumio volumio[764]: info: All search sources collected, pushing search results Dec 07 12:15:35 volumio volumio[764]: Searching plugin music_service/webradio Dec 07 12:15:35 volumio volumio[764]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 07 12:15:36 volumio volumio[764]: info: All search sources collected, pushing search results Dec 07 12:15:43 volumio volumio[764]: Searching plugin music_service/webradio Dec 07 12:15:43 volumio volumio[764]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 07 12:15:43 volumio volumio[764]: info: All search sources collected, pushing search results Dec 07 12:15:50 volumio volumio[764]: verbose: New Socket.io Connection to 192.168.211.1:3000 from 192.168.211.155 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Dec 07 12:15:50 volumio volumio[764]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 12:15:50 volumio volumio[764]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 12:15:50 volumio volumio[764]: info: Discovery: Getting this device information Dec 07 12:15:50 volumio volumio[764]: info: CoreCommandRouter::volumioGetState Dec 07 12:15:50 volumio volumio[764]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 12:15:50 volumio volumio[764]: verbose: New Socket.io Connection to 192.168.1.120 from 192.168.211.155 UA: Mozilla/5.0 (Linux; Android 11; SM-A405FN Build/RP1A.200720.012; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.171 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 07 12:15:50 volumio volumio[764]: info: CoreCommandRouter::volumioGetState Dec 07 12:15:50 volumio volumio[764]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 07 12:15:50 volumio volumio[764]: info: Listing playlists Dec 07 12:15:50 volumio volumio[764]: info: CoreCommandRouter::volumioGetVisibleSources Dec 07 12:15:50 volumio volumio[764]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 12:15:50 volumio volumio[764]: info: CoreCommandRouter::volumioGetQueue Dec 07 12:15:50 volumio volumio[764]: info: CoreStateMachine::getQueue Dec 07 12:15:50 volumio volumio[764]: info: CorePlayQueue::getQueue Dec 07 12:15:51 volumio volumio[764]: info: Preload queue cleared Dec 07 12:15:51 volumio volumio[764]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::ClearQueue Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::stop Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::stPlaybackTimer Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::updateTrackBlock Dec 07 12:15:51 volumio volumio[764]: info: CorePlayQueue::getTrackBlock Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:51 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:51 volumio volumio[764]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 12:15:51 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::serviceStop Dec 07 12:15:51 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:51 volumio volumio[764]: info: CoreCommandRouter::serviceStop Dec 07 12:15:51 volumio volumio[764]: info: [1765106151010] ControllerWebradio::stop Dec 07 12:15:51 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 12:15:51 volumio volumio[764]: info: CorePlayQueue::clearPlayQueue Dec 07 12:15:51 volumio volumio[764]: info: CorePlayQueue::saveQueue Dec 07 12:15:51 volumio volumio[764]: info: CoreCommandRouter::volumioPushQueue Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::addQueueItems Dec 07 12:15:51 volumio volumio[764]: info: CorePlayQueue::addQueueItems Dec 07 12:15:51 volumio volumio[764]: info: Preload queue cleared Dec 07 12:15:51 volumio volumio[764]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s16483 Dec 07 12:15:51 volumio volumio[764]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 07 12:15:51 volumio volumio[764]: info: Dec 07 12:15:51 volumio volumio[764]: ---------------------------- MPD announces state update: player Dec 07 12:15:51 volumio volumio[764]: info: sendMpdCommand stop took 15 milliseconds Dec 07 12:15:51 volumio volumio[764]: info: ControllerMpd::getState Dec 07 12:15:51 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand status Dec 07 12:15:51 volumio volumio[764]: info: Dec 07 12:15:51 volumio volumio[764]: ---------------------------- MPD announces state update: player Dec 07 12:15:51 volumio volumio[764]: info: ControllerMpd::getState Dec 07 12:15:51 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand status Dec 07 12:15:51 volumio volumio[764]: info: Dec 07 12:15:51 volumio volumio[764]: ---------------------------- MPD announces state update: player Dec 07 12:15:51 volumio volumio[764]: info: ControllerMpd::getState Dec 07 12:15:51 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand status Dec 07 12:15:51 volumio volumio[764]: info: sendMpdCommand status took 2 milliseconds Dec 07 12:15:51 volumio volumio[764]: info: sendMpdCommand status took 1 milliseconds Dec 07 12:15:51 volumio volumio[764]: info: sendMpdCommand status took 0 milliseconds Dec 07 12:15:51 volumio volumio[764]: verbose: ControllerMpd::parseState Dec 07 12:15:51 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 12:15:51 volumio volumio[764]: verbose: ControllerMpd::parseState Dec 07 12:15:51 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 12:15:51 volumio volumio[764]: verbose: ControllerMpd::parseState Dec 07 12:15:51 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 12:15:51 volumio volumio[764]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 07 12:15:51 volumio volumio[764]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 07 12:15:51 volumio volumio[764]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 07 12:15:51 volumio volumio[764]: verbose: ControllerMpd::parseTrackInfo Dec 07 12:15:51 volumio volumio[764]: verbose: ControllerMpd::parseTrackInfo Dec 07 12:15:51 volumio volumio[764]: verbose: ControllerMpd::parseTrackInfo Dec 07 12:15:51 volumio volumio[764]: info: ControllerMpd::pushState Dec 07 12:15:51 volumio volumio[764]: info: CoreCommandRouter::servicePushState Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:51 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:51 volumio volumio[764]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 12:15:51 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:51 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:51 volumio volumio[764]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"INDILA - Derniere danse","artist":"Europe2","album":null,"uri":"https://europe2.lmn.fm/europe2.mp3?aw_0_1st.playerid=lgrdnwsTuneIn","trackType":""} Dec 07 12:15:51 volumio volumio[764]: verbose: CURRENT POSITION 0 Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::syncState stateService stop Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::syncState currentStatus stop Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:51 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:51 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:51 volumio volumio[764]: info: No code Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:51 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:51 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:51 volumio volumio[764]: info: ControllerMpd::pushState Dec 07 12:15:51 volumio volumio[764]: info: CoreCommandRouter::servicePushState Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:51 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:51 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:51 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:51 volumio volumio[764]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"INDILA - Derniere danse","artist":"Europe2","album":null,"uri":"https://europe2.lmn.fm/europe2.mp3?aw_0_1st.playerid=lgrdnwsTuneIn","trackType":""} Dec 07 12:15:51 volumio volumio[764]: verbose: CURRENT POSITION 0 Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::syncState stateService stop Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::syncState currentStatus stop Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:51 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:51 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:51 volumio volumio[764]: info: No code Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:51 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:51 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:51 volumio volumio[764]: info: ControllerMpd::pushState Dec 07 12:15:51 volumio volumio[764]: info: CoreCommandRouter::servicePushState Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:51 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:51 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:51 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:51 volumio volumio[764]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"INDILA - Derniere danse","artist":"Europe2","album":null,"uri":"https://europe2.lmn.fm/europe2.mp3?aw_0_1st.playerid=lgrdnwsTuneIn","trackType":""} Dec 07 12:15:51 volumio volumio[764]: verbose: CURRENT POSITION 0 Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::syncState stateService stop Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::syncState currentStatus stop Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:51 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:51 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:51 volumio volumio[764]: info: No code Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:51 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:51 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:51 volumio volumio[764]: info: ------------------------------ 23ms Dec 07 12:15:51 volumio volumio[764]: info: ------------------------------ 21ms Dec 07 12:15:51 volumio volumio[764]: info: ------------------------------ 22ms Dec 07 12:15:51 volumio volumio[764]: info: CoreCommandRouter::volumioPushQueue Dec 07 12:15:51 volumio volumio[764]: info: CorePlayQueue::saveQueue Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::updateTrackBlock Dec 07 12:15:51 volumio volumio[764]: info: CorePlayQueue::getTrackBlock Dec 07 12:15:51 volumio volumio[764]: info: CoreCommandRouter::volumioPlay Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::play index 0 Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::stop Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::play index undefined Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 12:15:51 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:51 volumio volumio[764]: info: CoreStateMachine::startPlaybackTimer Dec 07 12:15:51 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:51 volumio volumio[764]: info: [1765106151275] ControllerWebradio::clearAddPlayTrack Dec 07 12:15:51 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 12:15:51 volumio volumio[764]: info: sendMpdCommand stop took 1 milliseconds Dec 07 12:15:51 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand clear Dec 07 12:15:51 volumio volumio[764]: info: Dec 07 12:15:51 volumio volumio[764]: ---------------------------- MPD announces system playlist update Dec 07 12:15:51 volumio volumio[764]: info: Ignoring MPD Status Update Dec 07 12:15:51 volumio volumio[764]: info: sendMpdCommand clear took 1 milliseconds Dec 07 12:15:51 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand load "http://str0.creacast.com/topmusic_hq.mp3" Dec 07 12:15:51 volumio volumio[764]: info: Dec 07 12:15:51 volumio volumio[764]: ---------------------------- MPD announces system playlist update Dec 07 12:15:51 volumio volumio[764]: info: Ignoring MPD Status Update Dec 07 12:15:51 volumio volumio[764]: info: Dec 07 12:15:51 volumio volumio[764]: ---------------------------- MPD announces system playlist update Dec 07 12:15:51 volumio volumio[764]: info: Ignoring MPD Status Update Dec 07 12:15:51 volumio volumio[764]: error: updateQueue error: null Dec 07 12:15:51 volumio volumio[764]: info: ------------------------------ 2ms Dec 07 12:15:51 volumio volumio[764]: verbose: New Socket.io Connection to 192.168.211.1:3000 from 192.168.211.155 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 07 12:15:52 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand add "http://str0.creacast.com/topmusic_hq.mp3" Dec 07 12:15:52 volumio volumio[764]: error: updateQueue error: null Dec 07 12:15:52 volumio volumio[764]: info: Dec 07 12:15:52 volumio volumio[764]: ---------------------------- MPD announces system playlist update Dec 07 12:15:52 volumio volumio[764]: info: Ignoring MPD Status Update Dec 07 12:15:52 volumio volumio[764]: info: ------------------------------ 1683ms Dec 07 12:15:52 volumio volumio[764]: info: ------------------------------ 1682ms Dec 07 12:15:52 volumio volumio[764]: info: sendMpdCommand add "http://str0.creacast.com/topmusic_hq.mp3" took 1 milliseconds Dec 07 12:15:52 volumio volumio[764]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 07 12:15:52 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand play Dec 07 12:15:52 volumio volumio[764]: info: Dec 07 12:15:52 volumio volumio[764]: ---------------------------- MPD announces system playlist update Dec 07 12:15:52 volumio volumio[764]: info: Ignoring MPD Status Update Dec 07 12:15:52 volumio volumio[764]: info: Dec 07 12:15:52 volumio volumio[764]: ---------------------------- MPD announces system playlist update Dec 07 12:15:52 volumio volumio[764]: info: Ignoring MPD Status Update Dec 07 12:15:52 volumio volumio[764]: info: ------------------------------ 3ms Dec 07 12:15:52 volumio volumio[764]: info: sendMpdCommand play took 2 milliseconds Dec 07 12:15:52 volumio volumio[764]: info: ------------------------------ 2ms Dec 07 12:15:52 volumio volumio[764]: info: ------------------------------ 1ms Dec 07 12:15:53 volumio volumio[764]: info: Dec 07 12:15:53 volumio volumio[764]: ---------------------------- MPD announces state update: player Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::getState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand status Dec 07 12:15:53 volumio volumio[764]: info: Dec 07 12:15:53 volumio volumio[764]: ---------------------------- MPD announces state update: player Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::getState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand status Dec 07 12:15:53 volumio volumio[764]: info: Dec 07 12:15:53 volumio volumio[764]: ---------------------------- MPD announces state update: player Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::getState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand status Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand status took 2 milliseconds Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 12:15:53 volumio volumio[764]: info: Dec 07 12:15:53 volumio volumio[764]: ---------------------------- MPD announces state update: player Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::getState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand status Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand status took 2 milliseconds Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand status took 2 milliseconds Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseTrackInfo Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::pushState Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::servicePushState Dec 07 12:15:53 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:53 volumio volumio[764]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"topmusic_hq.mp3","artist":null,"album":null,"uri":"http://str0.creacast.com/topmusic_hq.mp3","trackType":"mp3"} Dec 07 12:15:53 volumio volumio[764]: verbose: CURRENT POSITION 0 Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState stateService play Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState currentStatus stop Dec 07 12:15:53 volumio volumio[764]: info: ------------------------------ 5ms Dec 07 12:15:53 volumio volumio[764]: info: Dec 07 12:15:53 volumio volumio[764]: ---------------------------- MPD announces state update: player Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::getState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand status Dec 07 12:15:53 volumio volumio[764]: info: Dec 07 12:15:53 volumio volumio[764]: ---------------------------- MPD announces state update: player Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::getState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand status Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand status took 3 milliseconds Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand status took 2 milliseconds Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand status took 1 milliseconds Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseTrackInfo Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseTrackInfo Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::pushState Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::servicePushState Dec 07 12:15:53 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:53 volumio volumio[764]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"topmusic_hq.mp3","artist":null,"album":null,"uri":"http://str0.creacast.com/topmusic_hq.mp3","trackType":"mp3"} Dec 07 12:15:53 volumio volumio[764]: verbose: CURRENT POSITION 0 Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState stateService play Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState currentStatus play Dec 07 12:15:53 volumio volumio[764]: info: Received an update from plugin. extracting info from payload Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:53 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::pushState Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::servicePushState Dec 07 12:15:53 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:53 volumio volumio[764]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"topmusic_hq.mp3","artist":null,"album":null,"uri":"http://str0.creacast.com/topmusic_hq.mp3","trackType":"mp3"} Dec 07 12:15:53 volumio volumio[764]: verbose: CURRENT POSITION 0 Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState stateService play Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState currentStatus play Dec 07 12:15:53 volumio volumio[764]: info: Received an update from plugin. extracting info from payload Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:53 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:53 volumio volumio[764]: info: ------------------------------ 22ms Dec 07 12:15:53 volumio volumio[764]: info: ------------------------------ 22ms Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand playlistinfo took 17 milliseconds Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseTrackInfo Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseTrackInfo Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseTrackInfo Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::pushState Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::servicePushState Dec 07 12:15:53 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:53 volumio volumio[764]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"topmusic_hq.mp3","artist":null,"album":null,"uri":"http://str0.creacast.com/topmusic_hq.mp3","trackType":"mp3"} Dec 07 12:15:53 volumio volumio[764]: verbose: CURRENT POSITION 0 Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState stateService play Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState currentStatus play Dec 07 12:15:53 volumio volumio[764]: info: Received an update from plugin. extracting info from payload Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:53 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::pushState Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::servicePushState Dec 07 12:15:53 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:53 volumio volumio[764]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"topmusic_hq.mp3","artist":null,"album":null,"uri":"http://str0.creacast.com/topmusic_hq.mp3","trackType":"mp3"} Dec 07 12:15:53 volumio volumio[764]: verbose: CURRENT POSITION 0 Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState stateService play Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState currentStatus play Dec 07 12:15:53 volumio volumio[764]: info: Received an update from plugin. extracting info from payload Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:53 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::pushState Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::servicePushState Dec 07 12:15:53 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:53 volumio volumio[764]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"topmusic_hq.mp3","artist":null,"album":null,"uri":"http://str0.creacast.com/topmusic_hq.mp3","trackType":"mp3"} Dec 07 12:15:53 volumio volumio[764]: verbose: CURRENT POSITION 0 Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState stateService play Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState currentStatus play Dec 07 12:15:53 volumio volumio[764]: info: Received an update from plugin. extracting info from payload Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:53 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:53 volumio volumio[764]: info: ------------------------------ 37ms Dec 07 12:15:53 volumio volumio[764]: info: ------------------------------ 35ms Dec 07 12:15:53 volumio volumio[764]: info: ------------------------------ 34ms Dec 07 12:15:53 volumio volumio[764]: info: Dec 07 12:15:53 volumio volumio[764]: ---------------------------- MPD announces system playlist update Dec 07 12:15:53 volumio volumio[764]: info: Ignoring MPD Status Update Dec 07 12:15:53 volumio volumio[764]: info: Dec 07 12:15:53 volumio volumio[764]: ---------------------------- MPD announces state update: player Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::getState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand status Dec 07 12:15:53 volumio volumio[764]: info: Dec 07 12:15:53 volumio volumio[764]: ---------------------------- MPD announces system playlist update Dec 07 12:15:53 volumio volumio[764]: info: Ignoring MPD Status Update Dec 07 12:15:53 volumio volumio[764]: info: Dec 07 12:15:53 volumio volumio[764]: ---------------------------- MPD announces state update: player Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::getState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand status Dec 07 12:15:53 volumio volumio[764]: info: Dec 07 12:15:53 volumio volumio[764]: ---------------------------- MPD announces system playlist update Dec 07 12:15:53 volumio volumio[764]: info: Ignoring MPD Status Update Dec 07 12:15:53 volumio volumio[764]: info: Dec 07 12:15:53 volumio volumio[764]: ---------------------------- MPD announces state update: player Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::getState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand status Dec 07 12:15:53 volumio volumio[764]: info: Dec 07 12:15:53 volumio volumio[764]: ---------------------------- MPD announces system playlist update Dec 07 12:15:53 volumio volumio[764]: info: Ignoring MPD Status Update Dec 07 12:15:53 volumio volumio[764]: info: Dec 07 12:15:53 volumio volumio[764]: ---------------------------- MPD announces state update: player Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::getState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand status Dec 07 12:15:53 volumio volumio[764]: info: ------------------------------ 6ms Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand status took 5 milliseconds Dec 07 12:15:53 volumio volumio[764]: info: ------------------------------ 3ms Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand status took 3 milliseconds Dec 07 12:15:53 volumio volumio[764]: info: ------------------------------ 2ms Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand status took 3 milliseconds Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 12:15:53 volumio volumio[764]: info: Dec 07 12:15:53 volumio volumio[764]: ---------------------------- MPD announces system playlist update Dec 07 12:15:53 volumio volumio[764]: info: Ignoring MPD Status Update Dec 07 12:15:53 volumio volumio[764]: info: Dec 07 12:15:53 volumio volumio[764]: ---------------------------- MPD announces state update: player Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::getState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand status Dec 07 12:15:53 volumio volumio[764]: info: Dec 07 12:15:53 volumio volumio[764]: ---------------------------- MPD announces system playlist update Dec 07 12:15:53 volumio volumio[764]: info: Ignoring MPD Status Update Dec 07 12:15:53 volumio volumio[764]: info: Dec 07 12:15:53 volumio volumio[764]: ---------------------------- MPD announces state update: player Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::getState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand status Dec 07 12:15:53 volumio volumio[764]: info: ------------------------------ 8ms Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand status took 7 milliseconds Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 07 12:15:53 volumio volumio[764]: info: ------------------------------ 6ms Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand status took 5 milliseconds Dec 07 12:15:53 volumio volumio[764]: info: ------------------------------ 6ms Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand status took 3 milliseconds Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseTrackInfo Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseTrackInfo Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseTrackInfo Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseState Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::pushState Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::servicePushState Dec 07 12:15:53 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:53 volumio volumio[764]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"topmusic_hq.mp3","artist":"strasbourg-mp3","album":null,"uri":"http://str0.creacast.com/topmusic_hq.mp3","trackType":"mp3"} Dec 07 12:15:53 volumio volumio[764]: verbose: CURRENT POSITION 0 Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState stateService play Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState currentStatus play Dec 07 12:15:53 volumio volumio[764]: info: Received an update from plugin. extracting info from payload Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:53 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::pushState Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::servicePushState Dec 07 12:15:53 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:53 volumio volumio[764]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"topmusic_hq.mp3","artist":"strasbourg-mp3","album":null,"uri":"http://str0.creacast.com/topmusic_hq.mp3","trackType":"mp3"} Dec 07 12:15:53 volumio volumio[764]: verbose: CURRENT POSITION 0 Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState stateService play Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState currentStatus play Dec 07 12:15:53 volumio volumio[764]: info: Received an update from plugin. extracting info from payload Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:53 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::pushState Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::servicePushState Dec 07 12:15:53 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:53 volumio volumio[764]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"topmusic_hq.mp3","artist":"strasbourg-mp3","album":null,"uri":"http://str0.creacast.com/topmusic_hq.mp3","trackType":"mp3"} Dec 07 12:15:53 volumio volumio[764]: verbose: CURRENT POSITION 0 Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState stateService play Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState currentStatus play Dec 07 12:15:53 volumio volumio[764]: info: Received an update from plugin. extracting info from payload Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:53 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:53 volumio volumio[764]: info: ------------------------------ 32ms Dec 07 12:15:53 volumio volumio[764]: info: ------------------------------ 29ms Dec 07 12:15:53 volumio volumio[764]: info: ------------------------------ 28ms Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand playlistinfo took 17 milliseconds Dec 07 12:15:53 volumio volumio[764]: info: sendMpdCommand playlistinfo took 17 milliseconds Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseTrackInfo Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseTrackInfo Dec 07 12:15:53 volumio volumio[764]: verbose: ControllerMpd::parseTrackInfo Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::pushState Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::servicePushState Dec 07 12:15:53 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:53 volumio volumio[764]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"topmusic_hq.mp3","artist":"strasbourg-mp3","album":null,"uri":"http://str0.creacast.com/topmusic_hq.mp3","trackType":"mp3"} Dec 07 12:15:53 volumio volumio[764]: verbose: CURRENT POSITION 0 Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState stateService play Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState currentStatus play Dec 07 12:15:53 volumio volumio[764]: info: Received an update from plugin. extracting info from payload Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:53 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::pushState Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::servicePushState Dec 07 12:15:53 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:53 volumio volumio[764]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"topmusic_hq.mp3","artist":"strasbourg-mp3","album":null,"uri":"http://str0.creacast.com/topmusic_hq.mp3","trackType":"mp3"} Dec 07 12:15:53 volumio volumio[764]: verbose: CURRENT POSITION 0 Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState stateService play Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState currentStatus play Dec 07 12:15:53 volumio volumio[764]: info: Received an update from plugin. extracting info from payload Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:53 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:53 volumio volumio[764]: info: ControllerMpd::pushState Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::servicePushState Dec 07 12:15:53 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:53 volumio volumio[764]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"topmusic_hq.mp3","artist":"strasbourg-mp3","album":null,"uri":"http://str0.creacast.com/topmusic_hq.mp3","trackType":"mp3"} Dec 07 12:15:53 volumio volumio[764]: verbose: CURRENT POSITION 0 Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState stateService play Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::syncState currentStatus play Dec 07 12:15:53 volumio volumio[764]: info: Received an update from plugin. extracting info from payload Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:53 volumio volumio[764]: info: CorePlayQueue::getTrack 0 Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:53 volumio volumio[764]: info: CoreStateMachine::pushState Dec 07 12:15:53 volumio volumio[764]: info: CoreCommandRouter::volumioPushState Dec 07 12:15:53 volumio volumio[764]: info: ------------------------------ 44ms Dec 07 12:15:53 volumio volumio[764]: info: ------------------------------ 42ms Dec 07 12:15:53 volumio volumio[764]: info: ------------------------------ 40ms Dec 07 12:15:56 volumio volumio[764]: info: Saving local item radio-favourites Dec 07 12:15:56 volumio volumio[764]: info: Pushing Favourites {"uri":"http://opml.radiotime.com/Tune.ashx?id=s16483","favourite":false} Dec 07 12:15:59 volumio volumio[764]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 12:15:59 volumio volumio[764]: Error: connect ETIMEDOUT 158.69.209.125:443 Dec 07 12:15:59 volumio volumio[764]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Dec 07 12:15:59 volumio volumio[764]: errno: -110, Dec 07 12:15:59 volumio volumio[764]: code: 'ETIMEDOUT', Dec 07 12:15:59 volumio volumio[764]: syscall: 'connect', Dec 07 12:15:59 volumio volumio[764]: address: '158.69.209.125', Dec 07 12:15:59 volumio volumio[764]: port: 443 Dec 07 12:15:59 volumio volumio[764]: } Dec 07 12:15:59 volumio volumio[764]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 12:16:00 volumio sudo[30351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-07 12:15 Dec 07 12:16:00 volumio sudo[30351]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"