-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Mon 2025-06-23 18:45:32 BST. -- Jun 23 18:44:28 volumio-freddy volumio[1177]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 23 18:44:28 volumio-freddy volumio[1177]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 23 18:44:28 volumio-freddy volumio[1177]: info: Discovery: Getting this device information Jun 23 18:44:28 volumio-freddy volumio[1177]: info: CoreCommandRouter::volumioGetState Jun 23 18:44:28 volumio-freddy volumio[1177]: info: CorePlayQueue::getTrack 0 Jun 23 18:44:28 volumio-freddy volumio[1177]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 23 18:44:33 volumio-freddy volumiologrotate[601]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Jun 23 18:44:33 volumio-freddy volumiologrotate[601]: ls: cannot access 'FREDDY': No such file or directory Jun 23 18:44:57 volumio-freddy volumio[1177]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 23 18:44:57 volumio-freddy volumio[1177]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 23 18:44:57 volumio-freddy volumio[1177]: info: Discovery: Getting this device information Jun 23 18:44:57 volumio-freddy volumio[1177]: info: CoreCommandRouter::volumioGetState Jun 23 18:44:57 volumio-freddy volumio[1177]: info: CorePlayQueue::getTrack 0 Jun 23 18:44:57 volumio-freddy volumio[1177]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 23 18:45:11 volumio-freddy volumio[1177]: info: Clearing queue after UPNP request Jun 23 18:45:11 volumio-freddy volumio[1177]: info: CoreStateMachine::ClearQueue Jun 23 18:45:11 volumio-freddy volumio[1177]: info: CoreStateMachine::stop Jun 23 18:45:11 volumio-freddy volumio[1177]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 18:45:11 volumio-freddy volumio[1177]: info: CorePlayQueue::clearPlayQueue Jun 23 18:45:11 volumio-freddy volumio[1177]: info: CorePlayQueue::saveQueue Jun 23 18:45:11 volumio-freddy volumio[1177]: info: CoreCommandRouter::volumioPushState Jun 23 18:45:11 volumio-freddy volumio[1177]: info: CoreCommandRouter::volumioPushQueue Jun 23 18:45:11 volumio-freddy volumio[1177]: error: Upnp client error: Error: This socket has been ended by the other party Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Starting UPNP Playback Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Preparing playback through UPNP Jun 23 18:45:13 volumio-freddy volumio[1177]: info: CoreCommandRouter::volumioGetState Jun 23 18:45:13 volumio-freddy volumio[1177]: info: CorePlayQueue::getTrack 0 Jun 23 18:45:13 volumio-freddy volumio[1177]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Jun 23 18:45:13 volumio-freddy volumio[1177]: ---------------------------- MPD announces system playlist update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Ignoring MPD Status Update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Jun 23 18:45:13 volumio-freddy volumio[1177]: ---------------------------- MPD announces system playlist update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Ignoring MPD Status Update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Jun 23 18:45:13 volumio-freddy volumio[1177]: ---------------------------- MPD announces system playlist update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Ignoring MPD Status Update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: ------------------------------ 3ms Jun 23 18:45:13 volumio-freddy volumio[1177]: info: ------------------------------ 2ms Jun 23 18:45:13 volumio-freddy volumio[1177]: info: ------------------------------ 1ms Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Jun 23 18:45:13 volumio-freddy volumio[1177]: ---------------------------- MPD announces system playlist update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Ignoring MPD Status Update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Jun 23 18:45:13 volumio-freddy volumio[1177]: ---------------------------- MPD announces system playlist update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Ignoring MPD Status Update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Jun 23 18:45:13 volumio-freddy volumio[1177]: ---------------------------- MPD announces system playlist update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Ignoring MPD Status Update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Jun 23 18:45:13 volumio-freddy volumio[1177]: ---------------------------- MPD announces system playlist update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Ignoring MPD Status Update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: ------------------------------ 3ms Jun 23 18:45:13 volumio-freddy volumio[1177]: info: ------------------------------ 2ms Jun 23 18:45:13 volumio-freddy volumio[1177]: info: ------------------------------ 2ms Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Jun 23 18:45:13 volumio-freddy volumio[1177]: ---------------------------- MPD announces system playlist update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Ignoring MPD Status Update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Jun 23 18:45:13 volumio-freddy volumio[1177]: ---------------------------- MPD announces system playlist update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Ignoring MPD Status Update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: ------------------------------ 3ms Jun 23 18:45:13 volumio-freddy volumio[1177]: info: ------------------------------ 2ms Jun 23 18:45:13 volumio-freddy volumio[1177]: info: ------------------------------ 1ms Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Jun 23 18:45:13 volumio-freddy volumio[1177]: ---------------------------- MPD announces system playlist update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Ignoring MPD Status Update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Jun 23 18:45:13 volumio-freddy volumio[1177]: ---------------------------- MPD announces system playlist update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Ignoring MPD Status Update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Jun 23 18:45:13 volumio-freddy volumio[1177]: ---------------------------- MPD announces system playlist update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Ignoring MPD Status Update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Jun 23 18:45:13 volumio-freddy volumio[1177]: ---------------------------- MPD announces system playlist update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Ignoring MPD Status Update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: ------------------------------ 3ms Jun 23 18:45:13 volumio-freddy volumio[1177]: info: ------------------------------ 2ms Jun 23 18:45:13 volumio-freddy volumio[1177]: info: ------------------------------ 2ms Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Jun 23 18:45:13 volumio-freddy volumio[1177]: ---------------------------- MPD announces system playlist update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Ignoring MPD Status Update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Jun 23 18:45:13 volumio-freddy volumio[1177]: ---------------------------- MPD announces system playlist update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Ignoring MPD Status Update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: ------------------------------ 3ms Jun 23 18:45:13 volumio-freddy volumio[1177]: info: ------------------------------ 2ms Jun 23 18:45:13 volumio-freddy volumio[1177]: info: ------------------------------ 2ms Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Jun 23 18:45:13 volumio-freddy volumio[1177]: ---------------------------- MPD announces system playlist update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Ignoring MPD Status Update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Jun 23 18:45:13 volumio-freddy volumio[1177]: ---------------------------- MPD announces system playlist update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Ignoring MPD Status Update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Jun 23 18:45:13 volumio-freddy volumio[1177]: ---------------------------- MPD announces system playlist update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: Ignoring MPD Status Update Jun 23 18:45:13 volumio-freddy volumio[1177]: info: ------------------------------ 2ms Jun 23 18:45:13 volumio-freddy volumio[1177]: info: ------------------------------ 3ms Jun 23 18:45:13 volumio-freddy volumio[1177]: info: ------------------------------ 2ms Jun 23 18:45:14 volumio-freddy volumio[1177]: info: Jun 23 18:45:14 volumio-freddy volumio[1177]: ---------------------------- MPD announces state update: player Jun 23 18:45:14 volumio-freddy volumio[1177]: info: ControllerMpd::getState Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand status Jun 23 18:45:14 volumio-freddy volumio[1177]: info: Jun 23 18:45:14 volumio-freddy volumio[1177]: ---------------------------- MPD announces state update: player Jun 23 18:45:14 volumio-freddy volumio[1177]: info: ControllerMpd::getState Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand status Jun 23 18:45:14 volumio-freddy volumio[1177]: info: Jun 23 18:45:14 volumio-freddy volumio[1177]: ---------------------------- MPD announces state update: player Jun 23 18:45:14 volumio-freddy volumio[1177]: info: ControllerMpd::getState Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand status Jun 23 18:45:14 volumio-freddy volumio[1177]: info: Jun 23 18:45:14 volumio-freddy volumio[1177]: ---------------------------- MPD announces state update: player Jun 23 18:45:14 volumio-freddy volumio[1177]: info: sendMpdCommand status took 4 milliseconds Jun 23 18:45:14 volumio-freddy volumio[1177]: info: sendMpdCommand status took 3 milliseconds Jun 23 18:45:14 volumio-freddy volumio[1177]: info: sendMpdCommand status took 2 milliseconds Jun 23 18:45:14 volumio-freddy volumio[1177]: info: ControllerMpd::getState Jun 23 18:45:14 volumio-freddy kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand status Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseState Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseState Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseState Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 18:45:14 volumio-freddy volumio[1177]: info: sendMpdCommand status took 3 milliseconds Jun 23 18:45:14 volumio-freddy volumio[1177]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 23 18:45:14 volumio-freddy volumio[1177]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 23 18:45:14 volumio-freddy volumio[1177]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseState Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseTrackInfo Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseTrackInfo Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseTrackInfo Jun 23 18:45:14 volumio-freddy volumio[1177]: info: ControllerMpd::pushState Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CoreCommandRouter::servicePushState Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: In UPNP mode Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_four_extra-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_26173715/live/ww/bbc_radio_four_extra/bbc_radio_four_extra.isml/bbc_radio_four_extra-audio%3d128000.norewind.m3u8","trackType":"m3u8"} Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: CURRENT POSITION 0 Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState stateService play Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState currentStatus stop Jun 23 18:45:14 volumio-freddy volumio[1177]: info: ControllerMpd::pushState Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CoreCommandRouter::servicePushState Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: In UPNP mode Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_four_extra-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_26173715/live/ww/bbc_radio_four_extra/bbc_radio_four_extra.isml/bbc_radio_four_extra-audio%3d128000.norewind.m3u8","trackType":"m3u8"} Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: CURRENT POSITION 0 Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState stateService play Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState currentStatus play Jun 23 18:45:14 volumio-freddy volumio[1177]: info: Received an update from plugin. extracting info from payload Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CoreStateMachine::pushState Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CorePlayQueue::getTrack 0 Jun 23 18:45:14 volumio-freddy volumio[1177]: info: ControllerMpd::pushState Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CoreCommandRouter::servicePushState Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: In UPNP mode Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_four_extra-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_26173715/live/ww/bbc_radio_four_extra/bbc_radio_four_extra.isml/bbc_radio_four_extra-audio%3d128000.norewind.m3u8","trackType":"m3u8"} Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: CURRENT POSITION 0 Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState stateService play Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState currentStatus play Jun 23 18:45:14 volumio-freddy volumio[1177]: info: Received an update from plugin. extracting info from payload Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CoreStateMachine::pushState Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CorePlayQueue::getTrack 0 Jun 23 18:45:14 volumio-freddy volumio[1177]: info: ------------------------------ 19ms Jun 23 18:45:14 volumio-freddy volumio[1177]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 23 18:45:14 volumio-freddy volumio[1177]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 23 18:45:14 volumio-freddy volumio[1177]: info: ------------------------------ 19ms Jun 23 18:45:14 volumio-freddy volumio[1177]: info: ------------------------------ 18ms Jun 23 18:45:14 volumio-freddy volumio[1177]: info: Jun 23 18:45:14 volumio-freddy volumio[1177]: ---------------------------- MPD announces state update: player Jun 23 18:45:14 volumio-freddy volumio[1177]: info: ControllerMpd::getState Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand status Jun 23 18:45:14 volumio-freddy volumio[1177]: info: Jun 23 18:45:14 volumio-freddy volumio[1177]: ---------------------------- MPD announces state update: player Jun 23 18:45:14 volumio-freddy volumio[1177]: info: ControllerMpd::getState Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand status Jun 23 18:45:14 volumio-freddy volumio[1177]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 23 18:45:14 volumio-freddy volumio[1177]: info: sendMpdCommand status took 2 milliseconds Jun 23 18:45:14 volumio-freddy volumio[1177]: info: sendMpdCommand status took 2 milliseconds Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseTrackInfo Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseState Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseState Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 18:45:14 volumio-freddy volumio[1177]: info: ControllerMpd::pushState Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CoreCommandRouter::servicePushState Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: In UPNP mode Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: STATE SERVICE {"status":"play","position":1,"seek":52,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_four_extra-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_26173715/live/ww/bbc_radio_four_extra/bbc_radio_four_extra.isml/bbc_radio_four_extra-audio%3d128000.norewind.m3u8","trackType":"m3u8"} Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: CURRENT POSITION 0 Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState stateService play Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState currentStatus play Jun 23 18:45:14 volumio-freddy volumio[1177]: info: Received an update from plugin. extracting info from payload Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CoreStateMachine::pushState Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CorePlayQueue::getTrack 0 Jun 23 18:45:14 volumio-freddy volumio[1177]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 23 18:45:14 volumio-freddy volumio[1177]: info: ------------------------------ 25ms Jun 23 18:45:14 volumio-freddy volumio[1177]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 23 18:45:14 volumio-freddy volumio[1177]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseTrackInfo Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseTrackInfo Jun 23 18:45:14 volumio-freddy volumio[1177]: info: ControllerMpd::pushState Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CoreCommandRouter::servicePushState Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: In UPNP mode Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: STATE SERVICE {"status":"play","position":1,"seek":514,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_four_extra-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_26173715/live/ww/bbc_radio_four_extra/bbc_radio_four_extra.isml/bbc_radio_four_extra-audio%3d128000.norewind.m3u8","trackType":"m3u8"} Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: CURRENT POSITION 0 Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState stateService play Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState currentStatus play Jun 23 18:45:14 volumio-freddy volumio[1177]: info: Received an update from plugin. extracting info from payload Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CoreStateMachine::pushState Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CorePlayQueue::getTrack 0 Jun 23 18:45:14 volumio-freddy volumio[1177]: info: ControllerMpd::pushState Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CoreCommandRouter::servicePushState Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: In UPNP mode Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: STATE SERVICE {"status":"play","position":1,"seek":577,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_four_extra-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_26173715/live/ww/bbc_radio_four_extra/bbc_radio_four_extra.isml/bbc_radio_four_extra-audio%3d128000.norewind.m3u8","trackType":"m3u8"} Jun 23 18:45:14 volumio-freddy volumio[1177]: verbose: CURRENT POSITION 0 Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState stateService play Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState currentStatus play Jun 23 18:45:14 volumio-freddy volumio[1177]: info: Received an update from plugin. extracting info from payload Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CoreStateMachine::pushState Jun 23 18:45:14 volumio-freddy volumio[1177]: info: CorePlayQueue::getTrack 0 Jun 23 18:45:14 volumio-freddy volumio[1177]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 23 18:45:14 volumio-freddy volumio[1177]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 23 18:45:14 volumio-freddy volumio[1177]: info: ------------------------------ 15ms Jun 23 18:45:14 volumio-freddy volumio[1177]: info: ------------------------------ 14ms Jun 23 18:45:15 volumio-freddy volumio[1177]: info: Jun 23 18:45:15 volumio-freddy volumio[1177]: ---------------------------- MPD announces state update: player Jun 23 18:45:15 volumio-freddy volumio[1177]: info: ControllerMpd::getState Jun 23 18:45:15 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand status Jun 23 18:45:15 volumio-freddy volumio[1177]: info: Jun 23 18:45:15 volumio-freddy volumio[1177]: ---------------------------- MPD announces state update: player Jun 23 18:45:15 volumio-freddy volumio[1177]: info: ControllerMpd::getState Jun 23 18:45:15 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand status Jun 23 18:45:15 volumio-freddy volumio[1177]: info: Jun 23 18:45:15 volumio-freddy volumio[1177]: ---------------------------- MPD announces state update: player Jun 23 18:45:15 volumio-freddy volumio[1177]: info: ControllerMpd::getState Jun 23 18:45:15 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand status Jun 23 18:45:15 volumio-freddy volumio[1177]: info: sendMpdCommand status took 3 milliseconds Jun 23 18:45:15 volumio-freddy volumio[1177]: info: sendMpdCommand status took 2 milliseconds Jun 23 18:45:15 volumio-freddy volumio[1177]: info: sendMpdCommand status took 1 milliseconds Jun 23 18:45:15 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseState Jun 23 18:45:15 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 18:45:15 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseState Jun 23 18:45:15 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 18:45:15 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseState Jun 23 18:45:15 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 18:45:15 volumio-freddy volumio[1177]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 23 18:45:15 volumio-freddy volumio[1177]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 23 18:45:15 volumio-freddy volumio[1177]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 23 18:45:15 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseTrackInfo Jun 23 18:45:15 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseTrackInfo Jun 23 18:45:15 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseTrackInfo Jun 23 18:45:15 volumio-freddy volumio[1177]: info: ControllerMpd::pushState Jun 23 18:45:15 volumio-freddy volumio[1177]: info: CoreCommandRouter::servicePushState Jun 23 18:45:15 volumio-freddy volumio[1177]: verbose: In UPNP mode Jun 23 18:45:15 volumio-freddy volumio[1177]: verbose: STATE SERVICE {"status":"pause","position":1,"seek":1627,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_four_extra-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_26173715/live/ww/bbc_radio_four_extra/bbc_radio_four_extra.isml/bbc_radio_four_extra-audio%3d128000.norewind.m3u8","trackType":"m3u8"} Jun 23 18:45:15 volumio-freddy volumio[1177]: verbose: CURRENT POSITION 0 Jun 23 18:45:15 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState stateService pause Jun 23 18:45:15 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState currentStatus play Jun 23 18:45:15 volumio-freddy volumio[1177]: info: CoreStateMachine::pushState Jun 23 18:45:15 volumio-freddy volumio[1177]: info: CorePlayQueue::getTrack 0 Jun 23 18:45:15 volumio-freddy volumio[1177]: info: ControllerMpd::pushState Jun 23 18:45:15 volumio-freddy volumio[1177]: info: CoreCommandRouter::servicePushState Jun 23 18:45:15 volumio-freddy volumio[1177]: verbose: In UPNP mode Jun 23 18:45:15 volumio-freddy volumio[1177]: verbose: STATE SERVICE {"status":"pause","position":1,"seek":1627,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_four_extra-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_26173715/live/ww/bbc_radio_four_extra/bbc_radio_four_extra.isml/bbc_radio_four_extra-audio%3d128000.norewind.m3u8","trackType":"m3u8"} Jun 23 18:45:15 volumio-freddy volumio[1177]: verbose: CURRENT POSITION 0 Jun 23 18:45:15 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState stateService pause Jun 23 18:45:15 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState currentStatus play Jun 23 18:45:15 volumio-freddy volumio[1177]: info: CoreStateMachine::pushState Jun 23 18:45:15 volumio-freddy volumio[1177]: info: CorePlayQueue::getTrack 0 Jun 23 18:45:15 volumio-freddy volumio[1177]: info: ControllerMpd::pushState Jun 23 18:45:15 volumio-freddy volumio[1177]: info: CoreCommandRouter::servicePushState Jun 23 18:45:15 volumio-freddy volumio[1177]: verbose: In UPNP mode Jun 23 18:45:15 volumio-freddy volumio[1177]: verbose: STATE SERVICE {"status":"pause","position":1,"seek":1627,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_four_extra-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_26173715/live/ww/bbc_radio_four_extra/bbc_radio_four_extra.isml/bbc_radio_four_extra-audio%3d128000.norewind.m3u8","trackType":"m3u8"} Jun 23 18:45:15 volumio-freddy volumio[1177]: verbose: CURRENT POSITION 0 Jun 23 18:45:15 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState stateService pause Jun 23 18:45:15 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState currentStatus play Jun 23 18:45:15 volumio-freddy volumio[1177]: info: CoreStateMachine::pushState Jun 23 18:45:15 volumio-freddy volumio[1177]: info: CorePlayQueue::getTrack 0 Jun 23 18:45:15 volumio-freddy volumio[1177]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 23 18:45:15 volumio-freddy volumio[1177]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 23 18:45:15 volumio-freddy volumio[1177]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 23 18:45:15 volumio-freddy volumio[1177]: info: ------------------------------ 13ms Jun 23 18:45:15 volumio-freddy volumio[1177]: info: ------------------------------ 11ms Jun 23 18:45:15 volumio-freddy volumio[1177]: info: ------------------------------ 10ms Jun 23 18:45:23 volumio-freddy kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Jun 23 18:45:23 volumio-freddy volumio[1177]: info: Jun 23 18:45:23 volumio-freddy volumio[1177]: ---------------------------- MPD announces state update: player Jun 23 18:45:23 volumio-freddy volumio[1177]: info: ControllerMpd::getState Jun 23 18:45:23 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand status Jun 23 18:45:23 volumio-freddy volumio[1177]: info: Jun 23 18:45:23 volumio-freddy volumio[1177]: ---------------------------- MPD announces state update: player Jun 23 18:45:23 volumio-freddy volumio[1177]: info: ControllerMpd::getState Jun 23 18:45:23 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand status Jun 23 18:45:23 volumio-freddy volumio[1177]: info: Jun 23 18:45:23 volumio-freddy volumio[1177]: ---------------------------- MPD announces state update: player Jun 23 18:45:23 volumio-freddy volumio[1177]: info: ControllerMpd::getState Jun 23 18:45:23 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand status Jun 23 18:45:23 volumio-freddy volumio[1177]: info: sendMpdCommand status took 2 milliseconds Jun 23 18:45:23 volumio-freddy volumio[1177]: info: sendMpdCommand status took 1 milliseconds Jun 23 18:45:23 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseState Jun 23 18:45:23 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 18:45:23 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseState Jun 23 18:45:23 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 18:45:23 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseState Jun 23 18:45:23 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 18:45:23 volumio-freddy volumio[1177]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 23 18:45:23 volumio-freddy volumio[1177]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 23 18:45:23 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseTrackInfo Jun 23 18:45:23 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseTrackInfo Jun 23 18:45:23 volumio-freddy volumio[1177]: info: ControllerMpd::pushState Jun 23 18:45:23 volumio-freddy volumio[1177]: info: CoreCommandRouter::servicePushState Jun 23 18:45:23 volumio-freddy volumio[1177]: verbose: In UPNP mode Jun 23 18:45:23 volumio-freddy volumio[1177]: verbose: STATE SERVICE {"status":"play","position":1,"seek":1701,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_four_extra-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_26173715/live/ww/bbc_radio_four_extra/bbc_radio_four_extra.isml/bbc_radio_four_extra-audio%3d128000.norewind.m3u8","trackType":"m3u8"} Jun 23 18:45:23 volumio-freddy volumio[1177]: verbose: CURRENT POSITION 0 Jun 23 18:45:23 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState stateService play Jun 23 18:45:23 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState currentStatus play Jun 23 18:45:23 volumio-freddy volumio[1177]: info: Received an update from plugin. extracting info from payload Jun 23 18:45:23 volumio-freddy volumio[1177]: info: CoreStateMachine::pushState Jun 23 18:45:23 volumio-freddy volumio[1177]: info: CorePlayQueue::getTrack 0 Jun 23 18:45:23 volumio-freddy volumio[1177]: info: ControllerMpd::pushState Jun 23 18:45:23 volumio-freddy volumio[1177]: info: CoreCommandRouter::servicePushState Jun 23 18:45:23 volumio-freddy volumio[1177]: verbose: In UPNP mode Jun 23 18:45:23 volumio-freddy volumio[1177]: verbose: STATE SERVICE {"status":"play","position":1,"seek":1732,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_four_extra-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_26173715/live/ww/bbc_radio_four_extra/bbc_radio_four_extra.isml/bbc_radio_four_extra-audio%3d128000.norewind.m3u8","trackType":"m3u8"} Jun 23 18:45:23 volumio-freddy volumio[1177]: verbose: CURRENT POSITION 0 Jun 23 18:45:23 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState stateService play Jun 23 18:45:23 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState currentStatus play Jun 23 18:45:23 volumio-freddy volumio[1177]: info: Received an update from plugin. extracting info from payload Jun 23 18:45:23 volumio-freddy volumio[1177]: info: CoreStateMachine::pushState Jun 23 18:45:23 volumio-freddy volumio[1177]: info: CorePlayQueue::getTrack 0 Jun 23 18:45:23 volumio-freddy volumio[1177]: info: ControllerMpd::pushState Jun 23 18:45:23 volumio-freddy volumio[1177]: info: CoreCommandRouter::servicePushState Jun 23 18:45:23 volumio-freddy volumio[1177]: verbose: In UPNP mode Jun 23 18:45:23 volumio-freddy volumio[1177]: verbose: STATE SERVICE {"status":"play","position":1,"seek":1763,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_four_extra-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_26173715/live/ww/bbc_radio_four_extra/bbc_radio_four_extra.isml/bbc_radio_four_extra-audio%3d128000.norewind.m3u8","trackType":"m3u8"} Jun 23 18:45:23 volumio-freddy volumio[1177]: verbose: CURRENT POSITION 0 Jun 23 18:45:23 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState stateService play Jun 23 18:45:23 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState currentStatus play Jun 23 18:45:23 volumio-freddy volumio[1177]: info: Received an update from plugin. extracting info from payload Jun 23 18:45:23 volumio-freddy volumio[1177]: info: CoreStateMachine::pushState Jun 23 18:45:23 volumio-freddy volumio[1177]: info: CorePlayQueue::getTrack 0 Jun 23 18:45:23 volumio-freddy volumio[1177]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 23 18:45:23 volumio-freddy volumio[1177]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 23 18:45:23 volumio-freddy volumio[1177]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 23 18:45:23 volumio-freddy volumio[1177]: info: ------------------------------ 18ms Jun 23 18:45:23 volumio-freddy volumio[1177]: info: ------------------------------ 17ms Jun 23 18:45:23 volumio-freddy volumio[1177]: info: ------------------------------ 16ms Jun 23 18:45:27 volumio-freddy volumio[1177]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 23 18:45:27 volumio-freddy volumio[1177]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 23 18:45:27 volumio-freddy volumio[1177]: info: Discovery: Getting this device information Jun 23 18:45:27 volumio-freddy volumio[1177]: info: CoreCommandRouter::volumioGetState Jun 23 18:45:27 volumio-freddy volumio[1177]: info: CorePlayQueue::getTrack 0 Jun 23 18:45:27 volumio-freddy volumio[1177]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jun 23 18:45:27 volumio-freddy volumio[1177]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 23 18:45:27 volumio-freddy volumio[1177]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 23 18:45:27 volumio-freddy volumio[1177]: at ControllerVolumioDiscovery.getThisDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:464:38) Jun 23 18:45:27 volumio-freddy volumio[1177]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Jun 23 18:45:27 volumio-freddy volumio[1177]: at ControllerSystem.getSystemInfo (/volumio/app/plugins/system_controller/system/index.js:622:45) Jun 23 18:45:27 volumio-freddy volumio[1177]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Jun 23 18:45:27 volumio-freddy volumio[1177]: at RESTApiSystem.getSystemInfo (/volumio/app/plugins/user_interface/rest_api/system.js:38:41) Jun 23 18:45:27 volumio-freddy volumio[1177]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 23 18:45:27 volumio-freddy volumio[1177]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jun 23 18:45:27 volumio-freddy volumio[1177]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jun 23 18:45:27 volumio-freddy volumio[1177]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 23 18:45:27 volumio-freddy volumio[1177]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jun 23 18:45:27 volumio-freddy volumio[1177]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 23 18:45:27 volumio-freddy volumio[1177]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 23 18:45:27 volumio-freddy volumio[1177]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jun 23 18:45:27 volumio-freddy volumio[1177]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 23 18:45:28 volumio-freddy volumio[1177]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 23 18:45:28 volumio-freddy volumio[1177]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 23 18:45:28 volumio-freddy volumio[1177]: info: Discovery: Getting this device information Jun 23 18:45:28 volumio-freddy volumio[1177]: info: CoreCommandRouter::volumioGetState Jun 23 18:45:28 volumio-freddy volumio[1177]: info: CorePlayQueue::getTrack 0 Jun 23 18:45:28 volumio-freddy volumio[1177]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jun 23 18:45:28 volumio-freddy volumio[1177]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 23 18:45:28 volumio-freddy volumio[1177]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 23 18:45:28 volumio-freddy volumio[1177]: at ControllerVolumioDiscovery.getThisDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:464:38) Jun 23 18:45:28 volumio-freddy volumio[1177]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Jun 23 18:45:28 volumio-freddy volumio[1177]: at ControllerSystem.getSystemInfo (/volumio/app/plugins/system_controller/system/index.js:622:45) Jun 23 18:45:28 volumio-freddy volumio[1177]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Jun 23 18:45:28 volumio-freddy volumio[1177]: at RESTApiSystem.getSystemInfo (/volumio/app/plugins/user_interface/rest_api/system.js:38:41) Jun 23 18:45:28 volumio-freddy volumio[1177]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 23 18:45:28 volumio-freddy volumio[1177]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jun 23 18:45:28 volumio-freddy volumio[1177]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jun 23 18:45:28 volumio-freddy volumio[1177]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 23 18:45:28 volumio-freddy volumio[1177]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jun 23 18:45:28 volumio-freddy volumio[1177]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jun 23 18:45:28 volumio-freddy volumio[1177]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jun 23 18:45:28 volumio-freddy volumio[1177]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jun 23 18:45:28 volumio-freddy volumio[1177]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jun 23 18:45:30 volumio-freddy volumio[1177]: info: Jun 23 18:45:30 volumio-freddy volumio[1177]: ---------------------------- MPD announces state update: player Jun 23 18:45:30 volumio-freddy volumio[1177]: info: ControllerMpd::getState Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand status Jun 23 18:45:30 volumio-freddy volumio[1177]: info: Jun 23 18:45:30 volumio-freddy volumio[1177]: ---------------------------- MPD announces state update: player Jun 23 18:45:30 volumio-freddy volumio[1177]: info: ControllerMpd::getState Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand status Jun 23 18:45:30 volumio-freddy volumio[1177]: info: Jun 23 18:45:30 volumio-freddy volumio[1177]: ---------------------------- MPD announces state update: player Jun 23 18:45:30 volumio-freddy volumio[1177]: info: ControllerMpd::getState Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand status Jun 23 18:45:30 volumio-freddy volumio[1177]: info: sendMpdCommand status took 5 milliseconds Jun 23 18:45:30 volumio-freddy volumio[1177]: info: sendMpdCommand status took 4 milliseconds Jun 23 18:45:30 volumio-freddy volumio[1177]: info: sendMpdCommand status took 3 milliseconds Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseState Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseState Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseState Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 18:45:30 volumio-freddy volumio[1177]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 23 18:45:30 volumio-freddy volumio[1177]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 23 18:45:30 volumio-freddy volumio[1177]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseTrackInfo Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseTrackInfo Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseTrackInfo Jun 23 18:45:30 volumio-freddy volumio[1177]: info: ControllerMpd::pushState Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreCommandRouter::servicePushState Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: In UPNP mode Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: STATE SERVICE {"status":"pause","position":1,"seek":8893,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_four_extra-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_26173715/live/ww/bbc_radio_four_extra/bbc_radio_four_extra.isml/bbc_radio_four_extra-audio%3d128000.norewind.m3u8","trackType":"m3u8"} Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: CURRENT POSITION 0 Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState stateService pause Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState currentStatus play Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreStateMachine::pushState Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CorePlayQueue::getTrack 0 Jun 23 18:45:30 volumio-freddy volumio[1177]: info: ControllerMpd::pushState Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreCommandRouter::servicePushState Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: In UPNP mode Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: STATE SERVICE {"status":"pause","position":1,"seek":8893,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_four_extra-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_26173715/live/ww/bbc_radio_four_extra/bbc_radio_four_extra.isml/bbc_radio_four_extra-audio%3d128000.norewind.m3u8","trackType":"m3u8"} Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: CURRENT POSITION 0 Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState stateService pause Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState currentStatus play Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreStateMachine::pushState Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CorePlayQueue::getTrack 0 Jun 23 18:45:30 volumio-freddy volumio[1177]: info: ControllerMpd::pushState Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreCommandRouter::servicePushState Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: In UPNP mode Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: STATE SERVICE {"status":"pause","position":1,"seek":8893,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_four_extra-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_26173715/live/ww/bbc_radio_four_extra/bbc_radio_four_extra.isml/bbc_radio_four_extra-audio%3d128000.norewind.m3u8","trackType":"m3u8"} Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: CURRENT POSITION 0 Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState stateService pause Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState currentStatus play Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreStateMachine::pushState Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CorePlayQueue::getTrack 0 Jun 23 18:45:30 volumio-freddy volumio[1177]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 23 18:45:30 volumio-freddy volumio[1177]: info: ------------------------------ 19ms Jun 23 18:45:30 volumio-freddy volumio[1177]: info: ------------------------------ 18ms Jun 23 18:45:30 volumio-freddy volumio[1177]: info: Jun 23 18:45:30 volumio-freddy volumio[1177]: ---------------------------- MPD announces state update: player Jun 23 18:45:30 volumio-freddy volumio[1177]: info: ControllerMpd::getState Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand status Jun 23 18:45:30 volumio-freddy volumio[1177]: info: Jun 23 18:45:30 volumio-freddy volumio[1177]: ---------------------------- MPD announces state update: player Jun 23 18:45:30 volumio-freddy volumio[1177]: info: ControllerMpd::getState Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand status Jun 23 18:45:30 volumio-freddy volumio[1177]: info: Jun 23 18:45:30 volumio-freddy volumio[1177]: ---------------------------- MPD announces state update: player Jun 23 18:45:30 volumio-freddy volumio[1177]: info: ControllerMpd::getState Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand status Jun 23 18:45:30 volumio-freddy volumio[1177]: info: sendMpdCommand status took 5 milliseconds Jun 23 18:45:30 volumio-freddy volumio[1177]: info: sendMpdCommand status took 3 milliseconds Jun 23 18:45:30 volumio-freddy volumio[1177]: info: sendMpdCommand status took 2 milliseconds Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseState Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseState Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseState Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 18:45:30 volumio-freddy volumio[1177]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 23 18:45:30 volumio-freddy volumio[1177]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 23 18:45:30 volumio-freddy volumio[1177]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseTrackInfo Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseTrackInfo Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: ControllerMpd::parseTrackInfo Jun 23 18:45:30 volumio-freddy volumio[1177]: info: ControllerMpd::pushState Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreCommandRouter::servicePushState Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: In UPNP mode Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: STATE SERVICE {"status":"play","position":1,"seek":9229,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_four_extra-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_26173715/live/ww/bbc_radio_four_extra/bbc_radio_four_extra.isml/bbc_radio_four_extra-audio%3d128000.norewind.m3u8","trackType":"m3u8"} Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: CURRENT POSITION 0 Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState stateService play Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState currentStatus play Jun 23 18:45:30 volumio-freddy volumio[1177]: info: Received an update from plugin. extracting info from payload Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreStateMachine::pushState Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CorePlayQueue::getTrack 0 Jun 23 18:45:30 volumio-freddy volumio[1177]: info: ControllerMpd::pushState Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreCommandRouter::servicePushState Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: In UPNP mode Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: STATE SERVICE {"status":"play","position":1,"seek":9355,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_four_extra-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_26173715/live/ww/bbc_radio_four_extra/bbc_radio_four_extra.isml/bbc_radio_four_extra-audio%3d128000.norewind.m3u8","trackType":"m3u8"} Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: CURRENT POSITION 0 Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState stateService play Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState currentStatus play Jun 23 18:45:30 volumio-freddy volumio[1177]: info: Received an update from plugin. extracting info from payload Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreStateMachine::pushState Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CorePlayQueue::getTrack 0 Jun 23 18:45:30 volumio-freddy volumio[1177]: info: ControllerMpd::pushState Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreCommandRouter::servicePushState Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: In UPNP mode Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: STATE SERVICE {"status":"play","position":1,"seek":9418,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_four_extra-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_26173715/live/ww/bbc_radio_four_extra/bbc_radio_four_extra.isml/bbc_radio_four_extra-audio%3d128000.norewind.m3u8","trackType":"m3u8"} Jun 23 18:45:30 volumio-freddy volumio[1177]: verbose: CURRENT POSITION 0 Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState stateService play Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreStateMachine::syncState currentStatus play Jun 23 18:45:30 volumio-freddy volumio[1177]: info: Received an update from plugin. extracting info from payload Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CoreStateMachine::pushState Jun 23 18:45:30 volumio-freddy volumio[1177]: info: CorePlayQueue::getTrack 0 Jun 23 18:45:30 volumio-freddy volumio[1177]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 23 18:45:30 volumio-freddy volumio[1177]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 23 18:45:30 volumio-freddy volumio[1177]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 23 18:45:30 volumio-freddy volumio[1177]: info: ------------------------------ 17ms Jun 23 18:45:30 volumio-freddy volumio[1177]: info: ------------------------------ 15ms Jun 23 18:45:30 volumio-freddy volumio[1177]: info: ------------------------------ 11ms Jun 23 18:45:32 volumio-freddy volumio[1177]: info: Starting UPNP Playback Jun 23 18:45:32 volumio-freddy volumio[1177]: info: Preparing playback through UPNP Jun 23 18:45:32 volumio-freddy volumio[1177]: info: Consume mode Jun 23 18:45:32 volumio-freddy volumio[1177]: info: CoreCommandRouter::volumioGetState Jun 23 18:45:32 volumio-freddy volumio[1177]: info: CorePlayQueue::getTrack 0 Jun 23 18:45:32 volumio-freddy volumio[1177]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 23 18:45:32 volumio-freddy volumio[1177]: TypeError: Cannot read property 'name' of undefined Jun 23 18:45:32 volumio-freddy volumio[1177]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 23 18:45:32 volumio-freddy volumio[1177]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 23 18:45:32 volumio-freddy volumio[1177]: at UpnpInterface.prepareUpnpPlayback (/volumio/app/plugins/audio_interface/upnp/index.js:257:34) Jun 23 18:45:32 volumio-freddy volumio[1177]: at Socket. (/volumio/app/plugins/audio_interface/upnp/index.js:44:14) Jun 23 18:45:32 volumio-freddy volumio[1177]: at Socket.emit (events.js:315:20) Jun 23 18:45:32 volumio-freddy volumio[1177]: at addChunk (internal/streams/readable.js:309:12) Jun 23 18:45:32 volumio-freddy volumio[1177]: at readableAddChunk (internal/streams/readable.js:280:11) Jun 23 18:45:32 volumio-freddy volumio[1177]: at Socket.Readable.push (internal/streams/readable.js:223:10) Jun 23 18:45:32 volumio-freddy volumio[1177]: at TCP.onStreamRead (internal/stream_base_commons.js:188:23) Jun 23 18:45:32 volumio-freddy volumio[1177]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 23 18:45:32 volumio-freddy sudo[4633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-23 18:44 Jun 23 18:45:32 volumio-freddy sudo[4633]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"