-- Logs begin at Tue 2026-04-14 02:54:54 EDT, end at Tue 2026-04-14 20:41:38 EDT. -- Apr 14 20:40:04 pv1 volumio-time-update[613]: volumio-time-update-util: Fetching time from Volumio... Apr 14 20:40:05 pv1 volumio-time-update[613]: volumio-time-update-util: Setting system time to: 2026-04-14 20:40:04 Apr 14 20:40:05 pv1 sudo[3671]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2026-04-14 20:40:04 Apr 14 20:40:05 pv1 sudo[3671]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:40:05 pv1 dbus-daemon[584]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.84136' (uid=0 pid=3672 comm="timedatectl set-time 2026-04-14 20:40:04 ") Apr 14 20:40:05 pv1 systemd[1]: systemd-timedated.service: Failed to run 'start' task: No space left on device Apr 14 20:40:05 pv1 systemd[1]: systemd-timedated.service: Failed with result 'resources'. Apr 14 20:40:05 pv1 systemd[1]: Failed to start Time & Date Service. Apr 14 20:40:18 pv1 volumio[1050]: error: error Apr 14 20:40:18 pv1 volumio[1050]: error: error Apr 14 20:40:30 pv1 volumio-time-update[613]: Failed to set time: Connection timed out Apr 14 20:40:30 pv1 dbus-daemon[584]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Apr 14 20:40:30 pv1 sudo[3671]: pam_unix(sudo:session): session closed for user root Apr 14 20:40:30 pv1 volumio-time-update[613]: volumio-time-update-util: Error setting time. Apr 14 20:40:30 pv1 volumio-time-update[613]: volumio-time-update-util: Retrying in 5 seconds... Apr 14 20:40:35 pv1 volumio-time-update[613]: volumio-time-update-util: Fetching time from Volumio... Apr 14 20:40:35 pv1 volumio-time-update[613]: volumio-time-update-util: Setting system time to: 2026-04-14 20:40:35 Apr 14 20:40:35 pv1 sudo[3761]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2026-04-14 20:40:35 Apr 14 20:40:35 pv1 sudo[3761]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:40:35 pv1 dbus-daemon[584]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.84137' (uid=0 pid=3762 comm="timedatectl set-time 2026-04-14 20:40:35 ") Apr 14 20:40:35 pv1 systemd[1]: systemd-timedated.service: Failed to run 'start' task: No space left on device Apr 14 20:40:35 pv1 systemd[1]: systemd-timedated.service: Failed with result 'resources'. Apr 14 20:40:35 pv1 systemd[1]: Failed to start Time & Date Service. Apr 14 20:41:00 pv1 volumio-time-update[613]: Failed to set time: Connection timed out Apr 14 20:41:00 pv1 dbus-daemon[584]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Apr 14 20:41:00 pv1 sudo[3761]: pam_unix(sudo:session): session closed for user root Apr 14 20:41:00 pv1 volumio-time-update[613]: volumio-time-update-util: Error setting time. Apr 14 20:41:00 pv1 volumio-time-update[613]: volumio-time-update-util: Retrying in 5 seconds... Apr 14 20:41:03 pv1 volumio[1050]: info: CorePlayQueue::getTrack 0 Apr 14 20:41:04 pv1 volumio[1050]: info: CorePlayQueue::getTrack 1 Apr 14 20:41:04 pv1 volumio[1050]: info: Prefetching next song Apr 14 20:41:04 pv1 volumio[1050]: info: [1776213663999] ControllerQobuz::prefetch Apr 14 20:41:04 pv1 volumio[1050]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/87169205" Apr 14 20:41:04 pv1 volumio[1050]: STREAMING PROXY: Handling url /?data=qobuz://song/87169205 Apr 14 20:41:04 pv1 volumio[1050]: info: Executing endpoint getStreamUrlqobuz Apr 14 20:41:04 pv1 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 14 20:41:04 pv1 volumio[1050]: info: getStreamUrl took 460 milliseconds Apr 14 20:41:04 pv1 volumio[1050]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1623190&eid=87169205&fmt=6&profile=raw&app_id=539451548&cid=1374327&etsp=1776217264&hmac=F5NaxfjjziBW2pQOAzRVUkFPqCQ Apr 14 20:41:04 pv1 volumio[1050]: STREAMING PROXY: Requesting upstream with range: none Apr 14 20:41:05 pv1 volumio[1050]: STREAMING PROXY: Upstream status code: 200 Apr 14 20:41:05 pv1 volumio[1050]: STREAMING PROXY: Client closed connection Apr 14 20:41:05 pv1 volumio[1050]: STREAMING PROXY: Upstream closed. Received 15641 bytes Apr 14 20:41:05 pv1 volumio[1050]: STREAMING PROXY: Incomplete transfer. Expected 12928515, got 15641 Apr 14 20:41:05 pv1 volumio[1050]: STREAMING PROXY: Retrying with range: bytes=15641- (attempt 1) Apr 14 20:41:05 pv1 volumio[1050]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/87169205" Apr 14 20:41:05 pv1 volumio[1050]: info: Apr 14 20:41:05 pv1 volumio[1050]: ---------------------------- MPD announces system playlist update Apr 14 20:41:05 pv1 volumio[1050]: info: Ignoring MPD Status Update Apr 14 20:41:05 pv1 volumio[1050]: STREAMING PROXY: Handling url /?data=qobuz://song/87169205 Apr 14 20:41:05 pv1 volumio[1050]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/87169205" took 2 milliseconds Apr 14 20:41:05 pv1 volumio[1050]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 20:41:05 pv1 volumio[1050]: verbose: ControllerMpd::sendMpdCommand consume 1 Apr 14 20:41:05 pv1 volumio[1050]: info: ------------------------------ 2ms Apr 14 20:41:05 pv1 volumio[1050]: info: sendMpdCommand consume 1 took 1 milliseconds Apr 14 20:41:05 pv1 volumio[1050]: info: Executing endpoint getStreamUrlqobuz Apr 14 20:41:05 pv1 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 14 20:41:05 pv1 volumio-time-update[613]: volumio-time-update-util: Fetching time from Volumio... Apr 14 20:41:05 pv1 volumio[1050]: info: getStreamUrl took 362 milliseconds Apr 14 20:41:05 pv1 volumio[1050]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1623190&eid=87169205&fmt=6&profile=raw&app_id=539451548&cid=1374327&etsp=1776217265&hmac=NtFhIUS0bN7RHP97cSemlIdfZJc Apr 14 20:41:05 pv1 volumio[1050]: STREAMING PROXY: Requesting upstream with range: none Apr 14 20:41:05 pv1 volumio[1050]: STREAMING PROXY: Upstream status code: 200 Apr 14 20:41:05 pv1 volumio-time-update[613]: volumio-time-update-util: Setting system time to: 2026-04-14 20:41:05 Apr 14 20:41:05 pv1 sudo[3814]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2026-04-14 20:41:05 Apr 14 20:41:05 pv1 sudo[3814]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:41:05 pv1 dbus-daemon[584]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.84138' (uid=0 pid=3815 comm="timedatectl set-time 2026-04-14 20:41:05 ") Apr 14 20:41:05 pv1 systemd[1]: systemd-timedated.service: Failed to run 'start' task: No space left on device Apr 14 20:41:05 pv1 systemd[1]: systemd-timedated.service: Failed with result 'resources'. Apr 14 20:41:05 pv1 systemd[1]: Failed to start Time & Date Service. Apr 14 20:41:06 pv1 volumio[1050]: STREAMING PROXY: Resuming with range: bytes=15641- Apr 14 20:41:06 pv1 volumio[1050]: STREAMING PROXY: Upstream closed. Received 12928515 bytes Apr 14 20:41:06 pv1 volumio[1050]: STREAMING PROXY: Client closed connection Apr 14 20:41:06 pv1 volumio[1050]: STREAMING PROXY: Resume upstream status code: 206 Apr 14 20:41:06 pv1 volumio[1050]: STREAMING PROXY: Resume ended. Received 12912874 bytes Apr 14 20:41:08 pv1 volumio[1050]: info: CoreStateMachine::startPlaybackTimer Apr 14 20:41:08 pv1 volumio[1050]: info: CorePlayQueue::getTrack 1 Apr 14 20:41:08 pv1 volumio[1050]: info: Apr 14 20:41:08 pv1 volumio[1050]: ---------------------------- MPD announces system playlist update Apr 14 20:41:08 pv1 volumio[1050]: info: Ignoring MPD Status Update Apr 14 20:41:08 pv1 volumio[1050]: info: Apr 14 20:41:08 pv1 volumio[1050]: ---------------------------- MPD announces state update: player Apr 14 20:41:08 pv1 volumio[1050]: info: ControllerMpd::getState Apr 14 20:41:08 pv1 volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Apr 14 20:41:08 pv1 volumio[1050]: info: ------------------------------ 3ms Apr 14 20:41:08 pv1 volumio[1050]: info: sendMpdCommand status took 2 milliseconds Apr 14 20:41:08 pv1 volumio[1050]: verbose: ControllerMpd::parseState Apr 14 20:41:08 pv1 volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 20:41:08 pv1 volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 14 20:41:08 pv1 volumio[1050]: verbose: ControllerMpd::parseTrackInfo Apr 14 20:41:08 pv1 volumio[1050]: info: ControllerMpd::pushState Apr 14 20:41:08 pv1 volumio[1050]: info: CoreCommandRouter::servicePushState Apr 14 20:41:08 pv1 volumio[1050]: info: CorePlayQueue::getTrack 1 Apr 14 20:41:08 pv1 volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":124,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"880 Kbps","isStreaming":false,"title":"87169205","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/87169205","trackType":"qobuz"} Apr 14 20:41:08 pv1 volumio[1050]: verbose: CURRENT POSITION 1 Apr 14 20:41:08 pv1 volumio[1050]: info: CoreStateMachine::syncState stateService play Apr 14 20:41:08 pv1 volumio[1050]: info: CoreStateMachine::syncState currentStatus play Apr 14 20:41:08 pv1 volumio[1050]: info: Received an update from plugin. extracting info from payload Apr 14 20:41:08 pv1 volumio[1050]: info: CoreStateMachine::pushState Apr 14 20:41:08 pv1 volumio[1050]: info: CorePlayQueue::getTrack 1 Apr 14 20:41:08 pv1 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:41:08 pv1 volumio[1050]: info: CoreCommandRouter::volumioPushState Apr 14 20:41:08 pv1 volumio[1050]: info: CoreCommandRouter::volumioGetState Apr 14 20:41:08 pv1 volumio[1050]: info: CorePlayQueue::getTrack 1 Apr 14 20:41:08 pv1 volumio[1050]: info: CoreStateMachine::pushState Apr 14 20:41:08 pv1 volumio[1050]: info: CorePlayQueue::getTrack 1 Apr 14 20:41:08 pv1 volumio[1050]: info: CoreCommandRouter::volumioPushState Apr 14 20:41:08 pv1 volumio[1050]: info: CoreCommandRouter::volumioGetState Apr 14 20:41:08 pv1 volumio[1050]: info: CorePlayQueue::getTrack 1 Apr 14 20:41:08 pv1 volumio[1050]: info: ------------------------------ 9ms Apr 14 20:41:08 pv1 volumio[1050]: Error saving last played file: Error: ENOSPC: no space left on device, write Apr 14 20:41:08 pv1 volumio[1050]: info: CoreStateMachine::pushState Apr 14 20:41:08 pv1 volumio[1050]: info: CorePlayQueue::getTrack 1 Apr 14 20:41:08 pv1 volumio[1050]: info: CoreCommandRouter::volumioPushState Apr 14 20:41:08 pv1 volumio[1050]: info: CoreCommandRouter::volumioGetState Apr 14 20:41:08 pv1 volumio[1050]: info: CorePlayQueue::getTrack 1 Apr 14 20:41:18 pv1 volumio[1050]: error: error Apr 14 20:41:18 pv1 volumio[1050]: error: error Apr 14 20:41:24 pv1 volumio[1050]: Searching plugin music_service/qobuz Apr 14 20:41:24 pv1 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 20:41:25 pv1 volumio[1050]: Searching plugin music_service/qobuz Apr 14 20:41:25 pv1 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 20:41:26 pv1 volumio[1050]: info: searchQOBUZUri took 1705 milliseconds Apr 14 20:41:26 pv1 volumio[1050]: info: All search sources collected, pushing search results Apr 14 20:41:26 pv1 volumio[1050]: Searching plugin music_service/qobuz Apr 14 20:41:26 pv1 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 20:41:27 pv1 volumio[1050]: info: searchQOBUZUri took 1570 milliseconds Apr 14 20:41:27 pv1 volumio[1050]: info: All search sources collected, pushing search results Apr 14 20:41:27 pv1 volumio[1050]: Searching plugin music_service/qobuz Apr 14 20:41:27 pv1 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 20:41:28 pv1 volumio[1050]: info: searchQOBUZUri took 1398 milliseconds Apr 14 20:41:28 pv1 volumio[1050]: info: All search sources collected, pushing search results Apr 14 20:41:28 pv1 volumio[1050]: Searching plugin music_service/qobuz Apr 14 20:41:28 pv1 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 20:41:29 pv1 volumio[1050]: info: searchQOBUZUri took 1490 milliseconds Apr 14 20:41:29 pv1 volumio[1050]: info: All search sources collected, pushing search results Apr 14 20:41:29 pv1 volumio[1050]: Searching plugin music_service/qobuz Apr 14 20:41:29 pv1 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 20:41:30 pv1 volumio[1050]: info: searchQOBUZUri took 1562 milliseconds Apr 14 20:41:30 pv1 volumio[1050]: info: All search sources collected, pushing search results Apr 14 20:41:30 pv1 volumio[1050]: Searching plugin music_service/qobuz Apr 14 20:41:30 pv1 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 14 20:41:30 pv1 volumio-time-update[613]: Failed to set time: Connection timed out Apr 14 20:41:30 pv1 dbus-daemon[584]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Apr 14 20:41:30 pv1 sudo[3814]: pam_unix(sudo:session): session closed for user root Apr 14 20:41:30 pv1 volumio-time-update[613]: volumio-time-update-util: Error setting time. Apr 14 20:41:30 pv1 volumio-time-update[613]: volumio-time-update-util: Retrying in 5 seconds... Apr 14 20:41:31 pv1 volumio[1050]: info: searchQOBUZUri took 1586 milliseconds Apr 14 20:41:31 pv1 volumio[1050]: info: All search sources collected, pushing search results Apr 14 20:41:31 pv1 volumio[1050]: info: searchQOBUZUri took 1334 milliseconds Apr 14 20:41:31 pv1 volumio[1050]: info: All search sources collected, pushing search results Apr 14 20:41:35 pv1 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Apr 14 20:41:35 pv1 volumio-time-update[613]: volumio-time-update-util: Fetching time from Volumio... Apr 14 20:41:36 pv1 volumio-time-update[613]: volumio-time-update-util: Setting system time to: 2026-04-14 20:41:36 Apr 14 20:41:36 pv1 sudo[3905]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2026-04-14 20:41:36 Apr 14 20:41:36 pv1 sudo[3905]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 20:41:36 pv1 dbus-daemon[584]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.84139' (uid=0 pid=3906 comm="timedatectl set-time 2026-04-14 20:41:36 ") Apr 14 20:41:36 pv1 systemd[1]: systemd-timedated.service: Failed to run 'start' task: No space left on device Apr 14 20:41:36 pv1 systemd[1]: systemd-timedated.service: Failed with result 'resources'. Apr 14 20:41:36 pv1 systemd[1]: Failed to start Time & Date Service. Apr 14 20:41:36 pv1 volumio[1050]: info: handleBrowseUri took 920 milliseconds Apr 14 20:41:36 pv1 volumio[1050]: info: Preload queue cleared Apr 14 20:41:36 pv1 volumio[1050]: info: Executing endpoint getSimilarArtists Apr 14 20:41:36 pv1 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Apr 14 20:41:36 pv1 volumio[1050]: info: Executing endpoint getSimilarArtists Apr 14 20:41:36 pv1 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Apr 14 20:41:36 pv1 volumio[1050]: info: Executing endpoint metavolumio Apr 14 20:41:36 pv1 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 14 20:41:36 pv1 volumio[1050]: info: Executing endpoint metavolumio Apr 14 20:41:36 pv1 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 14 20:41:37 pv1 volumio[1050]: Error in writing albumart JSON file: Error: ENOSPC: no space left on device, write Apr 14 20:41:37 pv1 volumio[1050]: Error in writing albumart JSON file: Error: ENOSPC: no space left on device, write Apr 14 20:41:38 pv1 volumio[1050]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 20:41:38 pv1 volumio[1050]: [Error: ENOSPC: no space left on device, write] { Apr 14 20:41:38 pv1 volumio[1050]: errno: -28, Apr 14 20:41:38 pv1 volumio[1050]: code: 'ENOSPC', Apr 14 20:41:38 pv1 volumio[1050]: syscall: 'write' Apr 14 20:41:38 pv1 volumio[1050]: } Apr 14 20:41:38 pv1 volumio[1050]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 20:41:38 pv1 sudo[3917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-14 20:40 Apr 14 20:41:38 pv1 sudo[3917]: 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"