-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Mon 2025-03-10 23:29:12 CST. -- Mar 10 23:28:12 volumio3 volumio[9900]: info: Preload queue cleared Mar 10 23:28:12 volumio3 volumio[9900]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 10 23:28:12 volumio3 volumio[9900]: info: CoreStateMachine::ClearQueue Mar 10 23:28:12 volumio3 volumio[9900]: info: CoreStateMachine::stop Mar 10 23:28:12 volumio3 volumio[9900]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 10 23:28:12 volumio3 volumio[9900]: info: CorePlayQueue::clearPlayQueue Mar 10 23:28:12 volumio3 volumio[9900]: info: CorePlayQueue::saveQueue Mar 10 23:28:12 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushQueue Mar 10 23:28:12 volumio3 volumio[9900]: info: CoreStateMachine::addQueueItems Mar 10 23:28:12 volumio3 volumio[9900]: info: CorePlayQueue::addQueueItems Mar 10 23:28:12 volumio3 volumio[9900]: info: Preload queue cleared Mar 10 23:28:12 volumio3 volumio[9900]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99607260 Mar 10 23:28:12 volumio3 volumio[9900]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 10 23:28:12 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushQueue Mar 10 23:28:12 volumio3 volumio[9900]: info: CorePlayQueue::saveQueue Mar 10 23:28:12 volumio3 volumio[9900]: info: CoreStateMachine::updateTrackBlock Mar 10 23:28:12 volumio3 volumio[9900]: info: CorePlayQueue::getTrackBlock Mar 10 23:28:12 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPlay Mar 10 23:28:12 volumio3 volumio[9900]: info: CoreStateMachine::play index 0 Mar 10 23:28:12 volumio3 volumio[9900]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 10 23:28:12 volumio3 volumio[9900]: info: CoreStateMachine::stop Mar 10 23:28:12 volumio3 volumio[9900]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 10 23:28:12 volumio3 volumio[9900]: info: CoreStateMachine::play index undefined Mar 10 23:28:12 volumio3 volumio[9900]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 10 23:28:12 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:12 volumio3 volumio[9900]: info: CoreStateMachine::startPlaybackTimer Mar 10 23:28:12 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:12 volumio3 volumio[9900]: info: [1741620492902] ControllerWebradio::clearAddPlayTrack Mar 10 23:28:12 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand stop Mar 10 23:28:12 volumio3 volumio[9900]: info: sendMpdCommand stop took 5 milliseconds Mar 10 23:28:12 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand clear Mar 10 23:28:12 volumio3 volumio[9900]: info: Mar 10 23:28:12 volumio3 volumio[9900]: ---------------------------- MPD announces system playlist update Mar 10 23:28:12 volumio3 volumio[9900]: info: Ignoring MPD Status Update Mar 10 23:28:12 volumio3 volumio[9900]: info: sendMpdCommand clear took 2 milliseconds Mar 10 23:28:12 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99607260" Mar 10 23:28:12 volumio3 volumio[9900]: error: updateQueue error: null Mar 10 23:28:12 volumio3 volumio[9900]: info: ------------------------------ 3ms Mar 10 23:28:13 volumio3 volumio[9900]: info: Mar 10 23:28:13 volumio3 volumio[9900]: ---------------------------- MPD announces system playlist update Mar 10 23:28:13 volumio3 volumio[9900]: info: Ignoring MPD Status Update Mar 10 23:28:13 volumio3 volumio[9900]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99607260" took 245 milliseconds Mar 10 23:28:13 volumio3 volumio[9900]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 10 23:28:13 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand play Mar 10 23:28:13 volumio3 volumio[9900]: info: ------------------------------ 3ms Mar 10 23:28:13 volumio3 volumio[9900]: info: sendMpdCommand play took 2 milliseconds Mar 10 23:28:14 volumio3 kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Mar 10 23:28:14 volumio3 volumio[9900]: info: Mar 10 23:28:14 volumio3 volumio[9900]: ---------------------------- MPD announces state update: player Mar 10 23:28:14 volumio3 volumio[9900]: info: ControllerMpd::getState Mar 10 23:28:14 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand status Mar 10 23:28:14 volumio3 volumio[9900]: info: Mar 10 23:28:14 volumio3 volumio[9900]: ---------------------------- MPD announces state update: player Mar 10 23:28:14 volumio3 volumio[9900]: info: sendMpdCommand status took 17 milliseconds Mar 10 23:28:14 volumio3 volumio[9900]: info: ControllerMpd::getState Mar 10 23:28:14 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand status Mar 10 23:28:14 volumio3 volumio[9900]: verbose: ControllerMpd::parseState Mar 10 23:28:14 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 10 23:28:14 volumio3 volumio[9900]: info: sendMpdCommand status took 3 milliseconds Mar 10 23:28:14 volumio3 volumio[9900]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 10 23:28:14 volumio3 volumio[9900]: verbose: ControllerMpd::parseState Mar 10 23:28:14 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 10 23:28:14 volumio3 volumio[9900]: verbose: ControllerMpd::parseTrackInfo Mar 10 23:28:14 volumio3 volumio[9900]: info: ControllerMpd::pushState Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreCommandRouter::servicePushState Mar 10 23:28:14 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:14 volumio3 volumio[9900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio.mp3","artist":"(#1 - 49/9999) SMOOTH JAZZ DELUXE","album":null,"uri":"https://az1.mediacp.eu:8200/radio.mp3","trackType":"mp3"} Mar 10 23:28:14 volumio3 volumio[9900]: verbose: CURRENT POSITION 0 Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreStateMachine::syncState stateService play Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreStateMachine::syncState currentStatus stop Mar 10 23:28:14 volumio3 volumio[9900]: info: ------------------------------ 26ms Mar 10 23:28:14 volumio3 volumio[9900]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 10 23:28:14 volumio3 volumio[9900]: verbose: ControllerMpd::parseTrackInfo Mar 10 23:28:14 volumio3 volumio[9900]: info: ControllerMpd::pushState Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreCommandRouter::servicePushState Mar 10 23:28:14 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:14 volumio3 volumio[9900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio.mp3","artist":"(#1 - 49/9999) SMOOTH JAZZ DELUXE","album":null,"uri":"https://az1.mediacp.eu:8200/radio.mp3","trackType":"mp3"} Mar 10 23:28:14 volumio3 volumio[9900]: verbose: CURRENT POSITION 0 Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreStateMachine::syncState stateService play Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreStateMachine::syncState currentStatus play Mar 10 23:28:14 volumio3 volumio[9900]: info: Received an update from plugin. extracting info from payload Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreStateMachine::pushState Mar 10 23:28:14 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushState Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreStateMachine::pushState Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushState Mar 10 23:28:14 volumio3 volumio[9900]: info: ------------------------------ 39ms Mar 10 23:28:14 volumio3 volumio[9900]: info: Mar 10 23:28:14 volumio3 volumio[9900]: ---------------------------- MPD announces system playlist update Mar 10 23:28:14 volumio3 volumio[9900]: info: Ignoring MPD Status Update Mar 10 23:28:14 volumio3 volumio[9900]: info: Mar 10 23:28:14 volumio3 volumio[9900]: ---------------------------- MPD announces state update: player Mar 10 23:28:14 volumio3 volumio[9900]: info: ControllerMpd::getState Mar 10 23:28:14 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand status Mar 10 23:28:14 volumio3 volumio[9900]: info: Mar 10 23:28:14 volumio3 volumio[9900]: ---------------------------- MPD announces system playlist update Mar 10 23:28:14 volumio3 volumio[9900]: info: Ignoring MPD Status Update Mar 10 23:28:14 volumio3 volumio[9900]: info: Mar 10 23:28:14 volumio3 volumio[9900]: ---------------------------- MPD announces state update: player Mar 10 23:28:14 volumio3 volumio[9900]: info: ControllerMpd::getState Mar 10 23:28:14 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand status Mar 10 23:28:14 volumio3 volumio[9900]: info: ------------------------------ 7ms Mar 10 23:28:14 volumio3 volumio[9900]: info: sendMpdCommand status took 4 milliseconds Mar 10 23:28:14 volumio3 volumio[9900]: verbose: ControllerMpd::parseState Mar 10 23:28:14 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 10 23:28:14 volumio3 volumio[9900]: info: ------------------------------ 8ms Mar 10 23:28:14 volumio3 volumio[9900]: info: sendMpdCommand status took 5 milliseconds Mar 10 23:28:14 volumio3 volumio[9900]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 10 23:28:14 volumio3 volumio[9900]: verbose: ControllerMpd::parseState Mar 10 23:28:14 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 10 23:28:14 volumio3 volumio[9900]: verbose: ControllerMpd::parseTrackInfo Mar 10 23:28:14 volumio3 volumio[9900]: info: ControllerMpd::pushState Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreCommandRouter::servicePushState Mar 10 23:28:14 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:14 volumio3 volumio[9900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Peter White - For the Love of You (Album Version)","artist":"SMOOTH JAZZ DELUXE","album":null,"uri":"https://az1.mediacp.eu:8200/radio.mp3","trackType":"mp3"} Mar 10 23:28:14 volumio3 volumio[9900]: verbose: CURRENT POSITION 0 Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreStateMachine::syncState stateService play Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreStateMachine::syncState currentStatus play Mar 10 23:28:14 volumio3 volumio[9900]: info: Received an update from plugin. extracting info from payload Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreStateMachine::pushState Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushState Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreStateMachine::pushState Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushState Mar 10 23:28:14 volumio3 volumio[9900]: info: ------------------------------ 44ms Mar 10 23:28:14 volumio3 volumio[9900]: info: sendMpdCommand playlistinfo took 32 milliseconds Mar 10 23:28:14 volumio3 volumio[9900]: verbose: ControllerMpd::parseTrackInfo Mar 10 23:28:14 volumio3 volumio[9900]: info: ControllerMpd::pushState Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreCommandRouter::servicePushState Mar 10 23:28:14 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:14 volumio3 volumio[9900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Peter White - For the Love of You (Album Version)","artist":"SMOOTH JAZZ DELUXE","album":null,"uri":"https://az1.mediacp.eu:8200/radio.mp3","trackType":"mp3"} Mar 10 23:28:14 volumio3 volumio[9900]: verbose: CURRENT POSITION 0 Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreStateMachine::syncState stateService play Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreStateMachine::syncState currentStatus play Mar 10 23:28:14 volumio3 volumio[9900]: info: Received an update from plugin. extracting info from payload Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreStateMachine::pushState Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushState Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreStateMachine::pushState Mar 10 23:28:14 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushState Mar 10 23:28:14 volumio3 volumio[9900]: info: ------------------------------ 67ms Mar 10 23:28:18 volumio3 volumio[9900]: info: Saving local item radio-favourites Mar 10 23:28:18 volumio3 volumio[9900]: info: Pushing Favourites {"uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99607260","favourite":false} Mar 10 23:28:20 volumio3 wpa_supplicant[735]: wlan0: CTRL-EVENT-BEACON-LOSS Mar 10 23:28:33 volumio3 volumio[9900]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 10 23:28:34 volumio3 volumio[9900]: info: Preload queue cleared Mar 10 23:28:37 volumio3 volumio[9900]: info: Preload queue cleared Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreStateMachine::ClearQueue Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreStateMachine::stop Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreStateMachine::stPlaybackTimer Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreStateMachine::updateTrackBlock Mar 10 23:28:37 volumio3 volumio[9900]: info: CorePlayQueue::getTrackBlock Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreStateMachine::pushState Mar 10 23:28:37 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushState Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreStateMachine::serviceStop Mar 10 23:28:37 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreCommandRouter::serviceStop Mar 10 23:28:37 volumio3 volumio[9900]: info: [1741620517428] ControllerWebradio::stop Mar 10 23:28:37 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand stop Mar 10 23:28:37 volumio3 volumio[9900]: info: CorePlayQueue::clearPlayQueue Mar 10 23:28:37 volumio3 volumio[9900]: info: CorePlayQueue::saveQueue Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushQueue Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreStateMachine::addQueueItems Mar 10 23:28:37 volumio3 volumio[9900]: info: CorePlayQueue::addQueueItems Mar 10 23:28:37 volumio3 volumio[9900]: info: Preload queue cleared Mar 10 23:28:37 volumio3 volumio[9900]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99594606 Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushQueue Mar 10 23:28:37 volumio3 volumio[9900]: info: CorePlayQueue::saveQueue Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreStateMachine::updateTrackBlock Mar 10 23:28:37 volumio3 volumio[9900]: info: CorePlayQueue::getTrackBlock Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPlay Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreStateMachine::play index 0 Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreStateMachine::stop Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreStateMachine::play index undefined Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 10 23:28:37 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreStateMachine::startPlaybackTimer Mar 10 23:28:37 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:37 volumio3 volumio[9900]: info: [1741620517464] ControllerWebradio::clearAddPlayTrack Mar 10 23:28:37 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand stop Mar 10 23:28:37 volumio3 volumio[9900]: info: sendMpdCommand stop took 142 milliseconds Mar 10 23:28:37 volumio3 volumio[9900]: info: Mar 10 23:28:37 volumio3 volumio[9900]: ---------------------------- MPD announces state update: player Mar 10 23:28:37 volumio3 volumio[9900]: info: ControllerMpd::getState Mar 10 23:28:37 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand status Mar 10 23:28:37 volumio3 volumio[9900]: info: sendMpdCommand stop took 109 milliseconds Mar 10 23:28:37 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand clear Mar 10 23:28:37 volumio3 volumio[9900]: info: Mar 10 23:28:37 volumio3 volumio[9900]: ---------------------------- MPD announces system playlist update Mar 10 23:28:37 volumio3 volumio[9900]: info: Ignoring MPD Status Update Mar 10 23:28:37 volumio3 volumio[9900]: info: sendMpdCommand status took 5 milliseconds Mar 10 23:28:37 volumio3 volumio[9900]: info: sendMpdCommand clear took 3 milliseconds Mar 10 23:28:37 volumio3 volumio[9900]: verbose: ControllerMpd::parseState Mar 10 23:28:37 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 10 23:28:37 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99594606" Mar 10 23:28:37 volumio3 volumio[9900]: error: updateQueue error: null Mar 10 23:28:37 volumio3 volumio[9900]: info: ------------------------------ 7ms Mar 10 23:28:37 volumio3 volumio[9900]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 10 23:28:37 volumio3 volumio[9900]: verbose: ControllerMpd::parseTrackInfo Mar 10 23:28:37 volumio3 volumio[9900]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 10 23:28:37 volumio3 volumio[9900]: info: ------------------------------ 16ms Mar 10 23:28:37 volumio3 volumio[9900]: info: Mar 10 23:28:37 volumio3 volumio[9900]: ---------------------------- MPD announces system playlist update Mar 10 23:28:37 volumio3 volumio[9900]: info: Ignoring MPD Status Update Mar 10 23:28:37 volumio3 volumio[9900]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99594606" took 240 milliseconds Mar 10 23:28:37 volumio3 volumio[9900]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 10 23:28:37 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand play Mar 10 23:28:37 volumio3 volumio[9900]: info: ------------------------------ 5ms Mar 10 23:28:37 volumio3 volumio[9900]: info: sendMpdCommand play took 2 milliseconds Mar 10 23:28:38 volumio3 volumio[9900]: info: Mar 10 23:28:38 volumio3 volumio[9900]: ---------------------------- MPD announces state update: player Mar 10 23:28:38 volumio3 volumio[9900]: info: ControllerMpd::getState Mar 10 23:28:38 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand status Mar 10 23:28:38 volumio3 kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Mar 10 23:28:38 volumio3 volumio[9900]: info: Mar 10 23:28:38 volumio3 volumio[9900]: ---------------------------- MPD announces state update: player Mar 10 23:28:38 volumio3 volumio[9900]: info: sendMpdCommand status took 12 milliseconds Mar 10 23:28:38 volumio3 volumio[9900]: info: ControllerMpd::getState Mar 10 23:28:38 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand status Mar 10 23:28:38 volumio3 volumio[9900]: verbose: ControllerMpd::parseState Mar 10 23:28:38 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 10 23:28:38 volumio3 volumio[9900]: info: sendMpdCommand status took 2 milliseconds Mar 10 23:28:38 volumio3 volumio[9900]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 10 23:28:38 volumio3 volumio[9900]: verbose: ControllerMpd::parseState Mar 10 23:28:38 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 10 23:28:38 volumio3 volumio[9900]: verbose: ControllerMpd::parseTrackInfo Mar 10 23:28:38 volumio3 volumio[9900]: info: ControllerMpd::pushState Mar 10 23:28:38 volumio3 volumio[9900]: info: CoreCommandRouter::servicePushState Mar 10 23:28:38 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:38 volumio3 volumio[9900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 102/1000) CD 101.9 Smooth Jazz","album":null,"uri":"http://server.webnetradio.net:5334/stream","trackType":""} Mar 10 23:28:38 volumio3 volumio[9900]: verbose: CURRENT POSITION 0 Mar 10 23:28:38 volumio3 volumio[9900]: info: CoreStateMachine::syncState stateService play Mar 10 23:28:38 volumio3 volumio[9900]: info: CoreStateMachine::syncState currentStatus stop Mar 10 23:28:38 volumio3 volumio[9900]: info: ------------------------------ 23ms Mar 10 23:28:38 volumio3 volumio[9900]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 10 23:28:38 volumio3 volumio[9900]: verbose: ControllerMpd::parseTrackInfo Mar 10 23:28:38 volumio3 volumio[9900]: info: ControllerMpd::pushState Mar 10 23:28:38 volumio3 volumio[9900]: info: CoreCommandRouter::servicePushState Mar 10 23:28:38 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:38 volumio3 volumio[9900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 102/1000) CD 101.9 Smooth Jazz","album":null,"uri":"http://server.webnetradio.net:5334/stream","trackType":""} Mar 10 23:28:38 volumio3 volumio[9900]: verbose: CURRENT POSITION 0 Mar 10 23:28:38 volumio3 volumio[9900]: info: CoreStateMachine::syncState stateService play Mar 10 23:28:38 volumio3 volumio[9900]: info: CoreStateMachine::syncState currentStatus play Mar 10 23:28:38 volumio3 volumio[9900]: info: Received an update from plugin. extracting info from payload Mar 10 23:28:38 volumio3 volumio[9900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 10 23:28:38 volumio3 volumio[9900]: info: CoreStateMachine::pushState Mar 10 23:28:38 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:38 volumio3 volumio[9900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 10 23:28:38 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushState Mar 10 23:28:38 volumio3 volumio[9900]: info: CoreStateMachine::pushState Mar 10 23:28:38 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushState Mar 10 23:28:38 volumio3 volumio[9900]: info: ------------------------------ 38ms Mar 10 23:28:39 volumio3 volumio[9900]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%20102%2F1000)%20CD%20101.9%20Smooth%20Jazz/a1ef995a-909e-4edd-9d3b-d36160f77ceb.jpg' Mar 10 23:28:39 volumio3 volumio[9900]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%20102%2F1000)%20CD%20101.9%20Smooth%20Jazz/a1ef995a-909e-4edd-9d3b-d36160f77ceb.jpg' Mar 10 23:28:39 volumio3 volumio[9900]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%20102%2F1000)%20CD%20101.9%20Smooth%20Jazz/a1ef995a-909e-4edd-9d3b-d36160f77ceb.jpg' Mar 10 23:28:39 volumio3 volumio[9900]: info: Mar 10 23:28:39 volumio3 volumio[9900]: ---------------------------- MPD announces system playlist update Mar 10 23:28:39 volumio3 volumio[9900]: info: Ignoring MPD Status Update Mar 10 23:28:39 volumio3 volumio[9900]: info: Mar 10 23:28:39 volumio3 volumio[9900]: ---------------------------- MPD announces state update: player Mar 10 23:28:39 volumio3 volumio[9900]: info: ControllerMpd::getState Mar 10 23:28:39 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand status Mar 10 23:28:39 volumio3 volumio[9900]: info: Mar 10 23:28:39 volumio3 volumio[9900]: ---------------------------- MPD announces system playlist update Mar 10 23:28:39 volumio3 volumio[9900]: info: Ignoring MPD Status Update Mar 10 23:28:39 volumio3 volumio[9900]: info: Mar 10 23:28:39 volumio3 volumio[9900]: ---------------------------- MPD announces state update: player Mar 10 23:28:39 volumio3 volumio[9900]: info: ControllerMpd::getState Mar 10 23:28:39 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand status Mar 10 23:28:39 volumio3 volumio[9900]: info: ------------------------------ 12ms Mar 10 23:28:39 volumio3 volumio[9900]: info: sendMpdCommand status took 9 milliseconds Mar 10 23:28:39 volumio3 volumio[9900]: verbose: ControllerMpd::parseState Mar 10 23:28:39 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 10 23:28:39 volumio3 volumio[9900]: info: ------------------------------ 10ms Mar 10 23:28:39 volumio3 volumio[9900]: info: sendMpdCommand status took 4 milliseconds Mar 10 23:28:39 volumio3 volumio[9900]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 10 23:28:39 volumio3 volumio[9900]: verbose: ControllerMpd::parseState Mar 10 23:28:39 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 10 23:28:39 volumio3 volumio[9900]: verbose: ControllerMpd::parseTrackInfo Mar 10 23:28:39 volumio3 volumio[9900]: info: ControllerMpd::pushState Mar 10 23:28:39 volumio3 volumio[9900]: info: CoreCommandRouter::servicePushState Mar 10 23:28:39 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:39 volumio3 volumio[9900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Matt Bianco with Basia - La Luna","artist":"CD 101.9 Smooth Jazz","album":null,"uri":"http://server.webnetradio.net:5334/stream","trackType":""} Mar 10 23:28:39 volumio3 volumio[9900]: verbose: CURRENT POSITION 0 Mar 10 23:28:39 volumio3 volumio[9900]: info: CoreStateMachine::syncState stateService play Mar 10 23:28:39 volumio3 volumio[9900]: info: CoreStateMachine::syncState currentStatus play Mar 10 23:28:39 volumio3 volumio[9900]: info: Received an update from plugin. extracting info from payload Mar 10 23:28:39 volumio3 volumio[9900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 10 23:28:39 volumio3 volumio[9900]: info: CoreStateMachine::pushState Mar 10 23:28:39 volumio3 volumio[9900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 10 23:28:39 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushState Mar 10 23:28:39 volumio3 volumio[9900]: info: CoreStateMachine::pushState Mar 10 23:28:39 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushState Mar 10 23:28:39 volumio3 volumio[9900]: info: ------------------------------ 41ms Mar 10 23:28:39 volumio3 volumio[9900]: info: sendMpdCommand playlistinfo took 26 milliseconds Mar 10 23:28:39 volumio3 volumio[9900]: verbose: ControllerMpd::parseTrackInfo Mar 10 23:28:39 volumio3 volumio[9900]: info: ControllerMpd::pushState Mar 10 23:28:39 volumio3 volumio[9900]: info: CoreCommandRouter::servicePushState Mar 10 23:28:39 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:39 volumio3 volumio[9900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Matt Bianco with Basia - La Luna","artist":"CD 101.9 Smooth Jazz","album":null,"uri":"http://server.webnetradio.net:5334/stream","trackType":""} Mar 10 23:28:39 volumio3 volumio[9900]: verbose: CURRENT POSITION 0 Mar 10 23:28:39 volumio3 volumio[9900]: info: CoreStateMachine::syncState stateService play Mar 10 23:28:39 volumio3 volumio[9900]: info: CoreStateMachine::syncState currentStatus play Mar 10 23:28:39 volumio3 volumio[9900]: info: Received an update from plugin. extracting info from payload Mar 10 23:28:39 volumio3 volumio[9900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 10 23:28:39 volumio3 volumio[9900]: info: CoreStateMachine::pushState Mar 10 23:28:39 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushState Mar 10 23:28:39 volumio3 volumio[9900]: info: CoreStateMachine::pushState Mar 10 23:28:39 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushState Mar 10 23:28:39 volumio3 volumio[9900]: info: ------------------------------ 65ms Mar 10 23:28:43 volumio3 volumio[9900]: info: Preload queue cleared Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreStateMachine::ClearQueue Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreStateMachine::stop Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreStateMachine::stPlaybackTimer Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreStateMachine::updateTrackBlock Mar 10 23:28:43 volumio3 volumio[9900]: info: CorePlayQueue::getTrackBlock Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreStateMachine::pushState Mar 10 23:28:43 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushState Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreStateMachine::serviceStop Mar 10 23:28:43 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreCommandRouter::serviceStop Mar 10 23:28:43 volumio3 volumio[9900]: info: [1741620523244] ControllerWebradio::stop Mar 10 23:28:43 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand stop Mar 10 23:28:43 volumio3 volumio[9900]: info: CorePlayQueue::clearPlayQueue Mar 10 23:28:43 volumio3 volumio[9900]: info: CorePlayQueue::saveQueue Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushQueue Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreStateMachine::addQueueItems Mar 10 23:28:43 volumio3 volumio[9900]: info: CorePlayQueue::addQueueItems Mar 10 23:28:43 volumio3 volumio[9900]: info: Preload queue cleared Mar 10 23:28:43 volumio3 volumio[9900]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1682343 Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushQueue Mar 10 23:28:43 volumio3 volumio[9900]: info: CorePlayQueue::saveQueue Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreStateMachine::updateTrackBlock Mar 10 23:28:43 volumio3 volumio[9900]: info: CorePlayQueue::getTrackBlock Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPlay Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreStateMachine::play index 0 Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreStateMachine::stop Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreStateMachine::play index undefined Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 10 23:28:43 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreStateMachine::startPlaybackTimer Mar 10 23:28:43 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:43 volumio3 volumio[9900]: info: [1741620523272] ControllerWebradio::clearAddPlayTrack Mar 10 23:28:43 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand stop Mar 10 23:28:43 volumio3 volumio[9900]: info: Mar 10 23:28:43 volumio3 volumio[9900]: ---------------------------- MPD announces state update: player Mar 10 23:28:43 volumio3 volumio[9900]: info: sendMpdCommand stop took 40 milliseconds Mar 10 23:28:43 volumio3 volumio[9900]: info: ControllerMpd::getState Mar 10 23:28:43 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand status Mar 10 23:28:43 volumio3 volumio[9900]: info: sendMpdCommand stop took 13 milliseconds Mar 10 23:28:43 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand clear Mar 10 23:28:43 volumio3 volumio[9900]: info: Mar 10 23:28:43 volumio3 volumio[9900]: ---------------------------- MPD announces system playlist update Mar 10 23:28:43 volumio3 volumio[9900]: info: Ignoring MPD Status Update Mar 10 23:28:43 volumio3 volumio[9900]: info: sendMpdCommand status took 3 milliseconds Mar 10 23:28:43 volumio3 volumio[9900]: info: sendMpdCommand clear took 2 milliseconds Mar 10 23:28:43 volumio3 volumio[9900]: verbose: ControllerMpd::parseState Mar 10 23:28:43 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 10 23:28:43 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1682343" Mar 10 23:28:43 volumio3 volumio[9900]: error: updateQueue error: null Mar 10 23:28:43 volumio3 volumio[9900]: info: ------------------------------ 5ms Mar 10 23:28:43 volumio3 volumio[9900]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 10 23:28:43 volumio3 volumio[9900]: verbose: ControllerMpd::parseTrackInfo Mar 10 23:28:43 volumio3 volumio[9900]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 10 23:28:43 volumio3 volumio[9900]: info: ------------------------------ 11ms Mar 10 23:28:43 volumio3 volumio[9900]: info: Mar 10 23:28:43 volumio3 volumio[9900]: ---------------------------- MPD announces system playlist update Mar 10 23:28:43 volumio3 volumio[9900]: info: Ignoring MPD Status Update Mar 10 23:28:43 volumio3 volumio[9900]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1682343" took 243 milliseconds Mar 10 23:28:43 volumio3 volumio[9900]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 10 23:28:43 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand play Mar 10 23:28:43 volumio3 volumio[9900]: info: ------------------------------ 4ms Mar 10 23:28:43 volumio3 volumio[9900]: info: sendMpdCommand play took 2 milliseconds Mar 10 23:28:44 volumio3 volumio[9900]: info: Mar 10 23:28:44 volumio3 volumio[9900]: ---------------------------- MPD announces state update: player Mar 10 23:28:44 volumio3 volumio[9900]: info: ControllerMpd::getState Mar 10 23:28:44 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand status Mar 10 23:28:44 volumio3 kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Mar 10 23:28:44 volumio3 volumio[9900]: info: Mar 10 23:28:44 volumio3 volumio[9900]: ---------------------------- MPD announces state update: player Mar 10 23:28:44 volumio3 volumio[9900]: info: sendMpdCommand status took 15 milliseconds Mar 10 23:28:44 volumio3 volumio[9900]: info: ControllerMpd::getState Mar 10 23:28:44 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand status Mar 10 23:28:44 volumio3 volumio[9900]: verbose: ControllerMpd::parseState Mar 10 23:28:44 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 10 23:28:44 volumio3 volumio[9900]: info: sendMpdCommand status took 2 milliseconds Mar 10 23:28:44 volumio3 volumio[9900]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 10 23:28:44 volumio3 volumio[9900]: verbose: ControllerMpd::parseState Mar 10 23:28:44 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 10 23:28:44 volumio3 volumio[9900]: verbose: ControllerMpd::parseTrackInfo Mar 10 23:28:44 volumio3 volumio[9900]: info: ControllerMpd::pushState Mar 10 23:28:44 volumio3 volumio[9900]: info: CoreCommandRouter::servicePushState Mar 10 23:28:44 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:44 volumio3 volumio[9900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1992_128.mp3?fromyp=true","artist":"BluesMusicFan Radio","album":null,"uri":"http://216.235.80.104:80/1992_128.mp3?fromyp=true","trackType":""} Mar 10 23:28:44 volumio3 volumio[9900]: verbose: CURRENT POSITION 0 Mar 10 23:28:44 volumio3 volumio[9900]: info: CoreStateMachine::syncState stateService play Mar 10 23:28:44 volumio3 volumio[9900]: info: CoreStateMachine::syncState currentStatus stop Mar 10 23:28:44 volumio3 volumio[9900]: info: ------------------------------ 24ms Mar 10 23:28:44 volumio3 volumio[9900]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 10 23:28:44 volumio3 volumio[9900]: verbose: ControllerMpd::parseTrackInfo Mar 10 23:28:44 volumio3 volumio[9900]: info: ControllerMpd::pushState Mar 10 23:28:44 volumio3 volumio[9900]: info: CoreCommandRouter::servicePushState Mar 10 23:28:44 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:44 volumio3 volumio[9900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1992_128.mp3?fromyp=true","artist":"BluesMusicFan Radio","album":null,"uri":"http://216.235.80.104:80/1992_128.mp3?fromyp=true","trackType":""} Mar 10 23:28:44 volumio3 volumio[9900]: verbose: CURRENT POSITION 0 Mar 10 23:28:44 volumio3 volumio[9900]: info: CoreStateMachine::syncState stateService play Mar 10 23:28:44 volumio3 volumio[9900]: info: CoreStateMachine::syncState currentStatus play Mar 10 23:28:44 volumio3 volumio[9900]: info: Received an update from plugin. extracting info from payload Mar 10 23:28:44 volumio3 volumio[9900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 10 23:28:44 volumio3 volumio[9900]: info: CoreStateMachine::pushState Mar 10 23:28:44 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:44 volumio3 volumio[9900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 10 23:28:44 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushState Mar 10 23:28:44 volumio3 volumio[9900]: info: CoreStateMachine::pushState Mar 10 23:28:44 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushState Mar 10 23:28:44 volumio3 volumio[9900]: info: ------------------------------ 35ms Mar 10 23:28:44 volumio3 volumio[9900]: info: Mar 10 23:28:44 volumio3 volumio[9900]: ---------------------------- MPD announces system playlist update Mar 10 23:28:44 volumio3 volumio[9900]: info: Ignoring MPD Status Update Mar 10 23:28:44 volumio3 volumio[9900]: info: Mar 10 23:28:44 volumio3 volumio[9900]: ---------------------------- MPD announces state update: player Mar 10 23:28:44 volumio3 volumio[9900]: info: ControllerMpd::getState Mar 10 23:28:44 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand status Mar 10 23:28:44 volumio3 volumio[9900]: info: ------------------------------ 4ms Mar 10 23:28:44 volumio3 volumio[9900]: info: sendMpdCommand status took 2 milliseconds Mar 10 23:28:44 volumio3 volumio[9900]: verbose: ControllerMpd::parseState Mar 10 23:28:44 volumio3 volumio[9900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 10 23:28:44 volumio3 volumio[9900]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 10 23:28:44 volumio3 volumio[9900]: verbose: ControllerMpd::parseTrackInfo Mar 10 23:28:44 volumio3 volumio[9900]: info: ControllerMpd::pushState Mar 10 23:28:44 volumio3 volumio[9900]: info: CoreCommandRouter::servicePushState Mar 10 23:28:44 volumio3 volumio[9900]: info: CorePlayQueue::getTrack 0 Mar 10 23:28:44 volumio3 volumio[9900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Smoke Wagon Blues Band - Matapedia River Blues","artist":"BluesMusicFan Radio","album":null,"uri":"http://216.235.80.104:80/1992_128.mp3?fromyp=true","trackType":""} Mar 10 23:28:44 volumio3 volumio[9900]: verbose: CURRENT POSITION 0 Mar 10 23:28:44 volumio3 volumio[9900]: info: CoreStateMachine::syncState stateService play Mar 10 23:28:44 volumio3 volumio[9900]: info: CoreStateMachine::syncState currentStatus play Mar 10 23:28:44 volumio3 volumio[9900]: info: Received an update from plugin. extracting info from payload Mar 10 23:28:44 volumio3 volumio[9900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 10 23:28:44 volumio3 volumio[9900]: info: CoreStateMachine::pushState Mar 10 23:28:44 volumio3 volumio[9900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 10 23:28:44 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushState Mar 10 23:28:44 volumio3 volumio[9900]: info: CoreStateMachine::pushState Mar 10 23:28:44 volumio3 volumio[9900]: info: CoreCommandRouter::volumioPushState Mar 10 23:28:44 volumio3 volumio[9900]: info: ------------------------------ 34ms Mar 10 23:29:10 volumio3 wpa_supplicant[735]: wlan0: CTRL-EVENT-BEACON-LOSS Mar 10 23:29:11 volumio3 volumio[9900]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 10 23:29:12 volumio3 volumio[9900]: Error: connect ETIMEDOUT 108.160.166.253:80 Mar 10 23:29:12 volumio3 volumio[9900]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Mar 10 23:29:12 volumio3 volumio[9900]: errno: -110, Mar 10 23:29:12 volumio3 volumio[9900]: code: 'ETIMEDOUT', Mar 10 23:29:12 volumio3 volumio[9900]: syscall: 'connect', Mar 10 23:29:12 volumio3 volumio[9900]: address: '108.160.166.253', Mar 10 23:29:12 volumio3 volumio[9900]: port: 80 Mar 10 23:29:12 volumio3 volumio[9900]: } Mar 10 23:29:12 volumio3 volumio[9900]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 10 23:29:12 volumio3 sudo[10123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-10 23:28 Mar 10 23:29:12 volumio3 sudo[10123]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"