-- Logs begin at Thu 2019-02-14 11:12:03 CET, end at Fri 2024-10-04 16:46:40 CEST. -- Oct 04 16:45:02 amp volumio[638]: info: CoreCommandRouter::volumioGetState Oct 04 16:45:02 amp volumio[638]: info: CorePlayQueue::getTrack 2 Oct 04 16:45:12 amp volumio[638]: info: CoreCommandRouter::volumioGetState Oct 04 16:45:12 amp volumio[638]: info: CorePlayQueue::getTrack 2 Oct 04 16:45:12 amp volumio[638]: info: Listing playlists Oct 04 16:45:12 amp volumio[638]: info: Listing playlists Oct 04 16:45:22 amp volumio[638]: info: CoreCommandRouter::volumioGetState Oct 04 16:45:22 amp volumio[638]: info: CorePlayQueue::getTrack 2 Oct 04 16:45:23 amp volumio[638]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 04 16:45:23 amp volumio[638]: info: Preload queue cleared Oct 04 16:45:25 amp volumio[638]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 04 16:45:25 amp volumio[638]: info: Preload queue cleared Oct 04 16:45:30 amp volumio[638]: info: Preload queue cleared Oct 04 16:45:30 amp volumio[638]: info: CoreCommandRouter::volumioAddQueueItems Oct 04 16:45:30 amp volumio[638]: info: CoreStateMachine::addQueueItems Oct 04 16:45:30 amp volumio[638]: info: CorePlayQueue::addQueueItems Oct 04 16:45:30 amp volumio[638]: info: Preload queue cleared Oct 04 16:45:30 amp volumio[638]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s296663 Oct 04 16:45:30 amp volumio[638]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 04 16:45:31 amp volumio[638]: info: CoreCommandRouter::volumioPushQueue Oct 04 16:45:31 amp volumio[638]: info: CorePlayQueue::saveQueue Oct 04 16:45:31 amp volumio[638]: info: CoreStateMachine::updateTrackBlock Oct 04 16:45:31 amp volumio[638]: info: CorePlayQueue::getTrackBlock Oct 04 16:45:31 amp volumio[638]: info: CoreCommandRouter::volumioPlay Oct 04 16:45:31 amp volumio[638]: info: CoreStateMachine::play index 17 Oct 04 16:45:31 amp volumio[638]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 04 16:45:31 amp volumio[638]: info: CoreStateMachine::stop Oct 04 16:45:31 amp volumio[638]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 04 16:45:31 amp volumio[638]: info: CoreStateMachine::play index undefined Oct 04 16:45:31 amp volumio[638]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 04 16:45:31 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:31 amp volumio[638]: info: CoreStateMachine::startPlaybackTimer Oct 04 16:45:31 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:31 amp volumio[638]: info: [1728053131591] ControllerWebradio::clearAddPlayTrack Oct 04 16:45:31 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand stop Oct 04 16:45:31 amp volumio[638]: info: sendMpdCommand stop took 161 milliseconds Oct 04 16:45:31 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand clear Oct 04 16:45:31 amp volumio[638]: info: Oct 04 16:45:31 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:45:31 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:45:31 amp volumio[638]: info: sendMpdCommand clear took 65 milliseconds Oct 04 16:45:31 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand load "https://server-23.stream-server.nl:18118/stream" Oct 04 16:45:31 amp volumio[638]: info: Oct 04 16:45:31 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:45:31 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:45:31 amp volumio[638]: info: Oct 04 16:45:31 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:45:31 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:45:31 amp volumio[638]: error: updateQueue error: null Oct 04 16:45:31 amp volumio[638]: info: ------------------------------ 101ms Oct 04 16:45:31 amp volumio[638]: error: Upnp client error: Error: This socket has been ended by the other party Oct 04 16:45:32 amp volumio[638]: info: CoreCommandRouter::volumioGetState Oct 04 16:45:32 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:32 amp volumio[638]: info: Listing playlists Oct 04 16:45:32 amp volumio[638]: info: Listing playlists Oct 04 16:45:34 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand add "https://server-23.stream-server.nl:18118/stream" Oct 04 16:45:34 amp volumio[638]: error: updateQueue error: null Oct 04 16:45:34 amp volumio[638]: error: updateQueue error: null Oct 04 16:45:34 amp volumio[638]: info: Oct 04 16:45:34 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:45:34 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:45:34 amp volumio[638]: info: ------------------------------ 2261ms Oct 04 16:45:34 amp volumio[638]: info: ------------------------------ 2250ms Oct 04 16:45:34 amp volumio[638]: info: sendMpdCommand add "https://server-23.stream-server.nl:18118/stream" took 24 milliseconds Oct 04 16:45:34 amp volumio[638]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 04 16:45:34 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand play Oct 04 16:45:34 amp volumio[638]: info: Oct 04 16:45:34 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:45:34 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:45:34 amp volumio[638]: info: Oct 04 16:45:34 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:45:34 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:45:34 amp volumio[638]: info: ------------------------------ 79ms Oct 04 16:45:34 amp volumio[638]: info: sendMpdCommand play took 69 milliseconds Oct 04 16:45:34 amp volumio[638]: info: ------------------------------ 58ms Oct 04 16:45:34 amp volumio[638]: info: ------------------------------ 59ms Oct 04 16:45:36 amp volumio[638]: info: Oct 04 16:45:36 amp volumio[638]: ---------------------------- MPD announces state update: player Oct 04 16:45:36 amp volumio[638]: info: ControllerMpd::getState Oct 04 16:45:36 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand status Oct 04 16:45:36 amp volumio[638]: info: Oct 04 16:45:36 amp volumio[638]: ---------------------------- MPD announces state update: player Oct 04 16:45:36 amp volumio[638]: info: ControllerMpd::getState Oct 04 16:45:36 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand status Oct 04 16:45:36 amp volumio[638]: info: Oct 04 16:45:36 amp volumio[638]: ---------------------------- MPD announces state update: player Oct 04 16:45:36 amp volumio[638]: info: ControllerMpd::getState Oct 04 16:45:36 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand status Oct 04 16:45:36 amp volumio[638]: info: Oct 04 16:45:36 amp volumio[638]: ---------------------------- MPD announces state update: player Oct 04 16:45:36 amp volumio[638]: info: ControllerMpd::getState Oct 04 16:45:36 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand status Oct 04 16:45:36 amp volumio[638]: info: Oct 04 16:45:36 amp volumio[638]: ---------------------------- MPD announces state update: player Oct 04 16:45:36 amp volumio[638]: info: ControllerMpd::getState Oct 04 16:45:36 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand status Oct 04 16:45:36 amp volumio[638]: info: sendMpdCommand status took 140 milliseconds Oct 04 16:45:36 amp volumio[638]: info: sendMpdCommand status took 121 milliseconds Oct 04 16:45:36 amp volumio[638]: info: sendMpdCommand status took 104 milliseconds Oct 04 16:45:36 amp volumio[638]: verbose: ControllerMpd::parseState Oct 04 16:45:36 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 16:45:36 amp volumio[638]: verbose: ControllerMpd::parseState Oct 04 16:45:36 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 16:45:36 amp volumio[638]: verbose: ControllerMpd::parseState Oct 04 16:45:36 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 16:45:36 amp volumio[638]: info: sendMpdCommand status took 135 milliseconds Oct 04 16:45:36 amp volumio[638]: info: sendMpdCommand status took 114 milliseconds Oct 04 16:45:36 amp volumio[638]: info: sendMpdCommand playlistinfo took 49 milliseconds Oct 04 16:45:36 amp volumio[638]: verbose: ControllerMpd::parseState Oct 04 16:45:36 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 16:45:36 amp volumio[638]: verbose: ControllerMpd::parseState Oct 04 16:45:36 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 16:45:36 amp volumio[638]: verbose: ControllerMpd::parseTrackInfo Oct 04 16:45:36 amp volumio[638]: info: ControllerMpd::pushState Oct 04 16:45:36 amp volumio[638]: info: CoreCommandRouter::servicePushState Oct 04 16:45:36 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:36 amp volumio[638]: 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":null,"album":null,"uri":"https://server-23.stream-server.nl:18118/stream","trackType":""} Oct 04 16:45:36 amp volumio[638]: verbose: CURRENT POSITION 17 Oct 04 16:45:36 amp volumio[638]: info: CoreStateMachine::syncState stateService play Oct 04 16:45:36 amp volumio[638]: info: CoreStateMachine::syncState currentStatus stop Oct 04 16:45:36 amp volumio[638]: info: ------------------------------ 329ms Oct 04 16:45:36 amp volumio[638]: info: Oct 04 16:45:36 amp volumio[638]: ---------------------------- MPD announces state update: player Oct 04 16:45:36 amp volumio[638]: info: ControllerMpd::getState Oct 04 16:45:36 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand status Oct 04 16:45:36 amp volumio[638]: info: Oct 04 16:45:36 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:45:36 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:45:36 amp volumio[638]: info: Oct 04 16:45:36 amp volumio[638]: ---------------------------- MPD announces state update: player Oct 04 16:45:36 amp volumio[638]: info: sendMpdCommand playlistinfo took 175 milliseconds Oct 04 16:45:36 amp volumio[638]: info: sendMpdCommand playlistinfo took 171 milliseconds Oct 04 16:45:36 amp volumio[638]: info: sendMpdCommand playlistinfo took 132 milliseconds Oct 04 16:45:36 amp volumio[638]: info: sendMpdCommand playlistinfo took 130 milliseconds Oct 04 16:45:36 amp volumio[638]: info: ControllerMpd::getState Oct 04 16:45:36 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand status Oct 04 16:45:36 amp volumio[638]: verbose: ControllerMpd::parseTrackInfo Oct 04 16:45:36 amp volumio[638]: verbose: ControllerMpd::parseTrackInfo Oct 04 16:45:36 amp volumio[638]: verbose: ControllerMpd::parseTrackInfo Oct 04 16:45:36 amp volumio[638]: verbose: ControllerMpd::parseTrackInfo Oct 04 16:45:36 amp volumio[638]: info: ControllerMpd::pushState Oct 04 16:45:36 amp volumio[638]: info: CoreCommandRouter::servicePushState Oct 04 16:45:36 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:36 amp volumio[638]: 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":null,"album":null,"uri":"https://server-23.stream-server.nl:18118/stream","trackType":""} Oct 04 16:45:36 amp volumio[638]: verbose: CURRENT POSITION 17 Oct 04 16:45:36 amp volumio[638]: info: CoreStateMachine::syncState stateService play Oct 04 16:45:36 amp volumio[638]: info: CoreStateMachine::syncState currentStatus play Oct 04 16:45:36 amp volumio[638]: info: Received an update from plugin. extracting info from payload Oct 04 16:45:36 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:36 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:36 amp volumio[638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 04 16:45:36 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:36 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:36 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:36 amp volumio[638]: info: ControllerMpd::pushState Oct 04 16:45:36 amp volumio[638]: info: CoreCommandRouter::servicePushState Oct 04 16:45:36 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:36 amp volumio[638]: 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":null,"album":null,"uri":"https://server-23.stream-server.nl:18118/stream","trackType":""} Oct 04 16:45:36 amp volumio[638]: verbose: CURRENT POSITION 17 Oct 04 16:45:36 amp volumio[638]: info: CoreStateMachine::syncState stateService play Oct 04 16:45:36 amp volumio[638]: info: CoreStateMachine::syncState currentStatus play Oct 04 16:45:36 amp volumio[638]: info: Received an update from plugin. extracting info from payload Oct 04 16:45:36 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:36 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:36 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:36 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:36 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:36 amp volumio[638]: info: ControllerMpd::pushState Oct 04 16:45:36 amp volumio[638]: info: CoreCommandRouter::servicePushState Oct 04 16:45:36 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:36 amp volumio[638]: 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":null,"album":null,"uri":"https://server-23.stream-server.nl:18118/stream","trackType":""} Oct 04 16:45:36 amp volumio[638]: verbose: CURRENT POSITION 17 Oct 04 16:45:36 amp volumio[638]: info: CoreStateMachine::syncState stateService play Oct 04 16:45:36 amp volumio[638]: info: CoreStateMachine::syncState currentStatus play Oct 04 16:45:36 amp volumio[638]: info: Received an update from plugin. extracting info from payload Oct 04 16:45:36 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:36 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:36 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:36 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:36 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:36 amp volumio[638]: info: ControllerMpd::pushState Oct 04 16:45:36 amp volumio[638]: info: CoreCommandRouter::servicePushState Oct 04 16:45:37 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:37 amp volumio[638]: 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":null,"album":null,"uri":"https://server-23.stream-server.nl:18118/stream","trackType":""} Oct 04 16:45:37 amp volumio[638]: verbose: CURRENT POSITION 17 Oct 04 16:45:37 amp volumio[638]: info: CoreStateMachine::syncState stateService play Oct 04 16:45:37 amp volumio[638]: info: CoreStateMachine::syncState currentStatus play Oct 04 16:45:37 amp volumio[638]: info: Received an update from plugin. extracting info from payload Oct 04 16:45:37 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:37 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:37 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:37 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:37 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:37 amp volumio[638]: info: ------------------------------ 936ms Oct 04 16:45:37 amp volumio[638]: info: ------------------------------ 915ms Oct 04 16:45:37 amp volumio[638]: info: ------------------------------ 900ms Oct 04 16:45:37 amp volumio[638]: info: ------------------------------ 886ms Oct 04 16:45:37 amp volumio[638]: info: Oct 04 16:45:37 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:45:37 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:45:37 amp volumio[638]: info: Oct 04 16:45:37 amp volumio[638]: ---------------------------- MPD announces state update: player Oct 04 16:45:37 amp volumio[638]: info: ControllerMpd::getState Oct 04 16:45:37 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand status Oct 04 16:45:37 amp volumio[638]: info: Oct 04 16:45:37 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:45:37 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:45:37 amp volumio[638]: info: Oct 04 16:45:37 amp volumio[638]: ---------------------------- MPD announces state update: player Oct 04 16:45:37 amp volumio[638]: info: sendMpdCommand status took 704 milliseconds Oct 04 16:45:37 amp volumio[638]: info: ControllerMpd::getState Oct 04 16:45:37 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand status Oct 04 16:45:37 amp volumio[638]: info: ------------------------------ 705ms Oct 04 16:45:37 amp volumio[638]: info: sendMpdCommand status took 660 milliseconds Oct 04 16:45:37 amp volumio[638]: info: ------------------------------ 57ms Oct 04 16:45:37 amp volumio[638]: info: sendMpdCommand status took 43 milliseconds Oct 04 16:45:37 amp volumio[638]: verbose: ControllerMpd::parseState Oct 04 16:45:37 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 16:45:37 amp volumio[638]: verbose: ControllerMpd::parseState Oct 04 16:45:37 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 16:45:37 amp volumio[638]: verbose: ControllerMpd::parseState Oct 04 16:45:37 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 16:45:37 amp volumio[638]: info: Oct 04 16:45:37 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:45:37 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:45:37 amp volumio[638]: info: Oct 04 16:45:37 amp volumio[638]: ---------------------------- MPD announces state update: player Oct 04 16:45:37 amp volumio[638]: info: ControllerMpd::getState Oct 04 16:45:37 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand status Oct 04 16:45:37 amp volumio[638]: info: Oct 04 16:45:37 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:45:37 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:45:37 amp volumio[638]: info: Oct 04 16:45:37 amp volumio[638]: ---------------------------- MPD announces state update: player Oct 04 16:45:37 amp volumio[638]: info: ControllerMpd::getState Oct 04 16:45:37 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand status Oct 04 16:45:37 amp volumio[638]: info: ------------------------------ 155ms Oct 04 16:45:37 amp volumio[638]: info: sendMpdCommand status took 135 milliseconds Oct 04 16:45:37 amp volumio[638]: info: sendMpdCommand playlistinfo took 120 milliseconds Oct 04 16:45:37 amp volumio[638]: info: sendMpdCommand playlistinfo took 111 milliseconds Oct 04 16:45:37 amp volumio[638]: info: sendMpdCommand playlistinfo took 108 milliseconds Oct 04 16:45:37 amp volumio[638]: info: ------------------------------ 102ms Oct 04 16:45:37 amp volumio[638]: info: sendMpdCommand status took 88 milliseconds Oct 04 16:45:37 amp volumio[638]: info: ------------------------------ 84ms Oct 04 16:45:37 amp volumio[638]: info: sendMpdCommand status took 59 milliseconds Oct 04 16:45:37 amp volumio[638]: verbose: ControllerMpd::parseState Oct 04 16:45:37 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 16:45:37 amp volumio[638]: verbose: ControllerMpd::parseTrackInfo Oct 04 16:45:37 amp volumio[638]: verbose: ControllerMpd::parseTrackInfo Oct 04 16:45:37 amp volumio[638]: verbose: ControllerMpd::parseTrackInfo Oct 04 16:45:37 amp volumio[638]: verbose: ControllerMpd::parseState Oct 04 16:45:37 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 16:45:37 amp volumio[638]: verbose: ControllerMpd::parseState Oct 04 16:45:37 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 16:45:37 amp volumio[638]: info: ControllerMpd::pushState Oct 04 16:45:37 amp volumio[638]: info: CoreCommandRouter::servicePushState Oct 04 16:45:37 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:37 amp volumio[638]: verbose: STATE SERVICE {"status":"play","position":0,"seek":786,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Jon & Vangelis - I'll find my way home","artist":"Radio Goud van Oud","album":null,"uri":"https://server-23.stream-server.nl:18118/stream","trackType":""} Oct 04 16:45:37 amp volumio[638]: verbose: CURRENT POSITION 17 Oct 04 16:45:37 amp volumio[638]: info: CoreStateMachine::syncState stateService play Oct 04 16:45:37 amp volumio[638]: info: CoreStateMachine::syncState currentStatus play Oct 04 16:45:37 amp volumio[638]: info: Received an update from plugin. extracting info from payload Oct 04 16:45:37 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:37 amp volumio[638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 04 16:45:37 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:37 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:37 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:37 amp volumio[638]: info: ControllerMpd::pushState Oct 04 16:45:37 amp volumio[638]: info: CoreCommandRouter::servicePushState Oct 04 16:45:37 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:37 amp volumio[638]: 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":"192 Kbps","isStreaming":false,"title":"Jon & Vangelis - I'll find my way home","artist":"Radio Goud van Oud","album":null,"uri":"https://server-23.stream-server.nl:18118/stream","trackType":""} Oct 04 16:45:37 amp volumio[638]: verbose: CURRENT POSITION 17 Oct 04 16:45:37 amp volumio[638]: info: CoreStateMachine::syncState stateService play Oct 04 16:45:37 amp volumio[638]: info: CoreStateMachine::syncState currentStatus play Oct 04 16:45:37 amp volumio[638]: info: Received an update from plugin. extracting info from payload Oct 04 16:45:37 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:37 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:37 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:37 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:37 amp volumio[638]: info: ControllerMpd::pushState Oct 04 16:45:37 amp volumio[638]: info: CoreCommandRouter::servicePushState Oct 04 16:45:37 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:37 amp volumio[638]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1484,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Jon & Vangelis - I'll find my way home","artist":"Radio Goud van Oud","album":null,"uri":"https://server-23.stream-server.nl:18118/stream","trackType":""} Oct 04 16:45:37 amp volumio[638]: verbose: CURRENT POSITION 17 Oct 04 16:45:37 amp volumio[638]: info: CoreStateMachine::syncState stateService play Oct 04 16:45:37 amp volumio[638]: info: CoreStateMachine::syncState currentStatus play Oct 04 16:45:37 amp volumio[638]: info: Received an update from plugin. extracting info from payload Oct 04 16:45:37 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:37 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:37 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:37 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:37 amp volumio[638]: info: ------------------------------ 1361ms Oct 04 16:45:37 amp volumio[638]: info: ------------------------------ 1341ms Oct 04 16:45:37 amp volumio[638]: info: ------------------------------ 706ms Oct 04 16:45:38 amp volumio[638]: info: sendMpdCommand playlistinfo took 678 milliseconds Oct 04 16:45:38 amp volumio[638]: info: sendMpdCommand playlistinfo took 667 milliseconds Oct 04 16:45:38 amp volumio[638]: info: sendMpdCommand playlistinfo took 680 milliseconds Oct 04 16:45:38 amp volumio[638]: verbose: ControllerMpd::parseTrackInfo Oct 04 16:45:38 amp volumio[638]: verbose: ControllerMpd::parseTrackInfo Oct 04 16:45:38 amp volumio[638]: verbose: ControllerMpd::parseTrackInfo Oct 04 16:45:38 amp volumio[638]: info: ControllerMpd::pushState Oct 04 16:45:38 amp volumio[638]: info: CoreCommandRouter::servicePushState Oct 04 16:45:38 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:38 amp volumio[638]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1586,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Jon & Vangelis - I'll find my way home","artist":"Radio Goud van Oud","album":null,"uri":"https://server-23.stream-server.nl:18118/stream","trackType":""} Oct 04 16:45:38 amp volumio[638]: verbose: CURRENT POSITION 17 Oct 04 16:45:38 amp volumio[638]: info: CoreStateMachine::syncState stateService play Oct 04 16:45:38 amp volumio[638]: info: CoreStateMachine::syncState currentStatus play Oct 04 16:45:38 amp volumio[638]: info: Received an update from plugin. extracting info from payload Oct 04 16:45:38 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:38 amp volumio[638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 04 16:45:38 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:38 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:38 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:38 amp volumio[638]: info: ControllerMpd::pushState Oct 04 16:45:38 amp volumio[638]: info: CoreCommandRouter::servicePushState Oct 04 16:45:38 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:38 amp volumio[638]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1586,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Jon & Vangelis - I'll find my way home","artist":"Radio Goud van Oud","album":null,"uri":"https://server-23.stream-server.nl:18118/stream","trackType":""} Oct 04 16:45:38 amp volumio[638]: verbose: CURRENT POSITION 17 Oct 04 16:45:38 amp volumio[638]: info: CoreStateMachine::syncState stateService play Oct 04 16:45:38 amp volumio[638]: info: CoreStateMachine::syncState currentStatus play Oct 04 16:45:38 amp volumio[638]: info: Received an update from plugin. extracting info from payload Oct 04 16:45:38 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:38 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:38 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:38 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:38 amp volumio[638]: info: ControllerMpd::pushState Oct 04 16:45:38 amp volumio[638]: info: CoreCommandRouter::servicePushState Oct 04 16:45:38 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:38 amp volumio[638]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1678,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Jon & Vangelis - I'll find my way home","artist":"Radio Goud van Oud","album":null,"uri":"https://server-23.stream-server.nl:18118/stream","trackType":""} Oct 04 16:45:38 amp volumio[638]: verbose: CURRENT POSITION 17 Oct 04 16:45:38 amp volumio[638]: info: CoreStateMachine::syncState stateService play Oct 04 16:45:38 amp volumio[638]: info: CoreStateMachine::syncState currentStatus play Oct 04 16:45:38 amp volumio[638]: info: Received an update from plugin. extracting info from payload Oct 04 16:45:38 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:38 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:38 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:38 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:38 amp volumio[638]: info: ------------------------------ 1224ms Oct 04 16:45:38 amp volumio[638]: info: ------------------------------ 1158ms Oct 04 16:45:38 amp volumio[638]: info: ------------------------------ 1142ms Oct 04 16:45:38 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:45:38 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:45:39 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:45:39 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:45:39 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:45:39 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:45:39 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:45:40 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:45:40 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:45:40 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:45:40 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:45:40 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:45:40 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:45:40 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:45:40 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:45:41 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:45:41 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:45:41 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:45:41 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:45:41 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:45:42 amp volumio[638]: info: CoreCommandRouter::volumioGetState Oct 04 16:45:52 amp volumio[638]: info: CoreCommandRouter::volumioGetState Oct 04 16:45:52 amp volumio[638]: info: Listing playlists Oct 04 16:45:52 amp volumio[638]: info: Listing playlists Oct 04 16:45:54 amp volumio[638]: info: CoreCommandRouter::volumioStop Oct 04 16:45:54 amp volumio[638]: info: CoreStateMachine::stop Oct 04 16:45:54 amp volumio[638]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 04 16:45:54 amp volumio[638]: info: CoreStateMachine::stPlaybackTimer Oct 04 16:45:54 amp volumio[638]: info: CoreStateMachine::updateTrackBlock Oct 04 16:45:54 amp volumio[638]: info: CorePlayQueue::getTrackBlock Oct 04 16:45:54 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:54 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:54 amp volumio[638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 04 16:45:54 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:54 amp volumio[638]: info: CoreStateMachine::serviceStop Oct 04 16:45:54 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:54 amp volumio[638]: info: CoreCommandRouter::serviceStop Oct 04 16:45:54 amp volumio[638]: info: [1728053154758] ControllerWebradio::stop Oct 04 16:45:54 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand stop Oct 04 16:45:54 amp volumio[638]: info: Oct 04 16:45:54 amp volumio[638]: ---------------------------- MPD announces state update: player Oct 04 16:45:54 amp volumio[638]: info: sendMpdCommand stop took 180 milliseconds Oct 04 16:45:54 amp volumio[638]: info: ControllerMpd::getState Oct 04 16:45:54 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand status Oct 04 16:45:54 amp volumio[638]: info: Oct 04 16:45:54 amp volumio[638]: ---------------------------- MPD announces state update: player Oct 04 16:45:54 amp volumio[638]: info: ControllerMpd::getState Oct 04 16:45:54 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand status Oct 04 16:45:54 amp volumio[638]: info: Oct 04 16:45:54 amp volumio[638]: ---------------------------- MPD announces state update: player Oct 04 16:45:54 amp volumio[638]: info: ControllerMpd::getState Oct 04 16:45:54 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand status Oct 04 16:45:55 amp volumio[638]: info: sendMpdCommand status took 81 milliseconds Oct 04 16:45:55 amp volumio[638]: info: sendMpdCommand status took 74 milliseconds Oct 04 16:45:55 amp volumio[638]: info: sendMpdCommand status took 52 milliseconds Oct 04 16:45:55 amp volumio[638]: verbose: ControllerMpd::parseState Oct 04 16:45:55 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 16:45:55 amp volumio[638]: verbose: ControllerMpd::parseState Oct 04 16:45:55 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 16:45:55 amp volumio[638]: verbose: ControllerMpd::parseState Oct 04 16:45:55 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 16:45:55 amp volumio[638]: info: sendMpdCommand playlistinfo took 54 milliseconds Oct 04 16:45:55 amp volumio[638]: info: sendMpdCommand playlistinfo took 40 milliseconds Oct 04 16:45:55 amp volumio[638]: info: sendMpdCommand playlistinfo took 38 milliseconds Oct 04 16:45:55 amp volumio[638]: verbose: ControllerMpd::parseTrackInfo Oct 04 16:45:55 amp volumio[638]: verbose: ControllerMpd::parseTrackInfo Oct 04 16:45:55 amp volumio[638]: verbose: ControllerMpd::parseTrackInfo Oct 04 16:45:55 amp volumio[638]: info: ControllerMpd::pushState Oct 04 16:45:55 amp volumio[638]: info: CoreCommandRouter::servicePushState Oct 04 16:45:55 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:55 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:55 amp volumio[638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 04 16:45:55 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:55 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:55 amp volumio[638]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 04 16:45:55 amp volumio[638]: info: ControllerMpd::pushState Oct 04 16:45:55 amp volumio[638]: info: CoreCommandRouter::servicePushState Oct 04 16:45:55 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:55 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:55 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:55 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:55 amp volumio[638]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 04 16:45:55 amp volumio[638]: info: ControllerMpd::pushState Oct 04 16:45:55 amp volumio[638]: info: CoreCommandRouter::servicePushState Oct 04 16:45:55 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:45:55 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:55 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:45:55 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:45:55 amp volumio[638]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 04 16:45:55 amp volumio[638]: info: ------------------------------ 441ms Oct 04 16:45:55 amp volumio[638]: info: ------------------------------ 410ms Oct 04 16:45:55 amp volumio[638]: info: ------------------------------ 388ms Oct 04 16:45:55 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:45:55 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:45:56 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:45:56 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:46:02 amp volumio[638]: info: CoreCommandRouter::volumioGetState Oct 04 16:46:02 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:46:06 amp volumio[638]: info: Clearing queue after UPNP request Oct 04 16:46:06 amp volumio[638]: info: CoreStateMachine::ClearQueue Oct 04 16:46:07 amp volumio[638]: info: CoreStateMachine::stop Oct 04 16:46:07 amp volumio[638]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 04 16:46:07 amp volumio[638]: info: CorePlayQueue::clearPlayQueue Oct 04 16:46:07 amp volumio[638]: info: CorePlayQueue::saveQueue Oct 04 16:46:07 amp volumio[638]: info: CoreCommandRouter::volumioPushState Oct 04 16:46:07 amp volumio[638]: info: CoreCommandRouter::volumioPushQueue Oct 04 16:46:07 amp volumio[638]: info: Oct 04 16:46:07 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:07 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:07 amp volumio[638]: info: Oct 04 16:46:07 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:07 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:07 amp volumio[638]: info: Oct 04 16:46:07 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:07 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:07 amp volumio[638]: error: updateQueue error: null Oct 04 16:46:07 amp volumio[638]: error: updateQueue error: null Oct 04 16:46:07 amp volumio[638]: error: updateQueue error: null Oct 04 16:46:07 amp volumio[638]: info: ------------------------------ 151ms Oct 04 16:46:07 amp volumio[638]: info: ------------------------------ 124ms Oct 04 16:46:07 amp volumio[638]: info: ------------------------------ 108ms Oct 04 16:46:08 amp volumio[638]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 04 16:46:08 amp volumio[638]: info: Starting UPNP Playback Oct 04 16:46:08 amp volumio[638]: info: Preparing playback through UPNP Oct 04 16:46:08 amp volumio[638]: info: CoreCommandRouter::volumioGetState Oct 04 16:46:08 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:46:08 amp volumio[638]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 04 16:46:09 amp volumio[638]: info: Oct 04 16:46:09 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:09 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:09 amp volumio[638]: info: Oct 04 16:46:09 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:09 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:09 amp volumio[638]: info: Oct 04 16:46:09 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:09 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:09 amp volumio[638]: info: ------------------------------ 42ms Oct 04 16:46:09 amp volumio[638]: info: ------------------------------ 28ms Oct 04 16:46:09 amp volumio[638]: info: ------------------------------ 20ms Oct 04 16:46:09 amp volumio[638]: info: Oct 04 16:46:09 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:09 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:09 amp volumio[638]: info: Oct 04 16:46:09 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:09 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:09 amp volumio[638]: info: Oct 04 16:46:09 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:09 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:09 amp volumio[638]: info: ------------------------------ 37ms Oct 04 16:46:09 amp volumio[638]: info: ------------------------------ 28ms Oct 04 16:46:09 amp volumio[638]: info: ------------------------------ 19ms Oct 04 16:46:09 amp volumio[638]: info: Oct 04 16:46:09 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:09 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:09 amp volumio[638]: info: Oct 04 16:46:09 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:09 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:09 amp volumio[638]: info: Oct 04 16:46:09 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:09 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:09 amp volumio[638]: info: ------------------------------ 38ms Oct 04 16:46:09 amp volumio[638]: info: ------------------------------ 27ms Oct 04 16:46:09 amp volumio[638]: info: ------------------------------ 19ms Oct 04 16:46:09 amp volumio[638]: info: Oct 04 16:46:09 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:09 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:09 amp volumio[638]: info: Oct 04 16:46:09 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:09 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:09 amp volumio[638]: info: Oct 04 16:46:09 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:09 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:09 amp volumio[638]: info: ------------------------------ 44ms Oct 04 16:46:09 amp volumio[638]: info: ------------------------------ 27ms Oct 04 16:46:09 amp volumio[638]: info: ------------------------------ 20ms Oct 04 16:46:09 amp volumio[638]: info: Oct 04 16:46:09 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:09 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:09 amp volumio[638]: info: Oct 04 16:46:09 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:09 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:09 amp volumio[638]: info: Oct 04 16:46:09 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:09 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:09 amp volumio[638]: info: ------------------------------ 41ms Oct 04 16:46:09 amp volumio[638]: info: ------------------------------ 31ms Oct 04 16:46:09 amp volumio[638]: info: ------------------------------ 21ms Oct 04 16:46:09 amp volumio[638]: info: Oct 04 16:46:09 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:09 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:09 amp volumio[638]: info: Oct 04 16:46:09 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:09 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:09 amp volumio[638]: info: Oct 04 16:46:09 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:09 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:09 amp volumio[638]: info: ------------------------------ 37ms Oct 04 16:46:09 amp volumio[638]: info: ------------------------------ 28ms Oct 04 16:46:09 amp volumio[638]: info: ------------------------------ 19ms Oct 04 16:46:09 amp volumio[638]: info: Oct 04 16:46:09 amp volumio[638]: ---------------------------- MPD announces state update: player Oct 04 16:46:09 amp volumio[638]: info: ControllerMpd::getState Oct 04 16:46:09 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand status Oct 04 16:46:09 amp volumio[638]: info: Oct 04 16:46:09 amp volumio[638]: ---------------------------- MPD announces state update: player Oct 04 16:46:09 amp volumio[638]: info: ControllerMpd::getState Oct 04 16:46:09 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand status Oct 04 16:46:09 amp volumio[638]: info: Oct 04 16:46:09 amp volumio[638]: ---------------------------- MPD announces state update: player Oct 04 16:46:09 amp volumio[638]: info: ControllerMpd::getState Oct 04 16:46:09 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand status Oct 04 16:46:09 amp volumio[638]: info: Oct 04 16:46:09 amp volumio[638]: ---------------------------- MPD announces state update: player Oct 04 16:46:09 amp volumio[638]: info: ControllerMpd::getState Oct 04 16:46:09 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand status Oct 04 16:46:10 amp volumio[638]: info: sendMpdCommand status took 103 milliseconds Oct 04 16:46:10 amp volumio[638]: info: sendMpdCommand status took 92 milliseconds Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::parseState Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::parseState Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 16:46:10 amp volumio[638]: info: Oct 04 16:46:10 amp volumio[638]: ---------------------------- MPD announces state update: player Oct 04 16:46:10 amp volumio[638]: info: ControllerMpd::getState Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand status Oct 04 16:46:10 amp volumio[638]: info: Oct 04 16:46:10 amp volumio[638]: ---------------------------- MPD announces state update: player Oct 04 16:46:10 amp volumio[638]: info: ControllerMpd::getState Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand status Oct 04 16:46:10 amp volumio[638]: info: Oct 04 16:46:10 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:10 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:10 amp volumio[638]: info: Oct 04 16:46:10 amp volumio[638]: ---------------------------- MPD announces state update: player Oct 04 16:46:10 amp volumio[638]: info: sendMpdCommand status took 187 milliseconds Oct 04 16:46:10 amp volumio[638]: info: ControllerMpd::getState Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand status Oct 04 16:46:10 amp volumio[638]: info: sendMpdCommand status took 159 milliseconds Oct 04 16:46:10 amp volumio[638]: info: sendMpdCommand playlistinfo took 130 milliseconds Oct 04 16:46:10 amp volumio[638]: info: sendMpdCommand playlistinfo took 120 milliseconds Oct 04 16:46:10 amp volumio[638]: info: sendMpdCommand status took 102 milliseconds Oct 04 16:46:10 amp volumio[638]: info: sendMpdCommand status took 83 milliseconds Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::parseState Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::parseState Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::parseTrackInfo Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::parseTrackInfo Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::parseState Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::parseState Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 16:46:10 amp volumio[638]: info: ControllerMpd::pushState Oct 04 16:46:10 amp volumio[638]: info: CoreCommandRouter::servicePushState Oct 04 16:46:10 amp volumio[638]: verbose: In UPNP mode Oct 04 16:46:10 amp volumio[638]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bb699428c98340c8a01ad4d5a8f83036.flac?ts=1728053166","artist":"Sky Radio","album":null,"uri":"http://192.168.2.141:8097/single/uuid:b19cfe56-b78e-81fc-d782-b827eba49de1/bb699428c98340c8a01ad4d5a8f83036.flac?ts=1728053166","trackType":""} Oct 04 16:46:10 amp volumio[638]: verbose: CURRENT POSITION 17 Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::syncState stateService play Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::syncState currentStatus stop Oct 04 16:46:10 amp volumio[638]: info: ControllerMpd::pushState Oct 04 16:46:10 amp volumio[638]: info: CoreCommandRouter::servicePushState Oct 04 16:46:10 amp volumio[638]: verbose: In UPNP mode Oct 04 16:46:10 amp volumio[638]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bb699428c98340c8a01ad4d5a8f83036.flac?ts=1728053166","artist":"Sky Radio","album":null,"uri":"http://192.168.2.141:8097/single/uuid:b19cfe56-b78e-81fc-d782-b827eba49de1/bb699428c98340c8a01ad4d5a8f83036.flac?ts=1728053166","trackType":""} Oct 04 16:46:10 amp volumio[638]: verbose: CURRENT POSITION 17 Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::syncState stateService play Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::syncState currentStatus play Oct 04 16:46:10 amp volumio[638]: info: Received an update from plugin. extracting info from payload Oct 04 16:46:10 amp volumio[638]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:46:10 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:46:10 amp volumio[638]: info: ------------------------------ 460ms Oct 04 16:46:10 amp volumio[638]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 04 16:46:10 amp volumio[638]: info: ------------------------------ 442ms Oct 04 16:46:10 amp volumio[638]: info: Oct 04 16:46:10 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:10 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:10 amp volumio[638]: info: Oct 04 16:46:10 amp volumio[638]: ---------------------------- MPD announces state update: player Oct 04 16:46:10 amp volumio[638]: info: ControllerMpd::getState Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand status Oct 04 16:46:10 amp volumio[638]: info: Oct 04 16:46:10 amp volumio[638]: ---------------------------- MPD announces system playlist update Oct 04 16:46:10 amp volumio[638]: info: Ignoring MPD Status Update Oct 04 16:46:10 amp volumio[638]: info: Oct 04 16:46:10 amp volumio[638]: ---------------------------- MPD announces state update: player Oct 04 16:46:10 amp volumio[638]: info: ControllerMpd::getState Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand status Oct 04 16:46:10 amp volumio[638]: info: ------------------------------ 310ms Oct 04 16:46:10 amp volumio[638]: info: sendMpdCommand status took 276 milliseconds Oct 04 16:46:10 amp volumio[638]: info: sendMpdCommand playlistinfo took 234 milliseconds Oct 04 16:46:10 amp volumio[638]: info: sendMpdCommand playlistinfo took 223 milliseconds Oct 04 16:46:10 amp volumio[638]: info: sendMpdCommand playlistinfo took 195 milliseconds Oct 04 16:46:10 amp volumio[638]: info: sendMpdCommand playlistinfo took 187 milliseconds Oct 04 16:46:10 amp volumio[638]: info: ------------------------------ 101ms Oct 04 16:46:10 amp volumio[638]: info: sendMpdCommand status took 88 milliseconds Oct 04 16:46:10 amp volumio[638]: info: ------------------------------ 82ms Oct 04 16:46:10 amp volumio[638]: info: sendMpdCommand status took 69 milliseconds Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::parseState Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::parseTrackInfo Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::parseTrackInfo Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::parseTrackInfo Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::parseTrackInfo Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::parseState Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::parseState Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 16:46:10 amp volumio[638]: info: ControllerMpd::pushState Oct 04 16:46:10 amp volumio[638]: info: CoreCommandRouter::servicePushState Oct 04 16:46:10 amp volumio[638]: verbose: In UPNP mode Oct 04 16:46:10 amp volumio[638]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bb699428c98340c8a01ad4d5a8f83036.flac?ts=1728053166","artist":"Sky Radio","album":null,"uri":"http://192.168.2.141:8097/single/uuid:b19cfe56-b78e-81fc-d782-b827eba49de1/bb699428c98340c8a01ad4d5a8f83036.flac?ts=1728053166","trackType":""} Oct 04 16:46:10 amp volumio[638]: verbose: CURRENT POSITION 17 Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::syncState stateService play Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::syncState currentStatus play Oct 04 16:46:10 amp volumio[638]: info: Received an update from plugin. extracting info from payload Oct 04 16:46:10 amp volumio[638]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:46:10 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:46:10 amp volumio[638]: info: ControllerMpd::pushState Oct 04 16:46:10 amp volumio[638]: info: CoreCommandRouter::servicePushState Oct 04 16:46:10 amp volumio[638]: verbose: In UPNP mode Oct 04 16:46:10 amp volumio[638]: verbose: STATE SERVICE {"status":"play","position":0,"seek":503,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"145 Kbps","isStreaming":false,"title":"bb699428c98340c8a01ad4d5a8f83036.flac?ts=1728053166","artist":"Sky Radio","album":null,"uri":"http://192.168.2.141:8097/single/uuid:b19cfe56-b78e-81fc-d782-b827eba49de1/bb699428c98340c8a01ad4d5a8f83036.flac?ts=1728053166","trackType":""} Oct 04 16:46:10 amp volumio[638]: verbose: CURRENT POSITION 17 Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::syncState stateService play Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::syncState currentStatus play Oct 04 16:46:10 amp volumio[638]: info: Received an update from plugin. extracting info from payload Oct 04 16:46:10 amp volumio[638]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:46:10 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:46:10 amp volumio[638]: info: ControllerMpd::pushState Oct 04 16:46:10 amp volumio[638]: info: CoreCommandRouter::servicePushState Oct 04 16:46:10 amp volumio[638]: verbose: In UPNP mode Oct 04 16:46:10 amp volumio[638]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"145 Kbps","isStreaming":false,"title":"bb699428c98340c8a01ad4d5a8f83036.flac?ts=1728053166","artist":"Sky Radio","album":null,"uri":"http://192.168.2.141:8097/single/uuid:b19cfe56-b78e-81fc-d782-b827eba49de1/bb699428c98340c8a01ad4d5a8f83036.flac?ts=1728053166","trackType":""} Oct 04 16:46:10 amp volumio[638]: verbose: CURRENT POSITION 17 Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::syncState stateService play Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::syncState currentStatus play Oct 04 16:46:10 amp volumio[638]: info: Received an update from plugin. extracting info from payload Oct 04 16:46:10 amp volumio[638]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:46:10 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:46:10 amp volumio[638]: info: ControllerMpd::pushState Oct 04 16:46:10 amp volumio[638]: info: CoreCommandRouter::servicePushState Oct 04 16:46:10 amp volumio[638]: verbose: In UPNP mode Oct 04 16:46:10 amp volumio[638]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"144 Kbps","isStreaming":false,"title":"bb699428c98340c8a01ad4d5a8f83036.flac?ts=1728053166","artist":"Sky Radio","album":null,"uri":"http://192.168.2.141:8097/single/uuid:b19cfe56-b78e-81fc-d782-b827eba49de1/bb699428c98340c8a01ad4d5a8f83036.flac?ts=1728053166","trackType":""} Oct 04 16:46:10 amp volumio[638]: verbose: CURRENT POSITION 17 Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::syncState stateService play Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::syncState currentStatus play Oct 04 16:46:10 amp volumio[638]: info: Received an update from plugin. extracting info from payload Oct 04 16:46:10 amp volumio[638]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:46:10 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:46:10 amp volumio[638]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 04 16:46:10 amp volumio[638]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 04 16:46:10 amp volumio[638]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 04 16:46:10 amp volumio[638]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 04 16:46:10 amp volumio[638]: info: ------------------------------ 824ms Oct 04 16:46:10 amp volumio[638]: info: ------------------------------ 790ms Oct 04 16:46:10 amp volumio[638]: info: ------------------------------ 709ms Oct 04 16:46:10 amp volumio[638]: info: ------------------------------ 686ms Oct 04 16:46:10 amp volumio[638]: info: sendMpdCommand playlistinfo took 318 milliseconds Oct 04 16:46:10 amp volumio[638]: info: sendMpdCommand playlistinfo took 308 milliseconds Oct 04 16:46:10 amp volumio[638]: info: sendMpdCommand playlistinfo took 307 milliseconds Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::parseTrackInfo Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::parseTrackInfo Oct 04 16:46:10 amp volumio[638]: verbose: ControllerMpd::parseTrackInfo Oct 04 16:46:10 amp volumio[638]: info: ControllerMpd::pushState Oct 04 16:46:10 amp volumio[638]: info: CoreCommandRouter::servicePushState Oct 04 16:46:10 amp volumio[638]: verbose: In UPNP mode Oct 04 16:46:10 amp volumio[638]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"146 Kbps","isStreaming":false,"title":"bb699428c98340c8a01ad4d5a8f83036.flac?ts=1728053166","artist":"Sky Radio","album":null,"uri":"http://192.168.2.141:8097/single/uuid:b19cfe56-b78e-81fc-d782-b827eba49de1/bb699428c98340c8a01ad4d5a8f83036.flac?ts=1728053166","trackType":""} Oct 04 16:46:10 amp volumio[638]: verbose: CURRENT POSITION 17 Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::syncState stateService play Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::syncState currentStatus play Oct 04 16:46:10 amp volumio[638]: info: Received an update from plugin. extracting info from payload Oct 04 16:46:10 amp volumio[638]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:46:10 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:46:10 amp volumio[638]: info: ControllerMpd::pushState Oct 04 16:46:10 amp volumio[638]: info: CoreCommandRouter::servicePushState Oct 04 16:46:10 amp volumio[638]: verbose: In UPNP mode Oct 04 16:46:10 amp volumio[638]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1166,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"147 Kbps","isStreaming":false,"title":"bb699428c98340c8a01ad4d5a8f83036.flac?ts=1728053166","artist":"Sky Radio","album":null,"uri":"http://192.168.2.141:8097/single/uuid:b19cfe56-b78e-81fc-d782-b827eba49de1/bb699428c98340c8a01ad4d5a8f83036.flac?ts=1728053166","trackType":""} Oct 04 16:46:10 amp volumio[638]: verbose: CURRENT POSITION 17 Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::syncState stateService play Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::syncState currentStatus play Oct 04 16:46:10 amp volumio[638]: info: Received an update from plugin. extracting info from payload Oct 04 16:46:10 amp volumio[638]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:46:10 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:46:10 amp volumio[638]: info: ControllerMpd::pushState Oct 04 16:46:10 amp volumio[638]: info: CoreCommandRouter::servicePushState Oct 04 16:46:10 amp volumio[638]: verbose: In UPNP mode Oct 04 16:46:10 amp volumio[638]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1166,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"147 Kbps","isStreaming":false,"title":"bb699428c98340c8a01ad4d5a8f83036.flac?ts=1728053166","artist":"Sky Radio","album":null,"uri":"http://192.168.2.141:8097/single/uuid:b19cfe56-b78e-81fc-d782-b827eba49de1/bb699428c98340c8a01ad4d5a8f83036.flac?ts=1728053166","trackType":""} Oct 04 16:46:10 amp volumio[638]: verbose: CURRENT POSITION 17 Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::syncState stateService play Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::syncState currentStatus play Oct 04 16:46:10 amp volumio[638]: info: Received an update from plugin. extracting info from payload Oct 04 16:46:10 amp volumio[638]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 04 16:46:10 amp volumio[638]: info: CoreStateMachine::pushState Oct 04 16:46:10 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:46:10 amp volumio[638]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 04 16:46:10 amp volumio[638]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 04 16:46:10 amp volumio[638]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 04 16:46:10 amp volumio[638]: info: ------------------------------ 800ms Oct 04 16:46:10 amp volumio[638]: info: ------------------------------ 579ms Oct 04 16:46:10 amp volumio[638]: info: ------------------------------ 556ms Oct 04 16:46:12 amp volumio[638]: info: CoreCommandRouter::volumioGetState Oct 04 16:46:12 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:46:12 amp volumio[638]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Oct 04 16:46:12 amp volumio[638]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Oct 04 16:46:12 amp volumio[638]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Oct 04 16:46:12 amp volumio[638]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Oct 04 16:46:12 amp volumio[638]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Oct 04 16:46:12 amp volumio[638]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Oct 04 16:46:12 amp volumio[638]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Oct 04 16:46:12 amp volumio[638]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Oct 04 16:46:12 amp volumio[638]: at /volumio/node_modules/express/lib/router/index.js:281:22 Oct 04 16:46:12 amp volumio[638]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Oct 04 16:46:12 amp volumio[638]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Oct 04 16:46:12 amp volumio[638]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Oct 04 16:46:12 amp volumio[638]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Oct 04 16:46:12 amp volumio[638]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Oct 04 16:46:12 amp volumio[638]: at /volumio/node_modules/express/lib/router/index.js:284:7 Oct 04 16:46:12 amp volumio[638]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Oct 04 16:46:12 amp volumio[638]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Oct 04 16:46:12 amp volumio[638]: info: Listing playlists Oct 04 16:46:12 amp volumio[638]: info: Listing playlists Oct 04 16:46:22 amp volumio[638]: info: CoreCommandRouter::volumioGetState Oct 04 16:46:22 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:46:22 amp volumio[638]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Oct 04 16:46:22 amp volumio[638]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Oct 04 16:46:22 amp volumio[638]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Oct 04 16:46:22 amp volumio[638]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Oct 04 16:46:22 amp volumio[638]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Oct 04 16:46:22 amp volumio[638]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Oct 04 16:46:22 amp volumio[638]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Oct 04 16:46:22 amp volumio[638]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Oct 04 16:46:22 amp volumio[638]: at /volumio/node_modules/express/lib/router/index.js:281:22 Oct 04 16:46:22 amp volumio[638]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Oct 04 16:46:22 amp volumio[638]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Oct 04 16:46:22 amp volumio[638]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Oct 04 16:46:22 amp volumio[638]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Oct 04 16:46:22 amp volumio[638]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Oct 04 16:46:22 amp volumio[638]: at /volumio/node_modules/express/lib/router/index.js:284:7 Oct 04 16:46:22 amp volumio[638]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Oct 04 16:46:22 amp volumio[638]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Oct 04 16:46:26 amp go-librespot[2170]: time="2024-10-04T16:46:26+02:00" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection timed out" Oct 04 16:46:26 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 04 16:46:26 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 04 16:46:26 amp volumio[638]: (node:638) UnhandledPromiseRejectionWarning: Error: socket hang up Oct 04 16:46:26 amp volumio[638]: at connResetException (internal/errors.js:607:14) Oct 04 16:46:26 amp volumio[638]: at Socket.socketOnEnd (_http_client.js:493:23) Oct 04 16:46:26 amp volumio[638]: at Socket.emit (events.js:327:22) Oct 04 16:46:26 amp volumio[638]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 04 16:46:26 amp volumio[638]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 04 16:46:26 amp volumio[638]: (node:638) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 8) Oct 04 16:46:26 amp volumio[638]: info: Connection to go-librespot Websocket closed Oct 04 16:46:29 amp volumio[638]: info: Initializing connection to go-librespot Websocket Oct 04 16:46:29 amp volumio[638]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 04 16:46:29 amp systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 04 16:46:29 amp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Oct 04 16:46:29 amp systemd[1]: Stopped go-librespot Daemon. Oct 04 16:46:29 amp systemd[1]: Started go-librespot Daemon. Oct 04 16:46:29 amp go-librespot[2361]: Librespot-go daemon starting... Oct 04 16:46:29 amp go-librespot[2361]: time="2024-10-04T16:46:29+02:00" level=info msg="generated new device id: a764542810b3d1926f8c4223b23b44bfa85a6f85" Oct 04 16:46:29 amp go-librespot[2361]: time="2024-10-04T16:46:29+02:00" level=debug msg="stored credentials not found" Oct 04 16:46:31 amp go-librespot[2361]: time="2024-10-04T16:46:31+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 04 16:46:31 amp go-librespot[2361]: time="2024-10-04T16:46:31+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 04 16:46:31 amp go-librespot[2361]: time="2024-10-04T16:46:31+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 04 16:46:31 amp go-librespot[2361]: time="2024-10-04T16:46:31+02:00" level=debug msg="zeroconf server listening on port 33183" Oct 04 16:46:31 amp go-librespot[2361]: time="2024-10-04T16:46:31+02:00" level=debug msg="obtained new client token: AABd6bSwLBQKNz+UA9iymeY0RiIyHd8NLRDgEIo/x0C3iXlYML6fcwYDsa871CtuvgNf+9vIDY7slpI8MWcKnnPNT4ovmXoAiVl9cUVanQ+W/mRNqpWS/41L4Nh1YW4Fz8ls6mIbZ3GifYbmRZO2vv9dSO1dbjPfDthbI8KLnXokjevJ4I6ZxBuFUlvibRC9KKkfH3FVanhf5Bp1PwmEHjh+JReBG79F8bK8KEn8F8GdL1ZwZT8z4QUP" Oct 04 16:46:32 amp volumio[638]: info: Initializing connection to go-librespot Websocket Oct 04 16:46:32 amp go-librespot[2361]: time="2024-10-04T16:46:32+02:00" level=debug msg="new websocket client" Oct 04 16:46:32 amp volumio[638]: info: Connection to go-librespot Websocket established Oct 04 16:46:32 amp volumio[638]: info: CoreCommandRouter::volumioGetState Oct 04 16:46:32 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:46:32 amp volumio[638]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Oct 04 16:46:32 amp volumio[638]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Oct 04 16:46:32 amp volumio[638]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Oct 04 16:46:32 amp volumio[638]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Oct 04 16:46:32 amp volumio[638]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Oct 04 16:46:32 amp volumio[638]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Oct 04 16:46:32 amp volumio[638]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Oct 04 16:46:32 amp volumio[638]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Oct 04 16:46:32 amp volumio[638]: at /volumio/node_modules/express/lib/router/index.js:281:22 Oct 04 16:46:32 amp volumio[638]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Oct 04 16:46:32 amp volumio[638]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Oct 04 16:46:32 amp volumio[638]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Oct 04 16:46:32 amp volumio[638]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Oct 04 16:46:32 amp volumio[638]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Oct 04 16:46:32 amp volumio[638]: at /volumio/node_modules/express/lib/router/index.js:284:7 Oct 04 16:46:32 amp volumio[638]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Oct 04 16:46:32 amp volumio[638]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Oct 04 16:46:32 amp volumio[638]: info: Listing playlists Oct 04 16:46:32 amp volumio[638]: info: Listing playlists Oct 04 16:46:35 amp volumio[638]: info: Getting Spotify volume Oct 04 16:46:35 amp volumio[638]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 Oct 04 16:46:35 amp volumio[638]: info: CoreCommandRouter::volumioGetState Oct 04 16:46:35 amp volumio[638]: info: CorePlayQueue::getTrack 17 Oct 04 16:46:35 amp volumio[638]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 04 16:46:35 amp volumio[638]: TypeError: Cannot read property 'name' of undefined Oct 04 16:46:35 amp volumio[638]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Oct 04 16:46:35 amp volumio[638]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Oct 04 16:46:35 amp volumio[638]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:53:38) Oct 04 16:46:35 amp volumio[638]: at Socket.emit (events.js:315:20) Oct 04 16:46:35 amp volumio[638]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 04 16:46:35 amp volumio[638]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 04 16:46:35 amp volumio[638]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 04 16:46:40 amp sudo[2386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-04 16:45 Oct 04 16:46:40 amp sudo[2386]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"