-- Logs begin at Fri 2025-05-02 22:59:26 -07, end at Fri 2025-05-02 23:39:53 -07. -- May 02 23:38:01 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri May 02 23:38:01 volumio volumio[987]: info: In handleBrowseUri, curUri=spotify:album:0oy7i4zUSOH2smPYQoiI2P May 02 23:38:01 volumio volumio[987]: info: Preload queue cleared May 02 23:38:01 volumio volumio[987]: info: Preloading song: spotify:track:6E9KAk4S5zSCvpBFiqRY6N May 02 23:38:01 volumio volumio[987]: info: Preloading song: spotify:track:0tMfaskzgj7Dif6EPKquju May 02 23:38:01 volumio volumio[987]: info: Preloading song: spotify:track:0XC3YeBGaxbu8GuTkwxxej May 02 23:38:01 volumio volumio[987]: info: Preloading song: spotify:track:2Pg3LkPHA84EqY9gSIuLKV May 02 23:38:01 volumio volumio[987]: info: Preloading song: spotify:track:44G0Y2AVerBa6rU7aO5KAn May 02 23:38:01 volumio volumio[987]: info: Preloading song: spotify:track:48LSgK4uN1nZM0UPckBxin May 02 23:38:01 volumio volumio[987]: info: Preloading song: spotify:track:7lILZ2v0hb4eoQKs8dQYrm May 02 23:38:01 volumio volumio[987]: info: Preloading song: spotify:track:7zztXsHEQVzR7uK8fNCIrJ May 02 23:38:01 volumio volumio[987]: info: Preloading song: spotify:track:2u5KDfTYc6am5E6cPjBqqA May 02 23:38:01 volumio volumio[987]: info: Preloading song: spotify:track:0Dz5YrtYSIozZcGfUQ502M May 02 23:38:01 volumio volumio[987]: info: Preloading song: spotify:track:3auWL6jsEfbvpE5yUKu1ik May 02 23:38:01 volumio volumio[987]: info: Preloading song: spotify:track:5OsLp4QjevlwbnELI7QRbk May 02 23:38:01 volumio volumio[987]: info: Preloading song: spotify:track:0VrxyylevFRcM0OJKo7n3w May 02 23:38:01 volumio volumio[987]: info: Preloading song: spotify:track:1JueycPFak9jB5NPF4WKGJ May 02 23:38:01 volumio volumio[987]: info: Preloading song: spotify:track:1nMQrxFMWb4tasw1Kxj6S2 May 02 23:38:01 volumio volumio[987]: info: Preloading song: spotify:track:28Y3SNDJZY3t7DJmL95sJY May 02 23:38:01 volumio volumio[987]: info: Preloading song: spotify:track:3kfuYCgwLhaTYHZOvRRzJ5 May 02 23:38:01 volumio volumio[987]: info: Preloading song: spotify:track:37BLunHThw0YuCJUbWagOz May 02 23:38:01 volumio volumio[987]: info: Preloading song: spotify:track:2VNXLoSqEslAPHIAPsreZ3 May 02 23:38:01 volumio volumio[987]: info: Preloading song: spotify:track:61yBzX4ef0N0digwCekuxp May 02 23:38:01 volumio volumio[987]: info: Exploding uri spotify:track:6E9KAk4S5zSCvpBFiqRY6N in service spop May 02 23:38:01 volumio volumio[987]: SPOTIFY: EXPLODING URI:spotify:track:6E9KAk4S5zSCvpBFiqRY6N May 02 23:38:01 volumio volumio[987]: info: Exploding uri spotify:track:0tMfaskzgj7Dif6EPKquju in service spop May 02 23:38:01 volumio volumio[987]: SPOTIFY: EXPLODING URI:spotify:track:0tMfaskzgj7Dif6EPKquju May 02 23:38:01 volumio volumio[987]: info: Exploding uri spotify:track:0XC3YeBGaxbu8GuTkwxxej in service spop May 02 23:38:01 volumio volumio[987]: SPOTIFY: EXPLODING URI:spotify:track:0XC3YeBGaxbu8GuTkwxxej May 02 23:38:01 volumio volumio[987]: info: Exploding uri spotify:track:2Pg3LkPHA84EqY9gSIuLKV in service spop May 02 23:38:01 volumio volumio[987]: SPOTIFY: EXPLODING URI:spotify:track:2Pg3LkPHA84EqY9gSIuLKV May 02 23:38:01 volumio volumio[987]: info: Exploding uri spotify:track:44G0Y2AVerBa6rU7aO5KAn in service spop May 02 23:38:01 volumio volumio[987]: SPOTIFY: EXPLODING URI:spotify:track:44G0Y2AVerBa6rU7aO5KAn May 02 23:38:01 volumio volumio[987]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6E9KAk4S5zSCvpBFiqRY6N","service":"spop","name":"Sầu đông remix","artist":"Khưu Huy Vũ","album":"Thôi Em Về Đi Em","type":"song","duration":192,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fb119f69571f5c716c4ca09","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 02 23:38:01 volumio volumio[987]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0tMfaskzgj7Dif6EPKquju","service":"spop","name":"Sầu Lẻ Bóng","artist":"Khưu Huy Vũ","album":"Thôi Em Về Đi Em","type":"song","duration":316,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fb119f69571f5c716c4ca09","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 02 23:38:01 volumio volumio[987]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0XC3YeBGaxbu8GuTkwxxej","service":"spop","name":"Sầu lẻ bóng remix","artist":"Khưu Huy Vũ","album":"Thôi Em Về Đi Em","type":"song","duration":260,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fb119f69571f5c716c4ca09","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 02 23:38:01 volumio volumio[987]: info: Exploding uri spotify:track:48LSgK4uN1nZM0UPckBxin in service spop May 02 23:38:01 volumio volumio[987]: SPOTIFY: EXPLODING URI:spotify:track:48LSgK4uN1nZM0UPckBxin May 02 23:38:02 volumio volumio[987]: info: Exploding uri spotify:track:7lILZ2v0hb4eoQKs8dQYrm in service spop May 02 23:38:02 volumio volumio[987]: SPOTIFY: EXPLODING URI:spotify:track:7lILZ2v0hb4eoQKs8dQYrm May 02 23:38:02 volumio volumio[987]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2Pg3LkPHA84EqY9gSIuLKV","service":"spop","name":"Sầu tím thiệp hồng remix","artist":"Khưu Huy Vũ","album":"Thôi Em Về Đi Em","type":"song","duration":233,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fb119f69571f5c716c4ca09","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 02 23:38:02 volumio volumio[987]: info: Exploding uri spotify:track:7zztXsHEQVzR7uK8fNCIrJ in service spop May 02 23:38:02 volumio volumio[987]: SPOTIFY: EXPLODING URI:spotify:track:7zztXsHEQVzR7uK8fNCIrJ May 02 23:38:02 volumio volumio[987]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:44G0Y2AVerBa6rU7aO5KAn","service":"spop","name":"Say tình remix","artist":"Khưu Huy Vũ","album":"Thôi Em Về Đi Em","type":"song","duration":317,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fb119f69571f5c716c4ca09","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 02 23:38:02 volumio volumio[987]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:48LSgK4uN1nZM0UPckBxin","service":"spop","name":"Sến Nhảy","artist":"Khưu Huy Vũ","album":"Thôi Em Về Đi Em","type":"song","duration":297,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fb119f69571f5c716c4ca09","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 02 23:38:02 volumio volumio[987]: info: Exploding uri spotify:track:2u5KDfTYc6am5E6cPjBqqA in service spop May 02 23:38:02 volumio volumio[987]: SPOTIFY: EXPLODING URI:spotify:track:2u5KDfTYc6am5E6cPjBqqA May 02 23:38:02 volumio volumio[987]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7lILZ2v0hb4eoQKs8dQYrm","service":"spop","name":"Sến nhảy remix","artist":"Khưu Huy Vũ","album":"Thôi Em Về Đi Em","type":"song","duration":297,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fb119f69571f5c716c4ca09","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 02 23:38:02 volumio volumio[987]: info: Exploding uri spotify:track:0Dz5YrtYSIozZcGfUQ502M in service spop May 02 23:38:02 volumio volumio[987]: SPOTIFY: EXPLODING URI:spotify:track:0Dz5YrtYSIozZcGfUQ502M May 02 23:38:02 volumio volumio[987]: info: Exploding uri spotify:track:3auWL6jsEfbvpE5yUKu1ik in service spop May 02 23:38:02 volumio volumio[987]: SPOTIFY: EXPLODING URI:spotify:track:3auWL6jsEfbvpE5yUKu1ik May 02 23:38:02 volumio volumio[987]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7zztXsHEQVzR7uK8fNCIrJ","service":"spop","name":"Sợ Em Quên Anh (Beat)","artist":"Khanh Don","album":"Thôi Em Về Đi Em","type":"song","duration":283,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fb119f69571f5c716c4ca09","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 02 23:38:02 volumio volumio[987]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2u5KDfTYc6am5E6cPjBqqA","service":"spop","name":"Sóc sơ bai Sóc Trăng remix","artist":"Khưu Huy Vũ","album":"Thôi Em Về Đi Em","type":"song","duration":249,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fb119f69571f5c716c4ca09","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 02 23:38:02 volumio volumio[987]: info: Exploding uri spotify:track:5OsLp4QjevlwbnELI7QRbk in service spop May 02 23:38:02 volumio volumio[987]: SPOTIFY: EXPLODING URI:spotify:track:5OsLp4QjevlwbnELI7QRbk May 02 23:38:02 volumio volumio[987]: info: Exploding uri spotify:track:0VrxyylevFRcM0OJKo7n3w in service spop May 02 23:38:02 volumio volumio[987]: SPOTIFY: EXPLODING URI:spotify:track:0VrxyylevFRcM0OJKo7n3w May 02 23:38:02 volumio volumio[987]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0Dz5YrtYSIozZcGfUQ502M","service":"spop","name":"Sự Tích Trầu Cau","artist":"Khưu Huy Vũ","album":"Thôi Em Về Đi Em","type":"song","duration":946,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fb119f69571f5c716c4ca09","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 02 23:38:02 volumio volumio[987]: info: Exploding uri spotify:track:1JueycPFak9jB5NPF4WKGJ in service spop May 02 23:38:02 volumio volumio[987]: SPOTIFY: EXPLODING URI:spotify:track:1JueycPFak9jB5NPF4WKGJ May 02 23:38:02 volumio volumio[987]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3auWL6jsEfbvpE5yUKu1ik","service":"spop","name":"Tạm biệt người anh yêu beat","artist":"Khanh Don","album":"Thôi Em Về Đi Em","type":"song","duration":50,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fb119f69571f5c716c4ca09","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 02 23:38:02 volumio volumio[987]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5OsLp4QjevlwbnELI7QRbk","service":"spop","name":"Tận Đáy Lòng Anh (Beat)","artist":"Khanh Don","album":"Thôi Em Về Đi Em","type":"song","duration":249,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fb119f69571f5c716c4ca09","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 02 23:38:02 volumio volumio[987]: info: Exploding uri spotify:track:1nMQrxFMWb4tasw1Kxj6S2 in service spop May 02 23:38:02 volumio volumio[987]: SPOTIFY: EXPLODING URI:spotify:track:1nMQrxFMWb4tasw1Kxj6S2 May 02 23:38:02 volumio volumio[987]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0VrxyylevFRcM0OJKo7n3w","service":"spop","name":"Theo em góc biển chân trời beat","artist":"Khanh Don","album":"Thôi Em Về Đi Em","type":"song","duration":324,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fb119f69571f5c716c4ca09","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 02 23:38:02 volumio volumio[987]: info: Exploding uri spotify:track:28Y3SNDJZY3t7DJmL95sJY in service spop May 02 23:38:02 volumio volumio[987]: SPOTIFY: EXPLODING URI:spotify:track:28Y3SNDJZY3t7DJmL95sJY May 02 23:38:02 volumio volumio[987]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1JueycPFak9jB5NPF4WKGJ","service":"spop","name":"Theo Em Góc Biển Chân Trời Remix (Beat)","artist":"Khanh Don","album":"Thôi Em Về Đi Em","type":"song","duration":305,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fb119f69571f5c716c4ca09","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 02 23:38:02 volumio volumio[987]: info: Exploding uri spotify:track:3kfuYCgwLhaTYHZOvRRzJ5 in service spop May 02 23:38:02 volumio volumio[987]: SPOTIFY: EXPLODING URI:spotify:track:3kfuYCgwLhaTYHZOvRRzJ5 May 02 23:38:02 volumio volumio[987]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1nMQrxFMWb4tasw1Kxj6S2","service":"spop","name":"Thích Của Lạ","artist":"Khưu Huy Vũ","album":"Thôi Em Về Đi Em","type":"song","duration":272,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fb119f69571f5c716c4ca09","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 02 23:38:02 volumio volumio[987]: info: Exploding uri spotify:track:37BLunHThw0YuCJUbWagOz in service spop May 02 23:38:02 volumio volumio[987]: SPOTIFY: EXPLODING URI:spotify:track:37BLunHThw0YuCJUbWagOz May 02 23:38:02 volumio volumio[987]: info: Exploding uri spotify:track:2VNXLoSqEslAPHIAPsreZ3 in service spop May 02 23:38:02 volumio volumio[987]: SPOTIFY: EXPLODING URI:spotify:track:2VNXLoSqEslAPHIAPsreZ3 May 02 23:38:02 volumio volumio[987]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:28Y3SNDJZY3t7DJmL95sJY","service":"spop","name":"Thích Của Lạ (Beat)","artist":"Khưu Huy Vũ","album":"Thôi Em Về Đi Em","type":"song","duration":272,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fb119f69571f5c716c4ca09","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 02 23:38:02 volumio volumio[987]: info: Exploding uri spotify:track:61yBzX4ef0N0digwCekuxp in service spop May 02 23:38:02 volumio volumio[987]: SPOTIFY: EXPLODING URI:spotify:track:61yBzX4ef0N0digwCekuxp May 02 23:38:02 volumio volumio[987]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3kfuYCgwLhaTYHZOvRRzJ5","service":"spop","name":"Thích Của Lạ - Remix","artist":"Khưu Huy Vũ","album":"Thôi Em Về Đi Em","type":"song","duration":275,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fb119f69571f5c716c4ca09","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 02 23:38:02 volumio volumio[987]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:37BLunHThw0YuCJUbWagOz","service":"spop","name":"Thiên duyên tiền định","artist":"Khưu Huy Vũ","album":"Thôi Em Về Đi Em","type":"song","duration":238,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fb119f69571f5c716c4ca09","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 02 23:38:02 volumio volumio[987]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2VNXLoSqEslAPHIAPsreZ3","service":"spop","name":"Thói đời remix","artist":"Khưu Huy Vũ","album":"Thôi Em Về Đi Em","type":"song","duration":330,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fb119f69571f5c716c4ca09","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 02 23:38:02 volumio volumio[987]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:61yBzX4ef0N0digwCekuxp","service":"spop","name":"Thôi Em Về Đi Em","artist":"Khưu Huy Vũ","album":"Thôi Em Về Đi Em","type":"song","duration":297,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fb119f69571f5c716c4ca09","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 02 23:38:15 volumio go-librespot[1556]: time="2025-05-02T23:38:15-07:00" level=trace msg="sent dealer ping" May 02 23:38:15 volumio go-librespot[1556]: time="2025-05-02T23:38:15-07:00" level=trace msg="received dealer pong" May 02 23:38:23 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri May 02 23:38:23 volumio volumio[987]: info: In handleBrowseUri, curUri=spotify:album:40zm7IdwNVHR2h6JjNrGUw May 02 23:38:24 volumio volumio[987]: info: Preload queue cleared May 02 23:38:24 volumio volumio[987]: info: Preloading song: spotify:track:409FJ9zTOw5TYn1t6cwvrn May 02 23:38:24 volumio volumio[987]: info: Exploding uri spotify:track:409FJ9zTOw5TYn1t6cwvrn in service spop May 02 23:38:24 volumio volumio[987]: SPOTIFY: EXPLODING URI:spotify:track:409FJ9zTOw5TYn1t6cwvrn May 02 23:38:24 volumio volumio[987]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:409FJ9zTOw5TYn1t6cwvrn","service":"spop","name":"Thôi Em Về Đi Em","artist":"Khưu Huy Vũ","album":"Thôi Em Về Đi Em","type":"song","duration":297,"albumart":"https://i.scdn.co/image/ab67616d0000b273e3b7b658993ffe4a13a4ed25","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 02 23:38:38 volumio volumio[987]: Searching all installed plugins May 02 23:38:38 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 23:38:38 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: , search May 02 23:38:38 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 02 23:38:38 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search May 02 23:38:38 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: last_100 , search May 02 23:38:38 volumio volumio[987]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 May 02 23:38:38 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 02 23:38:38 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search May 02 23:38:38 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: spop , search May 02 23:38:39 volumio volumio[987]: (node:987) UnhandledPromiseRejectionWarning: Error: Start tag expected, '<' not found May 02 23:38:39 volumio volumio[987]: at Object.module.exports.fromXml (/volumio/node_modules/libxmljs/lib/document.js:168:21) May 02 23:38:39 volumio volumio[987]: at /volumio/app/plugins/music_service/webradio/index.js:949:31 May 02 23:38:39 volumio volumio[987]: at runMicrotasks () May 02 23:38:39 volumio volumio[987]: at processTicksAndRejections (internal/process/task_queues.js:95:5) May 02 23:38:39 volumio volumio[987]: (Use `node --trace-warnings ...` to show where the warning was created) May 02 23:38:39 volumio volumio[987]: (node:987) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1) May 02 23:38:39 volumio volumio[987]: (node:987) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. May 02 23:38:39 volumio volumio[987]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { May 02 23:38:39 volumio volumio[987]: command_run: { startIndex: 0, length: 75, onTap: { innertubeCommand: [Object] } }, May 02 23:38:39 volumio volumio[987]: input_data: { May 02 23:38:39 volumio volumio[987]: content: 'Éo Le Cuộc Tình Karaoke || Tone Nam - Nhạc Sống Thanh Ngân · 5:52', May 02 23:38:39 volumio volumio[987]: commandRuns: [ [Object] ] May 02 23:38:39 volumio volumio[987]: }, May 02 23:38:39 volumio volumio[987]: parsed_runs: [ May 02 23:38:39 volumio volumio[987]: { May 02 23:38:39 volumio volumio[987]: text: 'Éo Le Cuộc Tình Karaoke || Tone Nam - Nhạc Sống Thanh Ngân · 5:52', May 02 23:38:39 volumio volumio[987]: startIndex: 0 May 02 23:38:39 volumio volumio[987]: } May 02 23:38:39 volumio volumio[987]: ] May 02 23:38:39 volumio volumio[987]: } May 02 23:38:39 volumio volumio[987]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { May 02 23:38:39 volumio volumio[987]: command_run: { startIndex: 0, length: 72, onTap: { innertubeCommand: [Object] } }, May 02 23:38:39 volumio volumio[987]: input_data: { May 02 23:38:39 volumio volumio[987]: content: 'Karaoke Khóc Thầm - Tone Nam Nhạc Sống Beat Hay Dễ Hát · 5:32', May 02 23:38:39 volumio volumio[987]: commandRuns: [ [Object] ] May 02 23:38:39 volumio volumio[987]: }, May 02 23:38:39 volumio volumio[987]: parsed_runs: [ May 02 23:38:39 volumio volumio[987]: { May 02 23:38:39 volumio volumio[987]: text: 'Karaoke Khóc Thầm - Tone Nam Nhạc Sống Beat Hay Dễ Hát · 5:32', May 02 23:38:39 volumio volumio[987]: startIndex: 0 May 02 23:38:39 volumio volumio[987]: } May 02 23:38:39 volumio volumio[987]: ] May 02 23:38:39 volumio volumio[987]: } May 02 23:38:39 volumio volumio[987]: Searching all installed plugins May 02 23:38:39 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 23:38:39 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: , search May 02 23:38:39 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 02 23:38:39 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search May 02 23:38:39 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: last_100 , search May 02 23:38:39 volumio volumio[987]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 May 02 23:38:39 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 02 23:38:39 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search May 02 23:38:39 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: spop , search May 02 23:38:40 volumio volumio[987]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { May 02 23:38:40 volumio volumio[987]: command_run: { startIndex: 0, length: 57, onTap: { innertubeCommand: [Object] } }, May 02 23:38:40 volumio volumio[987]: input_data: { May 02 23:38:40 volumio volumio[987]: content: 'Karaoke | Áo Mới Cà Mau | Dương Hồng Loan · 5:06', May 02 23:38:40 volumio volumio[987]: commandRuns: [ [Object] ] May 02 23:38:40 volumio volumio[987]: }, May 02 23:38:40 volumio volumio[987]: parsed_runs: [ May 02 23:38:40 volumio volumio[987]: { May 02 23:38:40 volumio volumio[987]: text: 'Karaoke | Áo Mới Cà Mau | Dương Hồng Loan · 5:06', May 02 23:38:40 volumio volumio[987]: startIndex: 0 May 02 23:38:40 volumio volumio[987]: } May 02 23:38:40 volumio volumio[987]: ] May 02 23:38:40 volumio volumio[987]: } May 02 23:38:40 volumio volumio[987]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { May 02 23:38:40 volumio volumio[987]: command_run: { startIndex: 0, length: 53, onTap: { innertubeCommand: [Object] } }, May 02 23:38:40 volumio volumio[987]: input_data: { May 02 23:38:40 volumio volumio[987]: content: 'Giã Từ - Karaoke Nhạc Sống Thanh Ngân · 4:48', May 02 23:38:40 volumio volumio[987]: commandRuns: [ [Object] ] May 02 23:38:40 volumio volumio[987]: }, May 02 23:38:40 volumio volumio[987]: parsed_runs: [ May 02 23:38:40 volumio volumio[987]: { May 02 23:38:40 volumio volumio[987]: text: 'Giã Từ - Karaoke Nhạc Sống Thanh Ngân · 4:48', May 02 23:38:40 volumio volumio[987]: startIndex: 0 May 02 23:38:40 volumio volumio[987]: } May 02 23:38:40 volumio volumio[987]: ] May 02 23:38:40 volumio volumio[987]: } May 02 23:38:40 volumio volumio[987]: info: All search sources collected, pushing search results May 02 23:38:42 volumio volumio[987]: Searching all installed plugins May 02 23:38:42 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 23:38:42 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: , search May 02 23:38:42 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 02 23:38:42 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search May 02 23:38:42 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: last_100 , search May 02 23:38:42 volumio volumio[987]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 May 02 23:38:42 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 02 23:38:42 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search May 02 23:38:42 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: spop , search May 02 23:38:42 volumio volumio[987]: info: All search sources collected, pushing search results May 02 23:38:43 volumio volumio[987]: error: Search in plugin webradio timed out May 02 23:38:43 volumio volumio[987]: info: All search sources collected, pushing search results May 02 23:38:44 volumio volumio[987]: Searching all installed plugins May 02 23:38:44 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 23:38:44 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: , search May 02 23:38:44 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 02 23:38:44 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search May 02 23:38:44 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: last_100 , search May 02 23:38:44 volumio volumio[987]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 May 02 23:38:44 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 02 23:38:44 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search May 02 23:38:44 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: spop , search May 02 23:38:45 volumio volumio[987]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { May 02 23:38:45 volumio volumio[987]: command_run: { startIndex: 0, length: 75, onTap: { innertubeCommand: [Object] } }, May 02 23:38:45 volumio volumio[987]: input_data: { May 02 23:38:45 volumio volumio[987]: content: 'Éo Le Cuộc Tình Karaoke || Tone Nam - Nhạc Sống Thanh Ngân · 5:52', May 02 23:38:45 volumio volumio[987]: commandRuns: [ [Object] ] May 02 23:38:45 volumio volumio[987]: }, May 02 23:38:45 volumio volumio[987]: parsed_runs: [ May 02 23:38:45 volumio volumio[987]: { May 02 23:38:45 volumio volumio[987]: text: 'Éo Le Cuộc Tình Karaoke || Tone Nam - Nhạc Sống Thanh Ngân · 5:52', May 02 23:38:45 volumio volumio[987]: startIndex: 0 May 02 23:38:45 volumio volumio[987]: } May 02 23:38:45 volumio volumio[987]: ] May 02 23:38:45 volumio volumio[987]: } May 02 23:38:45 volumio volumio[987]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { May 02 23:38:45 volumio volumio[987]: command_run: { startIndex: 0, length: 57, onTap: { innertubeCommand: [Object] } }, May 02 23:38:45 volumio volumio[987]: input_data: { May 02 23:38:45 volumio volumio[987]: content: 'Karaoke | Áo Mới Cà Mau | Dương Hồng Loan · 5:06', May 02 23:38:45 volumio volumio[987]: commandRuns: [ [Object] ] May 02 23:38:45 volumio volumio[987]: }, May 02 23:38:45 volumio volumio[987]: parsed_runs: [ May 02 23:38:45 volumio volumio[987]: { May 02 23:38:45 volumio volumio[987]: text: 'Karaoke | Áo Mới Cà Mau | Dương Hồng Loan · 5:06', May 02 23:38:45 volumio volumio[987]: startIndex: 0 May 02 23:38:45 volumio volumio[987]: } May 02 23:38:45 volumio volumio[987]: ] May 02 23:38:45 volumio volumio[987]: } May 02 23:38:45 volumio volumio[987]: info: All search sources collected, pushing search results May 02 23:38:45 volumio go-librespot[1556]: time="2025-05-02T23:38:45-07:00" level=trace msg="sent dealer ping" May 02 23:38:45 volumio go-librespot[1556]: time="2025-05-02T23:38:45-07:00" level=trace msg="received dealer pong" May 02 23:38:46 volumio volumio[987]: Searching all installed plugins May 02 23:38:46 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 23:38:46 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: , search May 02 23:38:46 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 02 23:38:46 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search May 02 23:38:46 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: last_100 , search May 02 23:38:46 volumio volumio[987]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 May 02 23:38:46 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 02 23:38:46 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search May 02 23:38:46 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: spop , search May 02 23:38:47 volumio volumio[987]: info: All search sources collected, pushing search results May 02 23:38:48 volumio volumio[987]: Searching all installed plugins May 02 23:38:48 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 23:38:48 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: , search May 02 23:38:48 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 02 23:38:48 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search May 02 23:38:48 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: last_100 , search May 02 23:38:48 volumio volumio[987]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 May 02 23:38:48 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 02 23:38:48 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search May 02 23:38:48 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: spop , search May 02 23:38:49 volumio ntpd[966]: Soliciting pool server 115.165.161.155 May 02 23:38:49 volumio volumio[987]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { May 02 23:38:49 volumio volumio[987]: command_run: { startIndex: 0, length: 75, onTap: { innertubeCommand: [Object] } }, May 02 23:38:49 volumio volumio[987]: input_data: { May 02 23:38:49 volumio volumio[987]: content: 'Éo Le Cuộc Tình Karaoke || Tone Nam - Nhạc Sống Thanh Ngân · 5:52', May 02 23:38:49 volumio volumio[987]: commandRuns: [ [Object] ] May 02 23:38:49 volumio volumio[987]: }, May 02 23:38:49 volumio volumio[987]: parsed_runs: [ May 02 23:38:49 volumio volumio[987]: { May 02 23:38:49 volumio volumio[987]: text: 'Éo Le Cuộc Tình Karaoke || Tone Nam - Nhạc Sống Thanh Ngân · 5:52', May 02 23:38:49 volumio volumio[987]: startIndex: 0 May 02 23:38:49 volumio volumio[987]: } May 02 23:38:49 volumio volumio[987]: ] May 02 23:38:49 volumio volumio[987]: } May 02 23:38:49 volumio volumio[987]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { May 02 23:38:49 volumio volumio[987]: command_run: { startIndex: 0, length: 57, onTap: { innertubeCommand: [Object] } }, May 02 23:38:49 volumio volumio[987]: input_data: { May 02 23:38:49 volumio volumio[987]: content: 'Karaoke | Áo Mới Cà Mau | Dương Hồng Loan · 5:06', May 02 23:38:49 volumio volumio[987]: commandRuns: [ [Object] ] May 02 23:38:49 volumio volumio[987]: }, May 02 23:38:49 volumio volumio[987]: parsed_runs: [ May 02 23:38:49 volumio volumio[987]: { May 02 23:38:49 volumio volumio[987]: text: 'Karaoke | Áo Mới Cà Mau | Dương Hồng Loan · 5:06', May 02 23:38:49 volumio volumio[987]: startIndex: 0 May 02 23:38:49 volumio volumio[987]: } May 02 23:38:49 volumio volumio[987]: ] May 02 23:38:49 volumio volumio[987]: } May 02 23:38:49 volumio volumio[987]: info: All search sources collected, pushing search results May 02 23:38:50 volumio ntpd[966]: Soliciting pool server 103.184.124.254 May 02 23:38:51 volumio ntpd[966]: Soliciting pool server 103.184.124.254 May 02 23:38:58 volumio ntpd[966]: Soliciting pool server 2404:fbc0:0:2046::c May 02 23:39:10 volumio volumio[987]: info: Preload queue cleared May 02 23:39:10 volumio volumio[987]: info: CoreCommandRouter::volumioReplaceandPlayItems May 02 23:39:10 volumio volumio[987]: info: CoreStateMachine::ClearQueue May 02 23:39:10 volumio volumio[987]: info: CoreStateMachine::stop May 02 23:39:10 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 23:39:10 volumio volumio[987]: info: CoreStateMachine::stPlaybackTimer May 02 23:39:10 volumio volumio[987]: info: CoreStateMachine::updateTrackBlock May 02 23:39:10 volumio volumio[987]: info: CorePlayQueue::getTrackBlock May 02 23:39:10 volumio volumio[987]: info: CoreStateMachine::pushState May 02 23:39:10 volumio volumio[987]: info: CorePlayQueue::getTrack 74 May 02 23:39:10 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 23:39:10 volumio volumio[987]: info: CoreCommandRouter::volumioPushState May 02 23:39:10 volumio volumio[987]: info: CoreStateMachine::serviceStop May 02 23:39:10 volumio volumio[987]: info: CorePlayQueue::getTrack 74 May 02 23:39:10 volumio volumio[987]: info: CoreCommandRouter::serviceStop May 02 23:39:10 volumio volumio[987]: info: ControllerMpd::stop May 02 23:39:10 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand stop May 02 23:39:10 volumio volumio[987]: info: CorePlayQueue::clearPlayQueue May 02 23:39:10 volumio volumio[987]: info: CorePlayQueue::saveQueue May 02 23:39:10 volumio volumio[987]: info: CoreCommandRouter::volumioPushQueue May 02 23:39:10 volumio volumio[987]: info: CoreStateMachine::addQueueItems May 02 23:39:10 volumio volumio[987]: info: CorePlayQueue::addQueueItems May 02 23:39:10 volumio volumio[987]: info: Preload queue cleared May 02 23:39:10 volumio volumio[987]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22%C3%89o%20Le%20Cu%E1%BB%99c%20T%C3%ACnh%20%7C%20D%C6%B0%C6%A1ng%20Ng%E1%BB%8Dc%20Th%C3%A1i%20%23shorts%22%2C%22artist%22%3A%22363%C2%A0N%20l%C6%B0%E1%BB%A3t%20xem%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FCh3kgnTFEU4%2Foar2.jpg%3Fsqp%3D-oaymwEdCJUDENAFSFWQAgHyq4qpAwwIARUAAIhCcAHAAQY%3D%26rs%3DAOn4CLA9r2j6CsXsU65gpcIGbg0ej4iNHQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Ch3kgnTFEU4%22%2C%22params%22%3A%22CBQwAkosChUiE8OJbyBsZSBjdeG7mWMgdMOsbmgSE8OJbyBsZSBjdeG7mWMgdMOsbmg%253D%22%7D%7D%7D May 02 23:39:10 volumio volumio[987]: info: Exploding uri youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22%C3%89o%20Le%20Cu%E1%BB%99c%20T%C3%ACnh%20%7C%20D%C6%B0%C6%A1ng%20Ng%E1%BB%8Dc%20Th%C3%A1i%20%23shorts%22%2C%22artist%22%3A%22363%C2%A0N%20l%C6%B0%E1%BB%A3t%20xem%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FCh3kgnTFEU4%2Foar2.jpg%3Fsqp%3D-oaymwEdCJUDENAFSFWQAgHyq4qpAwwIARUAAIhCcAHAAQY%3D%26rs%3DAOn4CLA9r2j6CsXsU65gpcIGbg0ej4iNHQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Ch3kgnTFEU4%22%2C%22params%22%3A%22CBQwAkosChUiE8OJbyBsZSBjdeG7mWMgdMOsbmgSE8OJbyBsZSBjdeG7mWMgdMOsbmg%253D%22%7D%7D%7D in service youtube2 May 02 23:39:10 volumio volumio[987]: info: [youtube2-browse] explodeUri: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22%C3%89o%20Le%20Cu%E1%BB%99c%20T%C3%ACnh%20%7C%20D%C6%B0%C6%A1ng%20Ng%E1%BB%8Dc%20Th%C3%A1i%20%23shorts%22%2C%22artist%22%3A%22363%C2%A0N%20l%C6%B0%E1%BB%A3t%20xem%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FCh3kgnTFEU4%2Foar2.jpg%3Fsqp%3D-oaymwEdCJUDENAFSFWQAgHyq4qpAwwIARUAAIhCcAHAAQY%3D%26rs%3DAOn4CLA9r2j6CsXsU65gpcIGbg0ej4iNHQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Ch3kgnTFEU4%22%2C%22params%22%3A%22CBQwAkosChUiE8OJbyBsZSBjdeG7mWMgdMOsbmgSE8OJbyBsZSBjdeG7mWMgdMOsbmg%253D%22%7D%7D%7D May 02 23:39:10 volumio volumio[987]: info: CoreCommandRouter::volumioPushQueue May 02 23:39:10 volumio volumio[987]: info: CorePlayQueue::saveQueue May 02 23:39:10 volumio volumio[987]: info: CoreStateMachine::updateTrackBlock May 02 23:39:10 volumio volumio[987]: info: CorePlayQueue::getTrackBlock May 02 23:39:10 volumio volumio[987]: info: CoreCommandRouter::volumioPlay May 02 23:39:10 volumio volumio[987]: info: CoreStateMachine::play index 0 May 02 23:39:10 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 23:39:10 volumio volumio[987]: info: CoreStateMachine::stop May 02 23:39:10 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 23:39:10 volumio volumio[987]: info: CoreStateMachine::play index undefined May 02 23:39:10 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 23:39:10 volumio volumio[987]: info: CorePlayQueue::getTrack 0 May 02 23:39:10 volumio volumio[987]: info: CoreStateMachine::startPlaybackTimer May 02 23:39:10 volumio volumio[987]: info: CorePlayQueue::getTrack 0 May 02 23:39:10 volumio volumio[987]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Ch3kgnTFEU4%22%2C%22params%22%3A%22CBQwAkosChUiE8OJbyBsZSBjdeG7mWMgdMOsbmgSE8OJbyBsZSBjdeG7mWMgdMOsbmg%253D%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22%C3%89o%20Le%20Cu%E1%BB%99c%20T%C3%ACnh%20%7C%20D%C6%B0%C6%A1ng%20Ng%E1%BB%8Dc%20Th%C3%A1i%20%23shorts%22%2C%22artist%22%3A%22363%C2%A0N%20l%C6%B0%E1%BB%A3t%20xem%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FCh3kgnTFEU4%2Foar2.jpg%3Fsqp%3D-oaymwEdCJUDENAFSFWQAgHyq4qpAwwIARUAAIhCcAHAAQY%3D%26rs%3DAOn4CLA9r2j6CsXsU65gpcIGbg0ej4iNHQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Ch3kgnTFEU4%22%2C%22params%22%3A%22CBQwAkosChUiE8OJbyBsZSBjdeG7mWMgdMOsbmgSE8OJbyBsZSBjdeG7mWMgdMOsbmg%253D%22%7D%7D%7D May 02 23:39:10 volumio volumio[987]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 May 02 23:39:10 volumio volumio[987]: info: May 02 23:39:10 volumio volumio[987]: ---------------------------- MPD announces state update: player May 02 23:39:10 volumio volumio[987]: info: sendMpdCommand stop took 48 milliseconds May 02 23:39:10 volumio volumio[987]: info: ControllerMpd::getState May 02 23:39:10 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status May 02 23:39:10 volumio volumio[987]: info: May 02 23:39:10 volumio volumio[987]: ---------------------------- MPD announces state update: player May 02 23:39:10 volumio volumio[987]: info: ControllerMpd::getState May 02 23:39:10 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status May 02 23:39:10 volumio volumio[987]: info: May 02 23:39:10 volumio volumio[987]: ---------------------------- MPD announces state update: player May 02 23:39:10 volumio volumio[987]: info: ControllerMpd::getState May 02 23:39:10 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status May 02 23:39:10 volumio volumio[987]: info: sendMpdCommand status took 6 milliseconds May 02 23:39:10 volumio volumio[987]: info: sendMpdCommand status took 3 milliseconds May 02 23:39:10 volumio volumio[987]: info: sendMpdCommand status took 3 milliseconds May 02 23:39:10 volumio volumio[987]: verbose: ControllerMpd::parseState May 02 23:39:10 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:39:10 volumio volumio[987]: verbose: ControllerMpd::parseState May 02 23:39:10 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:39:10 volumio volumio[987]: verbose: ControllerMpd::parseState May 02 23:39:10 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:39:10 volumio volumio[987]: info: sendMpdCommand playlistinfo took 3 milliseconds May 02 23:39:10 volumio volumio[987]: info: sendMpdCommand playlistinfo took 3 milliseconds May 02 23:39:10 volumio volumio[987]: info: sendMpdCommand playlistinfo took 3 milliseconds May 02 23:39:10 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo May 02 23:39:10 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo May 02 23:39:10 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo May 02 23:39:10 volumio volumio[987]: info: ControllerMpd::pushState May 02 23:39:10 volumio volumio[987]: info: CoreCommandRouter::servicePushState May 02 23:39:10 volumio volumio[987]: info: CoreStateMachine::pushState May 02 23:39:10 volumio volumio[987]: info: CorePlayQueue::getTrack 0 May 02 23:39:10 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 23:39:10 volumio volumio[987]: info: CoreCommandRouter::volumioPushState May 02 23:39:10 volumio volumio[987]: info: CorePlayQueue::getTrack 0 May 02 23:39:10 volumio volumio[987]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd May 02 23:39:10 volumio volumio[987]: info: ControllerMpd::pushState May 02 23:39:10 volumio volumio[987]: info: CoreCommandRouter::servicePushState May 02 23:39:10 volumio volumio[987]: info: CoreStateMachine::pushState May 02 23:39:10 volumio volumio[987]: info: CorePlayQueue::getTrack 0 May 02 23:39:10 volumio volumio[987]: info: CoreCommandRouter::volumioPushState May 02 23:39:10 volumio volumio[987]: info: CorePlayQueue::getTrack 0 May 02 23:39:10 volumio volumio[987]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd May 02 23:39:10 volumio volumio[987]: info: ControllerMpd::pushState May 02 23:39:10 volumio volumio[987]: info: CoreCommandRouter::servicePushState May 02 23:39:10 volumio volumio[987]: info: CoreStateMachine::pushState May 02 23:39:10 volumio volumio[987]: info: CorePlayQueue::getTrack 0 May 02 23:39:10 volumio volumio[987]: info: CoreCommandRouter::volumioPushState May 02 23:39:10 volumio volumio[987]: info: CorePlayQueue::getTrack 0 May 02 23:39:10 volumio volumio[987]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd May 02 23:39:10 volumio volumio[987]: info: ------------------------------ 49ms May 02 23:39:10 volumio volumio[987]: info: ------------------------------ 44ms May 02 23:39:10 volumio volumio[987]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 May 02 23:39:10 volumio volumio[987]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 May 02 23:39:10 volumio volumio[987]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 May 02 23:39:10 volumio volumio[987]: error: [youtube2] Error in VideoModel.getInfo(Ch3kgnTFEU4): No valid URL to decipher Error: No valid URL to decipher May 02 23:39:10 volumio volumio[987]: at _Player.decipher (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:34251:13) May 02 23:39:10 volumio volumio[987]: at _Format.decipher (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:23105:19) May 02 23:39:10 volumio volumio[987]: at VideoModel._VideoModel_chooseFormat (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:86:39) May 02 23:39:10 volumio volumio[987]: at VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:69:116) May 02 23:39:10 volumio volumio[987]: at processTicksAndRejections (internal/process/task_queues.js:95:5) May 02 23:39:10 volumio volumio[987]: at async Function.getPlaybackInfoFromUri (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:167:19) May 02 23:39:10 volumio volumio[987]: at async PlayController.clearAddPlayTrack (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:89:49) May 02 23:39:15 volumio go-librespot[1556]: time="2025-05-02T23:39:15-07:00" level=trace msg="sent dealer ping" May 02 23:39:15 volumio go-librespot[1556]: time="2025-05-02T23:39:15-07:00" level=trace msg="received dealer pong" May 02 23:39:22 volumio volumio[987]: info: CoreCommandRouter::volumioPlay May 02 23:39:22 volumio volumio[987]: info: CoreStateMachine::play index undefined May 02 23:39:22 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 23:39:22 volumio volumio[987]: info: CorePlayQueue::getTrack 0 May 02 23:39:22 volumio volumio[987]: info: CoreStateMachine::startPlaybackTimer May 02 23:39:22 volumio volumio[987]: info: CorePlayQueue::getTrack 0 May 02 23:39:22 volumio volumio[987]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Ch3kgnTFEU4%22%2C%22params%22%3A%22CBQwAkosChUiE8OJbyBsZSBjdeG7mWMgdMOsbmgSE8OJbyBsZSBjdeG7mWMgdMOsbmg%253D%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22%C3%89o%20Le%20Cu%E1%BB%99c%20T%C3%ACnh%20%7C%20D%C6%B0%C6%A1ng%20Ng%E1%BB%8Dc%20Th%C3%A1i%20%23shorts%22%2C%22artist%22%3A%22363%C2%A0N%20l%C6%B0%E1%BB%A3t%20xem%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FCh3kgnTFEU4%2Foar2.jpg%3Fsqp%3D-oaymwEdCJUDENAFSFWQAgHyq4qpAwwIARUAAIhCcAHAAQY%3D%26rs%3DAOn4CLA9r2j6CsXsU65gpcIGbg0ej4iNHQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Ch3kgnTFEU4%22%2C%22params%22%3A%22CBQwAkosChUiE8OJbyBsZSBjdeG7mWMgdMOsbmgSE8OJbyBsZSBjdeG7mWMgdMOsbmg%253D%22%7D%7D%7D May 02 23:39:22 volumio volumio[987]: error: [youtube2] Error in VideoModel.getInfo(Ch3kgnTFEU4): No valid URL to decipher Error: No valid URL to decipher May 02 23:39:22 volumio volumio[987]: at _Player.decipher (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:34251:13) May 02 23:39:22 volumio volumio[987]: at _Format.decipher (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:23105:19) May 02 23:39:22 volumio volumio[987]: at VideoModel._VideoModel_chooseFormat (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:86:39) May 02 23:39:22 volumio volumio[987]: at VideoModel.getPlaybackInfo (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:69:116) May 02 23:39:22 volumio volumio[987]: at processTicksAndRejections (internal/process/task_queues.js:95:5) May 02 23:39:22 volumio volumio[987]: at async Function.getPlaybackInfoFromUri (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:167:19) May 02 23:39:22 volumio volumio[987]: at async PlayController.clearAddPlayTrack (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:89:49) May 02 23:39:45 volumio go-librespot[1556]: time="2025-05-02T23:39:45-07:00" level=trace msg="received accesspoint ping" May 02 23:39:45 volumio go-librespot[1556]: time="2025-05-02T23:39:45-07:00" level=trace msg="received accesspoint pong ack" May 02 23:39:45 volumio go-librespot[1556]: time="2025-05-02T23:39:45-07:00" level=trace msg="sent dealer ping" May 02 23:39:45 volumio go-librespot[1556]: time="2025-05-02T23:39:45-07:00" level=trace msg="received dealer pong" May 02 23:39:53 volumio volumio[987]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 23:39:53 volumio volumio[987]: Error: connect ETIMEDOUT 151.101.78.79:80 May 02 23:39:53 volumio volumio[987]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 02 23:39:53 volumio volumio[987]: errno: -110, May 02 23:39:53 volumio volumio[987]: code: 'ETIMEDOUT', May 02 23:39:53 volumio volumio[987]: syscall: 'connect', May 02 23:39:53 volumio volumio[987]: address: '151.101.78.79', May 02 23:39:53 volumio volumio[987]: port: 80 May 02 23:39:53 volumio volumio[987]: } May 02 23:39:53 volumio volumio[987]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 23:39:53 volumio sudo[7983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-02 23:38 May 02 23:39:53 volumio sudo[7983]: 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="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"