-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2025-06-19 23:11:47 CEST. -- Jun 19 23:10:10 volumio volumio[862]: info: Enabling plugin gpio-buttons Jun 19 23:10:10 volumio volumio[862]: info: Loading plugin "gpio-buttons"... Jun 19 23:10:10 volumio volumio[862]: info: GPIO-Buttons initialized Jun 19 23:10:10 volumio volumio[862]: info: PLUGIN START: gpio-buttons Jun 19 23:10:10 volumio volumio[862]: info: GPIO-Buttons: Reading config and creating triggers... Jun 19 23:10:10 volumio volumio[862]: info: GPIO-Buttons started Jun 19 23:10:10 volumio volumio[862]: info: Done. Jun 19 23:10:11 volumio volumio[862]: info: Enabling plugin gpio-buttons Jun 19 23:10:11 volumio volumio[862]: info: Loading plugin "gpio-buttons"... Jun 19 23:10:11 volumio volumio[862]: info: GPIO-Buttons initialized Jun 19 23:10:11 volumio volumio[862]: info: PLUGIN START: gpio-buttons Jun 19 23:10:11 volumio volumio[862]: info: GPIO-Buttons: Reading config and creating triggers... Jun 19 23:10:11 volumio volumio[862]: info: GPIO-Buttons started Jun 19 23:10:11 volumio volumio[862]: info: Done. Jun 19 23:10:12 volumio volumio[862]: info: Enabling plugin gpio-buttons Jun 19 23:10:12 volumio volumio[862]: info: Loading plugin "gpio-buttons"... Jun 19 23:10:12 volumio volumio[862]: info: GPIO-Buttons initialized Jun 19 23:10:12 volumio volumio[862]: info: PLUGIN START: gpio-buttons Jun 19 23:10:12 volumio volumio[862]: info: GPIO-Buttons: Reading config and creating triggers... Jun 19 23:10:12 volumio volumio[862]: info: GPIO-Buttons started Jun 19 23:10:12 volumio volumio[862]: info: Done. Jun 19 23:10:21 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 19 23:10:31 volumio volumio[862]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/gpio-buttons/0.8.4/volumio/buster/armhf Jun 19 23:10:32 volumio ntpd[925]: 194.50.19.117 local addr 192.168.2.146 -> Jun 19 23:10:33 volumio volumio[862]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/gpio-buttons/0.8.4/volumio/buster/armhf Jun 19 23:10:33 volumio volumio[862]: info: Folder /tmp/plugins removed Jun 19 23:10:33 volumio volumio[862]: info: Check plugin dependencies Jun 19 23:10:34 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 19 23:10:34 volumio volumio[862]: info: Checking if plugin already exists Jun 19 23:10:34 volumio volumio[862]: info: An error occurred installing the plugin. Rolling back config Jun 19 23:10:34 volumio volumio[862]: info: Plugin folders cleanup Jun 19 23:10:34 volumio volumio[862]: info: Scanning into folder /volumio/app/plugins/ Jun 19 23:10:34 volumio volumio[862]: info: Scanning category audio_interface Jun 19 23:10:34 volumio volumio[862]: info: Scanning category miscellanea Jun 19 23:10:34 volumio volumio[862]: info: Scanning category music_service Jun 19 23:10:34 volumio volumio[862]: info: Scanning category plugins.json Jun 19 23:10:34 volumio volumio[862]: info: Scanning category system_controller Jun 19 23:10:34 volumio volumio[862]: info: Scanning category user_interface Jun 19 23:10:34 volumio volumio[862]: info: Scanning into folder /data/plugins/ Jun 19 23:10:34 volumio volumio[862]: info: Scanning category system_hardware Jun 19 23:10:34 volumio volumio[862]: info: Scanning category user_interface Jun 19 23:10:34 volumio volumio[862]: info: Plugin folders cleanup completed Jun 19 23:10:34 volumio volumio[862]: info: Error: Error Jun 19 23:10:34 volumio volumio[862]: info: Folder /tmp/plugins removed Jun 19 23:10:34 volumio volumio[862]: info: Folder /tmp/downloaded_plugin.zip removed Jun 19 23:10:34 volumio volumio[862]: info: Folder /tmp/downloaded_plugin.zip removed Jun 19 23:10:34 volumio volumio[862]: info: Folder /data/temp removed Jun 19 23:10:58 volumio volumio[862]: info: CorePlayQueue::getTrack 13 Jun 19 23:10:58 volumio volumio[862]: info: CorePlayQueue::getTrack 14 Jun 19 23:10:58 volumio volumio[862]: info: Prefetching next song Jun 19 23:10:58 volumio volumio[862]: info: DOING PREFETCH IN MPD Jun 19 23:10:58 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Willie Nelson/Legend The Best of Willie Nelson/15 Willie Nelson - Highwayman.flac" Jun 19 23:10:58 volumio volumio[862]: info: sendMpdCommand add "INTERNAL/Willie Nelson/Legend The Best of Willie Nelson/15 Willie Nelson - Highwayman.flac" took 1 milliseconds Jun 19 23:10:58 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 19 23:10:58 volumio volumio[862]: info: Jun 19 23:10:58 volumio volumio[862]: ---------------------------- MPD announces system playlist update Jun 19 23:10:58 volumio volumio[862]: info: Ignoring MPD Status Update Jun 19 23:10:58 volumio volumio[862]: info: sendMpdCommand consume 1 took 2 milliseconds Jun 19 23:10:58 volumio volumio[862]: info: Jun 19 23:10:58 volumio volumio[862]: ---------------------------- MPD announces system playlist update Jun 19 23:10:58 volumio volumio[862]: info: Ignoring MPD Status Update Jun 19 23:10:58 volumio volumio[862]: info: Jun 19 23:10:58 volumio volumio[862]: ---------------------------- MPD announces system playlist update Jun 19 23:10:58 volumio volumio[862]: info: Ignoring MPD Status Update Jun 19 23:10:58 volumio volumio[862]: info: ------------------------------ 6ms Jun 19 23:10:58 volumio volumio[862]: info: ------------------------------ 4ms Jun 19 23:10:58 volumio volumio[862]: info: ------------------------------ 3ms Jun 19 23:11:02 volumio volumio[862]: info: Jun 19 23:11:02 volumio volumio[862]: ---------------------------- MPD announces system playlist update Jun 19 23:11:02 volumio volumio[862]: info: Ignoring MPD Status Update Jun 19 23:11:02 volumio volumio[862]: info: Jun 19 23:11:02 volumio volumio[862]: ---------------------------- MPD announces state update: player Jun 19 23:11:02 volumio volumio[862]: info: ControllerMpd::getState Jun 19 23:11:02 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Jun 19 23:11:02 volumio volumio[862]: info: Jun 19 23:11:02 volumio volumio[862]: ---------------------------- MPD announces system playlist update Jun 19 23:11:02 volumio volumio[862]: info: Ignoring MPD Status Update Jun 19 23:11:02 volumio volumio[862]: info: Jun 19 23:11:02 volumio volumio[862]: ---------------------------- MPD announces state update: player Jun 19 23:11:02 volumio volumio[862]: info: ControllerMpd::getState Jun 19 23:11:02 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Jun 19 23:11:02 volumio volumio[862]: info: Jun 19 23:11:02 volumio volumio[862]: ---------------------------- MPD announces system playlist update Jun 19 23:11:02 volumio volumio[862]: info: Ignoring MPD Status Update Jun 19 23:11:02 volumio volumio[862]: info: Jun 19 23:11:02 volumio volumio[862]: ---------------------------- MPD announces state update: player Jun 19 23:11:02 volumio volumio[862]: info: ControllerMpd::getState Jun 19 23:11:02 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Jun 19 23:11:02 volumio volumio[862]: info: ------------------------------ 14ms Jun 19 23:11:02 volumio volumio[862]: info: sendMpdCommand status took 10 milliseconds Jun 19 23:11:02 volumio volumio[862]: info: ------------------------------ 9ms Jun 19 23:11:02 volumio volumio[862]: info: sendMpdCommand status took 6 milliseconds Jun 19 23:11:02 volumio volumio[862]: info: ------------------------------ 6ms Jun 19 23:11:02 volumio volumio[862]: verbose: ControllerMpd::parseState Jun 19 23:11:02 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 23:11:02 volumio volumio[862]: verbose: ControllerMpd::parseState Jun 19 23:11:02 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 23:11:02 volumio volumio[862]: info: sendMpdCommand status took 5 milliseconds Jun 19 23:11:02 volumio volumio[862]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 19 23:11:02 volumio volumio[862]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 19 23:11:02 volumio volumio[862]: verbose: ControllerMpd::parseState Jun 19 23:11:02 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 23:11:02 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Jun 19 23:11:02 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Jun 19 23:11:02 volumio volumio[862]: info: ControllerMpd::pushState Jun 19 23:11:02 volumio volumio[862]: info: CoreCommandRouter::servicePushState Jun 19 23:11:02 volumio volumio[862]: info: CorePlayQueue::getTrack 13 Jun 19 23:11:02 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":185,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"686 Kbps","isStreaming":false,"title":"Highwayman","artist":"Willie Nelson","album":"Legend The Best of Willie Nelson","uri":"INTERNAL/Willie Nelson/Legend The Best of Willie Nelson/15 Willie Nelson - Highwayman.flac","trackType":"flac"} Jun 19 23:11:02 volumio volumio[862]: verbose: CURRENT POSITION 13 Jun 19 23:11:02 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Jun 19 23:11:02 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Jun 19 23:11:02 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Jun 19 23:11:02 volumio volumio[862]: info: CoreStateMachine::pushState Jun 19 23:11:02 volumio volumio[862]: info: CorePlayQueue::getTrack 13 Jun 19 23:11:02 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 23:11:02 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Jun 19 23:11:02 volumio volumio[862]: info: CoreStateMachine::pushState Jun 19 23:11:02 volumio volumio[862]: info: CorePlayQueue::getTrack 13 Jun 19 23:11:02 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Jun 19 23:11:02 volumio volumio[862]: info: ControllerMpd::pushState Jun 19 23:11:02 volumio volumio[862]: info: CoreCommandRouter::servicePushState Jun 19 23:11:02 volumio volumio[862]: info: CorePlayQueue::getTrack 13 Jun 19 23:11:02 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":185,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"686 Kbps","isStreaming":false,"title":"Highwayman","artist":"Willie Nelson","album":"Legend The Best of Willie Nelson","uri":"INTERNAL/Willie Nelson/Legend The Best of Willie Nelson/15 Willie Nelson - Highwayman.flac","trackType":"flac"} Jun 19 23:11:02 volumio volumio[862]: verbose: CURRENT POSITION 13 Jun 19 23:11:02 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Jun 19 23:11:02 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Jun 19 23:11:02 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Jun 19 23:11:02 volumio volumio[862]: info: CoreStateMachine::pushState Jun 19 23:11:02 volumio volumio[862]: info: CorePlayQueue::getTrack 13 Jun 19 23:11:02 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Jun 19 23:11:02 volumio volumio[862]: info: CoreStateMachine::pushState Jun 19 23:11:02 volumio volumio[862]: info: CorePlayQueue::getTrack 13 Jun 19 23:11:02 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Jun 19 23:11:02 volumio volumio[862]: info: ------------------------------ 60ms Jun 19 23:11:02 volumio volumio[862]: info: ------------------------------ 56ms Jun 19 23:11:02 volumio volumio[862]: info: CoreStateMachine::startPlaybackTimer Jun 19 23:11:02 volumio volumio[862]: info: CorePlayQueue::getTrack 14 Jun 19 23:11:02 volumio volumio[862]: info: sendMpdCommand playlistinfo took 47 milliseconds Jun 19 23:11:02 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Jun 19 23:11:02 volumio volumio[862]: info: ControllerMpd::pushState Jun 19 23:11:02 volumio volumio[862]: info: CoreCommandRouter::servicePushState Jun 19 23:11:02 volumio volumio[862]: info: CorePlayQueue::getTrack 14 Jun 19 23:11:02 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":185,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"686 Kbps","isStreaming":false,"title":"Highwayman","artist":"Willie Nelson","album":"Legend The Best of Willie Nelson","uri":"INTERNAL/Willie Nelson/Legend The Best of Willie Nelson/15 Willie Nelson - Highwayman.flac","trackType":"flac"} Jun 19 23:11:02 volumio volumio[862]: verbose: CURRENT POSITION 14 Jun 19 23:11:02 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Jun 19 23:11:02 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Jun 19 23:11:02 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Jun 19 23:11:02 volumio volumio[862]: info: CoreStateMachine::pushState Jun 19 23:11:02 volumio volumio[862]: info: CorePlayQueue::getTrack 14 Jun 19 23:11:02 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 23:11:02 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Jun 19 23:11:02 volumio volumio[862]: info: CoreStateMachine::pushState Jun 19 23:11:02 volumio volumio[862]: info: CorePlayQueue::getTrack 14 Jun 19 23:11:02 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Jun 19 23:11:02 volumio volumio[862]: info: ------------------------------ 80ms Jun 19 23:11:03 volumio volumio[862]: info: CoreStateMachine::pushState Jun 19 23:11:03 volumio volumio[862]: info: CorePlayQueue::getTrack 14 Jun 19 23:11:03 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 23:11:03 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Jun 19 23:11:09 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 19 23:11:14 volumio volumio[862]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/gpio-buttons/0.8.3/volumio/buster/armhf Jun 19 23:11:16 volumio volumio[862]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/gpio-buttons/0.8.3/volumio/buster/armhf Jun 19 23:11:16 volumio volumio[862]: info: Folder /tmp/plugins removed Jun 19 23:11:16 volumio volumio[862]: info: Check plugin dependencies Jun 19 23:11:16 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 19 23:11:16 volumio volumio[862]: info: Checking if plugin already exists Jun 19 23:11:16 volumio volumio[862]: info: An error occurred installing the plugin. Rolling back config Jun 19 23:11:16 volumio volumio[862]: info: Plugin folders cleanup Jun 19 23:11:16 volumio volumio[862]: info: Scanning into folder /volumio/app/plugins/ Jun 19 23:11:16 volumio volumio[862]: info: Scanning category audio_interface Jun 19 23:11:16 volumio volumio[862]: info: Scanning category miscellanea Jun 19 23:11:16 volumio volumio[862]: info: Scanning category music_service Jun 19 23:11:16 volumio volumio[862]: info: Scanning category plugins.json Jun 19 23:11:16 volumio volumio[862]: info: Scanning category system_controller Jun 19 23:11:16 volumio volumio[862]: info: Scanning category user_interface Jun 19 23:11:16 volumio volumio[862]: info: Scanning into folder /data/plugins/ Jun 19 23:11:16 volumio volumio[862]: info: Scanning category system_hardware Jun 19 23:11:16 volumio volumio[862]: info: Scanning category user_interface Jun 19 23:11:16 volumio volumio[862]: info: Plugin folders cleanup completed Jun 19 23:11:16 volumio volumio[862]: info: Error: Error Jun 19 23:11:16 volumio volumio[862]: info: Folder /tmp/plugins removed Jun 19 23:11:16 volumio volumio[862]: info: Folder /tmp/downloaded_plugin.zip removed Jun 19 23:11:16 volumio volumio[862]: info: Folder /tmp/downloaded_plugin.zip removed Jun 19 23:11:16 volumio volumio[862]: info: Folder /data/temp removed Jun 19 23:11:42 volumio volumio[862]: info: CoreCommandRouter::volumioGetState Jun 19 23:11:42 volumio volumio[862]: info: CorePlayQueue::getTrack 14 Jun 19 23:11:46 volumio ntpd[925]: 141.144.241.16 local addr 192.168.2.146 -> Jun 19 23:11:47 volumio volumio[862]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ Jun 19 23:11:47 volumio volumio[862]: info: PLUGIN onShutdown : networkfs Jun 19 23:11:47 volumio volumio[862]: info: PLUGIN onShutdown : audiophonicsonoff Jun 19 23:11:47 volumio volumio[862]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 23:11:47 volumio volumio[862]: TypeError: Cannot read property 'writeSync' of undefined Jun 19 23:11:47 volumio volumio[862]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_hardware/audiophonicsonoff/index.js:48:25) Jun 19 23:11:47 volumio volumio[862]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) Jun 19 23:11:47 volumio volumio[862]: at HashMap. (/volumio/app/pluginmanager.js:621:31) Jun 19 23:11:47 volumio volumio[862]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jun 19 23:11:47 volumio volumio[862]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jun 19 23:11:47 volumio volumio[862]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) Jun 19 23:11:47 volumio volumio[862]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1328:22) Jun 19 23:11:47 volumio volumio[862]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:859:33) Jun 19 23:11:47 volumio volumio[862]: at Socket.emit (events.js:315:20) Jun 19 23:11:47 volumio volumio[862]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 19 23:11:47 volumio volumio[862]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jun 19 23:11:47 volumio volumio[862]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 23:11:47 volumio sudo[5866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-19 23:10 Jun 19 23:11:47 volumio sudo[5866]: 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"