-- Logs begin at Mon 2024-08-19 13:48:08 UTC, end at Mon 2024-08-19 14:13:52 UTC. -- Aug 19 14:12:05 volumio volumio[4109]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 19 14:12:05 volumio volumio[4109]: info: CoreStateMachine::ClearQueue Aug 19 14:12:05 volumio volumio[4109]: info: CoreStateMachine::stop Aug 19 14:12:05 volumio volumio[4109]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 14:12:05 volumio volumio[4109]: verbose: UNSET VOLATILE Aug 19 14:12:05 volumio volumio[4109]: info: CoreStateMachine::stPlaybackTimer Aug 19 14:12:05 volumio volumio[4109]: info: CoreStateMachine::updateTrackBlock Aug 19 14:12:05 volumio volumio[4109]: info: CorePlayQueue::getTrackBlock Aug 19 14:12:05 volumio volumio[4109]: info: CoreStateMachine::pushState Aug 19 14:12:05 volumio volumio[4109]: info: CorePlayQueue::getTrack 0 Aug 19 14:12:05 volumio volumio[4109]: info: CoreCommandRouter::volumioPushState Aug 19 14:12:05 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:12:05 volumio volumio[4109]: info: CoreStateMachine::serviceStop Aug 19 14:12:05 volumio volumio[4109]: info: CorePlayQueue::getTrack 0 Aug 19 14:12:05 volumio volumio[4109]: info: CoreCommandRouter::serviceStop Aug 19 14:12:05 volumio volumio[4109]: info: [1724076725866] ControllerWebradio::stop Aug 19 14:12:05 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand stop Aug 19 14:12:05 volumio volumio[4109]: info: CorePlayQueue::clearPlayQueue Aug 19 14:12:05 volumio volumio[4109]: info: CorePlayQueue::saveQueue Aug 19 14:12:05 volumio volumio[4109]: info: CoreCommandRouter::volumioPushState Aug 19 14:12:05 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:12:05 volumio volumio[4109]: info: CoreCommandRouter::volumioPushQueue Aug 19 14:12:05 volumio volumio[4109]: info: CoreStateMachine::addQueueItems Aug 19 14:12:05 volumio volumio[4109]: info: CorePlayQueue::addQueueItems Aug 19 14:12:05 volumio volumio[4109]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99503656 Aug 19 14:12:05 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 19 14:12:05 volumio volumio[4109]: info: CorePlayQueue::saveQueue Aug 19 14:12:05 volumio volumio[4109]: info: CoreCommandRouter::volumioPushQueue Aug 19 14:12:05 volumio volumio[4109]: info: CoreStateMachine::updateTrackBlock Aug 19 14:12:05 volumio volumio[4109]: info: CorePlayQueue::getTrackBlock Aug 19 14:12:05 volumio volumio[4109]: info: CoreCommandRouter::volumioPlay Aug 19 14:12:05 volumio volumio[4109]: verbose: UNSET VOLATILE Aug 19 14:12:05 volumio volumio[4109]: info: CoreStateMachine::play index 0 Aug 19 14:12:05 volumio volumio[4109]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 14:12:05 volumio volumio[4109]: info: CoreStateMachine::stop Aug 19 14:12:05 volumio volumio[4109]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 14:12:05 volumio volumio[4109]: verbose: UNSET VOLATILE Aug 19 14:12:05 volumio volumio[4109]: info: CoreStateMachine::play index undefined Aug 19 14:12:05 volumio volumio[4109]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 14:12:05 volumio volumio[4109]: info: CorePlayQueue::getTrack 0 Aug 19 14:12:05 volumio volumio[4109]: info: CoreStateMachine::startPlaybackTimer Aug 19 14:12:05 volumio volumio[4109]: info: CorePlayQueue::getTrack 0 Aug 19 14:12:05 volumio volumio[4109]: info: [1724076725980] ControllerWebradio::clearAddPlayTrack Aug 19 14:12:05 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand stop Aug 19 14:12:06 volumio volumio[4109]: info: sendMpdCommand stop took 208 milliseconds Aug 19 14:12:06 volumio volumio[4109]: info: Aug 19 14:12:06 volumio volumio[4109]: ---------------------------- MPD announces state update: player Aug 19 14:12:06 volumio volumio[4109]: info: ControllerMpd::getState Aug 19 14:12:06 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand status Aug 19 14:12:06 volumio volumio[4109]: info: sendMpdCommand stop took 90 milliseconds Aug 19 14:12:06 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand clear Aug 19 14:12:06 volumio volumio[4109]: info: sendMpdCommand status took 5 milliseconds Aug 19 14:12:06 volumio volumio[4109]: verbose: ControllerMpd::parseState Aug 19 14:12:06 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 14:12:06 volumio volumio[4109]: info: Aug 19 14:12:06 volumio volumio[4109]: ---------------------------- MPD announces system playlist update Aug 19 14:12:06 volumio volumio[4109]: info: Ignoring MPD Status Update Aug 19 14:12:06 volumio volumio[4109]: info: sendMpdCommand clear took 17 milliseconds Aug 19 14:12:06 volumio volumio[4109]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 19 14:12:06 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99503656" Aug 19 14:12:06 volumio volumio[4109]: verbose: ControllerMpd::parseTrackInfo Aug 19 14:12:06 volumio volumio[4109]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 19 14:12:06 volumio volumio[4109]: info: ------------------------------ 29ms Aug 19 14:12:06 volumio volumio[4109]: error: updateQueue error: null Aug 19 14:12:06 volumio volumio[4109]: info: ------------------------------ 14ms Aug 19 14:12:06 volumio volumio[4109]: info: Aug 19 14:12:06 volumio volumio[4109]: ---------------------------- MPD announces system playlist update Aug 19 14:12:06 volumio volumio[4109]: info: Ignoring MPD Status Update Aug 19 14:12:06 volumio volumio[4109]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99503656" took 251 milliseconds Aug 19 14:12:06 volumio volumio[4109]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 19 14:12:06 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand play Aug 19 14:12:06 volumio volumio[4109]: info: ------------------------------ 7ms Aug 19 14:12:06 volumio volumio[4109]: info: sendMpdCommand play took 6 milliseconds Aug 19 14:12:07 volumio volumio[4109]: info: Aug 19 14:12:07 volumio volumio[4109]: ---------------------------- MPD announces state update: player Aug 19 14:12:07 volumio volumio[4109]: info: ControllerMpd::getState Aug 19 14:12:07 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand status Aug 19 14:12:07 volumio volumio[4109]: info: sendMpdCommand status took 11 milliseconds Aug 19 14:12:07 volumio volumio[4109]: verbose: ControllerMpd::parseState Aug 19 14:12:07 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 14:12:07 volumio volumio[4109]: info: Aug 19 14:12:07 volumio volumio[4109]: ---------------------------- MPD announces state update: player Aug 19 14:12:07 volumio volumio[4109]: info: Aug 19 14:12:07 volumio volumio[4109]: ---------------------------- MPD announces system playlist update Aug 19 14:12:07 volumio volumio[4109]: info: Ignoring MPD Status Update Aug 19 14:12:07 volumio volumio[4109]: info: ControllerMpd::getState Aug 19 14:12:07 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand status Aug 19 14:12:07 volumio volumio[4109]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 19 14:12:07 volumio volumio[4109]: verbose: ControllerMpd::parseTrackInfo Aug 19 14:12:07 volumio volumio[4109]: info: ControllerMpd::pushState Aug 19 14:12:07 volumio volumio[4109]: info: CoreCommandRouter::servicePushState Aug 19 14:12:07 volumio volumio[4109]: info: CorePlayQueue::getTrack 0 Aug 19 14:12:07 volumio volumio[4109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"32 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"","artist":"90.7 Magic FM","album":null,"uri":"http://197.243.19.130:8080/","trackType":"130:8080/"} Aug 19 14:12:07 volumio volumio[4109]: verbose: CURRENT POSITION 0 Aug 19 14:12:07 volumio volumio[4109]: info: CoreStateMachine::syncState stateService play Aug 19 14:12:07 volumio volumio[4109]: info: CoreStateMachine::syncState currentStatus stop Aug 19 14:12:07 volumio volumio[4109]: info: CoreStateMachine::pushState Aug 19 14:12:07 volumio volumio[4109]: info: CoreCommandRouter::volumioPushState Aug 19 14:12:07 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:12:07 volumio volumio[4109]: info: ------------------------------ 69ms Aug 19 14:12:07 volumio volumio[4109]: info: sendMpdCommand status took 51 milliseconds Aug 19 14:12:07 volumio volumio[4109]: info: ------------------------------ 53ms Aug 19 14:12:07 volumio volumio[4109]: verbose: ControllerMpd::parseState Aug 19 14:12:07 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 14:12:07 volumio volumio[4109]: info: sendMpdCommand playlistinfo took 15 milliseconds Aug 19 14:12:07 volumio volumio[4109]: verbose: ControllerMpd::parseTrackInfo Aug 19 14:12:07 volumio volumio[4109]: info: ControllerMpd::pushState Aug 19 14:12:07 volumio volumio[4109]: info: CoreCommandRouter::servicePushState Aug 19 14:12:07 volumio volumio[4109]: info: CorePlayQueue::getTrack 0 Aug 19 14:12:07 volumio volumio[4109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":512,"duration":0,"samplerate":"32 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"","artist":"90.7 Magic FM","album":null,"uri":"http://197.243.19.130:8080/","trackType":"130:8080/"} Aug 19 14:12:07 volumio volumio[4109]: verbose: CURRENT POSITION 0 Aug 19 14:12:07 volumio volumio[4109]: info: CoreStateMachine::syncState stateService play Aug 19 14:12:07 volumio volumio[4109]: info: CoreStateMachine::syncState currentStatus play Aug 19 14:12:07 volumio volumio[4109]: info: Received an update from plugin. extracting info from payload Aug 19 14:12:07 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 19 14:12:07 volumio volumio[4109]: info: CoreStateMachine::pushState Aug 19 14:12:07 volumio volumio[4109]: info: CoreCommandRouter::volumioPushState Aug 19 14:12:07 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:12:07 volumio volumio[4109]: info: CoreStateMachine::pushState Aug 19 14:12:07 volumio volumio[4109]: info: CoreCommandRouter::volumioPushState Aug 19 14:12:07 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:12:07 volumio volumio[4109]: info: ------------------------------ 207ms Aug 19 14:12:18 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 19 14:12:18 volumio volumio[4109]: info: TuneIn handleBrowseUri: tunein/location Aug 19 14:12:39 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 19 14:12:39 volumio volumio[4109]: info: TuneIn handleBrowseUri: tunein/popular Aug 19 14:12:47 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 19 14:12:48 volumio volumio[4109]: info: TuneIn handleBrowseUri: tunein/popular Aug 19 14:12:50 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 19 14:12:50 volumio volumio[4109]: info: TuneIn handleBrowseUri: tunein/best Aug 19 14:12:56 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 19 14:13:02 volumio volumio[4109]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 19 14:13:02 volumio volumio[4109]: info: CoreStateMachine::ClearQueue Aug 19 14:13:02 volumio volumio[4109]: info: CoreStateMachine::stop Aug 19 14:13:02 volumio volumio[4109]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 14:13:02 volumio volumio[4109]: verbose: UNSET VOLATILE Aug 19 14:13:02 volumio volumio[4109]: info: CoreStateMachine::stPlaybackTimer Aug 19 14:13:02 volumio volumio[4109]: info: CoreStateMachine::updateTrackBlock Aug 19 14:13:02 volumio volumio[4109]: info: CorePlayQueue::getTrackBlock Aug 19 14:13:02 volumio volumio[4109]: info: CoreStateMachine::pushState Aug 19 14:13:02 volumio volumio[4109]: info: CorePlayQueue::getTrack 0 Aug 19 14:13:02 volumio volumio[4109]: info: CoreCommandRouter::volumioPushState Aug 19 14:13:02 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:13:02 volumio volumio[4109]: info: CoreStateMachine::serviceStop Aug 19 14:13:02 volumio volumio[4109]: info: CorePlayQueue::getTrack 0 Aug 19 14:13:02 volumio volumio[4109]: info: CoreCommandRouter::serviceStop Aug 19 14:13:02 volumio volumio[4109]: info: [1724076782579] ControllerWebradio::stop Aug 19 14:13:02 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand stop Aug 19 14:13:02 volumio volumio[4109]: info: CorePlayQueue::clearPlayQueue Aug 19 14:13:02 volumio volumio[4109]: info: CorePlayQueue::saveQueue Aug 19 14:13:02 volumio volumio[4109]: info: CoreCommandRouter::volumioPushState Aug 19 14:13:02 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:13:02 volumio volumio[4109]: info: CoreCommandRouter::volumioPushQueue Aug 19 14:13:02 volumio volumio[4109]: info: CoreStateMachine::addQueueItems Aug 19 14:13:02 volumio volumio[4109]: info: CorePlayQueue::addQueueItems Aug 19 14:13:02 volumio volumio[4109]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=140955 Aug 19 14:13:02 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 19 14:13:02 volumio volumio[4109]: info: CorePlayQueue::saveQueue Aug 19 14:13:02 volumio volumio[4109]: info: CoreCommandRouter::volumioPushQueue Aug 19 14:13:02 volumio volumio[4109]: info: CoreStateMachine::updateTrackBlock Aug 19 14:13:02 volumio volumio[4109]: info: CorePlayQueue::getTrackBlock Aug 19 14:13:02 volumio volumio[4109]: info: CoreCommandRouter::volumioPlay Aug 19 14:13:02 volumio volumio[4109]: verbose: UNSET VOLATILE Aug 19 14:13:02 volumio volumio[4109]: info: CoreStateMachine::play index 0 Aug 19 14:13:02 volumio volumio[4109]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 14:13:02 volumio volumio[4109]: info: CoreStateMachine::stop Aug 19 14:13:02 volumio volumio[4109]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 14:13:02 volumio volumio[4109]: verbose: UNSET VOLATILE Aug 19 14:13:02 volumio volumio[4109]: info: CoreStateMachine::play index undefined Aug 19 14:13:02 volumio volumio[4109]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 19 14:13:02 volumio volumio[4109]: info: CorePlayQueue::getTrack 0 Aug 19 14:13:02 volumio volumio[4109]: info: CoreStateMachine::startPlaybackTimer Aug 19 14:13:02 volumio volumio[4109]: info: CorePlayQueue::getTrack 0 Aug 19 14:13:02 volumio volumio[4109]: info: [1724076782647] ControllerWebradio::clearAddPlayTrack Aug 19 14:13:02 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand stop Aug 19 14:13:02 volumio volumio[4109]: info: Aug 19 14:13:02 volumio volumio[4109]: ---------------------------- MPD announces state update: player Aug 19 14:13:02 volumio volumio[4109]: info: sendMpdCommand stop took 139 milliseconds Aug 19 14:13:02 volumio volumio[4109]: info: ControllerMpd::getState Aug 19 14:13:02 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand status Aug 19 14:13:02 volumio volumio[4109]: info: sendMpdCommand stop took 72 milliseconds Aug 19 14:13:02 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand clear Aug 19 14:13:02 volumio volumio[4109]: info: Aug 19 14:13:02 volumio volumio[4109]: ---------------------------- MPD announces system playlist update Aug 19 14:13:02 volumio volumio[4109]: info: Ignoring MPD Status Update Aug 19 14:13:02 volumio volumio[4109]: info: sendMpdCommand status took 8 milliseconds Aug 19 14:13:02 volumio volumio[4109]: info: sendMpdCommand clear took 4 milliseconds Aug 19 14:13:02 volumio volumio[4109]: verbose: ControllerMpd::parseState Aug 19 14:13:02 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 14:13:02 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=140955" Aug 19 14:13:02 volumio volumio[4109]: error: updateQueue error: null Aug 19 14:13:02 volumio volumio[4109]: info: ------------------------------ 10ms Aug 19 14:13:02 volumio volumio[4109]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 19 14:13:02 volumio volumio[4109]: verbose: ControllerMpd::parseTrackInfo Aug 19 14:13:02 volumio volumio[4109]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 19 14:13:02 volumio volumio[4109]: info: ------------------------------ 22ms Aug 19 14:13:03 volumio volumio[4109]: info: Aug 19 14:13:03 volumio volumio[4109]: ---------------------------- MPD announces system playlist update Aug 19 14:13:03 volumio volumio[4109]: info: Ignoring MPD Status Update Aug 19 14:13:03 volumio volumio[4109]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=140955" took 565 milliseconds Aug 19 14:13:03 volumio volumio[4109]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 19 14:13:03 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand play Aug 19 14:13:03 volumio volumio[4109]: info: ------------------------------ 12ms Aug 19 14:13:03 volumio volumio[4109]: info: sendMpdCommand play took 12 milliseconds Aug 19 14:13:06 volumio volumio[4109]: info: Aug 19 14:13:06 volumio volumio[4109]: ---------------------------- MPD announces state update: player Aug 19 14:13:06 volumio volumio[4109]: info: ControllerMpd::getState Aug 19 14:13:06 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand status Aug 19 14:13:06 volumio volumio[4109]: info: sendMpdCommand status took 9 milliseconds Aug 19 14:13:06 volumio volumio[4109]: verbose: ControllerMpd::parseState Aug 19 14:13:06 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 14:13:06 volumio volumio[4109]: info: Aug 19 14:13:06 volumio volumio[4109]: ---------------------------- MPD announces state update: player Aug 19 14:13:06 volumio volumio[4109]: info: Aug 19 14:13:06 volumio volumio[4109]: ---------------------------- MPD announces system playlist update Aug 19 14:13:06 volumio volumio[4109]: info: Ignoring MPD Status Update Aug 19 14:13:06 volumio volumio[4109]: info: ControllerMpd::getState Aug 19 14:13:06 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand status Aug 19 14:13:06 volumio volumio[4109]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 19 14:13:06 volumio volumio[4109]: verbose: ControllerMpd::parseTrackInfo Aug 19 14:13:06 volumio volumio[4109]: info: ControllerMpd::pushState Aug 19 14:13:06 volumio volumio[4109]: info: CoreCommandRouter::servicePushState Aug 19 14:13:06 volumio volumio[4109]: info: CorePlayQueue::getTrack 0 Aug 19 14:13:06 volumio volumio[4109]: 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":"128 Kbps","isStreaming":false,"title":"Radio-Mozart","artist":"Radio Mozart","album":null,"uri":"http://listen.radionomy.com/Radio-Mozart","trackType":""} Aug 19 14:13:06 volumio volumio[4109]: verbose: CURRENT POSITION 0 Aug 19 14:13:06 volumio volumio[4109]: info: CoreStateMachine::syncState stateService play Aug 19 14:13:06 volumio volumio[4109]: info: CoreStateMachine::syncState currentStatus stop Aug 19 14:13:06 volumio volumio[4109]: info: CoreStateMachine::pushState Aug 19 14:13:06 volumio volumio[4109]: info: CoreCommandRouter::volumioPushState Aug 19 14:13:06 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:13:06 volumio volumio[4109]: info: ------------------------------ 56ms Aug 19 14:13:06 volumio volumio[4109]: info: sendMpdCommand status took 42 milliseconds Aug 19 14:13:06 volumio volumio[4109]: info: ------------------------------ 44ms Aug 19 14:13:06 volumio volumio[4109]: verbose: ControllerMpd::parseState Aug 19 14:13:06 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 14:13:06 volumio volumio[4109]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 19 14:13:06 volumio volumio[4109]: verbose: ControllerMpd::parseTrackInfo Aug 19 14:13:06 volumio volumio[4109]: info: ControllerMpd::pushState Aug 19 14:13:06 volumio volumio[4109]: info: CoreCommandRouter::servicePushState Aug 19 14:13:06 volumio volumio[4109]: info: CorePlayQueue::getTrack 0 Aug 19 14:13:06 volumio volumio[4109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio-Mozart","artist":"Radio Mozart","album":null,"uri":"http://listen.radionomy.com/Radio-Mozart","trackType":""} Aug 19 14:13:06 volumio volumio[4109]: verbose: CURRENT POSITION 0 Aug 19 14:13:06 volumio volumio[4109]: info: CoreStateMachine::syncState stateService play Aug 19 14:13:06 volumio volumio[4109]: info: CoreStateMachine::syncState currentStatus play Aug 19 14:13:06 volumio volumio[4109]: info: Received an update from plugin. extracting info from payload Aug 19 14:13:06 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 19 14:13:06 volumio volumio[4109]: info: CoreStateMachine::pushState Aug 19 14:13:06 volumio volumio[4109]: info: CoreCommandRouter::volumioPushState Aug 19 14:13:06 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:13:06 volumio volumio[4109]: info: CoreStateMachine::pushState Aug 19 14:13:06 volumio volumio[4109]: info: CoreCommandRouter::volumioPushState Aug 19 14:13:06 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:13:06 volumio volumio[4109]: info: ------------------------------ 118ms Aug 19 14:13:06 volumio volumio[4109]: info: Aug 19 14:13:06 volumio volumio[4109]: ---------------------------- MPD announces system playlist update Aug 19 14:13:06 volumio volumio[4109]: info: Ignoring MPD Status Update Aug 19 14:13:06 volumio volumio[4109]: info: Aug 19 14:13:06 volumio volumio[4109]: ---------------------------- MPD announces state update: player Aug 19 14:13:06 volumio volumio[4109]: info: ControllerMpd::getState Aug 19 14:13:06 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand status Aug 19 14:13:06 volumio volumio[4109]: info: ------------------------------ 8ms Aug 19 14:13:06 volumio volumio[4109]: info: sendMpdCommand status took 6 milliseconds Aug 19 14:13:06 volumio volumio[4109]: verbose: ControllerMpd::parseState Aug 19 14:13:06 volumio volumio[4109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 19 14:13:06 volumio volumio[4109]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 19 14:13:06 volumio volumio[4109]: verbose: ControllerMpd::parseTrackInfo Aug 19 14:13:06 volumio volumio[4109]: info: ControllerMpd::pushState Aug 19 14:13:06 volumio volumio[4109]: info: CoreCommandRouter::servicePushState Aug 19 14:13:06 volumio volumio[4109]: info: CorePlayQueue::getTrack 0 Aug 19 14:13:06 volumio volumio[4109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":847,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Christoph Eschenbach/Justus Frantz/Franz Christian Wulff/Franz Christian Wulff/Karl-August Naegler/Karl-August Naegler/Jobst Eberhardt/Jobst Eberhardt - Sonata for 2 pianos in D, K.448: 1. Allegro con spirito","artist":"Radio Mozart","album":null,"uri":"http://listen.radionomy.com/Radio-Mozart","trackType":""} Aug 19 14:13:06 volumio volumio[4109]: verbose: CURRENT POSITION 0 Aug 19 14:13:06 volumio volumio[4109]: info: CoreStateMachine::syncState stateService play Aug 19 14:13:06 volumio volumio[4109]: info: CoreStateMachine::syncState currentStatus play Aug 19 14:13:06 volumio volumio[4109]: info: Received an update from plugin. extracting info from payload Aug 19 14:13:06 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 19 14:13:06 volumio volumio[4109]: info: CoreStateMachine::pushState Aug 19 14:13:06 volumio volumio[4109]: info: CoreCommandRouter::volumioPushState Aug 19 14:13:06 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:13:07 volumio volumio[4109]: info: CoreStateMachine::pushState Aug 19 14:13:07 volumio volumio[4109]: info: CoreCommandRouter::volumioPushState Aug 19 14:13:07 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 14:13:07 volumio volumio[4109]: info: ------------------------------ 81ms Aug 19 14:13:19 volumio volumio[4109]: info: Saving local item radio-favourites Aug 19 14:13:19 volumio volumio[4109]: info: Pushing Favourites {"uri":"http://listen.radionomy.com/Radio-Mozart","favourite":false} Aug 19 14:13:34 volumio sudo[5325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 19 14:13:34 volumio sudo[5325]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 14:13:34 volumio sudo[5325]: pam_unix(sudo:session): session closed for user root Aug 19 14:13:34 volumio sudo[5327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 19 14:13:34 volumio sudo[5327]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 14:13:34 volumio sudo[5327]: pam_unix(sudo:session): session closed for user root Aug 19 14:13:34 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 19 14:13:34 volumio volumio[4109]: info: CoreCommandRouter::volumioGetState Aug 19 14:13:34 volumio volumio[4109]: info: Listing playlists Aug 19 14:13:34 volumio volumio[4109]: info: CoreCommandRouter::volumioGetVisibleSources Aug 19 14:13:34 volumio volumio[4109]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 19 14:13:34 volumio volumio[4109]: info: CoreCommandRouter::volumioGetQueue Aug 19 14:13:34 volumio volumio[4109]: info: CoreStateMachine::getQueue Aug 19 14:13:34 volumio volumio[4109]: info: CorePlayQueue::getQueue Aug 19 14:13:51 volumio volumio[4109]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 14:13:51 volumio volumio[4109]: { Error: connect ETIMEDOUT 103.230.123.190:80 Aug 19 14:13:51 volumio volumio[4109]: at Object._errnoException (util.js:1022:11) Aug 19 14:13:51 volumio volumio[4109]: at _exceptionWithHostPort (util.js:1044:20) Aug 19 14:13:51 volumio volumio[4109]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Aug 19 14:13:51 volumio volumio[4109]: code: 'ETIMEDOUT', Aug 19 14:13:51 volumio volumio[4109]: errno: 'ETIMEDOUT', Aug 19 14:13:51 volumio volumio[4109]: syscall: 'connect', Aug 19 14:13:51 volumio volumio[4109]: address: '103.230.123.190', Aug 19 14:13:51 volumio volumio[4109]: port: 80 } Aug 19 14:13:51 volumio volumio[4109]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 14:13:52 volumio sudo[5379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-19 14:12 Aug 19 14:13:52 volumio sudo[5379]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"