Feb 07 12:05:02 volumio volumio[1128]: info: CorePlayQueue::getTrack 22
Feb 07 12:05:02 volumio volumio[1128]: info: CorePlayQueue::getTrack 23
Feb 07 12:05:02 volumio volumio[1128]: info: Prefetching next song
Feb 07 12:05:02 volumio volumio[1128]: info: [1770433502101] ControllerQobuz::prefetch
Feb 07 12:05:02 volumio volumio[1128]: info: getStreamUrl took 770 milliseconds
Feb 07 12:05:02 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=9573823&eid=222533841&fmt=7&profile=raw&app_id=539451548&cid=3666078&etsp=1770437102&hmac=OyuRDnuX5kFGY1RiwfVQyGHqEkc"
Feb 07 12:05:04 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=9573823&eid=222533841&fmt=7&profile=raw&app_id=539451548&cid=3666078&etsp=1770437102&hmac=OyuRDnuX5kFGY1RiwfVQyGHqEkc"
Feb 07 12:05:04 volumio volumio[1128]: info:
Feb 07 12:05:04 volumio volumio[1128]: ---------------------------- MPD announces system playlist update
Feb 07 12:05:04 volumio volumio[1128]: info: Ignoring MPD Status Update
Feb 07 12:05:04 volumio volumio[1128]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=9573823&eid=222533841&fmt=7&profile=raw&app_id=539451548&cid=3666078&etsp=1770437102&hmac=OyuRDnuX5kFGY1RiwfVQyGHqEkc" took 2 milliseconds
Feb 07 12:05:04 volumio volumio[1128]: info: CoreStateMachine::setConsumeUpdateService mpd
Feb 07 12:05:04 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand consume 1
Feb 07 12:05:04 volumio volumio[1128]: info: ------------------------------ 1ms
Feb 07 12:05:04 volumio volumio[1128]: info: sendMpdCommand consume 1 took 0 milliseconds
Feb 07 12:05:06 volumio volumio[1128]: info: CoreStateMachine::startPlaybackTimer
Feb 07 12:05:06 volumio volumio[1128]: info: CorePlayQueue::getTrack 23
Feb 07 12:05:06 volumio volumio[1128]: info: CoreCommandRouter::volumioGetVisibleSources
Feb 07 12:05:06 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 07 12:05:06 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Feb 07 12:05:06 volumio volumio[1128]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio
Feb 07 12:05:06 volumio volumio[1128]: info:
Feb 07 12:05:06 volumio volumio[1128]: ---------------------------- MPD announces system playlist update
Feb 07 12:05:06 volumio volumio[1128]: info: Ignoring MPD Status Update
Feb 07 12:05:06 volumio volumio[1128]: info:
Feb 07 12:05:06 volumio volumio[1128]: ---------------------------- MPD announces state update: player
Feb 07 12:05:06 volumio volumio[1128]: info: ControllerMpd::getState
Feb 07 12:05:06 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand status
Feb 07 12:05:06 volumio volumio[1128]: info: CoreStateMachine::pushState
Feb 07 12:05:06 volumio volumio[1128]: info: CorePlayQueue::getTrack 23
Feb 07 12:05:06 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 07 12:05:06 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState
Feb 07 12:05:07 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 1
Feb 07 12:05:07 volumio kernel: xhci_hcd 0000:01:00.0: @0000000428d16070 00000000 00000000 0e000000 02028001
Feb 07 12:05:08 volumio volumio[1128]: info:
Feb 07 12:05:08 volumio volumio[1128]: ---------------------------- MPD announces state update: player
Feb 07 12:05:08 volumio volumio[1128]: info: ------------------------------ 1132ms
Feb 07 12:05:08 volumio volumio[1128]: info: sendMpdCommand status took 1131 milliseconds
Feb 07 12:05:08 volumio volumio[1128]: info: ControllerMpd::getState
Feb 07 12:05:08 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand status
Feb 07 12:05:08 volumio volumio[1128]: verbose: ControllerMpd::parseState
Feb 07 12:05:08 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 07 12:05:08 volumio volumio[1128]: info: sendMpdCommand status took 2 milliseconds
Feb 07 12:05:08 volumio volumio[1128]: info: sendMpdCommand playlistinfo took 2 milliseconds
Feb 07 12:05:08 volumio volumio[1128]: verbose: ControllerMpd::parseState
Feb 07 12:05:08 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 07 12:05:08 volumio volumio[1128]: verbose: ControllerMpd::parseTrackInfo
Feb 07 12:05:08 volumio volumio[1128]: info: ControllerMpd::pushState
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::servicePushState
Feb 07 12:05:08 volumio volumio[1128]: info: CorePlayQueue::getTrack 23
Feb 07 12:05:08 volumio volumio[1128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":222,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=9573823&eid=222533841&fmt=7&profile=raw&app_id=539451548&cid=3666078&etsp=1770437102&hmac=OyuRDnuX5kFGY1RiwfVQyGHqEkc","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=9573823&eid=222533841&fmt=7&profile=raw&app_id=539451548&cid=3666078&etsp=1770437102&hmac=OyuRDnuX5kFGY1RiwfVQyGHqEkc","trackType":"qobuz"}
Feb 07 12:05:08 volumio volumio[1128]: verbose: CURRENT POSITION 23
Feb 07 12:05:08 volumio volumio[1128]: info: CoreStateMachine::syncState stateService play
Feb 07 12:05:08 volumio volumio[1128]: info: CoreStateMachine::syncState currentStatus play
Feb 07 12:05:08 volumio volumio[1128]: info: Received an update from plugin. extracting info from payload
Feb 07 12:05:08 volumio volumio[1128]: info: CoreStateMachine::pushState
Feb 07 12:05:08 volumio volumio[1128]: info: CorePlayQueue::getTrack 23
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState
Feb 07 12:05:08 volumio volumio[1128]: info: CoreStateMachine::pushState
Feb 07 12:05:08 volumio volumio[1128]: info: CorePlayQueue::getTrack 23
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState
Feb 07 12:05:08 volumio volumio[1128]: info: ------------------------------ 1143ms
Feb 07 12:05:08 volumio volumio[1128]: info: sendMpdCommand playlistinfo took 9 milliseconds
Feb 07 12:05:08 volumio volumio[1128]: verbose: ControllerMpd::parseTrackInfo
Feb 07 12:05:08 volumio volumio[1128]: info: ControllerMpd::pushState
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::servicePushState
Feb 07 12:05:08 volumio volumio[1128]: info: CorePlayQueue::getTrack 23
Feb 07 12:05:08 volumio volumio[1128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":251,"duration":222,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1201 Kbps","isStreaming":false,"title":"file?uid=9573823&eid=222533841&fmt=7&profile=raw&app_id=539451548&cid=3666078&etsp=1770437102&hmac=OyuRDnuX5kFGY1RiwfVQyGHqEkc","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=9573823&eid=222533841&fmt=7&profile=raw&app_id=539451548&cid=3666078&etsp=1770437102&hmac=OyuRDnuX5kFGY1RiwfVQyGHqEkc","trackType":"qobuz"}
Feb 07 12:05:08 volumio volumio[1128]: verbose: CURRENT POSITION 23
Feb 07 12:05:08 volumio volumio[1128]: info: CoreStateMachine::syncState stateService play
Feb 07 12:05:08 volumio volumio[1128]: info: CoreStateMachine::syncState currentStatus play
Feb 07 12:05:08 volumio volumio[1128]: info: Received an update from plugin. extracting info from payload
Feb 07 12:05:08 volumio volumio[1128]: info: CoreStateMachine::pushState
Feb 07 12:05:08 volumio volumio[1128]: info: CorePlayQueue::getTrack 23
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState
Feb 07 12:05:08 volumio volumio[1128]: info: CoreStateMachine::pushState
Feb 07 12:05:08 volumio volumio[1128]: info: CorePlayQueue::getTrack 23
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState
Feb 07 12:05:08 volumio volumio[1128]: info: ------------------------------ 19ms
Feb 07 12:05:08 volumio volumio[1128]: info: All cached search sources collected
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::volumioGetQueue
Feb 07 12:05:08 volumio volumio[1128]: info: CoreStateMachine::getQueue
Feb 07 12:05:08 volumio volumio[1128]: info: CorePlayQueue::getQueue
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::volumioAddQueueItems
Feb 07 12:05:08 volumio volumio[1128]: info: CoreStateMachine::addQueueItems
Feb 07 12:05:08 volumio volumio[1128]: info: CorePlayQueue::addQueueItems
Feb 07 12:05:08 volumio volumio[1128]: info: Preload queue cleared
Feb 07 12:05:08 volumio volumio[1128]: info: Adding Item to queue: qobuz://song/309996607
Feb 07 12:05:08 volumio volumio[1128]: info: Exploding uri qobuz://song/309996607 in service qobuz
Feb 07 12:05:08 volumio volumio[1128]: https://prod.vlmapi.io/v2/qobuz/explodeUri
Feb 07 12:05:08 volumio volumio[1128]: info: All cached search sources collected
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , search
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: qobuz , search
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: tidal , search
Feb 07 12:05:08 volumio volumio[1128]: info: All cached search sources collected
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , search
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: qobuz , search
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: tidal , search
Feb 07 12:05:08 volumio volumio[1128]: info: All cached search sources collected
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::volumioGetQueue
Feb 07 12:05:08 volumio volumio[1128]: info: CoreStateMachine::getQueue
Feb 07 12:05:08 volumio volumio[1128]: info: CorePlayQueue::getQueue
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::volumioAddQueueItems
Feb 07 12:05:08 volumio volumio[1128]: info: CoreStateMachine::addQueueItems
Feb 07 12:05:08 volumio volumio[1128]: info: CorePlayQueue::addQueueItems
Feb 07 12:05:08 volumio volumio[1128]: info: Preload queue cleared
Feb 07 12:05:08 volumio volumio[1128]: info: Adding Item to queue: qobuz://song/145431640
Feb 07 12:05:08 volumio volumio[1128]: info: Exploding uri qobuz://song/145431640 in service qobuz
Feb 07 12:05:08 volumio volumio[1128]: https://prod.vlmapi.io/v2/qobuz/explodeUri
Feb 07 12:05:08 volumio volumio[1128]: info: All cached search sources collected
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , search
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: qobuz , search
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: tidal , search
Feb 07 12:05:08 volumio volumio[1128]: info: All cached search sources collected
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , search
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: qobuz , search
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: tidal , search
Feb 07 12:05:08 volumio volumio[1128]: info: All cached search sources collected
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , search
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: qobuz , search
Feb 07 12:05:08 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: tidal , search
Feb 07 12:05:09 volumio volumio[1128]: info: All cached search sources collected
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , search
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: qobuz , search
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: tidal , search
Feb 07 12:05:09 volumio volumio[1128]: info: All cached search sources collected
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::volumioGetQueue
Feb 07 12:05:09 volumio volumio[1128]: info: CoreStateMachine::getQueue
Feb 07 12:05:09 volumio volumio[1128]: info: CorePlayQueue::getQueue
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::volumioAddQueueItems
Feb 07 12:05:09 volumio volumio[1128]: info: CoreStateMachine::addQueueItems
Feb 07 12:05:09 volumio volumio[1128]: info: CorePlayQueue::addQueueItems
Feb 07 12:05:09 volumio volumio[1128]: info: Preload queue cleared
Feb 07 12:05:09 volumio volumio[1128]: info: Adding Item to queue: qobuz://song/33951840
Feb 07 12:05:09 volumio volumio[1128]: info: Exploding uri qobuz://song/33951840 in service qobuz
Feb 07 12:05:09 volumio volumio[1128]: https://prod.vlmapi.io/v2/qobuz/explodeUri
Feb 07 12:05:09 volumio volumio[1128]: info: All cached search sources collected
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , search
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: qobuz , search
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: tidal , search
Feb 07 12:05:09 volumio volumio[1128]: info: All cached search sources collected
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::volumioGetQueue
Feb 07 12:05:09 volumio volumio[1128]: info: CoreStateMachine::getQueue
Feb 07 12:05:09 volumio volumio[1128]: info: CorePlayQueue::getQueue
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::volumioAddQueueItems
Feb 07 12:05:09 volumio volumio[1128]: info: CoreStateMachine::addQueueItems
Feb 07 12:05:09 volumio volumio[1128]: info: CorePlayQueue::addQueueItems
Feb 07 12:05:09 volumio volumio[1128]: info: Preload queue cleared
Feb 07 12:05:09 volumio volumio[1128]: info: Adding Item to queue: qobuz://song/15030821
Feb 07 12:05:09 volumio volumio[1128]: info: Exploding uri qobuz://song/15030821 in service qobuz
Feb 07 12:05:09 volumio volumio[1128]: https://prod.vlmapi.io/v2/qobuz/explodeUri
Feb 07 12:05:09 volumio volumio[1128]: info: All cached search sources collected
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , search
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: qobuz , search
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: tidal , search
Feb 07 12:05:09 volumio volumio[1128]: info: All cached search sources collected
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , search
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: qobuz , search
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: tidal , search
Feb 07 12:05:09 volumio volumio[1128]: info: All cached search sources collected
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , search
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: qobuz , search
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: tidal , search
Feb 07 12:05:09 volumio volumio[1128]: info: All cached search sources collected
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , search
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: qobuz , search
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: tidal , search
Feb 07 12:05:09 volumio volumio[1128]: info: All cached search sources collected
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , search
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: qobuz , search
Feb 07 12:05:09 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: tidal , search
Feb 07 12:05:09 volumio volumio[1128]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 07 12:05:09 volumio volumio[1128]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals.
Feb 07 12:05:09 volumio volumio[1128]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues
Feb 07 12:05:09 volumio volumio[1128]: at new NodeError (node:internal/errors:405:5)
Feb 07 12:05:09 volumio volumio[1128]: at assert (node:internal/assert:14:11)
Feb 07 12:05:09 volumio volumio[1128]: at internalConnectMultiple (node:net:1118:3)
Feb 07 12:05:09 volumio volumio[1128]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3)
Feb 07 12:05:09 volumio volumio[1128]: at listOnTimeout (node:internal/timers:575:11)
Feb 07 12:05:09 volumio volumio[1128]: at process.processTimers (node:internal/timers:514:7) {
Feb 07 12:05:09 volumio volumio[1128]: code: 'ERR_INTERNAL_ASSERTION'
Feb 07 12:05:09 volumio volumio[1128]: }
Feb 07 12:05:09 volumio volumio[1128]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 07 12:05:09 volumio sudo[14726]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-07 12:04'
Feb 07 12:05:09 volumio sudo[14726]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025"
VOLUMIO_VERSION="4.073"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"