-- Logs begin at Sat 2025-04-19 03:26:56 UTC, end at Sat 2025-04-19 03:53:49 UTC. -- Apr 19 03:52:01 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 19 03:52:02 volumio volumio[900]: info: READ Apr 19 03:52:04 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 19 03:52:06 volumio volumio[900]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 19 03:52:06 volumio volumio[900]: info: CoreStateMachine::ClearQueue Apr 19 03:52:06 volumio volumio[900]: info: CoreStateMachine::stop Apr 19 03:52:06 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:52:06 volumio volumio[900]: verbose: UNSET VOLATILE Apr 19 03:52:06 volumio volumio[900]: info: CoreStateMachine::stPlaybackTimer Apr 19 03:52:06 volumio volumio[900]: info: CoreStateMachine::updateTrackBlock Apr 19 03:52:06 volumio volumio[900]: info: CorePlayQueue::getTrackBlock Apr 19 03:52:06 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:06 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:06 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:06 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:06 volumio volumio[900]: info: CoreStateMachine::serviceStop Apr 19 03:52:06 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:06 volumio volumio[900]: info: CoreCommandRouter::serviceStop Apr 19 03:52:06 volumio volumio[900]: info: [1745034726339] ControllerWebradio::stop Apr 19 03:52:06 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 03:52:06 volumio volumio[900]: info: CorePlayQueue::clearPlayQueue Apr 19 03:52:06 volumio volumio[900]: info: CorePlayQueue::saveQueue Apr 19 03:52:06 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:06 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:06 volumio volumio[900]: info: CoreCommandRouter::volumioPushQueue Apr 19 03:52:06 volumio volumio[900]: info: CoreStateMachine::addQueueItems Apr 19 03:52:06 volumio volumio[900]: info: CorePlayQueue::addQueueItems Apr 19 03:52:06 volumio volumio[900]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497938 Apr 19 03:52:06 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 19 03:52:06 volumio volumio[900]: info: CorePlayQueue::saveQueue Apr 19 03:52:06 volumio volumio[900]: info: CoreCommandRouter::volumioPushQueue Apr 19 03:52:06 volumio volumio[900]: info: CoreStateMachine::updateTrackBlock Apr 19 03:52:06 volumio volumio[900]: info: CorePlayQueue::getTrackBlock Apr 19 03:52:06 volumio volumio[900]: info: CoreCommandRouter::volumioPlay Apr 19 03:52:06 volumio volumio[900]: verbose: UNSET VOLATILE Apr 19 03:52:06 volumio volumio[900]: info: CoreStateMachine::play index 0 Apr 19 03:52:06 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:52:06 volumio volumio[900]: info: CoreStateMachine::stop Apr 19 03:52:06 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:52:06 volumio volumio[900]: verbose: UNSET VOLATILE Apr 19 03:52:06 volumio volumio[900]: info: CoreStateMachine::play index undefined Apr 19 03:52:06 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:52:06 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:06 volumio volumio[900]: info: CoreStateMachine::startPlaybackTimer Apr 19 03:52:06 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:06 volumio volumio[900]: info: [1745034726353] ControllerWebradio::clearAddPlayTrack Apr 19 03:52:06 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 03:52:06 volumio volumio[900]: info: Apr 19 03:52:06 volumio volumio[900]: ---------------------------- MPD announces state update: player Apr 19 03:52:06 volumio volumio[900]: info: sendMpdCommand stop took 45 milliseconds Apr 19 03:52:06 volumio volumio[900]: info: ControllerMpd::getState Apr 19 03:52:06 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand status Apr 19 03:52:06 volumio volumio[900]: info: sendMpdCommand stop took 32 milliseconds Apr 19 03:52:06 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand clear Apr 19 03:52:06 volumio volumio[900]: info: Apr 19 03:52:06 volumio volumio[900]: ---------------------------- MPD announces system playlist update Apr 19 03:52:06 volumio volumio[900]: info: Ignoring MPD Status Update Apr 19 03:52:06 volumio volumio[900]: info: sendMpdCommand status took 9 milliseconds Apr 19 03:52:06 volumio volumio[900]: info: sendMpdCommand clear took 5 milliseconds Apr 19 03:52:06 volumio volumio[900]: verbose: ControllerMpd::parseState Apr 19 03:52:06 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 03:52:06 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497938" Apr 19 03:52:06 volumio volumio[900]: error: updateQueue error: null Apr 19 03:52:06 volumio volumio[900]: info: ------------------------------ 13ms Apr 19 03:52:06 volumio volumio[900]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 19 03:52:06 volumio volumio[900]: verbose: ControllerMpd::parseTrackInfo Apr 19 03:52:06 volumio volumio[900]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 19 03:52:06 volumio volumio[900]: info: ------------------------------ 23ms Apr 19 03:52:06 volumio volumio[900]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497938" took 239 milliseconds Apr 19 03:52:06 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 03:52:06 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand play Apr 19 03:52:06 volumio volumio[900]: info: Apr 19 03:52:06 volumio volumio[900]: ---------------------------- MPD announces system playlist update Apr 19 03:52:06 volumio volumio[900]: info: Ignoring MPD Status Update Apr 19 03:52:06 volumio volumio[900]: info: sendMpdCommand play took 2 milliseconds Apr 19 03:52:06 volumio volumio[900]: info: ------------------------------ 2ms Apr 19 03:52:07 volumio volumio[900]: info: Apr 19 03:52:07 volumio volumio[900]: ---------------------------- MPD announces state update: player Apr 19 03:52:07 volumio volumio[900]: info: ControllerMpd::getState Apr 19 03:52:07 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand status Apr 19 03:52:07 volumio volumio[900]: info: Apr 19 03:52:07 volumio volumio[900]: ---------------------------- MPD announces system playlist update Apr 19 03:52:07 volumio volumio[900]: info: Ignoring MPD Status Update Apr 19 03:52:07 volumio volumio[900]: info: Apr 19 03:52:07 volumio volumio[900]: ---------------------------- MPD announces state update: player Apr 19 03:52:07 volumio volumio[900]: info: ControllerMpd::getState Apr 19 03:52:07 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand status Apr 19 03:52:07 volumio volumio[900]: info: sendMpdCommand status took 3 milliseconds Apr 19 03:52:07 volumio volumio[900]: verbose: ControllerMpd::parseState Apr 19 03:52:07 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 03:52:07 volumio volumio[900]: info: ------------------------------ 8ms Apr 19 03:52:07 volumio volumio[900]: info: sendMpdCommand status took 8 milliseconds Apr 19 03:52:07 volumio volumio[900]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 19 03:52:07 volumio volumio[900]: verbose: ControllerMpd::parseState Apr 19 03:52:07 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 03:52:07 volumio volumio[900]: verbose: ControllerMpd::parseTrackInfo Apr 19 03:52:07 volumio volumio[900]: info: ControllerMpd::pushState Apr 19 03:52:07 volumio volumio[900]: info: CoreCommandRouter::servicePushState Apr 19 03:52:07 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:07 volumio volumio[900]: 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":"2000er-hits","artist":"ANTENNE BAYERN 2000er Hits","album":null,"uri":"http://stream.antenne.de:80/2000er-hits","trackType":""} Apr 19 03:52:07 volumio volumio[900]: verbose: CURRENT POSITION 0 Apr 19 03:52:07 volumio volumio[900]: info: CoreStateMachine::syncState stateService play Apr 19 03:52:07 volumio volumio[900]: info: CoreStateMachine::syncState currentStatus stop Apr 19 03:52:07 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:07 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:07 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:07 volumio volumio[900]: info: ------------------------------ 20ms Apr 19 03:52:07 volumio volumio[900]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 19 03:52:07 volumio volumio[900]: verbose: ControllerMpd::parseTrackInfo Apr 19 03:52:07 volumio volumio[900]: info: ControllerMpd::pushState Apr 19 03:52:07 volumio volumio[900]: info: CoreCommandRouter::servicePushState Apr 19 03:52:07 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:07 volumio volumio[900]: 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":"2000er-hits","artist":"ANTENNE BAYERN 2000er Hits","album":null,"uri":"http://stream.antenne.de:80/2000er-hits","trackType":""} Apr 19 03:52:07 volumio volumio[900]: verbose: CURRENT POSITION 0 Apr 19 03:52:07 volumio volumio[900]: info: CoreStateMachine::syncState stateService play Apr 19 03:52:07 volumio volumio[900]: info: CoreStateMachine::syncState currentStatus play Apr 19 03:52:07 volumio volumio[900]: info: Received an update from plugin. extracting info from payload Apr 19 03:52:07 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 03:52:07 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:07 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:07 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:07 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:07 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:07 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:07 volumio volumio[900]: info: ------------------------------ 37ms Apr 19 03:52:07 volumio volumio[900]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/ROCK%20ANTENNE/3dd2353a-c786-4fe1-8ff1-32fd5aa8fd7f.jpg' Apr 19 03:52:07 volumio volumio[900]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/ROCK%20ANTENNE/3dd2353a-c786-4fe1-8ff1-32fd5aa8fd7f.jpg' Apr 19 03:52:07 volumio volumio[900]: info: Apr 19 03:52:07 volumio volumio[900]: ---------------------------- MPD announces system playlist update Apr 19 03:52:07 volumio volumio[900]: info: Ignoring MPD Status Update Apr 19 03:52:07 volumio volumio[900]: info: Apr 19 03:52:07 volumio volumio[900]: ---------------------------- MPD announces state update: player Apr 19 03:52:07 volumio volumio[900]: info: ControllerMpd::getState Apr 19 03:52:07 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand status Apr 19 03:52:07 volumio volumio[900]: info: ------------------------------ 13ms Apr 19 03:52:07 volumio volumio[900]: info: sendMpdCommand status took 13 milliseconds Apr 19 03:52:07 volumio volumio[900]: verbose: ControllerMpd::parseState Apr 19 03:52:07 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 03:52:07 volumio volumio[900]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 19 03:52:07 volumio volumio[900]: verbose: ControllerMpd::parseTrackInfo Apr 19 03:52:07 volumio volumio[900]: info: ControllerMpd::pushState Apr 19 03:52:07 volumio volumio[900]: info: CoreCommandRouter::servicePushState Apr 19 03:52:07 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:07 volumio volumio[900]: 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":"ANTENNE BAYERN - 2000er Hits","artist":"ANTENNE BAYERN 2000er Hits","album":null,"uri":"http://stream.antenne.de:80/2000er-hits","trackType":""} Apr 19 03:52:07 volumio volumio[900]: verbose: CURRENT POSITION 0 Apr 19 03:52:07 volumio volumio[900]: info: CoreStateMachine::syncState stateService play Apr 19 03:52:07 volumio volumio[900]: info: CoreStateMachine::syncState currentStatus play Apr 19 03:52:07 volumio volumio[900]: info: Received an update from plugin. extracting info from payload Apr 19 03:52:07 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 03:52:07 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:07 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:07 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:07 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:07 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:07 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:07 volumio volumio[900]: info: ------------------------------ 42ms Apr 19 03:52:07 volumio volumio[900]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/ROCK%20ANTENNE/3dd2353a-c786-4fe1-8ff1-32fd5aa8fd7f.jpg' Apr 19 03:52:08 volumio volumio[900]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 19 03:52:08 volumio volumio[900]: info: CoreStateMachine::ClearQueue Apr 19 03:52:08 volumio volumio[900]: info: CoreStateMachine::stop Apr 19 03:52:08 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:52:08 volumio volumio[900]: verbose: UNSET VOLATILE Apr 19 03:52:08 volumio volumio[900]: info: CoreStateMachine::stPlaybackTimer Apr 19 03:52:08 volumio volumio[900]: info: CoreStateMachine::updateTrackBlock Apr 19 03:52:08 volumio volumio[900]: info: CorePlayQueue::getTrackBlock Apr 19 03:52:08 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:08 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:08 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:08 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:08 volumio volumio[900]: info: CoreStateMachine::serviceStop Apr 19 03:52:08 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:08 volumio volumio[900]: info: CoreCommandRouter::serviceStop Apr 19 03:52:08 volumio volumio[900]: info: [1745034728414] ControllerWebradio::stop Apr 19 03:52:08 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 03:52:08 volumio volumio[900]: info: CorePlayQueue::clearPlayQueue Apr 19 03:52:08 volumio volumio[900]: info: CorePlayQueue::saveQueue Apr 19 03:52:08 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:08 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:08 volumio volumio[900]: info: CoreCommandRouter::volumioPushQueue Apr 19 03:52:08 volumio volumio[900]: info: CoreStateMachine::addQueueItems Apr 19 03:52:08 volumio volumio[900]: info: CorePlayQueue::addQueueItems Apr 19 03:52:08 volumio volumio[900]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99576771 Apr 19 03:52:08 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 19 03:52:08 volumio volumio[900]: info: CorePlayQueue::saveQueue Apr 19 03:52:08 volumio volumio[900]: info: CoreCommandRouter::volumioPushQueue Apr 19 03:52:08 volumio volumio[900]: info: CoreStateMachine::updateTrackBlock Apr 19 03:52:08 volumio volumio[900]: info: CorePlayQueue::getTrackBlock Apr 19 03:52:08 volumio volumio[900]: info: CoreCommandRouter::volumioPlay Apr 19 03:52:08 volumio volumio[900]: verbose: UNSET VOLATILE Apr 19 03:52:08 volumio volumio[900]: info: CoreStateMachine::play index 0 Apr 19 03:52:08 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:52:08 volumio volumio[900]: info: CoreStateMachine::stop Apr 19 03:52:08 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:52:08 volumio volumio[900]: verbose: UNSET VOLATILE Apr 19 03:52:08 volumio volumio[900]: info: CoreStateMachine::play index undefined Apr 19 03:52:08 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:52:08 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:08 volumio volumio[900]: info: CoreStateMachine::startPlaybackTimer Apr 19 03:52:08 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:08 volumio volumio[900]: info: [1745034728438] ControllerWebradio::clearAddPlayTrack Apr 19 03:52:08 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 03:52:08 volumio volumio[900]: info: Apr 19 03:52:08 volumio volumio[900]: ---------------------------- MPD announces state update: player Apr 19 03:52:08 volumio volumio[900]: info: sendMpdCommand stop took 44 milliseconds Apr 19 03:52:08 volumio volumio[900]: info: ControllerMpd::getState Apr 19 03:52:08 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand status Apr 19 03:52:08 volumio volumio[900]: info: sendMpdCommand stop took 21 milliseconds Apr 19 03:52:08 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand clear Apr 19 03:52:08 volumio volumio[900]: info: Apr 19 03:52:08 volumio volumio[900]: ---------------------------- MPD announces system playlist update Apr 19 03:52:08 volumio volumio[900]: info: Ignoring MPD Status Update Apr 19 03:52:08 volumio volumio[900]: info: sendMpdCommand status took 7 milliseconds Apr 19 03:52:08 volumio volumio[900]: info: sendMpdCommand clear took 4 milliseconds Apr 19 03:52:08 volumio volumio[900]: verbose: ControllerMpd::parseState Apr 19 03:52:08 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 03:52:08 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99576771" Apr 19 03:52:08 volumio volumio[900]: error: updateQueue error: null Apr 19 03:52:08 volumio volumio[900]: info: ------------------------------ 6ms Apr 19 03:52:08 volumio volumio[900]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 19 03:52:08 volumio volumio[900]: verbose: ControllerMpd::parseTrackInfo Apr 19 03:52:08 volumio volumio[900]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 19 03:52:08 volumio volumio[900]: info: ------------------------------ 12ms Apr 19 03:52:08 volumio volumio[900]: info: Apr 19 03:52:08 volumio volumio[900]: ---------------------------- MPD announces system playlist update Apr 19 03:52:08 volumio volumio[900]: info: Ignoring MPD Status Update Apr 19 03:52:08 volumio volumio[900]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99576771" took 236 milliseconds Apr 19 03:52:08 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 03:52:08 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand play Apr 19 03:52:08 volumio volumio[900]: info: ------------------------------ 5ms Apr 19 03:52:08 volumio volumio[900]: info: sendMpdCommand play took 4 milliseconds Apr 19 03:52:09 volumio volumio[900]: info: Apr 19 03:52:09 volumio volumio[900]: ---------------------------- MPD announces state update: player Apr 19 03:52:09 volumio volumio[900]: info: ControllerMpd::getState Apr 19 03:52:09 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand status Apr 19 03:52:09 volumio volumio[900]: info: Apr 19 03:52:09 volumio volumio[900]: ---------------------------- MPD announces system playlist update Apr 19 03:52:09 volumio volumio[900]: info: Ignoring MPD Status Update Apr 19 03:52:09 volumio volumio[900]: info: Apr 19 03:52:09 volumio volumio[900]: ---------------------------- MPD announces state update: player Apr 19 03:52:09 volumio volumio[900]: info: ControllerMpd::getState Apr 19 03:52:09 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand status Apr 19 03:52:09 volumio volumio[900]: info: sendMpdCommand status took 3 milliseconds Apr 19 03:52:09 volumio volumio[900]: verbose: ControllerMpd::parseState Apr 19 03:52:09 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 03:52:09 volumio volumio[900]: info: ------------------------------ 3ms Apr 19 03:52:09 volumio volumio[900]: info: sendMpdCommand status took 3 milliseconds Apr 19 03:52:09 volumio volumio[900]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 19 03:52:09 volumio volumio[900]: verbose: ControllerMpd::parseState Apr 19 03:52:09 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 03:52:09 volumio volumio[900]: verbose: ControllerMpd::parseTrackInfo Apr 19 03:52:09 volumio volumio[900]: info: ControllerMpd::pushState Apr 19 03:52:09 volumio volumio[900]: info: CoreCommandRouter::servicePushState Apr 19 03:52:09 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:09 volumio volumio[900]: 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":"A_A 2000s","album":null,"uri":"http://62.210.204.2:8000/stream/21/","trackType":""} Apr 19 03:52:09 volumio volumio[900]: verbose: CURRENT POSITION 0 Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::syncState stateService play Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::syncState currentStatus stop Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:09 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:09 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:09 volumio volumio[900]: info: ------------------------------ 16ms Apr 19 03:52:09 volumio volumio[900]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 19 03:52:09 volumio volumio[900]: verbose: ControllerMpd::parseTrackInfo Apr 19 03:52:09 volumio volumio[900]: info: ControllerMpd::pushState Apr 19 03:52:09 volumio volumio[900]: info: CoreCommandRouter::servicePushState Apr 19 03:52:09 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:09 volumio volumio[900]: 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":"A_A 2000s","album":null,"uri":"http://62.210.204.2:8000/stream/21/","trackType":""} Apr 19 03:52:09 volumio volumio[900]: verbose: CURRENT POSITION 0 Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::syncState stateService play Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::syncState currentStatus play Apr 19 03:52:09 volumio volumio[900]: info: Received an update from plugin. extracting info from payload Apr 19 03:52:09 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:09 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:09 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:09 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:09 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:09 volumio volumio[900]: info: ------------------------------ 29ms Apr 19 03:52:09 volumio volumio[900]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::ClearQueue Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::stop Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:52:09 volumio volumio[900]: verbose: UNSET VOLATILE Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::stPlaybackTimer Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::updateTrackBlock Apr 19 03:52:09 volumio volumio[900]: info: CorePlayQueue::getTrackBlock Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:09 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:09 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:09 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::serviceStop Apr 19 03:52:09 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:09 volumio volumio[900]: info: CoreCommandRouter::serviceStop Apr 19 03:52:09 volumio volumio[900]: info: [1745034729412] ControllerWebradio::stop Apr 19 03:52:09 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 03:52:09 volumio volumio[900]: info: CorePlayQueue::clearPlayQueue Apr 19 03:52:09 volumio volumio[900]: info: CorePlayQueue::saveQueue Apr 19 03:52:09 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:09 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:09 volumio volumio[900]: info: CoreCommandRouter::volumioPushQueue Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::addQueueItems Apr 19 03:52:09 volumio volumio[900]: info: CorePlayQueue::addQueueItems Apr 19 03:52:09 volumio volumio[900]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99576771 Apr 19 03:52:09 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 19 03:52:09 volumio volumio[900]: info: CorePlayQueue::saveQueue Apr 19 03:52:09 volumio volumio[900]: info: CoreCommandRouter::volumioPushQueue Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::updateTrackBlock Apr 19 03:52:09 volumio volumio[900]: info: CorePlayQueue::getTrackBlock Apr 19 03:52:09 volumio volumio[900]: info: CoreCommandRouter::volumioPlay Apr 19 03:52:09 volumio volumio[900]: verbose: UNSET VOLATILE Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::play index 0 Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::stop Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:52:09 volumio volumio[900]: verbose: UNSET VOLATILE Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::play index undefined Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:52:09 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::startPlaybackTimer Apr 19 03:52:09 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:09 volumio volumio[900]: info: [1745034729442] ControllerWebradio::clearAddPlayTrack Apr 19 03:52:09 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 03:52:09 volumio volumio[900]: info: Apr 19 03:52:09 volumio volumio[900]: ---------------------------- MPD announces state update: player Apr 19 03:52:09 volumio volumio[900]: info: sendMpdCommand stop took 102 milliseconds Apr 19 03:52:09 volumio volumio[900]: info: ControllerMpd::getState Apr 19 03:52:09 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand status Apr 19 03:52:09 volumio volumio[900]: info: sendMpdCommand stop took 68 milliseconds Apr 19 03:52:09 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand clear Apr 19 03:52:09 volumio volumio[900]: info: Apr 19 03:52:09 volumio volumio[900]: ---------------------------- MPD announces system playlist update Apr 19 03:52:09 volumio volumio[900]: info: Ignoring MPD Status Update Apr 19 03:52:09 volumio volumio[900]: info: sendMpdCommand status took 8 milliseconds Apr 19 03:52:09 volumio volumio[900]: info: sendMpdCommand clear took 7 milliseconds Apr 19 03:52:09 volumio volumio[900]: verbose: ControllerMpd::parseState Apr 19 03:52:09 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 03:52:09 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99576771" Apr 19 03:52:09 volumio volumio[900]: error: updateQueue error: null Apr 19 03:52:09 volumio volumio[900]: info: ------------------------------ 6ms Apr 19 03:52:09 volumio volumio[900]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 19 03:52:09 volumio volumio[900]: verbose: ControllerMpd::parseTrackInfo Apr 19 03:52:09 volumio volumio[900]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 19 03:52:09 volumio volumio[900]: info: ------------------------------ 14ms Apr 19 03:52:09 volumio volumio[900]: info: Apr 19 03:52:09 volumio volumio[900]: ---------------------------- MPD announces system playlist update Apr 19 03:52:09 volumio volumio[900]: info: Ignoring MPD Status Update Apr 19 03:52:09 volumio volumio[900]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99576771" took 234 milliseconds Apr 19 03:52:09 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 03:52:09 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand play Apr 19 03:52:09 volumio volumio[900]: info: ------------------------------ 2ms Apr 19 03:52:09 volumio volumio[900]: info: sendMpdCommand play took 1 milliseconds Apr 19 03:52:10 volumio volumio[900]: info: Apr 19 03:52:10 volumio volumio[900]: ---------------------------- MPD announces state update: player Apr 19 03:52:10 volumio volumio[900]: info: ControllerMpd::getState Apr 19 03:52:10 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand status Apr 19 03:52:10 volumio volumio[900]: info: Apr 19 03:52:10 volumio volumio[900]: ---------------------------- MPD announces system playlist update Apr 19 03:52:10 volumio volumio[900]: info: Ignoring MPD Status Update Apr 19 03:52:10 volumio volumio[900]: info: Apr 19 03:52:10 volumio volumio[900]: ---------------------------- MPD announces state update: player Apr 19 03:52:10 volumio volumio[900]: info: ControllerMpd::getState Apr 19 03:52:10 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand status Apr 19 03:52:10 volumio volumio[900]: info: sendMpdCommand status took 5 milliseconds Apr 19 03:52:10 volumio volumio[900]: verbose: ControllerMpd::parseState Apr 19 03:52:10 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 03:52:10 volumio volumio[900]: info: ------------------------------ 7ms Apr 19 03:52:10 volumio volumio[900]: info: sendMpdCommand status took 8 milliseconds Apr 19 03:52:10 volumio volumio[900]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 19 03:52:10 volumio volumio[900]: verbose: ControllerMpd::parseState Apr 19 03:52:10 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 03:52:10 volumio volumio[900]: verbose: ControllerMpd::parseTrackInfo Apr 19 03:52:10 volumio volumio[900]: info: ControllerMpd::pushState Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::servicePushState Apr 19 03:52:10 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:10 volumio volumio[900]: 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":"A_A 2000s","album":null,"uri":"http://62.210.204.2:8000/stream/21/","trackType":""} Apr 19 03:52:10 volumio volumio[900]: verbose: CURRENT POSITION 0 Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::syncState stateService play Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::syncState currentStatus stop Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:10 volumio volumio[900]: info: ------------------------------ 25ms Apr 19 03:52:10 volumio volumio[900]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 19 03:52:10 volumio volumio[900]: verbose: ControllerMpd::parseTrackInfo Apr 19 03:52:10 volumio volumio[900]: info: ControllerMpd::pushState Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::servicePushState Apr 19 03:52:10 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:10 volumio volumio[900]: 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":"A_A 2000s","album":null,"uri":"http://62.210.204.2:8000/stream/21/","trackType":""} Apr 19 03:52:10 volumio volumio[900]: verbose: CURRENT POSITION 0 Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::syncState stateService play Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::syncState currentStatus play Apr 19 03:52:10 volumio volumio[900]: info: Received an update from plugin. extracting info from payload Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:10 volumio volumio[900]: info: ------------------------------ 48ms Apr 19 03:52:10 volumio volumio[900]: info: Apr 19 03:52:10 volumio volumio[900]: ---------------------------- MPD announces system playlist update Apr 19 03:52:10 volumio volumio[900]: info: Ignoring MPD Status Update Apr 19 03:52:10 volumio volumio[900]: info: Apr 19 03:52:10 volumio volumio[900]: ---------------------------- MPD announces state update: player Apr 19 03:52:10 volumio volumio[900]: info: ControllerMpd::getState Apr 19 03:52:10 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand status Apr 19 03:52:10 volumio volumio[900]: info: ------------------------------ 6ms Apr 19 03:52:10 volumio volumio[900]: info: sendMpdCommand status took 6 milliseconds Apr 19 03:52:10 volumio volumio[900]: verbose: ControllerMpd::parseState Apr 19 03:52:10 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 03:52:10 volumio volumio[900]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 19 03:52:10 volumio volumio[900]: verbose: ControllerMpd::parseTrackInfo Apr 19 03:52:10 volumio volumio[900]: info: ControllerMpd::pushState Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::servicePushState Apr 19 03:52:10 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:10 volumio volumio[900]: 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":"Patty Griffin - Where I Came From","artist":"A_A 2000s","album":null,"uri":"http://62.210.204.2:8000/stream/21/","trackType":""} Apr 19 03:52:10 volumio volumio[900]: verbose: CURRENT POSITION 0 Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::syncState stateService play Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::syncState currentStatus play Apr 19 03:52:10 volumio volumio[900]: info: Received an update from plugin. extracting info from payload Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:10 volumio volumio[900]: info: ------------------------------ 45ms Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::ClearQueue Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::stop Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:52:10 volumio volumio[900]: verbose: UNSET VOLATILE Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::stPlaybackTimer Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::updateTrackBlock Apr 19 03:52:10 volumio volumio[900]: info: CorePlayQueue::getTrackBlock Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:10 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::serviceStop Apr 19 03:52:10 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::serviceStop Apr 19 03:52:10 volumio volumio[900]: info: [1745034730706] ControllerWebradio::stop Apr 19 03:52:10 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 03:52:10 volumio volumio[900]: info: CorePlayQueue::clearPlayQueue Apr 19 03:52:10 volumio volumio[900]: info: CorePlayQueue::saveQueue Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::volumioPushQueue Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::addQueueItems Apr 19 03:52:10 volumio volumio[900]: info: CorePlayQueue::addQueueItems Apr 19 03:52:10 volumio volumio[900]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99576771 Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 19 03:52:10 volumio volumio[900]: info: CorePlayQueue::saveQueue Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::volumioPushQueue Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::updateTrackBlock Apr 19 03:52:10 volumio volumio[900]: info: CorePlayQueue::getTrackBlock Apr 19 03:52:10 volumio volumio[900]: info: CoreCommandRouter::volumioPlay Apr 19 03:52:10 volumio volumio[900]: verbose: UNSET VOLATILE Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::play index 0 Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::stop Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:52:10 volumio volumio[900]: verbose: UNSET VOLATILE Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::play index undefined Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:52:10 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:10 volumio volumio[900]: info: CoreStateMachine::startPlaybackTimer Apr 19 03:52:10 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:10 volumio volumio[900]: info: [1745034730744] ControllerWebradio::clearAddPlayTrack Apr 19 03:52:10 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 03:52:10 volumio volumio[900]: info: Apr 19 03:52:10 volumio volumio[900]: ---------------------------- MPD announces state update: player Apr 19 03:52:10 volumio volumio[900]: info: sendMpdCommand stop took 96 milliseconds Apr 19 03:52:10 volumio volumio[900]: info: ControllerMpd::getState Apr 19 03:52:10 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand status Apr 19 03:52:10 volumio volumio[900]: info: sendMpdCommand stop took 68 milliseconds Apr 19 03:52:10 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand clear Apr 19 03:52:10 volumio volumio[900]: info: Apr 19 03:52:10 volumio volumio[900]: ---------------------------- MPD announces system playlist update Apr 19 03:52:10 volumio volumio[900]: info: Ignoring MPD Status Update Apr 19 03:52:10 volumio volumio[900]: info: sendMpdCommand status took 14 milliseconds Apr 19 03:52:10 volumio volumio[900]: info: sendMpdCommand clear took 12 milliseconds Apr 19 03:52:10 volumio volumio[900]: verbose: ControllerMpd::parseState Apr 19 03:52:10 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 03:52:10 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99576771" Apr 19 03:52:10 volumio volumio[900]: error: updateQueue error: null Apr 19 03:52:10 volumio volumio[900]: info: ------------------------------ 11ms Apr 19 03:52:10 volumio volumio[900]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 19 03:52:10 volumio volumio[900]: verbose: ControllerMpd::parseTrackInfo Apr 19 03:52:10 volumio volumio[900]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 19 03:52:10 volumio volumio[900]: info: ------------------------------ 31ms Apr 19 03:52:11 volumio volumio[900]: info: Apr 19 03:52:11 volumio volumio[900]: ---------------------------- MPD announces system playlist update Apr 19 03:52:11 volumio volumio[900]: info: Ignoring MPD Status Update Apr 19 03:52:11 volumio volumio[900]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99576771" took 235 milliseconds Apr 19 03:52:11 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 03:52:11 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand play Apr 19 03:52:11 volumio volumio[900]: info: ------------------------------ 4ms Apr 19 03:52:11 volumio volumio[900]: info: sendMpdCommand play took 3 milliseconds Apr 19 03:52:11 volumio volumio[900]: info: Apr 19 03:52:11 volumio volumio[900]: ---------------------------- MPD announces state update: player Apr 19 03:52:11 volumio volumio[900]: info: ControllerMpd::getState Apr 19 03:52:11 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand status Apr 19 03:52:11 volumio volumio[900]: info: sendMpdCommand status took 4 milliseconds Apr 19 03:52:11 volumio volumio[900]: verbose: ControllerMpd::parseState Apr 19 03:52:11 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 03:52:11 volumio volumio[900]: info: Apr 19 03:52:11 volumio volumio[900]: ---------------------------- MPD announces state update: player Apr 19 03:52:11 volumio volumio[900]: info: ControllerMpd::getState Apr 19 03:52:11 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand status Apr 19 03:52:11 volumio volumio[900]: info: Apr 19 03:52:11 volumio volumio[900]: ---------------------------- MPD announces system playlist update Apr 19 03:52:11 volumio volumio[900]: info: Ignoring MPD Status Update Apr 19 03:52:11 volumio volumio[900]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 19 03:52:11 volumio volumio[900]: info: sendMpdCommand status took 3 milliseconds Apr 19 03:52:11 volumio volumio[900]: verbose: ControllerMpd::parseTrackInfo Apr 19 03:52:11 volumio volumio[900]: verbose: ControllerMpd::parseState Apr 19 03:52:11 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 03:52:11 volumio volumio[900]: info: ControllerMpd::pushState Apr 19 03:52:11 volumio volumio[900]: info: CoreCommandRouter::servicePushState Apr 19 03:52:11 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:11 volumio volumio[900]: 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":"A_A 2000s","album":null,"uri":"http://62.210.204.2:8000/stream/21/","trackType":""} Apr 19 03:52:11 volumio volumio[900]: verbose: CURRENT POSITION 0 Apr 19 03:52:11 volumio volumio[900]: info: CoreStateMachine::syncState stateService play Apr 19 03:52:11 volumio volumio[900]: info: CoreStateMachine::syncState currentStatus stop Apr 19 03:52:11 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:11 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:11 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:11 volumio volumio[900]: info: ------------------------------ 19ms Apr 19 03:52:11 volumio volumio[900]: info: ------------------------------ 8ms Apr 19 03:52:11 volumio volumio[900]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 19 03:52:11 volumio volumio[900]: verbose: ControllerMpd::parseTrackInfo Apr 19 03:52:11 volumio volumio[900]: info: ControllerMpd::pushState Apr 19 03:52:11 volumio volumio[900]: info: CoreCommandRouter::servicePushState Apr 19 03:52:11 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:11 volumio volumio[900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":23,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"","artist":"A_A 2000s","album":null,"uri":"http://62.210.204.2:8000/stream/21/","trackType":""} Apr 19 03:52:11 volumio volumio[900]: verbose: CURRENT POSITION 0 Apr 19 03:52:11 volumio volumio[900]: info: CoreStateMachine::syncState stateService play Apr 19 03:52:11 volumio volumio[900]: info: CoreStateMachine::syncState currentStatus play Apr 19 03:52:11 volumio volumio[900]: info: Received an update from plugin. extracting info from payload Apr 19 03:52:11 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 03:52:11 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:11 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:11 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:11 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:11 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:11 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:11 volumio volumio[900]: info: ------------------------------ 42ms Apr 19 03:52:11 volumio volumio[900]: info: Apr 19 03:52:11 volumio volumio[900]: ---------------------------- MPD announces system playlist update Apr 19 03:52:11 volumio volumio[900]: info: Ignoring MPD Status Update Apr 19 03:52:11 volumio volumio[900]: info: Apr 19 03:52:11 volumio volumio[900]: ---------------------------- MPD announces state update: player Apr 19 03:52:11 volumio volumio[900]: info: ControllerMpd::getState Apr 19 03:52:11 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand status Apr 19 03:52:11 volumio volumio[900]: info: ------------------------------ 2ms Apr 19 03:52:11 volumio volumio[900]: info: sendMpdCommand status took 2 milliseconds Apr 19 03:52:11 volumio volumio[900]: verbose: ControllerMpd::parseState Apr 19 03:52:11 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 03:52:11 volumio volumio[900]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 19 03:52:11 volumio volumio[900]: verbose: ControllerMpd::parseTrackInfo Apr 19 03:52:11 volumio volumio[900]: info: ControllerMpd::pushState Apr 19 03:52:11 volumio volumio[900]: info: CoreCommandRouter::servicePushState Apr 19 03:52:11 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:11 volumio volumio[900]: 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":"Patty Griffin - Where I Came From","artist":"A_A 2000s","album":null,"uri":"http://62.210.204.2:8000/stream/21/","trackType":""} Apr 19 03:52:11 volumio volumio[900]: verbose: CURRENT POSITION 0 Apr 19 03:52:11 volumio volumio[900]: info: CoreStateMachine::syncState stateService play Apr 19 03:52:11 volumio volumio[900]: info: CoreStateMachine::syncState currentStatus play Apr 19 03:52:11 volumio volumio[900]: info: Received an update from plugin. extracting info from payload Apr 19 03:52:11 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 03:52:11 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:11 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:11 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:11 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:11 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:11 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:11 volumio volumio[900]: info: ------------------------------ 12ms Apr 19 03:52:25 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 19 03:52:29 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 19 03:52:29 volumio volumio[900]: info: CURURI: music-library Apr 19 03:52:32 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 19 03:52:32 volumio volumio[900]: info: CURURI: artists:// Apr 19 03:52:32 volumio volumio[900]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/9mm%20Parabellum%20Bullet/d2daea29-b190-4d6d-a15f-632cb9ae7b3a.jpg' Apr 19 03:52:32 volumio volumio[900]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F/905bc273-bc38-47dd-8d63-e215184ea836.jpg' Apr 19 03:52:33 volumio volumio[900]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%20%3F%3F%3F/d758fa5c-dd26-4fb1-b870-83b814c97bfd.jpg' Apr 19 03:52:37 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 19 03:52:37 volumio volumio[900]: info: CURURI: artists://%E9%A9%AC%E5%85%8B%E8%A5%BF%E5%A7%86.%E5%A7%86%E5%B0%94%E7%BB%B4%E5%AF%9F Apr 19 03:52:38 volumio volumio[900]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 19 03:52:38 volumio volumio[900]: info: CoreStateMachine::ClearQueue Apr 19 03:52:38 volumio volumio[900]: info: CoreStateMachine::stop Apr 19 03:52:38 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:52:38 volumio volumio[900]: verbose: UNSET VOLATILE Apr 19 03:52:38 volumio volumio[900]: info: CoreStateMachine::stPlaybackTimer Apr 19 03:52:38 volumio volumio[900]: info: CoreStateMachine::updateTrackBlock Apr 19 03:52:38 volumio volumio[900]: info: CorePlayQueue::getTrackBlock Apr 19 03:52:38 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:38 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:38 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:38 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:38 volumio volumio[900]: info: CoreStateMachine::serviceStop Apr 19 03:52:38 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:38 volumio volumio[900]: info: CoreCommandRouter::serviceStop Apr 19 03:52:38 volumio volumio[900]: info: [1745034758810] ControllerWebradio::stop Apr 19 03:52:38 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 03:52:38 volumio volumio[900]: info: CorePlayQueue::clearPlayQueue Apr 19 03:52:38 volumio volumio[900]: info: CorePlayQueue::saveQueue Apr 19 03:52:38 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:38 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:38 volumio volumio[900]: info: CoreCommandRouter::volumioPushQueue Apr 19 03:52:38 volumio volumio[900]: info: CoreStateMachine::addQueueItems Apr 19 03:52:38 volumio volumio[900]: info: CorePlayQueue::addQueueItems Apr 19 03:52:38 volumio volumio[900]: info: Adding Item to queue: artists://%E9%A9%AC%E5%85%8B%E8%A5%BF%E5%A7%86.%E5%A7%86%E5%B0%94%E7%BB%B4%E5%AF%9F Apr 19 03:52:38 volumio volumio[900]: info: Exploding uri artists://%E9%A9%AC%E5%85%8B%E8%A5%BF%E5%A7%86.%E5%A7%86%E5%B0%94%E7%BB%B4%E5%AF%9F in service mpd Apr 19 03:52:38 volumio volumio[900]: info: CorePlayQueue::saveQueue Apr 19 03:52:38 volumio volumio[900]: info: CoreCommandRouter::volumioPushQueue Apr 19 03:52:38 volumio volumio[900]: info: CoreStateMachine::updateTrackBlock Apr 19 03:52:38 volumio volumio[900]: info: CorePlayQueue::getTrackBlock Apr 19 03:52:38 volumio volumio[900]: info: CoreCommandRouter::volumioPlay Apr 19 03:52:38 volumio volumio[900]: verbose: UNSET VOLATILE Apr 19 03:52:38 volumio volumio[900]: info: CoreStateMachine::play index 0 Apr 19 03:52:38 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:52:38 volumio volumio[900]: info: CoreStateMachine::stop Apr 19 03:52:38 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:52:38 volumio volumio[900]: verbose: UNSET VOLATILE Apr 19 03:52:38 volumio volumio[900]: info: CoreStateMachine::play index undefined Apr 19 03:52:38 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:52:38 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:38 volumio volumio[900]: info: CoreStateMachine::startPlaybackTimer Apr 19 03:52:38 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:38 volumio volumio[900]: verbose: ControllerMpd::clearAddPlayTracks USB/122882A228828487/音乐2/钢琴精选40首/马克西姆.姆尔维察 - Croatian Rhapsody.mp3 Apr 19 03:52:38 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 03:52:38 volumio volumio[900]: info: Apr 19 03:52:38 volumio volumio[900]: ---------------------------- MPD announces state update: player Apr 19 03:52:38 volumio volumio[900]: info: sendMpdCommand stop took 49 milliseconds Apr 19 03:52:38 volumio volumio[900]: info: ControllerMpd::getState Apr 19 03:52:38 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand status Apr 19 03:52:38 volumio volumio[900]: info: sendMpdCommand stop took 14 milliseconds Apr 19 03:52:38 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand clear Apr 19 03:52:38 volumio volumio[900]: info: Apr 19 03:52:38 volumio volumio[900]: ---------------------------- MPD announces system playlist update Apr 19 03:52:38 volumio volumio[900]: info: Ignoring MPD Status Update Apr 19 03:52:38 volumio volumio[900]: info: sendMpdCommand status took 2 milliseconds Apr 19 03:52:38 volumio volumio[900]: info: sendMpdCommand clear took 1 milliseconds Apr 19 03:52:38 volumio volumio[900]: verbose: ControllerMpd::parseState Apr 19 03:52:38 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 03:52:38 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand add "USB/122882A228828487/音乐2/钢琴精选40首/马克西姆.姆尔维察 - Croatian Rhapsody.mp3" Apr 19 03:52:38 volumio volumio[900]: error: updateQueue error: null Apr 19 03:52:38 volumio volumio[900]: info: Apr 19 03:52:38 volumio volumio[900]: ---------------------------- MPD announces system playlist update Apr 19 03:52:38 volumio volumio[900]: info: Ignoring MPD Status Update Apr 19 03:52:38 volumio volumio[900]: info: ------------------------------ 4ms Apr 19 03:52:38 volumio volumio[900]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 19 03:52:38 volumio volumio[900]: info: sendMpdCommand add "USB/122882A228828487/音乐2/钢琴精选40首/马克西姆.姆尔维察 - Croatian Rhapsody.mp3" took 2 milliseconds Apr 19 03:52:38 volumio volumio[900]: verbose: ControllerMpd::parseTrackInfo Apr 19 03:52:38 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand play Apr 19 03:52:38 volumio volumio[900]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 19 03:52:38 volumio volumio[900]: info: ------------------------------ 17ms Apr 19 03:52:38 volumio volumio[900]: info: ------------------------------ 12ms Apr 19 03:52:38 volumio volumio[900]: info: sendMpdCommand play took 11 milliseconds Apr 19 03:52:38 volumio volumio[900]: info: Apr 19 03:52:38 volumio volumio[900]: ---------------------------- MPD announces state update: player Apr 19 03:52:38 volumio volumio[900]: info: ControllerMpd::getState Apr 19 03:52:38 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand status Apr 19 03:52:38 volumio volumio[900]: info: Apr 19 03:52:38 volumio volumio[900]: ---------------------------- MPD announces state update: player Apr 19 03:52:38 volumio volumio[900]: info: ControllerMpd::getState Apr 19 03:52:38 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand status Apr 19 03:52:38 volumio volumio[900]: info: sendMpdCommand status took 13 milliseconds Apr 19 03:52:38 volumio volumio[900]: verbose: ControllerMpd::parseState Apr 19 03:52:38 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 03:52:38 volumio volumio[900]: info: sendMpdCommand status took 10 milliseconds Apr 19 03:52:38 volumio volumio[900]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 19 03:52:38 volumio volumio[900]: verbose: ControllerMpd::parseState Apr 19 03:52:38 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 03:52:38 volumio volumio[900]: verbose: ControllerMpd::parseTrackInfo Apr 19 03:52:38 volumio volumio[900]: info: ControllerMpd::pushState Apr 19 03:52:38 volumio volumio[900]: info: CoreCommandRouter::servicePushState Apr 19 03:52:38 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:38 volumio volumio[900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":104,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Croatian Rhapsody","artist":"马克西姆.姆尔维察","album":"The Piano Player","uri":"USB/122882A228828487/音乐2/钢琴精选40首/马克西姆.姆尔维察 - Croatian Rhapsody.mp3","trackType":"mp3"} Apr 19 03:52:38 volumio volumio[900]: verbose: CURRENT POSITION 0 Apr 19 03:52:38 volumio volumio[900]: info: CoreStateMachine::syncState stateService play Apr 19 03:52:38 volumio volumio[900]: info: CoreStateMachine::syncState currentStatus stop Apr 19 03:52:38 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:38 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:38 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:38 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:38 volumio volumio[900]: info: ------------------------------ 47ms Apr 19 03:52:38 volumio volumio[900]: info: sendMpdCommand playlistinfo took 24 milliseconds Apr 19 03:52:38 volumio volumio[900]: verbose: ControllerMpd::parseTrackInfo Apr 19 03:52:38 volumio volumio[900]: info: ControllerMpd::pushState Apr 19 03:52:38 volumio volumio[900]: info: CoreCommandRouter::servicePushState Apr 19 03:52:38 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:38 volumio volumio[900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Croatian Rhapsody","artist":"马克西姆.姆尔维察","album":"The Piano Player","uri":"USB/122882A228828487/音乐2/钢琴精选40首/马克西姆.姆尔维察 - Croatian Rhapsody.mp3","trackType":"mp3"} Apr 19 03:52:38 volumio volumio[900]: verbose: CURRENT POSITION 0 Apr 19 03:52:38 volumio volumio[900]: info: CoreStateMachine::syncState stateService play Apr 19 03:52:38 volumio volumio[900]: info: CoreStateMachine::syncState currentStatus play Apr 19 03:52:38 volumio volumio[900]: info: Received an update from plugin. extracting info from payload Apr 19 03:52:38 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:38 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:38 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:38 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:38 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:52:38 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:52:39 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:52:39 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:52:39 volumio volumio[900]: info: ------------------------------ 61ms Apr 19 03:52:50 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 19 03:52:50 volumio volumio[900]: info: CURURI: artists://%E9%BD%90%E8%B1%AB%26%E8%B5%B5%E7%85%A7 Apr 19 03:53:08 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 19 03:53:08 volumio volumio[900]: info: CURURI: artists://%E8%B5%B5%E9%9B%B7 Apr 19 03:53:26 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 19 03:53:26 volumio volumio[900]: info: CURURI: artists://Crepe Apr 19 03:53:28 volumio volumio[900]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 19 03:53:28 volumio volumio[900]: info: CoreStateMachine::ClearQueue Apr 19 03:53:28 volumio volumio[900]: info: CoreStateMachine::stop Apr 19 03:53:28 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:53:28 volumio volumio[900]: verbose: UNSET VOLATILE Apr 19 03:53:28 volumio volumio[900]: info: CoreStateMachine::stPlaybackTimer Apr 19 03:53:28 volumio volumio[900]: info: CoreStateMachine::updateTrackBlock Apr 19 03:53:28 volumio volumio[900]: info: CorePlayQueue::getTrackBlock Apr 19 03:53:28 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:53:28 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:53:28 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:53:28 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:53:28 volumio volumio[900]: info: CoreStateMachine::serviceStop Apr 19 03:53:28 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:53:28 volumio volumio[900]: info: CoreCommandRouter::serviceStop Apr 19 03:53:28 volumio volumio[900]: info: ControllerMpd::stop Apr 19 03:53:28 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 03:53:28 volumio volumio[900]: info: CorePlayQueue::clearPlayQueue Apr 19 03:53:28 volumio volumio[900]: info: CorePlayQueue::saveQueue Apr 19 03:53:28 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:53:28 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:53:28 volumio volumio[900]: info: CoreCommandRouter::volumioPushQueue Apr 19 03:53:28 volumio volumio[900]: info: CoreStateMachine::addQueueItems Apr 19 03:53:28 volumio volumio[900]: info: CorePlayQueue::addQueueItems Apr 19 03:53:28 volumio volumio[900]: info: Adding Item to queue: artists://Crepe Apr 19 03:53:28 volumio volumio[900]: info: Exploding uri artists://Crepe in service mpd Apr 19 03:53:28 volumio volumio[900]: info: CorePlayQueue::saveQueue Apr 19 03:53:28 volumio volumio[900]: info: CoreCommandRouter::volumioPushQueue Apr 19 03:53:28 volumio volumio[900]: info: CoreStateMachine::updateTrackBlock Apr 19 03:53:28 volumio volumio[900]: info: CorePlayQueue::getTrackBlock Apr 19 03:53:28 volumio volumio[900]: info: CoreCommandRouter::volumioPlay Apr 19 03:53:28 volumio volumio[900]: verbose: UNSET VOLATILE Apr 19 03:53:28 volumio volumio[900]: info: CoreStateMachine::play index 0 Apr 19 03:53:28 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:53:28 volumio volumio[900]: info: CoreStateMachine::stop Apr 19 03:53:28 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:53:28 volumio volumio[900]: verbose: UNSET VOLATILE Apr 19 03:53:28 volumio volumio[900]: info: CoreStateMachine::play index undefined Apr 19 03:53:28 volumio volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 03:53:28 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:53:28 volumio volumio[900]: info: CoreStateMachine::startPlaybackTimer Apr 19 03:53:28 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:53:28 volumio volumio[900]: verbose: ControllerMpd::clearAddPlayTracks USB/122882A228828487/音乐2/叙事钢琴/Crepe - 마녀라 불리는 아이.mp3 Apr 19 03:53:28 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 03:53:28 volumio volumio[900]: info: Apr 19 03:53:28 volumio volumio[900]: ---------------------------- MPD announces state update: player Apr 19 03:53:28 volumio volumio[900]: info: sendMpdCommand stop took 120 milliseconds Apr 19 03:53:28 volumio volumio[900]: info: ControllerMpd::getState Apr 19 03:53:28 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand status Apr 19 03:53:28 volumio volumio[900]: info: sendMpdCommand stop took 82 milliseconds Apr 19 03:53:28 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand clear Apr 19 03:53:28 volumio volumio[900]: info: Apr 19 03:53:28 volumio volumio[900]: ---------------------------- MPD announces system playlist update Apr 19 03:53:28 volumio volumio[900]: info: Ignoring MPD Status Update Apr 19 03:53:28 volumio volumio[900]: info: sendMpdCommand status took 6 milliseconds Apr 19 03:53:28 volumio volumio[900]: info: sendMpdCommand clear took 6 milliseconds Apr 19 03:53:28 volumio volumio[900]: verbose: ControllerMpd::parseState Apr 19 03:53:28 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 03:53:28 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand add "USB/122882A228828487/音乐2/叙事钢琴/Crepe - 마녀라 불리는 아이.mp3" Apr 19 03:53:28 volumio volumio[900]: error: updateQueue error: null Apr 19 03:53:28 volumio volumio[900]: info: Apr 19 03:53:28 volumio volumio[900]: ---------------------------- MPD announces system playlist update Apr 19 03:53:28 volumio volumio[900]: info: Ignoring MPD Status Update Apr 19 03:53:28 volumio volumio[900]: info: ------------------------------ 16ms Apr 19 03:53:28 volumio volumio[900]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 19 03:53:28 volumio volumio[900]: info: sendMpdCommand add "USB/122882A228828487/音乐2/叙事钢琴/Crepe - 마녀라 불리는 아이.mp3" took 7 milliseconds Apr 19 03:53:28 volumio volumio[900]: verbose: ControllerMpd::parseTrackInfo Apr 19 03:53:28 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand play Apr 19 03:53:28 volumio volumio[900]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 19 03:53:28 volumio volumio[900]: info: ------------------------------ 36ms Apr 19 03:53:28 volumio volumio[900]: info: ------------------------------ 12ms Apr 19 03:53:28 volumio volumio[900]: info: sendMpdCommand play took 6 milliseconds Apr 19 03:53:28 volumio volumio[900]: info: Apr 19 03:53:28 volumio volumio[900]: ---------------------------- MPD announces state update: player Apr 19 03:53:28 volumio volumio[900]: info: ControllerMpd::getState Apr 19 03:53:28 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand status Apr 19 03:53:28 volumio volumio[900]: info: sendMpdCommand status took 5 milliseconds Apr 19 03:53:28 volumio volumio[900]: verbose: ControllerMpd::parseState Apr 19 03:53:28 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 03:53:28 volumio volumio[900]: info: Apr 19 03:53:28 volumio volumio[900]: ---------------------------- MPD announces state update: player Apr 19 03:53:28 volumio volumio[900]: info: ControllerMpd::getState Apr 19 03:53:28 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand status Apr 19 03:53:28 volumio volumio[900]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 19 03:53:28 volumio volumio[900]: info: sendMpdCommand status took 4 milliseconds Apr 19 03:53:28 volumio volumio[900]: verbose: ControllerMpd::parseTrackInfo Apr 19 03:53:28 volumio volumio[900]: verbose: ControllerMpd::parseState Apr 19 03:53:28 volumio volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 03:53:28 volumio volumio[900]: info: ControllerMpd::pushState Apr 19 03:53:28 volumio volumio[900]: info: CoreCommandRouter::servicePushState Apr 19 03:53:28 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:53:28 volumio volumio[900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":215,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"마녀라 불리는 아이","artist":"Crepe","album":"Lucid Dream","uri":"USB/122882A228828487/音乐2/叙事钢琴/Crepe - 마녀라 불리는 아이.mp3","trackType":"mp3"} Apr 19 03:53:28 volumio volumio[900]: verbose: CURRENT POSITION 0 Apr 19 03:53:28 volumio volumio[900]: info: CoreStateMachine::syncState stateService play Apr 19 03:53:28 volumio volumio[900]: info: CoreStateMachine::syncState currentStatus stop Apr 19 03:53:28 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:53:28 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:53:28 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:53:28 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:53:28 volumio volumio[900]: info: ------------------------------ 31ms Apr 19 03:53:28 volumio volumio[900]: info: sendMpdCommand playlistinfo took 17 milliseconds Apr 19 03:53:28 volumio volumio[900]: verbose: ControllerMpd::parseTrackInfo Apr 19 03:53:28 volumio volumio[900]: info: ControllerMpd::pushState Apr 19 03:53:28 volumio volumio[900]: info: CoreCommandRouter::servicePushState Apr 19 03:53:28 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:53:28 volumio volumio[900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":81,"duration":215,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"마녀라 불리는 아이","artist":"Crepe","album":"Lucid Dream","uri":"USB/122882A228828487/音乐2/叙事钢琴/Crepe - 마녀라 불리는 아이.mp3","trackType":"mp3"} Apr 19 03:53:28 volumio volumio[900]: verbose: CURRENT POSITION 0 Apr 19 03:53:28 volumio volumio[900]: info: CoreStateMachine::syncState stateService play Apr 19 03:53:28 volumio volumio[900]: info: CoreStateMachine::syncState currentStatus play Apr 19 03:53:28 volumio volumio[900]: info: Received an update from plugin. extracting info from payload Apr 19 03:53:28 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:53:28 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:53:28 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:53:28 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:53:28 volumio volumio[900]: info: CoreStateMachine::pushState Apr 19 03:53:28 volumio volumio[900]: info: CorePlayQueue::getTrack 0 Apr 19 03:53:28 volumio volumio[900]: info: CoreCommandRouter::volumioPushState Apr 19 03:53:28 volumio volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 03:53:28 volumio volumio[900]: info: ------------------------------ 44ms Apr 19 03:53:49 volumio volumio[900]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 03:53:49 volumio volumio[900]: { Error: connect ETIMEDOUT 108.160.170.33:80 Apr 19 03:53:49 volumio volumio[900]: at Object._errnoException (util.js:1022:11) Apr 19 03:53:49 volumio volumio[900]: at _exceptionWithHostPort (util.js:1044:20) Apr 19 03:53:49 volumio volumio[900]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Apr 19 03:53:49 volumio volumio[900]: code: 'ETIMEDOUT', Apr 19 03:53:49 volumio volumio[900]: errno: 'ETIMEDOUT', Apr 19 03:53:49 volumio volumio[900]: syscall: 'connect', Apr 19 03:53:49 volumio volumio[900]: address: '108.160.170.33', Apr 19 03:53:49 volumio volumio[900]: port: 80 } Apr 19 03:53:49 volumio volumio[900]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 03:53:49 volumio sudo[5209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-19 03:52 Apr 19 03:53:49 volumio sudo[5209]: 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"