-- Logs begin at Mon 2024-07-22 13:33:46 EDT, end at Mon 2024-07-22 13:36:25 EDT. -- Jul 22 13:35:00 rptap sshd[1620]: Accepted password for volumio from 192.168.1.68 port 54924 ssh2 Jul 22 13:35:00 rptap sshd[1620]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Jul 22 13:35:00 rptap systemd-logind[879]: New session 1 of user volumio. Jul 22 13:35:00 rptap systemd[1]: Created slice User Slice of UID 1000. Jul 22 13:35:00 rptap systemd[1]: Starting User Runtime Directory /run/user/1000... Jul 22 13:35:00 rptap systemd[1]: Started User Runtime Directory /run/user/1000. Jul 22 13:35:00 rptap systemd[1]: Starting User Manager for UID 1000... Jul 22 13:35:00 rptap systemd[1624]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Jul 22 13:35:00 rptap systemd[1624]: Reached target Paths. Jul 22 13:35:00 rptap systemd[1624]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Jul 22 13:35:00 rptap systemd[1624]: Listening on GnuPG cryptographic agent and passphrase cache. Jul 22 13:35:00 rptap systemd[1624]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Jul 22 13:35:00 rptap systemd[1624]: Starting D-Bus User Message Bus Socket. Jul 22 13:35:00 rptap systemd[1624]: Listening on GnuPG network certificate management daemon. Jul 22 13:35:00 rptap systemd[1624]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Jul 22 13:35:00 rptap systemd[1624]: Listening on Sound System. Jul 22 13:35:00 rptap systemd[1624]: Reached target Timers. Jul 22 13:35:00 rptap systemd[1624]: Listening on D-Bus User Message Bus Socket. Jul 22 13:35:00 rptap systemd[1624]: Reached target Sockets. Jul 22 13:35:00 rptap systemd[1624]: Reached target Basic System. Jul 22 13:35:00 rptap systemd[1]: Started User Manager for UID 1000. Jul 22 13:35:00 rptap systemd[1624]: Starting Music Player Daemon... Jul 22 13:35:00 rptap systemd[1]: Started Session 1 of user volumio. Jul 22 13:35:00 rptap mpd[1635]: exception: failed to open log file "/var/log/mpd.log" (config line 7): Permission denied Jul 22 13:35:00 rptap systemd[1624]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jul 22 13:35:00 rptap systemd[1624]: mpd.service: Failed with result 'exit-code'. Jul 22 13:35:00 rptap systemd[1624]: Failed to start Music Player Daemon. Jul 22 13:35:00 rptap systemd[1624]: Reached target Default. Jul 22 13:35:00 rptap systemd[1624]: Startup finished in 351ms. Jul 22 13:35:00 rptap ntpd[1077]: Soliciting pool server 71.162.136.44 Jul 22 13:35:01 rptap ntpd[1077]: Soliciting pool server 2a05:dfc1:cd4::123 Jul 22 13:35:01 rptap ntpd[1077]: receive: Unexpected origin timestamp 0xea4916c5.ed171bbd does not match aorg 0000000000.00000000 from server@171.66.97.126 xmt 0xea4916c5.b1b932e3 Jul 22 13:35:01 rptap ntpd[1077]: receive: Unexpected origin timestamp 0xea4916c5.ed19a199 does not match aorg 0000000000.00000000 from server@155.248.196.28 xmt 0xea4916c5.b2ba469e Jul 22 13:35:01 rptap ntpd[1077]: receive: Unexpected origin timestamp 0xea4916c5.ed167459 does not match aorg 0000000000.00000000 from server@199.68.201.237 xmt 0xea4916c5.b13d3495 Jul 22 13:35:01 rptap ntpd[1077]: receive: Unexpected origin timestamp 0xea4916c5.ed148bb7 does not match aorg 0000000000.00000000 from server@73.193.62.54 xmt 0xea4916c5.b36626fc Jul 22 13:35:46 rptap sudo[1648]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/apt-get -y install xserver-xorg-input-evdev Jul 22 13:35:46 rptap sudo[1648]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Jul 22 13:35:50 rptap sudo[1648]: pam_unix(sudo:session): session closed for user root Jul 22 13:36:04 rptap volumio[1115]: info: CoreCommandRouter::volumioGetState Jul 22 13:36:04 rptap volumio[1115]: info: CorePlayQueue::getTrack 0 Jul 22 13:36:05 rptap sudo[1730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 22 13:36:05 rptap sudo[1730]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 13:36:05 rptap sudo[1730]: pam_unix(sudo:session): session closed for user root Jul 22 13:36:05 rptap sudo[1732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 22 13:36:05 rptap sudo[1732]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 13:36:05 rptap sudo[1732]: pam_unix(sudo:session): session closed for user root Jul 22 13:36:05 rptap volumio[1115]: verbose: New Socket.io Connection to 192.168.1.112 from 192.168.1.68 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jul 22 13:36:05 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 22 13:36:05 rptap volumio[1115]: info: CoreCommandRouter::volumioGetVisibleSources Jul 22 13:36:05 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 22 13:36:05 rptap volumio[1115]: info: CoreCommandRouter::volumioGetState Jul 22 13:36:05 rptap volumio[1115]: info: CorePlayQueue::getTrack 0 Jul 22 13:36:05 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 22 13:36:05 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 22 13:36:05 rptap volumio[1115]: info: Received Get System Info Jul 22 13:36:05 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 22 13:36:05 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 22 13:36:05 rptap volumio[1115]: info: Discovery: Getting this device information Jul 22 13:36:05 rptap volumio[1115]: info: CoreCommandRouter::volumioGetState Jul 22 13:36:05 rptap volumio[1115]: info: CorePlayQueue::getTrack 0 Jul 22 13:36:05 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 22 13:36:05 rptap volumio[1115]: info: CoreCommandRouter::volumioGetState Jul 22 13:36:05 rptap volumio[1115]: info: CorePlayQueue::getTrack 0 Jul 22 13:36:05 rptap volumio[1115]: info: Listing playlists Jul 22 13:36:05 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 22 13:36:05 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 22 13:36:05 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 22 13:36:06 rptap volumio[1115]: info: CoreCommandRouter::volumioGetState Jul 22 13:36:06 rptap volumio[1115]: info: CorePlayQueue::getTrack 0 Jul 22 13:36:06 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 22 13:36:07 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 22 13:36:07 rptap volumio[1115]: info: Received Get System Info Jul 22 13:36:07 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 22 13:36:07 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 22 13:36:07 rptap volumio[1115]: info: Discovery: Getting this device information Jul 22 13:36:07 rptap volumio[1115]: info: CoreCommandRouter::volumioGetState Jul 22 13:36:07 rptap volumio[1115]: info: CorePlayQueue::getTrack 0 Jul 22 13:36:07 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 22 13:36:08 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 22 13:36:08 rptap volumio[1115]: info: Received Get System Info Jul 22 13:36:08 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 22 13:36:08 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 22 13:36:08 rptap volumio[1115]: info: Discovery: Getting this device information Jul 22 13:36:08 rptap volumio[1115]: info: CoreCommandRouter::volumioGetState Jul 22 13:36:08 rptap volumio[1115]: info: CorePlayQueue::getTrack 0 Jul 22 13:36:08 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 22 13:36:15 rptap volumio[1115]: verbose: New Socket.io Connection to 192.168.1.112 from 192.168.1.68 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jul 22 13:36:15 rptap volumio[1115]: info: CoreCommandRouter::volumioGetState Jul 22 13:36:15 rptap volumio[1115]: info: CorePlayQueue::getTrack 0 Jul 22 13:36:15 rptap volumio[1115]: info: CoreCommandRouter::volumioGetQueue Jul 22 13:36:15 rptap volumio[1115]: info: CoreStateMachine::getQueue Jul 22 13:36:15 rptap volumio[1115]: info: CorePlayQueue::getQueue Jul 22 13:36:15 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jul 22 13:36:15 rptap volumio[1115]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Jul 22 13:36:15 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Jul 22 13:36:15 rptap volumio[1115]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Jul 22 13:36:15 rptap volumio[1115]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Jul 22 13:36:15 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Jul 22 13:36:16 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 22 13:36:17 rptap volumio[1115]: info: CALLMETHOD: system_controller system enableSSH true Jul 22 13:36:17 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , enableSSH Jul 22 13:36:17 rptap sudo[1736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ssh.service Jul 22 13:36:17 rptap sudo[1736]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 13:36:17 rptap sudo[1736]: pam_unix(sudo:session): session closed for user root Jul 22 13:36:17 rptap sudo[1738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable ssh.service Jul 22 13:36:17 rptap sudo[1738]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 13:36:17 rptap systemd[1]: Reloading. Jul 22 13:36:17 rptap 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. Jul 22 13:36:17 rptap systemd[1]: Reloading. Jul 22 13:36:17 rptap volumio[1115]: info: CALLMETHOD: system_controller system setTestPlugins true Jul 22 13:36:17 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , setTestPlugins Jul 22 13:36:17 rptap volumio[1115]: info: Plugins store is now in test mode Jul 22 13:36:17 rptap 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. Jul 22 13:36:17 rptap systemd[1]: Reloading. Jul 22 13:36:17 rptap 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. Jul 22 13:36:17 rptap sudo[1738]: pam_unix(sudo:session): session closed for user root Jul 22 13:36:17 rptap volumio[1115]: info: enable SSH service success Jul 22 13:36:18 rptap volumio[1115]: info: CALLMETHOD: system_controller system enableLiveLog true Jul 22 13:36:18 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog Jul 22 13:36:18 rptap volumio[1115]: info: Launching a new LiveLog session Jul 22 13:36:21 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 22 13:36:21 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 22 13:36:24 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 22 13:36:24 rptap volumio[1115]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 22 13:36:24 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 22 13:36:24 rptap volumio[1115]: info: Received Get System Version Jul 22 13:36:24 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 22 13:36:24 rptap volumio[1115]: info: Received Get System Info Jul 22 13:36:24 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 22 13:36:24 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 22 13:36:24 rptap volumio[1115]: info: Discovery: Getting this device information Jul 22 13:36:24 rptap volumio[1115]: info: CoreCommandRouter::volumioGetState Jul 22 13:36:24 rptap volumio[1115]: info: CorePlayQueue::getTrack 0 Jul 22 13:36:24 rptap volumio[1115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 22 13:36:24 rptap sudo[1789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jul 22 13:36:24 rptap sudo[1789]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 13:36:24 rptap sudo[1789]: pam_unix(sudo:session): session closed for user root Jul 22 13:36:24 rptap sudo[1792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Jul 22 13:36:24 rptap sudo[1792]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 13:36:24 rptap sudo[1794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Jul 22 13:36:24 rptap sudo[1794]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 13:36:24 rptap volumio[1115]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jul 22 13:36:24 rptap volumio[1115]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 22 13:36:24 rptap sudo[1798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Jul 22 13:36:24 rptap sudo[1798]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 13:36:24 rptap volumio[1115]: TypeError: Cannot read property 'split' of undefined Jul 22 13:36:24 rptap volumio[1115]: at /data/plugins/user_interface/touch_display/index.js:1258:37 Jul 22 13:36:24 rptap volumio[1115]: at ChildProcess.exithandler (child_process.js:299:7) Jul 22 13:36:24 rptap volumio[1115]: at ChildProcess.emit (events.js:315:20) Jul 22 13:36:24 rptap volumio[1115]: at maybeClose (internal/child_process.js:1048:16) Jul 22 13:36:24 rptap volumio[1115]: at Socket. (internal/child_process.js:439:11) Jul 22 13:36:24 rptap volumio[1115]: at Socket.emit (events.js:315:20) Jul 22 13:36:24 rptap volumio[1115]: at Pipe. (net.js:673:12) Jul 22 13:36:24 rptap volumio[1115]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 22 13:36:24 rptap systemd[1]: Created slice system-getty.slice. Jul 22 13:36:24 rptap systemd[1]: Started Getty on tty1. Jul 22 13:36:24 rptap systemd[1]: Reloading. Jul 22 13:36:24 rptap sudo[1792]: pam_unix(sudo:session): session closed for user root Jul 22 13:36:25 rptap 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. Jul 22 13:36:25 rptap sudo[1823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-22 13:35 Jul 22 13:36:25 rptap sudo[1823]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"