-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Mon 2025-12-15 21:26:06 UTC. -- Dec 15 21:25:18 volumio go-librespot[24600]: time="2025-12-15T21:25:18Z" level=trace msg="sent dealer ping" Dec 15 21:25:18 volumio go-librespot[24600]: time="2025-12-15T21:25:18Z" level=trace msg="received dealer pong" Dec 15 21:25:18 volumio go-librespot[24600]: time="2025-12-15T21:25:18Z" level=trace msg="received accesspoint ping" Dec 15 21:25:18 volumio go-librespot[24600]: time="2025-12-15T21:25:18Z" level=trace msg="received accesspoint pong ack" Dec 15 21:25:40 volumio sudo[26022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 21:25:40 volumio sudo[26022]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 15 21:25:40 volumio sudo[26022]: pam_unix(sudo:session): session closed for user root Dec 15 21:25:40 volumio sudo[26025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 21:25:40 volumio sudo[26025]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 15 21:25:40 volumio sudo[26025]: pam_unix(sudo:session): session closed for user root Dec 15 21:25:40 volumio volumio[758]: verbose: New Socket.io Connection to 192.168.1.18 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Mobile Safari/537.36 Total Clients: 5 Dec 15 21:25:40 volumio volumio[758]: info: CoreCommandRouter::volumioGetState Dec 15 21:25:40 volumio volumio[758]: info: CorePlayQueue::getTrack 6 Dec 15 21:25:40 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 15 21:25:40 volumio volumio[758]: info: Listing playlists Dec 15 21:25:40 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 15 21:25:40 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 15 21:25:40 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 15 21:25:40 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 15 21:25:42 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 15 21:25:43 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 15 21:25:45 volumio volumio[758]: info: CoreCommandRouter::volumioPause Dec 15 21:25:45 volumio volumio[758]: info: CoreStateMachine::pause Dec 15 21:25:45 volumio volumio[758]: info: CoreStateMachine::stPlaybackTimer Dec 15 21:25:45 volumio volumio[758]: info: CoreStateMachine::servicePause Dec 15 21:25:45 volumio volumio[758]: info: CorePlayQueue::getTrack 6 Dec 15 21:25:45 volumio volumio[758]: info: CoreCommandRouter::servicePause Dec 15 21:25:45 volumio volumio[758]: info: Spotify Received pause Dec 15 21:25:45 volumio volumio[758]: SPOTIFY: SPOTIFY PAUSE Dec 15 21:25:45 volumio volumio[758]: SPOTIFY: {"status":"play","position":6,"title":"Go Tell It On The Mountain","artist":"Toby Keith","album":"Toby Keith: A Classic Christmas","albumart":"https://i.scdn.co/image/ab67616d0000b273b25a523de5048716bca6073b","uri":"spotify:track:1Pxkl1tV6Ll7hGgGkyoG09","trackType":"spotify","seek":147392,"duration":197,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"repeat":true,"repeatSingle":false,"consume":false,"volume":39,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Dec 15 21:25:45 volumio volumio[758]: info: Sending Spotify command to local API: /player/pause Dec 15 21:25:47 volumio volumio[758]: info: CoreCommandRouter::volumioPause Dec 15 21:25:47 volumio volumio[758]: info: CoreStateMachine::pause Dec 15 21:25:48 volumio go-librespot[24600]: time="2025-12-15T21:25:48Z" level=trace msg="sent dealer ping" Dec 15 21:25:48 volumio go-librespot[24600]: time="2025-12-15T21:25:48Z" level=trace msg="received dealer pong" Dec 15 21:25:59 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 15 21:25:59 volumio volumio[758]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 15 21:25:59 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 15 21:25:59 volumio volumio[758]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Dec 15 21:25:59 volumio volumio[758]: info: Received Get System Version Dec 15 21:25:59 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 21:25:59 volumio volumio[758]: info: Received Get System Info Dec 15 21:25:59 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 21:25:59 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 21:25:59 volumio volumio[758]: info: Discovery: Getting this device information Dec 15 21:25:59 volumio volumio[758]: info: CoreCommandRouter::volumioGetState Dec 15 21:25:59 volumio volumio[758]: info: CorePlayQueue::getTrack 6 Dec 15 21:25:59 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 21:26:00 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 15 21:26:00 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 15 21:26:04 volumio volumio[758]: info: [ASDebug] Port: 26 Dec 15 21:26:04 volumio volumio[758]: info: [ASDebug] Inverted: true Dec 15 21:26:04 volumio volumio[758]: info: [ASDebug] Delay: 30000 Dec 15 21:26:06 volumio volumio[758]: info: [ASDebug] Port: 26 Dec 15 21:26:06 volumio volumio[758]: info: [ASDebug] Inverted: true Dec 15 21:26:06 volumio volumio[758]: info: [ASDebug] Delay: 30000 Dec 15 21:26:06 volumio volumio[758]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 21:26:06 volumio volumio[758]: Error: EBADF: bad file descriptor, close Dec 15 21:26:06 volumio volumio[758]: at Object.closeSync (fs.js:439:3) Dec 15 21:26:06 volumio volumio[758]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Dec 15 21:26:06 volumio volumio[758]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Dec 15 21:26:06 volumio volumio[758]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Dec 15 21:26:06 volumio volumio[758]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Dec 15 21:26:06 volumio volumio[758]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Dec 15 21:26:06 volumio volumio[758]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Dec 15 21:26:06 volumio volumio[758]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1869:8) Dec 15 21:26:06 volumio volumio[758]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1498:29) Dec 15 21:26:06 volumio volumio[758]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1264:47) Dec 15 21:26:06 volumio volumio[758]: at Socket.emit (events.js:315:20) Dec 15 21:26:06 volumio volumio[758]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 15 21:26:06 volumio volumio[758]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Dec 15 21:26:06 volumio volumio[758]: errno: -9, Dec 15 21:26:06 volumio volumio[758]: syscall: 'close', Dec 15 21:26:06 volumio volumio[758]: code: 'EBADF' Dec 15 21:26:06 volumio volumio[758]: } Dec 15 21:26:06 volumio volumio[758]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 21:26:06 volumio sudo[26047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-15 21:25 Dec 15 21:26:06 volumio sudo[26047]: 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="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="85acf7bbbceb3e3dec447156a39a88033f921210" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 30 Sep 2022 10:43:40 AM CEST" VOLUMIO_VERSION="3.378" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4bc817297375b72097a1fade15c6518b"