-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Sat 2025-06-07 19:08:06 CST. -- Jun 07 19:07:01 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 07 19:07:01 volumio volumio[845]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 56 Jun 07 19:07:01 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Jun 07 19:07:01 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Jun 07 19:07:06 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 07 19:07:06 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 19:07:06 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 07 19:07:06 volumio volumio-remote-updater[554]: No test mode Jun 07 19:07:06 volumio volumio-remote-updater[554]: No alpha test mode Jun 07 19:07:06 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 07 19:07:06 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 19:07:06 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 19:07:06 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 07 19:07:06 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 07 19:07:06 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 07 19:07:06 volumio volumio[845]: info: CoreCommandRouter::volumioGetBrowseSources Jun 07 19:07:06 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 07 19:07:06 volumio volumio[845]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 07 19:07:06 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 07 19:07:07 volumio volumio[845]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 07 19:07:07 volumio volumio[845]: info: Completed starting MyVolumio Plugin Jun 07 19:07:07 volumio volumio[845]: [Metrics] CommandRouter: 36s 117.39ms Jun 07 19:07:07 volumio volumio[845]: info: CoreCommandRouter::volumiosetStartupVolume Jun 07 19:07:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 19:07:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 19:07:07 volumio volumio[845]: info: CoreCommandRouter::Close All Modals sent Jun 07 19:07:07 volumio volumio[845]: info: CoreCommandRouter::Close All Modals sent Jun 07 19:07:08 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 07 19:07:08 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 19:07:08 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 07 19:07:11 volumio systemd[1]: systemd-timedated.service: Succeeded. Jun 07 19:07:12 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 3 Jun 07 19:07:12 volumio kernel: xhci_hcd 0000:01:00.0: @000000042951cfb0 00000000 00000000 0e000000 02048001 Jun 07 19:07:14 volumio volumio[845]: info: BOOT COMPLETED Jun 07 19:07:17 volumio volumio[845]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 07 19:07:17 volumio volumio[845]: info: Retrying Login Due to Network Error Jun 07 19:07:27 volumio volumio[845]: info: MyVolumio login type: Token Jun 07 19:07:30 volumio sudo[1453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 07 19:07:30 volumio sudo[1453]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 19:07:30 volumio sudo[1453]: pam_unix(sudo:session): session closed for user root Jun 07 19:07:30 volumio sudo[1455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 07 19:07:30 volumio sudo[1455]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 19:07:30 volumio sudo[1455]: pam_unix(sudo:session): session closed for user root Jun 07 19:07:30 volumio volumio[845]: verbose: New Socket.io Connection to 192.168.31.39 from 192.168.31.188 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::volumioGetVisibleSources Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Jun 07 19:07:30 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 07 19:07:30 volumio volumio[845]: info: Received Get System Info Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 07 19:07:30 volumio volumio[845]: info: Discovery: Getting this device information Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Jun 07 19:07:30 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Jun 07 19:07:30 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Jun 07 19:07:30 volumio volumio[845]: info: Listing playlists Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 07 19:07:30 volumio sudo[1459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 07 19:07:30 volumio sudo[1459]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 19:07:30 volumio sudo[1459]: pam_unix(sudo:session): session closed for user root Jun 07 19:07:30 volumio sudo[1461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 07 19:07:30 volumio sudo[1461]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 19:07:30 volumio sudo[1461]: pam_unix(sudo:session): session closed for user root Jun 07 19:07:30 volumio volumio[845]: verbose: New Socket.io Connection to 192.168.31.39 from 192.168.31.188 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::volumioGetVisibleSources Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Jun 07 19:07:30 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 07 19:07:30 volumio volumio[845]: info: Received Get System Info Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 07 19:07:30 volumio volumio[845]: info: Discovery: Getting this device information Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Jun 07 19:07:30 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Jun 07 19:07:30 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Jun 07 19:07:30 volumio volumio[845]: info: Listing playlists Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 07 19:07:30 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 07 19:07:31 volumio ntpd[765]: Soliciting pool server 193.182.111.12 Jun 07 19:07:31 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Jun 07 19:07:31 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Jun 07 19:07:32 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 07 19:07:32 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 07 19:07:32 volumio volumio[845]: info: Received Get System Info Jun 07 19:07:32 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 07 19:07:32 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 07 19:07:32 volumio volumio[845]: info: Discovery: Getting this device information Jun 07 19:07:32 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Jun 07 19:07:32 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Jun 07 19:07:32 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 07 19:07:32 volumio ntpd[765]: Soliciting pool server 193.182.111.143 Jun 07 19:07:32 volumio ntpd[765]: Soliciting pool server 193.182.111.14 Jun 07 19:07:33 volumio ntpd[765]: Soliciting pool server 202.118.1.130 Jun 07 19:07:33 volumio ntpd[765]: Soliciting pool server 45.76.221.157 Jun 07 19:07:33 volumio ntpd[765]: Soliciting pool server 95.111.202.5 Jun 07 19:07:34 volumio ntpd[765]: Soliciting pool server 240e:980:b65:e900:bd10:673a:3f2a:f0e2 Jun 07 19:07:35 volumio ntpd[765]: Soliciting pool server 211.68.71.26 Jun 07 19:07:36 volumio ntpd[765]: Soliciting pool server 162.159.200.123 Jun 07 19:07:37 volumio ntpd[765]: Soliciting pool server 116.203.151.74 Jun 07 19:07:38 volumio volumio[845]: info: CoreCommandRouter::volumioGetQueue Jun 07 19:07:38 volumio volumio[845]: info: CoreStateMachine::getQueue Jun 07 19:07:38 volumio volumio[845]: info: CorePlayQueue::getQueue Jun 07 19:07:40 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 07 19:07:41 volumio ntpd[765]: receive: Unexpected origin timestamp 0xebee9bfc.ebe766a8 does not match aorg 0000000000.00000000 from server@45.76.221.157 xmt 0xebee9bfd.a4f982d3 Jun 07 19:07:41 volumio ntpd[765]: receive: Unexpected origin timestamp 0xebee9bfc.ebe9bb95 does not match aorg 0000000000.00000000 from server@193.182.111.12 xmt 0xebee9bfd.be4ce180 Jun 07 19:07:41 volumio ntpd[765]: receive: Unexpected origin timestamp 0xebee9bfc.ebe4f425 does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xebee9bfd.b3d4f574 Jun 07 19:07:49 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 07 19:07:49 volumio volumio[845]: info: CURURI: music-library Jun 07 19:07:49 volumio volumio[845]: error: Failed LSINFO: null Jun 07 19:07:49 volumio volumio[845]: info: Preload queue cleared Jun 07 19:07:55 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 07 19:07:55 volumio volumio[845]: info: CURURI: music-library Jun 07 19:07:55 volumio volumio[845]: error: Failed LSINFO: null Jun 07 19:07:55 volumio volumio[845]: info: Preload queue cleared Jun 07 19:07:58 volumio volumio[845]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 07 19:07:58 volumio volumio[845]: info: Retrying Login Due to Network Error Jun 07 19:07:59 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 07 19:07:59 volumio volumio[845]: info: Preload queue cleared Jun 07 19:08:01 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 07 19:08:01 volumio volumio[845]: info: Preload queue cleared Jun 07 19:08:04 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 07 19:08:04 volumio volumio[845]: info: Preload queue cleared Jun 07 19:08:06 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 07 19:08:06 volumio volumio[845]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 07 19:08:06 volumio volumio[845]: TypeError: Cannot read property 'length' of undefined Jun 07 19:08:06 volumio volumio[845]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 07 19:08:06 volumio volumio[845]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 07 19:08:06 volumio volumio[845]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 07 19:08:06 volumio volumio[845]: at Parser.emit (events.js:315:20) Jun 07 19:08:06 volumio volumio[845]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 07 19:08:06 volumio volumio[845]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 07 19:08:06 volumio volumio[845]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 07 19:08:06 volumio volumio[845]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 07 19:08:06 volumio volumio[845]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 07 19:08:06 volumio volumio[845]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 07 19:08:06 volumio volumio[845]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 07 19:08:06 volumio volumio[845]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 07 19:08:06 volumio volumio[845]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 07 19:08:06 volumio volumio[845]: at IncomingMessage.emit (events.js:327:22) Jun 07 19:08:06 volumio volumio[845]: at endReadableNT (internal/streams/readable.js:1327:12) Jun 07 19:08:06 volumio volumio[845]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 07 19:08:06 volumio volumio[845]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 07 19:08:06 volumio sudo[1532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-07 19:07 Jun 07 19:08:06 volumio sudo[1532]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"