-- Logs begin at Sun 2024-12-01 15:30:57 CST, end at Sun 2024-12-01 16:32:56 CST. -- Dec 01 16:31:03 volumio volumio[10094]: info: Preload queue cleared Dec 01 16:31:03 volumio volumio[10094]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 01 16:31:03 volumio volumio[10094]: info: CoreStateMachine::ClearQueue Dec 01 16:31:03 volumio volumio[10094]: info: CoreStateMachine::stop Dec 01 16:31:03 volumio volumio[10094]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 16:31:03 volumio volumio[10094]: info: CorePlayQueue::clearPlayQueue Dec 01 16:31:03 volumio volumio[10094]: info: CorePlayQueue::saveQueue Dec 01 16:31:03 volumio volumio[10094]: info: CoreCommandRouter::volumioPushQueue Dec 01 16:31:03 volumio volumio[10094]: info: CoreStateMachine::addQueueItems Dec 01 16:31:03 volumio volumio[10094]: info: CorePlayQueue::addQueueItems Dec 01 16:31:03 volumio volumio[10094]: info: Preload queue cleared Dec 01 16:31:03 volumio volumio[10094]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1783668 Dec 01 16:31:03 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 01 16:31:03 volumio volumio[10094]: info: CoreCommandRouter::volumioPushQueue Dec 01 16:31:03 volumio volumio[10094]: info: CorePlayQueue::saveQueue Dec 01 16:31:03 volumio volumio[10094]: info: CoreStateMachine::updateTrackBlock Dec 01 16:31:03 volumio volumio[10094]: info: CorePlayQueue::getTrackBlock Dec 01 16:31:03 volumio volumio[10094]: info: CoreCommandRouter::volumioPlay Dec 01 16:31:03 volumio volumio[10094]: info: CoreStateMachine::play index 0 Dec 01 16:31:03 volumio volumio[10094]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 16:31:03 volumio volumio[10094]: info: CoreStateMachine::stop Dec 01 16:31:03 volumio volumio[10094]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 16:31:03 volumio volumio[10094]: info: CoreStateMachine::play index undefined Dec 01 16:31:03 volumio volumio[10094]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 16:31:03 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:03 volumio volumio[10094]: info: CoreStateMachine::startPlaybackTimer Dec 01 16:31:03 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:03 volumio volumio[10094]: info: [1733041863914] ControllerWebradio::clearAddPlayTrack Dec 01 16:31:03 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand stop Dec 01 16:31:03 volumio volumio[10094]: info: sendMpdCommand stop took 3 milliseconds Dec 01 16:31:03 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand clear Dec 01 16:31:03 volumio volumio[10094]: info: Dec 01 16:31:03 volumio volumio[10094]: ---------------------------- MPD announces system playlist update Dec 01 16:31:03 volumio volumio[10094]: info: Ignoring MPD Status Update Dec 01 16:31:03 volumio volumio[10094]: info: sendMpdCommand clear took 1 milliseconds Dec 01 16:31:03 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1783668" Dec 01 16:31:03 volumio volumio[10094]: info: Dec 01 16:31:03 volumio volumio[10094]: ---------------------------- MPD announces system playlist update Dec 01 16:31:03 volumio volumio[10094]: info: Ignoring MPD Status Update Dec 01 16:31:03 volumio volumio[10094]: info: Dec 01 16:31:03 volumio volumio[10094]: ---------------------------- MPD announces system playlist update Dec 01 16:31:03 volumio volumio[10094]: info: Ignoring MPD Status Update Dec 01 16:31:03 volumio volumio[10094]: error: updateQueue error: null Dec 01 16:31:03 volumio volumio[10094]: info: ------------------------------ 3ms Dec 01 16:31:04 volumio volumio[10094]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1783668" took 293 milliseconds Dec 01 16:31:04 volumio volumio[10094]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 01 16:31:04 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand play Dec 01 16:31:04 volumio volumio[10094]: info: Dec 01 16:31:04 volumio volumio[10094]: ---------------------------- MPD announces system playlist update Dec 01 16:31:04 volumio volumio[10094]: info: Ignoring MPD Status Update Dec 01 16:31:04 volumio volumio[10094]: info: ------------------------------ 293ms Dec 01 16:31:04 volumio volumio[10094]: info: ------------------------------ 294ms Dec 01 16:31:04 volumio volumio[10094]: info: Dec 01 16:31:04 volumio volumio[10094]: ---------------------------- MPD announces system playlist update Dec 01 16:31:04 volumio volumio[10094]: info: Ignoring MPD Status Update Dec 01 16:31:04 volumio volumio[10094]: info: Dec 01 16:31:04 volumio volumio[10094]: ---------------------------- MPD announces system playlist update Dec 01 16:31:04 volumio volumio[10094]: info: Ignoring MPD Status Update Dec 01 16:31:04 volumio volumio[10094]: info: sendMpdCommand play took 4 milliseconds Dec 01 16:31:04 volumio volumio[10094]: info: ------------------------------ 3ms Dec 01 16:31:04 volumio volumio[10094]: info: ------------------------------ 2ms Dec 01 16:31:04 volumio volumio[10094]: info: ------------------------------ 1ms Dec 01 16:31:05 volumio volumio[10094]: info: Dec 01 16:31:05 volumio volumio[10094]: ---------------------------- MPD announces state update: player Dec 01 16:31:05 volumio volumio[10094]: info: ControllerMpd::getState Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand status Dec 01 16:31:05 volumio volumio[10094]: info: Dec 01 16:31:05 volumio volumio[10094]: ---------------------------- MPD announces state update: player Dec 01 16:31:05 volumio volumio[10094]: info: ControllerMpd::getState Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand status Dec 01 16:31:05 volumio volumio[10094]: info: Dec 01 16:31:05 volumio volumio[10094]: ---------------------------- MPD announces state update: player Dec 01 16:31:05 volumio volumio[10094]: info: ControllerMpd::getState Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand status Dec 01 16:31:05 volumio volumio[10094]: info: Dec 01 16:31:05 volumio volumio[10094]: ---------------------------- MPD announces state update: player Dec 01 16:31:05 volumio volumio[10094]: info: sendMpdCommand status took 7 milliseconds Dec 01 16:31:05 volumio volumio[10094]: info: ControllerMpd::getState Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand status Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::parseState Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 16:31:05 volumio volumio[10094]: info: Dec 01 16:31:05 volumio volumio[10094]: ---------------------------- MPD announces state update: player Dec 01 16:31:05 volumio volumio[10094]: info: ControllerMpd::getState Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand status Dec 01 16:31:05 volumio volumio[10094]: info: sendMpdCommand status took 8 milliseconds Dec 01 16:31:05 volumio volumio[10094]: info: sendMpdCommand status took 7 milliseconds Dec 01 16:31:05 volumio volumio[10094]: info: sendMpdCommand status took 4 milliseconds Dec 01 16:31:05 volumio volumio[10094]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 01 16:31:05 volumio volumio[10094]: info: sendMpdCommand status took 2 milliseconds Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::parseState Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::parseState Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::parseState Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::parseTrackInfo Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::parseState Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 16:31:05 volumio volumio[10094]: info: ControllerMpd::pushState Dec 01 16:31:05 volumio volumio[10094]: info: CoreCommandRouter::servicePushState Dec 01 16:31:05 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:05 volumio volumio[10094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"x_128","artist":"1.FM - Alternative Rock X Hits (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/x_128","trackType":""} Dec 01 16:31:05 volumio volumio[10094]: verbose: CURRENT POSITION 0 Dec 01 16:31:05 volumio volumio[10094]: info: CoreStateMachine::syncState stateService play Dec 01 16:31:05 volumio volumio[10094]: info: CoreStateMachine::syncState currentStatus stop Dec 01 16:31:05 volumio volumio[10094]: info: ------------------------------ 19ms Dec 01 16:31:05 volumio volumio[10094]: info: Dec 01 16:31:05 volumio volumio[10094]: ---------------------------- MPD announces state update: player Dec 01 16:31:05 volumio volumio[10094]: info: ControllerMpd::getState Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand status Dec 01 16:31:05 volumio volumio[10094]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 01 16:31:05 volumio volumio[10094]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 01 16:31:05 volumio volumio[10094]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 01 16:31:05 volumio volumio[10094]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::parseTrackInfo Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::parseTrackInfo Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::parseTrackInfo Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::parseTrackInfo Dec 01 16:31:05 volumio volumio[10094]: info: ControllerMpd::pushState Dec 01 16:31:05 volumio volumio[10094]: info: CoreCommandRouter::servicePushState Dec 01 16:31:05 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:05 volumio volumio[10094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"x_128","artist":"1.FM - Alternative Rock X Hits (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/x_128","trackType":""} Dec 01 16:31:05 volumio volumio[10094]: verbose: CURRENT POSITION 0 Dec 01 16:31:05 volumio volumio[10094]: info: CoreStateMachine::syncState stateService play Dec 01 16:31:05 volumio volumio[10094]: info: CoreStateMachine::syncState currentStatus play Dec 01 16:31:05 volumio volumio[10094]: info: Received an update from plugin. extracting info from payload Dec 01 16:31:05 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:05 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:05 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:31:05 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:05 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:05 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:05 volumio volumio[10094]: info: ControllerMpd::pushState Dec 01 16:31:05 volumio volumio[10094]: info: CoreCommandRouter::servicePushState Dec 01 16:31:05 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:05 volumio volumio[10094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"x_128","artist":"1.FM - Alternative Rock X Hits (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/x_128","trackType":""} Dec 01 16:31:05 volumio volumio[10094]: verbose: CURRENT POSITION 0 Dec 01 16:31:05 volumio volumio[10094]: info: CoreStateMachine::syncState stateService play Dec 01 16:31:05 volumio volumio[10094]: info: CoreStateMachine::syncState currentStatus play Dec 01 16:31:05 volumio volumio[10094]: info: Received an update from plugin. extracting info from payload Dec 01 16:31:05 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:05 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:05 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:05 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:05 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:05 volumio volumio[10094]: info: ControllerMpd::pushState Dec 01 16:31:05 volumio volumio[10094]: info: CoreCommandRouter::servicePushState Dec 01 16:31:05 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:05 volumio volumio[10094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"x_128","artist":"1.FM - Alternative Rock X Hits (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/x_128","trackType":""} Dec 01 16:31:05 volumio volumio[10094]: verbose: CURRENT POSITION 0 Dec 01 16:31:05 volumio volumio[10094]: info: CoreStateMachine::syncState stateService play Dec 01 16:31:05 volumio volumio[10094]: info: CoreStateMachine::syncState currentStatus play Dec 01 16:31:05 volumio volumio[10094]: info: Received an update from plugin. extracting info from payload Dec 01 16:31:05 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:05 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:05 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:05 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:05 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:05 volumio volumio[10094]: info: ControllerMpd::pushState Dec 01 16:31:05 volumio volumio[10094]: info: CoreCommandRouter::servicePushState Dec 01 16:31:05 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:05 volumio volumio[10094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"x_128","artist":"1.FM - Alternative Rock X Hits (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/x_128","trackType":""} Dec 01 16:31:05 volumio volumio[10094]: verbose: CURRENT POSITION 0 Dec 01 16:31:05 volumio volumio[10094]: info: CoreStateMachine::syncState stateService play Dec 01 16:31:05 volumio volumio[10094]: info: CoreStateMachine::syncState currentStatus play Dec 01 16:31:05 volumio volumio[10094]: info: Received an update from plugin. extracting info from payload Dec 01 16:31:05 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:05 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:05 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:05 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:05 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:05 volumio volumio[10094]: info: ------------------------------ 61ms Dec 01 16:31:05 volumio volumio[10094]: info: ------------------------------ 56ms Dec 01 16:31:05 volumio volumio[10094]: info: ------------------------------ 51ms Dec 01 16:31:05 volumio volumio[10094]: info: sendMpdCommand status took 46 milliseconds Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::parseState Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 16:31:05 volumio volumio[10094]: info: sendMpdCommand playlistinfo took 15 milliseconds Dec 01 16:31:05 volumio volumio[10094]: verbose: ControllerMpd::parseTrackInfo Dec 01 16:31:05 volumio volumio[10094]: info: ControllerMpd::pushState Dec 01 16:31:05 volumio volumio[10094]: info: CoreCommandRouter::servicePushState Dec 01 16:31:05 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:05 volumio volumio[10094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"x_128","artist":"1.FM - Alternative Rock X Hits (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/x_128","trackType":""} Dec 01 16:31:05 volumio volumio[10094]: verbose: CURRENT POSITION 0 Dec 01 16:31:05 volumio volumio[10094]: info: CoreStateMachine::syncState stateService play Dec 01 16:31:05 volumio volumio[10094]: info: CoreStateMachine::syncState currentStatus play Dec 01 16:31:05 volumio volumio[10094]: info: Received an update from plugin. extracting info from payload Dec 01 16:31:05 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:05 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:05 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:31:05 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:05 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:05 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:05 volumio volumio[10094]: info: ------------------------------ 75ms Dec 01 16:31:06 volumio volumio[10094]: info: Dec 01 16:31:06 volumio volumio[10094]: ---------------------------- MPD announces system playlist update Dec 01 16:31:06 volumio volumio[10094]: info: Ignoring MPD Status Update Dec 01 16:31:06 volumio volumio[10094]: info: Dec 01 16:31:06 volumio volumio[10094]: ---------------------------- MPD announces state update: player Dec 01 16:31:06 volumio volumio[10094]: info: ControllerMpd::getState Dec 01 16:31:06 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand status Dec 01 16:31:06 volumio volumio[10094]: info: Dec 01 16:31:06 volumio volumio[10094]: ---------------------------- MPD announces system playlist update Dec 01 16:31:06 volumio volumio[10094]: info: Ignoring MPD Status Update Dec 01 16:31:06 volumio volumio[10094]: info: Dec 01 16:31:06 volumio volumio[10094]: ---------------------------- MPD announces state update: player Dec 01 16:31:06 volumio volumio[10094]: info: ControllerMpd::getState Dec 01 16:31:06 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand status Dec 01 16:31:06 volumio volumio[10094]: info: Dec 01 16:31:06 volumio volumio[10094]: ---------------------------- MPD announces system playlist update Dec 01 16:31:06 volumio volumio[10094]: info: Ignoring MPD Status Update Dec 01 16:31:06 volumio volumio[10094]: info: Dec 01 16:31:06 volumio volumio[10094]: ---------------------------- MPD announces state update: player Dec 01 16:31:06 volumio volumio[10094]: info: ControllerMpd::getState Dec 01 16:31:06 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand status Dec 01 16:31:06 volumio volumio[10094]: info: ------------------------------ 46ms Dec 01 16:31:06 volumio volumio[10094]: info: sendMpdCommand status took 35 milliseconds Dec 01 16:31:06 volumio volumio[10094]: info: ------------------------------ 36ms Dec 01 16:31:06 volumio volumio[10094]: info: sendMpdCommand status took 35 milliseconds Dec 01 16:31:06 volumio volumio[10094]: info: ------------------------------ 34ms Dec 01 16:31:06 volumio volumio[10094]: info: sendMpdCommand status took 33 milliseconds Dec 01 16:31:06 volumio volumio[10094]: verbose: ControllerMpd::parseState Dec 01 16:31:06 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 16:31:06 volumio volumio[10094]: verbose: ControllerMpd::parseState Dec 01 16:31:06 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 16:31:06 volumio volumio[10094]: verbose: ControllerMpd::parseState Dec 01 16:31:06 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 16:31:06 volumio volumio[10094]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 01 16:31:06 volumio volumio[10094]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 01 16:31:06 volumio volumio[10094]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 01 16:31:06 volumio volumio[10094]: verbose: ControllerMpd::parseTrackInfo Dec 01 16:31:06 volumio volumio[10094]: verbose: ControllerMpd::parseTrackInfo Dec 01 16:31:06 volumio volumio[10094]: verbose: ControllerMpd::parseTrackInfo Dec 01 16:31:06 volumio volumio[10094]: info: ControllerMpd::pushState Dec 01 16:31:06 volumio volumio[10094]: info: CoreCommandRouter::servicePushState Dec 01 16:31:06 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:06 volumio volumio[10094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":703,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Rob Zombie - Dragula","artist":"1.FM - Alternative Rock X Hits Radio","album":null,"uri":"http://185.33.21.112:80/x_128","trackType":""} Dec 01 16:31:06 volumio volumio[10094]: verbose: CURRENT POSITION 0 Dec 01 16:31:06 volumio volumio[10094]: info: CoreStateMachine::syncState stateService play Dec 01 16:31:06 volumio volumio[10094]: info: CoreStateMachine::syncState currentStatus play Dec 01 16:31:06 volumio volumio[10094]: info: Received an update from plugin. extracting info from payload Dec 01 16:31:06 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:06 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:31:06 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:06 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:06 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:06 volumio volumio[10094]: info: ControllerMpd::pushState Dec 01 16:31:06 volumio volumio[10094]: info: CoreCommandRouter::servicePushState Dec 01 16:31:06 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:06 volumio volumio[10094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":703,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Rob Zombie - Dragula","artist":"1.FM - Alternative Rock X Hits Radio","album":null,"uri":"http://185.33.21.112:80/x_128","trackType":""} Dec 01 16:31:06 volumio volumio[10094]: verbose: CURRENT POSITION 0 Dec 01 16:31:06 volumio volumio[10094]: info: CoreStateMachine::syncState stateService play Dec 01 16:31:06 volumio volumio[10094]: info: CoreStateMachine::syncState currentStatus play Dec 01 16:31:06 volumio volumio[10094]: info: Received an update from plugin. extracting info from payload Dec 01 16:31:06 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:06 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:06 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:06 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:06 volumio volumio[10094]: info: ControllerMpd::pushState Dec 01 16:31:06 volumio volumio[10094]: info: CoreCommandRouter::servicePushState Dec 01 16:31:06 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:06 volumio volumio[10094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":771,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Rob Zombie - Dragula","artist":"1.FM - Alternative Rock X Hits Radio","album":null,"uri":"http://185.33.21.112:80/x_128","trackType":""} Dec 01 16:31:06 volumio volumio[10094]: verbose: CURRENT POSITION 0 Dec 01 16:31:06 volumio volumio[10094]: info: CoreStateMachine::syncState stateService play Dec 01 16:31:06 volumio volumio[10094]: info: CoreStateMachine::syncState currentStatus play Dec 01 16:31:06 volumio volumio[10094]: info: Received an update from plugin. extracting info from payload Dec 01 16:31:06 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:06 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:06 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:06 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:06 volumio volumio[10094]: info: ------------------------------ 77ms Dec 01 16:31:06 volumio volumio[10094]: info: ------------------------------ 76ms Dec 01 16:31:06 volumio volumio[10094]: info: ------------------------------ 75ms Dec 01 16:31:14 volumio ntpd[1352]: ntpd exiting on signal 15 (Terminated) Dec 01 16:31:14 volumio ntpd[1352]: 78.46.102.180 local addr 192.168.0.100 -> Dec 01 16:31:14 volumio ntpd[1352]: 162.159.200.1 local addr 192.168.0.100 -> Dec 01 16:31:14 volumio ntpd[1352]: 202.118.1.81 local addr 192.168.0.100 -> Dec 01 16:31:14 volumio ntpd[1352]: 193.182.111.12 local addr 192.168.0.100 -> Dec 01 16:31:14 volumio ntpd[1352]: 162.159.200.123 local addr 192.168.0.100 -> Dec 01 16:31:14 volumio ntpd[1352]: 193.182.111.142 local addr 192.168.0.100 -> Dec 01 16:31:14 volumio systemd[1]: Stopping Network Time Service... Dec 01 16:31:14 volumio systemd[1]: ntp.service: Succeeded. Dec 01 16:31:14 volumio systemd[1]: Stopped Network Time Service. Dec 01 16:31:14 volumio systemd[1]: Starting Network Time Service... Dec 01 16:31:14 volumio ntpd[10412]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 01 16:31:14 volumio ntpd[10412]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Dec 01 16:31:14 volumio systemd[1]: Started Network Time Service. Dec 01 16:31:14 volumio ntpd[10418]: proto: precision = 0.162 usec (-22) Dec 01 16:31:14 volumio ntpd[10418]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 01 16:31:14 volumio ntpd[10418]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 01 16:31:14 volumio ntpd[10418]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 705 days ago Dec 01 16:31:14 volumio ntpd[10418]: Listen and drop on 0 v6wildcard [::]:123 Dec 01 16:31:14 volumio ntpd[10418]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 01 16:31:14 volumio ntpd[10418]: Listen normally on 2 lo 127.0.0.1:123 Dec 01 16:31:14 volumio ntpd[10418]: Listen normally on 3 eth0 192.168.0.100:123 Dec 01 16:31:14 volumio ntpd[10418]: Listening on routing socket on fd #20 for interface updates Dec 01 16:31:14 volumio ntpd[10418]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Dec 01 16:31:14 volumio ntpd[10418]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Dec 01 16:31:15 volumio ntpd[10418]: Soliciting pool server 202.112.31.197 Dec 01 16:31:16 volumio ntpd[10418]: Soliciting pool server 202.118.1.130 Dec 01 16:31:17 volumio ntpd[10418]: Soliciting pool server 78.46.102.180 Dec 01 16:31:17 volumio ntpd[10418]: Soliciting pool server 139.199.214.202 Dec 01 16:31:18 volumio ntpd[10418]: Soliciting pool server 119.28.183.184 Dec 01 16:31:18 volumio ntpd[10418]: Soliciting pool server 84.16.73.33 Dec 01 16:31:19 volumio ntpd[10418]: Soliciting pool server 193.182.111.141 Dec 01 16:31:29 volumio volumio[10094]: info: Preload queue cleared Dec 01 16:31:29 volumio volumio[10094]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 01 16:31:29 volumio volumio[10094]: info: CoreStateMachine::ClearQueue Dec 01 16:31:29 volumio volumio[10094]: info: CoreStateMachine::stop Dec 01 16:31:29 volumio volumio[10094]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 16:31:29 volumio volumio[10094]: info: CoreStateMachine::stPlaybackTimer Dec 01 16:31:29 volumio volumio[10094]: info: CoreStateMachine::updateTrackBlock Dec 01 16:31:29 volumio volumio[10094]: info: CorePlayQueue::getTrackBlock Dec 01 16:31:29 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:29 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:29 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:31:29 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:29 volumio volumio[10094]: info: CoreStateMachine::serviceStop Dec 01 16:31:29 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:29 volumio volumio[10094]: info: CoreCommandRouter::serviceStop Dec 01 16:31:29 volumio volumio[10094]: info: [1733041889185] ControllerWebradio::stop Dec 01 16:31:29 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand stop Dec 01 16:31:29 volumio volumio[10094]: info: CorePlayQueue::clearPlayQueue Dec 01 16:31:29 volumio volumio[10094]: info: CorePlayQueue::saveQueue Dec 01 16:31:29 volumio volumio[10094]: info: CoreCommandRouter::volumioPushQueue Dec 01 16:31:29 volumio volumio[10094]: info: CoreStateMachine::addQueueItems Dec 01 16:31:29 volumio volumio[10094]: info: CorePlayQueue::addQueueItems Dec 01 16:31:29 volumio volumio[10094]: info: Preload queue cleared Dec 01 16:31:29 volumio volumio[10094]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99571797 Dec 01 16:31:29 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 01 16:31:29 volumio volumio[10094]: info: CoreCommandRouter::volumioPushQueue Dec 01 16:31:29 volumio volumio[10094]: info: CorePlayQueue::saveQueue Dec 01 16:31:29 volumio volumio[10094]: info: CoreStateMachine::updateTrackBlock Dec 01 16:31:29 volumio volumio[10094]: info: CorePlayQueue::getTrackBlock Dec 01 16:31:29 volumio volumio[10094]: info: CoreCommandRouter::volumioPlay Dec 01 16:31:29 volumio volumio[10094]: info: CoreStateMachine::play index 0 Dec 01 16:31:29 volumio volumio[10094]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 16:31:29 volumio volumio[10094]: info: CoreStateMachine::stop Dec 01 16:31:29 volumio volumio[10094]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 16:31:29 volumio volumio[10094]: info: CoreStateMachine::play index undefined Dec 01 16:31:29 volumio volumio[10094]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 16:31:29 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:29 volumio volumio[10094]: info: CoreStateMachine::startPlaybackTimer Dec 01 16:31:29 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:29 volumio volumio[10094]: info: [1733041889212] ControllerWebradio::clearAddPlayTrack Dec 01 16:31:29 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand stop Dec 01 16:31:29 volumio volumio[10094]: info: Dec 01 16:31:29 volumio volumio[10094]: ---------------------------- MPD announces state update: player Dec 01 16:31:29 volumio volumio[10094]: info: sendMpdCommand stop took 33 milliseconds Dec 01 16:31:29 volumio volumio[10094]: info: ControllerMpd::getState Dec 01 16:31:29 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand status Dec 01 16:31:29 volumio volumio[10094]: info: sendMpdCommand stop took 6 milliseconds Dec 01 16:31:29 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand clear Dec 01 16:31:29 volumio volumio[10094]: info: Dec 01 16:31:29 volumio volumio[10094]: ---------------------------- MPD announces state update: player Dec 01 16:31:29 volumio volumio[10094]: info: ControllerMpd::getState Dec 01 16:31:29 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand status Dec 01 16:31:29 volumio volumio[10094]: info: Dec 01 16:31:29 volumio volumio[10094]: ---------------------------- MPD announces state update: player Dec 01 16:31:29 volumio volumio[10094]: info: ControllerMpd::getState Dec 01 16:31:29 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand status Dec 01 16:31:29 volumio volumio[10094]: info: Dec 01 16:31:29 volumio volumio[10094]: ---------------------------- MPD announces system playlist update Dec 01 16:31:29 volumio volumio[10094]: info: Ignoring MPD Status Update Dec 01 16:31:29 volumio volumio[10094]: info: sendMpdCommand status took 7 milliseconds Dec 01 16:31:29 volumio volumio[10094]: info: sendMpdCommand clear took 5 milliseconds Dec 01 16:31:29 volumio volumio[10094]: info: sendMpdCommand status took 3 milliseconds Dec 01 16:31:29 volumio volumio[10094]: verbose: ControllerMpd::parseState Dec 01 16:31:29 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 16:31:29 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99571797" Dec 01 16:31:29 volumio volumio[10094]: verbose: ControllerMpd::parseState Dec 01 16:31:29 volumio volumio[10094]: verbose: ControllerMpd::parseState Dec 01 16:31:29 volumio volumio[10094]: info: ControllerMpd::pushState Dec 01 16:31:29 volumio volumio[10094]: info: CoreCommandRouter::servicePushState Dec 01 16:31:29 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:29 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:29 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:31:29 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:29 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:29 volumio volumio[10094]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 01 16:31:29 volumio volumio[10094]: info: ControllerMpd::pushState Dec 01 16:31:29 volumio volumio[10094]: info: CoreCommandRouter::servicePushState Dec 01 16:31:29 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:29 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:29 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:29 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:29 volumio volumio[10094]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 01 16:31:29 volumio volumio[10094]: info: ------------------------------ 28ms Dec 01 16:31:29 volumio volumio[10094]: info: ------------------------------ 28ms Dec 01 16:31:29 volumio volumio[10094]: info: Dec 01 16:31:29 volumio volumio[10094]: ---------------------------- MPD announces system playlist update Dec 01 16:31:29 volumio volumio[10094]: info: Ignoring MPD Status Update Dec 01 16:31:29 volumio volumio[10094]: info: Dec 01 16:31:29 volumio volumio[10094]: ---------------------------- MPD announces system playlist update Dec 01 16:31:29 volumio volumio[10094]: info: Ignoring MPD Status Update Dec 01 16:31:29 volumio volumio[10094]: error: updateQueue error: null Dec 01 16:31:29 volumio volumio[10094]: info: ------------------------------ 39ms Dec 01 16:31:29 volumio volumio[10094]: info: sendMpdCommand playlistinfo took 38 milliseconds Dec 01 16:31:29 volumio volumio[10094]: verbose: ControllerMpd::parseTrackInfo Dec 01 16:31:29 volumio volumio[10094]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 01 16:31:29 volumio volumio[10094]: info: ------------------------------ 48ms Dec 01 16:31:29 volumio volumio[10094]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99571797" took 291 milliseconds Dec 01 16:31:29 volumio volumio[10094]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 01 16:31:29 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand play Dec 01 16:31:29 volumio volumio[10094]: info: Dec 01 16:31:29 volumio volumio[10094]: ---------------------------- MPD announces system playlist update Dec 01 16:31:29 volumio volumio[10094]: info: Ignoring MPD Status Update Dec 01 16:31:29 volumio volumio[10094]: info: Dec 01 16:31:29 volumio volumio[10094]: ---------------------------- MPD announces system playlist update Dec 01 16:31:29 volumio volumio[10094]: info: Ignoring MPD Status Update Dec 01 16:31:29 volumio volumio[10094]: info: Dec 01 16:31:29 volumio volumio[10094]: ---------------------------- MPD announces system playlist update Dec 01 16:31:29 volumio volumio[10094]: info: Ignoring MPD Status Update Dec 01 16:31:29 volumio volumio[10094]: info: ------------------------------ 274ms Dec 01 16:31:29 volumio volumio[10094]: info: ------------------------------ 273ms Dec 01 16:31:29 volumio volumio[10094]: info: sendMpdCommand play took 7 milliseconds Dec 01 16:31:29 volumio volumio[10094]: info: ------------------------------ 5ms Dec 01 16:31:29 volumio volumio[10094]: info: ------------------------------ 3ms Dec 01 16:31:29 volumio volumio[10094]: info: ------------------------------ 3ms Dec 01 16:31:30 volumio volumio[10094]: info: Dec 01 16:31:30 volumio volumio[10094]: ---------------------------- MPD announces state update: player Dec 01 16:31:30 volumio volumio[10094]: info: ControllerMpd::getState Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand status Dec 01 16:31:30 volumio volumio[10094]: info: Dec 01 16:31:30 volumio volumio[10094]: ---------------------------- MPD announces state update: player Dec 01 16:31:30 volumio volumio[10094]: info: ControllerMpd::getState Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand status Dec 01 16:31:30 volumio volumio[10094]: info: Dec 01 16:31:30 volumio volumio[10094]: ---------------------------- MPD announces state update: player Dec 01 16:31:30 volumio volumio[10094]: info: ControllerMpd::getState Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand status Dec 01 16:31:30 volumio volumio[10094]: info: Dec 01 16:31:30 volumio volumio[10094]: ---------------------------- MPD announces state update: player Dec 01 16:31:30 volumio volumio[10094]: info: ControllerMpd::getState Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand status Dec 01 16:31:30 volumio volumio[10094]: info: sendMpdCommand status took 4 milliseconds Dec 01 16:31:30 volumio volumio[10094]: info: sendMpdCommand status took 3 milliseconds Dec 01 16:31:30 volumio volumio[10094]: info: sendMpdCommand status took 2 milliseconds Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::parseState Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::parseState Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::parseState Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 16:31:30 volumio volumio[10094]: info: Dec 01 16:31:30 volumio volumio[10094]: ---------------------------- MPD announces state update: player Dec 01 16:31:30 volumio volumio[10094]: info: ControllerMpd::getState Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand status Dec 01 16:31:30 volumio volumio[10094]: info: Dec 01 16:31:30 volumio volumio[10094]: ---------------------------- MPD announces state update: player Dec 01 16:31:30 volumio volumio[10094]: info: ControllerMpd::getState Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand status Dec 01 16:31:30 volumio volumio[10094]: info: sendMpdCommand status took 7 milliseconds Dec 01 16:31:30 volumio volumio[10094]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 01 16:31:30 volumio volumio[10094]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 01 16:31:30 volumio volumio[10094]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 01 16:31:30 volumio volumio[10094]: info: sendMpdCommand status took 3 milliseconds Dec 01 16:31:30 volumio volumio[10094]: info: sendMpdCommand status took 2 milliseconds Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::parseState Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::parseTrackInfo Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::parseTrackInfo Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::parseTrackInfo Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::parseState Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::parseState Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 16:31:30 volumio volumio[10094]: info: ControllerMpd::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::servicePushState Dec 01 16:31:30 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:30 volumio volumio[10094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 51/9999) Minimal Mix Radio","album":null,"uri":"http://148.251.43.231:8750/stream","trackType":""} Dec 01 16:31:30 volumio volumio[10094]: verbose: CURRENT POSITION 0 Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::syncState stateService play Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::syncState currentStatus stop Dec 01 16:31:30 volumio volumio[10094]: info: ControllerMpd::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::servicePushState Dec 01 16:31:30 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:30 volumio volumio[10094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 51/9999) Minimal Mix Radio","album":null,"uri":"http://148.251.43.231:8750/stream","trackType":""} Dec 01 16:31:30 volumio volumio[10094]: verbose: CURRENT POSITION 0 Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::syncState stateService play Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::syncState currentStatus play Dec 01 16:31:30 volumio volumio[10094]: info: Received an update from plugin. extracting info from payload Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:30 volumio volumio[10094]: info: ControllerMpd::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::servicePushState Dec 01 16:31:30 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:30 volumio volumio[10094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 51/9999) Minimal Mix Radio","album":null,"uri":"http://148.251.43.231:8750/stream","trackType":""} Dec 01 16:31:30 volumio volumio[10094]: verbose: CURRENT POSITION 0 Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::syncState stateService play Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::syncState currentStatus play Dec 01 16:31:30 volumio volumio[10094]: info: Received an update from plugin. extracting info from payload Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:30 volumio volumio[10094]: info: ------------------------------ 32ms Dec 01 16:31:30 volumio volumio[10094]: info: ------------------------------ 45ms Dec 01 16:31:30 volumio volumio[10094]: info: ------------------------------ 47ms Dec 01 16:31:30 volumio volumio[10094]: info: sendMpdCommand playlistinfo took 39 milliseconds Dec 01 16:31:30 volumio volumio[10094]: info: sendMpdCommand playlistinfo took 38 milliseconds Dec 01 16:31:30 volumio volumio[10094]: info: sendMpdCommand playlistinfo took 37 milliseconds Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::parseTrackInfo Dec 01 16:31:30 volumio volumio[10094]: info: ControllerMpd::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::servicePushState Dec 01 16:31:30 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:30 volumio volumio[10094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 51/9999) Minimal Mix Radio","album":null,"uri":"http://148.251.43.231:8750/stream","trackType":""} Dec 01 16:31:30 volumio volumio[10094]: verbose: CURRENT POSITION 0 Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::syncState stateService play Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::syncState currentStatus play Dec 01 16:31:30 volumio volumio[10094]: info: Received an update from plugin. extracting info from payload Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:30 volumio volumio[10094]: info: ControllerMpd::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::servicePushState Dec 01 16:31:30 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:30 volumio volumio[10094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 51/9999) Minimal Mix Radio","album":null,"uri":"http://148.251.43.231:8750/stream","trackType":""} Dec 01 16:31:30 volumio volumio[10094]: verbose: CURRENT POSITION 0 Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::syncState stateService play Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::syncState currentStatus play Dec 01 16:31:30 volumio volumio[10094]: info: Received an update from plugin. extracting info from payload Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:30 volumio volumio[10094]: info: ControllerMpd::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::servicePushState Dec 01 16:31:30 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:30 volumio volumio[10094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 51/9999) Minimal Mix Radio","album":null,"uri":"http://148.251.43.231:8750/stream","trackType":""} Dec 01 16:31:30 volumio volumio[10094]: verbose: CURRENT POSITION 0 Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::syncState stateService play Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::syncState currentStatus play Dec 01 16:31:30 volumio volumio[10094]: info: Received an update from plugin. extracting info from payload Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:30 volumio volumio[10094]: info: ------------------------------ 93ms Dec 01 16:31:30 volumio volumio[10094]: info: ------------------------------ 89ms Dec 01 16:31:30 volumio volumio[10094]: info: ------------------------------ 89ms Dec 01 16:31:30 volumio volumio[10094]: info: Dec 01 16:31:30 volumio volumio[10094]: ---------------------------- MPD announces system playlist update Dec 01 16:31:30 volumio volumio[10094]: info: Ignoring MPD Status Update Dec 01 16:31:30 volumio volumio[10094]: info: Dec 01 16:31:30 volumio volumio[10094]: ---------------------------- MPD announces state update: player Dec 01 16:31:30 volumio volumio[10094]: info: ControllerMpd::getState Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand status Dec 01 16:31:30 volumio volumio[10094]: info: Dec 01 16:31:30 volumio volumio[10094]: ---------------------------- MPD announces system playlist update Dec 01 16:31:30 volumio volumio[10094]: info: Ignoring MPD Status Update Dec 01 16:31:30 volumio volumio[10094]: info: Dec 01 16:31:30 volumio volumio[10094]: ---------------------------- MPD announces state update: player Dec 01 16:31:30 volumio volumio[10094]: info: ControllerMpd::getState Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand status Dec 01 16:31:30 volumio volumio[10094]: info: Dec 01 16:31:30 volumio volumio[10094]: ---------------------------- MPD announces system playlist update Dec 01 16:31:30 volumio volumio[10094]: info: Ignoring MPD Status Update Dec 01 16:31:30 volumio volumio[10094]: info: Dec 01 16:31:30 volumio volumio[10094]: ---------------------------- MPD announces state update: player Dec 01 16:31:30 volumio volumio[10094]: info: ControllerMpd::getState Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand status Dec 01 16:31:30 volumio volumio[10094]: info: ------------------------------ 37ms Dec 01 16:31:30 volumio volumio[10094]: info: sendMpdCommand status took 5 milliseconds Dec 01 16:31:30 volumio volumio[10094]: info: ------------------------------ 5ms Dec 01 16:31:30 volumio volumio[10094]: info: sendMpdCommand status took 3 milliseconds Dec 01 16:31:30 volumio volumio[10094]: info: ------------------------------ 3ms Dec 01 16:31:30 volumio volumio[10094]: info: sendMpdCommand status took 2 milliseconds Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::parseState Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::parseState Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::parseState Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 16:31:30 volumio volumio[10094]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 01 16:31:30 volumio volumio[10094]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 01 16:31:30 volumio volumio[10094]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::parseTrackInfo Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::parseTrackInfo Dec 01 16:31:30 volumio volumio[10094]: verbose: ControllerMpd::parseTrackInfo Dec 01 16:31:30 volumio volumio[10094]: info: ControllerMpd::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::servicePushState Dec 01 16:31:30 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:30 volumio volumio[10094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1007,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Gregorythmic - Malikan (07 2017 54min) (minimalmix.com)","artist":"Minimal Mix Radio","album":null,"uri":"http://148.251.43.231:8750/stream","trackType":""} Dec 01 16:31:30 volumio volumio[10094]: verbose: CURRENT POSITION 0 Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::syncState stateService play Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::syncState currentStatus play Dec 01 16:31:30 volumio volumio[10094]: info: Received an update from plugin. extracting info from payload Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:30 volumio volumio[10094]: info: ControllerMpd::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::servicePushState Dec 01 16:31:30 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:30 volumio volumio[10094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1007,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Gregorythmic - Malikan (07 2017 54min) (minimalmix.com)","artist":"Minimal Mix Radio","album":null,"uri":"http://148.251.43.231:8750/stream","trackType":""} Dec 01 16:31:30 volumio volumio[10094]: verbose: CURRENT POSITION 0 Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::syncState stateService play Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::syncState currentStatus play Dec 01 16:31:30 volumio volumio[10094]: info: Received an update from plugin. extracting info from payload Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:30 volumio volumio[10094]: info: ControllerMpd::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::servicePushState Dec 01 16:31:30 volumio volumio[10094]: info: CorePlayQueue::getTrack 0 Dec 01 16:31:30 volumio volumio[10094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1007,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Gregorythmic - Malikan (07 2017 54min) (minimalmix.com)","artist":"Minimal Mix Radio","album":null,"uri":"http://148.251.43.231:8750/stream","trackType":""} Dec 01 16:31:30 volumio volumio[10094]: verbose: CURRENT POSITION 0 Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::syncState stateService play Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::syncState currentStatus play Dec 01 16:31:30 volumio volumio[10094]: info: Received an update from plugin. extracting info from payload Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreStateMachine::pushState Dec 01 16:31:30 volumio volumio[10094]: info: CoreCommandRouter::volumioPushState Dec 01 16:31:30 volumio volumio[10094]: info: ------------------------------ 56ms Dec 01 16:31:30 volumio volumio[10094]: info: ------------------------------ 54ms Dec 01 16:31:30 volumio volumio[10094]: info: ------------------------------ 53ms Dec 01 16:31:52 volumio sudo[10426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 01 16:31:52 volumio sudo[10426]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:31:52 volumio sudo[10426]: pam_unix(sudo:session): session closed for user root Dec 01 16:31:52 volumio sudo[10428]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 01 16:31:52 volumio sudo[10428]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:31:52 volumio sudo[10428]: pam_unix(sudo:session): session closed for user root Dec 01 16:31:52 volumio volumio[10094]: verbose: New Socket.io Connection to 192.168.0.100 from 192.168.0.102 UA: Mozilla/5.0 (Linux; U; Android 14; zh-cn; 23049RAD8C Build/UKQ1.230804.001) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.119 Mobile Safari/537.36 XiaoMi/MiuiBrowser/18.7.921118 Total Clients: 4 Dec 01 16:31:53 volumio sudo[10434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 01 16:31:53 volumio sudo[10434]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:31:53 volumio sudo[10434]: pam_unix(sudo:session): session closed for user root Dec 01 16:31:53 volumio sudo[10437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 01 16:31:53 volumio sudo[10437]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:31:53 volumio sudo[10437]: pam_unix(sudo:session): session closed for user root Dec 01 16:31:53 volumio volumio[10094]: verbose: New Socket.io Connection to 192.168.0.100 from 192.168.0.102 UA: Mozilla/5.0 (Linux; U; Android 14; zh-cn; 23049RAD8C Build/UKQ1.230804.001) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.119 Mobile Safari/537.36 XiaoMi/MiuiBrowser/18.7.921118 Total Clients: 5 Dec 01 16:31:53 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 01 16:31:53 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 01 16:31:53 volumio volumio[10094]: info: CoreCommandRouter::volumioGetVisibleSources Dec 01 16:31:53 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 16:31:53 volumio volumio[10094]: info: CoreCommandRouter::volumioGetState Dec 01 16:31:53 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 01 16:31:53 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 01 16:31:53 volumio volumio[10094]: info: Received Get System Info Dec 01 16:31:53 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 01 16:31:53 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 01 16:31:53 volumio volumio[10094]: info: Discovery: Getting this device information Dec 01 16:31:53 volumio volumio[10094]: info: CoreCommandRouter::volumioGetState Dec 01 16:31:53 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 16:31:53 volumio volumio[10094]: info: CoreCommandRouter::volumioGetState Dec 01 16:31:53 volumio volumio[10094]: info: Listing playlists Dec 01 16:31:53 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 01 16:31:53 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 01 16:31:53 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 01 16:31:54 volumio volumio[10094]: info: CoreCommandRouter::volumioGetQueue Dec 01 16:31:54 volumio volumio[10094]: info: CoreStateMachine::getQueue Dec 01 16:31:54 volumio volumio[10094]: info: CorePlayQueue::getQueue Dec 01 16:31:55 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 01 16:31:55 volumio volumio[10094]: info: Received Get System Info Dec 01 16:31:55 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 01 16:31:55 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 01 16:31:55 volumio volumio[10094]: info: Discovery: Getting this device information Dec 01 16:31:55 volumio volumio[10094]: info: CoreCommandRouter::volumioGetState Dec 01 16:31:55 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 16:32:05 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 16:32:05 volumio volumio[10094]: info: CURURI: music-library Dec 01 16:32:05 volumio volumio[10094]: error: Failed LSINFO: null Dec 01 16:32:05 volumio volumio[10094]: info: Preload queue cleared Dec 01 16:32:21 volumio ntpd[10418]: Soliciting pool server 193.182.111.141 Dec 01 16:32:24 volumio ntpd[10418]: Soliciting pool server 108.59.2.24 Dec 01 16:32:25 volumio ntpd[10418]: Soliciting pool server 193.182.111.12 Dec 01 16:32:26 volumio ntpd[10418]: Soliciting pool server 162.159.200.1 Dec 01 16:32:26 volumio ntpd[10418]: Soliciting pool server 116.203.151.74 Dec 01 16:32:36 volumio volumio[10094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 16:32:36 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 01 16:32:36 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 01 16:32:36 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 01 16:32:36 volumio volumio[10094]: info: Received Get System Version Dec 01 16:32:36 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 16:32:46 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard Dec 01 16:32:46 volumio volumio[10094]: info: CoreCommandRouter::Reload Ui Dec 01 16:32:47 volumio sudo[10505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 01 16:32:47 volumio sudo[10505]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:32:47 volumio sudo[10505]: pam_unix(sudo:session): session closed for user root Dec 01 16:32:47 volumio sudo[10508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 01 16:32:47 volumio sudo[10508]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:32:47 volumio sudo[10508]: pam_unix(sudo:session): session closed for user root Dec 01 16:32:47 volumio volumio[10094]: verbose: New Socket.io Connection to 192.168.0.100 from 192.168.0.102 UA: Mozilla/5.0 (Linux; U; Android 14; zh-cn; 23049RAD8C Build/UKQ1.230804.001) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.119 Mobile Safari/537.36 XiaoMi/MiuiBrowser/18.7.921118 Total Clients: 4 Dec 01 16:32:48 volumio volumio[10094]: info: CoreCommandRouter::volumioGetVisibleSources Dec 01 16:32:48 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 16:32:48 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Dec 01 16:32:48 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Dec 01 16:32:48 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 01 16:32:48 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Dec 01 16:32:48 volumio volumio[10094]: info: Received Get System Info Dec 01 16:32:48 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 01 16:32:48 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 01 16:32:48 volumio volumio[10094]: info: Discovery: Getting this device information Dec 01 16:32:48 volumio volumio[10094]: info: CoreCommandRouter::volumioGetState Dec 01 16:32:48 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 16:32:48 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 01 16:32:48 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 01 16:32:48 volumio volumio[10094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 16:32:48 volumio volumio[10094]: info: Retrieving Cloud Streaming UI Dec 01 16:32:48 volumio volumio[10094]: info: Getting Tidal Cloud Configuration Dec 01 16:32:48 volumio volumio[10094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 16:32:48 volumio volumio[10094]: info: Getting Qobuz Cloud Configuration Dec 01 16:32:48 volumio volumio[10094]: info: Asking plugin for UI Config Dec 01 16:32:48 volumio volumio[10094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 16:32:48 volumio volumio[10094]: info: Getting Spotify Cloud Configuration Dec 01 16:32:48 volumio volumio[10094]: info: Asking plugin for UI Config Dec 01 16:32:48 volumio volumio[10094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 16:32:48 volumio volumio[10094]: info: Saving Spotify Acccount Dec 01 16:32:48 volumio volumio[10094]: info: Got Tidal Cloud Configuration Dec 01 16:32:48 volumio volumio[10094]: info: Got it Dec 01 16:32:48 volumio volumio[10094]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 01 16:32:48 volumio volumio[10094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 16:32:48 volumio volumio[10094]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 01 16:32:48 volumio volumio[10094]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 5 Dec 01 16:32:49 volumio volumio[10094]: info: CoreCommandRouter::volumioGetVisibleSources Dec 01 16:32:49 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 16:32:49 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Dec 01 16:32:49 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Dec 01 16:32:49 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 01 16:32:49 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Dec 01 16:32:49 volumio volumio[10094]: info: Received Get System Info Dec 01 16:32:49 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 01 16:32:49 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 01 16:32:49 volumio volumio[10094]: info: Discovery: Getting this device information Dec 01 16:32:49 volumio volumio[10094]: info: CoreCommandRouter::volumioGetState Dec 01 16:32:49 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 16:32:49 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 01 16:32:49 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 01 16:32:49 volumio volumio[10094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 16:32:49 volumio volumio[10094]: info: Retrieving Cloud Streaming UI Dec 01 16:32:49 volumio volumio[10094]: info: Getting Tidal Cloud Configuration Dec 01 16:32:49 volumio volumio[10094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 16:32:49 volumio volumio[10094]: info: Getting Qobuz Cloud Configuration Dec 01 16:32:49 volumio volumio[10094]: info: Asking plugin for UI Config Dec 01 16:32:49 volumio volumio[10094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 16:32:49 volumio volumio[10094]: info: Getting Spotify Cloud Configuration Dec 01 16:32:49 volumio volumio[10094]: info: Asking plugin for UI Config Dec 01 16:32:49 volumio volumio[10094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 16:32:49 volumio volumio[10094]: info: Saving Spotify Acccount Dec 01 16:32:49 volumio volumio[10094]: info: Got Tidal Cloud Configuration Dec 01 16:32:49 volumio volumio[10094]: info: Got it Dec 01 16:32:49 volumio volumio[10094]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 01 16:32:49 volumio volumio[10094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 16:32:49 volumio volumio[10094]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 01 16:32:49 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 01 16:32:49 volumio volumio[10094]: info: Loading i18n strings for locale zh Dec 01 16:32:49 volumio volumio[10094]: Updating browse sources language Dec 01 16:32:49 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 16:32:49 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 01 16:32:49 volumio volumio[10094]: info: Setting timezone to Asia/Shanghai Dec 01 16:32:49 volumio sudo[10520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 01 16:32:49 volumio sudo[10520]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:32:49 volumio sudo[10520]: pam_unix(sudo:session): session closed for user root Dec 01 16:32:49 volumio sudo[10523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Shanghai /etc/localtime Dec 01 16:32:49 volumio sudo[10523]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:32:49 volumio sudo[10523]: pam_unix(sudo:session): session closed for user root Dec 01 16:32:49 volumio sudo[10526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 01 16:32:49 volumio sudo[10526]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:32:49 volumio sudo[10526]: pam_unix(sudo:session): session closed for user root Dec 01 16:32:49 volumio sudo[10529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Shanghai Dec 01 16:32:49 volumio sudo[10529]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:32:49 volumio dbus-daemon[492]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.77' (uid=0 pid=10530 comm="/usr/bin/timedatectl set-timezone Asia/Shanghai ") Dec 01 16:32:49 volumio systemd[1]: Starting Time & Date Service... Dec 01 16:32:49 volumio dbus-daemon[492]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 01 16:32:49 volumio systemd[1]: Started Time & Date Service. Dec 01 16:32:49 volumio sudo[10529]: pam_unix(sudo:session): session closed for user root Dec 01 16:32:49 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 01 16:32:49 volumio volumio[10094]: info: Fetching Streaming Services browse cache Dec 01 16:32:50 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 01 16:32:50 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 01 16:32:50 volumio volumio[10094]: info: Loading i18n strings for locale en Dec 01 16:32:50 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 01 16:32:50 volumio volumio[10094]: info: Setting timezone to Asia/Shanghai Dec 01 16:32:50 volumio sudo[10533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 01 16:32:50 volumio sudo[10533]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:32:50 volumio sudo[10533]: pam_unix(sudo:session): session closed for user root Dec 01 16:32:50 volumio sudo[10536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Shanghai /etc/localtime Dec 01 16:32:50 volumio sudo[10536]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:32:50 volumio sudo[10536]: pam_unix(sudo:session): session closed for user root Dec 01 16:32:50 volumio sudo[10539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 01 16:32:50 volumio sudo[10539]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:32:50 volumio sudo[10539]: pam_unix(sudo:session): session closed for user root Dec 01 16:32:50 volumio sudo[10542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Shanghai Dec 01 16:32:50 volumio sudo[10542]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:32:50 volumio sudo[10542]: pam_unix(sudo:session): session closed for user root Dec 01 16:32:50 volumio volumio[10094]: Updating browse sources language Dec 01 16:32:50 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 16:32:50 volumio volumio[10094]: info: Fetching Streaming Services browse cache Dec 01 16:32:53 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 01 16:32:53 volumio volumio[10094]: info: Loading i18n strings for locale zh Dec 01 16:32:53 volumio volumio[10094]: Updating browse sources language Dec 01 16:32:53 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 16:32:53 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 01 16:32:53 volumio volumio[10094]: info: Setting timezone to Asia/Shanghai Dec 01 16:32:53 volumio sudo[10546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 01 16:32:53 volumio sudo[10546]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:32:53 volumio sudo[10546]: pam_unix(sudo:session): session closed for user root Dec 01 16:32:53 volumio sudo[10549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Shanghai /etc/localtime Dec 01 16:32:53 volumio sudo[10549]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:32:53 volumio sudo[10549]: pam_unix(sudo:session): session closed for user root Dec 01 16:32:53 volumio sudo[10552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 01 16:32:53 volumio sudo[10552]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:32:53 volumio sudo[10552]: pam_unix(sudo:session): session closed for user root Dec 01 16:32:53 volumio sudo[10555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Shanghai Dec 01 16:32:53 volumio sudo[10555]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:32:53 volumio sudo[10555]: pam_unix(sudo:session): session closed for user root Dec 01 16:32:53 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 01 16:32:53 volumio volumio[10094]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 01 16:32:54 volumio sudo[10558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 01 16:32:54 volumio sudo[10558]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:32:54 volumio sudo[10558]: pam_unix(sudo:session): session closed for user root Dec 01 16:32:54 volumio volumio[10094]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 01 16:32:54 volumio volumio[10094]: wlan0 Interface doesn't support scanning. Dec 01 16:32:54 volumio volumio[10094]: info: Cannot use regular scanning, forcing with ap-force Dec 01 16:32:54 volumio sudo[10563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 01 16:32:54 volumio sudo[10563]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:32:54 volumio sudo[10563]: pam_unix(sudo:session): session closed for user root Dec 01 16:32:54 volumio volumio[10094]: command failed: No such device (-19) Dec 01 16:32:54 volumio volumio[10094]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 01 16:32:54 volumio volumio[10094]: command failed: No such device (-19) Dec 01 16:32:54 volumio volumio[10094]: info: Fetching Streaming Services browse cache Dec 01 16:32:56 volumio volumio[10094]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 16:32:56 volumio volumio[10094]: Error: connect ETIMEDOUT 108.160.165.141:80 Dec 01 16:32:56 volumio volumio[10094]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Dec 01 16:32:56 volumio volumio[10094]: errno: -110, Dec 01 16:32:56 volumio volumio[10094]: code: 'ETIMEDOUT', Dec 01 16:32:56 volumio volumio[10094]: syscall: 'connect', Dec 01 16:32:56 volumio volumio[10094]: address: '108.160.165.141', Dec 01 16:32:56 volumio volumio[10094]: port: 80 Dec 01 16:32:56 volumio volumio[10094]: } Dec 01 16:32:56 volumio volumio[10094]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 16:32:56 volumio sudo[10576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-01 16:31 Dec 01 16:32:56 volumio sudo[10576]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"