-- Logs begin at Sun 2024-04-14 12:59:42 UTC, end at Mon 2024-04-15 05:03:01 UTC. -- Apr 15 05:02:23 volumio volumio[5034]: info: CoreCommandRouter::volumioNext Apr 15 05:02:23 volumio volumio[5034]: info: CoreStateMachine::next Apr 15 05:02:23 volumio volumio[5034]: info: CoreStateMachine::stop Apr 15 05:02:23 volumio volumio[5034]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 05:02:23 volumio volumio[5034]: UNSET VOLATILE Apr 15 05:02:23 volumio volumio[5034]: info: CoreStateMachine::stPlaybackTimer Apr 15 05:02:23 volumio volumio[5034]: info: CoreStateMachine::updateTrackBlock Apr 15 05:02:23 volumio volumio[5034]: info: CorePlayQueue::getTrackBlock Apr 15 05:02:23 volumio volumio[5034]: info: CoreStateMachine::pushState Apr 15 05:02:23 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:23 volumio volumio[5034]: info: CoreCommandRouter::volumioPushState Apr 15 05:02:23 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 05:02:23 volumio volumio[5034]: info: CoreStateMachine::serviceStop Apr 15 05:02:23 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:23 volumio volumio[5034]: info: CoreCommandRouter::serviceStop Apr 15 05:02:23 volumio volumio[5034]: info: [1713157343630] ControllerWebradio::stop Apr 15 05:02:23 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 05:02:23 volumio volumio[5034]: info: Apr 15 05:02:23 volumio volumio[5034]: ---------------------------- MPD announces state update: player Apr 15 05:02:23 volumio volumio[5034]: info: ControllerMpd::getState Apr 15 05:02:23 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand status Apr 15 05:02:23 volumio volumio[5034]: info: CoreStateMachine::play index undefined Apr 15 05:02:23 volumio volumio[5034]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 05:02:23 volumio volumio[5034]: info: CorePlayQueue::getTrack 1 Apr 15 05:02:23 volumio volumio[5034]: verbose: ControllerMpd::parseState Apr 15 05:02:23 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 05:02:23 volumio volumio[5034]: verbose: ControllerMpd::parseTrackInfo Apr 15 05:02:23 volumio volumio[5034]: info: ControllerMpd::pushState Apr 15 05:02:23 volumio volumio[5034]: info: CoreCommandRouter::servicePushState Apr 15 05:02:23 volumio volumio[5034]: info: CoreStateMachine::pushState Apr 15 05:02:23 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:23 volumio volumio[5034]: info: CoreCommandRouter::volumioPushState Apr 15 05:02:23 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 05:02:23 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:23 volumio volumio[5034]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 15 05:02:23 volumio volumio[5034]: info: ------------------------------ 17ms Apr 15 05:02:28 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 05:02:28 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 05:02:28 volumio volumio[5034]: info: Discovery: Getting this device information Apr 15 05:02:28 volumio volumio[5034]: info: CoreCommandRouter::volumioGetState Apr 15 05:02:28 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:28 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Apr 15 05:02:28 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 15 05:02:28 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 15 05:02:29 volumio volumio[5034]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 15 05:02:29 volumio volumio[5034]: info: CoreStateMachine::ClearQueue Apr 15 05:02:29 volumio volumio[5034]: info: CoreStateMachine::stop Apr 15 05:02:29 volumio volumio[5034]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 05:02:29 volumio volumio[5034]: UNSET VOLATILE Apr 15 05:02:29 volumio volumio[5034]: info: CorePlayQueue::clearPlayQueue Apr 15 05:02:29 volumio volumio[5034]: info: CorePlayQueue::saveQueue Apr 15 05:02:29 volumio volumio[5034]: info: CoreCommandRouter::volumioPushState Apr 15 05:02:29 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 05:02:29 volumio volumio[5034]: info: CoreCommandRouter::volumioPushQueue Apr 15 05:02:29 volumio volumio[5034]: info: CoreStateMachine::addQueueItems Apr 15 05:02:29 volumio volumio[5034]: info: CorePlayQueue::addQueueItems Apr 15 05:02:29 volumio volumio[5034]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1854583 Apr 15 05:02:29 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 15 05:02:29 volumio volumio[5034]: info: CorePlayQueue::saveQueue Apr 15 05:02:29 volumio volumio[5034]: info: CoreCommandRouter::volumioPushQueue Apr 15 05:02:29 volumio volumio[5034]: info: CoreStateMachine::updateTrackBlock Apr 15 05:02:29 volumio volumio[5034]: info: CorePlayQueue::getTrackBlock Apr 15 05:02:29 volumio volumio[5034]: info: CoreCommandRouter::volumioPlay Apr 15 05:02:29 volumio volumio[5034]: UNSET VOLATILE Apr 15 05:02:29 volumio volumio[5034]: info: CoreStateMachine::play index 0 Apr 15 05:02:29 volumio volumio[5034]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 05:02:29 volumio volumio[5034]: info: CoreStateMachine::stop Apr 15 05:02:29 volumio volumio[5034]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 05:02:29 volumio volumio[5034]: UNSET VOLATILE Apr 15 05:02:29 volumio volumio[5034]: info: CoreStateMachine::play index undefined Apr 15 05:02:29 volumio volumio[5034]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 05:02:29 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:29 volumio volumio[5034]: info: CoreStateMachine::startPlaybackTimer Apr 15 05:02:29 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:29 volumio volumio[5034]: info: [1713157349603] ControllerWebradio::clearAddPlayTrack Apr 15 05:02:29 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 05:02:29 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand clear Apr 15 05:02:29 volumio volumio[5034]: info: Apr 15 05:02:29 volumio volumio[5034]: ---------------------------- MPD announces system playlist update Apr 15 05:02:29 volumio volumio[5034]: info: Ignoring MPD Status Update Apr 15 05:02:29 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1854583" Apr 15 05:02:29 volumio volumio[5034]: error: updateQueue error: null Apr 15 05:02:29 volumio volumio[5034]: info: ------------------------------ 4ms Apr 15 05:02:39 volumio volumio[5034]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 15 05:02:39 volumio volumio[5034]: info: CoreStateMachine::ClearQueue Apr 15 05:02:39 volumio volumio[5034]: info: CoreStateMachine::stop Apr 15 05:02:39 volumio volumio[5034]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 05:02:39 volumio volumio[5034]: UNSET VOLATILE Apr 15 05:02:39 volumio volumio[5034]: info: CorePlayQueue::clearPlayQueue Apr 15 05:02:39 volumio volumio[5034]: info: CorePlayQueue::saveQueue Apr 15 05:02:39 volumio volumio[5034]: info: CoreCommandRouter::volumioPushState Apr 15 05:02:39 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 05:02:39 volumio volumio[5034]: info: CoreCommandRouter::volumioPushQueue Apr 15 05:02:39 volumio volumio[5034]: info: CoreStateMachine::addQueueItems Apr 15 05:02:39 volumio volumio[5034]: info: CorePlayQueue::addQueueItems Apr 15 05:02:39 volumio volumio[5034]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99503708 Apr 15 05:02:39 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 15 05:02:39 volumio volumio[5034]: info: CorePlayQueue::saveQueue Apr 15 05:02:39 volumio volumio[5034]: info: CoreCommandRouter::volumioPushQueue Apr 15 05:02:39 volumio volumio[5034]: info: CoreStateMachine::updateTrackBlock Apr 15 05:02:39 volumio volumio[5034]: info: CorePlayQueue::getTrackBlock Apr 15 05:02:39 volumio volumio[5034]: info: CoreCommandRouter::volumioPlay Apr 15 05:02:39 volumio volumio[5034]: UNSET VOLATILE Apr 15 05:02:39 volumio volumio[5034]: info: CoreStateMachine::play index 0 Apr 15 05:02:39 volumio volumio[5034]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 05:02:39 volumio volumio[5034]: info: CoreStateMachine::stop Apr 15 05:02:39 volumio volumio[5034]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 05:02:39 volumio volumio[5034]: UNSET VOLATILE Apr 15 05:02:39 volumio volumio[5034]: info: CoreStateMachine::play index undefined Apr 15 05:02:39 volumio volumio[5034]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 05:02:39 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:39 volumio volumio[5034]: info: CoreStateMachine::startPlaybackTimer Apr 15 05:02:39 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:39 volumio volumio[5034]: info: [1713157359763] ControllerWebradio::clearAddPlayTrack Apr 15 05:02:39 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 05:02:40 volumio volumio[5034]: info: Apr 15 05:02:40 volumio volumio[5034]: ---------------------------- MPD announces system playlist update Apr 15 05:02:40 volumio volumio[5034]: info: Ignoring MPD Status Update Apr 15 05:02:40 volumio volumio[5034]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 15 05:02:40 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand play Apr 15 05:02:40 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand clear Apr 15 05:02:40 volumio volumio[5034]: info: Apr 15 05:02:40 volumio volumio[5034]: ---------------------------- MPD announces system playlist update Apr 15 05:02:40 volumio volumio[5034]: info: Ignoring MPD Status Update Apr 15 05:02:40 volumio volumio[5034]: info: Apr 15 05:02:40 volumio volumio[5034]: ---------------------------- MPD announces state update: player Apr 15 05:02:40 volumio volumio[5034]: info: ------------------------------ 8ms Apr 15 05:02:40 volumio volumio[5034]: info: ControllerMpd::getState Apr 15 05:02:40 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand status Apr 15 05:02:40 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99503708" Apr 15 05:02:40 volumio volumio[5034]: error: updateQueue error: null Apr 15 05:02:40 volumio volumio[5034]: info: ------------------------------ 4ms Apr 15 05:02:40 volumio volumio[5034]: verbose: ControllerMpd::parseState Apr 15 05:02:40 volumio volumio[5034]: info: ControllerMpd::pushState Apr 15 05:02:40 volumio volumio[5034]: info: CoreCommandRouter::servicePushState Apr 15 05:02:40 volumio volumio[5034]: info: CoreStateMachine::pushState Apr 15 05:02:40 volumio volumio[5034]: info: CoreCommandRouter::volumioPushState Apr 15 05:02:40 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 05:02:40 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:40 volumio volumio[5034]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 15 05:02:40 volumio volumio[5034]: verbose: CURRENT POSITION 0 Apr 15 05:02:40 volumio volumio[5034]: info: CoreStateMachine::syncState stateService stop Apr 15 05:02:40 volumio volumio[5034]: info: CoreStateMachine::syncState currentStatus stop Apr 15 05:02:40 volumio volumio[5034]: info: CoreStateMachine::pushState Apr 15 05:02:40 volumio volumio[5034]: info: CoreCommandRouter::volumioPushState Apr 15 05:02:40 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 05:02:40 volumio volumio[5034]: info: No code Apr 15 05:02:40 volumio volumio[5034]: info: CoreStateMachine::pushState Apr 15 05:02:40 volumio volumio[5034]: info: CoreCommandRouter::volumioPushState Apr 15 05:02:40 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 05:02:40 volumio volumio[5034]: info: ------------------------------ 22ms Apr 15 05:02:42 volumio volumio[5034]: info: Apr 15 05:02:42 volumio volumio[5034]: ---------------------------- MPD announces system playlist update Apr 15 05:02:42 volumio volumio[5034]: info: Ignoring MPD Status Update Apr 15 05:02:42 volumio volumio[5034]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 15 05:02:42 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand play Apr 15 05:02:42 volumio volumio[5034]: info: ------------------------------ 5ms Apr 15 05:02:44 volumio volumio[5034]: info: Apr 15 05:02:44 volumio volumio[5034]: ---------------------------- MPD announces state update: player Apr 15 05:02:44 volumio volumio[5034]: info: ControllerMpd::getState Apr 15 05:02:44 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand status Apr 15 05:02:44 volumio volumio[5034]: info: Apr 15 05:02:44 volumio volumio[5034]: ---------------------------- MPD announces state update: player Apr 15 05:02:44 volumio volumio[5034]: info: Apr 15 05:02:44 volumio volumio[5034]: ---------------------------- MPD announces system playlist update Apr 15 05:02:44 volumio volumio[5034]: info: Ignoring MPD Status Update Apr 15 05:02:44 volumio volumio[5034]: info: ControllerMpd::getState Apr 15 05:02:44 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand status Apr 15 05:02:44 volumio volumio[5034]: verbose: ControllerMpd::parseState Apr 15 05:02:44 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 05:02:44 volumio volumio[5034]: info: ------------------------------ 4ms Apr 15 05:02:44 volumio volumio[5034]: verbose: ControllerMpd::parseState Apr 15 05:02:44 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 05:02:44 volumio volumio[5034]: verbose: ControllerMpd::parseTrackInfo Apr 15 05:02:44 volumio volumio[5034]: info: ControllerMpd::pushState Apr 15 05:02:44 volumio volumio[5034]: info: CoreCommandRouter::servicePushState Apr 15 05:02:44 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:44 volumio volumio[5034]: 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":"stream","artist":"Classic Hits 109 - 70s,80s,90s","album":null,"uri":"https://jenny.torontocast.com:8142/stream","trackType":""} Apr 15 05:02:44 volumio volumio[5034]: verbose: CURRENT POSITION 0 Apr 15 05:02:44 volumio volumio[5034]: info: CoreStateMachine::syncState stateService play Apr 15 05:02:44 volumio volumio[5034]: info: CoreStateMachine::syncState currentStatus stop Apr 15 05:02:44 volumio volumio[5034]: info: CoreStateMachine::pushState Apr 15 05:02:44 volumio volumio[5034]: info: CoreCommandRouter::volumioPushState Apr 15 05:02:44 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 05:02:44 volumio volumio[5034]: info: ------------------------------ 22ms Apr 15 05:02:44 volumio volumio[5034]: verbose: ControllerMpd::parseTrackInfo Apr 15 05:02:44 volumio volumio[5034]: info: ControllerMpd::pushState Apr 15 05:02:44 volumio volumio[5034]: info: CoreCommandRouter::servicePushState Apr 15 05:02:44 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:44 volumio volumio[5034]: 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":"stream","artist":"Classic Hits 109 - 70s,80s,90s","album":null,"uri":"https://jenny.torontocast.com:8142/stream","trackType":""} Apr 15 05:02:44 volumio volumio[5034]: verbose: CURRENT POSITION 0 Apr 15 05:02:44 volumio volumio[5034]: info: CoreStateMachine::syncState stateService play Apr 15 05:02:44 volumio volumio[5034]: info: CoreStateMachine::syncState currentStatus play Apr 15 05:02:44 volumio volumio[5034]: info: Received an update from plugin. extracting info from payload Apr 15 05:02:44 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 15 05:02:44 volumio volumio[5034]: info: CoreStateMachine::pushState Apr 15 05:02:44 volumio volumio[5034]: info: CoreCommandRouter::volumioPushState Apr 15 05:02:44 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 05:02:44 volumio volumio[5034]: info: CoreStateMachine::pushState Apr 15 05:02:44 volumio volumio[5034]: info: CoreCommandRouter::volumioPushState Apr 15 05:02:44 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 05:02:44 volumio volumio[5034]: info: ------------------------------ 40ms Apr 15 05:02:44 volumio volumio[5034]: info: Apr 15 05:02:44 volumio volumio[5034]: ---------------------------- MPD announces system playlist update Apr 15 05:02:44 volumio volumio[5034]: info: Ignoring MPD Status Update Apr 15 05:02:44 volumio volumio[5034]: info: Apr 15 05:02:44 volumio volumio[5034]: ---------------------------- MPD announces state update: player Apr 15 05:02:44 volumio volumio[5034]: info: ControllerMpd::getState Apr 15 05:02:44 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand status Apr 15 05:02:44 volumio volumio[5034]: info: ------------------------------ 10ms Apr 15 05:02:44 volumio volumio[5034]: verbose: ControllerMpd::parseState Apr 15 05:02:44 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 05:02:44 volumio volumio[5034]: verbose: ControllerMpd::parseTrackInfo Apr 15 05:02:44 volumio volumio[5034]: info: ControllerMpd::pushState Apr 15 05:02:44 volumio volumio[5034]: info: CoreCommandRouter::servicePushState Apr 15 05:02:44 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:44 volumio volumio[5034]: verbose: STATE SERVICE {"status":"play","position":0,"seek":603,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Alanis Morissette - Ironic","artist":"Classic Hits 109 - 70s,80s,90s","album":null,"uri":"https://jenny.torontocast.com:8142/stream","trackType":""} Apr 15 05:02:44 volumio volumio[5034]: verbose: CURRENT POSITION 0 Apr 15 05:02:44 volumio volumio[5034]: info: CoreStateMachine::syncState stateService play Apr 15 05:02:44 volumio volumio[5034]: info: CoreStateMachine::syncState currentStatus play Apr 15 05:02:44 volumio volumio[5034]: info: Received an update from plugin. extracting info from payload Apr 15 05:02:44 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 15 05:02:44 volumio volumio[5034]: info: CoreStateMachine::pushState Apr 15 05:02:44 volumio volumio[5034]: info: CoreCommandRouter::volumioPushState Apr 15 05:02:44 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 05:02:44 volumio volumio[5034]: info: CoreStateMachine::pushState Apr 15 05:02:44 volumio volumio[5034]: info: CoreCommandRouter::volumioPushState Apr 15 05:02:44 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 05:02:44 volumio volumio[5034]: info: ------------------------------ 21ms Apr 15 05:02:47 volumio volumio[5034]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 15 05:02:47 volumio volumio[5034]: info: CoreStateMachine::ClearQueue Apr 15 05:02:47 volumio volumio[5034]: info: CoreStateMachine::stop Apr 15 05:02:47 volumio volumio[5034]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 05:02:47 volumio volumio[5034]: UNSET VOLATILE Apr 15 05:02:47 volumio volumio[5034]: info: CoreStateMachine::stPlaybackTimer Apr 15 05:02:47 volumio volumio[5034]: info: CoreStateMachine::updateTrackBlock Apr 15 05:02:47 volumio volumio[5034]: info: CorePlayQueue::getTrackBlock Apr 15 05:02:47 volumio volumio[5034]: info: CoreStateMachine::pushState Apr 15 05:02:47 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:47 volumio volumio[5034]: info: CoreCommandRouter::volumioPushState Apr 15 05:02:47 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 05:02:47 volumio volumio[5034]: info: CoreStateMachine::serviceStop Apr 15 05:02:47 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:47 volumio volumio[5034]: info: CoreCommandRouter::serviceStop Apr 15 05:02:47 volumio volumio[5034]: info: [1713157367609] ControllerWebradio::stop Apr 15 05:02:47 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 05:02:47 volumio volumio[5034]: info: CorePlayQueue::clearPlayQueue Apr 15 05:02:47 volumio volumio[5034]: info: CorePlayQueue::saveQueue Apr 15 05:02:47 volumio volumio[5034]: info: CoreCommandRouter::volumioPushState Apr 15 05:02:47 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 05:02:47 volumio volumio[5034]: info: CoreCommandRouter::volumioPushQueue Apr 15 05:02:47 volumio volumio[5034]: info: CoreStateMachine::addQueueItems Apr 15 05:02:47 volumio volumio[5034]: info: CorePlayQueue::addQueueItems Apr 15 05:02:47 volumio volumio[5034]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99500312 Apr 15 05:02:47 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 15 05:02:47 volumio volumio[5034]: info: CorePlayQueue::saveQueue Apr 15 05:02:47 volumio volumio[5034]: info: CoreCommandRouter::volumioPushQueue Apr 15 05:02:47 volumio volumio[5034]: info: CoreStateMachine::updateTrackBlock Apr 15 05:02:47 volumio volumio[5034]: info: CorePlayQueue::getTrackBlock Apr 15 05:02:47 volumio volumio[5034]: info: CoreCommandRouter::volumioPlay Apr 15 05:02:47 volumio volumio[5034]: UNSET VOLATILE Apr 15 05:02:47 volumio volumio[5034]: info: CoreStateMachine::play index 0 Apr 15 05:02:47 volumio volumio[5034]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 05:02:47 volumio volumio[5034]: info: CoreStateMachine::stop Apr 15 05:02:47 volumio volumio[5034]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 05:02:47 volumio volumio[5034]: UNSET VOLATILE Apr 15 05:02:47 volumio volumio[5034]: info: CoreStateMachine::play index undefined Apr 15 05:02:47 volumio volumio[5034]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 05:02:47 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:47 volumio volumio[5034]: info: CoreStateMachine::startPlaybackTimer Apr 15 05:02:47 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:47 volumio volumio[5034]: info: [1713157367625] ControllerWebradio::clearAddPlayTrack Apr 15 05:02:47 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 05:02:47 volumio volumio[5034]: info: Apr 15 05:02:47 volumio volumio[5034]: ---------------------------- MPD announces state update: player Apr 15 05:02:47 volumio volumio[5034]: info: ControllerMpd::getState Apr 15 05:02:47 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand status Apr 15 05:02:47 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand clear Apr 15 05:02:47 volumio volumio[5034]: info: Apr 15 05:02:47 volumio volumio[5034]: ---------------------------- MPD announces system playlist update Apr 15 05:02:47 volumio volumio[5034]: info: Ignoring MPD Status Update Apr 15 05:02:47 volumio volumio[5034]: verbose: ControllerMpd::parseState Apr 15 05:02:47 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 05:02:47 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99500312" Apr 15 05:02:47 volumio volumio[5034]: error: updateQueue error: null Apr 15 05:02:47 volumio volumio[5034]: info: ------------------------------ 4ms Apr 15 05:02:47 volumio volumio[5034]: verbose: ControllerMpd::parseTrackInfo Apr 15 05:02:47 volumio volumio[5034]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 15 05:02:47 volumio volumio[5034]: info: ------------------------------ 9ms Apr 15 05:02:48 volumio volumio[5034]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 15 05:02:48 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand play Apr 15 05:02:48 volumio volumio[5034]: info: Apr 15 05:02:48 volumio volumio[5034]: ---------------------------- MPD announces system playlist update Apr 15 05:02:48 volumio volumio[5034]: info: Ignoring MPD Status Update Apr 15 05:02:48 volumio volumio[5034]: info: ------------------------------ 4ms Apr 15 05:02:51 volumio volumio[5034]: info: Apr 15 05:02:51 volumio volumio[5034]: ---------------------------- MPD announces state update: player Apr 15 05:02:51 volumio volumio[5034]: info: ControllerMpd::getState Apr 15 05:02:51 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand status Apr 15 05:02:51 volumio volumio[5034]: info: Apr 15 05:02:51 volumio volumio[5034]: ---------------------------- MPD announces system playlist update Apr 15 05:02:51 volumio volumio[5034]: info: Ignoring MPD Status Update Apr 15 05:02:51 volumio volumio[5034]: info: Apr 15 05:02:51 volumio volumio[5034]: ---------------------------- MPD announces state update: player Apr 15 05:02:51 volumio volumio[5034]: info: ControllerMpd::getState Apr 15 05:02:51 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand status Apr 15 05:02:51 volumio volumio[5034]: verbose: ControllerMpd::parseState Apr 15 05:02:51 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 05:02:51 volumio volumio[5034]: info: ------------------------------ 9ms Apr 15 05:02:51 volumio volumio[5034]: verbose: ControllerMpd::parseState Apr 15 05:02:51 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 05:02:51 volumio volumio[5034]: verbose: ControllerMpd::parseTrackInfo Apr 15 05:02:51 volumio volumio[5034]: info: ControllerMpd::pushState Apr 15 05:02:51 volumio volumio[5034]: info: CoreCommandRouter::servicePushState Apr 15 05:02:51 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:51 volumio volumio[5034]: 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":"","artist":"Asia DREAM Radio - Japan Hits","album":null,"uri":"https://cast1.torontocast.com:2120/","trackType":"com:2120/"} Apr 15 05:02:51 volumio volumio[5034]: verbose: CURRENT POSITION 0 Apr 15 05:02:51 volumio volumio[5034]: info: CoreStateMachine::syncState stateService play Apr 15 05:02:51 volumio volumio[5034]: info: CoreStateMachine::syncState currentStatus stop Apr 15 05:02:51 volumio volumio[5034]: info: CoreStateMachine::pushState Apr 15 05:02:51 volumio volumio[5034]: info: CoreCommandRouter::volumioPushState Apr 15 05:02:51 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 05:02:51 volumio volumio[5034]: info: ------------------------------ 33ms Apr 15 05:02:51 volumio volumio[5034]: verbose: ControllerMpd::parseTrackInfo Apr 15 05:02:51 volumio volumio[5034]: info: ControllerMpd::pushState Apr 15 05:02:51 volumio volumio[5034]: info: CoreCommandRouter::servicePushState Apr 15 05:02:51 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:51 volumio volumio[5034]: 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":"","artist":"Asia DREAM Radio - Japan Hits","album":null,"uri":"https://cast1.torontocast.com:2120/","trackType":"com:2120/"} Apr 15 05:02:51 volumio volumio[5034]: verbose: CURRENT POSITION 0 Apr 15 05:02:51 volumio volumio[5034]: info: CoreStateMachine::syncState stateService play Apr 15 05:02:51 volumio volumio[5034]: info: CoreStateMachine::syncState currentStatus play Apr 15 05:02:51 volumio volumio[5034]: info: Received an update from plugin. extracting info from payload Apr 15 05:02:51 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 15 05:02:51 volumio volumio[5034]: info: CoreStateMachine::pushState Apr 15 05:02:51 volumio volumio[5034]: info: CoreCommandRouter::volumioPushState Apr 15 05:02:51 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 05:02:51 volumio volumio[5034]: info: CoreStateMachine::pushState Apr 15 05:02:51 volumio volumio[5034]: info: CoreCommandRouter::volumioPushState Apr 15 05:02:51 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 05:02:51 volumio volumio[5034]: info: ------------------------------ 39ms Apr 15 05:02:52 volumio volumio[5034]: info: Apr 15 05:02:52 volumio volumio[5034]: ---------------------------- MPD announces system playlist update Apr 15 05:02:52 volumio volumio[5034]: info: Ignoring MPD Status Update Apr 15 05:02:52 volumio volumio[5034]: info: Apr 15 05:02:52 volumio volumio[5034]: ---------------------------- MPD announces state update: player Apr 15 05:02:52 volumio volumio[5034]: info: ControllerMpd::getState Apr 15 05:02:52 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand status Apr 15 05:02:52 volumio volumio[5034]: info: ------------------------------ 4ms Apr 15 05:02:52 volumio volumio[5034]: verbose: ControllerMpd::parseState Apr 15 05:02:52 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 05:02:52 volumio volumio[5034]: verbose: ControllerMpd::parseTrackInfo Apr 15 05:02:52 volumio volumio[5034]: info: ControllerMpd::pushState Apr 15 05:02:52 volumio volumio[5034]: info: CoreCommandRouter::servicePushState Apr 15 05:02:52 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:52 volumio volumio[5034]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1985,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"SCANDAL (JP) - Fanfare","artist":"Asia DREAM Radio - Japan Hits","album":null,"uri":"https://cast1.torontocast.com:2120/","trackType":"com:2120/"} Apr 15 05:02:52 volumio volumio[5034]: verbose: CURRENT POSITION 0 Apr 15 05:02:52 volumio volumio[5034]: info: CoreStateMachine::syncState stateService play Apr 15 05:02:52 volumio volumio[5034]: info: CoreStateMachine::syncState currentStatus play Apr 15 05:02:52 volumio volumio[5034]: info: Received an update from plugin. extracting info from payload Apr 15 05:02:52 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 15 05:02:52 volumio volumio[5034]: info: CoreStateMachine::pushState Apr 15 05:02:52 volumio volumio[5034]: info: CoreCommandRouter::volumioPushState Apr 15 05:02:52 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 05:02:52 volumio volumio[5034]: info: CoreStateMachine::pushState Apr 15 05:02:52 volumio volumio[5034]: info: CoreCommandRouter::volumioPushState Apr 15 05:02:52 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 05:02:52 volumio volumio[5034]: info: ------------------------------ 17ms Apr 15 05:02:58 volumio volumio[5034]: info: CoreCommandRouter::volumioStop Apr 15 05:02:58 volumio volumio[5034]: info: CoreStateMachine::stop Apr 15 05:02:58 volumio volumio[5034]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 05:02:58 volumio volumio[5034]: UNSET VOLATILE Apr 15 05:02:58 volumio volumio[5034]: info: CoreStateMachine::stPlaybackTimer Apr 15 05:02:58 volumio volumio[5034]: info: CoreStateMachine::updateTrackBlock Apr 15 05:02:58 volumio volumio[5034]: info: CorePlayQueue::getTrackBlock Apr 15 05:02:58 volumio volumio[5034]: info: CoreStateMachine::pushState Apr 15 05:02:58 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:58 volumio volumio[5034]: info: CoreCommandRouter::volumioPushState Apr 15 05:02:58 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 05:02:58 volumio volumio[5034]: info: CoreStateMachine::serviceStop Apr 15 05:02:58 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:58 volumio volumio[5034]: info: CoreCommandRouter::serviceStop Apr 15 05:02:58 volumio volumio[5034]: info: [1713157378850] ControllerWebradio::stop Apr 15 05:02:58 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 05:02:58 volumio volumio[5034]: info: Apr 15 05:02:58 volumio volumio[5034]: ---------------------------- MPD announces state update: player Apr 15 05:02:58 volumio volumio[5034]: info: ControllerMpd::getState Apr 15 05:02:58 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand status Apr 15 05:02:58 volumio volumio[5034]: verbose: ControllerMpd::parseState Apr 15 05:02:58 volumio volumio[5034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 05:02:58 volumio volumio[5034]: verbose: ControllerMpd::parseTrackInfo Apr 15 05:02:58 volumio volumio[5034]: info: ControllerMpd::pushState Apr 15 05:02:58 volumio volumio[5034]: info: CoreCommandRouter::servicePushState Apr 15 05:02:58 volumio volumio[5034]: info: CoreStateMachine::pushState Apr 15 05:02:58 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:58 volumio volumio[5034]: info: CoreCommandRouter::volumioPushState Apr 15 05:02:58 volumio volumio[5034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 05:02:58 volumio volumio[5034]: info: CorePlayQueue::getTrack 0 Apr 15 05:02:58 volumio volumio[5034]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 15 05:02:58 volumio volumio[5034]: info: ------------------------------ 12ms Apr 15 05:03:00 volumio volumio[5034]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 05:03:00 volumio volumio[5034]: { Error: connect ETIMEDOUT 157.240.18.18:80 Apr 15 05:03:00 volumio volumio[5034]: at Object._errnoException (util.js:1022:11) Apr 15 05:03:00 volumio volumio[5034]: at _exceptionWithHostPort (util.js:1044:20) Apr 15 05:03:00 volumio volumio[5034]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Apr 15 05:03:00 volumio volumio[5034]: code: 'ETIMEDOUT', Apr 15 05:03:00 volumio volumio[5034]: errno: 'ETIMEDOUT', Apr 15 05:03:00 volumio volumio[5034]: syscall: 'connect', Apr 15 05:03:00 volumio volumio[5034]: address: '157.240.18.18', Apr 15 05:03:00 volumio volumio[5034]: port: 80 } Apr 15 05:03:00 volumio volumio[5034]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 05:03:01 volumio sudo[9777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-15 05:02 Apr 15 05:03:01 volumio sudo[9777]: 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="168ba7444df98cd868db72f24faec0b6346489cc" VOLUMIO_FE_VERSION="847a48ecf32d35cc502a0053d585d1a69236e391" VOLUMIO_BE_VERSION="99dfb342fe07851f1e26bd1d8046d0234eda91dd" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 11 15:53:56 CET 2020" VOLUMIO_VERSION="2.857" VOLUMIO_HARDWARE="aml9xxxarmv7" VOLUMIO_HASH="5e595833ee6075a27fef1ba35f928abb"