-- Logs begin at Sat 2024-11-02 21:22:26 CET, end at Sat 2024-11-02 21:25:23 CET. -- Nov 02 21:24:02 volumioskh volumio[1155]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 21:24:02 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 02 21:24:02 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 02 21:24:02 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 02 21:24:02 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Nov 02 21:24:02 volumioskh volumio[1155]: info: Received Get System Version Nov 02 21:24:02 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 21:24:10 volumioskh sshd[1970]: Accepted password for volumio from 192.168.1.77 port 46382 ssh2 Nov 02 21:24:10 volumioskh sshd[1970]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Nov 02 21:24:10 volumioskh systemd[1]: Created slice User Slice of UID 1000. Nov 02 21:24:10 volumioskh systemd[1]: Starting User Runtime Directory /run/user/1000... Nov 02 21:24:10 volumioskh systemd-logind[842]: New session 1 of user volumio. Nov 02 21:24:10 volumioskh systemd[1]: Started User Runtime Directory /run/user/1000. Nov 02 21:24:10 volumioskh systemd[1]: Starting User Manager for UID 1000... Nov 02 21:24:10 volumioskh systemd[1974]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Nov 02 21:24:10 volumioskh systemd[1974]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Nov 02 21:24:10 volumioskh systemd[1974]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Nov 02 21:24:10 volumioskh systemd[1974]: Listening on GnuPG cryptographic agent and passphrase cache. Nov 02 21:24:10 volumioskh systemd[1974]: Listening on Sound System. Nov 02 21:24:10 volumioskh systemd[1974]: Reached target Paths. Nov 02 21:24:10 volumioskh systemd[1974]: Reached target Timers. Nov 02 21:24:10 volumioskh systemd[1974]: Starting D-Bus User Message Bus Socket. Nov 02 21:24:10 volumioskh systemd[1974]: Listening on GnuPG network certificate management daemon. Nov 02 21:24:10 volumioskh systemd[1974]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Nov 02 21:24:10 volumioskh systemd[1974]: Listening on D-Bus User Message Bus Socket. Nov 02 21:24:10 volumioskh systemd[1974]: Reached target Sockets. Nov 02 21:24:10 volumioskh systemd[1974]: Reached target Basic System. Nov 02 21:24:10 volumioskh systemd[1]: Started User Manager for UID 1000. Nov 02 21:24:10 volumioskh systemd[1974]: Starting Music Player Daemon... Nov 02 21:24:10 volumioskh systemd[1]: Started Session 1 of user volumio. Nov 02 21:24:11 volumioskh sshd[1993]: error: Failed to allocate internet-domain X11 display socket. Nov 02 21:24:11 volumioskh mpd[1985]: exception: failed to open log file "/var/log/mpd.log" (config line 7): Permission denied Nov 02 21:24:11 volumioskh systemd[1974]: mpd.service: Main process exited, code=exited, status=1/FAILURE Nov 02 21:24:11 volumioskh systemd[1974]: mpd.service: Failed with result 'exit-code'. Nov 02 21:24:11 volumioskh systemd[1974]: Failed to start Music Player Daemon. Nov 02 21:24:11 volumioskh systemd[1974]: Reached target Default. Nov 02 21:24:11 volumioskh systemd[1974]: Startup finished in 334ms. Nov 02 21:24:18 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 02 21:24:18 volumioskh volumio[1155]: info: Received Get System Info Nov 02 21:24:18 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 21:24:18 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 21:24:18 volumioskh volumio[1155]: info: Discovery: Getting this device information Nov 02 21:24:18 volumioskh volumio[1155]: info: CoreCommandRouter::volumioGetState Nov 02 21:24:18 volumioskh volumio[1155]: info: CorePlayQueue::getTrack 0 Nov 02 21:24:18 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 21:24:33 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 21:24:33 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 21:24:33 volumioskh volumio[1155]: info: Discovery: Getting this device information Nov 02 21:24:33 volumioskh volumio[1155]: info: CoreCommandRouter::volumioGetState Nov 02 21:24:33 volumioskh volumio[1155]: info: CorePlayQueue::getTrack 0 Nov 02 21:24:33 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 21:24:35 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 21:24:35 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 21:24:35 volumioskh volumio[1155]: info: Discovery: Getting this device information Nov 02 21:24:35 volumioskh volumio[1155]: info: CoreCommandRouter::volumioGetState Nov 02 21:24:35 volumioskh volumio[1155]: info: CorePlayQueue::getTrack 0 Nov 02 21:24:35 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 21:24:35 volumioskh volumio[1155]: verbose: New Socket.io Connection to 192.168.1.14:3000 from 192.168.1.15 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Nov 02 21:24:41 volumioskh volumio[1155]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.15 UA: Mozilla/5.0 (Linux; Android 14; SM-S901B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Nov 02 21:24:41 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 21:24:41 volumioskh volumio[1155]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 21:24:41 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 21:24:41 volumioskh volumio[1155]: info: CoreCommandRouter::volumioGetState Nov 02 21:24:41 volumioskh volumio[1155]: info: CorePlayQueue::getTrack 0 Nov 02 21:24:41 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 21:24:41 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 21:24:41 volumioskh volumio[1155]: info: Received Get System Info Nov 02 21:24:41 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 21:24:41 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 21:24:41 volumioskh volumio[1155]: info: Discovery: Getting this device information Nov 02 21:24:41 volumioskh volumio[1155]: info: CoreCommandRouter::volumioGetState Nov 02 21:24:41 volumioskh volumio[1155]: info: CorePlayQueue::getTrack 0 Nov 02 21:24:41 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 21:24:41 volumioskh volumio[1155]: info: CoreCommandRouter::volumioGetState Nov 02 21:24:41 volumioskh volumio[1155]: info: CorePlayQueue::getTrack 0 Nov 02 21:24:41 volumioskh volumio[1155]: info: Listing playlists Nov 02 21:24:41 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 02 21:24:54 volumioskh volumio[1155]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Nov 02 21:25:06 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 21:25:06 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 21:25:06 volumioskh volumio[1155]: info: Discovery: Getting this device information Nov 02 21:25:06 volumioskh volumio[1155]: info: CoreCommandRouter::volumioGetState Nov 02 21:25:06 volumioskh volumio[1155]: info: CorePlayQueue::getTrack 0 Nov 02 21:25:06 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 21:25:10 volumioskh volumio[1155]: verbose: New Socket.io Connection to 192.168.1.14:3000 from 192.168.1.15 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Nov 02 21:25:14 volumioskh sudo[2075]: volumio : TTY=pts/0 ; PWD=/data/INTERNAL/peppy_screensaver_2.2.1/peppy_screensaver ; USER=root ; COMMAND=/usr/local/bin/volumio plugin install Nov 02 21:25:14 volumioskh sudo[2075]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Nov 02 21:25:18 volumioskh volumio[1155]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.15 UA: Mozilla/5.0 (Linux; Android 14; SM-S901B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Nov 02 21:25:18 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 21:25:19 volumioskh volumio[1155]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 21:25:19 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 21:25:19 volumioskh volumio[1155]: info: CoreCommandRouter::volumioGetState Nov 02 21:25:19 volumioskh volumio[1155]: info: CorePlayQueue::getTrack 0 Nov 02 21:25:19 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 21:25:19 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 21:25:19 volumioskh volumio[1155]: info: Received Get System Info Nov 02 21:25:19 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 21:25:19 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 21:25:19 volumioskh volumio[1155]: info: Discovery: Getting this device information Nov 02 21:25:19 volumioskh volumio[1155]: info: CoreCommandRouter::volumioGetState Nov 02 21:25:19 volumioskh volumio[1155]: info: CorePlayQueue::getTrack 0 Nov 02 21:25:19 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 21:25:19 volumioskh volumio[1155]: info: CoreCommandRouter::volumioGetState Nov 02 21:25:19 volumioskh volumio[1155]: info: CorePlayQueue::getTrack 0 Nov 02 21:25:19 volumioskh volumio[1155]: info: Listing playlists Nov 02 21:25:19 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 02 21:25:22 volumioskh volumio[1155]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Nov 02 21:25:22 volumioskh volumio[1155]: info: Downloading plugin at http://127.0.0.1:3000/plugin-serve/peppy_screensaver.zip Nov 02 21:25:22 volumioskh volumio[1155]: info: END DOWNLOAD: http://127.0.0.1:3000/plugin-serve/peppy_screensaver.zip Nov 02 21:25:22 volumioskh volumio[1155]: info: Folder /tmp/plugins removed Nov 02 21:25:22 volumioskh volumio[1155]: info: Check plugin dependencies Nov 02 21:25:22 volumioskh volumio[1155]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 21:25:22 volumioskh volumio[1155]: info: Checking if plugin already exists Nov 02 21:25:22 volumioskh volumio[1155]: info: Rename folder Nov 02 21:25:22 volumioskh volumio[1155]: info: Move to category Nov 02 21:25:22 volumioskh volumio[1155]: info: Folder /tmp/downloaded_plugin.zip removed Nov 02 21:25:22 volumioskh volumio[1155]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 21:25:22 volumioskh volumio[1155]: Error: Unable to resolve or reject the same promise twice Nov 02 21:25:22 volumioskh volumio[1155]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Nov 02 21:25:22 volumioskh volumio[1155]: at /volumio/app/pluginmanager.js:1087:11 Nov 02 21:25:22 volumioskh volumio[1155]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Nov 02 21:25:22 volumioskh volumio[1155]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:201:20 Nov 02 21:25:22 volumioskh volumio[1155]: at go$readdir$cb (/volumio/node_modules/graceful-fs/graceful-fs.js:195:14) Nov 02 21:25:22 volumioskh volumio[1155]: at FSReqCallback.oncomplete (fs.js:171:23) Nov 02 21:25:22 volumioskh volumio[1155]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 21:25:23 volumioskh sudo[2110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-02 21:24 Nov 02 21:25:23 volumioskh sudo[2110]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"