-- Logs begin at Fri 2025-05-09 08:57:01 HKT, end at Fri 2025-05-09 10:06:04 HKT. -- May 09 10:05:40 volumio ntpd[1264]: Soliciting pool server 2405:8d40:f27:cd00:3d0b:a6df:43f7:e7c9 May 09 10:05:46 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri May 09 10:05:46 volumio volumio[1277]: info: [youtube2-browse] browseUri: youtube2 May 09 10:05:46 volumio volumio[1277]: info: [youtube2] InnertubeLoader: creating Innertube instance... May 09 10:05:46 volumio sudo[10675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 10:05:46 volumio sudo[10675]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 10:05:46 volumio sudo[10678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 10:05:46 volumio sudo[10678]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 10:05:46 volumio sudo[10675]: pam_unix(sudo:session): session closed for user root May 09 10:05:46 volumio sudo[10678]: pam_unix(sudo:session): session closed for user root May 09 10:05:46 volumio volumio[1277]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 09 10:05:46 volumio volumio[1277]: info: CoreCommandRouter::volumioGetState May 09 10:05:46 volumio volumio[1277]: info: CorePlayQueue::getTrack 0 May 09 10:05:46 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 09 10:05:46 volumio volumio[1277]: info: CoreCommandRouter::volumioGetVisibleSources May 09 10:05:46 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 10:05:46 volumio volumio[1277]: info: Listing playlists May 09 10:05:46 volumio volumio[1277]: info: CoreCommandRouter::volumioGetQueue May 09 10:05:46 volumio volumio[1277]: info: CoreStateMachine::getQueue May 09 10:05:46 volumio volumio[1277]: info: CorePlayQueue::getQueue May 09 10:05:52 volumio ntpd[1264]: Soliciting pool server 222.127.1.23 May 09 10:05:52 volumio volumio[1277]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. May 09 10:05:52 volumio volumio[1277]: info: [youtube2] InnertubeLoader: obtaining po_token by visitorData... May 09 10:05:52 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 09 10:05:52 volumio volumio[1277]: info: CURURI: music-library May 09 10:05:53 volumio volumio[1277]: info: Preload queue cleared May 09 10:05:53 volumio volumio[1277]: info: [youtube2] InnertubeLoader: obtained po_token (expires in 43200 seconds) May 09 10:05:53 volumio volumio[1277]: info: [youtube2] InnertubeLoader: re-create Innertube instance with po_token May 09 10:05:53 volumio volumio[1277]: info: [youtube2] InnertubeLoader: creating Innertube instance with po_token... May 09 10:05:53 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 09 10:05:53 volumio volumio[1277]: info: CURURI: music-library/INTERNAL May 09 10:05:53 volumio volumio[1277]: info: Preload queue cleared May 09 10:05:54 volumio volumio[1277]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found May 09 10:05:54 volumio volumio[1277]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) May 09 10:05:54 volumio volumio[1277]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) May 09 10:05:54 volumio volumio[1277]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) May 09 10:05:54 volumio volumio[1277]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) May 09 10:05:54 volumio volumio[1277]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) May 09 10:05:54 volumio volumio[1277]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 09 10:05:54 volumio volumio[1277]: at processTicksAndRejections (internal/process/task_queues.js:77:11) May 09 10:05:55 volumio ntpd[1264]: Soliciting pool server 222.127.1.21 May 09 10:05:56 volumio volumio[1277]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found May 09 10:05:56 volumio volumio[1277]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) May 09 10:05:56 volumio volumio[1277]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) May 09 10:05:56 volumio volumio[1277]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) May 09 10:05:56 volumio volumio[1277]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) May 09 10:05:56 volumio volumio[1277]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) May 09 10:05:56 volumio volumio[1277]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 09 10:05:56 volumio volumio[1277]: at processTicksAndRejections (internal/process/task_queues.js:77:11) May 09 10:05:57 volumio ntpd[1264]: Soliciting pool server 222.127.1.22 May 09 10:05:59 volumio volumio[1277]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. May 09 10:05:59 volumio volumio[1277]: info: [youtube2] InnertubeLoader: going to refresh po_token in 43100 seconds May 09 10:06:00 volumio volumio[1277]: info: Preload queue cleared May 09 10:06:00 volumio volumio[1277]: info: Preload queue cleared May 09 10:06:00 volumio volumio[1277]: info: Preload queue cleared May 09 10:06:02 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 09 10:06:02 volumio volumio[1277]: info: CURURI: music-library/INTERNAL/48Oldies3 May 09 10:06:02 volumio volumio[1277]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found May 09 10:06:02 volumio volumio[1277]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) May 09 10:06:02 volumio volumio[1277]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) May 09 10:06:02 volumio volumio[1277]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) May 09 10:06:02 volumio volumio[1277]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) May 09 10:06:02 volumio volumio[1277]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) May 09 10:06:02 volumio volumio[1277]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 09 10:06:02 volumio volumio[1277]: at processTicksAndRejections (internal/process/task_queues.js:77:11) May 09 10:06:02 volumio volumio[1277]: info: Preload queue cleared May 09 10:06:02 volumio volumio[1277]: info: Preloading song: music-library/INTERNAL/48Oldies3/Track-01.mp3 May 09 10:06:02 volumio volumio[1277]: info: Preloading song: music-library/INTERNAL/48Oldies3/Track-02.flac May 09 10:06:02 volumio volumio[1277]: info: Preloading song: music-library/INTERNAL/48Oldies3/Track-03.flac May 09 10:06:02 volumio volumio[1277]: info: Preloading song: music-library/INTERNAL/48Oldies3/Track-01.flac May 09 10:06:02 volumio volumio[1277]: info: Preloading song: music-library/INTERNAL/48Oldies3/Track-02.mp3 May 09 10:06:02 volumio volumio[1277]: info: Preloading song: music-library/INTERNAL/48Oldies3/Track-03.mp3 May 09 10:06:02 volumio volumio[1277]: info: Exploding uri music-library/INTERNAL/48Oldies3/Track-01.mp3 in service mpd May 09 10:06:02 volumio volumio[1277]: info: ALBUMART /albumart?cacheid=510&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F48Oldies3%2FTrack-01.mp3&metadata=false May 09 10:06:02 volumio volumio[1277]: info: URI /mnt/INTERNAL/48Oldies3/Track-01.mp3 May 09 10:06:02 volumio volumio[1277]: info: Exploding uri music-library/INTERNAL/48Oldies3/Track-02.flac in service mpd May 09 10:06:02 volumio volumio[1277]: info: ALBUMART /albumart?cacheid=510&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F48Oldies3%2FTrack-02.flac&metadata=false May 09 10:06:02 volumio volumio[1277]: info: URI /mnt/INTERNAL/48Oldies3/Track-02.flac May 09 10:06:02 volumio volumio[1277]: info: Exploding uri music-library/INTERNAL/48Oldies3/Track-03.flac in service mpd May 09 10:06:02 volumio volumio[1277]: info: ALBUMART /albumart?cacheid=510&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F48Oldies3%2FTrack-03.flac&metadata=false May 09 10:06:02 volumio volumio[1277]: info: URI /mnt/INTERNAL/48Oldies3/Track-03.flac May 09 10:06:02 volumio volumio[1277]: info: Exploding uri music-library/INTERNAL/48Oldies3/Track-01.flac in service mpd May 09 10:06:02 volumio volumio[1277]: info: ALBUMART /albumart?cacheid=510&web=Various/48%20Non-Stop%20Greatest%20Oldies/extralarge&path=%2Fmnt%2FINTERNAL%2F48Oldies3%2FTrack-01.flac&metadata=false May 09 10:06:02 volumio volumio[1277]: info: URI /mnt/INTERNAL/48Oldies3/Track-01.flac May 09 10:06:02 volumio volumio[1277]: info: Exploding uri music-library/INTERNAL/48Oldies3/Track-02.mp3 in service mpd May 09 10:06:02 volumio volumio[1277]: info: ALBUMART /albumart?cacheid=510&web=Various/48%20Non-Stop%20Greatest%20Oldies/extralarge&path=%2Fmnt%2FINTERNAL%2F48Oldies3%2FTrack-02.mp3&metadata=false May 09 10:06:02 volumio volumio[1277]: info: URI /mnt/INTERNAL/48Oldies3/Track-02.mp3 May 09 10:06:02 volumio volumio[1277]: info: Exploding uri music-library/INTERNAL/48Oldies3/Track-03.mp3 in service mpd May 09 10:06:02 volumio volumio[1277]: info: ALBUMART /albumart?cacheid=510&web=Various/48%20Non-Stop%20Greatest%20Oldies/extralarge&path=%2Fmnt%2FINTERNAL%2F48Oldies3%2FTrack-03.mp3&metadata=false May 09 10:06:02 volumio volumio[1277]: info: URI /mnt/INTERNAL/48Oldies3/Track-03.mp3 May 09 10:06:04 volumio volumio[1277]: info: Preload queue cleared May 09 10:06:04 volumio volumio[1277]: info: CoreCommandRouter::volumioReplaceandPlayItems May 09 10:06:04 volumio volumio[1277]: info: CoreStateMachine::ClearQueue May 09 10:06:04 volumio volumio[1277]: info: CoreStateMachine::stop May 09 10:06:04 volumio volumio[1277]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 10:06:04 volumio volumio[1277]: info: CorePlayQueue::clearPlayQueue May 09 10:06:04 volumio volumio[1277]: info: CorePlayQueue::saveQueue May 09 10:06:04 volumio volumio[1277]: info: CoreCommandRouter::volumioPushQueue May 09 10:06:04 volumio volumio[1277]: info: CoreStateMachine::addQueueItems May 09 10:06:04 volumio volumio[1277]: info: CorePlayQueue::addQueueItems May 09 10:06:04 volumio volumio[1277]: info: Preload queue cleared May 09 10:06:04 volumio volumio[1277]: info: Adding Item to queue: music-library/INTERNAL/48Oldies3/Track-01.mp3 May 09 10:06:04 volumio volumio[1277]: info: Using cached record of: music-library/INTERNAL/48Oldies3/Track-01.mp3 May 09 10:06:04 volumio volumio[1277]: info: CoreCommandRouter::volumioPushQueue May 09 10:06:04 volumio volumio[1277]: info: CorePlayQueue::saveQueue May 09 10:06:04 volumio volumio[1277]: info: CoreStateMachine::updateTrackBlock May 09 10:06:04 volumio volumio[1277]: info: CorePlayQueue::getTrackBlock May 09 10:06:04 volumio volumio[1277]: info: CoreCommandRouter::volumioPlay May 09 10:06:04 volumio volumio[1277]: info: CoreStateMachine::play index 0 May 09 10:06:04 volumio volumio[1277]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 10:06:04 volumio volumio[1277]: info: CoreStateMachine::addQueueItems May 09 10:06:04 volumio volumio[1277]: info: CorePlayQueue::addQueueItems May 09 10:06:04 volumio volumio[1277]: info: Preload queue cleared May 09 10:06:04 volumio volumio[1277]: info: Adding Item to queue: music-library/INTERNAL/48Oldies3/Track-02.flac May 09 10:06:04 volumio volumio[1277]: info: Using cached record of: music-library/INTERNAL/48Oldies3/Track-02.flac May 09 10:06:04 volumio volumio[1277]: info: Adding Item to queue: music-library/INTERNAL/48Oldies3/Track-03.flac May 09 10:06:04 volumio volumio[1277]: info: Using cached record of: music-library/INTERNAL/48Oldies3/Track-03.flac May 09 10:06:04 volumio volumio[1277]: info: Adding Item to queue: music-library/INTERNAL/48Oldies3/Track-01.flac May 09 10:06:04 volumio volumio[1277]: info: Using cached record of: music-library/INTERNAL/48Oldies3/Track-01.flac May 09 10:06:04 volumio volumio[1277]: info: Adding Item to queue: music-library/INTERNAL/48Oldies3/Track-02.mp3 May 09 10:06:04 volumio volumio[1277]: info: Using cached record of: music-library/INTERNAL/48Oldies3/Track-02.mp3 May 09 10:06:04 volumio volumio[1277]: info: Adding Item to queue: music-library/INTERNAL/48Oldies3/Track-03.mp3 May 09 10:06:04 volumio volumio[1277]: info: Using cached record of: music-library/INTERNAL/48Oldies3/Track-03.mp3 May 09 10:06:04 volumio volumio[1277]: info: Adding Item to queue: music-library/INTERNAL/48Oldies3/Originals.zip May 09 10:06:04 volumio volumio[1277]: info: Exploding uri music-library/INTERNAL/48Oldies3/Originals.zip in service mpd May 09 10:06:04 volumio volumio[1277]: info: CoreStateMachine::stop May 09 10:06:04 volumio volumio[1277]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 10:06:04 volumio volumio[1277]: info: CoreStateMachine::play index undefined May 09 10:06:04 volumio volumio[1277]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 10:06:04 volumio volumio[1277]: info: CorePlayQueue::getTrack 0 May 09 10:06:04 volumio volumio[1277]: info: CoreStateMachine::startPlaybackTimer May 09 10:06:04 volumio volumio[1277]: info: CorePlayQueue::getTrack 0 May 09 10:06:04 volumio volumio[1277]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/48Oldies3/Track-01.mp3 May 09 10:06:04 volumio volumio[1277]: verbose: ControllerMpd::sendMpdCommand stop May 09 10:06:04 volumio volumio[1277]: info: ALBUMART /albumart?cacheid=510&web=Various/48%20Non-Stop%20Greatest%20Oldies/extralarge&path=%2Fmnt%2FINTERNAL%2F48Oldies3%2FOriginals.zip&metadata=false May 09 10:06:04 volumio volumio[1277]: info: URI /mnt/INTERNAL/48Oldies3/Originals.zip May 09 10:06:04 volumio volumio[1277]: info: ALBUMART /albumart?cacheid=510&web=Various/48%20Non-Stop%20Greatest%20Oldies/extralarge&path=%2Fmnt%2FINTERNAL%2F48Oldies3%2FOriginals.zip&metadata=false May 09 10:06:04 volumio volumio[1277]: info: URI /mnt/INTERNAL/48Oldies3/Originals.zip May 09 10:06:04 volumio volumio[1277]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 10:06:04 volumio volumio[1277]: Error: Unable to resolve or reject the same promise twice May 09 10:06:04 volumio volumio[1277]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 09 10:06:04 volumio volumio[1277]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 May 09 10:06:04 volumio volumio[1277]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) May 09 10:06:04 volumio volumio[1277]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) May 09 10:06:04 volumio volumio[1277]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) May 09 10:06:04 volumio volumio[1277]: at Socket.emit (events.js:400:28) May 09 10:06:04 volumio volumio[1277]: at addChunk (internal/streams/readable.js:293:12) May 09 10:06:04 volumio volumio[1277]: at readableAddChunk (internal/streams/readable.js:263:11) May 09 10:06:04 volumio volumio[1277]: at Socket.Readable.push (internal/streams/readable.js:206:10) May 09 10:06:04 volumio volumio[1277]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) May 09 10:06:04 volumio volumio[1277]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 10:06:04 volumio sudo[10723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-09 10:05 May 09 10:06:04 volumio sudo[10723]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 10:07:11 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"