-- Logs begin at Thu 2024-10-31 14:13:02 CET, end at Thu 2024-10-31 14:25:38 CET. -- Oct 31 14:24:01 wharfedale volumio[1006]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 31 14:24:08 wharfedale go-librespot[1215]: time="2024-10-31T14:24:08+01:00" level=debug msg="fetched chunk 6/37, size: 524288" uri="spotify:track:47H09jVxrZLalZBv0MnD0R" Oct 31 14:24:09 wharfedale volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 31 14:24:09 wharfedale volumio[1006]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 31 14:24:09 wharfedale volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 31 14:24:09 wharfedale volumio[1006]: info: Received Get System Version Oct 31 14:24:09 wharfedale volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 31 14:24:09 wharfedale volumio[1006]: info: Received Get System Info Oct 31 14:24:09 wharfedale volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 31 14:24:09 wharfedale volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 31 14:24:09 wharfedale volumio[1006]: info: Discovery: Getting this device information Oct 31 14:24:09 wharfedale volumio[1006]: info: CoreCommandRouter::volumioGetState Oct 31 14:24:09 wharfedale volumio[1006]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 31 14:24:22 wharfedale go-librespot[1215]: time="2024-10-31T14:24:22+01:00" level=debug msg="fetched chunk 7/37, size: 524288" uri="spotify:track:47H09jVxrZLalZBv0MnD0R" Oct 31 14:24:33 wharfedale sshd[1979]: Accepted password for volumio from 192.168.1.107 port 56846 ssh2 Oct 31 14:24:33 wharfedale sshd[1979]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Oct 31 14:24:33 wharfedale systemd[1]: Created slice User Slice of UID 1000. Oct 31 14:24:33 wharfedale systemd[1]: Starting User Runtime Directory /run/user/1000... Oct 31 14:24:33 wharfedale systemd-logind[579]: New session 1 of user volumio. Oct 31 14:24:33 wharfedale systemd[1]: Started User Runtime Directory /run/user/1000. Oct 31 14:24:33 wharfedale systemd[1]: Starting User Manager for UID 1000... Oct 31 14:24:33 wharfedale systemd[2021]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Oct 31 14:24:34 wharfedale ntpd[769]: 162.159.200.123 local addr 192.168.1.106 -> Oct 31 14:24:34 wharfedale systemd[2021]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Oct 31 14:24:34 wharfedale systemd[2021]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Oct 31 14:24:34 wharfedale systemd[2021]: Starting D-Bus User Message Bus Socket. Oct 31 14:24:34 wharfedale systemd[2021]: Listening on Sound System. Oct 31 14:24:34 wharfedale systemd[2021]: Reached target Paths. Oct 31 14:24:34 wharfedale systemd[2021]: Listening on GnuPG cryptographic agent and passphrase cache. Oct 31 14:24:34 wharfedale systemd[2021]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Oct 31 14:24:34 wharfedale systemd[2021]: Listening on GnuPG network certificate management daemon. Oct 31 14:24:34 wharfedale systemd[2021]: Reached target Timers. Oct 31 14:24:34 wharfedale systemd[2021]: Listening on D-Bus User Message Bus Socket. Oct 31 14:24:34 wharfedale systemd[2021]: Reached target Sockets. Oct 31 14:24:34 wharfedale systemd[2021]: Reached target Basic System. Oct 31 14:24:34 wharfedale systemd[1]: Started User Manager for UID 1000. Oct 31 14:24:34 wharfedale systemd[2021]: Starting Music Player Daemon... Oct 31 14:24:34 wharfedale systemd[1]: Started Session 1 of user volumio. Oct 31 14:24:35 wharfedale mpd[2033]: exception: failed to open log file "/var/log/mpd.log" (config line 7): Permission denied Oct 31 14:24:35 wharfedale systemd[2021]: mpd.service: Main process exited, code=exited, status=1/FAILURE Oct 31 14:24:35 wharfedale systemd[2021]: mpd.service: Failed with result 'exit-code'. Oct 31 14:24:35 wharfedale systemd[2021]: Failed to start Music Player Daemon. Oct 31 14:24:35 wharfedale systemd[2021]: Reached target Default. Oct 31 14:24:35 wharfedale systemd[2021]: Startup finished in 1.483s. Oct 31 14:24:38 wharfedale go-librespot[1215]: time="2024-10-31T14:24:38+01:00" level=debug msg="fetched chunk 8/37, size: 524288" uri="spotify:track:47H09jVxrZLalZBv0MnD0R" Oct 31 14:24:49 wharfedale go-librespot[1215]: time="2024-10-31T14:24:49+01:00" level=debug msg="fetched chunk 9/37, size: 524288" uri="spotify:track:47H09jVxrZLalZBv0MnD0R" Oct 31 14:25:01 wharfedale go-librespot[1215]: time="2024-10-31T14:25:01+01:00" level=debug msg="fetched chunk 10/37, size: 524288" uri="spotify:track:47H09jVxrZLalZBv0MnD0R" Oct 31 14:25:14 wharfedale go-librespot[1215]: time="2024-10-31T14:25:14+01:00" level=debug msg="fetched chunk 11/37, size: 524288" uri="spotify:track:47H09jVxrZLalZBv0MnD0R" Oct 31 14:25:22 wharfedale volumio[1006]: info: Tunnel connection is inactive, restarting it Oct 31 14:25:22 wharfedale volumio[1006]: info: Starting Tunnel 1 Oct 31 14:25:22 wharfedale volumio[1006]: info: Starting Tunnel Connection Checker Oct 31 14:25:22 wharfedale sudo[2055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 31 14:25:22 wharfedale sudo[2055]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 14:25:22 wharfedale autossh[1831]: received signal to exit (15) Oct 31 14:25:22 wharfedale systemd[1]: Stopping MyVolumio SSH Tunnel... Oct 31 14:25:22 wharfedale systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Oct 31 14:25:22 wharfedale systemd[1]: sshtunnel.service: Succeeded. Oct 31 14:25:22 wharfedale systemd[1]: Stopped MyVolumio SSH Tunnel. Oct 31 14:25:22 wharfedale systemd[1]: Started MyVolumio SSH Tunnel. Oct 31 14:25:22 wharfedale sudo[2055]: pam_unix(sudo:session): session closed for user root Oct 31 14:25:22 wharfedale volumio[1006]: info: Remote SSH Started Oct 31 14:25:22 wharfedale autossh[2059]: port set to 0, monitoring disabled Oct 31 14:25:22 wharfedale autossh[2059]: starting ssh (count 1) Oct 31 14:25:22 wharfedale autossh[2059]: ssh child pid is 2062 Oct 31 14:25:22 wharfedale volumio[1006]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 31 14:25:22 wharfedale volumio[1006]: info: CoreCommandRouter::volumioGetState Oct 31 14:25:26 wharfedale go-librespot[1215]: time="2024-10-31T14:25:26+01:00" level=debug msg="fetched chunk 12/37, size: 524288" uri="spotify:track:47H09jVxrZLalZBv0MnD0R" Oct 31 14:25:30 wharfedale volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 31 14:25:30 wharfedale volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 31 14:25:32 wharfedale volumio[1006]: info: [ASDebug] Port: 4 Oct 31 14:25:32 wharfedale volumio[1006]: info: [ASDebug] Inverted: false Oct 31 14:25:32 wharfedale volumio[1006]: info: [ASDebug] Delay: 600000 Oct 31 14:25:38 wharfedale volumio[1006]: info: Starting Uninstall of plugin system_controller - ampswitch Oct 31 14:25:38 wharfedale volumio[1006]: info: Uninstalling plugin ampswitch Oct 31 14:25:38 wharfedale volumio[1006]: info: [ASDebug] Port: 4 Oct 31 14:25:38 wharfedale volumio[1006]: info: [ASDebug] Inverted: false Oct 31 14:25:38 wharfedale volumio[1006]: info: [ASDebug] Delay: 600000 Oct 31 14:25:38 wharfedale volumio[1006]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 31 14:25:38 wharfedale volumio[1006]: Error: EBADF: bad file descriptor, close Oct 31 14:25:38 wharfedale volumio[1006]: at Object.closeSync (fs.js:439:3) Oct 31 14:25:38 wharfedale volumio[1006]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Oct 31 14:25:38 wharfedale volumio[1006]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Oct 31 14:25:38 wharfedale volumio[1006]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:234:19) Oct 31 14:25:38 wharfedale volumio[1006]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:84:10) Oct 31 14:25:38 wharfedale volumio[1006]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Oct 31 14:25:38 wharfedale volumio[1006]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Oct 31 14:25:38 wharfedale volumio[1006]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1465:22) Oct 31 14:25:38 wharfedale volumio[1006]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1246:45) Oct 31 14:25:38 wharfedale volumio[1006]: at Socket.emit (events.js:315:20) Oct 31 14:25:38 wharfedale volumio[1006]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 31 14:25:38 wharfedale volumio[1006]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Oct 31 14:25:38 wharfedale volumio[1006]: errno: -9, Oct 31 14:25:38 wharfedale volumio[1006]: syscall: 'close', Oct 31 14:25:38 wharfedale volumio[1006]: code: 'EBADF' Oct 31 14:25:38 wharfedale volumio[1006]: } Oct 31 14:25:38 wharfedale volumio[1006]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 31 14:25:38 wharfedale sudo[2111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-31 14:24 Oct 31 14:25:38 wharfedale sudo[2111]: 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"