-- Logs begin at Thu 2024-09-05 04:33:31 CST, end at Thu 2024-09-05 04:43:17 CST. -- Sep 05 04:42:02 volumio volumio[1093]: info: Preload queue cleared Sep 05 04:42:02 volumio volumio[1093]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 05 04:42:02 volumio volumio[1093]: info: CoreStateMachine::ClearQueue Sep 05 04:42:02 volumio volumio[1093]: info: CoreStateMachine::stop Sep 05 04:42:02 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 04:42:02 volumio volumio[1093]: info: CoreStateMachine::stPlaybackTimer Sep 05 04:42:02 volumio volumio[1093]: info: CoreStateMachine::updateTrackBlock Sep 05 04:42:02 volumio volumio[1093]: info: CorePlayQueue::getTrackBlock Sep 05 04:42:02 volumio volumio[1093]: info: CoreStateMachine::pushState Sep 05 04:42:02 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:02 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:42:02 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Sep 05 04:42:02 volumio volumio[1093]: info: CoreStateMachine::serviceStop Sep 05 04:42:02 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:02 volumio volumio[1093]: info: CoreCommandRouter::serviceStop Sep 05 04:42:02 volumio volumio[1093]: info: [1725482522123] ControllerWebradio::stop Sep 05 04:42:02 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 04:42:02 volumio volumio[1093]: info: CorePlayQueue::clearPlayQueue Sep 05 04:42:02 volumio volumio[1093]: info: CorePlayQueue::saveQueue Sep 05 04:42:02 volumio volumio[1093]: info: CoreCommandRouter::volumioPushQueue Sep 05 04:42:02 volumio volumio[1093]: info: CoreStateMachine::addQueueItems Sep 05 04:42:02 volumio volumio[1093]: info: CorePlayQueue::addQueueItems Sep 05 04:42:02 volumio volumio[1093]: info: Preload queue cleared Sep 05 04:42:02 volumio volumio[1093]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1823321 Sep 05 04:42:02 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 05 04:42:02 volumio volumio[1093]: info: CoreCommandRouter::volumioPushQueue Sep 05 04:42:02 volumio volumio[1093]: info: CorePlayQueue::saveQueue Sep 05 04:42:02 volumio volumio[1093]: info: CoreStateMachine::updateTrackBlock Sep 05 04:42:02 volumio volumio[1093]: info: CorePlayQueue::getTrackBlock Sep 05 04:42:02 volumio volumio[1093]: info: CoreCommandRouter::volumioPlay Sep 05 04:42:02 volumio volumio[1093]: info: CoreStateMachine::play index 0 Sep 05 04:42:02 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 04:42:02 volumio volumio[1093]: info: CoreStateMachine::stop Sep 05 04:42:02 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 04:42:02 volumio volumio[1093]: info: CoreStateMachine::play index undefined Sep 05 04:42:02 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 04:42:02 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:02 volumio volumio[1093]: info: CoreStateMachine::startPlaybackTimer Sep 05 04:42:02 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:02 volumio volumio[1093]: info: [1725482522153] ControllerWebradio::clearAddPlayTrack Sep 05 04:42:02 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 04:42:02 volumio volumio[1093]: info: Sep 05 04:42:02 volumio volumio[1093]: ---------------------------- MPD announces state update: player Sep 05 04:42:02 volumio volumio[1093]: info: sendMpdCommand stop took 35 milliseconds Sep 05 04:42:02 volumio volumio[1093]: info: ControllerMpd::getState Sep 05 04:42:02 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Sep 05 04:42:02 volumio volumio[1093]: info: sendMpdCommand stop took 12 milliseconds Sep 05 04:42:02 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 04:42:02 volumio volumio[1093]: info: Sep 05 04:42:02 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Sep 05 04:42:02 volumio volumio[1093]: info: Ignoring MPD Status Update Sep 05 04:42:02 volumio volumio[1093]: info: sendMpdCommand status took 5 milliseconds Sep 05 04:42:02 volumio volumio[1093]: info: sendMpdCommand clear took 4 milliseconds Sep 05 04:42:02 volumio volumio[1093]: verbose: ControllerMpd::parseState Sep 05 04:42:02 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 04:42:02 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1823321" Sep 05 04:42:02 volumio volumio[1093]: error: updateQueue error: null Sep 05 04:42:02 volumio volumio[1093]: info: ------------------------------ 5ms Sep 05 04:42:02 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 05 04:42:02 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Sep 05 04:42:02 volumio volumio[1093]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 05 04:42:02 volumio volumio[1093]: info: ------------------------------ 16ms Sep 05 04:42:03 volumio volumio[1093]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1823321" took 1630 milliseconds Sep 05 04:42:03 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 05 04:42:03 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand play Sep 05 04:42:03 volumio volumio[1093]: info: sendMpdCommand play took 1 milliseconds Sep 05 04:42:05 volumio volumio[1093]: info: CoreCommandRouter::volumioPlay Sep 05 04:42:05 volumio volumio[1093]: info: CoreStateMachine::play index undefined Sep 05 04:42:05 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 04:42:05 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:05 volumio volumio[1093]: info: CoreStateMachine::startPlaybackTimer Sep 05 04:42:05 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:05 volumio volumio[1093]: info: [1725482525418] ControllerWebradio::clearAddPlayTrack Sep 05 04:42:05 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 04:42:05 volumio volumio[1093]: info: sendMpdCommand stop took 1 milliseconds Sep 05 04:42:05 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 04:42:05 volumio volumio[1093]: info: Sep 05 04:42:05 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Sep 05 04:42:05 volumio volumio[1093]: info: Ignoring MPD Status Update Sep 05 04:42:05 volumio volumio[1093]: info: sendMpdCommand clear took 1 milliseconds Sep 05 04:42:05 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1823321" Sep 05 04:42:05 volumio volumio[1093]: error: updateQueue error: null Sep 05 04:42:05 volumio volumio[1093]: info: ------------------------------ 2ms Sep 05 04:42:05 volumio volumio[1093]: info: Sep 05 04:42:05 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Sep 05 04:42:05 volumio volumio[1093]: info: Ignoring MPD Status Update Sep 05 04:42:05 volumio volumio[1093]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1823321" took 328 milliseconds Sep 05 04:42:05 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 05 04:42:05 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand play Sep 05 04:42:05 volumio volumio[1093]: info: ------------------------------ 2ms Sep 05 04:42:05 volumio volumio[1093]: info: sendMpdCommand play took 2 milliseconds Sep 05 04:42:06 volumio volumio[1093]: info: Sep 05 04:42:06 volumio volumio[1093]: ---------------------------- MPD announces state update: player Sep 05 04:42:06 volumio volumio[1093]: info: ControllerMpd::getState Sep 05 04:42:06 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Sep 05 04:42:06 volumio volumio[1093]: info: Sep 05 04:42:06 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Sep 05 04:42:06 volumio volumio[1093]: info: Ignoring MPD Status Update Sep 05 04:42:06 volumio volumio[1093]: info: Sep 05 04:42:06 volumio volumio[1093]: ---------------------------- MPD announces state update: player Sep 05 04:42:06 volumio volumio[1093]: info: sendMpdCommand status took 11 milliseconds Sep 05 04:42:06 volumio volumio[1093]: info: ControllerMpd::getState Sep 05 04:42:06 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Sep 05 04:42:06 volumio volumio[1093]: verbose: ControllerMpd::parseState Sep 05 04:42:06 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 04:42:06 volumio volumio[1093]: info: ------------------------------ 4ms Sep 05 04:42:06 volumio volumio[1093]: info: sendMpdCommand status took 2 milliseconds Sep 05 04:42:06 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 05 04:42:06 volumio volumio[1093]: verbose: ControllerMpd::parseState Sep 05 04:42:06 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 04:42:06 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Sep 05 04:42:06 volumio volumio[1093]: info: ControllerMpd::pushState Sep 05 04:42:06 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Sep 05 04:42:06 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:06 volumio volumio[1093]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"59 Kbps","isStreaming":false,"title":"stream3","artist":"ANTENA1 - 94 7 FM","album":null,"uri":"http://51.254.29.39:80/stream3","trackType":""} Sep 05 04:42:06 volumio volumio[1093]: verbose: CURRENT POSITION 0 Sep 05 04:42:06 volumio volumio[1093]: info: CoreStateMachine::syncState stateService play Sep 05 04:42:06 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus stop Sep 05 04:42:06 volumio volumio[1093]: info: ------------------------------ 18ms Sep 05 04:42:06 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 05 04:42:06 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Sep 05 04:42:06 volumio volumio[1093]: info: ControllerMpd::pushState Sep 05 04:42:06 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Sep 05 04:42:06 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:06 volumio volumio[1093]: verbose: STATE SERVICE {"status":"play","position":0,"seek":972,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"59 Kbps","isStreaming":false,"title":"stream3","artist":"ANTENA1 - 94 7 FM","album":null,"uri":"http://51.254.29.39:80/stream3","trackType":""} Sep 05 04:42:06 volumio volumio[1093]: verbose: CURRENT POSITION 0 Sep 05 04:42:06 volumio volumio[1093]: info: CoreStateMachine::syncState stateService play Sep 05 04:42:06 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus play Sep 05 04:42:06 volumio volumio[1093]: info: Received an update from plugin. extracting info from payload Sep 05 04:42:06 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 05 04:42:06 volumio volumio[1093]: info: CoreStateMachine::pushState Sep 05 04:42:06 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:06 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:42:06 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Sep 05 04:42:06 volumio volumio[1093]: info: CoreStateMachine::pushState Sep 05 04:42:06 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Sep 05 04:42:06 volumio volumio[1093]: info: ------------------------------ 27ms Sep 05 04:42:09 volumio volumio[1093]: info: Preload queue cleared Sep 05 04:42:09 volumio volumio[1093]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 05 04:42:09 volumio volumio[1093]: info: CoreStateMachine::ClearQueue Sep 05 04:42:09 volumio volumio[1093]: info: CoreStateMachine::stop Sep 05 04:42:09 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 04:42:09 volumio volumio[1093]: info: CoreStateMachine::stPlaybackTimer Sep 05 04:42:09 volumio volumio[1093]: info: CoreStateMachine::updateTrackBlock Sep 05 04:42:09 volumio volumio[1093]: info: CorePlayQueue::getTrackBlock Sep 05 04:42:09 volumio volumio[1093]: info: CoreStateMachine::pushState Sep 05 04:42:09 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:09 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:42:09 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Sep 05 04:42:09 volumio volumio[1093]: info: CoreStateMachine::serviceStop Sep 05 04:42:09 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:09 volumio volumio[1093]: info: CoreCommandRouter::serviceStop Sep 05 04:42:09 volumio volumio[1093]: info: [1725482529498] ControllerWebradio::stop Sep 05 04:42:09 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 04:42:09 volumio volumio[1093]: info: CorePlayQueue::clearPlayQueue Sep 05 04:42:09 volumio volumio[1093]: info: CorePlayQueue::saveQueue Sep 05 04:42:09 volumio volumio[1093]: info: CoreCommandRouter::volumioPushQueue Sep 05 04:42:09 volumio volumio[1093]: info: CoreStateMachine::addQueueItems Sep 05 04:42:09 volumio volumio[1093]: info: CorePlayQueue::addQueueItems Sep 05 04:42:09 volumio volumio[1093]: info: Preload queue cleared Sep 05 04:42:09 volumio volumio[1093]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996 Sep 05 04:42:09 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 05 04:42:09 volumio volumio[1093]: info: CoreCommandRouter::volumioPushQueue Sep 05 04:42:09 volumio volumio[1093]: info: CorePlayQueue::saveQueue Sep 05 04:42:09 volumio volumio[1093]: info: CoreStateMachine::updateTrackBlock Sep 05 04:42:09 volumio volumio[1093]: info: CorePlayQueue::getTrackBlock Sep 05 04:42:09 volumio volumio[1093]: info: CoreCommandRouter::volumioPlay Sep 05 04:42:09 volumio volumio[1093]: info: CoreStateMachine::play index 0 Sep 05 04:42:09 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 04:42:09 volumio volumio[1093]: info: CoreStateMachine::stop Sep 05 04:42:09 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 04:42:09 volumio volumio[1093]: info: CoreStateMachine::play index undefined Sep 05 04:42:09 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 04:42:09 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:09 volumio volumio[1093]: info: CoreStateMachine::startPlaybackTimer Sep 05 04:42:09 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:09 volumio volumio[1093]: info: [1725482529526] ControllerWebradio::clearAddPlayTrack Sep 05 04:42:09 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 04:42:09 volumio volumio[1093]: info: Sep 05 04:42:09 volumio volumio[1093]: ---------------------------- MPD announces state update: player Sep 05 04:42:09 volumio volumio[1093]: info: sendMpdCommand stop took 33 milliseconds Sep 05 04:42:09 volumio volumio[1093]: info: ControllerMpd::getState Sep 05 04:42:09 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Sep 05 04:42:09 volumio volumio[1093]: info: sendMpdCommand stop took 7 milliseconds Sep 05 04:42:09 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 04:42:09 volumio volumio[1093]: info: Sep 05 04:42:09 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Sep 05 04:42:09 volumio volumio[1093]: info: Ignoring MPD Status Update Sep 05 04:42:09 volumio volumio[1093]: info: sendMpdCommand status took 4 milliseconds Sep 05 04:42:09 volumio volumio[1093]: info: sendMpdCommand clear took 3 milliseconds Sep 05 04:42:09 volumio volumio[1093]: verbose: ControllerMpd::parseState Sep 05 04:42:09 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 04:42:09 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Sep 05 04:42:09 volumio volumio[1093]: error: updateQueue error: null Sep 05 04:42:09 volumio volumio[1093]: info: ------------------------------ 6ms Sep 05 04:42:09 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 05 04:42:09 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Sep 05 04:42:09 volumio volumio[1093]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 05 04:42:09 volumio volumio[1093]: info: ------------------------------ 14ms Sep 05 04:42:09 volumio volumio[1093]: info: Sep 05 04:42:09 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Sep 05 04:42:09 volumio volumio[1093]: info: Ignoring MPD Status Update Sep 05 04:42:09 volumio volumio[1093]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 334 milliseconds Sep 05 04:42:09 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 05 04:42:09 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand play Sep 05 04:42:09 volumio volumio[1093]: info: ------------------------------ 6ms Sep 05 04:42:09 volumio volumio[1093]: info: sendMpdCommand play took 4 milliseconds Sep 05 04:42:11 volumio volumio[1093]: info: CoreCommandRouter::volumioPlay Sep 05 04:42:11 volumio volumio[1093]: info: CoreStateMachine::play index undefined Sep 05 04:42:11 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 04:42:11 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:11 volumio volumio[1093]: info: CoreStateMachine::startPlaybackTimer Sep 05 04:42:11 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:11 volumio volumio[1093]: info: [1725482531665] ControllerWebradio::clearAddPlayTrack Sep 05 04:42:11 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 04:42:11 volumio volumio[1093]: info: Sep 05 04:42:11 volumio volumio[1093]: ---------------------------- MPD announces state update: player Sep 05 04:42:11 volumio volumio[1093]: info: sendMpdCommand stop took 2 milliseconds Sep 05 04:42:11 volumio volumio[1093]: info: ControllerMpd::getState Sep 05 04:42:11 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Sep 05 04:42:11 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 04:42:11 volumio volumio[1093]: info: Sep 05 04:42:11 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Sep 05 04:42:11 volumio volumio[1093]: info: Ignoring MPD Status Update Sep 05 04:42:11 volumio volumio[1093]: info: sendMpdCommand status took 2 milliseconds Sep 05 04:42:11 volumio volumio[1093]: info: sendMpdCommand clear took 1 milliseconds Sep 05 04:42:11 volumio volumio[1093]: verbose: ControllerMpd::parseState Sep 05 04:42:11 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 04:42:11 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Sep 05 04:42:11 volumio volumio[1093]: error: updateQueue error: null Sep 05 04:42:11 volumio volumio[1093]: info: ------------------------------ 3ms Sep 05 04:42:11 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 05 04:42:11 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Sep 05 04:42:11 volumio volumio[1093]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 05 04:42:11 volumio volumio[1093]: info: ------------------------------ 9ms Sep 05 04:42:11 volumio volumio[1093]: info: Sep 05 04:42:11 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Sep 05 04:42:11 volumio volumio[1093]: info: Ignoring MPD Status Update Sep 05 04:42:11 volumio volumio[1093]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 312 milliseconds Sep 05 04:42:11 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 05 04:42:11 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand play Sep 05 04:42:11 volumio volumio[1093]: info: ------------------------------ 3ms Sep 05 04:42:11 volumio volumio[1093]: info: sendMpdCommand play took 2 milliseconds Sep 05 04:42:13 volumio volumio[1093]: info: Sep 05 04:42:13 volumio volumio[1093]: ---------------------------- MPD announces state update: player Sep 05 04:42:13 volumio volumio[1093]: info: ControllerMpd::getState Sep 05 04:42:13 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Sep 05 04:42:13 volumio volumio[1093]: info: Sep 05 04:42:13 volumio volumio[1093]: ---------------------------- MPD announces state update: player Sep 05 04:42:13 volumio volumio[1093]: info: sendMpdCommand status took 11 milliseconds Sep 05 04:42:13 volumio volumio[1093]: info: ControllerMpd::getState Sep 05 04:42:13 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Sep 05 04:42:13 volumio volumio[1093]: verbose: ControllerMpd::parseState Sep 05 04:42:13 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 04:42:13 volumio volumio[1093]: info: sendMpdCommand status took 1 milliseconds Sep 05 04:42:13 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 05 04:42:13 volumio volumio[1093]: verbose: ControllerMpd::parseState Sep 05 04:42:13 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 04:42:13 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Sep 05 04:42:13 volumio volumio[1093]: info: ControllerMpd::pushState Sep 05 04:42:13 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Sep 05 04:42:13 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:13 volumio volumio[1093]: 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":"antenne","artist":"(#1 - 10633/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Sep 05 04:42:13 volumio volumio[1093]: verbose: CURRENT POSITION 0 Sep 05 04:42:13 volumio volumio[1093]: info: CoreStateMachine::syncState stateService play Sep 05 04:42:13 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus stop Sep 05 04:42:13 volumio volumio[1093]: info: ------------------------------ 16ms Sep 05 04:42:13 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 05 04:42:13 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Sep 05 04:42:13 volumio volumio[1093]: info: ControllerMpd::pushState Sep 05 04:42:13 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Sep 05 04:42:13 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:13 volumio volumio[1093]: 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":"antenne","artist":"(#1 - 10633/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Sep 05 04:42:13 volumio volumio[1093]: verbose: CURRENT POSITION 0 Sep 05 04:42:13 volumio volumio[1093]: info: CoreStateMachine::syncState stateService play Sep 05 04:42:13 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus play Sep 05 04:42:13 volumio volumio[1093]: info: Received an update from plugin. extracting info from payload Sep 05 04:42:13 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 05 04:42:13 volumio volumio[1093]: info: CoreStateMachine::pushState Sep 05 04:42:13 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:13 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:42:13 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Sep 05 04:42:13 volumio volumio[1093]: info: CoreStateMachine::pushState Sep 05 04:42:13 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Sep 05 04:42:13 volumio volumio[1093]: info: ------------------------------ 22ms Sep 05 04:42:13 volumio volumio[1093]: info: Sep 05 04:42:13 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Sep 05 04:42:13 volumio volumio[1093]: info: Ignoring MPD Status Update Sep 05 04:42:13 volumio volumio[1093]: info: Sep 05 04:42:13 volumio volumio[1093]: ---------------------------- MPD announces state update: player Sep 05 04:42:13 volumio volumio[1093]: info: ControllerMpd::getState Sep 05 04:42:13 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Sep 05 04:42:13 volumio volumio[1093]: info: ------------------------------ 2ms Sep 05 04:42:13 volumio volumio[1093]: info: Sep 05 04:42:13 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Sep 05 04:42:13 volumio volumio[1093]: info: Ignoring MPD Status Update Sep 05 04:42:13 volumio volumio[1093]: info: Sep 05 04:42:13 volumio volumio[1093]: ---------------------------- MPD announces state update: player Sep 05 04:42:13 volumio volumio[1093]: info: sendMpdCommand status took 10 milliseconds Sep 05 04:42:13 volumio volumio[1093]: info: ControllerMpd::getState Sep 05 04:42:13 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Sep 05 04:42:13 volumio volumio[1093]: verbose: ControllerMpd::parseState Sep 05 04:42:13 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 04:42:13 volumio volumio[1093]: info: ------------------------------ 6ms Sep 05 04:42:13 volumio volumio[1093]: info: sendMpdCommand status took 3 milliseconds Sep 05 04:42:13 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 05 04:42:13 volumio volumio[1093]: verbose: ControllerMpd::parseState Sep 05 04:42:13 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 04:42:13 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Sep 05 04:42:13 volumio volumio[1093]: info: ControllerMpd::pushState Sep 05 04:42:13 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Sep 05 04:42:13 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:13 volumio volumio[1093]: verbose: STATE SERVICE {"status":"play","position":0,"seek":387,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nea - Some Say","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Sep 05 04:42:13 volumio volumio[1093]: verbose: CURRENT POSITION 0 Sep 05 04:42:13 volumio volumio[1093]: info: CoreStateMachine::syncState stateService play Sep 05 04:42:13 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus play Sep 05 04:42:13 volumio volumio[1093]: info: Received an update from plugin. extracting info from payload Sep 05 04:42:13 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 05 04:42:13 volumio volumio[1093]: info: CoreStateMachine::pushState Sep 05 04:42:13 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:42:13 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Sep 05 04:42:13 volumio volumio[1093]: info: CoreStateMachine::pushState Sep 05 04:42:13 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Sep 05 04:42:13 volumio volumio[1093]: info: ------------------------------ 36ms Sep 05 04:42:13 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 22 milliseconds Sep 05 04:42:13 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Sep 05 04:42:13 volumio volumio[1093]: info: ControllerMpd::pushState Sep 05 04:42:13 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Sep 05 04:42:13 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:13 volumio volumio[1093]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1487,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nea - Some Say","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Sep 05 04:42:13 volumio volumio[1093]: verbose: CURRENT POSITION 0 Sep 05 04:42:13 volumio volumio[1093]: info: CoreStateMachine::syncState stateService play Sep 05 04:42:13 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus play Sep 05 04:42:13 volumio volumio[1093]: info: Received an update from plugin. extracting info from payload Sep 05 04:42:13 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 05 04:42:13 volumio volumio[1093]: info: CoreStateMachine::pushState Sep 05 04:42:13 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Sep 05 04:42:14 volumio volumio[1093]: info: CoreStateMachine::pushState Sep 05 04:42:14 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Sep 05 04:42:14 volumio volumio[1093]: info: ------------------------------ 61ms Sep 05 04:42:14 volumio volumio[1093]: info: Sep 05 04:42:14 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Sep 05 04:42:14 volumio volumio[1093]: info: Ignoring MPD Status Update Sep 05 04:42:14 volumio volumio[1093]: info: Sep 05 04:42:14 volumio volumio[1093]: ---------------------------- MPD announces state update: player Sep 05 04:42:14 volumio volumio[1093]: info: ControllerMpd::getState Sep 05 04:42:14 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Sep 05 04:42:14 volumio volumio[1093]: info: ------------------------------ 4ms Sep 05 04:42:14 volumio volumio[1093]: info: sendMpdCommand status took 2 milliseconds Sep 05 04:42:14 volumio volumio[1093]: verbose: ControllerMpd::parseState Sep 05 04:42:14 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 04:42:14 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 05 04:42:14 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Sep 05 04:42:14 volumio volumio[1093]: info: ControllerMpd::pushState Sep 05 04:42:14 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Sep 05 04:42:14 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:14 volumio volumio[1093]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1737,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nea - Some Say","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Sep 05 04:42:14 volumio volumio[1093]: verbose: CURRENT POSITION 0 Sep 05 04:42:14 volumio volumio[1093]: info: CoreStateMachine::syncState stateService play Sep 05 04:42:14 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus play Sep 05 04:42:14 volumio volumio[1093]: info: Received an update from plugin. extracting info from payload Sep 05 04:42:14 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 05 04:42:14 volumio volumio[1093]: info: CoreStateMachine::pushState Sep 05 04:42:14 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:42:14 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Sep 05 04:42:14 volumio volumio[1093]: info: CoreStateMachine::pushState Sep 05 04:42:14 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Sep 05 04:42:14 volumio volumio[1093]: info: ------------------------------ 23ms Sep 05 04:42:15 volumio volumio[1093]: info: Sep 05 04:42:15 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Sep 05 04:42:15 volumio volumio[1093]: info: Ignoring MPD Status Update Sep 05 04:42:15 volumio volumio[1093]: info: Sep 05 04:42:15 volumio volumio[1093]: ---------------------------- MPD announces state update: player Sep 05 04:42:15 volumio volumio[1093]: info: ControllerMpd::getState Sep 05 04:42:15 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Sep 05 04:42:15 volumio volumio[1093]: info: ------------------------------ 3ms Sep 05 04:42:15 volumio volumio[1093]: info: sendMpdCommand status took 1 milliseconds Sep 05 04:42:15 volumio volumio[1093]: verbose: ControllerMpd::parseState Sep 05 04:42:15 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 04:42:15 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 05 04:42:15 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Sep 05 04:42:15 volumio volumio[1093]: info: ControllerMpd::pushState Sep 05 04:42:15 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Sep 05 04:42:15 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:15 volumio volumio[1093]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nea - Some Say","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Sep 05 04:42:15 volumio volumio[1093]: verbose: CURRENT POSITION 0 Sep 05 04:42:15 volumio volumio[1093]: info: CoreStateMachine::syncState stateService play Sep 05 04:42:15 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus play Sep 05 04:42:15 volumio volumio[1093]: info: Received an update from plugin. extracting info from payload Sep 05 04:42:15 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 05 04:42:15 volumio volumio[1093]: info: CoreStateMachine::pushState Sep 05 04:42:15 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:42:15 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Sep 05 04:42:15 volumio volumio[1093]: info: CoreStateMachine::pushState Sep 05 04:42:15 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Sep 05 04:42:15 volumio volumio[1093]: info: ------------------------------ 28ms Sep 05 04:42:16 volumio volumio[1093]: info: Sep 05 04:42:16 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Sep 05 04:42:16 volumio volumio[1093]: info: Ignoring MPD Status Update Sep 05 04:42:16 volumio volumio[1093]: info: Sep 05 04:42:16 volumio volumio[1093]: ---------------------------- MPD announces state update: player Sep 05 04:42:16 volumio volumio[1093]: info: ControllerMpd::getState Sep 05 04:42:16 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Sep 05 04:42:16 volumio volumio[1093]: info: ------------------------------ 2ms Sep 05 04:42:16 volumio volumio[1093]: info: sendMpdCommand status took 1 milliseconds Sep 05 04:42:16 volumio volumio[1093]: verbose: ControllerMpd::parseState Sep 05 04:42:16 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 04:42:16 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 05 04:42:16 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Sep 05 04:42:16 volumio volumio[1093]: info: ControllerMpd::pushState Sep 05 04:42:16 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Sep 05 04:42:16 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:16 volumio volumio[1093]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3732,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nea - Some Say","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Sep 05 04:42:16 volumio volumio[1093]: verbose: CURRENT POSITION 0 Sep 05 04:42:16 volumio volumio[1093]: info: CoreStateMachine::syncState stateService play Sep 05 04:42:16 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus play Sep 05 04:42:16 volumio volumio[1093]: info: Received an update from plugin. extracting info from payload Sep 05 04:42:16 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 05 04:42:16 volumio volumio[1093]: info: CoreStateMachine::pushState Sep 05 04:42:16 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:42:16 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Sep 05 04:42:16 volumio volumio[1093]: info: CoreStateMachine::pushState Sep 05 04:42:16 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Sep 05 04:42:16 volumio volumio[1093]: info: ------------------------------ 25ms Sep 05 04:42:20 volumio volumio[1093]: info: Preload queue cleared Sep 05 04:42:20 volumio volumio[1093]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 05 04:42:20 volumio volumio[1093]: info: CoreStateMachine::ClearQueue Sep 05 04:42:20 volumio volumio[1093]: info: CoreStateMachine::stop Sep 05 04:42:20 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 04:42:20 volumio volumio[1093]: info: CoreStateMachine::stPlaybackTimer Sep 05 04:42:20 volumio volumio[1093]: info: CoreStateMachine::updateTrackBlock Sep 05 04:42:20 volumio volumio[1093]: info: CorePlayQueue::getTrackBlock Sep 05 04:42:20 volumio volumio[1093]: info: CoreStateMachine::pushState Sep 05 04:42:20 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:20 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:42:20 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Sep 05 04:42:20 volumio volumio[1093]: info: CoreStateMachine::serviceStop Sep 05 04:42:20 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:20 volumio volumio[1093]: info: CoreCommandRouter::serviceStop Sep 05 04:42:20 volumio volumio[1093]: info: [1725482540155] ControllerWebradio::stop Sep 05 04:42:20 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 04:42:20 volumio volumio[1093]: info: CorePlayQueue::clearPlayQueue Sep 05 04:42:20 volumio volumio[1093]: info: CorePlayQueue::saveQueue Sep 05 04:42:20 volumio volumio[1093]: info: CoreCommandRouter::volumioPushQueue Sep 05 04:42:20 volumio volumio[1093]: info: CoreStateMachine::addQueueItems Sep 05 04:42:20 volumio volumio[1093]: info: CorePlayQueue::addQueueItems Sep 05 04:42:20 volumio volumio[1093]: info: Preload queue cleared Sep 05 04:42:20 volumio volumio[1093]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1462920 Sep 05 04:42:20 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 05 04:42:20 volumio volumio[1093]: info: CoreCommandRouter::volumioPushQueue Sep 05 04:42:20 volumio volumio[1093]: info: CorePlayQueue::saveQueue Sep 05 04:42:20 volumio volumio[1093]: info: CoreStateMachine::updateTrackBlock Sep 05 04:42:20 volumio volumio[1093]: info: CorePlayQueue::getTrackBlock Sep 05 04:42:20 volumio volumio[1093]: info: CoreCommandRouter::volumioPlay Sep 05 04:42:20 volumio volumio[1093]: info: CoreStateMachine::play index 0 Sep 05 04:42:20 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 04:42:20 volumio volumio[1093]: info: CoreStateMachine::stop Sep 05 04:42:20 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 04:42:20 volumio volumio[1093]: info: CoreStateMachine::play index undefined Sep 05 04:42:20 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 04:42:20 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:20 volumio volumio[1093]: info: CoreStateMachine::startPlaybackTimer Sep 05 04:42:20 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:20 volumio volumio[1093]: info: [1725482540173] ControllerWebradio::clearAddPlayTrack Sep 05 04:42:20 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 04:42:20 volumio volumio[1093]: info: Sep 05 04:42:20 volumio volumio[1093]: ---------------------------- MPD announces state update: player Sep 05 04:42:20 volumio volumio[1093]: info: sendMpdCommand stop took 22 milliseconds Sep 05 04:42:20 volumio volumio[1093]: info: ControllerMpd::getState Sep 05 04:42:20 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Sep 05 04:42:20 volumio volumio[1093]: info: sendMpdCommand stop took 4 milliseconds Sep 05 04:42:20 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 04:42:20 volumio volumio[1093]: info: Sep 05 04:42:20 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Sep 05 04:42:20 volumio volumio[1093]: info: Ignoring MPD Status Update Sep 05 04:42:20 volumio volumio[1093]: info: sendMpdCommand status took 4 milliseconds Sep 05 04:42:20 volumio volumio[1093]: info: sendMpdCommand clear took 3 milliseconds Sep 05 04:42:20 volumio volumio[1093]: verbose: ControllerMpd::parseState Sep 05 04:42:20 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 04:42:20 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1462920" Sep 05 04:42:20 volumio volumio[1093]: error: updateQueue error: null Sep 05 04:42:20 volumio volumio[1093]: info: ------------------------------ 3ms Sep 05 04:42:20 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 05 04:42:20 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Sep 05 04:42:20 volumio volumio[1093]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 05 04:42:20 volumio volumio[1093]: info: ------------------------------ 11ms Sep 05 04:42:20 volumio volumio[1093]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1462920" took 327 milliseconds Sep 05 04:42:20 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 05 04:42:20 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand play Sep 05 04:42:20 volumio volumio[1093]: info: sendMpdCommand play took 2 milliseconds Sep 05 04:42:23 volumio volumio[1093]: info: CoreCommandRouter::volumioPlay Sep 05 04:42:23 volumio volumio[1093]: info: CoreStateMachine::play index undefined Sep 05 04:42:23 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 04:42:23 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:23 volumio volumio[1093]: info: CoreStateMachine::startPlaybackTimer Sep 05 04:42:23 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Sep 05 04:42:23 volumio volumio[1093]: info: [1725482543133] ControllerWebradio::clearAddPlayTrack Sep 05 04:42:23 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 04:42:23 volumio volumio[1093]: info: sendMpdCommand stop took 1 milliseconds Sep 05 04:42:23 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 04:42:23 volumio volumio[1093]: info: Sep 05 04:42:23 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Sep 05 04:42:23 volumio volumio[1093]: info: Ignoring MPD Status Update Sep 05 04:42:23 volumio volumio[1093]: info: sendMpdCommand clear took 1 milliseconds Sep 05 04:42:23 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1462920" Sep 05 04:42:23 volumio volumio[1093]: error: updateQueue error: null Sep 05 04:42:23 volumio volumio[1093]: info: ------------------------------ 2ms Sep 05 04:42:23 volumio volumio[1093]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1462920" took 305 milliseconds Sep 05 04:42:23 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 05 04:42:23 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand play Sep 05 04:42:23 volumio volumio[1093]: info: sendMpdCommand play took 1 milliseconds Sep 05 04:42:35 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 05 04:42:36 volumio volumio[1093]: info: Preload queue cleared Sep 05 04:42:44 volumio volumio[1093]: info: CoreCommandRouter::volumioGetState Sep 05 04:42:47 volumio volumio[1093]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 04:42:47 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 05 04:42:47 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Sep 05 04:42:47 volumio sudo[3561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Sep 05 04:42:47 volumio sudo[3561]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 04:42:47 volumio sudo[3566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 05 04:42:47 volumio sudo[3566]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 04:42:47 volumio sudo[3566]: pam_unix(sudo:session): session closed for user root Sep 05 04:42:47 volumio sudo[3561]: pam_unix(sudo:session): session closed for user root Sep 05 04:42:47 volumio sudo[3574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 05 04:42:47 volumio sudo[3574]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 04:42:47 volumio sudo[3580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 05 04:42:47 volumio sudo[3580]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 04:42:47 volumio sudo[3574]: pam_unix(sudo:session): session closed for user root Sep 05 04:42:47 volumio sudo[3580]: pam_unix(sudo:session): session closed for user root Sep 05 04:42:47 volumio sudo[3587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 04:42:47 volumio sudo[3587]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 04:42:47 volumio sudo[3587]: pam_unix(sudo:session): session closed for user root Sep 05 04:42:47 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 05 04:42:47 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 05 04:42:47 volumio sudo[3589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 04:42:47 volumio sudo[3589]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 04:42:47 volumio sudo[3589]: pam_unix(sudo:session): session closed for user root Sep 05 04:42:47 volumio sudo[3593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 05 04:42:47 volumio sudo[3593]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 04:42:57 volumio sudo[3593]: pam_unix(sudo:session): session closed for user root Sep 05 04:42:58 volumio wpa_supplicant[3137]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 05 04:43:00 volumio dhcpcd[855]: wlan0: carrier lost Sep 05 04:43:00 volumio avahi-daemon[775]: Withdrawing address record for 192.168.31.14 on wlan0. Sep 05 04:43:00 volumio avahi-daemon[775]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.31.14. Sep 05 04:43:00 volumio wpa_supplicant[3137]: wlan0: CTRL-EVENT-DISCONNECTED bssid=d4:35:38:95:15:f2 reason=4 locally_generated=1 Sep 05 04:43:00 volumio avahi-daemon[775]: Interface wlan0.IPv4 no longer relevant for mDNS. Sep 05 04:43:00 volumio wpa_supplicant[3137]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 05 04:43:00 volumio wpa_supplicant[3137]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN Sep 05 04:43:00 volumio volumio[1093]: info: Discovery: A device disappeared from network Sep 05 04:43:00 volumio volumio[1093]: info: Discovery: Device volumio disappeared from network Sep 05 04:43:00 volumio dhcpcd[855]: wlan0: deleting route to 192.168.31.0/24 Sep 05 04:43:00 volumio dhcpcd[855]: wlan0: deleting default route via 192.168.31.1 Sep 05 04:43:01 volumio wpa_supplicant[3137]: wlan0: SME: Trying to authenticate with d4:35:38:95:15:f3 (SSID='Xiaomi-402' freq=2417 MHz) Sep 05 04:43:01 volumio kernel: wlan0: authenticate with d4:35:38:95:15:f3 Sep 05 04:43:01 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Sep 05 04:43:01 volumio kernel: wlan0: send auth to d4:35:38:95:15:f3 (try 1/3) Sep 05 04:43:01 volumio wpa_supplicant[3137]: wlan0: Trying to associate with d4:35:38:95:15:f3 (SSID='Xiaomi-402' freq=2417 MHz) Sep 05 04:43:01 volumio kernel: wlan0: authenticated Sep 05 04:43:01 volumio kernel: wlan0: associate with d4:35:38:95:15:f3 (try 1/3) Sep 05 04:43:01 volumio wpa_supplicant[3137]: wlan0: Associated with d4:35:38:95:15:f3 Sep 05 04:43:01 volumio wpa_supplicant[3137]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 05 04:43:01 volumio wpa_supplicant[3137]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Sep 05 04:43:01 volumio kernel: wlan0: RX AssocResp from d4:35:38:95:15:f3 (capab=0x1431 status=0 aid=13) Sep 05 04:43:01 volumio kernel: wlan0: associated Sep 05 04:43:01 volumio kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by d4:35:38:95:15:f3 Sep 05 04:43:01 volumio wpa_supplicant[3137]: wlan0: WPA: Key negotiation completed with d4:35:38:95:15:f3 [PTK=CCMP GTK=CCMP] Sep 05 04:43:01 volumio wpa_supplicant[3137]: wlan0: CTRL-EVENT-CONNECTED - Connection to d4:35:38:95:15:f3 completed [id=0 id_str=] Sep 05 04:43:01 volumio dhcpcd[855]: wlan0: carrier acquired Sep 05 04:43:01 volumio dhcpcd[855]: wlan0: IAID 7d:8f:a8:98 Sep 05 04:43:02 volumio dhcpcd[855]: wlan0: soliciting an IPv6 router Sep 05 04:43:02 volumio dhcpcd[855]: wlan0: rebinding lease of 192.168.31.14 Sep 05 04:43:02 volumio dhcpcd[855]: wlan0: probing address 192.168.31.14/24 Sep 05 04:43:03 volumio ntpd[3209]: Deleting interface #4 wlan0, 192.168.31.14#123, interface stats: received=0, sent=0, dropped=0, active_time=284 secs Sep 05 04:43:07 volumio dhcpcd[855]: wlan0: leased 192.168.31.14 for 43200 seconds Sep 05 04:43:07 volumio avahi-daemon[775]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.31.14. Sep 05 04:43:07 volumio avahi-daemon[775]: New relevant interface wlan0.IPv4 for mDNS. Sep 05 04:43:07 volumio dhcpcd[855]: wlan0: adding route to 192.168.31.0/24 Sep 05 04:43:07 volumio avahi-daemon[775]: Registering new address record for 192.168.31.14 on wlan0.IPv4. Sep 05 04:43:07 volumio dhcpcd[855]: wlan0: adding default route via 192.168.31.1 Sep 05 04:43:08 volumio ntpd[3209]: ntpd exiting on signal 15 (Terminated) Sep 05 04:43:08 volumio ntpd[3209]: 193.182.111.142 local addr 192.168.31.44 -> Sep 05 04:43:08 volumio ntpd[3209]: 193.182.111.143 local addr 192.168.31.44 -> Sep 05 04:43:08 volumio ntpd[3209]: 185.209.85.222 local addr 192.168.31.44 -> Sep 05 04:43:08 volumio ntpd[3209]: 202.112.31.197 local addr 192.168.31.44 -> Sep 05 04:43:08 volumio ntpd[3209]: 193.182.111.14 local addr 192.168.31.44 -> Sep 05 04:43:08 volumio ntpd[3209]: 193.182.111.12 local addr 192.168.31.44 -> Sep 05 04:43:08 volumio systemd[1]: Stopping Network Time Service... Sep 05 04:43:08 volumio systemd[1]: ntp.service: Succeeded. Sep 05 04:43:08 volumio systemd[1]: Stopped Network Time Service. Sep 05 04:43:08 volumio systemd[1]: Starting Network Time Service... Sep 05 04:43:08 volumio ntpd[3662]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 05 04:43:08 volumio ntpd[3662]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 05 04:43:08 volumio systemd[1]: Started Network Time Service. Sep 05 04:43:08 volumio ntpd[3668]: proto: precision = 0.163 usec (-22) Sep 05 04:43:08 volumio ntpd[3668]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 05 04:43:08 volumio ntpd[3668]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 05 04:43:08 volumio ntpd[3668]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 617 days ago Sep 05 04:43:08 volumio ntpd[3668]: Listen and drop on 0 v6wildcard [::]:123 Sep 05 04:43:08 volumio ntpd[3668]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 05 04:43:08 volumio ntpd[3668]: Listen normally on 2 lo 127.0.0.1:123 Sep 05 04:43:08 volumio ntpd[3668]: Listen normally on 3 eth0 192.168.31.44:123 Sep 05 04:43:08 volumio ntpd[3668]: Listen normally on 4 wlan0 192.168.31.14:123 Sep 05 04:43:08 volumio ntpd[3668]: Listening on routing socket on fd #21 for interface updates Sep 05 04:43:08 volumio ntpd[3668]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 05 04:43:08 volumio ntpd[3668]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 05 04:43:08 volumio volumio[1093]: info: Discovery: adding 2df301a1-92c8-4382-b117-889d1c6d0ae4 Sep 05 04:43:08 volumio volumio[1093]: info: Discovery: Found device Volumio Sep 05 04:43:08 volumio volumio[1093]: info: CoreCommandRouter::volumioGetState Sep 05 04:43:09 volumio ntpd[3668]: Soliciting pool server 111.230.189.174 Sep 05 04:43:10 volumio ntpd[3668]: Soliciting pool server 84.16.73.33 Sep 05 04:43:10 volumio ntpd[3668]: Soliciting pool server 162.159.200.1 Sep 05 04:43:11 volumio ntpd[3668]: Soliciting pool server 162.159.200.123 Sep 05 04:43:11 volumio ntpd[3668]: Soliciting pool server 108.59.2.24 Sep 05 04:43:11 volumio ntpd[3668]: Soliciting pool server 162.159.200.123 Sep 05 04:43:12 volumio ntpd[3668]: Soliciting pool server 193.182.111.14 Sep 05 04:43:12 volumio ntpd[3668]: Soliciting pool server 139.199.214.202 Sep 05 04:43:13 volumio ntpd[3668]: Soliciting pool server 111.203.6.13 Sep 05 04:43:13 volumio volumio[1093]: info: CoreCommandRouter::volumioGetState Sep 05 04:43:13 volumio volumio[1093]: info: VolumeController::SetAlsaVolume81 Sep 05 04:43:14 volumio volumio[1093]: info: VolumeController::SetAlsaVolume91 Sep 05 04:43:14 volumio volumio[1093]: info: VolumeController::SetAlsaVolume93 Sep 05 04:43:14 volumio volumio[1093]: info: VolumeController::SetAlsaVolume0 Sep 05 04:43:14 volumio ntpd[3668]: Soliciting pool server 95.111.202.5 Sep 05 04:43:14 volumio volumio[1093]: info: CoreStateMachine::pushState Sep 05 04:43:14 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:43:14 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Sep 05 04:43:14 volumio volumio[1093]: info: CoreStateMachine::pushState Sep 05 04:43:14 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Sep 05 04:43:14 volumio volumio[1093]: info: CoreStateMachine::pushState Sep 05 04:43:14 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Sep 05 04:43:14 volumio volumio[1093]: info: CoreStateMachine::pushState Sep 05 04:43:14 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Sep 05 04:43:14 volumio volumio[1093]: info: VolumeController::SetAlsaVolume74 Sep 05 04:43:14 volumio volumio[1093]: info: VolumeController::SetAlsaVolume0 Sep 05 04:43:14 volumio volumio[1093]: info: CoreStateMachine::pushState Sep 05 04:43:14 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Sep 05 04:43:14 volumio volumio[1093]: info: CoreStateMachine::pushState Sep 05 04:43:14 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Sep 05 04:43:15 volumio ntpd[3668]: Soliciting pool server 202.118.1.130 Sep 05 04:43:17 volumio volumio[1093]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 04:43:17 volumio volumio[1093]: Error: connect ETIMEDOUT 157.240.20.18:80 Sep 05 04:43:17 volumio volumio[1093]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Sep 05 04:43:17 volumio volumio[1093]: errno: -110, Sep 05 04:43:17 volumio volumio[1093]: code: 'ETIMEDOUT', Sep 05 04:43:17 volumio volumio[1093]: syscall: 'connect', Sep 05 04:43:17 volumio volumio[1093]: address: '157.240.20.18', Sep 05 04:43:17 volumio volumio[1093]: port: 80 Sep 05 04:43:17 volumio volumio[1093]: } Sep 05 04:43:17 volumio volumio[1093]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 04:43:17 volumio sudo[3690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-05 04:42 Sep 05 04:43:17 volumio sudo[3690]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"