-- Logs begin at Thu 2024-10-24 08:59:23 UTC, end at Thu 2025-05-15 08:04:16 UTC. -- May 15 08:03:50 volumio volumio[898]: info: CoreCommandRouter::volumioPlay May 15 08:03:50 volumio volumio[898]: verbose: UNSET VOLATILE May 15 08:03:50 volumio volumio[898]: info: CoreStateMachine::play index undefined May 15 08:03:50 volumio volumio[898]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 08:03:50 volumio volumio[898]: info: CorePlayQueue::getTrack 0 May 15 08:03:50 volumio volumio[898]: info: CoreStateMachine::startPlaybackTimer May 15 08:03:50 volumio volumio[898]: info: CorePlayQueue::getTrack 0 May 15 08:03:50 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand stop May 15 08:03:50 volumio volumio[898]: info: sendMpdCommand stop took 53 milliseconds May 15 08:03:50 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand clear May 15 08:03:50 volumio volumio[898]: info: May 15 08:03:50 volumio volumio[898]: ---------------------------- MPD announces system playlist update May 15 08:03:50 volumio volumio[898]: info: Ignoring MPD Status Update May 15 08:03:50 volumio volumio[898]: info: sendMpdCommand clear took 29 milliseconds May 15 08:03:51 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand add "https://podcast-mp3.dradio.de/podcast/2025/03/28/deutschlandfunknova_anthropozaen_und_klima_20250328_2b942540.mp3" May 15 08:03:51 volumio volumio[898]: error: updateQueue error: null May 15 08:03:51 volumio volumio[898]: info: May 15 08:03:51 volumio volumio[898]: ---------------------------- MPD announces system playlist update May 15 08:03:51 volumio volumio[898]: info: Ignoring MPD Status Update May 15 08:03:51 volumio volumio[898]: info: ------------------------------ 64ms May 15 08:03:51 volumio volumio[898]: info: sendMpdCommand add "https://podcast-mp3.dradio.de/podcast/2025/03/28/deutschlandfunknova_anthropozaen_und_klima_20250328_2b942540.mp3" took 43 milliseconds May 15 08:03:51 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand play May 15 08:03:51 volumio volumio[898]: info: ------------------------------ 48ms May 15 08:03:51 volumio volumio[898]: info: sendMpdCommand play took 25 milliseconds May 15 08:03:51 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand status May 15 08:03:51 volumio volumio[898]: info: sendMpdCommand status took 15 milliseconds May 15 08:03:51 volumio volumio[898]: verbose: ControllerMpd::parseState May 15 08:03:51 volumio volumio[898]: info: CorePlayQueue::getTrack 0 May 15 08:03:51 volumio volumio[898]: info: CoreCommandRouter::servicePushState May 15 08:03:51 volumio volumio[898]: info: CorePlayQueue::getTrack 0 May 15 08:03:51 volumio volumio[898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"uri":"https://podcast-mp3.dradio.de/podcast/2025/03/28/deutschlandfunknova_anthropozaen_und_klima_20250328_2b942540.mp3","trackType":"Podcast","serviceName":"podcast"} May 15 08:03:51 volumio volumio[898]: verbose: CURRENT POSITION 0 May 15 08:03:51 volumio volumio[898]: info: CoreStateMachine::syncState stateService play May 15 08:03:51 volumio volumio[898]: info: CoreStateMachine::syncState currentStatus stop May 15 08:03:51 volumio volumio[898]: info: CoreStateMachine::pushState May 15 08:03:51 volumio volumio[898]: info: CorePlayQueue::getTrack 0 May 15 08:03:51 volumio volumio[898]: info: CoreCommandRouter::volumioPushState May 15 08:03:51 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 08:03:52 volumio volumio[898]: info: May 15 08:03:52 volumio volumio[898]: ---------------------------- MPD announces state update: player May 15 08:03:52 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand status May 15 08:03:52 volumio volumio[898]: info: ControllerMpd::getState May 15 08:03:52 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand status May 15 08:03:52 volumio volumio[898]: info: May 15 08:03:52 volumio volumio[898]: ---------------------------- MPD announces system playlist update May 15 08:03:52 volumio volumio[898]: info: Ignoring MPD Status Update May 15 08:03:52 volumio volumio[898]: info: May 15 08:03:52 volumio volumio[898]: ---------------------------- MPD announces state update: player May 15 08:03:52 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand status May 15 08:03:52 volumio volumio[898]: info: ControllerMpd::getState May 15 08:03:52 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand status May 15 08:03:52 volumio volumio[898]: info: sendMpdCommand status took 116 milliseconds May 15 08:03:52 volumio volumio[898]: info: sendMpdCommand status took 97 milliseconds May 15 08:03:52 volumio volumio[898]: verbose: ControllerMpd::parseState May 15 08:03:52 volumio volumio[898]: info: CorePlayQueue::getTrack 0 May 15 08:03:52 volumio volumio[898]: verbose: ControllerMpd::parseState May 15 08:03:52 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 08:03:52 volumio volumio[898]: info: CoreCommandRouter::servicePushState May 15 08:03:52 volumio volumio[898]: info: CorePlayQueue::getTrack 0 May 15 08:03:52 volumio volumio[898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":501,"duration":3822,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"uri":"https://podcast-mp3.dradio.de/podcast/2025/03/28/deutschlandfunknova_anthropozaen_und_klima_20250328_2b942540.mp3","trackType":"Podcast","serviceName":"podcast"} May 15 08:03:52 volumio volumio[898]: verbose: CURRENT POSITION 0 May 15 08:03:52 volumio volumio[898]: info: CoreStateMachine::syncState stateService play May 15 08:03:52 volumio volumio[898]: info: CoreStateMachine::syncState currentStatus play May 15 08:03:52 volumio volumio[898]: info: Received an update from plugin. extracting info from payload May 15 08:03:52 volumio volumio[898]: info: CoreStateMachine::pushState May 15 08:03:52 volumio volumio[898]: info: CorePlayQueue::getTrack 0 May 15 08:03:52 volumio volumio[898]: info: CoreCommandRouter::volumioPushState May 15 08:03:52 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 08:03:52 volumio volumio[898]: info: CoreStateMachine::pushState May 15 08:03:52 volumio volumio[898]: info: CorePlayQueue::getTrack 0 May 15 08:03:52 volumio volumio[898]: info: CoreCommandRouter::volumioPushState May 15 08:03:52 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 08:03:52 volumio volumio[898]: info: sendMpdCommand status took 468 milliseconds May 15 08:03:52 volumio volumio[898]: info: ------------------------------ 509ms May 15 08:03:52 volumio volumio[898]: info: sendMpdCommand status took 463 milliseconds May 15 08:03:52 volumio volumio[898]: info: sendMpdCommand playlistinfo took 373 milliseconds May 15 08:03:52 volumio volumio[898]: verbose: ControllerMpd::parseState May 15 08:03:52 volumio volumio[898]: info: CorePlayQueue::getTrack 0 May 15 08:03:52 volumio volumio[898]: verbose: ControllerMpd::parseState May 15 08:03:52 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 08:03:52 volumio volumio[898]: verbose: ControllerMpd::parseTrackInfo May 15 08:03:52 volumio volumio[898]: info: CoreCommandRouter::servicePushState May 15 08:03:52 volumio volumio[898]: info: CorePlayQueue::getTrack 0 May 15 08:03:52 volumio volumio[898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":501,"duration":3822,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"uri":"https://podcast-mp3.dradio.de/podcast/2025/03/28/deutschlandfunknova_anthropozaen_und_klima_20250328_2b942540.mp3","trackType":"Podcast","serviceName":"podcast"} May 15 08:03:52 volumio volumio[898]: verbose: CURRENT POSITION 0 May 15 08:03:52 volumio volumio[898]: info: CoreStateMachine::syncState stateService play May 15 08:03:52 volumio volumio[898]: info: CoreStateMachine::syncState currentStatus play May 15 08:03:52 volumio volumio[898]: info: Received an update from plugin. extracting info from payload May 15 08:03:52 volumio volumio[898]: info: CoreStateMachine::pushState May 15 08:03:52 volumio volumio[898]: info: CorePlayQueue::getTrack 0 May 15 08:03:52 volumio volumio[898]: info: CoreCommandRouter::volumioPushState May 15 08:03:52 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 08:03:52 volumio volumio[898]: info: CoreStateMachine::pushState May 15 08:03:52 volumio volumio[898]: info: CorePlayQueue::getTrack 0 May 15 08:03:52 volumio volumio[898]: info: CoreCommandRouter::volumioPushState May 15 08:03:52 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 08:03:53 volumio volumio[898]: info: ControllerMpd::pushState May 15 08:03:53 volumio volumio[898]: info: CoreCommandRouter::servicePushState May 15 08:03:53 volumio volumio[898]: info: CorePlayQueue::getTrack 0 May 15 08:03:53 volumio volumio[898]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current podcast Received mpd May 15 08:03:53 volumio volumio[898]: info: ------------------------------ 1046ms May 15 08:03:53 volumio volumio[898]: info: sendMpdCommand playlistinfo took 573 milliseconds May 15 08:03:53 volumio volumio[898]: verbose: ControllerMpd::parseTrackInfo May 15 08:03:53 volumio volumio[898]: info: ControllerMpd::pushState May 15 08:03:53 volumio volumio[898]: info: CoreCommandRouter::servicePushState May 15 08:03:53 volumio volumio[898]: info: CorePlayQueue::getTrack 0 May 15 08:03:53 volumio volumio[898]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current podcast Received mpd May 15 08:03:53 volumio volumio[898]: info: ------------------------------ 1192ms May 15 08:04:00 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: podcast , handleBrowseUri May 15 08:04:00 volumio volumio[898]: info: CoreCommandRouter::volumioGetQueue May 15 08:04:00 volumio volumio[898]: info: CoreStateMachine::getQueue May 15 08:04:00 volumio volumio[898]: info: CorePlayQueue::getQueue May 15 08:04:10 volumio volumio[898]: info: CoreCommandRouter::volumioReplaceandPlayItems May 15 08:04:10 volumio volumio[898]: info: CoreStateMachine::ClearQueue May 15 08:04:10 volumio volumio[898]: info: CoreStateMachine::stop May 15 08:04:10 volumio volumio[898]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 08:04:10 volumio volumio[898]: verbose: UNSET VOLATILE May 15 08:04:10 volumio volumio[898]: info: CoreStateMachine::stPlaybackTimer May 15 08:04:10 volumio volumio[898]: info: CoreStateMachine::updateTrackBlock May 15 08:04:10 volumio volumio[898]: info: CorePlayQueue::getTrackBlock May 15 08:04:10 volumio volumio[898]: info: CoreStateMachine::pushState May 15 08:04:10 volumio volumio[898]: info: CorePlayQueue::getTrack 0 May 15 08:04:10 volumio volumio[898]: info: CoreCommandRouter::volumioPushState May 15 08:04:10 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 08:04:10 volumio volumio[898]: info: CoreStateMachine::serviceStop May 15 08:04:10 volumio volumio[898]: info: CorePlayQueue::getTrack 0 May 15 08:04:10 volumio volumio[898]: info: CoreCommandRouter::serviceStop May 15 08:04:10 volumio volumio[898]: info: ControllerMpd::stop May 15 08:04:10 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand stop May 15 08:04:10 volumio volumio[898]: info: CorePlayQueue::clearPlayQueue May 15 08:04:10 volumio volumio[898]: info: CorePlayQueue::saveQueue May 15 08:04:10 volumio volumio[898]: info: CoreCommandRouter::volumioPushState May 15 08:04:10 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 08:04:10 volumio volumio[898]: info: CoreCommandRouter::volumioPushQueue May 15 08:04:10 volumio volumio[898]: info: CoreStateMachine::addQueueItems May 15 08:04:10 volumio volumio[898]: info: CorePlayQueue::addQueueItems May 15 08:04:10 volumio volumio[898]: info: Adding Item to queue: podcast/6 May 15 08:04:10 volumio volumio[898]: info: Exploding uri podcast/6 in service podcast May 15 08:04:10 volumio volumio[898]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 08:04:10 volumio volumio[898]: TypeError: Cannot read property 'url' of undefined May 15 08:04:10 volumio volumio[898]: at ControllerPodcast.explodeUri (/data/plugins/music_service/podcast/index.js:659:22) May 15 08:04:10 volumio volumio[898]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1283:16) May 15 08:04:10 volumio volumio[898]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:144:48) May 15 08:04:10 volumio volumio[898]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:238:25) May 15 08:04:10 volumio volumio[898]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:482:23) May 15 08:04:10 volumio volumio[898]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:452:17) May 15 08:04:10 volumio volumio[898]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:106:35) May 15 08:04:10 volumio volumio[898]: at emitTwo (events.js:126:13) May 15 08:04:10 volumio volumio[898]: at Socket.emit (events.js:214:7) May 15 08:04:10 volumio volumio[898]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 15 08:04:10 volumio volumio[898]: at _combinedTickCallback (internal/process/next_tick.js:131:7) May 15 08:04:10 volumio volumio[898]: at process._tickCallback (internal/process/next_tick.js:180:9) May 15 08:04:10 volumio volumio[898]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 08:04:16 volumio sudo[3649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-15 08:03 May 15 08:04:16 volumio sudo[3649]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"