-- Logs begin at Mon 2024-09-09 13:56:02 +07, end at Mon 2024-09-09 14:04:03 +07. -- Sep 09 14:03:02 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 09 14:03:02 volumio volumio[1106]: info: Preload queue cleared Sep 09 14:03:04 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 09 14:03:04 volumio volumio[1106]: info: TuneIn handleBrowseUri: tunein/location Sep 09 14:03:05 volumio volumio[1106]: info: Preload queue cleared Sep 09 14:03:09 volumio volumio[1106]: Searching plugin music_service/webradio Sep 09 14:03:09 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: webradio , search Sep 09 14:03:10 volumio volumio[1106]: info: All search sources collected, pushing search results Sep 09 14:03:11 volumio ntpd[796]: Soliciting pool server 103.184.124.23 Sep 09 14:03:12 volumio ntpd[796]: Soliciting pool server 2400:6760:0:3::23 Sep 09 14:03:18 volumio ntpd[796]: Soliciting pool server 103.184.124.23 Sep 09 14:03:19 volumio sudo[1735]: pam_unix(sudo:session): session closed for user root Sep 09 14:03:19 volumio volumio[1106]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Sep 09 14:03:21 volumio ntpd[796]: Soliciting pool server 103.184.124.23 Sep 09 14:03:27 volumio volumio[1106]: info: Preload queue cleared Sep 09 14:03:27 volumio volumio[1106]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 09 14:03:27 volumio volumio[1106]: info: CoreStateMachine::ClearQueue Sep 09 14:03:27 volumio volumio[1106]: info: CoreStateMachine::stop Sep 09 14:03:27 volumio volumio[1106]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 14:03:27 volumio volumio[1106]: info: CorePlayQueue::clearPlayQueue Sep 09 14:03:27 volumio volumio[1106]: info: CorePlayQueue::saveQueue Sep 09 14:03:27 volumio volumio[1106]: info: CoreCommandRouter::volumioPushQueue Sep 09 14:03:27 volumio volumio[1106]: info: CoreStateMachine::addQueueItems Sep 09 14:03:27 volumio volumio[1106]: info: CorePlayQueue::addQueueItems Sep 09 14:03:27 volumio volumio[1106]: info: Preload queue cleared Sep 09 14:03:27 volumio volumio[1106]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99600570 Sep 09 14:03:27 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 09 14:03:27 volumio volumio[1106]: info: CoreCommandRouter::volumioPushQueue Sep 09 14:03:27 volumio volumio[1106]: info: CorePlayQueue::saveQueue Sep 09 14:03:27 volumio volumio[1106]: info: CoreStateMachine::updateTrackBlock Sep 09 14:03:27 volumio volumio[1106]: info: CorePlayQueue::getTrackBlock Sep 09 14:03:27 volumio volumio[1106]: info: CoreCommandRouter::volumioPlay Sep 09 14:03:27 volumio volumio[1106]: info: CoreStateMachine::play index 0 Sep 09 14:03:27 volumio volumio[1106]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 14:03:27 volumio volumio[1106]: info: CoreStateMachine::stop Sep 09 14:03:27 volumio volumio[1106]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 14:03:27 volumio volumio[1106]: info: CoreStateMachine::play index undefined Sep 09 14:03:27 volumio volumio[1106]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 14:03:27 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:27 volumio volumio[1106]: info: CoreStateMachine::startPlaybackTimer Sep 09 14:03:27 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:27 volumio volumio[1106]: info: [1725865407841] ControllerWebradio::clearAddPlayTrack Sep 09 14:03:27 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand stop Sep 09 14:03:27 volumio volumio[1106]: info: sendMpdCommand stop took 1 milliseconds Sep 09 14:03:27 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand clear Sep 09 14:03:27 volumio volumio[1106]: info: Sep 09 14:03:27 volumio volumio[1106]: ---------------------------- MPD announces system playlist update Sep 09 14:03:27 volumio volumio[1106]: info: Ignoring MPD Status Update Sep 09 14:03:27 volumio volumio[1106]: info: sendMpdCommand clear took 0 milliseconds Sep 09 14:03:27 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99600570" Sep 09 14:03:27 volumio volumio[1106]: info: Sep 09 14:03:27 volumio volumio[1106]: ---------------------------- MPD announces system playlist update Sep 09 14:03:27 volumio volumio[1106]: info: Ignoring MPD Status Update Sep 09 14:03:27 volumio volumio[1106]: error: updateQueue error: null Sep 09 14:03:27 volumio volumio[1106]: info: ------------------------------ 2ms Sep 09 14:03:28 volumio volumio[1106]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99600570" took 267 milliseconds Sep 09 14:03:28 volumio volumio[1106]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 09 14:03:28 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand play Sep 09 14:03:28 volumio volumio[1106]: info: Sep 09 14:03:28 volumio volumio[1106]: ---------------------------- MPD announces system playlist update Sep 09 14:03:28 volumio volumio[1106]: info: Ignoring MPD Status Update Sep 09 14:03:28 volumio volumio[1106]: info: ------------------------------ 268ms Sep 09 14:03:28 volumio volumio[1106]: info: sendMpdCommand play took 1 milliseconds Sep 09 14:03:28 volumio volumio[1106]: info: Sep 09 14:03:28 volumio volumio[1106]: ---------------------------- MPD announces system playlist update Sep 09 14:03:28 volumio volumio[1106]: info: Ignoring MPD Status Update Sep 09 14:03:28 volumio volumio[1106]: info: ------------------------------ 3ms Sep 09 14:03:28 volumio volumio[1106]: info: ------------------------------ 1ms Sep 09 14:03:30 volumio volumio[1106]: info: Sep 09 14:03:30 volumio volumio[1106]: ---------------------------- MPD announces state update: player Sep 09 14:03:30 volumio volumio[1106]: info: ControllerMpd::getState Sep 09 14:03:30 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand status Sep 09 14:03:30 volumio volumio[1106]: info: Sep 09 14:03:30 volumio volumio[1106]: ---------------------------- MPD announces state update: player Sep 09 14:03:30 volumio volumio[1106]: info: ControllerMpd::getState Sep 09 14:03:30 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand status Sep 09 14:03:30 volumio volumio[1106]: info: Sep 09 14:03:30 volumio volumio[1106]: ---------------------------- MPD announces state update: player Sep 09 14:03:30 volumio volumio[1106]: info: sendMpdCommand status took 2 milliseconds Sep 09 14:03:30 volumio volumio[1106]: info: ControllerMpd::getState Sep 09 14:03:30 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand status Sep 09 14:03:30 volumio volumio[1106]: info: sendMpdCommand status took 1 milliseconds Sep 09 14:03:30 volumio volumio[1106]: verbose: ControllerMpd::parseState Sep 09 14:03:30 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 14:03:30 volumio volumio[1106]: verbose: ControllerMpd::parseState Sep 09 14:03:30 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 14:03:30 volumio volumio[1106]: info: Sep 09 14:03:30 volumio volumio[1106]: ---------------------------- MPD announces state update: player Sep 09 14:03:30 volumio volumio[1106]: info: ControllerMpd::getState Sep 09 14:03:30 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand status Sep 09 14:03:30 volumio volumio[1106]: info: sendMpdCommand status took 4 milliseconds Sep 09 14:03:30 volumio volumio[1106]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 09 14:03:30 volumio volumio[1106]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 09 14:03:30 volumio volumio[1106]: info: sendMpdCommand status took 1 milliseconds Sep 09 14:03:30 volumio volumio[1106]: verbose: ControllerMpd::parseState Sep 09 14:03:30 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 14:03:30 volumio volumio[1106]: verbose: ControllerMpd::parseTrackInfo Sep 09 14:03:30 volumio volumio[1106]: verbose: ControllerMpd::parseTrackInfo Sep 09 14:03:30 volumio volumio[1106]: verbose: ControllerMpd::parseState Sep 09 14:03:30 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 14:03:30 volumio volumio[1106]: info: ControllerMpd::pushState Sep 09 14:03:30 volumio volumio[1106]: info: CoreCommandRouter::servicePushState Sep 09 14:03:30 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:30 volumio volumio[1106]: 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 - 0/0) V100 Vietnamese Radio","album":null,"uri":"https://sonic.radiostream.ca:8022/stream","trackType":""} Sep 09 14:03:30 volumio volumio[1106]: verbose: CURRENT POSITION 0 Sep 09 14:03:30 volumio volumio[1106]: info: CoreStateMachine::syncState stateService play Sep 09 14:03:30 volumio volumio[1106]: info: CoreStateMachine::syncState currentStatus stop Sep 09 14:03:30 volumio volumio[1106]: info: ControllerMpd::pushState Sep 09 14:03:30 volumio volumio[1106]: info: CoreCommandRouter::servicePushState Sep 09 14:03:30 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:30 volumio volumio[1106]: 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 - 0/0) V100 Vietnamese Radio","album":null,"uri":"https://sonic.radiostream.ca:8022/stream","trackType":""} Sep 09 14:03:30 volumio volumio[1106]: verbose: CURRENT POSITION 0 Sep 09 14:03:30 volumio volumio[1106]: info: CoreStateMachine::syncState stateService play Sep 09 14:03:30 volumio volumio[1106]: info: CoreStateMachine::syncState currentStatus play Sep 09 14:03:30 volumio volumio[1106]: info: Received an update from plugin. extracting info from payload Sep 09 14:03:30 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 09 14:03:30 volumio volumio[1106]: info: CoreStateMachine::pushState Sep 09 14:03:30 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:30 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 14:03:30 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:30 volumio volumio[1106]: info: CoreStateMachine::pushState Sep 09 14:03:30 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:30 volumio volumio[1106]: info: ------------------------------ 17ms Sep 09 14:03:30 volumio volumio[1106]: info: ------------------------------ 24ms Sep 09 14:03:30 volumio volumio[1106]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 09 14:03:30 volumio volumio[1106]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 09 14:03:30 volumio volumio[1106]: verbose: ControllerMpd::parseTrackInfo Sep 09 14:03:30 volumio volumio[1106]: verbose: ControllerMpd::parseTrackInfo Sep 09 14:03:30 volumio volumio[1106]: info: ControllerMpd::pushState Sep 09 14:03:30 volumio volumio[1106]: info: CoreCommandRouter::servicePushState Sep 09 14:03:30 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:30 volumio volumio[1106]: 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 - 0/0) V100 Vietnamese Radio","album":null,"uri":"https://sonic.radiostream.ca:8022/stream","trackType":""} Sep 09 14:03:30 volumio volumio[1106]: verbose: CURRENT POSITION 0 Sep 09 14:03:30 volumio volumio[1106]: info: CoreStateMachine::syncState stateService play Sep 09 14:03:30 volumio volumio[1106]: info: CoreStateMachine::syncState currentStatus play Sep 09 14:03:30 volumio volumio[1106]: info: Received an update from plugin. extracting info from payload Sep 09 14:03:30 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 09 14:03:30 volumio volumio[1106]: info: CoreStateMachine::pushState Sep 09 14:03:30 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:30 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:30 volumio volumio[1106]: info: CoreStateMachine::pushState Sep 09 14:03:30 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:30 volumio volumio[1106]: info: ControllerMpd::pushState Sep 09 14:03:30 volumio volumio[1106]: info: CoreCommandRouter::servicePushState Sep 09 14:03:30 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:30 volumio volumio[1106]: 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 - 0/0) V100 Vietnamese Radio","album":null,"uri":"https://sonic.radiostream.ca:8022/stream","trackType":""} Sep 09 14:03:30 volumio volumio[1106]: verbose: CURRENT POSITION 0 Sep 09 14:03:30 volumio volumio[1106]: info: CoreStateMachine::syncState stateService play Sep 09 14:03:30 volumio volumio[1106]: info: CoreStateMachine::syncState currentStatus play Sep 09 14:03:30 volumio volumio[1106]: info: Received an update from plugin. extracting info from payload Sep 09 14:03:30 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 09 14:03:30 volumio volumio[1106]: info: CoreStateMachine::pushState Sep 09 14:03:30 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:30 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:30 volumio volumio[1106]: info: CoreStateMachine::pushState Sep 09 14:03:30 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:30 volumio volumio[1106]: info: ------------------------------ 41ms Sep 09 14:03:30 volumio volumio[1106]: info: ------------------------------ 37ms Sep 09 14:03:31 volumio volumio[1106]: info: Sep 09 14:03:31 volumio volumio[1106]: ---------------------------- MPD announces system playlist update Sep 09 14:03:31 volumio volumio[1106]: info: Ignoring MPD Status Update Sep 09 14:03:31 volumio volumio[1106]: info: Sep 09 14:03:31 volumio volumio[1106]: ---------------------------- MPD announces state update: player Sep 09 14:03:31 volumio volumio[1106]: info: ControllerMpd::getState Sep 09 14:03:31 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand status Sep 09 14:03:31 volumio volumio[1106]: info: Sep 09 14:03:31 volumio volumio[1106]: ---------------------------- MPD announces system playlist update Sep 09 14:03:31 volumio volumio[1106]: info: Ignoring MPD Status Update Sep 09 14:03:31 volumio volumio[1106]: info: Sep 09 14:03:31 volumio volumio[1106]: ---------------------------- MPD announces state update: player Sep 09 14:03:31 volumio volumio[1106]: info: ControllerMpd::getState Sep 09 14:03:31 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand status Sep 09 14:03:31 volumio volumio[1106]: info: Sep 09 14:03:31 volumio volumio[1106]: ---------------------------- MPD announces system playlist update Sep 09 14:03:31 volumio volumio[1106]: info: Ignoring MPD Status Update Sep 09 14:03:31 volumio volumio[1106]: info: Sep 09 14:03:31 volumio volumio[1106]: ---------------------------- MPD announces state update: player Sep 09 14:03:31 volumio volumio[1106]: info: ControllerMpd::getState Sep 09 14:03:31 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand status Sep 09 14:03:31 volumio volumio[1106]: info: ------------------------------ 5ms Sep 09 14:03:31 volumio volumio[1106]: info: sendMpdCommand status took 5 milliseconds Sep 09 14:03:31 volumio volumio[1106]: info: ------------------------------ 4ms Sep 09 14:03:31 volumio volumio[1106]: info: sendMpdCommand status took 3 milliseconds Sep 09 14:03:31 volumio volumio[1106]: verbose: ControllerMpd::parseState Sep 09 14:03:31 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 14:03:31 volumio volumio[1106]: verbose: ControllerMpd::parseState Sep 09 14:03:31 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 14:03:31 volumio volumio[1106]: info: Sep 09 14:03:31 volumio volumio[1106]: ---------------------------- MPD announces system playlist update Sep 09 14:03:31 volumio volumio[1106]: info: Ignoring MPD Status Update Sep 09 14:03:31 volumio volumio[1106]: info: Sep 09 14:03:31 volumio volumio[1106]: ---------------------------- MPD announces state update: player Sep 09 14:03:31 volumio volumio[1106]: info: ControllerMpd::getState Sep 09 14:03:31 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand status Sep 09 14:03:31 volumio volumio[1106]: info: ------------------------------ 6ms Sep 09 14:03:31 volumio volumio[1106]: info: sendMpdCommand status took 5 milliseconds Sep 09 14:03:31 volumio volumio[1106]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 09 14:03:31 volumio volumio[1106]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 09 14:03:31 volumio volumio[1106]: info: ------------------------------ 3ms Sep 09 14:03:31 volumio volumio[1106]: info: sendMpdCommand status took 2 milliseconds Sep 09 14:03:31 volumio volumio[1106]: verbose: ControllerMpd::parseState Sep 09 14:03:31 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 14:03:31 volumio volumio[1106]: verbose: ControllerMpd::parseTrackInfo Sep 09 14:03:31 volumio volumio[1106]: verbose: ControllerMpd::parseTrackInfo Sep 09 14:03:31 volumio volumio[1106]: verbose: ControllerMpd::parseState Sep 09 14:03:31 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 14:03:31 volumio volumio[1106]: info: ControllerMpd::pushState Sep 09 14:03:31 volumio volumio[1106]: info: CoreCommandRouter::servicePushState Sep 09 14:03:31 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:31 volumio volumio[1106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"ANH TRAI \"SAY HI\", HIEUTHUHAI, ERIK, Anh Tú Atus, JSOL, Orange - NGÁO NGƠ (feat. HIEUTHUHAI, ERIK, Anh Tú Atus, JSOL & Orange)","artist":"V100 Vietnamese Radio","album":null,"uri":"https://sonic.radiostream.ca:8022/stream","trackType":""} Sep 09 14:03:31 volumio volumio[1106]: verbose: CURRENT POSITION 0 Sep 09 14:03:31 volumio volumio[1106]: info: CoreStateMachine::syncState stateService play Sep 09 14:03:31 volumio volumio[1106]: info: CoreStateMachine::syncState currentStatus play Sep 09 14:03:31 volumio volumio[1106]: info: Received an update from plugin. extracting info from payload Sep 09 14:03:31 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 09 14:03:31 volumio volumio[1106]: info: CoreStateMachine::pushState Sep 09 14:03:31 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 14:03:31 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:31 volumio volumio[1106]: info: CoreStateMachine::pushState Sep 09 14:03:31 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:31 volumio volumio[1106]: info: ControllerMpd::pushState Sep 09 14:03:31 volumio volumio[1106]: info: CoreCommandRouter::servicePushState Sep 09 14:03:31 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:31 volumio volumio[1106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"ANH TRAI \"SAY HI\", HIEUTHUHAI, ERIK, Anh Tú Atus, JSOL, Orange - NGÁO NGƠ (feat. HIEUTHUHAI, ERIK, Anh Tú Atus, JSOL & Orange)","artist":"V100 Vietnamese Radio","album":null,"uri":"https://sonic.radiostream.ca:8022/stream","trackType":""} Sep 09 14:03:31 volumio volumio[1106]: verbose: CURRENT POSITION 0 Sep 09 14:03:31 volumio volumio[1106]: info: CoreStateMachine::syncState stateService play Sep 09 14:03:31 volumio volumio[1106]: info: CoreStateMachine::syncState currentStatus play Sep 09 14:03:31 volumio volumio[1106]: info: Received an update from plugin. extracting info from payload Sep 09 14:03:31 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 09 14:03:31 volumio volumio[1106]: info: CoreStateMachine::pushState Sep 09 14:03:31 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:31 volumio volumio[1106]: info: CoreStateMachine::pushState Sep 09 14:03:31 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:31 volumio volumio[1106]: info: ------------------------------ 32ms Sep 09 14:03:31 volumio volumio[1106]: info: ------------------------------ 31ms Sep 09 14:03:31 volumio volumio[1106]: info: sendMpdCommand playlistinfo took 22 milliseconds Sep 09 14:03:31 volumio volumio[1106]: info: sendMpdCommand playlistinfo took 22 milliseconds Sep 09 14:03:31 volumio volumio[1106]: verbose: ControllerMpd::parseTrackInfo Sep 09 14:03:31 volumio volumio[1106]: verbose: ControllerMpd::parseTrackInfo Sep 09 14:03:31 volumio volumio[1106]: info: ControllerMpd::pushState Sep 09 14:03:31 volumio volumio[1106]: info: CoreCommandRouter::servicePushState Sep 09 14:03:31 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:31 volumio volumio[1106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"ANH TRAI \"SAY HI\", HIEUTHUHAI, ERIK, Anh Tú Atus, JSOL, Orange - NGÁO NGƠ (feat. HIEUTHUHAI, ERIK, Anh Tú Atus, JSOL & Orange)","artist":"V100 Vietnamese Radio","album":null,"uri":"https://sonic.radiostream.ca:8022/stream","trackType":""} Sep 09 14:03:31 volumio volumio[1106]: verbose: CURRENT POSITION 0 Sep 09 14:03:31 volumio volumio[1106]: info: CoreStateMachine::syncState stateService play Sep 09 14:03:31 volumio volumio[1106]: info: CoreStateMachine::syncState currentStatus play Sep 09 14:03:31 volumio volumio[1106]: info: Received an update from plugin. extracting info from payload Sep 09 14:03:31 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 09 14:03:31 volumio volumio[1106]: info: CoreStateMachine::pushState Sep 09 14:03:31 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:31 volumio volumio[1106]: info: CoreStateMachine::pushState Sep 09 14:03:31 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:31 volumio volumio[1106]: info: ControllerMpd::pushState Sep 09 14:03:31 volumio volumio[1106]: info: CoreCommandRouter::servicePushState Sep 09 14:03:31 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:31 volumio volumio[1106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"ANH TRAI \"SAY HI\", HIEUTHUHAI, ERIK, Anh Tú Atus, JSOL, Orange - NGÁO NGƠ (feat. HIEUTHUHAI, ERIK, Anh Tú Atus, JSOL & Orange)","artist":"V100 Vietnamese Radio","album":null,"uri":"https://sonic.radiostream.ca:8022/stream","trackType":""} Sep 09 14:03:31 volumio volumio[1106]: verbose: CURRENT POSITION 0 Sep 09 14:03:31 volumio volumio[1106]: info: CoreStateMachine::syncState stateService play Sep 09 14:03:31 volumio volumio[1106]: info: CoreStateMachine::syncState currentStatus play Sep 09 14:03:31 volumio volumio[1106]: info: Received an update from plugin. extracting info from payload Sep 09 14:03:31 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 09 14:03:31 volumio volumio[1106]: info: CoreStateMachine::pushState Sep 09 14:03:31 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:31 volumio volumio[1106]: info: CoreStateMachine::pushState Sep 09 14:03:31 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:31 volumio volumio[1106]: info: ------------------------------ 47ms Sep 09 14:03:31 volumio volumio[1106]: info: ------------------------------ 43ms Sep 09 14:03:40 volumio volumio[1106]: info: Preload queue cleared Sep 09 14:03:40 volumio volumio[1106]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 09 14:03:40 volumio volumio[1106]: info: CoreStateMachine::ClearQueue Sep 09 14:03:40 volumio volumio[1106]: info: CoreStateMachine::stop Sep 09 14:03:40 volumio volumio[1106]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 14:03:40 volumio volumio[1106]: info: CoreStateMachine::stPlaybackTimer Sep 09 14:03:40 volumio volumio[1106]: info: CoreStateMachine::updateTrackBlock Sep 09 14:03:40 volumio volumio[1106]: info: CorePlayQueue::getTrackBlock Sep 09 14:03:40 volumio volumio[1106]: info: CoreStateMachine::pushState Sep 09 14:03:40 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:40 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 14:03:40 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:40 volumio volumio[1106]: info: CoreStateMachine::serviceStop Sep 09 14:03:40 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:40 volumio volumio[1106]: info: CoreCommandRouter::serviceStop Sep 09 14:03:40 volumio volumio[1106]: info: [1725865420423] ControllerWebradio::stop Sep 09 14:03:40 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand stop Sep 09 14:03:40 volumio volumio[1106]: info: CorePlayQueue::clearPlayQueue Sep 09 14:03:40 volumio volumio[1106]: info: CorePlayQueue::saveQueue Sep 09 14:03:40 volumio volumio[1106]: info: CoreCommandRouter::volumioPushQueue Sep 09 14:03:40 volumio volumio[1106]: info: CoreStateMachine::addQueueItems Sep 09 14:03:40 volumio volumio[1106]: info: CorePlayQueue::addQueueItems Sep 09 14:03:40 volumio volumio[1106]: info: Preload queue cleared Sep 09 14:03:40 volumio volumio[1106]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99305976 Sep 09 14:03:40 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 09 14:03:40 volumio volumio[1106]: info: CoreCommandRouter::volumioPushQueue Sep 09 14:03:40 volumio volumio[1106]: info: CorePlayQueue::saveQueue Sep 09 14:03:40 volumio volumio[1106]: info: CoreStateMachine::updateTrackBlock Sep 09 14:03:40 volumio volumio[1106]: info: CorePlayQueue::getTrackBlock Sep 09 14:03:40 volumio volumio[1106]: info: CoreCommandRouter::volumioPlay Sep 09 14:03:40 volumio volumio[1106]: info: CoreStateMachine::play index 0 Sep 09 14:03:40 volumio volumio[1106]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 14:03:40 volumio volumio[1106]: info: CoreStateMachine::stop Sep 09 14:03:40 volumio volumio[1106]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 14:03:40 volumio volumio[1106]: info: CoreStateMachine::play index undefined Sep 09 14:03:40 volumio volumio[1106]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 14:03:40 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:40 volumio volumio[1106]: info: CoreStateMachine::startPlaybackTimer Sep 09 14:03:40 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:40 volumio volumio[1106]: info: [1725865420437] ControllerWebradio::clearAddPlayTrack Sep 09 14:03:40 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand stop Sep 09 14:03:40 volumio volumio[1106]: info: Sep 09 14:03:40 volumio volumio[1106]: ---------------------------- MPD announces state update: player Sep 09 14:03:40 volumio volumio[1106]: info: sendMpdCommand stop took 30 milliseconds Sep 09 14:03:40 volumio volumio[1106]: info: ControllerMpd::getState Sep 09 14:03:40 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand status Sep 09 14:03:40 volumio volumio[1106]: info: sendMpdCommand stop took 16 milliseconds Sep 09 14:03:40 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand clear Sep 09 14:03:40 volumio volumio[1106]: info: Sep 09 14:03:40 volumio volumio[1106]: ---------------------------- MPD announces state update: player Sep 09 14:03:40 volumio volumio[1106]: info: ControllerMpd::getState Sep 09 14:03:40 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand status Sep 09 14:03:40 volumio volumio[1106]: info: Sep 09 14:03:40 volumio volumio[1106]: ---------------------------- MPD announces system playlist update Sep 09 14:03:40 volumio volumio[1106]: info: Ignoring MPD Status Update Sep 09 14:03:40 volumio volumio[1106]: info: sendMpdCommand status took 3 milliseconds Sep 09 14:03:40 volumio volumio[1106]: info: sendMpdCommand clear took 2 milliseconds Sep 09 14:03:40 volumio volumio[1106]: info: sendMpdCommand status took 1 milliseconds Sep 09 14:03:40 volumio volumio[1106]: verbose: ControllerMpd::parseState Sep 09 14:03:40 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 14:03:40 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99305976" Sep 09 14:03:40 volumio volumio[1106]: verbose: ControllerMpd::parseState Sep 09 14:03:40 volumio volumio[1106]: info: ControllerMpd::pushState Sep 09 14:03:40 volumio volumio[1106]: info: CoreCommandRouter::servicePushState Sep 09 14:03:40 volumio volumio[1106]: info: CoreStateMachine::pushState Sep 09 14:03:40 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:40 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 14:03:40 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:40 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:40 volumio volumio[1106]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Sep 09 14:03:40 volumio volumio[1106]: info: ------------------------------ 10ms Sep 09 14:03:40 volumio volumio[1106]: info: Sep 09 14:03:40 volumio volumio[1106]: ---------------------------- MPD announces system playlist update Sep 09 14:03:40 volumio volumio[1106]: info: Ignoring MPD Status Update Sep 09 14:03:40 volumio volumio[1106]: error: updateQueue error: null Sep 09 14:03:40 volumio volumio[1106]: info: ------------------------------ 10ms Sep 09 14:03:40 volumio volumio[1106]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 09 14:03:40 volumio volumio[1106]: verbose: ControllerMpd::parseTrackInfo Sep 09 14:03:40 volumio volumio[1106]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 09 14:03:40 volumio volumio[1106]: info: ------------------------------ 14ms Sep 09 14:03:40 volumio volumio[1106]: info: Sep 09 14:03:40 volumio volumio[1106]: ---------------------------- MPD announces system playlist update Sep 09 14:03:40 volumio volumio[1106]: info: Ignoring MPD Status Update Sep 09 14:03:40 volumio volumio[1106]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99305976" took 271 milliseconds Sep 09 14:03:40 volumio volumio[1106]: info: ------------------------------ 264ms Sep 09 14:03:40 volumio volumio[1106]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 09 14:03:40 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand play Sep 09 14:03:40 volumio volumio[1106]: info: Sep 09 14:03:40 volumio volumio[1106]: ---------------------------- MPD announces system playlist update Sep 09 14:03:40 volumio volumio[1106]: info: Ignoring MPD Status Update Sep 09 14:03:40 volumio volumio[1106]: info: ------------------------------ 3ms Sep 09 14:03:40 volumio volumio[1106]: info: sendMpdCommand play took 2 milliseconds Sep 09 14:03:40 volumio volumio[1106]: info: ------------------------------ 2ms Sep 09 14:03:41 volumio volumio[1106]: info: Sep 09 14:03:41 volumio volumio[1106]: ---------------------------- MPD announces state update: player Sep 09 14:03:41 volumio volumio[1106]: info: ControllerMpd::getState Sep 09 14:03:41 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand status Sep 09 14:03:41 volumio volumio[1106]: info: Sep 09 14:03:41 volumio volumio[1106]: ---------------------------- MPD announces state update: player Sep 09 14:03:41 volumio volumio[1106]: info: ControllerMpd::getState Sep 09 14:03:41 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand status Sep 09 14:03:41 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 09 14:03:41 volumio volumio[1106]: info: sendMpdCommand status took 6 milliseconds Sep 09 14:03:41 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 09 14:03:41 volumio volumio[1106]: info: sendMpdCommand status took 4 milliseconds Sep 09 14:03:41 volumio volumio[1106]: verbose: ControllerMpd::parseState Sep 09 14:03:41 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 14:03:41 volumio volumio[1106]: verbose: ControllerMpd::parseState Sep 09 14:03:41 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 14:03:41 volumio volumio[1106]: info: sendMpdCommand clearerror took 4 milliseconds Sep 09 14:03:41 volumio volumio[1106]: info: sendMpdCommand clearerror took 4 milliseconds Sep 09 14:03:41 volumio volumio[1106]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 09 14:03:41 volumio volumio[1106]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 09 14:03:41 volumio volumio[1106]: verbose: ControllerMpd::parseTrackInfo Sep 09 14:03:41 volumio volumio[1106]: verbose: ControllerMpd::parseTrackInfo Sep 09 14:03:41 volumio volumio[1106]: info: ControllerMpd::pushState Sep 09 14:03:41 volumio volumio[1106]: info: CoreCommandRouter::servicePushState Sep 09 14:03:41 volumio volumio[1106]: info: CoreStateMachine::pushState Sep 09 14:03:41 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 14:03:41 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:41 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:41 volumio volumio[1106]: 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":"vietstation","artist":"Viet Station","album":null,"uri":"http://listen.shoutcast.com/vietstation","trackType":""} Sep 09 14:03:41 volumio volumio[1106]: verbose: CURRENT POSITION 0 Sep 09 14:03:41 volumio volumio[1106]: info: CoreStateMachine::syncState stateService stop Sep 09 14:03:41 volumio volumio[1106]: info: CoreStateMachine::syncState currentStatus stop Sep 09 14:03:41 volumio volumio[1106]: info: CoreStateMachine::pushState Sep 09 14:03:41 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:41 volumio volumio[1106]: info: No code Sep 09 14:03:41 volumio volumio[1106]: info: CoreStateMachine::pushState Sep 09 14:03:41 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:41 volumio volumio[1106]: info: ControllerMpd::pushState Sep 09 14:03:41 volumio volumio[1106]: info: CoreCommandRouter::servicePushState Sep 09 14:03:41 volumio volumio[1106]: info: CoreStateMachine::pushState Sep 09 14:03:41 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:41 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:41 volumio volumio[1106]: 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":"vietstation","artist":"Viet Station","album":null,"uri":"http://listen.shoutcast.com/vietstation","trackType":""} Sep 09 14:03:41 volumio volumio[1106]: verbose: CURRENT POSITION 0 Sep 09 14:03:41 volumio volumio[1106]: info: CoreStateMachine::syncState stateService stop Sep 09 14:03:41 volumio volumio[1106]: info: CoreStateMachine::syncState currentStatus stop Sep 09 14:03:41 volumio volumio[1106]: info: CoreStateMachine::pushState Sep 09 14:03:41 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:41 volumio volumio[1106]: info: No code Sep 09 14:03:41 volumio volumio[1106]: info: CoreStateMachine::pushState Sep 09 14:03:41 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:41 volumio volumio[1106]: info: ------------------------------ 41ms Sep 09 14:03:41 volumio volumio[1106]: info: ------------------------------ 41ms Sep 09 14:03:43 volumio volumio[1106]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 14:03:43 volumio volumio[1106]: Error: connect ETIMEDOUT 151.101.78.79:80 Sep 09 14:03:43 volumio volumio[1106]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Sep 09 14:03:43 volumio volumio[1106]: errno: -110, Sep 09 14:03:43 volumio volumio[1106]: code: 'ETIMEDOUT', Sep 09 14:03:43 volumio volumio[1106]: syscall: 'connect', Sep 09 14:03:43 volumio volumio[1106]: address: '151.101.78.79', Sep 09 14:03:43 volumio volumio[1106]: port: 80 Sep 09 14:03:43 volumio volumio[1106]: } Sep 09 14:03:43 volumio volumio[1106]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 14:03:44 volumio sudo[1812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-09 14:02 Sep 09 14:03:44 volumio sudo[1812]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 14:03:44 volumio sudo[1812]: pam_unix(sudo:session): session closed for user root Sep 09 14:03:44 volumio wpa_supplicant[886]: RRM: Ignoring radio measurement request: Not RRM network Sep 09 14:03:45 volumio volumio-remote-updater[655]: [2024-09-09 14:03:45] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 09 14:03:45 volumio volumio-remote-updater[655]: [2024-09-09 14:03:45] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 09 14:03:45 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 09 14:03:45 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 09 14:03:45 volumio systemd[1]: Started dynamicswap service. Sep 09 14:03:45 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 09 14:03:45 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 09 14:03:45 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Sep 09 14:03:45 volumio systemd[1]: Stopped Volumio Backend Module. Sep 09 14:03:45 volumio systemd[1]: Started Volumio Backend Module. Sep 09 14:03:45 volumio systemd[1]: Started dynamicswap service. Sep 09 14:03:45 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 09 14:03:46 volumio volumio[1826]: info: ------------------------------------------- Sep 09 14:03:46 volumio volumio[1826]: info: ----- Volumio3 ---- Sep 09 14:03:46 volumio volumio[1826]: info: ------------------------------------------- Sep 09 14:03:46 volumio volumio[1826]: info: ----- System startup ---- Sep 09 14:03:46 volumio volumio[1826]: info: ------------------------------------------- Sep 09 14:03:46 volumio volumio[1826]: info: MYVOLUMIO Environment detected Sep 09 14:03:47 volumio volumio[1826]: info: Plugin folders cleanup Sep 09 14:03:47 volumio volumio[1826]: info: Scanning into folder /volumio/app/plugins/ Sep 09 14:03:47 volumio volumio[1826]: info: Scanning category audio_interface Sep 09 14:03:47 volumio volumio[1826]: info: Scanning category miscellanea Sep 09 14:03:47 volumio volumio[1826]: info: Scanning category music_service Sep 09 14:03:47 volumio volumio[1826]: info: Scanning category plugins.json Sep 09 14:03:47 volumio volumio[1826]: info: Scanning category system_controller Sep 09 14:03:47 volumio volumio[1826]: info: Scanning category user_interface Sep 09 14:03:47 volumio volumio[1826]: info: Scanning into folder /data/plugins/ Sep 09 14:03:47 volumio volumio[1826]: info: Scanning category audio_interface Sep 09 14:03:47 volumio volumio[1826]: info: Plugin folders cleanup completed Sep 09 14:03:47 volumio volumio[1826]: info: ------------------------------------------- Sep 09 14:03:47 volumio volumio[1826]: info: ----- Core plugins startup ---- Sep 09 14:03:47 volumio volumio[1826]: info: ------------------------------------------- Sep 09 14:03:47 volumio volumio[1826]: info: Loading plugins from folder /volumio/app/plugins/ Sep 09 14:03:47 volumio volumio[1826]: info: Adding plugin upnp to MyMusic Plugins Sep 09 14:03:47 volumio volumio[1826]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 09 14:03:47 volumio volumio[1826]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 09 14:03:47 volumio volumio[1826]: info: Loading plugins from folder /data/plugins/ Sep 09 14:03:47 volumio volumio[1826]: info: Loading plugin "system"... Sep 09 14:03:47 volumio volumio[1826]: info: Loading plugin "appearance"... Sep 09 14:03:47 volumio volumio[1826]: info: Loading plugin "network"... Sep 09 14:03:47 volumio volumio[1826]: info: Refreshing Cached IP Addresses Sep 09 14:03:47 volumio sudo[1853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 09 14:03:47 volumio sudo[1853]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 14:03:47 volumio sudo[1853]: pam_unix(sudo:session): session closed for user root Sep 09 14:03:47 volumio volumio[1826]: info: Loading plugin "services"... Sep 09 14:03:47 volumio sudo[1855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 09 14:03:47 volumio sudo[1855]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 14:03:47 volumio volumio[1826]: info: Loading plugin "alsa_controller"... Sep 09 14:03:47 volumio sudo[1855]: pam_unix(sudo:session): session closed for user root Sep 09 14:03:47 volumio sudo[1858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 09 14:03:47 volumio sudo[1858]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 14:03:47 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 09 14:03:47 volumio volumio[1826]: info: Loading plugin "wizard"... Sep 09 14:03:47 volumio volumio[1826]: info: Loading plugin "networkfs"... Sep 09 14:03:47 volumio volumio[1826]: info: Starting Udev Watcher for removable devices Sep 09 14:03:47 volumio sudo[1866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 10.0.0.2:/public/ /mnt/NAS/ok Sep 09 14:03:47 volumio sudo[1866]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 14:03:47 volumio volumio[1826]: info: Ignoring mount for partition: boot Sep 09 14:03:47 volumio volumio[1826]: info: Ignoring mount for partition: volumio Sep 09 14:03:47 volumio volumio[1826]: info: Ignoring mount for partition: volumio_data Sep 09 14:03:47 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 09 14:03:47 volumio volumio[1826]: info: Loading plugin "volumio_command_line_client"... Sep 09 14:03:47 volumio volumio[1826]: info: Loading plugin "upnp"... Sep 09 14:03:47 volumio volumio[1826]: info: [1725865427852] Starting Upmpd Daemon Sep 09 14:03:47 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 09 14:03:47 volumio volumio[1826]: info: Loading plugin "my_music"... Sep 09 14:03:47 volumio volumio[1826]: info: Loading plugin "mpd"... Sep 09 14:03:48 volumio volumio[1826]: info: Loading plugin "upnp_browser"... Sep 09 14:03:48 volumio volumio[1826]: info: Loading plugin "alarm-clock"... Sep 09 14:03:48 volumio volumio[1826]: info: Loading plugin "airplay_emulation"... Sep 09 14:03:48 volumio volumio[1826]: info: Starting Shairport Sync Sep 09 14:03:48 volumio volumio[1826]: info: Loading plugin "last_100"... Sep 09 14:03:48 volumio volumio[1826]: info: Loading plugin "webradio"... Sep 09 14:03:48 volumio volumio[1826]: info: Loading plugin "i2s_dacs"... Sep 09 14:03:48 volumio volumio[1826]: info: I2S DAC not set, start Auto-detection Sep 09 14:03:48 volumio volumio[1826]: info: Loading plugin "volumiodiscovery"... Sep 09 14:03:48 volumio volumio[1826]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 09 14:03:48 volumio volumio[1826]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 09 14:03:48 volumio node[1826]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 09 14:03:48 volumio volumio[1826]: *** WARNING *** For more information see Sep 09 14:03:48 volumio volumio[1826]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 09 14:03:48 volumio volumio[1826]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 09 14:03:48 volumio volumio[1826]: *** WARNING *** For more information see Sep 09 14:03:48 volumio node[1826]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 09 14:03:48 volumio node[1826]: *** WARNING *** For more information see Sep 09 14:03:48 volumio node[1826]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 09 14:03:48 volumio node[1826]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 09 14:03:48 volumio node[1826]: *** WARNING *** For more information see Sep 09 14:03:48 volumio volumio[1826]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 09 14:03:48 volumio volumio[1826]: info: Discovery: Started advertising with name: Volumio Sep 09 14:03:48 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 09 14:03:48 volumio volumio[1826]: info: Loading plugin "outputs"... Sep 09 14:03:48 volumio volumio[1826]: info: Loading plugin "albumart"... Sep 09 14:03:48 volumio volumio[1826]: info: Plugin example_plugin is not enabled Sep 09 14:03:48 volumio volumio[1826]: info: Loading plugin "inputs"... Sep 09 14:03:48 volumio volumio[1826]: info: Loading plugin "updater_comm"... Sep 09 14:03:48 volumio volumio[1826]: info: Plugin mpdemulation is not enabled Sep 09 14:03:48 volumio volumio[1826]: info: Loading plugin "rest_api"... Sep 09 14:03:48 volumio volumio[1826]: info: Loading plugin "websocket"... Sep 09 14:03:48 volumio volumio[1826]: info: Starting Socket.io Server version 2.3.0 Sep 09 14:03:48 volumio volumio[1826]: info: Plugin fusiondsp is not enabled Sep 09 14:03:48 volumio volumio[1826]: info: Loading i18n strings for locale vi Sep 09 14:03:48 volumio volumio[1826]: Updating browse sources language Sep 09 14:03:48 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 14:03:48 volumio volumio[1826]: Forking 3 albumart workers Sep 09 14:03:48 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 14:03:48 volumio volumio[1826]: info: CoreCommandRouter::initPlayerControls Sep 09 14:03:48 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 14:03:48 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 14:03:48 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 14:03:48 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 14:03:48 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 14:03:48 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 14:03:48 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 14:03:48 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 14:03:48 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 14:03:48 volumio volumio[1826]: Express server listening on port 3000 Sep 09 14:03:48 volumio volumio[1826]: [Metrics] WebUI: 2s 533.98ms Sep 09 14:03:48 volumio volumio[1826]: info: CoreStateMachine::resetVolumioState Sep 09 14:03:48 volumio volumio[1826]: info: CoreStateMachine::getcurrentVolume Sep 09 14:03:48 volumio volumio[1826]: info: CoreCommandRouter::volumioRetrievevolume Sep 09 14:03:48 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 14:03:48 volumio volumio[1826]: verbose: New Socket.io Connection to 192.168.0.120 from 192.168.0.112 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Sep 09 14:03:48 volumio volumio[1826]: verbose: New Socket.io Connection to 192.168.0.121 from 192.168.0.112 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Sep 09 14:03:48 volumio volumio[1826]: verbose: New Socket.io Connection to 192.168.0.120 from 192.168.0.112 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Sep 09 14:03:48 volumio volumio[1826]: verbose: New Socket.io Connection to 192.168.0.121 from 192.168.0.112 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Sep 09 14:03:48 volumio volumio[1826]: verbose: New Socket.io Connection to 192.168.0.120 from 192.168.0.112 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Sep 09 14:03:49 volumio volumio[1826]: info: Reloading queue from file Sep 09 14:03:49 volumio volumio[1826]: verbose: New Socket.io Connection to 192.168.0.121 from 192.168.0.112 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Sep 09 14:03:49 volumio volumio[1826]: info: VolumeController:: Volume=46 Mute =false Sep 09 14:03:49 volumio volumio[1826]: info: CoreStateMachine::pushState Sep 09 14:03:49 volumio volumio[1826]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:49 volumio volumio[1826]: info: CoreStateMachine::updateTrackBlock Sep 09 14:03:49 volumio volumio[1826]: info: CorePlayQueue::getTrackBlock Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::volumioRetrievevolume Sep 09 14:03:49 volumio volumio[1826]: info: CoreStateMachine::setRepeat null single undefined Sep 09 14:03:49 volumio volumio[1826]: info: CoreStateMachine::pushState Sep 09 14:03:49 volumio volumio[1826]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:49 volumio volumio[1826]: info: CoreStateMachine::setRandom null Sep 09 14:03:49 volumio volumio[1826]: info: CoreStateMachine::pushState Sep 09 14:03:49 volumio volumio[1826]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:49 volumio volumio[1826]: info: Setting Device type: Raspberry PI Sep 09 14:03:49 volumio volumio[1826]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 09 14:03:49 volumio volumio[1826]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Sep 09 14:03:49 volumio volumio[1826]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::volumioGetState Sep 09 14:03:49 volumio volumio[1826]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:49 volumio volumio[1826]: Starting albumart workers Sep 09 14:03:49 volumio volumio[1826]: Starting albumart workers Sep 09 14:03:49 volumio volumio[1826]: info: VolumeController:: Volume=46 Mute =false Sep 09 14:03:49 volumio volumio[1826]: info: CoreStateMachine::pushState Sep 09 14:03:49 volumio volumio[1826]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::volumioPushState Sep 09 14:03:49 volumio volumio[1826]: info: Received Get System Info Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 14:03:49 volumio volumio[1826]: info: Discovery: Getting this device information Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::volumioGetState Sep 09 14:03:49 volumio volumio[1826]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::volumioGetState Sep 09 14:03:49 volumio volumio[1826]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:49 volumio volumio[1826]: info: Received Get System Info Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 14:03:49 volumio volumio[1826]: info: Discovery: Getting this device information Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::volumioGetState Sep 09 14:03:49 volumio volumio[1826]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::volumioGetState Sep 09 14:03:49 volumio volumio[1826]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::volumioGetVisibleSources Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::volumioGetState Sep 09 14:03:49 volumio volumio[1826]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::volumioGetState Sep 09 14:03:49 volumio volumio[1826]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 09 14:03:49 volumio volumio[1826]: info: Received Get System Info Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 14:03:49 volumio volumio[1826]: info: Discovery: Getting this device information Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::volumioGetState Sep 09 14:03:49 volumio volumio[1826]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::volumioGetState Sep 09 14:03:49 volumio volumio[1826]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:49 volumio volumio[1826]: info: Listing playlists Sep 09 14:03:49 volumio volumio[1826]: info: Received Get System Info Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 14:03:49 volumio volumio[1826]: info: Discovery: Getting this device information Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::volumioGetState Sep 09 14:03:49 volumio volumio[1826]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::volumioGetState Sep 09 14:03:49 volumio volumio[1826]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:49 volumio volumio[1826]: Starting albumart workers Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::volumioGetQueue Sep 09 14:03:49 volumio volumio[1826]: info: CoreStateMachine::getQueue Sep 09 14:03:49 volumio volumio[1826]: info: CorePlayQueue::getQueue Sep 09 14:03:49 volumio volumio[1826]: info: Discovery: adding 00d21d02-e94a-4e48-bf27-99c5dd893303 Sep 09 14:03:49 volumio volumio[1826]: info: Discovery: Found device Volumio Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::volumioGetState Sep 09 14:03:49 volumio volumio[1826]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:49 volumio volumio[1826]: info: Discovery: this is already registered, 00d21d02-e94a-4e48-bf27-99c5dd893303 Sep 09 14:03:49 volumio volumio[1826]: info: Discovery: Found device Volumio Sep 09 14:03:49 volumio volumio[1826]: info: CoreCommandRouter::volumioGetState Sep 09 14:03:49 volumio volumio[1826]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:50 volumio volumio-remote-updater[655]: [2024-09-09 14:03:50] [connect] Successful connection Sep 09 14:03:50 volumio volumio-remote-updater[655]: [2024-09-09 14:03:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1725865430 101 Sep 09 14:03:50 volumio volumio[1826]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 7 Sep 09 14:03:51 volumio sudo[1858]: pam_unix(sudo:session): session closed for user root Sep 09 14:03:53 volumio volumio[1826]: info: CoreCommandRouter::volumioPlay Sep 09 14:03:53 volumio volumio[1826]: info: CoreStateMachine::play index undefined Sep 09 14:03:53 volumio volumio[1826]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 14:03:53 volumio volumio[1826]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:53 volumio volumio[1826]: info: CoreStateMachine::startPlaybackTimer Sep 09 14:03:53 volumio volumio[1826]: info: CorePlayQueue::getTrack 0 Sep 09 14:03:53 volumio volumio[1826]: info: [1725865433197] ControllerWebradio::clearAddPlayTrack Sep 09 14:03:56 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 14:03:57 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 14:03:57 volumio sudo[1981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 09 14:03:57 volumio sudo[1981]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 14:03:57 volumio sudo[1981]: pam_unix(sudo:session): session closed for user root Sep 09 14:03:57 volumio sudo[1983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 09 14:03:57 volumio sudo[1983]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 14:03:57 volumio sudo[1983]: pam_unix(sudo:session): session closed for user root Sep 09 14:03:57 volumio sudo[1987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 09 14:03:57 volumio sudo[1987]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 14:03:57 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 14:03:57 volumio sudo[1987]: pam_unix(sudo:session): session closed for user root Sep 09 14:03:57 volumio volumio[1826]: info: Upmpdcli Daemon Started Sep 09 14:03:58 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 14:03:58 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 14:04:03 volumio volumio[1826]: info: Preload queue cleared Sep 09 14:04:03 volumio volumio[1826]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 09 14:04:03 volumio volumio[1826]: info: CoreStateMachine::ClearQueue Sep 09 14:04:03 volumio volumio[1826]: info: CoreStateMachine::stop Sep 09 14:04:03 volumio volumio[1826]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 14:04:03 volumio volumio[1826]: info: CorePlayQueue::clearPlayQueue Sep 09 14:04:03 volumio volumio[1826]: info: CorePlayQueue::saveQueue Sep 09 14:04:03 volumio volumio[1826]: info: CoreCommandRouter::volumioPushQueue Sep 09 14:04:03 volumio volumio[1826]: info: CoreStateMachine::addQueueItems Sep 09 14:04:03 volumio volumio[1826]: info: CorePlayQueue::addQueueItems Sep 09 14:04:03 volumio volumio[1826]: info: Preload queue cleared Sep 09 14:04:03 volumio volumio[1826]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s219225 Sep 09 14:04:03 volumio volumio[1826]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 09 14:04:03 volumio volumio[1826]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 14:04:03 volumio volumio[1826]: TypeError: Cannot read property 'tune_radio' of undefined Sep 09 14:04:03 volumio volumio[1826]: at ControllerWebradio.explodeUri (/volumio/app/plugins/music_service/webradio/index.js:538:33) Sep 09 14:04:03 volumio volumio[1826]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1083:32) Sep 09 14:04:03 volumio volumio[1826]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:179:33) Sep 09 14:04:03 volumio volumio[1826]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Sep 09 14:04:03 volumio volumio[1826]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Sep 09 14:04:03 volumio volumio[1826]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:513:23) Sep 09 14:04:03 volumio volumio[1826]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:483:17) Sep 09 14:04:03 volumio volumio[1826]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Sep 09 14:04:03 volumio volumio[1826]: at Socket.emit (events.js:315:20) Sep 09 14:04:03 volumio volumio[1826]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 09 14:04:03 volumio volumio[1826]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 09 14:04:03 volumio volumio[1826]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 14:04:03 volumio sudo[2001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-09 14:03 Sep 09 14:04:03 volumio sudo[2001]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"