-- Logs begin at Thu 2019-02-14 12:11:59 EET, end at Tue 2025-07-01 11:06:27 EEST. -- Jul 01 11:05:00 rma go-librespot[1257]: time="2025-07-01T11:05:00+03:00" level=trace msg="sent dealer ping" Jul 01 11:05:00 rma go-librespot[1257]: time="2025-07-01T11:05:00+03:00" level=trace msg="received dealer pong" Jul 01 11:05:08 rma go-librespot[1257]: time="2025-07-01T11:05:08+03:00" level=debug msg="fetched chunk 9/13, size: 524288" uri="spotify:track:1ZUEvvu34Oc2i2PuA6EitL" Jul 01 11:05:20 rma go-librespot[1257]: time="2025-07-01T11:05:20+03:00" level=debug msg="fetched chunk 10/13, size: 524288" uri="spotify:track:1ZUEvvu34Oc2i2PuA6EitL" Jul 01 11:05:21 rma volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 01 11:05:21 rma volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 01 11:05:21 rma volumio[975]: info: Discovery: Getting this device information Jul 01 11:05:21 rma volumio[975]: info: CoreCommandRouter::volumioGetState Jul 01 11:05:21 rma volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 01 11:05:30 rma go-librespot[1257]: time="2025-07-01T11:05:30+03:00" level=trace msg="received accesspoint ping" Jul 01 11:05:30 rma go-librespot[1257]: time="2025-07-01T11:05:30+03:00" level=trace msg="received accesspoint pong ack" Jul 01 11:05:30 rma go-librespot[1257]: time="2025-07-01T11:05:30+03:00" level=trace msg="sent dealer ping" Jul 01 11:05:30 rma go-librespot[1257]: time="2025-07-01T11:05:30+03:00" level=trace msg="received dealer pong" Jul 01 11:05:32 rma go-librespot[1257]: time="2025-07-01T11:05:32+03:00" level=debug msg="fetched chunk 11/13, size: 524288" uri="spotify:track:1ZUEvvu34Oc2i2PuA6EitL" Jul 01 11:05:44 rma go-librespot[1257]: time="2025-07-01T11:05:44+03:00" level=debug msg="fetched chunk 12/13, size: 524288" uri="spotify:track:1ZUEvvu34Oc2i2PuA6EitL" Jul 01 11:05:51 rma volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 01 11:05:51 rma volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 01 11:05:51 rma volumio[975]: info: Discovery: Getting this device information Jul 01 11:05:51 rma volumio[975]: info: CoreCommandRouter::volumioGetState Jul 01 11:05:51 rma volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 01 11:05:55 rma go-librespot[1257]: time="2025-07-01T11:05:55+03:00" level=debug msg="fetched chunk 13/13, size: 132976" uri="spotify:track:1ZUEvvu34Oc2i2PuA6EitL" Jul 01 11:06:00 rma go-librespot[1257]: time="2025-07-01T11:06:00+03:00" level=trace msg="sent dealer ping" Jul 01 11:06:00 rma go-librespot[1257]: time="2025-07-01T11:06:00+03:00" level=trace msg="received dealer pong" Jul 01 11:06:01 rma volumio[975]: info: CoreCommandRouter::volumioGetState Jul 01 11:06:05 rma go-librespot[1257]: time="2025-07-01T11:06:05+03:00" level=debug msg="prefetching next track" uri="spotify:track:2r8x4Aljcsw8h4rjiG4ifS" Jul 01 11:06:05 rma volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 01 11:06:05 rma volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 01 11:06:05 rma go-librespot[1257]: time="2025-07-01T11:06:05+03:00" level=debug msg="selected format OGG_VORBIS_320 (5c74b71ceaf85d8edce136f11c38b8af0344fedb)" uri="spotify:track:2r8x4Aljcsw8h4rjiG4ifS" Jul 01 11:06:05 rma go-librespot[1257]: time="2025-07-01T11:06:05+03:00" level=debug msg="requested aes key for file 5c74b71ceaf85d8edce136f11c38b8af0344fedb, gid: 2r8x4Aljcsw8h4rjiG4ifS" Jul 01 11:06:06 rma go-librespot[1257]: time="2025-07-01T11:06:06+03:00" level=debug msg="fetched first chunk of 18, total size is 9147912 bytes" uri="spotify:track:2r8x4Aljcsw8h4rjiG4ifS" Jul 01 11:06:06 rma go-librespot[1257]: time="2025-07-01T11:06:06+03:00" level=info msg="prefetched track \"Kalokairines Stigmes\" (duration: 205376ms)" uri="spotify:track:2r8x4Aljcsw8h4rjiG4ifS" Jul 01 11:06:07 rma go-librespot[1257]: time="2025-07-01T11:06:07+03:00" level=debug msg="fetched chunk 1/17, size: 524288" uri="spotify:track:2r8x4Aljcsw8h4rjiG4ifS" Jul 01 11:06:08 rma go-librespot[1257]: time="2025-07-01T11:06:08+03:00" level=debug msg="fetched chunk 3/17, size: 524288" uri="spotify:track:2r8x4Aljcsw8h4rjiG4ifS" Jul 01 11:06:08 rma go-librespot[1257]: time="2025-07-01T11:06:08+03:00" level=debug msg="fetched chunk 2/17, size: 524288" uri="spotify:track:2r8x4Aljcsw8h4rjiG4ifS" Jul 01 11:06:14 rma volumio[975]: info: Enabling plugin ampswitch Jul 01 11:06:14 rma volumio[975]: info: Loading plugin "ampswitch"... Jul 01 11:06:14 rma volumio[975]: info: PLUGIN START: ampswitch Jul 01 11:06:14 rma volumio[975]: info: Done. Jul 01 11:06:14 rma volumio[975]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 01 11:06:14 rma volumio[975]: info: CoreCommandRouter::volumioGetState Jul 01 11:06:14 rma volumio[975]: info: [ASDebug] CurState: play PrevState: na Jul 01 11:06:14 rma volumio[975]: info: [ASDebug] Togle GPIO: ON Jul 01 11:06:14 rma volumio[975]: info: [ASDebug] CurState: play PrevState: play Jul 01 11:06:15 rma volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 01 11:06:15 rma volumio[975]: info: [ASDebug] Setting UI defaults Jul 01 11:06:15 rma volumio[975]: info: [ASDebug] Port: 24 Jul 01 11:06:15 rma volumio[975]: info: [ASDebug] Inverted: false Jul 01 11:06:15 rma volumio[975]: info: [ASDebug] Latched: false Jul 01 11:06:15 rma volumio[975]: info: [ASDebug] On pulse width: 500 Jul 01 11:06:15 rma volumio[975]: info: [ASDebug] Off pulse width: 500 Jul 01 11:06:21 rma volumio[975]: info: CoreCommandRouter::volumioGetState Jul 01 11:06:21 rma volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 01 11:06:21 rma volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 01 11:06:21 rma volumio[975]: info: Discovery: Getting this device information Jul 01 11:06:21 rma volumio[975]: info: CoreCommandRouter::volumioGetState Jul 01 11:06:21 rma volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 01 11:06:22 rma volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 01 11:06:22 rma volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 01 11:06:24 rma volumio[975]: info: [ASDebug] Port: 24 Jul 01 11:06:24 rma volumio[975]: info: [ASDebug] Inverted: false Jul 01 11:06:24 rma volumio[975]: info: [ASDebug] Delay: 720 Jul 01 11:06:26 rma volumio[975]: info: Starting Uninstall of plugin system_controller - ampswitch Jul 01 11:06:26 rma volumio[975]: info: Uninstalling plugin ampswitch Jul 01 11:06:26 rma volumio[975]: info: [ASDebug] Port: 24 Jul 01 11:06:26 rma volumio[975]: info: [ASDebug] Inverted: false Jul 01 11:06:26 rma volumio[975]: info: [ASDebug] Delay: 720 Jul 01 11:06:26 rma volumio[975]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 01 11:06:26 rma volumio[975]: Error: EBADF: bad file descriptor, close Jul 01 11:06:26 rma volumio[975]: at Object.closeSync (fs.js:439:3) Jul 01 11:06:26 rma volumio[975]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Jul 01 11:06:26 rma volumio[975]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Jul 01 11:06:26 rma volumio[975]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Jul 01 11:06:26 rma volumio[975]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Jul 01 11:06:26 rma volumio[975]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Jul 01 11:06:26 rma volumio[975]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jul 01 11:06:26 rma volumio[975]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Jul 01 11:06:26 rma volumio[975]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) Jul 01 11:06:26 rma volumio[975]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1260:45) Jul 01 11:06:26 rma volumio[975]: at Socket.emit (events.js:315:20) Jul 01 11:06:26 rma volumio[975]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 01 11:06:26 rma volumio[975]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jul 01 11:06:26 rma volumio[975]: errno: -9, Jul 01 11:06:26 rma volumio[975]: syscall: 'close', Jul 01 11:06:26 rma volumio[975]: code: 'EBADF' Jul 01 11:06:26 rma volumio[975]: } Jul 01 11:06:26 rma volumio[975]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 01 11:06:27 rma sudo[8577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-01 11:05 Jul 01 11:06:27 rma sudo[8577]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"