-- Logs begin at Fri 2025-02-14 21:58:46 CST, end at Fri 2025-02-14 22:08:35 CST. -- Feb 14 22:07:10 volumio volumio[879]: Searching plugin music_service/webradio Feb 14 22:07:10 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 14 22:07:10 volumio volumio[879]: (node:879) UnhandledPromiseRejectionWarning: Error: Start tag expected, '<' not found Feb 14 22:07:10 volumio volumio[879]: at Object.module.exports.fromXml (/volumio/node_modules/libxmljs/lib/document.js:168:21) Feb 14 22:07:10 volumio volumio[879]: at /volumio/app/plugins/music_service/webradio/index.js:971:31 Feb 14 22:07:10 volumio volumio[879]: at runMicrotasks () Feb 14 22:07:10 volumio volumio[879]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Feb 14 22:07:10 volumio volumio[879]: (node:879) 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: 4) Feb 14 22:07:12 volumio volumio[879]: Searching plugin music_service/webradio Feb 14 22:07:12 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 14 22:07:16 volumio volumio[879]: info: All search sources collected, pushing search results Feb 14 22:07:19 volumio volumio[879]: Searching plugin music_service/webradio Feb 14 22:07:19 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 14 22:07:20 volumio volumio[879]: Searching plugin music_service/webradio Feb 14 22:07:20 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 14 22:07:20 volumio volumio[879]: (node:879) UnhandledPromiseRejectionWarning: Error: Start tag expected, '<' not found Feb 14 22:07:20 volumio volumio[879]: at Object.module.exports.fromXml (/volumio/node_modules/libxmljs/lib/document.js:168:21) Feb 14 22:07:20 volumio volumio[879]: at /volumio/app/plugins/music_service/webradio/index.js:971:31 Feb 14 22:07:20 volumio volumio[879]: at runMicrotasks () Feb 14 22:07:20 volumio volumio[879]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Feb 14 22:07:20 volumio volumio[879]: (node:879) 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: 5) Feb 14 22:07:21 volumio volumio[879]: info: All search sources collected, pushing search results Feb 14 22:07:21 volumio volumio[879]: Searching plugin music_service/webradio Feb 14 22:07:21 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 14 22:07:23 volumio volumio[879]: info: All search sources collected, pushing search results Feb 14 22:07:26 volumio volumio[879]: Searching plugin music_service/webradio Feb 14 22:07:26 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 14 22:07:40 volumio volumio[879]: Searching plugin music_service/webradio Feb 14 22:07:40 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 14 22:07:42 volumio volumio[879]: info: All search sources collected, pushing search results Feb 14 22:07:43 volumio volumio[879]: Searching plugin music_service/webradio Feb 14 22:07:43 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 14 22:07:44 volumio volumio[879]: Searching plugin music_service/webradio Feb 14 22:07:44 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 14 22:07:45 volumio volumio[879]: info: All search sources collected, pushing search results Feb 14 22:07:45 volumio volumio[879]: Searching plugin music_service/webradio Feb 14 22:07:45 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 14 22:07:46 volumio volumio[879]: info: All search sources collected, pushing search results Feb 14 22:07:52 volumio volumio[879]: Searching plugin music_service/webradio Feb 14 22:07:52 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 14 22:07:52 volumio volumio[879]: info: All search sources collected, pushing search results Feb 14 22:08:27 volumio volumio[879]: info: Feb 14 22:08:27 volumio volumio[879]: ---------------------------- MPD announces system playlist update Feb 14 22:08:27 volumio volumio[879]: info: Ignoring MPD Status Update Feb 14 22:08:27 volumio volumio[879]: info: Feb 14 22:08:27 volumio volumio[879]: ---------------------------- MPD announces state update: player Feb 14 22:08:27 volumio volumio[879]: info: ControllerMpd::getState Feb 14 22:08:27 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Feb 14 22:08:27 volumio volumio[879]: info: Feb 14 22:08:27 volumio volumio[879]: ---------------------------- MPD announces system playlist update Feb 14 22:08:27 volumio volumio[879]: info: Ignoring MPD Status Update Feb 14 22:08:27 volumio volumio[879]: info: Feb 14 22:08:27 volumio volumio[879]: ---------------------------- MPD announces state update: player Feb 14 22:08:27 volumio volumio[879]: info: ControllerMpd::getState Feb 14 22:08:27 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Feb 14 22:08:27 volumio volumio[879]: info: Feb 14 22:08:27 volumio volumio[879]: ---------------------------- MPD announces system playlist update Feb 14 22:08:27 volumio volumio[879]: info: Ignoring MPD Status Update Feb 14 22:08:27 volumio volumio[879]: info: Feb 14 22:08:27 volumio volumio[879]: ---------------------------- MPD announces state update: player Feb 14 22:08:27 volumio volumio[879]: info: ControllerMpd::getState Feb 14 22:08:27 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Feb 14 22:08:27 volumio volumio[879]: info: Feb 14 22:08:27 volumio volumio[879]: ---------------------------- MPD announces system playlist update Feb 14 22:08:27 volumio volumio[879]: info: Ignoring MPD Status Update Feb 14 22:08:27 volumio volumio[879]: info: Feb 14 22:08:27 volumio volumio[879]: ---------------------------- MPD announces state update: player Feb 14 22:08:27 volumio volumio[879]: info: ControllerMpd::getState Feb 14 22:08:27 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Feb 14 22:08:27 volumio volumio[879]: info: ------------------------------ 3ms Feb 14 22:08:27 volumio volumio[879]: info: sendMpdCommand status took 3 milliseconds Feb 14 22:08:27 volumio volumio[879]: info: ------------------------------ 2ms Feb 14 22:08:27 volumio volumio[879]: info: sendMpdCommand status took 2 milliseconds Feb 14 22:08:27 volumio volumio[879]: info: ------------------------------ 3ms Feb 14 22:08:27 volumio volumio[879]: info: sendMpdCommand status took 2 milliseconds Feb 14 22:08:27 volumio volumio[879]: info: ------------------------------ 2ms Feb 14 22:08:27 volumio volumio[879]: info: sendMpdCommand status took 2 milliseconds Feb 14 22:08:27 volumio volumio[879]: verbose: ControllerMpd::parseState Feb 14 22:08:27 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 14 22:08:27 volumio volumio[879]: verbose: ControllerMpd::parseState Feb 14 22:08:27 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 14 22:08:27 volumio volumio[879]: verbose: ControllerMpd::parseState Feb 14 22:08:27 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 14 22:08:27 volumio volumio[879]: verbose: ControllerMpd::parseState Feb 14 22:08:27 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 14 22:08:27 volumio volumio[879]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 14 22:08:27 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Feb 14 22:08:27 volumio volumio[879]: info: ControllerMpd::pushState Feb 14 22:08:27 volumio volumio[879]: info: CoreCommandRouter::servicePushState Feb 14 22:08:27 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Feb 14 22:08:27 volumio volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":128060,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Marina Rei - Al Di Là Di Questi Anni","artist":"70 80 90 ITALIA D'AUTORE","album":null,"uri":"https://maggie.torontocast.com:8118/stream","trackType":""} Feb 14 22:08:27 volumio volumio[879]: verbose: CURRENT POSITION 0 Feb 14 22:08:27 volumio volumio[879]: info: CoreStateMachine::syncState stateService play Feb 14 22:08:27 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus play Feb 14 22:08:27 volumio volumio[879]: info: Received an update from plugin. extracting info from payload Feb 14 22:08:27 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 14 22:08:27 volumio volumio[879]: info: CoreStateMachine::pushState Feb 14 22:08:27 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 14 22:08:27 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Feb 14 22:08:27 volumio volumio[879]: info: CoreStateMachine::pushState Feb 14 22:08:27 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Feb 14 22:08:27 volumio volumio[879]: info: ControllerMpd::pushState Feb 14 22:08:27 volumio volumio[879]: info: CoreCommandRouter::servicePushState Feb 14 22:08:27 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Feb 14 22:08:27 volumio volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":128060,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Marina Rei - Al Di Là Di Questi Anni","artist":"70 80 90 ITALIA D'AUTORE","album":null,"uri":"https://maggie.torontocast.com:8118/stream","trackType":""} Feb 14 22:08:27 volumio volumio[879]: verbose: CURRENT POSITION 0 Feb 14 22:08:27 volumio volumio[879]: info: CoreStateMachine::syncState stateService play Feb 14 22:08:27 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus play Feb 14 22:08:27 volumio volumio[879]: info: Received an update from plugin. extracting info from payload Feb 14 22:08:27 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 14 22:08:27 volumio volumio[879]: info: CoreStateMachine::pushState Feb 14 22:08:27 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Feb 14 22:08:27 volumio volumio[879]: info: CoreStateMachine::pushState Feb 14 22:08:27 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Feb 14 22:08:27 volumio volumio[879]: info: ControllerMpd::pushState Feb 14 22:08:27 volumio volumio[879]: info: CoreCommandRouter::servicePushState Feb 14 22:08:27 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Feb 14 22:08:27 volumio volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":128060,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Marina Rei - Al Di Là Di Questi Anni","artist":"70 80 90 ITALIA D'AUTORE","album":null,"uri":"https://maggie.torontocast.com:8118/stream","trackType":""} Feb 14 22:08:27 volumio volumio[879]: verbose: CURRENT POSITION 0 Feb 14 22:08:27 volumio volumio[879]: info: CoreStateMachine::syncState stateService play Feb 14 22:08:27 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus play Feb 14 22:08:27 volumio volumio[879]: info: Received an update from plugin. extracting info from payload Feb 14 22:08:27 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 14 22:08:27 volumio volumio[879]: info: CoreStateMachine::pushState Feb 14 22:08:27 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Feb 14 22:08:27 volumio volumio[879]: info: CoreStateMachine::pushState Feb 14 22:08:27 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Feb 14 22:08:27 volumio volumio[879]: info: ControllerMpd::pushState Feb 14 22:08:27 volumio volumio[879]: info: CoreCommandRouter::servicePushState Feb 14 22:08:27 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Feb 14 22:08:27 volumio volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":128060,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Marina Rei - Al Di Là Di Questi Anni","artist":"70 80 90 ITALIA D'AUTORE","album":null,"uri":"https://maggie.torontocast.com:8118/stream","trackType":""} Feb 14 22:08:27 volumio volumio[879]: verbose: CURRENT POSITION 0 Feb 14 22:08:27 volumio volumio[879]: info: CoreStateMachine::syncState stateService play Feb 14 22:08:27 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus play Feb 14 22:08:27 volumio volumio[879]: info: Received an update from plugin. extracting info from payload Feb 14 22:08:27 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 14 22:08:27 volumio volumio[879]: info: CoreStateMachine::pushState Feb 14 22:08:27 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Feb 14 22:08:27 volumio volumio[879]: info: CoreStateMachine::pushState Feb 14 22:08:27 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Feb 14 22:08:27 volumio volumio[879]: info: ------------------------------ 51ms Feb 14 22:08:27 volumio volumio[879]: info: ------------------------------ 50ms Feb 14 22:08:27 volumio volumio[879]: info: ------------------------------ 50ms Feb 14 22:08:27 volumio volumio[879]: info: ------------------------------ 50ms Feb 14 22:08:35 volumio volumio[879]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 14 22:08:35 volumio volumio[879]: Error: connect ETIMEDOUT 168.143.162.42:80 Feb 14 22:08:35 volumio volumio[879]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Feb 14 22:08:35 volumio volumio[879]: errno: -110, Feb 14 22:08:35 volumio volumio[879]: code: 'ETIMEDOUT', Feb 14 22:08:35 volumio volumio[879]: syscall: 'connect', Feb 14 22:08:35 volumio volumio[879]: address: '168.143.162.42', Feb 14 22:08:35 volumio volumio[879]: port: 80 Feb 14 22:08:35 volumio volumio[879]: } Feb 14 22:08:35 volumio volumio[879]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 14 22:08:35 volumio sudo[3524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-14 22:07 Feb 14 22:08:35 volumio sudo[3524]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"