-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2026-05-30 18:08:57 CEST. -- May 30 18:07:00 volumio go-librespot[1314]: time="2026-05-30T18:07:00+02:00" level=trace msg="sent dealer ping" May 30 18:07:00 volumio go-librespot[1314]: time="2026-05-30T18:07:00+02:00" level=trace msg="received dealer pong" May 30 18:07:14 volumio go-librespot[1314]: time="2026-05-30T18:07:14+02:00" level=debug msg="fetched chunk 10/14, size: 524288" uri="spotify:track:4Tp6sO8uzu7RWrPO8wrEUw" May 30 18:07:29 volumio volumio5-onboarding[1247]: time=2026-05-30T18:07:29.496+02:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn May 30 18:07:30 volumio go-librespot[1314]: time="2026-05-30T18:07:30+02:00" level=trace msg="sent dealer ping" May 30 18:07:30 volumio go-librespot[1314]: time="2026-05-30T18:07:30+02:00" level=trace msg="received dealer pong" May 30 18:07:33 volumio volumio5-onboarding[1247]: time=2026-05-30T18:07:33.845+02:00 level=INFO msg="new address was allocated" component=ble/conn old=11 new=12 May 30 18:07:35 volumio volumio5-onboarding[1247]: time=2026-05-30T18:07:35.454+02:00 level=INFO msg="new address was allocated" component=ble/conn old=12 new=13 May 30 18:07:39 volumio volumio5-onboarding[1247]: time=2026-05-30T18:07:39.531+02:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn May 30 18:07:47 volumio go-librespot[1314]: time="2026-05-30T18:07:47+02:00" level=debug msg="fetched chunk 11/14, size: 524288" uri="spotify:track:4Tp6sO8uzu7RWrPO8wrEUw" May 30 18:08:00 volumio go-librespot[1314]: time="2026-05-30T18:08:00+02:00" level=trace msg="sent dealer ping" May 30 18:08:00 volumio go-librespot[1314]: time="2026-05-30T18:08:00+02:00" level=trace msg="received dealer pong" May 30 18:08:20 volumio go-librespot[1314]: time="2026-05-30T18:08:20+02:00" level=debug msg="fetched chunk 12/14, size: 524288" uri="spotify:track:4Tp6sO8uzu7RWrPO8wrEUw" May 30 18:08:29 volumio go-librespot[1314]: time="2026-05-30T18:08:29+02:00" level=trace msg="received accesspoint ping" May 30 18:08:29 volumio go-librespot[1314]: time="2026-05-30T18:08:29+02:00" level=trace msg="received accesspoint pong ack" May 30 18:08:30 volumio go-librespot[1314]: time="2026-05-30T18:08:30+02:00" level=trace msg="sent dealer ping" May 30 18:08:30 volumio go-librespot[1314]: time="2026-05-30T18:08:30+02:00" level=trace msg="received dealer pong" May 30 18:08:52 volumio go-librespot[1314]: time="2026-05-30T18:08:52+02:00" level=debug msg="fetched chunk 13/14, size: 524288" uri="spotify:track:4Tp6sO8uzu7RWrPO8wrEUw" May 30 18:08:56 volumio volumio[899]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ May 30 18:08:56 volumio volumio[899]: info: PLUGIN onShutdown : networkfs May 30 18:08:56 volumio volumio[899]: info: PLUGIN onShutdown : audiophonicsonoff May 30 18:08:56 volumio volumio[899]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 18:08:56 volumio volumio[899]: TypeError: Cannot read property 'writeSync' of undefined May 30 18:08:56 volumio volumio[899]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_controller/audiophonicsonoff/index.js:43:25) May 30 18:08:56 volumio volumio[899]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) May 30 18:08:56 volumio volumio[899]: at HashMap. (/volumio/app/pluginmanager.js:621:31) May 30 18:08:56 volumio volumio[899]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 30 18:08:56 volumio volumio[899]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 30 18:08:56 volumio volumio[899]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) May 30 18:08:56 volumio volumio[899]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1328:22) May 30 18:08:56 volumio volumio[899]: at ControllerAudiophonicsOnOff.hardShutdownRequest (/data/plugins/system_hardware/audiophonicsonoff/index.js:209:21) May 30 18:08:56 volumio volumio[899]: at /data/plugins/system_hardware/audiophonicsonoff/node_modules/onoff/onoff.js:135:9 May 30 18:08:56 volumio volumio[899]: at Array.forEach () May 30 18:08:56 volumio volumio[899]: at pollerEventHandler (/data/plugins/system_hardware/audiophonicsonoff/node_modules/onoff/onoff.js:134:32) May 30 18:08:56 volumio volumio[899]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 18:08:57 volumio sudo[14229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-30 18:07 May 30 18:08:57 volumio sudo[14229]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"