-- Logs begin at Thu 2024-04-18 14:13:34 CEST, end at Fri 2024-04-19 17:35:38 CEST. -- Apr 19 17:34:06 volumio volumio[9043]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/podcast/2.2.0/volumio/buster/armhf Apr 19 17:34:07 volumio volumio[9043]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/podcast/2.2.0/volumio/buster/armhf Apr 19 17:34:07 volumio volumio[9043]: info: Folder /tmp/plugins removed Apr 19 17:34:08 volumio volumio[9043]: info: Check plugin dependencies Apr 19 17:34:08 volumio volumio[9043]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 17:34:08 volumio volumio[9043]: info: Checking if plugin already exists Apr 19 17:34:08 volumio volumio[9043]: info: Rename folder Apr 19 17:34:08 volumio volumio[9043]: info: Folder /tmp/downloaded_plugin.zip removed Apr 19 17:34:08 volumio volumio[9043]: info: Move to category Apr 19 17:34:08 volumio volumio[9043]: info: Checking if install.sh is present Apr 19 17:34:08 volumio volumio[9043]: info: Executing install.sh Apr 19 17:34:09 volumio sudo[9871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/podcast/install.sh Apr 19 17:34:09 volumio sudo[9871]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 17:34:09 volumio sudo[9871]: pam_unix(sudo:session): session closed for user root Apr 19 17:34:09 volumio volumio[9043]: info: Installing Podcast plugin Apr 19 17:34:09 volumio volumio[9043]: Plugin install end detected on script Apr 19 17:34:09 volumio volumio[9043]: info: Install script completed Apr 19 17:34:09 volumio volumio[9043]: info: Adding reference to registry Apr 19 17:34:09 volumio volumio[9043]: info: Done installing plugin. Apr 19 17:34:09 volumio volumio[9043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 19 17:34:09 volumio volumio[9043]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 19 17:34:09 volumio volumio[9043]: info: Folder /tmp/plugins removed Apr 19 17:34:09 volumio volumio[9043]: info: Folder /tmp/downloaded_plugin.zip removed Apr 19 17:34:09 volumio volumio[9043]: info: Folder /data/temp removed Apr 19 17:34:12 volumio volumio[9043]: info: Enabling plugin podcast Apr 19 17:34:12 volumio volumio[9043]: info: Loading plugin "podcast"... Apr 19 17:34:12 volumio volumio[9043]: info: ControllerPodcast::constructor Apr 19 17:34:12 volumio volumio[9043]: info: PLUGIN START: podcast Apr 19 17:34:12 volumio volumio[9043]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 17:34:12 volumio volumio[9043]: info: [1713540852220] CoreMusicLibrary::Adding element Podcast Apr 19 17:34:12 volumio volumio[9043]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 17:34:12 volumio volumio[9043]: Cannot find translation for source Podcast Apr 19 17:34:12 volumio volumio[9043]: Cannot find translation for source Randomizer Apr 19 17:34:12 volumio volumio[9043]: info: Done. Apr 19 17:34:13 volumio volumio[9043]: info: Enabling plugin podcast Apr 19 17:34:13 volumio volumio[9043]: info: Loading plugin "podcast"... Apr 19 17:34:13 volumio volumio[9043]: info: ControllerPodcast::constructor Apr 19 17:34:13 volumio volumio[9043]: info: PLUGIN START: podcast Apr 19 17:34:13 volumio volumio[9043]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 17:34:13 volumio volumio[9043]: info: [1713540853913] CoreMusicLibrary::Adding element Podcast Apr 19 17:34:13 volumio volumio[9043]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 17:34:13 volumio volumio[9043]: Cannot find translation for source Podcast Apr 19 17:34:13 volumio volumio[9043]: Cannot find translation for source Randomizer Apr 19 17:34:13 volumio volumio[9043]: info: Done. Apr 19 17:34:16 volumio volumio[9043]: info: Enabling plugin podcast Apr 19 17:34:16 volumio volumio[9043]: info: Loading plugin "podcast"... Apr 19 17:34:16 volumio volumio[9043]: info: ControllerPodcast::constructor Apr 19 17:34:16 volumio volumio[9043]: info: PLUGIN START: podcast Apr 19 17:34:16 volumio volumio[9043]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 17:34:16 volumio volumio[9043]: info: [1713540856155] CoreMusicLibrary::Adding element Podcast Apr 19 17:34:16 volumio volumio[9043]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 17:34:16 volumio volumio[9043]: Cannot find translation for source Podcast Apr 19 17:34:16 volumio volumio[9043]: Cannot find translation for source Randomizer Apr 19 17:34:16 volumio volumio[9043]: info: Done. Apr 19 17:34:19 volumio volumio[9043]: info: Enabling plugin podcast Apr 19 17:34:19 volumio volumio[9043]: info: Loading plugin "podcast"... Apr 19 17:34:19 volumio volumio[9043]: info: ControllerPodcast::constructor Apr 19 17:34:19 volumio volumio[9043]: info: PLUGIN START: podcast Apr 19 17:34:19 volumio volumio[9043]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 17:34:19 volumio volumio[9043]: info: [1713540859890] CoreMusicLibrary::Adding element Podcast Apr 19 17:34:19 volumio volumio[9043]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 17:34:19 volumio volumio[9043]: Cannot find translation for source Podcast Apr 19 17:34:19 volumio volumio[9043]: Cannot find translation for source Randomizer Apr 19 17:34:19 volumio volumio[9043]: info: Done. Apr 19 17:34:24 volumio volumio[9043]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 17:35:10 volumio volumio[9043]: info: CoreCommandRouter::volumioGetState Apr 19 17:35:10 volumio volumio[9043]: info: CorePlayQueue::getTrack 23 Apr 19 17:35:25 volumio volumio[9043]: info: CoreCommandRouter::executeOnPlugin: podcast , handleBrowseUri Apr 19 17:35:25 volumio volumio[9043]: info: Preload queue cleared Apr 19 17:35:35 volumio volumio[9043]: info: CoreCommandRouter::volumioPause Apr 19 17:35:35 volumio volumio[9043]: info: CoreStateMachine::pause Apr 19 17:35:35 volumio volumio[9043]: info: CoreStateMachine::stPlaybackTimer Apr 19 17:35:35 volumio volumio[9043]: info: CoreStateMachine::servicePause Apr 19 17:35:35 volumio volumio[9043]: info: CorePlayQueue::getTrack 23 Apr 19 17:35:35 volumio volumio[9043]: info: CoreCommandRouter::servicePause Apr 19 17:35:35 volumio volumio[9043]: info: ControllerMpd::pause Apr 19 17:35:35 volumio volumio[9043]: verbose: ControllerMpd::sendMpdCommand pause Apr 19 17:35:35 volumio volumio[9043]: info: sendMpdCommand pause took 1 milliseconds Apr 19 17:35:35 volumio volumio[9043]: info: Apr 19 17:35:35 volumio volumio[9043]: ---------------------------- MPD announces state update: player Apr 19 17:35:35 volumio volumio[9043]: info: ControllerMpd::getState Apr 19 17:35:35 volumio volumio[9043]: verbose: ControllerMpd::sendMpdCommand status Apr 19 17:35:35 volumio volumio[9043]: info: Apr 19 17:35:35 volumio volumio[9043]: ---------------------------- MPD announces state update: player Apr 19 17:35:35 volumio volumio[9043]: info: ControllerMpd::getState Apr 19 17:35:35 volumio volumio[9043]: verbose: ControllerMpd::sendMpdCommand status Apr 19 17:35:35 volumio volumio[9043]: info: Apr 19 17:35:35 volumio volumio[9043]: ---------------------------- MPD announces state update: player Apr 19 17:35:35 volumio volumio[9043]: info: ControllerMpd::getState Apr 19 17:35:35 volumio volumio[9043]: verbose: ControllerMpd::sendMpdCommand status Apr 19 17:35:35 volumio volumio[9043]: info: sendMpdCommand status took 6 milliseconds Apr 19 17:35:35 volumio volumio[9043]: info: sendMpdCommand status took 6 milliseconds Apr 19 17:35:35 volumio volumio[9043]: verbose: ControllerMpd::parseState Apr 19 17:35:35 volumio volumio[9043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 17:35:35 volumio volumio[9043]: verbose: ControllerMpd::parseState Apr 19 17:35:35 volumio volumio[9043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 17:35:35 volumio volumio[9043]: info: sendMpdCommand status took 2 milliseconds Apr 19 17:35:35 volumio volumio[9043]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 19 17:35:35 volumio volumio[9043]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 19 17:35:35 volumio volumio[9043]: verbose: ControllerMpd::parseState Apr 19 17:35:35 volumio volumio[9043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 17:35:35 volumio volumio[9043]: verbose: ControllerMpd::parseTrackInfo Apr 19 17:35:35 volumio volumio[9043]: info: ControllerMpd::pushState Apr 19 17:35:35 volumio volumio[9043]: info: CoreCommandRouter::servicePushState Apr 19 17:35:35 volumio volumio[9043]: info: CorePlayQueue::getTrack 23 Apr 19 17:35:35 volumio volumio[9043]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":259302,"duration":429,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"I Am A River","artist":"Foo Fighters","album":"Sonic Highways","uri":"USB/Elements/Musiques/Rock/Foo Fighters/Foo Fighters - Sonic Highways/08 - I Am A River.mp3","trackType":"mp3"} Apr 19 17:35:35 volumio volumio[9043]: verbose: CURRENT POSITION 23 Apr 19 17:35:35 volumio volumio[9043]: info: CoreStateMachine::syncState stateService pause Apr 19 17:35:35 volumio volumio[9043]: info: CoreStateMachine::syncState currentStatus pause Apr 19 17:35:35 volumio volumio[9043]: info: CoreStateMachine::pushState Apr 19 17:35:35 volumio volumio[9043]: info: CorePlayQueue::getTrack 23 Apr 19 17:35:35 volumio volumio[9043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 17:35:35 volumio volumio[9043]: info: CoreCommandRouter::volumioPushState Apr 19 17:35:35 volumio volumio[9043]: info: CoreStateMachine::stPlaybackTimer Apr 19 17:35:35 volumio volumio[9043]: info: ControllerMpd::pushState Apr 19 17:35:35 volumio volumio[9043]: info: CoreCommandRouter::servicePushState Apr 19 17:35:35 volumio volumio[9043]: info: CorePlayQueue::getTrack 23 Apr 19 17:35:35 volumio volumio[9043]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":259302,"duration":429,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"I Am A River","artist":"Foo Fighters","album":"Sonic Highways","uri":"USB/Elements/Musiques/Rock/Foo Fighters/Foo Fighters - Sonic Highways/08 - I Am A River.mp3","trackType":"mp3"} Apr 19 17:35:35 volumio volumio[9043]: verbose: CURRENT POSITION 23 Apr 19 17:35:35 volumio volumio[9043]: info: CoreStateMachine::syncState stateService pause Apr 19 17:35:35 volumio volumio[9043]: info: CoreStateMachine::syncState currentStatus pause Apr 19 17:35:35 volumio volumio[9043]: info: CoreStateMachine::pushState Apr 19 17:35:35 volumio volumio[9043]: info: CorePlayQueue::getTrack 23 Apr 19 17:35:35 volumio volumio[9043]: info: CoreCommandRouter::volumioPushState Apr 19 17:35:35 volumio volumio[9043]: info: CoreStateMachine::stPlaybackTimer Apr 19 17:35:35 volumio volumio[9043]: info: ------------------------------ 42ms Apr 19 17:35:35 volumio volumio[9043]: info: ------------------------------ 42ms Apr 19 17:35:35 volumio volumio[9043]: info: sendMpdCommand playlistinfo took 33 milliseconds Apr 19 17:35:35 volumio volumio[9043]: verbose: ControllerMpd::parseTrackInfo Apr 19 17:35:35 volumio volumio[9043]: info: ControllerMpd::pushState Apr 19 17:35:35 volumio volumio[9043]: info: CoreCommandRouter::servicePushState Apr 19 17:35:35 volumio volumio[9043]: info: CorePlayQueue::getTrack 23 Apr 19 17:35:35 volumio volumio[9043]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":259302,"duration":429,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"I Am A River","artist":"Foo Fighters","album":"Sonic Highways","uri":"USB/Elements/Musiques/Rock/Foo Fighters/Foo Fighters - Sonic Highways/08 - I Am A River.mp3","trackType":"mp3"} Apr 19 17:35:35 volumio volumio[9043]: verbose: CURRENT POSITION 23 Apr 19 17:35:35 volumio volumio[9043]: info: CoreStateMachine::syncState stateService pause Apr 19 17:35:35 volumio volumio[9043]: info: CoreStateMachine::syncState currentStatus pause Apr 19 17:35:35 volumio volumio[9043]: info: CoreStateMachine::pushState Apr 19 17:35:35 volumio volumio[9043]: info: CorePlayQueue::getTrack 23 Apr 19 17:35:35 volumio volumio[9043]: info: CoreCommandRouter::volumioPushState Apr 19 17:35:35 volumio volumio[9043]: info: CoreStateMachine::stPlaybackTimer Apr 19 17:35:35 volumio volumio[9043]: info: ------------------------------ 62ms Apr 19 17:35:35 volumio volumio[9043]: info: touch_display: Setting screensaver timeout to 15 seconds. Apr 19 17:35:35 volumio volumio[9043]: info: peppy_screensaver: Start PeppyMeter Apr 19 17:35:37 volumio volumio[9043]: info: Preload queue cleared Apr 19 17:35:37 volumio volumio[9043]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 19 17:35:37 volumio volumio[9043]: info: CoreStateMachine::ClearQueue Apr 19 17:35:37 volumio volumio[9043]: info: CoreStateMachine::stop Apr 19 17:35:37 volumio volumio[9043]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 17:35:37 volumio volumio[9043]: info: CoreStateMachine::updateTrackBlock Apr 19 17:35:37 volumio volumio[9043]: info: CorePlayQueue::getTrackBlock Apr 19 17:35:37 volumio volumio[9043]: info: CoreStateMachine::stPlaybackTimer Apr 19 17:35:37 volumio volumio[9043]: info: CoreStateMachine::pushState Apr 19 17:35:37 volumio volumio[9043]: info: CorePlayQueue::getTrack 23 Apr 19 17:35:37 volumio volumio[9043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 17:35:37 volumio volumio[9043]: info: CoreCommandRouter::volumioPushState Apr 19 17:35:37 volumio volumio[9043]: info: CoreStateMachine::serviceStop Apr 19 17:35:37 volumio volumio[9043]: info: CorePlayQueue::getTrack 23 Apr 19 17:35:37 volumio volumio[9043]: info: CoreCommandRouter::serviceStop Apr 19 17:35:37 volumio volumio[9043]: info: ControllerMpd::stop Apr 19 17:35:37 volumio volumio[9043]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 17:35:37 volumio volumio[9043]: info: CorePlayQueue::clearPlayQueue Apr 19 17:35:37 volumio volumio[9043]: info: CorePlayQueue::saveQueue Apr 19 17:35:37 volumio volumio[9043]: info: CoreCommandRouter::volumioPushQueue Apr 19 17:35:37 volumio volumio[9043]: info: CoreStateMachine::addQueueItems Apr 19 17:35:37 volumio volumio[9043]: info: CorePlayQueue::addQueueItems Apr 19 17:35:37 volumio volumio[9043]: info: Preload queue cleared Apr 19 17:35:37 volumio volumio[9043]: info: Adding Item to queue: podcast/1 Apr 19 17:35:37 volumio volumio[9043]: info: Exploding uri podcast/1 in service podcast Apr 19 17:35:37 volumio volumio[9043]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 17:35:37 volumio volumio[9043]: TypeError: Cannot read property 'url' of undefined Apr 19 17:35:37 volumio volumio[9043]: at ControllerPodcast.explodeUri (/data/plugins/music_service/podcast/index.js:659:22) Apr 19 17:35:37 volumio volumio[9043]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16) Apr 19 17:35:37 volumio volumio[9043]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Apr 19 17:35:37 volumio volumio[9043]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Apr 19 17:35:37 volumio volumio[9043]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Apr 19 17:35:37 volumio volumio[9043]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Apr 19 17:35:37 volumio volumio[9043]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:507:23) Apr 19 17:35:37 volumio volumio[9043]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:477:17) Apr 19 17:35:37 volumio volumio[9043]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:118:33) Apr 19 17:35:37 volumio volumio[9043]: at Socket.emit (events.js:315:20) Apr 19 17:35:37 volumio volumio[9043]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 19 17:35:37 volumio volumio[9043]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 19 17:35:37 volumio volumio[9043]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 17:35:38 volumio sudo[9937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-19 17:34 Apr 19 17:35:38 volumio sudo[9937]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"