-- Logs begin at Thu 2025-12-11 20:08:18 UTC, end at Sun 2025-12-14 07:54:48 UTC. --
Dec 14 07:54:26 volumio volumio[621]: info: CorePlayQueue::getTrack 6
Dec 14 07:54:26 volumio volumio[621]: info: CorePlayQueue::getTrack 7
Dec 14 07:54:30 volumio volumio[621]: info:
Dec 14 07:54:30 volumio volumio[621]: ---------------------------- MPD announces system playlist update
Dec 14 07:54:30 volumio volumio[621]: info: Ignoring MPD Status Update
Dec 14 07:54:30 volumio volumio[621]: info:
Dec 14 07:54:30 volumio volumio[621]: ---------------------------- MPD announces state update: player
Dec 14 07:54:30 volumio volumio[621]: info: ControllerMpd::getState
Dec 14 07:54:30 volumio volumio[621]: verbose: ControllerMpd::sendMpdCommand status
Dec 14 07:54:30 volumio volumio[621]: info:
Dec 14 07:54:30 volumio volumio[621]: ---------------------------- MPD announces system playlist update
Dec 14 07:54:30 volumio volumio[621]: info: Ignoring MPD Status Update
Dec 14 07:54:30 volumio volumio[621]: info:
Dec 14 07:54:30 volumio volumio[621]: ---------------------------- MPD announces state update: player
Dec 14 07:54:30 volumio volumio[621]: info: ControllerMpd::getState
Dec 14 07:54:30 volumio volumio[621]: verbose: ControllerMpd::sendMpdCommand status
Dec 14 07:54:30 volumio volumio[621]: info:
Dec 14 07:54:30 volumio volumio[621]: ---------------------------- MPD announces system playlist update
Dec 14 07:54:30 volumio volumio[621]: info: Ignoring MPD Status Update
Dec 14 07:54:30 volumio volumio[621]: info:
Dec 14 07:54:30 volumio volumio[621]: ---------------------------- MPD announces state update: player
Dec 14 07:54:30 volumio volumio[621]: info: ControllerMpd::getState
Dec 14 07:54:30 volumio volumio[621]: verbose: ControllerMpd::sendMpdCommand status
Dec 14 07:54:30 volumio volumio[621]: info:
Dec 14 07:54:30 volumio volumio[621]: ---------------------------- MPD announces system playlist update
Dec 14 07:54:30 volumio volumio[621]: info: Ignoring MPD Status Update
Dec 14 07:54:30 volumio volumio[621]: info:
Dec 14 07:54:30 volumio volumio[621]: ---------------------------- MPD announces state update: player
Dec 14 07:54:30 volumio volumio[621]: info: ControllerMpd::getState
Dec 14 07:54:30 volumio volumio[621]: verbose: ControllerMpd::sendMpdCommand status
Dec 14 07:54:30 volumio volumio[621]: error: updateQueue error: null
Dec 14 07:54:30 volumio volumio[621]: error: updateQueue error: null
Dec 14 07:54:30 volumio volumio[621]: error: updateQueue error: null
Dec 14 07:54:30 volumio volumio[621]: error: updateQueue error: null
Dec 14 07:54:30 volumio volumio[621]: info: ------------------------------ 56ms
Dec 14 07:54:30 volumio volumio[621]: info: sendMpdCommand status took 44 milliseconds
Dec 14 07:54:30 volumio volumio[621]: info: ------------------------------ 36ms
Dec 14 07:54:30 volumio volumio[621]: info: sendMpdCommand status took 32 milliseconds
Dec 14 07:54:30 volumio volumio[621]: info: ------------------------------ 30ms
Dec 14 07:54:30 volumio volumio[621]: info: sendMpdCommand status took 20 milliseconds
Dec 14 07:54:30 volumio volumio[621]: info: ------------------------------ 18ms
Dec 14 07:54:30 volumio volumio[621]: info: sendMpdCommand status took 15 milliseconds
Dec 14 07:54:30 volumio volumio[621]: verbose: ControllerMpd::parseState
Dec 14 07:54:30 volumio volumio[621]: verbose: ControllerMpd::parseState
Dec 14 07:54:30 volumio volumio[621]: verbose: ControllerMpd::parseState
Dec 14 07:54:30 volumio volumio[621]: verbose: ControllerMpd::parseState
Dec 14 07:54:30 volumio volumio[621]: info: ControllerMpd::pushState
Dec 14 07:54:30 volumio volumio[621]: info: CoreCommandRouter::servicePushState
Dec 14 07:54:30 volumio volumio[621]: info: CoreStateMachine::pushState
Dec 14 07:54:30 volumio volumio[621]: info: CorePlayQueue::getTrack 6
Dec 14 07:54:30 volumio volumio[621]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 14 07:54:30 volumio volumio[621]: info: CoreCommandRouter::volumioPushState
Dec 14 07:54:31 volumio volumio[621]: info: CorePlayQueue::getTrack 6
Dec 14 07:54:31 volumio volumio[621]: 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}
Dec 14 07:54:31 volumio volumio[621]: verbose: CURRENT POSITION 6
Dec 14 07:54:31 volumio volumio[621]: info: CoreStateMachine::syncState stateService stop
Dec 14 07:54:31 volumio volumio[621]: info: CoreStateMachine::syncState currentStatus play
Dec 14 07:54:31 volumio volumio[621]: info: CoreCommandRouter::volumioPushState
Dec 14 07:54:31 volumio volumio[621]: info: CoreStateMachine::stPlaybackTimer
Dec 14 07:54:31 volumio volumio[621]: info: ControllerMpd::pushState
Dec 14 07:54:31 volumio volumio[621]: info: CoreCommandRouter::servicePushState
Dec 14 07:54:31 volumio volumio[621]: info: CoreStateMachine::pushState
Dec 14 07:54:31 volumio volumio[621]: info: CorePlayQueue::getTrack 0
Dec 14 07:54:31 volumio volumio[621]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 14 07:54:31 volumio volumio[621]: info: CoreCommandRouter::volumioPushState
Dec 14 07:54:31 volumio volumio[621]: info: CorePlayQueue::getTrack 0
Dec 14 07:54:31 volumio volumio[621]: 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}
Dec 14 07:54:31 volumio volumio[621]: verbose: CURRENT POSITION 0
Dec 14 07:54:31 volumio volumio[621]: info: CoreStateMachine::syncState stateService stop
Dec 14 07:54:31 volumio volumio[621]: info: CoreStateMachine::syncState currentStatus stop
Dec 14 07:54:31 volumio volumio[621]: info: CoreStateMachine::pushState
Dec 14 07:54:31 volumio volumio[621]: info: CorePlayQueue::getTrack 0
Dec 14 07:54:31 volumio volumio[621]: info: CoreCommandRouter::volumioPushState
Dec 14 07:54:31 volumio volumio[621]: info: No code
Dec 14 07:54:31 volumio volumio[621]: info: CoreStateMachine::pushState
Dec 14 07:54:31 volumio volumio[621]: info: CorePlayQueue::getTrack 0
Dec 14 07:54:31 volumio volumio[621]: info: CoreCommandRouter::volumioPushState
Dec 14 07:54:31 volumio volumio[621]: info: ControllerMpd::pushState
Dec 14 07:54:31 volumio volumio[621]: info: CoreCommandRouter::servicePushState
Dec 14 07:54:31 volumio volumio[621]: info: CoreStateMachine::pushState
Dec 14 07:54:31 volumio volumio[621]: info: CorePlayQueue::getTrack 0
Dec 14 07:54:31 volumio volumio[621]: info: CoreCommandRouter::volumioPushState
Dec 14 07:54:31 volumio volumio[621]: info: CorePlayQueue::getTrack 0
Dec 14 07:54:31 volumio volumio[621]: 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}
Dec 14 07:54:31 volumio volumio[621]: verbose: CURRENT POSITION 0
Dec 14 07:54:31 volumio volumio[621]: info: CoreStateMachine::syncState stateService stop
Dec 14 07:54:31 volumio volumio[621]: info: CoreStateMachine::syncState currentStatus stop
Dec 14 07:54:31 volumio volumio[621]: info: CoreStateMachine::pushState
Dec 14 07:54:31 volumio volumio[621]: info: CorePlayQueue::getTrack 0
Dec 14 07:54:31 volumio volumio[621]: info: CoreCommandRouter::volumioPushState
Dec 14 07:54:31 volumio volumio[621]: info: No code
Dec 14 07:54:31 volumio volumio[621]: info: CoreStateMachine::pushState
Dec 14 07:54:31 volumio volumio[621]: info: CorePlayQueue::getTrack 0
Dec 14 07:54:31 volumio volumio[621]: info: CoreCommandRouter::volumioPushState
Dec 14 07:54:31 volumio volumio[621]: info: ControllerMpd::pushState
Dec 14 07:54:31 volumio volumio[621]: info: CoreCommandRouter::servicePushState
Dec 14 07:54:31 volumio volumio[621]: info: CoreStateMachine::pushState
Dec 14 07:54:31 volumio volumio[621]: info: CorePlayQueue::getTrack 0
Dec 14 07:54:31 volumio volumio[621]: info: CoreCommandRouter::volumioPushState
Dec 14 07:54:31 volumio volumio[621]: info: CorePlayQueue::getTrack 0
Dec 14 07:54:31 volumio volumio[621]: 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}
Dec 14 07:54:31 volumio volumio[621]: verbose: CURRENT POSITION 0
Dec 14 07:54:31 volumio volumio[621]: info: CoreStateMachine::syncState stateService stop
Dec 14 07:54:31 volumio volumio[621]: info: CoreStateMachine::syncState currentStatus stop
Dec 14 07:54:31 volumio volumio[621]: info: CoreStateMachine::pushState
Dec 14 07:54:31 volumio volumio[621]: info: CorePlayQueue::getTrack 0
Dec 14 07:54:31 volumio volumio[621]: info: CoreCommandRouter::volumioPushState
Dec 14 07:54:31 volumio volumio[621]: info: No code
Dec 14 07:54:31 volumio volumio[621]: info: CoreStateMachine::pushState
Dec 14 07:54:31 volumio volumio[621]: info: CorePlayQueue::getTrack 0
Dec 14 07:54:31 volumio volumio[621]: info: CoreCommandRouter::volumioPushState
Dec 14 07:54:31 volumio volumio[621]: info: ------------------------------ 172ms
Dec 14 07:54:31 volumio volumio[621]: info: ------------------------------ 156ms
Dec 14 07:54:31 volumio volumio[621]: info: ------------------------------ 152ms
Dec 14 07:54:31 volumio volumio[621]: info: ------------------------------ 151ms
Dec 14 07:54:31 volumio volumio[621]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40
Dec 14 07:54:31 volumio volumio[621]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40
Dec 14 07:54:31 volumio volumio[621]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40
Dec 14 07:54:31 volumio volumio[621]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40
Dec 14 07:54:31 volumio volumio[621]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40
Dec 14 07:54:31 volumio volumio[621]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40
Dec 14 07:54:31 volumio volumio[621]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40
Dec 14 07:54:31 volumio volumio[621]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40
Dec 14 07:54:31 volumio volumio[621]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40
Dec 14 07:54:31 volumio volumio[621]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40
Dec 14 07:54:31 volumio volumio[621]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40
Dec 14 07:54:44 volumio volumio[621]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 14 07:54:44 volumio volumio[621]: TypeError: Cannot read property 'slice' of null
Dec 14 07:54:44 volumio volumio[621]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:714:41)
Dec 14 07:54:44 volumio volumio[621]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:724:7)
Dec 14 07:54:44 volumio volumio[621]: at Object.onceWrapper (events.js:422:26)
Dec 14 07:54:44 volumio volumio[621]: at ClientRequest.emit (events.js:315:20)
Dec 14 07:54:44 volumio volumio[621]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27)
Dec 14 07:54:44 volumio volumio[621]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17)
Dec 14 07:54:44 volumio volumio[621]: at TLSSocket.socketOnData (_http_client.js:509:22)
Dec 14 07:54:44 volumio volumio[621]: at TLSSocket.emit (events.js:315:20)
Dec 14 07:54:44 volumio volumio[621]: at addChunk (internal/streams/readable.js:309:12)
Dec 14 07:54:44 volumio volumio[621]: at readableAddChunk (internal/streams/readable.js:284:9)
Dec 14 07:54:44 volumio volumio[621]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10)
Dec 14 07:54:44 volumio volumio[621]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23)
Dec 14 07:54:44 volumio volumio[621]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 14 07:54:48 volumio sudo[22210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-14 07:53
Dec 14 07:54:48 volumio sudo[22210]: 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="1f061e035f7cb2799b32d3520aa8e55cd07df6f9"
VOLUMIO_FE_VERSION="9971a09df9c3edfdedf2ecf438999be8aba9db68"
VOLUMIO_FE3_VERSION="3974def988c7b2392ddc85f9b8cb4692d5e8fda9"
VOLUMIO_BE_VERSION="b3c53a818b9b3d0e5c13c9d346c4d613c4ca426f"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Mon 11 Jul 2022 04:03:33 PM CEST"
VOLUMIO_VERSION="3.324"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="5b2f59e6e90e57f326ca25c7ca839d52"