-- Logs begin at Fri 2025-12-19 03:36:30 CET, end at Sat 2025-12-27 18:12:01 CET. -- Dec 27 18:11:11 volumio go-librespot[1177]: time="2025-12-27T18:11:11+01:00" level=debug msg="handling update_context player command from b16a6a76a53b67d1878233279e824bd89342dec5" Dec 27 18:11:11 volumio go-librespot[1177]: time="2025-12-27T18:11:11+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 27 18:11:11 volumio go-librespot[1177]: time="2025-12-27T18:11:11+01:00" level=debug msg="sending successful reply for dealer request" Dec 27 18:11:14 volumio volumio[794]: verbose: New Socket.io Connection to 192.168.1.138:3000 from 192.168.1.135 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 27 18:11:14 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 18:11:14 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 18:11:14 volumio volumio[794]: info: Discovery: Getting this device information Dec 27 18:11:14 volumio volumio[794]: info: CoreCommandRouter::volumioGetState Dec 27 18:11:14 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 18:11:15 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 27 18:11:15 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 27 18:11:17 volumio sudo[2314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 18:11:17 volumio sudo[2314]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 18:11:17 volumio sudo[2316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 18:11:17 volumio sudo[2316]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 18:11:17 volumio sudo[2316]: pam_unix(sudo:session): session closed for user root Dec 27 18:11:17 volumio sudo[2314]: pam_unix(sudo:session): session closed for user root Dec 27 18:11:17 volumio volumio[794]: verbose: New Socket.io Connection to 192.168.1.138 from 192.168.1.135 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Dec 27 18:11:18 volumio sudo[2322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 18:11:18 volumio sudo[2322]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 18:11:18 volumio sudo[2328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 18:11:18 volumio sudo[2322]: pam_unix(sudo:session): session closed for user root Dec 27 18:11:18 volumio sudo[2328]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 18:11:18 volumio sudo[2328]: pam_unix(sudo:session): session closed for user root Dec 27 18:11:18 volumio volumio[794]: verbose: New Socket.io Connection to 192.168.1.138 from 192.168.1.135 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Dec 27 18:11:18 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 27 18:11:18 volumio volumio[794]: info: CoreCommandRouter::volumioGetVisibleSources Dec 27 18:11:18 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 18:11:18 volumio volumio[794]: info: CoreCommandRouter::volumioGetState Dec 27 18:11:18 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 27 18:11:18 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 27 18:11:18 volumio volumio[794]: info: Received Get System Info Dec 27 18:11:18 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 18:11:18 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 18:11:18 volumio volumio[794]: info: Discovery: Getting this device information Dec 27 18:11:18 volumio volumio[794]: info: CoreCommandRouter::volumioGetState Dec 27 18:11:18 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 18:11:18 volumio volumio[794]: info: CoreCommandRouter::volumioGetState Dec 27 18:11:18 volumio volumio[794]: info: Listing playlists Dec 27 18:11:18 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 27 18:11:18 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 27 18:11:19 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 27 18:11:19 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 27 18:11:20 volumio volumio[794]: info: Received Get System Info Dec 27 18:11:20 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 18:11:20 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 18:11:20 volumio volumio[794]: info: Discovery: Getting this device information Dec 27 18:11:20 volumio volumio[794]: info: CoreCommandRouter::volumioGetState Dec 27 18:11:20 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 18:11:20 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 27 18:11:21 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 27 18:11:21 volumio volumio[794]: info: Received Get System Info Dec 27 18:11:21 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 18:11:21 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 18:11:21 volumio volumio[794]: info: Discovery: Getting this device information Dec 27 18:11:21 volumio volumio[794]: info: CoreCommandRouter::volumioGetState Dec 27 18:11:21 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 18:11:21 volumio go-librespot[1177]: time="2025-12-27T18:11:21+01:00" level=trace msg="sent dealer ping" Dec 27 18:11:21 volumio go-librespot[1177]: time="2025-12-27T18:11:21+01:00" level=trace msg="received dealer pong" Dec 27 18:11:24 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 27 18:11:24 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 27 18:11:28 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 27 18:11:29 volumio volumio[794]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 18:11:29 volumio volumio[794]: info: [ASDebug] Setting UI defaults Dec 27 18:11:29 volumio volumio[794]: info: [ASDebug] Port: 17 Dec 27 18:11:29 volumio volumio[794]: info: [ASDebug] Inverted: true Dec 27 18:11:29 volumio volumio[794]: info: [ASDebug] Latched: false Dec 27 18:11:29 volumio volumio[794]: info: [ASDebug] On pulse width: 500 Dec 27 18:11:29 volumio volumio[794]: info: [ASDebug] Off pulse width: 500 Dec 27 18:11:36 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 27 18:11:36 volumio volumio[794]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 27 18:11:36 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 27 18:11:36 volumio volumio[794]: info: Received Get System Version Dec 27 18:11:36 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 18:11:36 volumio volumio[794]: info: Received Get System Info Dec 27 18:11:36 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 18:11:36 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 18:11:36 volumio volumio[794]: info: Discovery: Getting this device information Dec 27 18:11:36 volumio volumio[794]: info: CoreCommandRouter::volumioGetState Dec 27 18:11:36 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 18:11:51 volumio go-librespot[1177]: time="2025-12-27T18:11:51+01:00" level=trace msg="received accesspoint ping" Dec 27 18:11:51 volumio go-librespot[1177]: time="2025-12-27T18:11:51+01:00" level=trace msg="received accesspoint pong ack" Dec 27 18:11:51 volumio go-librespot[1177]: time="2025-12-27T18:11:51+01:00" level=trace msg="sent dealer ping" Dec 27 18:11:51 volumio go-librespot[1177]: time="2025-12-27T18:11:51+01:00" level=trace msg="received dealer pong" Dec 27 18:11:52 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 27 18:11:52 volumio volumio[794]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 27 18:11:56 volumio volumio[794]: info: [ASDebug] Port: 17 Dec 27 18:11:56 volumio volumio[794]: info: [ASDebug] Inverted: true Dec 27 18:11:56 volumio volumio[794]: info: [ASDebug] Delay: 720 Dec 27 18:11:58 volumio volumio[794]: info: [ASDebug] Port: 17 Dec 27 18:11:58 volumio volumio[794]: info: [ASDebug] Inverted: true Dec 27 18:11:58 volumio volumio[794]: info: [ASDebug] Delay: 720 Dec 27 18:11:58 volumio volumio[794]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 18:11:58 volumio volumio[794]: Error: EBADF: bad file descriptor, close Dec 27 18:11:58 volumio volumio[794]: at Object.closeSync (fs.js:439:3) Dec 27 18:11:58 volumio volumio[794]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Dec 27 18:11:58 volumio volumio[794]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Dec 27 18:11:58 volumio volumio[794]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Dec 27 18:11:58 volumio volumio[794]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Dec 27 18:11:58 volumio volumio[794]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Dec 27 18:11:58 volumio volumio[794]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Dec 27 18:11:58 volumio volumio[794]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Dec 27 18:11:58 volumio volumio[794]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Dec 27 18:11:58 volumio volumio[794]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1412:47) Dec 27 18:11:58 volumio volumio[794]: at Socket.emit (events.js:315:20) Dec 27 18:11:58 volumio volumio[794]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Dec 27 18:11:58 volumio volumio[794]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Dec 27 18:11:58 volumio volumio[794]: errno: -9, Dec 27 18:11:58 volumio volumio[794]: syscall: 'close', Dec 27 18:11:58 volumio volumio[794]: code: 'EBADF' Dec 27 18:11:58 volumio volumio[794]: } Dec 27 18:11:58 volumio volumio[794]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 18:12:01 volumio sudo[2455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-27 18:11 Dec 27 18:12:01 volumio sudo[2455]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"