Aug 28 16:01:10 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 387. Aug 28 16:01:10 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 16:01:10 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 16:01:10 volumio upmpdcli[12090]: Could not open config: /tmp/upmpdcli.conf Aug 28 16:01:10 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 28 16:01:10 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 28 16:01:25 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 388. Aug 28 16:01:25 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 16:01:26 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 16:01:26 volumio upmpdcli[12107]: Could not open config: /tmp/upmpdcli.conf Aug 28 16:01:26 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 28 16:01:26 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 28 16:01:26 volumio volumio[1223]: info: CorePlayQueue::getTrack 12 Aug 28 16:01:26 volumio volumio[1223]: info: CorePlayQueue::getTrack 13 Aug 28 16:01:26 volumio volumio[1223]: info: Prefetching next song Aug 28 16:01:31 volumio volumio[1223]: info: CoreStateMachine::startPlaybackTimer Aug 28 16:01:31 volumio volumio[1223]: info: CorePlayQueue::getTrack 13 Aug 28 16:01:31 volumio volumio[1223]: info: CoreStateMachine::pushState Aug 28 16:01:31 volumio volumio[1223]: info: CorePlayQueue::getTrack 13 Aug 28 16:01:31 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 16:01:31 volumio volumio[1223]: info: CoreCommandRouter::volumioPushState Aug 28 16:01:31 volumio volumio[1223]: info: FusionDsp - Volumio is playing Aug 28 16:01:34 volumio volumio[1223]: info: Aug 28 16:01:34 volumio volumio[1223]: ---------------------------- MPD announces system playlist update Aug 28 16:01:34 volumio volumio[1223]: info: Ignoring MPD Status Update Aug 28 16:01:34 volumio volumio[1223]: info: ControllerMpd::getState Aug 28 16:01:34 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Aug 28 16:01:34 volumio volumio[1223]: info: Aug 28 16:01:34 volumio volumio[1223]: ---------------------------- MPD announces state update: player Aug 28 16:01:34 volumio volumio[1223]: info: ControllerMpd::getState Aug 28 16:01:34 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Aug 28 16:01:34 volumio volumio[1223]: error: updateQueue error: null Aug 28 16:01:34 volumio volumio[1223]: info: sendMpdCommand status took 2 milliseconds Aug 28 16:01:34 volumio volumio[1223]: info: ------------------------------ 3ms Aug 28 16:01:34 volumio volumio[1223]: info: sendMpdCommand status took 1 milliseconds Aug 28 16:01:34 volumio volumio[1223]: verbose: ControllerMpd::parseState Aug 28 16:01:34 volumio volumio[1223]: verbose: ControllerMpd::parseState Aug 28 16:01:34 volumio volumio[1223]: info: ControllerMpd::pushState Aug 28 16:01:34 volumio volumio[1223]: info: CoreCommandRouter::servicePushState Aug 28 16:01:34 volumio volumio[1223]: info: CoreStateMachine::pushState Aug 28 16:01:34 volumio volumio[1223]: info: CorePlayQueue::getTrack 13 Aug 28 16:01:34 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 16:01:34 volumio volumio[1223]: info: CoreCommandRouter::volumioPushState Aug 28 16:01:34 volumio volumio[1223]: info: CorePlayQueue::getTrack 13 Aug 28 16:01:34 volumio volumio[1223]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 28 16:01:34 volumio volumio[1223]: verbose: CURRENT POSITION 13 Aug 28 16:01:34 volumio volumio[1223]: info: CoreStateMachine::syncState stateService stop Aug 28 16:01:34 volumio volumio[1223]: info: CoreStateMachine::syncState currentStatus play Aug 28 16:01:34 volumio volumio[1223]: info: CoreStateMachine::play index undefined Aug 28 16:01:34 volumio volumio[1223]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 16:01:34 volumio volumio[1223]: info: CoreStateMachine::pushState Aug 28 16:01:34 volumio volumio[1223]: info: CorePlayQueue::getTrack 14 Aug 28 16:01:34 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 16:01:34 volumio volumio[1223]: info: CoreCommandRouter::volumioPushState Aug 28 16:01:34 volumio volumio[1223]: info: CorePlayQueue::getTrack 14 Aug 28 16:01:34 volumio volumio[1223]: info: CoreStateMachine::startPlaybackTimer Aug 28 16:01:34 volumio volumio[1223]: info: CorePlayQueue::getTrack 14 Aug 28 16:01:34 volumio volumio[1223]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/song@songId=e48a3c9696032e710374af0b5035bcfa Aug 28 16:01:34 volumio volumio[1223]: info: ------------------------------ 8ms Aug 28 16:01:34 volumio volumio[1223]: info: CoreStateMachine::pushState Aug 28 16:01:34 volumio volumio[1223]: info: CorePlayQueue::getTrack 14 Aug 28 16:01:34 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 16:01:34 volumio volumio[1223]: info: CoreCommandRouter::volumioPushState Aug 28 16:01:34 volumio volumio[1223]: info: FusionDsp - Volumio is playing Aug 28 16:01:34 volumio volumio[1223]: info: FusionDsp - Volumio is not playing Aug 28 16:01:34 volumio volumio[1223]: info: FusionDsp - Clipped samples monitor stopped Aug 28 16:01:34 volumio volumio[1223]: info: FusionDsp - Volumio is not playing Aug 28 16:01:34 volumio volumio[1223]: info: FusionDsp - Clipped samples monitor stopped Aug 28 16:01:34 volumio volumio[1223]: info: camilladsp respawn in 100 ms (attempt 1/10) Aug 28 16:01:41 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 389. Aug 28 16:01:41 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 16:01:41 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 16:01:41 volumio upmpdcli[12140]: Could not open config: /tmp/upmpdcli.conf Aug 28 16:01:41 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 28 16:01:41 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 28 16:01:56 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 390. Aug 28 16:01:56 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 16:01:56 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 16:01:56 volumio upmpdcli[12157]: Could not open config: /tmp/upmpdcli.conf Aug 28 16:01:56 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 28 16:01:56 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 28 16:02:03 volumio volumio[1223]: info: [jellyfin-play] Stream URL for No ordinary love: http://192.168.178.20:8096/Audio/1604520d6501d14bcb1effec1751be23/stream.flac?static=true&mediaSourceId=1604520d6501d14bcb1effec1751be23&tag=a86df2ab9b4dfaa538990132e3d46078 Aug 28 16:02:03 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand addid "http://192.168.178.20:8096/Audio/1604520d6501d14bcb1effec1751be23/stream.flac?static=true&mediaSourceId=1604520d6501d14bcb1effec1751be23&tag=a86df2ab9b4dfaa538990132e3d46078&t.flac" Aug 28 16:02:03 volumio volumio[1223]: info: Aug 28 16:02:03 volumio volumio[1223]: ---------------------------- MPD announces system playlist update Aug 28 16:02:03 volumio volumio[1223]: info: Ignoring MPD Status Update Aug 28 16:02:03 volumio volumio[1223]: info: sendMpdCommand addid "http://192.168.178.20:8096/Audio/1604520d6501d14bcb1effec1751be23/stream.flac?static=true&mediaSourceId=1604520d6501d14bcb1effec1751be23&tag=a86df2ab9b4dfaa538990132e3d46078&t.flac" took 1 milliseconds Aug 28 16:02:03 volumio volumio[1223]: verbose: MPD COMMAND [object Object] Aug 28 16:02:03 volumio volumio[1223]: verbose: MPD COMMAND [object Object] Aug 28 16:02:03 volumio volumio[1223]: verbose: MPD COMMAND [object Object] Aug 28 16:02:03 volumio volumio[1223]: info: Aug 28 16:02:03 volumio volumio[1223]: ---------------------------- MPD announces system playlist update Aug 28 16:02:03 volumio volumio[1223]: info: Ignoring MPD Status Update Aug 28 16:02:03 volumio volumio[1223]: info: ------------------------------ 1ms Aug 28 16:02:03 volumio volumio[1223]: info: [jellyfin-play] Prefetched and added song to MPD queue: No ordinary love Aug 28 16:02:03 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 28 16:02:03 volumio volumio[1223]: info: ------------------------------ 2ms Aug 28 16:02:03 volumio volumio[1223]: info: sendMpdCommand consume 1 took 1 milliseconds Aug 28 16:02:04 volumio volumio[1223]: verbose: New Socket.io Connection to 192.168.178.28 from 192.168.178.20 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 6 Aug 28 16:02:04 volumio volumio[1223]: info: CoreCommandRouter::volumioGetState Aug 28 16:02:04 volumio volumio[1223]: info: CorePlayQueue::getTrack 14 Aug 28 16:02:04 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 28 16:02:04 volumio volumio[1223]: info: CoreCommandRouter::volumioGetVisibleSources Aug 28 16:02:04 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 16:02:04 volumio volumio[1223]: info: CoreCommandRouter::volumioGetQueue Aug 28 16:02:04 volumio volumio[1223]: info: CoreStateMachine::getQueue Aug 28 16:02:04 volumio volumio[1223]: info: CorePlayQueue::getQueue Aug 28 16:02:04 volumio volumio[1223]: info: Listing playlists Aug 28 16:02:08 volumio volumio[1223]: info: CoreCommandRouter::volumioPlay Aug 28 16:02:08 volumio volumio[1223]: info: CoreStateMachine::play index undefined Aug 28 16:02:08 volumio volumio[1223]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 16:02:08 volumio volumio[1223]: info: CorePlayQueue::getTrack 14 Aug 28 16:02:08 volumio volumio[1223]: info: CoreStateMachine::startPlaybackTimer Aug 28 16:02:08 volumio volumio[1223]: info: CorePlayQueue::getTrack 14 Aug 28 16:02:08 volumio volumio[1223]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/song@songId=e48a3c9696032e710374af0b5035bcfa Aug 28 16:02:08 volumio volumio[1223]: info: [jellyfin-play] Stream URL for Permanent midnight: http://192.168.178.20:8096/Audio/e48a3c9696032e710374af0b5035bcfa/stream.flac?static=true&mediaSourceId=e48a3c9696032e710374af0b5035bcfa&tag=a86df2ab9b4dfaa538990132e3d46078 Aug 28 16:02:08 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand stop Aug 28 16:02:08 volumio volumio[1223]: info: sendMpdCommand stop took 0 milliseconds Aug 28 16:02:08 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand clear Aug 28 16:02:08 volumio volumio[1223]: info: Aug 28 16:02:08 volumio volumio[1223]: ---------------------------- MPD announces system playlist update Aug 28 16:02:08 volumio volumio[1223]: info: Ignoring MPD Status Update Aug 28 16:02:08 volumio volumio[1223]: info: sendMpdCommand clear took 1 milliseconds Aug 28 16:02:08 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.178.20:8096/Audio/e48a3c9696032e710374af0b5035bcfa/stream.flac?static=true&mediaSourceId=e48a3c9696032e710374af0b5035bcfa&tag=a86df2ab9b4dfaa538990132e3d46078&t.flac" Aug 28 16:02:08 volumio volumio[1223]: error: updateQueue error: null Aug 28 16:02:08 volumio volumio[1223]: info: ------------------------------ 1ms Aug 28 16:02:09 volumio volumio[1223]: info: [jellyfin-poller] Polled http://192.168.178.20:8096: offline (lost) Aug 28 16:02:10 volumio volumio[1223]: info: [jellyfin-play]: Reported 'stop' for song: Love without living (at 236122 ms) Aug 28 16:02:11 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 391. Aug 28 16:02:11 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 16:02:11 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 16:02:11 volumio upmpdcli[12187]: Could not open config: /tmp/upmpdcli.conf Aug 28 16:02:11 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 28 16:02:11 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 28 16:02:12 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand addid "http://192.168.178.20:8096/Audio/e48a3c9696032e710374af0b5035bcfa/stream.flac?static=true&mediaSourceId=e48a3c9696032e710374af0b5035bcfa&tag=a86df2ab9b4dfaa538990132e3d46078&t.flac" Aug 28 16:02:12 volumio volumio[1223]: info: Aug 28 16:02:12 volumio volumio[1223]: ---------------------------- MPD announces system playlist update Aug 28 16:02:12 volumio volumio[1223]: info: Ignoring MPD Status Update Aug 28 16:02:12 volumio volumio[1223]: info: sendMpdCommand addid "http://192.168.178.20:8096/Audio/e48a3c9696032e710374af0b5035bcfa/stream.flac?static=true&mediaSourceId=e48a3c9696032e710374af0b5035bcfa&tag=a86df2ab9b4dfaa538990132e3d46078&t.flac" took 2 milliseconds Aug 28 16:02:12 volumio volumio[1223]: verbose: MPD COMMAND [object Object] Aug 28 16:02:12 volumio volumio[1223]: verbose: MPD COMMAND [object Object] Aug 28 16:02:12 volumio volumio[1223]: verbose: MPD COMMAND [object Object] Aug 28 16:02:12 volumio volumio[1223]: info: Aug 28 16:02:12 volumio volumio[1223]: ---------------------------- MPD announces system playlist update Aug 28 16:02:12 volumio volumio[1223]: info: Ignoring MPD Status Update Aug 28 16:02:12 volumio volumio[1223]: info: ------------------------------ 3ms Aug 28 16:02:12 volumio volumio[1223]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 28 16:02:12 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand play Aug 28 16:02:12 volumio volumio[1223]: info: ------------------------------ 2ms Aug 28 16:02:12 volumio volumio[1223]: info: sendMpdCommand play took 1 milliseconds Aug 28 16:02:12 volumio volumio[1223]: info: [jellyfin-play] Stream URL for Permanent midnight: http://192.168.178.20:8096/Audio/e48a3c9696032e710374af0b5035bcfa/stream.flac?static=true&mediaSourceId=e48a3c9696032e710374af0b5035bcfa&tag=a86df2ab9b4dfaa538990132e3d46078 Aug 28 16:02:12 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand stop Aug 28 16:02:12 volumio volumio[1223]: info: ControllerMpd::getState Aug 28 16:02:12 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Aug 28 16:02:12 volumio volumio[1223]: info: Aug 28 16:02:12 volumio volumio[1223]: ---------------------------- MPD announces state update: player Aug 28 16:02:12 volumio volumio[1223]: info: sendMpdCommand stop took 1 milliseconds Aug 28 16:02:12 volumio volumio[1223]: info: ControllerMpd::getState Aug 28 16:02:12 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Aug 28 16:02:12 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand clear Aug 28 16:02:12 volumio volumio[1223]: info: Aug 28 16:02:12 volumio volumio[1223]: ---------------------------- MPD announces system playlist update Aug 28 16:02:12 volumio volumio[1223]: info: Ignoring MPD Status Update Aug 28 16:02:12 volumio volumio[1223]: info: sendMpdCommand status took 2 milliseconds Aug 28 16:02:12 volumio volumio[1223]: info: sendMpdCommand status took 2 milliseconds Aug 28 16:02:12 volumio volumio[1223]: info: sendMpdCommand clear took 1 milliseconds Aug 28 16:02:12 volumio volumio[1223]: verbose: ControllerMpd::parseState Aug 28 16:02:12 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 16:02:12 volumio volumio[1223]: verbose: ControllerMpd::parseState Aug 28 16:02:12 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 16:02:12 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.178.20:8096/Audio/e48a3c9696032e710374af0b5035bcfa/stream.flac?static=true&mediaSourceId=e48a3c9696032e710374af0b5035bcfa&tag=a86df2ab9b4dfaa538990132e3d46078&t.flac" Aug 28 16:02:12 volumio volumio[1223]: error: updateQueue error: null Aug 28 16:02:12 volumio volumio[1223]: info: ------------------------------ 2ms Aug 28 16:02:12 volumio volumio[1223]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 28 16:02:12 volumio volumio[1223]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 28 16:02:12 volumio volumio[1223]: verbose: ControllerMpd::parseTrackInfo Aug 28 16:02:12 volumio volumio[1223]: verbose: ControllerMpd::parseTrackInfo Aug 28 16:02:12 volumio volumio[1223]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 28 16:02:12 volumio volumio[1223]: info: ------------------------------ 5ms Aug 28 16:02:12 volumio volumio[1223]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 16:02:12 volumio volumio[1223]: TypeError: Cannot read properties of undefined (reading 'split') Aug 28 16:02:12 volumio volumio[1223]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:280:60) Aug 28 16:02:12 volumio volumio[1223]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 28 16:02:12 volumio volumio[1223]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Aug 28 16:02:12 volumio volumio[1223]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 16:02:13 volumio sudo[12204]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-28 16:01' Aug 28 16:02:13 volumio sudo[12204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"