-- Logs begin at Sun 2025-12-28 22:52:40 MSK, end at Sun 2025-12-28 23:22:01 MSK. -- Dec 28 23:21:00 volumio volumio[1165]: info: Dec 28 23:21:00 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Dec 28 23:21:00 volumio volumio[1165]: info: Ignoring MPD Status Update Dec 28 23:21:00 volumio volumio[1165]: info: Dec 28 23:21:00 volumio volumio[1165]: ---------------------------- MPD announces state update: player Dec 28 23:21:00 volumio volumio[1165]: info: ControllerMpd::getState Dec 28 23:21:00 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:21:00 volumio volumio[1165]: info: Dec 28 23:21:00 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Dec 28 23:21:00 volumio volumio[1165]: info: Ignoring MPD Status Update Dec 28 23:21:00 volumio volumio[1165]: info: Dec 28 23:21:00 volumio volumio[1165]: ---------------------------- MPD announces state update: player Dec 28 23:21:00 volumio volumio[1165]: info: ControllerMpd::getState Dec 28 23:21:00 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:21:00 volumio volumio[1165]: info: Dec 28 23:21:00 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Dec 28 23:21:00 volumio volumio[1165]: info: Ignoring MPD Status Update Dec 28 23:21:00 volumio volumio[1165]: info: Dec 28 23:21:00 volumio volumio[1165]: ---------------------------- MPD announces state update: player Dec 28 23:21:00 volumio volumio[1165]: info: ControllerMpd::getState Dec 28 23:21:00 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:21:00 volumio volumio[1165]: info: Dec 28 23:21:00 volumio volumio[1165]: ---------------------------- MPD announces system playlist update Dec 28 23:21:00 volumio volumio[1165]: info: Ignoring MPD Status Update Dec 28 23:21:00 volumio volumio[1165]: info: Dec 28 23:21:00 volumio volumio[1165]: ---------------------------- MPD announces state update: player Dec 28 23:21:00 volumio volumio[1165]: info: ControllerMpd::getState Dec 28 23:21:00 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:21:00 volumio volumio[1165]: info: ------------------------------ 7ms Dec 28 23:21:00 volumio volumio[1165]: info: sendMpdCommand status took 5 milliseconds Dec 28 23:21:00 volumio volumio[1165]: info: ------------------------------ 4ms Dec 28 23:21:00 volumio volumio[1165]: info: sendMpdCommand status took 4 milliseconds Dec 28 23:21:00 volumio volumio[1165]: info: ------------------------------ 4ms Dec 28 23:21:00 volumio volumio[1165]: info: sendMpdCommand status took 4 milliseconds Dec 28 23:21:00 volumio volumio[1165]: info: ------------------------------ 3ms Dec 28 23:21:00 volumio volumio[1165]: verbose: ControllerMpd::parseState Dec 28 23:21:00 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:21:00 volumio volumio[1165]: verbose: ControllerMpd::parseState Dec 28 23:21:00 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:21:00 volumio volumio[1165]: verbose: ControllerMpd::parseState Dec 28 23:21:00 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:21:00 volumio volumio[1165]: info: sendMpdCommand status took 4 milliseconds Dec 28 23:21:00 volumio volumio[1165]: verbose: ControllerMpd::parseState Dec 28 23:21:00 volumio volumio[1165]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:21:00 volumio volumio[1165]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 28 23:21:00 volumio volumio[1165]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 28 23:21:00 volumio volumio[1165]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:21:00 volumio volumio[1165]: info: ControllerMpd::pushState Dec 28 23:21:00 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Dec 28 23:21:00 volumio volumio[1165]: info: CorePlayQueue::getTrack 4 Dec 28 23:21:00 volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":286,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"That's The Way She Loves","artist":"Aaron Neville","album":"Warm Your Heart","uri":"USB/EXTERNAL_USB/DSD Pop & Rock/Aaron Neville - Warm Your Heart (1991) [DSD]/06 That's The Way She Loves.dsf","trackType":"dsf"} Dec 28 23:21:00 volumio volumio[1165]: verbose: CURRENT POSITION 4 Dec 28 23:21:00 volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Dec 28 23:21:00 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:21:00 volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Dec 28 23:21:00 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 28 23:21:00 volumio volumio[1165]: info: CorePlayQueue::getTrack 4 Dec 28 23:21:00 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:21:00 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 28 23:21:00 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 28 23:21:00 volumio volumio[1165]: info: CorePlayQueue::getTrack 4 Dec 28 23:21:00 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 28 23:21:00 volumio volumio[1165]: info: ControllerMpd::pushState Dec 28 23:21:00 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Dec 28 23:21:00 volumio volumio[1165]: info: CorePlayQueue::getTrack 4 Dec 28 23:21:00 volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":286,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"That's The Way She Loves","artist":"Aaron Neville","album":"Warm Your Heart","uri":"USB/EXTERNAL_USB/DSD Pop & Rock/Aaron Neville - Warm Your Heart (1991) [DSD]/06 That's The Way She Loves.dsf","trackType":"dsf"} Dec 28 23:21:00 volumio volumio[1165]: verbose: CURRENT POSITION 4 Dec 28 23:21:00 volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Dec 28 23:21:00 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:21:00 volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Dec 28 23:21:00 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 28 23:21:00 volumio volumio[1165]: info: CorePlayQueue::getTrack 4 Dec 28 23:21:00 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 28 23:21:00 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 28 23:21:00 volumio volumio[1165]: info: CorePlayQueue::getTrack 4 Dec 28 23:21:00 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 28 23:21:00 volumio volumio[1165]: info: ControllerMpd::pushState Dec 28 23:21:00 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Dec 28 23:21:00 volumio volumio[1165]: info: CorePlayQueue::getTrack 4 Dec 28 23:21:00 volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":286,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"That's The Way She Loves","artist":"Aaron Neville","album":"Warm Your Heart","uri":"USB/EXTERNAL_USB/DSD Pop & Rock/Aaron Neville - Warm Your Heart (1991) [DSD]/06 That's The Way She Loves.dsf","trackType":"dsf"} Dec 28 23:21:00 volumio volumio[1165]: verbose: CURRENT POSITION 4 Dec 28 23:21:00 volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Dec 28 23:21:00 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:21:00 volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Dec 28 23:21:00 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 28 23:21:00 volumio volumio[1165]: info: CorePlayQueue::getTrack 4 Dec 28 23:21:00 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 28 23:21:00 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 28 23:21:00 volumio volumio[1165]: info: CorePlayQueue::getTrack 4 Dec 28 23:21:00 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 28 23:21:00 volumio volumio[1165]: info: ControllerMpd::pushState Dec 28 23:21:00 volumio volumio[1165]: info: CoreCommandRouter::servicePushState Dec 28 23:21:00 volumio volumio[1165]: info: CorePlayQueue::getTrack 4 Dec 28 23:21:00 volumio volumio[1165]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":286,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"That's The Way She Loves","artist":"Aaron Neville","album":"Warm Your Heart","uri":"USB/EXTERNAL_USB/DSD Pop & Rock/Aaron Neville - Warm Your Heart (1991) [DSD]/06 That's The Way She Loves.dsf","trackType":"dsf"} Dec 28 23:21:00 volumio volumio[1165]: verbose: CURRENT POSITION 4 Dec 28 23:21:00 volumio volumio[1165]: info: CoreStateMachine::syncState stateService play Dec 28 23:21:00 volumio volumio[1165]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:21:00 volumio volumio[1165]: info: Received an update from plugin. extracting info from payload Dec 28 23:21:00 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 28 23:21:00 volumio volumio[1165]: info: CorePlayQueue::getTrack 4 Dec 28 23:21:00 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 28 23:21:00 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 28 23:21:00 volumio volumio[1165]: info: CorePlayQueue::getTrack 4 Dec 28 23:21:00 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 28 23:21:00 volumio volumio[1165]: info: ------------------------------ 45ms Dec 28 23:21:00 volumio volumio[1165]: info: ------------------------------ 43ms Dec 28 23:21:00 volumio volumio[1165]: info: ------------------------------ 43ms Dec 28 23:21:00 volumio volumio[1165]: info: ------------------------------ 43ms Dec 28 23:21:00 volumio volumio[1165]: info: CoreStateMachine::startPlaybackTimer Dec 28 23:21:00 volumio volumio[1165]: info: CorePlayQueue::getTrack 5 Dec 28 23:21:00 volumio volumio[1165]: info: CoreStateMachine::pushState Dec 28 23:21:00 volumio volumio[1165]: info: CorePlayQueue::getTrack 5 Dec 28 23:21:00 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:21:00 volumio volumio[1165]: info: CoreCommandRouter::volumioPushState Dec 28 23:21:04 volumio volumio[1165]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/mpdoutput/volumio/buster/amd64 Dec 28 23:21:06 volumio volumio[1165]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/mpdoutput/volumio/buster/amd64 Dec 28 23:21:06 volumio volumio[1165]: info: Folder /tmp/plugins removed Dec 28 23:21:06 volumio volumio[1165]: info: Check plugin dependencies Dec 28 23:21:06 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 23:21:06 volumio volumio[1165]: info: Checking if plugin already exists Dec 28 23:21:06 volumio volumio[1165]: info: Rename folder Dec 28 23:21:06 volumio volumio[1165]: info: Folder /tmp/downloaded_plugin.zip removed Dec 28 23:21:06 volumio volumio[1165]: info: Move to category Dec 28 23:21:07 volumio volumio[1165]: info: Checking if install.sh is present Dec 28 23:21:07 volumio volumio[1165]: info: Executing install.sh Dec 28 23:21:07 volumio sudo[5098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/mpdoutput/install.sh Dec 28 23:21:07 volumio sudo[5098]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 23:21:07 volumio sudo[5098]: pam_unix(sudo:session): session closed for user root Dec 28 23:21:07 volumio volumio[1165]: info: Installing mpd http output Dec 28 23:21:07 volumio volumio[1165]: Plugin install end detected on script Dec 28 23:21:07 volumio volumio[1165]: info: Install script completed Dec 28 23:21:07 volumio volumio[1165]: info: Adding reference to registry Dec 28 23:21:07 volumio volumio[1165]: info: Done installing plugin. Dec 28 23:21:07 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 23:21:07 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 28 23:21:07 volumio volumio[1165]: info: Folder /tmp/plugins removed Dec 28 23:21:07 volumio volumio[1165]: info: Folder /tmp/downloaded_plugin.zip removed Dec 28 23:21:07 volumio volumio[1165]: info: Folder /data/temp removed Dec 28 23:21:09 volumio volumio[1165]: info: Enabling plugin mpdoutput Dec 28 23:21:09 volumio volumio[1165]: info: Loading plugin "mpdoutput"... Dec 28 23:21:09 volumio volumio[1165]: info: PLUGIN START: mpdoutput Dec 28 23:21:09 volumio volumio[1165]: info: Done. Dec 28 23:21:47 volumio volumio[1165]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/autostart/volumio/buster/amd64 Dec 28 23:21:48 volumio volumio[1165]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/autostart/volumio/buster/amd64 Dec 28 23:21:48 volumio volumio[1165]: info: Folder /tmp/plugins removed Dec 28 23:21:49 volumio volumio[1165]: info: Check plugin dependencies Dec 28 23:21:49 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 23:21:49 volumio volumio[1165]: info: Checking if plugin already exists Dec 28 23:21:49 volumio volumio[1165]: info: Rename folder Dec 28 23:21:49 volumio volumio[1165]: info: Folder /tmp/downloaded_plugin.zip removed Dec 28 23:21:49 volumio volumio[1165]: info: Move to category Dec 28 23:21:49 volumio volumio[1165]: info: Checking if install.sh is present Dec 28 23:21:49 volumio volumio[1165]: info: Executing install.sh Dec 28 23:21:49 volumio sudo[5264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_controller/autostart/install.sh Dec 28 23:21:49 volumio sudo[5264]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 23:21:49 volumio sudo[5264]: pam_unix(sudo:session): session closed for user root Dec 28 23:21:49 volumio volumio[1165]: info: Installing autostart Dependencies - no special dependencies needed Dec 28 23:21:49 volumio volumio[1165]: Plugin install end detected on script Dec 28 23:21:49 volumio volumio[1165]: info: Install script completed Dec 28 23:21:49 volumio volumio[1165]: info: Adding reference to registry Dec 28 23:21:49 volumio volumio[1165]: info: Done installing plugin. Dec 28 23:21:49 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 23:21:49 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 28 23:21:49 volumio volumio[1165]: info: Folder /tmp/plugins removed Dec 28 23:21:49 volumio volumio[1165]: info: Folder /tmp/downloaded_plugin.zip removed Dec 28 23:21:49 volumio volumio[1165]: info: Folder /data/temp removed Dec 28 23:21:50 volumio volumio[1165]: info: Enabling plugin autostart Dec 28 23:21:50 volumio volumio[1165]: info: Loading plugin "autostart"... Dec 28 23:21:50 volumio volumio[1165]: info: AutoStart - onVolumioStart - read config.json Dec 28 23:21:50 volumio volumio[1165]: info: PLUGIN START: autostart Dec 28 23:21:50 volumio volumio[1165]: info: AutoStart - onStart Dec 28 23:21:50 volumio volumio[1165]: info: Done. Dec 28 23:21:54 volumio volumio[1165]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 Dec 28 23:22:01 volumio volumio[1165]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 Dec 28 23:22:01 volumio volumio[1165]: info: FusionDsp - Stopping FusionDsp service Dec 28 23:22:01 volumio volumio[1165]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 23:22:01 volumio volumio[1165]: TypeError: Cannot read property 'stop' of null Dec 28 23:22:01 volumio volumio[1165]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Dec 28 23:22:01 volumio volumio[1165]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Dec 28 23:22:01 volumio volumio[1165]: at /volumio/app/pluginmanager.js:956:12 Dec 28 23:22:01 volumio volumio[1165]: at ChildProcess.exithandler (child_process.js:374:7) Dec 28 23:22:01 volumio volumio[1165]: at ChildProcess.emit (events.js:400:28) Dec 28 23:22:01 volumio volumio[1165]: at maybeClose (internal/child_process.js:1088:16) Dec 28 23:22:01 volumio volumio[1165]: at Socket. (internal/child_process.js:446:11) Dec 28 23:22:01 volumio volumio[1165]: at Socket.emit (events.js:400:28) Dec 28 23:22:01 volumio volumio[1165]: at Pipe. (net.js:686:12) Dec 28 23:22:01 volumio volumio[1165]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 23:22:01 volumio sudo[5378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-28 23:21 Dec 28 23:22:01 volumio sudo[5378]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:18:30 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="33f9369b544b4488ff4979d2ee3813a5"