-- Logs begin at Wed 2024-08-28 17:51:18 UTC, end at Thu 2024-08-29 15:21:49 UTC. -- Aug 29 15:20:04 volumio-2 volumio[23382]: info: CoreCommandRouter::volumioGetState Aug 29 15:20:04 volumio-2 volumio[23382]: info: Listing playlists Aug 29 15:20:04 volumio-2 volumio[23382]: info: Listing playlists Aug 29 15:20:15 volumio-2 volumio[23382]: info: CoreCommandRouter::volumioGetState Aug 29 15:20:24 volumio-2 volumio[23382]: info: CoreCommandRouter::volumioGetState Aug 29 15:20:24 volumio-2 volumio[23382]: info: Listing playlists Aug 29 15:20:24 volumio-2 volumio[23382]: info: Listing playlists Aug 29 15:20:34 volumio-2 volumio[23382]: info: CoreCommandRouter::volumioGetState Aug 29 15:20:44 volumio-2 volumio[23382]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 15:20:44 volumio-2 volumio[23382]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 29 15:20:44 volumio-2 volumio[23382]: info: CoreCommandRouter::volumioGetState Aug 29 15:20:45 volumio-2 volumio[23382]: info: Listing playlists Aug 29 15:20:45 volumio-2 volumio[23382]: info: Listing playlists Aug 29 15:20:54 volumio-2 volumio[23382]: info: CoreCommandRouter::volumioGetState Aug 29 15:20:55 volumio-2 volumio[23382]: info: Starting Uninstall of plugin music_service - squeezelite_mc Aug 29 15:20:55 volumio-2 volumio[23382]: info: Uninstalling plugin squeezelite_mc Aug 29 15:20:55 volumio-2 volumio[23382]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Aug 29 15:20:55 volumio-2 volumio[23382]: info: [squeezelite_mc] Proxy server stopped Aug 29 15:20:55 volumio-2 sudo[596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Aug 29 15:20:55 volumio-2 sudo[596]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:20:55 volumio-2 systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... Aug 29 15:20:55 volumio-2 systemd[1]: squeezelite.service: Succeeded. Aug 29 15:20:55 volumio-2 systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Aug 29 15:20:55 volumio-2 sudo[596]: pam_unix(sudo:session): session closed for user root Aug 29 15:20:56 volumio-2 volumio[23382]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Aug 29 15:20:56 volumio-2 sudo[602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Aug 29 15:20:56 volumio-2 sudo[602]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:20:56 volumio-2 sudo[602]: pam_unix(sudo:session): session closed for user root Aug 29 15:20:56 volumio-2 volumio[23382]: info: Disabling plugin squeezelite_mc Aug 29 15:20:56 volumio-2 volumio[23382]: info: Checking if uninstall.sh is present Aug 29 15:20:56 volumio-2 volumio[23382]: info: Executing uninstall.sh Aug 29 15:20:56 volumio-2 sudo[609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/squeezelite_mc/uninstall.sh Aug 29 15:20:56 volumio-2 sudo[609]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:20:56 volumio-2 systemd[1]: Reloading. Aug 29 15:20:56 volumio-2 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 15:20:57 volumio-2 sudo[609]: pam_unix(sudo:session): session closed for user root Aug 29 15:20:57 volumio-2 volumio[23382]: info: Uninstall script completed Aug 29 15:20:57 volumio-2 volumio[23382]: info: Removing plugin squeezelite_mc from configuration Aug 29 15:20:57 volumio-2 volumio[23382]: info: Successfully removed squeezelite_mc configuration files Aug 29 15:20:57 volumio-2 volumio[23382]: info: Plugin folders cleanup Aug 29 15:20:57 volumio-2 volumio[23382]: info: Scanning into folder /volumio/app/plugins/ Aug 29 15:20:57 volumio-2 volumio[23382]: info: Scanning category audio_interface Aug 29 15:20:57 volumio-2 volumio[23382]: info: Scanning category miscellanea Aug 29 15:20:57 volumio-2 volumio[23382]: info: Scanning category music_service Aug 29 15:20:57 volumio-2 volumio[23382]: info: Scanning category plugins.json Aug 29 15:20:57 volumio-2 volumio[23382]: info: Scanning category system_controller Aug 29 15:20:57 volumio-2 volumio[23382]: info: Scanning category user_interface Aug 29 15:20:57 volumio-2 volumio[23382]: info: Scanning into folder /data/plugins/ Aug 29 15:20:57 volumio-2 volumio[23382]: info: Scanning category music_service Aug 29 15:20:57 volumio-2 volumio[23382]: info: Cleaning folder for squeezelite_mc Aug 29 15:20:57 volumio-2 volumio[23382]: info: Scanning category user_interface Aug 29 15:20:57 volumio-2 volumio[23382]: info: Plugin folders cleanup completed Aug 29 15:20:57 volumio-2 volumio[23382]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 15:20:57 volumio-2 volumio[23382]: info: [{"prettyName":"80s80s Radio","name":"80s80s","category":"music_service","version":"1.0.2","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Radio Paradise","name":"radio_paradise","category":"music_service","version":"1.0.3","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Now Playing","name":"now_playing","category":"user_interface","version":"0.3.8","icon":"fa-play-circle","isManuallyInstalled":false,"enabled":true,"active":true}] Aug 29 15:20:57 volumio-2 volumio[23382]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 29 15:21:04 volumio-2 volumio[23382]: info: Starting Uninstall of plugin user_interface - now_playing Aug 29 15:21:04 volumio-2 volumio[23382]: info: Uninstalling plugin now_playing Aug 29 15:21:04 volumio-2 volumio[23382]: info: Disabling plugin now_playing Aug 29 15:21:04 volumio-2 volumio[23382]: info: Checking if uninstall.sh is present Aug 29 15:21:04 volumio-2 volumio[23382]: info: Executing uninstall.sh Aug 29 15:21:04 volumio-2 sudo[692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/now_playing/uninstall.sh Aug 29 15:21:04 volumio-2 sudo[692]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:21:04 volumio-2 sudo[692]: pam_unix(sudo:session): session closed for user root Aug 29 15:21:04 volumio-2 volumio[23382]: info: Uninstall script completed Aug 29 15:21:04 volumio-2 volumio[23382]: info: Removing plugin now_playing from configuration Aug 29 15:21:05 volumio-2 volumio[23382]: info: Successfully removed now_playing configuration files Aug 29 15:21:05 volumio-2 volumio[23382]: info: Plugin folders cleanup Aug 29 15:21:05 volumio-2 volumio[23382]: info: Scanning into folder /volumio/app/plugins/ Aug 29 15:21:05 volumio-2 volumio[23382]: info: Scanning category audio_interface Aug 29 15:21:05 volumio-2 volumio[23382]: info: Scanning category miscellanea Aug 29 15:21:05 volumio-2 volumio[23382]: info: Scanning category music_service Aug 29 15:21:05 volumio-2 volumio[23382]: info: Scanning category plugins.json Aug 29 15:21:05 volumio-2 volumio[23382]: info: Scanning category system_controller Aug 29 15:21:05 volumio-2 volumio[23382]: info: Scanning category user_interface Aug 29 15:21:05 volumio-2 volumio[23382]: info: Scanning into folder /data/plugins/ Aug 29 15:21:05 volumio-2 volumio[23382]: info: Scanning category music_service Aug 29 15:21:05 volumio-2 volumio[23382]: info: Scanning category user_interface Aug 29 15:21:05 volumio-2 volumio[23382]: info: Cleaning folder for now_playing Aug 29 15:21:07 volumio-2 volumio[23382]: info: Plugin folders cleanup completed Aug 29 15:21:07 volumio-2 volumio[23382]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 15:21:07 volumio-2 volumio[23382]: info: [{"prettyName":"80s80s Radio","name":"80s80s","category":"music_service","version":"1.0.2","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Radio Paradise","name":"radio_paradise","category":"music_service","version":"1.0.3","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true}] Aug 29 15:21:07 volumio-2 volumio[23382]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 29 15:21:07 volumio-2 volumio[23382]: info: CoreCommandRouter::volumioGetState Aug 29 15:21:07 volumio-2 volumio[23382]: info: Listing playlists Aug 29 15:21:07 volumio-2 volumio[23382]: info: Listing playlists Aug 29 15:21:14 volumio-2 volumio[23382]: info: CoreCommandRouter::volumioGetState Aug 29 15:21:18 volumio-2 volumio[23382]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 29 15:21:18 volumio-2 volumio[23382]: info: Preload queue cleared Aug 29 15:21:19 volumio-2 volumio[23382]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 29 15:21:19 volumio-2 volumio[23382]: info: Preload queue cleared Aug 29 15:21:21 volumio-2 volumio[23382]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 29 15:21:21 volumio-2 volumio[23382]: info: Preload queue cleared Aug 29 15:21:22 volumio-2 volumio[23382]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 29 15:21:22 volumio-2 volumio[23382]: info: Preload queue cleared Aug 29 15:21:23 volumio-2 volumio[23382]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 29 15:21:23 volumio-2 volumio[23382]: info: Preload queue cleared Aug 29 15:21:24 volumio-2 volumio[23382]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 29 15:21:24 volumio-2 volumio[23382]: info: CoreCommandRouter::volumioGetState Aug 29 15:21:25 volumio-2 volumio[23382]: info: Listing playlists Aug 29 15:21:25 volumio-2 volumio[23382]: info: Listing playlists Aug 29 15:21:25 volumio-2 volumio[23382]: info: Preload queue cleared Aug 29 15:21:34 volumio-2 volumio[23382]: info: CoreCommandRouter::volumioGetState Aug 29 15:21:38 volumio-2 volumio[23382]: info: CorePlayQueue::getTrack 3 Aug 29 15:21:38 volumio-2 volumio[23382]: info: CorePlayQueue::getTrack 4 Aug 29 15:21:38 volumio-2 volumio[23382]: info: Prefetching next song Aug 29 15:21:38 volumio-2 volumio[23382]: info: Doing Prefetch in UPNP Aug 29 15:21:38 volumio-2 volumio[23382]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.10.20:50002/m/MP3/2946902.mp3" Aug 29 15:21:38 volumio-2 volumio[23382]: error: updateQueue error: null Aug 29 15:21:38 volumio-2 volumio[23382]: info: Aug 29 15:21:38 volumio-2 volumio[23382]: ---------------------------- MPD announces system playlist update Aug 29 15:21:38 volumio-2 volumio[23382]: info: Ignoring MPD Status Update Aug 29 15:21:38 volumio-2 volumio[23382]: info: ------------------------------ 148752ms Aug 29 15:21:38 volumio-2 volumio[23382]: info: Aug 29 15:21:38 volumio-2 volumio[23382]: ---------------------------- MPD announces system playlist update Aug 29 15:21:38 volumio-2 volumio[23382]: info: Ignoring MPD Status Update Aug 29 15:21:38 volumio-2 volumio[23382]: info: Aug 29 15:21:38 volumio-2 volumio[23382]: ---------------------------- MPD announces system playlist update Aug 29 15:21:38 volumio-2 volumio[23382]: info: Ignoring MPD Status Update Aug 29 15:21:38 volumio-2 volumio[23382]: info: sendMpdCommand consume 1 took 148753 milliseconds Aug 29 15:21:38 volumio-2 volumio[23382]: info: sendMpdCommand add "http://192.168.10.20:50002/m/MP3/2946902.mp3" took 8 milliseconds Aug 29 15:21:38 volumio-2 volumio[23382]: info: ------------------------------ 6ms Aug 29 15:21:38 volumio-2 volumio[23382]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 29 15:21:38 volumio-2 volumio[23382]: error: updateQueue error: null Aug 29 15:21:38 volumio-2 volumio[23382]: info: ------------------------------ 6ms Aug 29 15:21:43 volumio-2 volumio[23382]: info: CoreStateMachine::startPlaybackTimer Aug 29 15:21:43 volumio-2 volumio[23382]: info: CorePlayQueue::getTrack 4 Aug 29 15:21:43 volumio-2 volumio[23382]: info: CoreStateMachine::pushState Aug 29 15:21:43 volumio-2 volumio[23382]: info: CoreCommandRouter::volumioPushState Aug 29 15:21:44 volumio-2 volumio[23382]: info: CoreCommandRouter::volumioGetState Aug 29 15:21:45 volumio-2 volumio[23382]: info: Listing playlists Aug 29 15:21:45 volumio-2 volumio[23382]: info: Listing playlists Aug 29 15:21:49 volumio-2 volumio[23382]: info: CoreCommandRouter::volumioPlay Aug 29 15:21:49 volumio-2 volumio[23382]: verbose: UNSET VOLATILE: Service: mpd Aug 29 15:21:49 volumio-2 volumio[23382]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 15:21:49 volumio-2 volumio[23382]: TypeError: Cannot read property 'logger' of null Aug 29 15:21:49 volumio-2 volumio[23382]: at SqueezeliteMCContext.getLogger (/data/plugins/music_service/squeezelite_mc/dist/lib/SqueezeliteMCContext.js:64:86) Aug 29 15:21:49 volumio-2 volumio[23382]: at ControllerSqueezeliteMC._ControllerSqueezeliteMC_pushEmptyState (/data/plugins/music_service/squeezelite_mc/dist/index.js:952:36) Aug 29 15:21:49 volumio-2 volumio[23382]: at ControllerSqueezeliteMC.onUnsetVolatile (/data/plugins/music_service/squeezelite_mc/dist/index.js:261:120) Aug 29 15:21:49 volumio-2 volumio[23382]: at CoreStateMachine.unSetVolatile (/volumio/app/statemachine.js:1548:27) Aug 29 15:21:49 volumio-2 volumio[23382]: at CoreCommandRouter.volumioPlay (/volumio/app/index.js:1387:21) Aug 29 15:21:49 volumio-2 volumio[23382]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:241:35) Aug 29 15:21:49 volumio-2 volumio[23382]: at Socket.emit (events.js:315:20) Aug 29 15:21:49 volumio-2 volumio[23382]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 29 15:21:49 volumio-2 volumio[23382]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 29 15:21:49 volumio-2 volumio[23382]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 15:21:49 volumio-2 sudo[769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 15:20 Aug 29 15:21:49 volumio-2 sudo[769]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"