-- Logs begin at Mon 2024-12-16 17:35:29 KST, end at Sun 2025-02-23 08:51:38 KST. -- Feb 23 08:50:01 volumio volumio[938]: info: Enabling plugin touch_display Feb 23 08:50:01 volumio volumio[938]: info: Loading plugin "touch_display"... Feb 23 08:50:02 volumio volumio[938]: info: PLUGIN START: touch_display Feb 23 08:50:02 volumio volumio[938]: info: Loading i18n strings for locale ko Feb 23 08:50:02 volumio volumio[938]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Feb 23 08:50:02 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 08:50:02 volumio go-librespot[3431]: time="2025-02-23T08:50:02+09:00" level=trace msg="sent dealer ping" Feb 23 08:50:02 volumio go-librespot[3431]: time="2025-02-23T08:50:02+09:00" level=trace msg="received dealer pong" Feb 23 08:50:02 volumio volumio[938]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Feb 23 08:50:02 volumio volumio[938]: info: Setting up libxcomposite1:armhf (1:0.4.4-2) ... Feb 23 08:50:02 volumio volumio[938]: info: Setting up libxfont2:armhf (1:2.0.3-1) ... Feb 23 08:50:02 volumio volumio[938]: info: Setting up libevdev2:armhf (1.6.0+dfsg-1) ... Feb 23 08:50:02 volumio volumio[938]: info: Setting up libxmuu1:armhf (2:1.1.2-2+b3) ... Feb 23 08:50:02 volumio volumio[938]: info: Setting up libgudev-1.0-0:armhf (232-2+rpi1) ... Feb 23 08:50:02 volumio volumio[938]: info: Setting up xbitmaps (1.1.1-2) ... Feb 23 08:50:02 volumio volumio[938]: info: Setting up libwacom-common (0.32-1) ... Feb 23 08:50:02 volumio volumio[938]: info: Setting up libxmu6:armhf (2:1.1.2-2+b3) ... Feb 23 08:50:02 volumio volumio[938]: info: Setting up libxaw7:armhf (2:1.0.13-1+b2) ... Feb 23 08:50:02 volumio volumio[938]: info: Setting up xfonts-utils (1:7.7+6) ... Feb 23 08:50:02 volumio volumio[938]: info: Setting up libegl1-mesa:armhf (19.3.2-1~bpo10+1~rpt4) ... Feb 23 08:50:02 volumio volumio[938]: info: Setting up xfonts-base (1:1.0.5) ... Feb 23 08:50:02 volumio volumio[938]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 23 08:50:02 volumio sudo[6192]: volumio : unable to resolve host volumio Feb 23 08:50:02 volumio sudo[6188]: volumio : unable to resolve host volumio Feb 23 08:50:02 volumio sudo[6192]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 23 08:50:02 volumio sudo[6188]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 23 08:50:02 volumio sudo[6190]: volumio : unable to resolve host volumio Feb 23 08:50:02 volumio sudo[6190]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 23 08:50:02 volumio volumio[938]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Feb 23 08:50:02 volumio sudo[6206]: volumio : unable to resolve host volumio Feb 23 08:50:02 volumio sudo[6206]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 23 08:50:03 volumio volumio[938]: info: Setting up xauth (1:1.0.10-1) ... Feb 23 08:50:03 volumio sudo[6192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 23 08:50:03 volumio sudo[6206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 23 08:50:03 volumio sudo[6188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Feb 23 08:50:03 volumio sudo[6192]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 08:50:03 volumio sudo[6206]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 08:50:03 volumio sudo[6188]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 08:50:03 volumio sudo[6190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Feb 23 08:50:03 volumio sudo[6190]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 08:50:03 volumio sudo[6206]: pam_unix(sudo:session): session closed for user root Feb 23 08:50:03 volumio volumio[938]: error: touch_display: Error setting file permissions for /etc/X11/xorg.conf.d/99-vc4.conf: Error: Command failed: /usr/bin/sudo /bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 23 08:50:03 volumio volumio[938]: sudo: unable to resolve host volumio: System error Feb 23 08:50:03 volumio volumio[938]: /bin/chmod: cannot access '/etc/X11/xorg.conf.d/99-vc4.conf': No such file or directory Feb 23 08:50:03 volumio volumio[938]: info: Setting up x11-utils (7.7+4) ... Feb 23 08:50:03 volumio systemd[1]: Reloading. Feb 23 08:50:03 volumio volumio[938]: info: Setting up xterm (344-1+deb10u2) ... Feb 23 08:50:04 volumio volumio[938]: info: update-alternatives: using /usr/bin/xterm to provide /usr/bin/x-terminal-emulator (x-terminal-emulator) in auto mode Feb 23 08:50:04 volumio volumio[938]: info: update-alternatives: using /usr/bin/lxterm to provide /usr/bin/x-terminal-emulator (x-terminal-emulator) in auto mode Feb 23 08:50:04 volumio volumio[938]: info: Setting up libwacom2:armhf (0.32-1) ... Feb 23 08:50:04 volumio 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. Feb 23 08:50:04 volumio volumio[938]: info: Setting up xinit (1.4.0-1) ... Feb 23 08:50:04 volumio volumio[938]: info: Setting up x11-xkb-utils (7.7+4) ... Feb 23 08:50:04 volumio volumio[938]: info: Setting up libinput-bin (1.12.6-2+deb10u1) ... Feb 23 08:50:04 volumio volumio[938]: info: Setting up libwacom-bin (0.32-1) ... Feb 23 08:50:04 volumio volumio[938]: info: Setting up xserver-common (2:1.20.4-1+rpt4+deb10u9) ... Feb 23 08:50:04 volumio volumio[938]: info: Setting up xserver-xorg-legacy (2:1.20.4-1+rpt4+deb10u9) ... Feb 23 08:50:05 volumio systemd[1]: Reloading. Feb 23 08:50:05 volumio sudo[6192]: pam_unix(sudo:session): session closed for user root Feb 23 08:50:05 volumio volumio[938]: info: touch_display: systemctl daemon-reload succeeded. Feb 23 08:50:05 volumio sudo[6237]: volumio : unable to resolve host volumio Feb 23 08:50:05 volumio sudo[6237]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 23 08:50:05 volumio sudo[6237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 23 08:50:05 volumio sudo[6237]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 08:50:05 volumio 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. Feb 23 08:50:06 volumio volumio[938]: info: Setting up libinput10:armhf (1.12.6-2+deb10u1) ... Feb 23 08:50:06 volumio volumio[938]: info: Setting up xserver-xorg-core (2:1.20.4-1+rpt4+deb10u9) ... Feb 23 08:50:06 volumio volumio[938]: info: Setting up xserver-xorg-video-radeon (1:19.0.1-1) ... Feb 23 08:50:06 volumio volumio[938]: info: Setting up xserver-xorg-input-wacom (0.34.99.1-1+b6) ... Feb 23 08:50:06 volumio volumio[938]: info: Setting up xserver-xorg-video-fbdev (1:0.5.0-1+b2) ... Feb 23 08:50:06 volumio sudo[6190]: pam_unix(sudo:session): session closed for user root Feb 23 08:50:06 volumio sudo[6237]: pam_unix(sudo:session): session closed for user root Feb 23 08:50:06 volumio systemd[1]: Stopping Getty on tty1... Feb 23 08:50:06 volumio volumio[938]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 23 08:50:06 volumio volumio[938]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Feb 23 08:50:06 volumio volumio[938]: sudo: unable to resolve host volumio: System error Feb 23 08:50:06 volumio volumio[938]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Feb 23 08:50:06 volumio volumio[938]: info: Error: Error Feb 23 08:50:06 volumio systemd[1]: getty@tty1.service: Main process exited, code=killed, status=15/TERM Feb 23 08:50:06 volumio systemd[1]: getty@tty1.service: Succeeded. Feb 23 08:50:06 volumio volumio[938]: info: Setting up xserver-xorg-video-vesa (1:2.4.0-1+b1) ... Feb 23 08:50:06 volumio systemd[1]: Stopped Getty on tty1. Feb 23 08:50:06 volumio volumio[938]: info: Setting up xserver-xorg-video-amdgpu (18.1.99+git20190207-1) ... Feb 23 08:50:06 volumio sudo[6188]: pam_unix(sudo:session): session closed for user root Feb 23 08:50:07 volumio volumio[938]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 23 08:50:07 volumio volumio[938]: info: Setting up xserver-xorg (1:7.7+19+b8) ... Feb 23 08:50:07 volumio volumio[938]: info: Setting up xserver-xorg-video-nouveau (1:1.0.16-1) ... Feb 23 08:50:07 volumio volumio[938]: info: Setting up xserver-xorg-input-libinput (0.28.2-2) ... Feb 23 08:50:07 volumio volumio[938]: info: Setting up xserver-xorg-video-ati (1:19.0.1-1) ... Feb 23 08:50:07 volumio volumio[938]: info: Setting up xserver-xorg-video-all (1:7.7+19+b8) ... Feb 23 08:50:07 volumio volumio[938]: info: Setting up xserver-xorg-input-all (1:7.7+19+b8) ... Feb 23 08:50:07 volumio volumio[938]: info: Processing triggers for fontconfig (2.13.1-2) ... Feb 23 08:50:10 volumio volumio[938]: info: Processing triggers for mime-support (3.62) ... Feb 23 08:50:10 volumio volumio[938]: info: Processing triggers for libc-bin (2.28-10+rpt2+rpi1+deb10u2) ... Feb 23 08:50:12 volumio volumio[938]: info: Reading package lists... Feb 23 08:50:14 volumio volumio[938]: info: Building dependency tree... Feb 23 08:50:14 volumio volumio[938]: info: Reading state information... Feb 23 08:50:16 volumio volumio[938]: info: The following additional packages will be installed: Feb 23 08:50:16 volumio volumio[938]: info: bsdmainutils cpp cpp-8 groff-base libglu1-mesa libisl19 libmpc3 libmpfr6 Feb 23 08:50:16 volumio volumio[938]: info: libpipeline1 libuchardet0 man-db x11-apps x11-session-utils Feb 23 08:50:16 volumio volumio[938]: info: x11-xserver-utils xfonts-100dpi xfonts-75dpi xfonts-scalable xorg-docs-core Feb 23 08:50:16 volumio volumio[938]: info: Suggested packages: Feb 23 08:50:16 volumio volumio[938]: info: wamerican | wordlist whois vacation cpp-doc gcc-8-locales groff apparmor Feb 23 08:50:16 volumio volumio[938]: info: www-browser mesa-utils nickle cairo-5c xorg-docs x11-xfs-utils Feb 23 08:50:17 volumio volumio[938]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 08:50:22 volumio volumio[938]: info: The following NEW packages will be installed: Feb 23 08:50:22 volumio volumio[938]: info: bsdmainutils cpp cpp-8 groff-base libglu1-mesa libisl19 libmpc3 libmpfr6 Feb 23 08:50:22 volumio volumio[938]: info: libpipeline1 libuchardet0 man-db x11-apps x11-session-utils Feb 23 08:50:22 volumio volumio[938]: info: x11-xserver-utils xfonts-100dpi xfonts-75dpi xfonts-scalable xorg Feb 23 08:50:22 volumio volumio[938]: info: xorg-docs-core Feb 23 08:50:24 volumio volumio[938]: info: 0 upgraded, 19 newly installed, 0 to remove and 2 not upgraded. Feb 23 08:50:24 volumio volumio[938]: info: Need to get 18.8 MB of archives. Feb 23 08:50:24 volumio volumio[938]: info: After this operation, 39.8 MB of additional disk space will be used. Feb 23 08:50:24 volumio volumio[938]: info: Get:1 http://archive.raspberrypi.org/debian buster/main armhf x11-xserver-utils armhf 7.7+8+rpt1 [149 kB] Feb 23 08:50:25 volumio volumio[938]: info: Get:2 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf bsdmainutils armhf 11.1.2 [182 kB] Feb 23 08:50:25 volumio volumio[938]: info: Get:3 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf libuchardet0 armhf 0.0.6-3 [62.2 kB] Feb 23 08:50:26 volumio volumio[938]: info: Get:4 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf groff-base armhf 1.22.4-3+deb10u1 [782 kB] Feb 23 08:50:27 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 08:50:27 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 23 08:50:27 volumio volumio[938]: info: Get:5 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf libpipeline1 armhf 1.5.1-2 [26.6 kB] Feb 23 08:50:28 volumio volumio[938]: info: Get:6 http://mirror.rise.ph/raspbian/raspbian buster/main armhf man-db armhf 2.8.5-2+deb10u1 [1231 kB] Feb 23 08:50:29 volumio volumio[938]: info: Get:7 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf libisl19 armhf 0.20-2 [439 kB] Feb 23 08:50:30 volumio volumio[938]: info: Get:8 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf libmpfr6 armhf 4.0.2-1 [723 kB] Feb 23 08:50:30 volumio volumio[938]: info: Get:9 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf libmpc3 armhf 1.1.0-1 [32.1 kB] Feb 23 08:50:31 volumio volumio[938]: info: Get:10 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf cpp-8 armhf 8.3.0-6+rpi1 [6787 kB] Feb 23 08:50:31 volumio volumio[938]: info: Get:11 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf cpp armhf 4:8.3.0-1+rpi2 [19.6 kB] Feb 23 08:50:32 volumio go-librespot[3431]: time="2025-02-23T08:50:32+09:00" level=trace msg="sent dealer ping" Feb 23 08:50:32 volumio go-librespot[3431]: time="2025-02-23T08:50:32+09:00" level=trace msg="received dealer pong" Feb 23 08:50:32 volumio volumio[938]: info: Get:12 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf libglu1-mesa armhf 9.0.0-2.1 [130 kB] Feb 23 08:50:32 volumio volumio[938]: info: Get:13 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf x11-apps armhf 7.7+7 [541 kB] Feb 23 08:50:34 volumio volumio[938]: info: Get:14 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf x11-session-utils armhf 7.7+3 [61.2 kB] Feb 23 08:50:34 volumio volumio[938]: info: Get:15 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf xfonts-100dpi all 1:1.0.4+nmu1 [3822 kB] Feb 23 08:50:35 volumio volumio[938]: info: Get:16 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf xfonts-75dpi all 1:1.0.4+nmu1 [3367 kB] Feb 23 08:50:35 volumio volumio[938]: info: Get:17 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf xfonts-scalable all 1:1.0.3-1.1 [304 kB] Feb 23 08:50:36 volumio volumio[938]: info: Get:18 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf xorg-docs-core all 1:1.7.1-1.1 [84.0 kB] Feb 23 08:50:36 volumio volumio[938]: info: Get:19 http://ftp.kaist.ac.kr/raspbian/raspbian buster/main armhf xorg armhf 1:7.7+19+b8 [38.8 kB] Feb 23 08:50:37 volumio volumio[938]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/buster/armhf Feb 23 08:50:39 volumio volumio[938]: info: Fetched 18.8 MB in 13s (1478 kB/s) Feb 23 08:50:40 volumio volumio[938]: info: Selecting previously unselected package bsdmainutils. Feb 23 08:50:40 volumio volumio[938]: [620B blob data] Feb 23 08:50:40 volumio volumio[938]: info: Preparing to unpack .../00-bsdmainutils_11.1.2_armhf.deb ... Feb 23 08:50:40 volumio volumio[938]: info: Unpacking bsdmainutils (11.1.2) ... Feb 23 08:50:41 volumio volumio[938]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/buster/armhf Feb 23 08:50:41 volumio volumio[938]: info: Folder /tmp/plugins removed Feb 23 08:50:42 volumio volumio[938]: info: Selecting previously unselected package libuchardet0:armhf. Feb 23 08:50:42 volumio volumio[938]: info: Preparing to unpack .../01-libuchardet0_0.0.6-3_armhf.deb ... Feb 23 08:50:42 volumio volumio[938]: info: Unpacking libuchardet0:armhf (0.0.6-3) ... Feb 23 08:50:43 volumio volumio[938]: info: Selecting previously unselected package groff-base. Feb 23 08:50:43 volumio volumio[938]: info: Preparing to unpack .../02-groff-base_1.22.4-3+deb10u1_armhf.deb ... Feb 23 08:50:43 volumio volumio[938]: info: Unpacking groff-base (1.22.4-3+deb10u1) ... Feb 23 08:50:48 volumio volumio[938]: info: Check plugin dependencies Feb 23 08:50:48 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 08:50:48 volumio volumio[938]: info: Checking if plugin already exists Feb 23 08:50:48 volumio volumio[938]: info: Rename folder Feb 23 08:50:48 volumio volumio[938]: info: Move to category Feb 23 08:50:48 volumio volumio[938]: info: Folder /tmp/downloaded_plugin.zip removed Feb 23 08:51:02 volumio go-librespot[3431]: time="2025-02-23T08:51:02+09:00" level=trace msg="sent dealer ping" Feb 23 08:51:02 volumio go-librespot[3431]: time="2025-02-23T08:51:02+09:00" level=trace msg="received dealer pong" Feb 23 08:51:15 volumio volumio[938]: info: Checking if install.sh is present Feb 23 08:51:15 volumio volumio[938]: info: Executing install.sh Feb 23 08:51:16 volumio sudo[6516]: volumio : unable to resolve host volumio Feb 23 08:51:16 volumio sudo[6516]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 23 08:51:16 volumio volumio[938]: verbose: New Socket.io Connection to 192.168.0.17 from 192.168.0.24 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Feb 23 08:51:16 volumio sudo[6516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/now_playing/install.sh Feb 23 08:51:16 volumio sudo[6516]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 08:51:16 volumio volumio[938]: info: Installing geo-tz node dependency Feb 23 08:51:16 volumio volumio[938]: info: Installing geo-tz node dependency Feb 23 08:51:16 volumio volumio[938]: verbose: New Socket.io Connection to 192.168.0.17 from 192.168.0.25 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Feb 23 08:51:16 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 23 08:51:16 volumio volumio[938]: info: CoreCommandRouter::volumioGetVisibleSources Feb 23 08:51:16 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 08:51:16 volumio volumio[938]: info: CoreCommandRouter::volumioGetState Feb 23 08:51:16 volumio volumio[938]: info: CorePlayQueue::getTrack 0 Feb 23 08:51:16 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 23 08:51:16 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 23 08:51:16 volumio volumio[938]: info: Received Get System Info Feb 23 08:51:16 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 08:51:16 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 08:51:16 volumio volumio[938]: info: Discovery: Getting this device information Feb 23 08:51:16 volumio volumio[938]: info: CoreCommandRouter::volumioGetState Feb 23 08:51:16 volumio volumio[938]: info: CorePlayQueue::getTrack 0 Feb 23 08:51:16 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 08:51:16 volumio volumio[938]: info: CoreCommandRouter::volumioGetState Feb 23 08:51:16 volumio volumio[938]: info: CorePlayQueue::getTrack 0 Feb 23 08:51:16 volumio volumio[938]: info: Listing playlists Feb 23 08:51:16 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 23 08:51:17 volumio volumio[938]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 23 08:51:17 volumio volumio[938]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 23 08:51:17 volumio volumio[938]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 23 08:51:17 volumio volumio[938]: info: CoreCommandRouter::volumioGetState Feb 23 08:51:17 volumio volumio[938]: info: CorePlayQueue::getTrack 0 Feb 23 08:51:17 volumio volumio[938]: info: CoreCommandRouter::volumioGetState Feb 23 08:51:17 volumio volumio[938]: info: CorePlayQueue::getTrack 0 Feb 23 08:51:17 volumio volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 23 08:51:17 volumio volumio[938]: verbose: New Socket.io Connection to 192.168.0.17 from 192.168.0.24 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Feb 23 08:51:17 volumio volumio[938]: info: CoreCommandRouter::volumioGetState Feb 23 08:51:17 volumio volumio[938]: info: CorePlayQueue::getTrack 0 Feb 23 08:51:17 volumio volumio[938]: info: Received Get System Info Feb 23 08:51:17 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 08:51:17 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 08:51:17 volumio volumio[938]: info: Discovery: Getting this device information Feb 23 08:51:17 volumio volumio[938]: info: CoreCommandRouter::volumioGetState Feb 23 08:51:17 volumio volumio[938]: info: CorePlayQueue::getTrack 0 Feb 23 08:51:17 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 08:51:17 volumio volumio[938]: info: CoreCommandRouter::volumioGetState Feb 23 08:51:17 volumio volumio[938]: info: CorePlayQueue::getTrack 0 Feb 23 08:51:17 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 23 08:51:17 volumio volumio[938]: info: CoreCommandRouter::volumioGetVisibleSources Feb 23 08:51:17 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 08:51:17 volumio volumio[938]: info: CoreCommandRouter::volumioGetState Feb 23 08:51:17 volumio volumio[938]: info: CorePlayQueue::getTrack 0 Feb 23 08:51:17 volumio volumio[938]: info: CoreCommandRouter::volumioGetState Feb 23 08:51:17 volumio volumio[938]: info: CorePlayQueue::getTrack 0 Feb 23 08:51:17 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 23 08:51:17 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 23 08:51:17 volumio volumio[938]: info: Received Get System Info Feb 23 08:51:17 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 08:51:17 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 08:51:17 volumio volumio[938]: info: Discovery: Getting this device information Feb 23 08:51:17 volumio volumio[938]: info: CoreCommandRouter::volumioGetState Feb 23 08:51:17 volumio volumio[938]: info: CorePlayQueue::getTrack 0 Feb 23 08:51:17 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 08:51:17 volumio volumio[938]: info: CoreCommandRouter::volumioGetState Feb 23 08:51:17 volumio volumio[938]: info: CorePlayQueue::getTrack 0 Feb 23 08:51:17 volumio volumio[938]: info: Listing playlists Feb 23 08:51:17 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 23 08:51:17 volumio volumio[938]: info: Feb 23 08:51:17 volumio volumio[938]: Selecting previously unselected package libpipeline1:armhf. Feb 23 08:51:17 volumio volumio[938]: info: Feb 23 08:51:17 volumio volumio[938]: Selecting previously unselected package libpipeline1:armhf. Feb 23 08:51:18 volumio volumio[938]: info: Preparing to unpack .../03-libpipeline1_1.5.1-2_armhf.deb ... Feb 23 08:51:18 volumio volumio[938]: info: Unpacking libpipeline1:armhf (1.5.1-2) ... Feb 23 08:51:18 volumio volumio[938]: info: Preparing to unpack .../03-libpipeline1_1.5.1-2_armhf.deb ... Feb 23 08:51:18 volumio volumio[938]: info: Unpacking libpipeline1:armhf (1.5.1-2) ... Feb 23 08:51:18 volumio volumio[938]: info: Selecting previously unselected package man-db. Feb 23 08:51:19 volumio volumio[938]: info: Preparing to unpack .../04-man-db_2.8.5-2+deb10u1_armhf.deb ... Feb 23 08:51:19 volumio volumio[938]: info: Unpacking man-db (2.8.5-2+deb10u1) ... Feb 23 08:51:19 volumio volumio[938]: info: Selecting previously unselected package man-db. Feb 23 08:51:19 volumio volumio[938]: info: Preparing to unpack .../04-man-db_2.8.5-2+deb10u1_armhf.deb ... Feb 23 08:51:19 volumio volumio[938]: info: Unpacking man-db (2.8.5-2+deb10u1) ... Feb 23 08:51:21 volumio volumio[938]: info: Selecting previously unselected package libisl19:armhf. Feb 23 08:51:21 volumio volumio[938]: info: Selecting previously unselected package libisl19:armhf. Feb 23 08:51:21 volumio volumio[938]: info: Preparing to unpack .../05-libisl19_0.20-2_armhf.deb ... Feb 23 08:51:21 volumio volumio[938]: info: Unpacking libisl19:armhf (0.20-2) ... Feb 23 08:51:21 volumio volumio[938]: info: Preparing to unpack .../05-libisl19_0.20-2_armhf.deb ... Feb 23 08:51:21 volumio volumio[938]: info: Unpacking libisl19:armhf (0.20-2) ... Feb 23 08:51:23 volumio volumio[938]: info: Selecting previously unselected package libmpfr6:armhf. Feb 23 08:51:23 volumio volumio[938]: info: Selecting previously unselected package libmpfr6:armhf. Feb 23 08:51:23 volumio volumio[938]: info: Preparing to unpack .../06-libmpfr6_4.0.2-1_armhf.deb ... Feb 23 08:51:23 volumio volumio[938]: info: Unpacking libmpfr6:armhf (4.0.2-1) ... Feb 23 08:51:24 volumio volumio[938]: info: Preparing to unpack .../06-libmpfr6_4.0.2-1_armhf.deb ... Feb 23 08:51:24 volumio volumio[938]: info: Unpacking libmpfr6:armhf (4.0.2-1) ... Feb 23 08:51:24 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 23 08:51:24 volumio volumio[938]: info: Selecting previously unselected package libmpc3:armhf. Feb 23 08:51:24 volumio volumio[938]: info: Preparing to unpack .../07-libmpc3_1.1.0-1_armhf.deb ... Feb 23 08:51:24 volumio volumio[938]: info: Unpacking libmpc3:armhf (1.1.0-1) ... Feb 23 08:51:24 volumio volumio[938]: info: Selecting previously unselected package cpp-8. Feb 23 08:51:24 volumio volumio[938]: info: Preparing to unpack .../08-cpp-8_8.3.0-6+rpi1_armhf.deb ... Feb 23 08:51:24 volumio volumio[938]: info: Unpacking cpp-8 (8.3.0-6+rpi1) ... Feb 23 08:51:25 volumio volumio[938]: info: Selecting previously unselected package libmpc3:armhf. Feb 23 08:51:25 volumio volumio[938]: info: Preparing to unpack .../07-libmpc3_1.1.0-1_armhf.deb ... Feb 23 08:51:25 volumio volumio[938]: info: Unpacking libmpc3:armhf (1.1.0-1) ... Feb 23 08:51:25 volumio volumio[938]: info: Selecting previously unselected package cpp-8. Feb 23 08:51:25 volumio volumio[938]: info: Preparing to unpack .../08-cpp-8_8.3.0-6+rpi1_armhf.deb ... Feb 23 08:51:25 volumio volumio[938]: info: Unpacking cpp-8 (8.3.0-6+rpi1) ... Feb 23 08:51:29 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 08:51:29 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 23 08:51:31 volumio go-librespot[3431]: time="2025-02-23T08:51:31+09:00" level=trace msg="received accesspoint ping" Feb 23 08:51:31 volumio go-librespot[3431]: time="2025-02-23T08:51:31+09:00" level=trace msg="received accesspoint pong ack" Feb 23 08:51:32 volumio go-librespot[3431]: time="2025-02-23T08:51:32+09:00" level=trace msg="sent dealer ping" Feb 23 08:51:32 volumio go-librespot[3431]: time="2025-02-23T08:51:32+09:00" level=trace msg="received dealer pong" Feb 23 08:51:36 volumio volumio[938]: info: Enabling plugin now_playing Feb 23 08:51:36 volumio volumio[938]: info: Loading plugin "now_playing"... Feb 23 08:51:36 volumio volumio[938]: error: !!!! WARNING !!!! Feb 23 08:51:36 volumio volumio[938]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Feb 23 08:51:36 volumio volumio[938]: Require stack: Feb 23 08:51:36 volumio volumio[938]: - /data/plugins/user_interface/now_playing/dist/index.js Feb 23 08:51:36 volumio volumio[938]: - /volumio/app/pluginmanager.js Feb 23 08:51:36 volumio volumio[938]: - /volumio/app/index.js Feb 23 08:51:36 volumio volumio[938]: - /volumio/index.js Feb 23 08:51:36 volumio volumio[938]: error: Stack trace: Error: Cannot find module 'geo-tz' Feb 23 08:51:36 volumio volumio[938]: Require stack: Feb 23 08:51:36 volumio volumio[938]: - /data/plugins/user_interface/now_playing/dist/index.js Feb 23 08:51:36 volumio volumio[938]: - /volumio/app/pluginmanager.js Feb 23 08:51:36 volumio volumio[938]: - /volumio/app/index.js Feb 23 08:51:36 volumio volumio[938]: - /volumio/index.js Feb 23 08:51:36 volumio volumio[938]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:880:15) Feb 23 08:51:36 volumio volumio[938]: at Function.Module._load (internal/modules/cjs/loader.js:725:27) Feb 23 08:51:36 volumio volumio[938]: at Module.require (internal/modules/cjs/loader.js:952:19) Feb 23 08:51:36 volumio volumio[938]: at require (internal/modules/cjs/helpers.js:88:18) Feb 23 08:51:36 volumio volumio[938]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) Feb 23 08:51:36 volumio volumio[938]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Feb 23 08:51:36 volumio volumio[938]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Feb 23 08:51:36 volumio volumio[938]: at Module.load (internal/modules/cjs/loader.js:928:32) Feb 23 08:51:36 volumio volumio[938]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Feb 23 08:51:36 volumio volumio[938]: at Module.require (internal/modules/cjs/loader.js:952:19) Feb 23 08:51:36 volumio volumio[938]: at require (internal/modules/cjs/helpers.js:88:18) Feb 23 08:51:36 volumio volumio[938]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Feb 23 08:51:36 volumio volumio[938]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Feb 23 08:51:36 volumio volumio[938]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 23 08:51:36 volumio volumio[938]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 23 08:51:36 volumio volumio[938]: error: !!!! WARNING !!!! Feb 23 08:51:36 volumio volumio[938]: info: Done. Feb 23 08:51:36 volumio volumio[938]: info: Selecting previously unselected package cpp. Feb 23 08:51:36 volumio volumio[938]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 08:51:36 volumio volumio[938]: TypeError: Cannot read property 'broadcastMessage' of null Feb 23 08:51:36 volumio volumio[938]: at /volumio/app/index.js:1528:32 Feb 23 08:51:36 volumio volumio[938]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Feb 23 08:51:36 volumio volumio[938]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Feb 23 08:51:36 volumio volumio[938]: at CoreCommandRouter.broadcastMessage (/volumio/app/index.js:1526:13) Feb 23 08:51:36 volumio volumio[938]: at PluginManager.pushMessage (/volumio/app/pluginmanager.js:1594:20) Feb 23 08:51:36 volumio volumio[938]: at Tail. (/volumio/app/pluginmanager.js:860:24) Feb 23 08:51:36 volumio volumio[938]: at Tail.emit (events.js:315:20) Feb 23 08:51:36 volumio volumio[938]: at ReadStream. (/volumio/node_modules/tail/lib/tail.js:51:33) Feb 23 08:51:36 volumio volumio[938]: at ReadStream.emit (events.js:315:20) Feb 23 08:51:36 volumio volumio[938]: at addChunk (internal/streams/readable.js:309:12) Feb 23 08:51:36 volumio volumio[938]: at readableAddChunk (internal/streams/readable.js:280:11) Feb 23 08:51:36 volumio volumio[938]: at ReadStream.Readable.push (internal/streams/readable.js:223:10) Feb 23 08:51:36 volumio volumio[938]: at internal/fs/streams.js:226:14 Feb 23 08:51:36 volumio volumio[938]: at FSReqCallback.wrapper [as oncomplete] (fs.js:539:5) Feb 23 08:51:36 volumio volumio[938]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 08:51:38 volumio sudo[6704]: volumio : unable to resolve host volumio Feb 23 08:51:38 volumio sudo[6704]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 23 08:51:38 volumio sudo[6704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-23 08:50 Feb 23 08:51:38 volumio sudo[6704]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"