Sep 07 08:15:30 volumio volumio[997]: info: CoreCommandRouter::volumioPause Sep 07 08:15:30 volumio volumio[997]: info: CoreStateMachine::pause Sep 07 08:15:30 volumio volumio[997]: info: CoreStateMachine::stPlaybackTimer Sep 07 08:15:30 volumio volumio[997]: info: CoreStateMachine::servicePause Sep 07 08:15:30 volumio volumio[997]: info: CorePlayQueue::getTrack 0 Sep 07 08:15:30 volumio volumio[997]: info: CoreCommandRouter::servicePause Sep 07 08:15:30 volumio volumio[997]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 07 08:15:30 volumio volumio[997]: info: ControllerMpd::pause Sep 07 08:15:30 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand pause Sep 07 08:15:30 volumio volumio[997]: info: Sep 07 08:15:30 volumio volumio[997]: ---------------------------- MPD announces state update: player Sep 07 08:15:30 volumio volumio[997]: info: sendMpdCommand pause took 2 milliseconds Sep 07 08:15:30 volumio volumio[997]: info: ControllerMpd::getState Sep 07 08:15:30 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand status Sep 07 08:15:30 volumio volumio[997]: info: sendMpdCommand status took 2 milliseconds Sep 07 08:15:30 volumio volumio[997]: verbose: ControllerMpd::parseState Sep 07 08:15:30 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 08:15:30 volumio volumio[997]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 07 08:15:30 volumio volumio[997]: verbose: ControllerMpd::parseTrackInfo Sep 07 08:15:30 volumio volumio[997]: info: ControllerMpd::pushState Sep 07 08:15:30 volumio volumio[997]: info: CoreCommandRouter::servicePushState Sep 07 08:15:30 volumio volumio[997]: info: CorePlayQueue::getTrack 0 Sep 07 08:15:30 volumio volumio[997]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":214737,"duration":224,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Typhoon - Stil de tijd | Beste Zangers 2025","artist":"AVROTROS","album":null,"uri":"https://rr2---sn-5hneknek.googlevideo.com/videoplayback?expire=1757247116&ei=LCK9aOmcJuufzPsPmei9yAQ&ip=81.204.44.1&id=o-APZcoSGNfpf2xEN181dyc1urVMjzAbxAjsRnoP2paFoP&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1757225516%2C&mh=iX&mm=31%2C29&mn=sn-5hneknek%2Csn-5hne6nzs&ms=au%2Crdu&mv=m&mvi=2&pl=22&rms=au%2Cau&initcwndbps=2712500&bui=AY1jyLNIaPvzWJxacPUsDQ2XWTOUy-E5UaO4z21da2APvQ6f_dCA2fq7Qg-pAgGzislOIhFoP0F4H6yo&spc=l3OVKW-uIN7PgqAvW5V4kOW6Hl24CxCa9HN7SmszYuD0xDy8rTuE9F-tSd_4-FUtsfU&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=SL6_W2GIGh1gBQC42-8rrFQQ&rqh=1&gir=yes&clen=4119828&dur=223.741&lmt=1757206192506909&mt=1757224967&fvip=1&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=5532534&n=4aHqELlPlQ72rw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIgBUTXdSn5sCihINf6i4F1ooMhDxiK0h3UvHk0X25rmVMCIQClUEAG3qXdwflata1OobJHXk4m8Qbzi43M6wXU-XSIzw%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgCfYEEowQcGjYp4b0KXIOzHsoatSnQRlDiboC6yqwCa0CIEuQc1UAQku6-xjrlJ0Dg402iIVqAnNrxNNaK-pTe7oA&pot=MpoBEjJAYHPCpNMSDFhEbzdrccpCxKaoO-f-Qh4OS_MkZjm-JnicekRpp6W-12P3ipHB-CahpUClhHk_FNrTO1NPpp6liJQE08nIPsxgI1vO_lc4RUJrw-S_QzfidaoUCltJxqS8oPT0TzjW1Mg7kwQZp-g8cnNxWiJRgcr3cXfHRococBOtwj3gMb20-X6NnYWKiJH9S1MwBMkZ6Q%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 07 08:15:30 volumio volumio[997]: verbose: CURRENT POSITION 0 Sep 07 08:15:30 volumio volumio[997]: info: CoreStateMachine::syncState stateService pause Sep 07 08:15:30 volumio volumio[997]: info: CoreStateMachine::syncState currentStatus pause Sep 07 08:15:30 volumio volumio[997]: info: CoreStateMachine::pushState Sep 07 08:15:30 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 08:15:30 volumio volumio[997]: info: CoreCommandRouter::volumioPushState Sep 07 08:15:30 volumio volumio[997]: info: CoreStateMachine::stPlaybackTimer Sep 07 08:15:30 volumio volumio[997]: info: ------------------------------ 9ms Sep 07 08:15:30 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 07 08:15:30 volumio dbus-daemon[566]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.8905' (uid=0 pid=13462 comm="timedatectl show --property=NTPSynchronized --valu") Sep 07 08:15:30 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 07 08:15:31 volumio dbus-daemon[566]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 07 08:15:31 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 07 08:15:31 volumio setdatetime-helper.sh[13459]: Time is already synchronized. Sep 07 08:15:31 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 07 08:15:31 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 07 08:15:31 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 3 ep 1 Sep 07 08:15:31 volumio kernel: xhci_hcd 0000:01:00.0: @0000000428d14de0 00000000 00000000 0e000000 03028001 Sep 07 08:15:43 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 07 08:15:43 volumio volumio[997]: info: CURURI: music-library Sep 07 08:15:43 volumio volumio[997]: info: Preload queue cleared Sep 07 08:15:45 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 07 08:15:45 volumio volumio[997]: info: CURURI: music-library/USB Sep 07 08:15:45 volumio volumio[997]: info: Preload queue cleared Sep 07 08:15:49 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 07 08:15:49 volumio volumio[997]: info: CURURI: music-library/USB/Volumio_hdd_Dick Sep 07 08:15:50 volumio volumio[997]: info: Preload queue cleared Sep 07 08:16:01 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 07 08:16:17 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 07 08:16:17 volumio volumio[997]: info: CURURI: music-library/USB/Volumio_hdd_Dick/Bach, Johann Sebastian; Masaaki Suzuki, Bach Collegium Japan - Complete Sacred Cantatas Sep 07 08:16:18 volumio volumio[997]: info: Preload queue cleared Sep 07 08:16:24 volumio volumio[997]: info: Preload queue cleared Sep 07 08:16:24 volumio volumio[997]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 07 08:16:24 volumio volumio[997]: info: CoreStateMachine::ClearQueue Sep 07 08:16:24 volumio volumio[997]: info: CoreStateMachine::stop Sep 07 08:16:24 volumio volumio[997]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 08:16:24 volumio volumio[997]: info: CoreStateMachine::updateTrackBlock Sep 07 08:16:24 volumio volumio[997]: info: CorePlayQueue::getTrackBlock Sep 07 08:16:24 volumio volumio[997]: info: CoreStateMachine::stPlaybackTimer Sep 07 08:16:24 volumio volumio[997]: info: CoreStateMachine::pushState Sep 07 08:16:24 volumio volumio[997]: info: CorePlayQueue::getTrack 0 Sep 07 08:16:24 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 08:16:24 volumio volumio[997]: info: CoreCommandRouter::volumioPushState Sep 07 08:16:24 volumio volumio[997]: info: CoreStateMachine::serviceStop Sep 07 08:16:24 volumio volumio[997]: info: CorePlayQueue::getTrack 0 Sep 07 08:16:24 volumio volumio[997]: info: CoreCommandRouter::serviceStop Sep 07 08:16:24 volumio volumio[997]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 07 08:16:24 volumio volumio[997]: info: ControllerMpd::stop Sep 07 08:16:24 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand stop Sep 07 08:16:24 volumio volumio[997]: info: CorePlayQueue::clearPlayQueue Sep 07 08:16:24 volumio volumio[997]: info: CorePlayQueue::saveQueue Sep 07 08:16:24 volumio volumio[997]: info: CoreCommandRouter::volumioPushQueue Sep 07 08:16:24 volumio volumio[997]: info: CoreStateMachine::addQueueItems Sep 07 08:16:24 volumio volumio[997]: info: CorePlayQueue::addQueueItems Sep 07 08:16:24 volumio volumio[997]: info: Preload queue cleared Sep 07 08:16:24 volumio volumio[997]: info: Adding Item to queue: music-library/USB/Volumio_hdd_Dick/Bach, Johann Sebastian; Masaaki Suzuki, Bach Collegium Japan - Complete Sacred Cantatas/Box 2 Sep 07 08:16:24 volumio volumio[997]: info: Exploding uri music-library/USB/Volumio_hdd_Dick/Bach, Johann Sebastian; Masaaki Suzuki, Bach Collegium Japan - Complete Sacred Cantatas/Box 2 in service mpd Sep 07 08:16:25 volumio volumio[997]: info: ALBUMART /albumart?cacheid=199&web=Johann%20Sebastian%20Bach/Complete%20Cantatas%20(2.11)/extralarge&path=%2Fmnt%2FUSB%2FVolumio_hdd_Dick%2FBach%2C%20Johann%20Sebastian%3B%20Masaaki%20Suzuki%2C%20Bach%20Collegium%20Japan%20-%20Complete%20Sacred%20Cantatas%2FBox%202%2FVol.11%2FJohann%20Sebastian%20Bach%20-%20Complete%20Cantatas%20(2.11).cue&metadata=false Sep 07 08:16:25 volumio volumio[997]: info: URI /mnt/USB/Volumio_hdd_Dick/Bach, Johann Sebastian; Masaaki Suzuki, Bach Collegium Japan - Complete Sacred Cantatas/Box 2/Vol.11/Johann Sebastian Bach - Complete Cantatas (2.11).cue Sep 07 08:16:25 volumio volumio[997]: info: ALBUMART /albumart?cacheid=199&web=Johann%20Sebastian%20Bach/Complete%20Cantatas%20(2.11)/extralarge&path=%2Fmnt%2FUSB%2FVolumio_hdd_Dick%2FBach%2C%20Johann%20Sebastian%3B%20Masaaki%20Suzuki%2C%20Bach%20Collegium%20Japan%20-%20Complete%20Sacred%20Cantatas%2FBox%202%2FVol.11%2FJohann%20Sebastian%20Bach%20-%20Complete%20Cantatas%20(2.11).cue&metadata=false Sep 07 08:16:25 volumio volumio[997]: info: URI /mnt/USB/Volumio_hdd_Dick/Bach, Johann Sebastian; Masaaki Suzuki, Bach Collegium Japan - Complete Sacred Cantatas/Box 2/Vol.11/Johann Sebastian Bach - Complete Cantatas (2.11).cue Sep 07 08:16:25 volumio volumio[997]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 08:16:25 volumio volumio[997]: Error: Unable to resolve or reject the same promise twice Sep 07 08:16:25 volumio volumio[997]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Sep 07 08:16:25 volumio volumio[997]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Sep 07 08:16:25 volumio volumio[997]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Sep 07 08:16:25 volumio volumio[997]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Sep 07 08:16:25 volumio volumio[997]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Sep 07 08:16:25 volumio volumio[997]: at Socket.emit (node:events:514:28) Sep 07 08:16:25 volumio volumio[997]: at addChunk (node:internal/streams/readable:343:12) Sep 07 08:16:25 volumio volumio[997]: at readableAddChunk (node:internal/streams/readable:312:11) Sep 07 08:16:25 volumio volumio[997]: at Readable.push (node:internal/streams/readable:253:10) Sep 07 08:16:25 volumio volumio[997]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Sep 07 08:16:25 volumio volumio[997]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 08:16:26 volumio sudo[13609]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-07 08:15' Sep 07 08:16:26 volumio sudo[13609]: 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="dfc7bb04e09bf2260691765a2340814af1fa2971" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="30d63a8c53d2826bd7f262e22b4e4511a4cc9d01" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Aug 30 06:39:32 UTC 2025" VOLUMIO_VERSION="4.022" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e7b0bd0cb5661ea77402e5bd121ec64a"