-- Logs begin at Thu 2019-02-14 05:11:58 EST, end at Fri 2025-07-11 21:09:59 EDT. -- Jul 11 21:08:01 volumio volumio[1181]: verbose: New Socket.io Connection to 192.168.50.187 from 192.168.50.164 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 7 Jul 11 21:08:01 volumio volumio[1181]: info: CoreCommandRouter::volumioGetState Jul 11 21:08:01 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Jul 11 21:08:01 volumio volumio[1181]: info: Received Get System Info Jul 11 21:08:01 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 21:08:01 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 21:08:01 volumio volumio[1181]: info: Discovery: Getting this device information Jul 11 21:08:01 volumio volumio[1181]: info: CoreCommandRouter::volumioGetState Jul 11 21:08:01 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Jul 11 21:08:01 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 21:08:01 volumio volumio[1181]: info: CoreCommandRouter::volumioGetState Jul 11 21:08:01 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Jul 11 21:08:01 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 11 21:08:01 volumio volumio[1181]: info: CoreCommandRouter::volumioGetVisibleSources Jul 11 21:08:01 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 21:08:01 volumio volumio[1181]: info: CoreCommandRouter::volumioGetState Jul 11 21:08:01 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Jul 11 21:08:01 volumio volumio[1181]: info: CoreCommandRouter::volumioGetState Jul 11 21:08:01 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Jul 11 21:08:01 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 11 21:08:01 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 11 21:08:01 volumio volumio[1181]: info: Received Get System Info Jul 11 21:08:01 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 21:08:01 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 21:08:01 volumio volumio[1181]: info: Discovery: Getting this device information Jul 11 21:08:01 volumio volumio[1181]: info: CoreCommandRouter::volumioGetState Jul 11 21:08:01 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Jul 11 21:08:01 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 21:08:01 volumio volumio[1181]: info: CoreCommandRouter::volumioGetState Jul 11 21:08:01 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Jul 11 21:08:01 volumio volumio[1181]: info: Listing playlists Jul 11 21:08:02 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 11 21:08:02 volumio volumio[1181]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 21:08:02 volumio volumio[1181]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 21:08:02 volumio volumio[1181]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 21:08:02 volumio volumio[1181]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 21:08:02 volumio volumio[1181]: info: FusionDsp - Jul 11 21:08:02 volumio volumio[1181]: info: FusionDsp - Jul 11 21:08:02 volumio volumio[1181]: info: FusionDsp - Jul 11 21:08:02 volumio volumio[1181]: info: FusionDsp - undefined Jul 11 21:08:02 volumio volumio[1181]: info: FusionDsp - undefined Jul 11 21:08:03 volumio volumio[1181]: info: FusionDsp - undefined Jul 11 21:08:03 volumio volumio[1181]: info: FusionDsp - Jul 11 21:08:03 volumio volumio[1181]: info: FusionDsp - undefined Jul 11 21:08:03 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jul 11 21:08:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jul 11 21:08:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jul 11 21:08:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jul 11 21:08:06 volumio wpa_supplicant[897]: wlan0: Failed to initiate sched scan Jul 11 21:08:11 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 11 21:08:15 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jul 11 21:08:16 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jul 11 21:08:16 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jul 11 21:08:16 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jul 11 21:08:18 volumio wpa_supplicant[897]: wlan0: Failed to initiate sched scan Jul 11 21:08:27 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jul 11 21:08:27 volumio volumio[1181]: info: CoreCommandRouter::volumioGetQueue Jul 11 21:08:27 volumio volumio[1181]: info: CoreStateMachine::getQueue Jul 11 21:08:27 volumio volumio[1181]: info: CorePlayQueue::getQueue Jul 11 21:08:27 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Jul 11 21:08:27 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , goto Jul 11 21:08:27 volumio volumio[1181]: info: Error : CoreCommandRouter::executeOnPlugin: No method [goto] in plugin upnp_browser Jul 11 21:08:28 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jul 11 21:08:28 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jul 11 21:08:28 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jul 11 21:08:30 volumio wpa_supplicant[897]: wlan0: Failed to initiate sched scan Jul 11 21:08:39 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jul 11 21:08:40 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jul 11 21:08:40 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jul 11 21:08:40 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jul 11 21:08:42 volumio wpa_supplicant[897]: wlan0: Failed to initiate sched scan Jul 11 21:08:51 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jul 11 21:08:51 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jul 11 21:08:52 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jul 11 21:08:52 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jul 11 21:08:54 volumio wpa_supplicant[897]: wlan0: Failed to initiate sched scan Jul 11 21:08:55 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Jul 11 21:08:55 volumio volumio[1181]: info: [jellyfin-browse] browseUri: jellyfin Jul 11 21:08:55 volumio volumio[1181]: info: Preload queue cleared Jul 11 21:08:55 volumio ntpd[774]: 23.111.186.186 local addr 192.168.50.187 -> Jul 11 21:09:03 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jul 11 21:09:03 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jul 11 21:09:04 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 11 21:09:04 volumio volumio[1181]: info: CURURI: music-library Jul 11 21:09:04 volumio volumio[1181]: error: Failed LSINFO: null Jul 11 21:09:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jul 11 21:09:04 volumio volumio[1181]: info: Preload queue cleared Jul 11 21:09:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jul 11 21:09:06 volumio wpa_supplicant[897]: wlan0: Failed to initiate sched scan Jul 11 21:09:14 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 11 21:09:14 volumio volumio[1181]: info: CURURI: albums:// Jul 11 21:09:14 volumio volumio[1181]: info: listAlbums - loading Albums from cache Jul 11 21:09:14 volumio volumio[1181]: info: Preload queue cleared Jul 11 21:09:15 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jul 11 21:09:15 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jul 11 21:09:15 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jul 11 21:09:16 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jul 11 21:09:18 volumio wpa_supplicant[897]: wlan0: Failed to initiate sched scan Jul 11 21:09:19 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 11 21:09:19 volumio volumio[1181]: info: Preload queue cleared Jul 11 21:09:24 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 11 21:09:24 volumio volumio[1181]: error: Error browsing0:Error: Did not get expected response from server:{"Envelope":{"$":{"xmlns:SOAP-ENV":"http://schemas.xmlsoap.org/soap/envelope/","SOAP-ENV:encodingStyle":"http://schemas.xmlsoap.org/soap/encoding/"},"Body":[{"Fault":[{"faultcode":[{"_":"SOAP-ENV:Client"}],"faultstring":[{"_":"UPnPError"}],"detail":[{"UPnPError":[{"$":{"xmlns:u":"urn:schemas-upnp-org:control-1-0"},"errorCode":[{"_":"801"}],"errorDescription":[{"_":"Access denied"}]}]}]}]}]}} Jul 11 21:09:24 volumio volumio[1181]: error: Failed to execute browseSource: Jul 11 21:09:25 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 11 21:09:25 volumio volumio[1181]: error: Error browsing0:Error: Did not get expected response from server:{"Envelope":{"$":{"xmlns:SOAP-ENV":"http://schemas.xmlsoap.org/soap/envelope/","SOAP-ENV:encodingStyle":"http://schemas.xmlsoap.org/soap/encoding/"},"Body":[{"Fault":[{"faultcode":[{"_":"SOAP-ENV:Client"}],"faultstring":[{"_":"UPnPError"}],"detail":[{"UPnPError":[{"$":{"xmlns:u":"urn:schemas-upnp-org:control-1-0"},"errorCode":[{"_":"801"}],"errorDescription":[{"_":"Access denied"}]}]}]}]}]}} Jul 11 21:09:25 volumio volumio[1181]: error: Failed to execute browseSource: Jul 11 21:09:27 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 11 21:09:27 volumio volumio[1181]: info: Preload queue cleared Jul 11 21:09:27 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jul 11 21:09:27 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jul 11 21:09:27 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jul 11 21:09:28 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jul 11 21:09:29 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 11 21:09:29 volumio volumio[1181]: info: Preload queue cleared Jul 11 21:09:30 volumio wpa_supplicant[897]: wlan0: Failed to initiate sched scan Jul 11 21:09:35 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 11 21:09:36 volumio volumio[1181]: info: Preload queue cleared Jul 11 21:09:38 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 11 21:09:38 volumio volumio[1181]: info: Preload queue cleared Jul 11 21:09:39 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jul 11 21:09:39 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jul 11 21:09:39 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jul 11 21:09:39 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jul 11 21:09:42 volumio wpa_supplicant[897]: wlan0: Failed to initiate sched scan Jul 11 21:09:51 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jul 11 21:09:51 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jul 11 21:09:51 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jul 11 21:09:51 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jul 11 21:09:52 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 11 21:09:52 volumio volumio[1181]: info: Preload queue cleared Jul 11 21:09:54 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 11 21:09:54 volumio wpa_supplicant[897]: wlan0: Failed to initiate sched scan Jul 11 21:09:54 volumio volumio[1181]: info: Preload queue cleared Jul 11 21:09:58 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 11 21:09:58 volumio volumio[1181]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 21:09:58 volumio volumio[1181]: TypeError: Cannot read property 'length' of undefined Jul 11 21:09:58 volumio volumio[1181]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 11 21:09:58 volumio volumio[1181]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 11 21:09:58 volumio volumio[1181]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 11 21:09:58 volumio volumio[1181]: at Parser.emit (events.js:315:20) Jul 11 21:09:58 volumio volumio[1181]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 11 21:09:58 volumio volumio[1181]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 11 21:09:58 volumio volumio[1181]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 11 21:09:58 volumio volumio[1181]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 11 21:09:58 volumio volumio[1181]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 11 21:09:58 volumio volumio[1181]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 11 21:09:58 volumio volumio[1181]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 11 21:09:58 volumio volumio[1181]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 11 21:09:58 volumio volumio[1181]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 11 21:09:58 volumio volumio[1181]: at IncomingMessage.emit (events.js:327:22) Jul 11 21:09:58 volumio volumio[1181]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 11 21:09:58 volumio volumio[1181]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 11 21:09:58 volumio volumio[1181]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 21:09:59 volumio sudo[25155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-11 21:08 Jul 11 21:09:59 volumio sudo[25155]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"