-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2025-03-12 10:41:10 CET. -- Mar 12 10:40:01 bozvolumio go-librespot[1192]: time="2025-03-12T10:40:01+01:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 192.168.50.65:43840->34.158.1.133:4070: read: connection reset by peer" Mar 12 10:40:01 bozvolumio go-librespot[1192]: time="2025-03-12T10:40:01+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Mar 12 10:40:02 bozvolumio go-librespot[1192]: time="2025-03-12T10:40:02+01:00" level=debug msg="completed keyexchange" Mar 12 10:40:02 bozvolumio go-librespot[1192]: time="2025-03-12T10:40:02+01:00" level=debug msg="completed challenge" Mar 12 10:40:02 bozvolumio go-librespot[1192]: time="2025-03-12T10:40:02+01:00" level=info msg="authenticated AP as abozzetto" Mar 12 10:40:02 bozvolumio go-librespot[1192]: time="2025-03-12T10:40:02+01:00" level=debug msg="re-established accesspoint connection" Mar 12 10:40:02 bozvolumio go-librespot[1192]: time="2025-03-12T10:40:02+01:00" level=trace msg="received accesspoint ping" Mar 12 10:40:02 bozvolumio go-librespot[1192]: time="2025-03-12T10:40:02+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 12 10:40:02 bozvolumio go-librespot[1192]: time="2025-03-12T10:40:02+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 12 10:40:02 bozvolumio go-librespot[1192]: time="2025-03-12T10:40:02+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 12 10:40:02 bozvolumio go-librespot[1192]: time="2025-03-12T10:40:02+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 12 10:40:02 bozvolumio go-librespot[1192]: time="2025-03-12T10:40:02+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 12 10:40:02 bozvolumio go-librespot[1192]: time="2025-03-12T10:40:02+01:00" level=trace msg="received accesspoint pong ack" Mar 12 10:40:14 bozvolumio volumio[845]: info: CoreCommandRouter::volumioGetState Mar 12 10:40:14 bozvolumio volumio[845]: info: CorePlayQueue::getTrack 0 Mar 12 10:40:16 bozvolumio go-librespot[1192]: time="2025-03-12T10:40:16+01:00" level=trace msg="sent dealer ping" Mar 12 10:40:16 bozvolumio go-librespot[1192]: time="2025-03-12T10:40:16+01:00" level=trace msg="received dealer pong" Mar 12 10:40:17 bozvolumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 12 10:40:17 bozvolumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 12 10:40:21 bozvolumio kernel: [UFW BLOCK] IN=eth0 OUT= MAC=b8:27:eb:b1:97:0e:2c:3a:fd:28:24:75:08:00 SRC=192.168.50.1 DST=192.168.50.65 LEN=60 TOS=0x00 PREC=0x00 TTL=64 ID=55596 DF PROTO=TCP SPT=43875 DPT=49152 WINDOW=29200 RES=0x00 SYN URGP=0 Mar 12 10:40:28 bozvolumio volumio[845]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 12 10:40:35 bozvolumio volumio[845]: info: CALLMETHOD: user_interface touch_display saveVirtualKeyboardConf [object Object] Mar 12 10:40:35 bozvolumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveVirtualKeyboardConf Mar 12 10:40:36 bozvolumio sudo[6032]: pam_unix(sudo:auth): authentication failure; logname= uid=1000 euid=0 tty= ruser=volumio rhost= user=volumio Mar 12 10:40:36 bozvolumio kernel: [UFW BLOCK] IN=eth0 OUT= MAC=01:00:5e:00:00:01:2c:3a:fd:28:24:75:08:00 SRC=192.168.50.1 DST=224.0.0.1 LEN=36 TOS=0x00 PREC=0xC0 TTL=1 ID=58831 DF PROTO=2 Mar 12 10:40:38 bozvolumio sudo[6032]: pam_unix(sudo:auth): conversation failed Mar 12 10:40:38 bozvolumio sudo[6032]: pam_unix(sudo:auth): auth could not identify password for [volumio] Mar 12 10:40:38 bozvolumio sudo[6032]: volumio : 1 incorrect password attempt ; TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e s/load-extension=.* /load-extension=\'\\/data\\/volumiokioskextensions\\/VirtualKeyboard\\/\' / /opt/volumiokiosk.sh Mar 12 10:40:38 bozvolumio volumio[845]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 12 10:40:38 bozvolumio volumio[845]: error: touch_display: Error modifying /opt/volumiokiosk.sh: Error: Command failed: /bin/echo volumio | /usr/bin/sudo -S /bin/sed -i -e "s/load-extension=.* /load-extension='\/data\/volumiokioskextensions\/VirtualKeyboard\/' /" /opt/volumiokiosk.sh Mar 12 10:40:38 bozvolumio volumio[845]: [sudo] password for volumio: Sorry, try again. Mar 12 10:40:38 bozvolumio volumio[845]: [sudo] password for volumio: Mar 12 10:40:38 bozvolumio volumio[845]: sudo: no password was provided Mar 12 10:40:38 bozvolumio volumio[845]: sudo: 1 incorrect password attempt Mar 12 10:40:46 bozvolumio go-librespot[1192]: time="2025-03-12T10:40:46+01:00" level=trace msg="sent dealer ping" Mar 12 10:40:46 bozvolumio go-librespot[1192]: time="2025-03-12T10:40:46+01:00" level=trace msg="received dealer pong" Mar 12 10:40:53 bozvolumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 12 10:40:53 bozvolumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 12 10:41:00 bozvolumio volumio[845]: info: Starting Uninstall of plugin system_hardware - audiophonicsonoff Mar 12 10:41:00 bozvolumio volumio[845]: info: Uninstalling plugin audiophonicsonoff Mar 12 10:41:00 bozvolumio volumio[845]: info: performing onStop action Mar 12 10:41:00 bozvolumio volumio[845]: info: Disabling plugin audiophonicsonoff Mar 12 10:41:00 bozvolumio volumio[845]: info: Checking if uninstall.sh is present Mar 12 10:41:00 bozvolumio volumio[845]: info: Executing uninstall.sh Mar 12 10:41:00 bozvolumio sudo[6083]: pam_unix(sudo:auth): authentication failure; logname= uid=1000 euid=0 tty= ruser=volumio rhost= user=volumio Mar 12 10:41:02 bozvolumio sudo[6083]: pam_unix(sudo:auth): conversation failed Mar 12 10:41:02 bozvolumio sudo[6083]: pam_unix(sudo:auth): auth could not identify password for [volumio] Mar 12 10:41:02 bozvolumio sudo[6083]: volumio : 1 incorrect password attempt ; TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_hardware/audiophonicsonoff/uninstall.sh Mar 12 10:41:02 bozvolumio volumio[845]: info: Uninstall script return the error Error: Command failed: echo volumio | sudo -S sh /data/plugins/system_hardware/audiophonicsonoff/uninstall.sh > /tmp/installog Mar 12 10:41:02 bozvolumio volumio[845]: [sudo] password for volumio: Sorry, try again. Mar 12 10:41:02 bozvolumio volumio[845]: [sudo] password for volumio: Mar 12 10:41:02 bozvolumio volumio[845]: sudo: no password was provided Mar 12 10:41:02 bozvolumio volumio[845]: sudo: 1 incorrect password attempt Mar 12 10:41:08 bozvolumio volumio[845]: info: performing onStop action Mar 12 10:41:08 bozvolumio volumio[845]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 12 10:41:08 bozvolumio volumio[845]: Error: EBADF: bad file descriptor, close Mar 12 10:41:08 bozvolumio volumio[845]: at Object.closeSync (fs.js:439:3) Mar 12 10:41:08 bozvolumio volumio[845]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Mar 12 10:41:08 bozvolumio volumio[845]: at Gpio.unexport (/data/plugins/system_hardware/audiophonicsonoff/node_modules/onoff/onoff.js:307:8) Mar 12 10:41:08 bozvolumio volumio[845]: at ControllerAudiophonicsOnOff.onStop (/data/plugins/system_hardware/audiophonicsonoff/index.js:68:15) Mar 12 10:41:08 bozvolumio volumio[845]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Mar 12 10:41:08 bozvolumio volumio[845]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Mar 12 10:41:08 bozvolumio volumio[845]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Mar 12 10:41:08 bozvolumio volumio[845]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) Mar 12 10:41:08 bozvolumio volumio[845]: at Socket.emit (events.js:315:20) Mar 12 10:41:08 bozvolumio volumio[845]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 { Mar 12 10:41:08 bozvolumio volumio[845]: errno: -9, Mar 12 10:41:08 bozvolumio volumio[845]: syscall: 'close', Mar 12 10:41:08 bozvolumio volumio[845]: code: 'EBADF' Mar 12 10:41:08 bozvolumio volumio[845]: } Mar 12 10:41:08 bozvolumio volumio[845]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 12 10:41:10 bozvolumio sudo[6111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-12 10:40 Mar 12 10:41:10 bozvolumio sudo[6111]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"