-- Logs begin at Thu 2019-02-14 19:11:58 KST, end at Sat 2025-05-10 13:42:53 KST. -- May 10 13:41:07 mm-1 sshd[3719]: Accepted publickey for volumio from 172.30.1.60 port 58582 ssh2: RSA SHA256:H5iHY4JhkUeSQA+cHOGQSEQfWGQGxd6JPIoDlB2RFz4 May 10 13:41:07 mm-1 sshd[3719]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 10 13:41:07 mm-1 systemd-logind[815]: New session 24 of user volumio. May 10 13:41:07 mm-1 systemd[1]: Started Session 24 of user volumio. May 10 13:41:07 mm-1 sshd[3728]: Received disconnect from 172.30.1.60 port 58582:11: disconnected by user May 10 13:41:07 mm-1 sshd[3728]: Disconnected from user volumio 172.30.1.60 port 58582 May 10 13:41:07 mm-1 sshd[3719]: pam_unix(sshd:session): session closed for user volumio May 10 13:41:07 mm-1 systemd[1]: session-24.scope: Succeeded. May 10 13:41:07 mm-1 systemd-logind[815]: Session 24 logged out. Waiting for processes to exit. May 10 13:41:07 mm-1 systemd-logind[815]: Removed session 24. May 10 13:41:08 mm-1 sshd[3731]: Accepted publickey for volumio from 172.30.1.60 port 58594 ssh2: RSA SHA256:H5iHY4JhkUeSQA+cHOGQSEQfWGQGxd6JPIoDlB2RFz4 May 10 13:41:08 mm-1 sshd[3731]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 10 13:41:08 mm-1 systemd-logind[815]: New session 25 of user volumio. May 10 13:41:08 mm-1 systemd[1]: Started Session 25 of user volumio. May 10 13:41:08 mm-1 volumio[1233]: info: CoreCommandRouter::volumioGetState May 10 13:41:08 mm-1 volumio[1233]: info: CorePlayQueue::getTrack 0 May 10 13:41:08 mm-1 sshd[3740]: Received disconnect from 172.30.1.60 port 58594:11: disconnected by user May 10 13:41:08 mm-1 sshd[3740]: Disconnected from user volumio 172.30.1.60 port 58594 May 10 13:41:08 mm-1 sshd[3731]: pam_unix(sshd:session): session closed for user volumio May 10 13:41:08 mm-1 systemd[1]: session-25.scope: Succeeded. May 10 13:41:08 mm-1 systemd-logind[815]: Session 25 logged out. Waiting for processes to exit. May 10 13:41:08 mm-1 systemd-logind[815]: Removed session 25. May 10 13:42:01 mm-1 nmbd[1004]: [2025/05/10 13:42:01.272411, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 10 13:42:01 mm-1 nmbd[1004]: query_name_response: Multiple (2) responses received for a query on subnet 172.30.1.70 for name WORKGROUP<1d>. May 10 13:42:01 mm-1 nmbd[1004]: This response was from IP 172.30.1.60, reporting an IP address of 172.30.1.60. May 10 13:42:08 mm-1 sshd[3886]: Accepted publickey for volumio from 172.30.1.60 port 48728 ssh2: RSA SHA256:H5iHY4JhkUeSQA+cHOGQSEQfWGQGxd6JPIoDlB2RFz4 May 10 13:42:08 mm-1 sshd[3886]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 10 13:42:08 mm-1 systemd-logind[815]: New session 26 of user volumio. May 10 13:42:08 mm-1 systemd[1]: Started Session 26 of user volumio. May 10 13:42:08 mm-1 sshd[3895]: Received disconnect from 172.30.1.60 port 48728:11: disconnected by user May 10 13:42:08 mm-1 sshd[3895]: Disconnected from user volumio 172.30.1.60 port 48728 May 10 13:42:08 mm-1 sshd[3886]: pam_unix(sshd:session): session closed for user volumio May 10 13:42:08 mm-1 systemd[1]: session-26.scope: Succeeded. May 10 13:42:08 mm-1 systemd-logind[815]: Session 26 logged out. Waiting for processes to exit. May 10 13:42:08 mm-1 systemd-logind[815]: Removed session 26. May 10 13:42:08 mm-1 sshd[3898]: Accepted publickey for volumio from 172.30.1.60 port 48734 ssh2: RSA SHA256:H5iHY4JhkUeSQA+cHOGQSEQfWGQGxd6JPIoDlB2RFz4 May 10 13:42:08 mm-1 sshd[3898]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 10 13:42:08 mm-1 systemd-logind[815]: New session 27 of user volumio. May 10 13:42:08 mm-1 systemd[1]: Started Session 27 of user volumio. May 10 13:42:08 mm-1 volumio[1233]: info: CoreCommandRouter::volumioGetState May 10 13:42:08 mm-1 volumio[1233]: info: CorePlayQueue::getTrack 0 May 10 13:42:08 mm-1 sshd[3907]: Received disconnect from 172.30.1.60 port 48734:11: disconnected by user May 10 13:42:08 mm-1 sshd[3907]: Disconnected from user volumio 172.30.1.60 port 48734 May 10 13:42:08 mm-1 sshd[3898]: pam_unix(sshd:session): session closed for user volumio May 10 13:42:08 mm-1 systemd[1]: session-27.scope: Succeeded. May 10 13:42:08 mm-1 systemd-logind[815]: Session 27 logged out. Waiting for processes to exit. May 10 13:42:08 mm-1 systemd-logind[815]: Removed session 27. May 10 13:42:36 mm-1 ntpd[1026]: 129.250.35.250 local addr 172.30.1.70 -> May 10 13:42:37 mm-1 kernel: Bluetooth: hci0: unexpected event for opcode 0x2016 May 10 13:42:37 mm-1 kernel: NET: Registered PF_ALG protocol family May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 bluetoothd[914]: bt_uhid_send: Invalid argument (22) May 10 13:42:38 mm-1 kernel: input: BTLE Precision Mouse as /devices/virtual/misc/uhid/0005:045E:0821.0003/input/input6 May 10 13:42:38 mm-1 kernel: input: BTLE Precision Mouse Keyboard as /devices/virtual/misc/uhid/0005:045E:0821.0003/input/input7 May 10 13:42:38 mm-1 kernel: input: BTLE Precision Mouse as /devices/virtual/misc/uhid/0005:045E:0821.0003/input/input8 May 10 13:42:38 mm-1 kernel: input: BTLE Precision Mouse as /devices/virtual/misc/uhid/0005:045E:0821.0003/input/input9 May 10 13:42:38 mm-1 kernel: hid-generic 0005:045E:0821.0003: input,hidraw2: BLUETOOTH HID v2.16 Mouse [BTLE Precision Mouse] on 2C:CF:67:29:26:15 May 10 13:42:38 mm-1 systemd-udevd[4006]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 10 13:42:38 mm-1 systemd-udevd[4012]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 10 13:42:39 mm-1 systemd-udevd[4001]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 10 13:42:39 mm-1 systemd-logind[815]: Watching system buttons on /dev/input/event7 (BTLE Precision Mouse Keyboard) May 10 13:42:39 mm-1 systemd-udevd[4007]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 10 13:42:39 mm-1 systemd-udevd[4008]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 10 13:42:42 mm-1 volumio[1233]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 10 13:42:42 mm-1 volumio[1233]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 10 13:42:49 mm-1 volumio[1233]: info: [ASDebug] Port: 24 May 10 13:42:49 mm-1 volumio[1233]: info: [ASDebug] Inverted: false May 10 13:42:49 mm-1 volumio[1233]: info: [ASDebug] Delay: 720 May 10 13:42:53 mm-1 volumio[1233]: info: [ASDebug] Port: 24 May 10 13:42:53 mm-1 volumio[1233]: info: [ASDebug] Inverted: false May 10 13:42:53 mm-1 volumio[1233]: info: [ASDebug] Delay: 720 May 10 13:42:53 mm-1 volumio[1233]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 10 13:42:53 mm-1 volumio[1233]: Error: EBADF: bad file descriptor, close May 10 13:42:53 mm-1 volumio[1233]: at Object.closeSync (fs.js:439:3) May 10 13:42:53 mm-1 volumio[1233]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) May 10 13:42:53 mm-1 volumio[1233]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) May 10 13:42:53 mm-1 volumio[1233]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) May 10 13:42:53 mm-1 volumio[1233]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) May 10 13:42:53 mm-1 volumio[1233]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) May 10 13:42:53 mm-1 volumio[1233]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 10 13:42:53 mm-1 volumio[1233]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) May 10 13:42:53 mm-1 volumio[1233]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) May 10 13:42:53 mm-1 volumio[1233]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) May 10 13:42:53 mm-1 volumio[1233]: at Socket.emit (events.js:315:20) May 10 13:42:53 mm-1 volumio[1233]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 10 13:42:53 mm-1 volumio[1233]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { May 10 13:42:53 mm-1 volumio[1233]: errno: -9, May 10 13:42:53 mm-1 volumio[1233]: syscall: 'close', May 10 13:42:53 mm-1 volumio[1233]: code: 'EBADF' May 10 13:42:53 mm-1 volumio[1233]: } May 10 13:42:53 mm-1 volumio[1233]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 10 13:42:53 mm-1 sudo[4060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-10 13:41 May 10 13:42:53 mm-1 sudo[4060]: 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="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"