-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2025-06-08 21:11:22 CEST. -- Jun 08 21:10:26 volumio go-librespot[1298]: time="2025-06-08T21:10:26+02:00" level=trace msg="sent dealer ping" Jun 08 21:10:26 volumio go-librespot[1298]: time="2025-06-08T21:10:26+02:00" level=trace msg="received dealer pong" Jun 08 21:10:56 volumio go-librespot[1298]: time="2025-06-08T21:10:56+02:00" level=trace msg="sent dealer ping" Jun 08 21:10:56 volumio go-librespot[1298]: time="2025-06-08T21:10:56+02:00" level=trace msg="received dealer pong" Jun 08 21:11:17 volumio volumio[847]: info: CoreCommandRouter::volumioPause Jun 08 21:11:17 volumio volumio[847]: info: CoreStateMachine::pause Jun 08 21:11:17 volumio volumio[847]: info: CoreStateMachine::stPlaybackTimer Jun 08 21:11:17 volumio volumio[847]: info: CoreStateMachine::servicePause Jun 08 21:11:17 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 08 21:11:17 volumio volumio[847]: info: CoreCommandRouter::servicePause Jun 08 21:11:17 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand pause Jun 08 21:11:17 volumio volumio[847]: info: sendMpdCommand pause took 3 milliseconds Jun 08 21:11:17 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 08 21:11:17 volumio volumio[847]: info: CoreCommandRouter::servicePushState Jun 08 21:11:17 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 08 21:11:17 volumio volumio[847]: verbose: STATE SERVICE {"status":"pause","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000002KAT.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Astral Weeks","title":"Astral Weeks","artist":"Van Morrison","album":"Astral Weeks","streaming":true,"disableUiControls":true,"duration":416,"seek":239965,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jun 08 21:11:17 volumio volumio[847]: verbose: CURRENT POSITION 0 Jun 08 21:11:17 volumio volumio[847]: info: CoreStateMachine::syncState stateService pause Jun 08 21:11:17 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus pause Jun 08 21:11:17 volumio volumio[847]: info: CoreStateMachine::pushState Jun 08 21:11:17 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 08 21:11:17 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 21:11:17 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Jun 08 21:11:17 volumio volumio[847]: info: MRS: Pushing multiroomSync output update for this device Jun 08 21:11:17 volumio volumio[847]: info: MRS: Pushing multiroomSync output Jun 08 21:11:17 volumio volumio[847]: info: CoreCommandRouter::volumioGetState Jun 08 21:11:17 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 08 21:11:17 volumio volumio[847]: info: CoreStateMachine::stPlaybackTimer Jun 08 21:11:17 volumio volumio[847]: info: Jun 08 21:11:17 volumio volumio[847]: ---------------------------- MPD announces state update: player Jun 08 21:11:17 volumio volumio[847]: info: ControllerMpd::getState Jun 08 21:11:17 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Jun 08 21:11:17 volumio volumio[847]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 08 21:11:17 volumio volumio[847]: info: sendMpdCommand status took 9 milliseconds Jun 08 21:11:17 volumio volumio[847]: verbose: ControllerMpd::parseState Jun 08 21:11:17 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 21:11:17 volumio volumio[847]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 08 21:11:17 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo Jun 08 21:11:17 volumio volumio[847]: info: ControllerMpd::pushState Jun 08 21:11:17 volumio volumio[847]: info: CoreCommandRouter::servicePushState Jun 08 21:11:17 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Jun 08 21:11:17 volumio volumio[847]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 08 21:11:17 volumio volumio[847]: info: ------------------------------ 14ms Jun 08 21:11:21 volumio volumio[847]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ Jun 08 21:11:21 volumio volumio[847]: info: PLUGIN onShutdown : networkfs Jun 08 21:11:21 volumio volumio[847]: info: PLUGIN onShutdown : audiophonicsonoff Jun 08 21:11:21 volumio volumio[847]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 21:11:21 volumio volumio[847]: Error: EBADF: bad file descriptor, write Jun 08 21:11:21 volumio volumio[847]: at Object.writeSync (fs.js:711:3) Jun 08 21:11:21 volumio volumio[847]: at Gpio.writeSync (/data/plugins/system_hardware/audiophonicsonoff/node_modules/onoff/onoff.js:243:8) Jun 08 21:11:21 volumio volumio[847]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_hardware/audiophonicsonoff/index.js:48:25) Jun 08 21:11:21 volumio volumio[847]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) Jun 08 21:11:21 volumio volumio[847]: at HashMap. (/volumio/app/pluginmanager.js:621:31) Jun 08 21:11:21 volumio volumio[847]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jun 08 21:11:21 volumio volumio[847]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jun 08 21:11:21 volumio volumio[847]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) Jun 08 21:11:21 volumio volumio[847]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1328:22) Jun 08 21:11:21 volumio volumio[847]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:859:33) Jun 08 21:11:21 volumio volumio[847]: at Socket.emit (events.js:315:20) Jun 08 21:11:21 volumio volumio[847]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 08 21:11:21 volumio volumio[847]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jun 08 21:11:21 volumio volumio[847]: errno: -9, Jun 08 21:11:21 volumio volumio[847]: syscall: 'write', Jun 08 21:11:21 volumio volumio[847]: code: 'EBADF' Jun 08 21:11:21 volumio volumio[847]: } Jun 08 21:11:21 volumio volumio[847]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 21:11:22 volumio sudo[19649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-08 21:10 Jun 08 21:11:22 volumio sudo[19649]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"