-- Logs begin at Sat 2024-10-05 10:34:56 -03, end at Sat 2024-10-05 12:54:09 -03. -- Oct 05 12:53:03 volumioae1-572 go-librespot[1682]: time="2024-10-05T12:53:03-03:00" level=debug msg="fetched chunk 4/22, size: 524288" uri="spotify:track:6IoUbKPRLM7nTt2bjbTogh" Oct 05 12:53:16 volumioae1-572 go-librespot[1682]: time="2024-10-05T12:53:16-03:00" level=debug msg="fetched chunk 5/22, size: 524288" uri="spotify:track:6IoUbKPRLM7nTt2bjbTogh" Oct 05 12:53:30 volumioae1-572 go-librespot[1682]: time="2024-10-05T12:53:30-03:00" level=debug msg="fetched chunk 6/22, size: 524288" uri="spotify:track:6IoUbKPRLM7nTt2bjbTogh" Oct 05 12:53:39 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 12:53:39 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 12:53:39 volumioae1-572 volumio[1163]: info: Discovery: Getting this device information Oct 05 12:53:39 volumioae1-572 volumio[1163]: info: CoreCommandRouter::volumioGetState Oct 05 12:53:39 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 12:53:39 volumioae1-572 volumio[1163]: verbose: New Socket.io Connection to 192.168.15.13:3000 from 192.168.15.102 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Oct 05 12:53:39 volumioae1-572 volumio[1163]: info: CoreCommandRouter::volumioGetState Oct 05 12:53:39 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 05 12:53:39 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 05 12:53:42 volumioae1-572 sudo[30031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 12:53:42 volumioae1-572 sudo[30031]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 12:53:42 volumioae1-572 sudo[30031]: pam_unix(sudo:session): session closed for user root Oct 05 12:53:42 volumioae1-572 sudo[30033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 12:53:42 volumioae1-572 sudo[30033]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 12:53:42 volumioae1-572 sudo[30033]: pam_unix(sudo:session): session closed for user root Oct 05 12:53:42 volumioae1-572 volumio[1163]: verbose: New Socket.io Connection to 192.168.15.13 from 192.168.15.102 UA: Mozilla/5.0 (Linux; Android 14; SM-S916B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.70 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 05 12:53:43 volumioae1-572 sudo[30037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 12:53:43 volumioae1-572 sudo[30037]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 12:53:43 volumioae1-572 sudo[30037]: pam_unix(sudo:session): session closed for user root Oct 05 12:53:43 volumioae1-572 sudo[30039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 12:53:43 volumioae1-572 sudo[30039]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 12:53:43 volumioae1-572 sudo[30039]: pam_unix(sudo:session): session closed for user root Oct 05 12:53:43 volumioae1-572 volumio[1163]: verbose: New Socket.io Connection to 192.168.15.13 from 192.168.15.102 UA: Mozilla/5.0 (Linux; Android 14; SM-S916B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.70 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 05 12:53:43 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 05 12:53:43 volumioae1-572 volumio[1163]: info: CoreCommandRouter::volumioGetVisibleSources Oct 05 12:53:43 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 12:53:43 volumioae1-572 volumio[1163]: info: CoreCommandRouter::volumioGetState Oct 05 12:53:43 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 05 12:53:43 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 05 12:53:43 volumioae1-572 volumio[1163]: info: Received Get System Info Oct 05 12:53:43 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 12:53:43 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 12:53:43 volumioae1-572 volumio[1163]: info: Discovery: Getting this device information Oct 05 12:53:43 volumioae1-572 volumio[1163]: info: CoreCommandRouter::volumioGetState Oct 05 12:53:43 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 12:53:43 volumioae1-572 volumio[1163]: info: CoreCommandRouter::volumioGetState Oct 05 12:53:43 volumioae1-572 volumio[1163]: info: Listing playlists Oct 05 12:53:43 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 05 12:53:43 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 05 12:53:43 volumioae1-572 go-librespot[1682]: time="2024-10-05T12:53:43-03:00" level=debug msg="fetched chunk 7/22, size: 524288" uri="spotify:track:6IoUbKPRLM7nTt2bjbTogh" Oct 05 12:53:43 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 05 12:53:44 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 05 12:53:44 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 12:53:44 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 12:53:44 volumioae1-572 volumio[1163]: info: Discovery: Getting this device information Oct 05 12:53:44 volumioae1-572 volumio[1163]: info: CoreCommandRouter::volumioGetState Oct 05 12:53:44 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 12:53:45 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 12:53:45 volumioae1-572 volumio[1163]: info: Received Get System Info Oct 05 12:53:45 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 12:53:45 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 12:53:45 volumioae1-572 volumio[1163]: info: Discovery: Getting this device information Oct 05 12:53:45 volumioae1-572 volumio[1163]: info: CoreCommandRouter::volumioGetState Oct 05 12:53:45 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 12:53:45 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 05 12:53:47 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 12:53:47 volumioae1-572 volumio[1163]: info: Received Get System Info Oct 05 12:53:47 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 12:53:47 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 12:53:47 volumioae1-572 volumio[1163]: info: Discovery: Getting this device information Oct 05 12:53:47 volumioae1-572 volumio[1163]: info: CoreCommandRouter::volumioGetState Oct 05 12:53:47 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 12:53:48 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 12:53:48 volumioae1-572 volumio[1163]: info: Received Get System Info Oct 05 12:53:48 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 12:53:48 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 12:53:48 volumioae1-572 volumio[1163]: info: Discovery: Getting this device information Oct 05 12:53:48 volumioae1-572 volumio[1163]: info: CoreCommandRouter::volumioGetState Oct 05 12:53:48 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 12:53:49 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 12:53:49 volumioae1-572 volumio[1163]: info: Received Get System Info Oct 05 12:53:49 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 12:53:49 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 12:53:49 volumioae1-572 volumio[1163]: info: Discovery: Getting this device information Oct 05 12:53:49 volumioae1-572 volumio[1163]: info: CoreCommandRouter::volumioGetState Oct 05 12:53:49 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 12:53:53 volumioae1-572 volumio[1163]: info: CoreCommandRouter::volumioGetQueue Oct 05 12:53:53 volumioae1-572 volumio[1163]: info: CoreStateMachine::getQueue Oct 05 12:53:53 volumioae1-572 volumio[1163]: info: CorePlayQueue::getQueue Oct 05 12:53:53 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 05 12:53:56 volumioae1-572 go-librespot[1682]: time="2024-10-05T12:53:56-03:00" level=debug msg="fetched chunk 8/22, size: 524288" uri="spotify:track:6IoUbKPRLM7nTt2bjbTogh" Oct 05 12:53:58 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 05 12:53:58 volumioae1-572 volumio[1163]: info: Preload queue cleared Oct 05 12:54:00 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 05 12:54:01 volumioae1-572 volumio[1163]: info: Preload queue cleared Oct 05 12:54:02 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 05 12:54:02 volumioae1-572 volumio[1163]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 05 12:54:02 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 05 12:54:02 volumioae1-572 volumio[1163]: info: Received Get System Version Oct 05 12:54:02 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 12:54:02 volumioae1-572 volumio[1163]: info: Received Get System Info Oct 05 12:54:02 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 12:54:02 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 12:54:02 volumioae1-572 volumio[1163]: info: Discovery: Getting this device information Oct 05 12:54:02 volumioae1-572 volumio[1163]: info: CoreCommandRouter::volumioGetState Oct 05 12:54:02 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 12:54:04 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 05 12:54:04 volumioae1-572 volumio[1163]: info: Preload queue cleared Oct 05 12:54:06 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 05 12:54:06 volumioae1-572 volumio[1163]: info: Preload queue cleared Oct 05 12:54:08 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 05 12:54:08 volumioae1-572 volumio[1163]: info: Preload queue cleared Oct 05 12:54:09 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 12:54:09 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 12:54:09 volumioae1-572 volumio[1163]: info: Discovery: Getting this device information Oct 05 12:54:09 volumioae1-572 volumio[1163]: info: CoreCommandRouter::volumioGetState Oct 05 12:54:09 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 12:54:09 volumioae1-572 volumio[1163]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 05 12:54:09 volumioae1-572 volumio[1163]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 12:54:09 volumioae1-572 volumio[1163]: TypeError: Cannot read property 'length' of undefined Oct 05 12:54:09 volumioae1-572 volumio[1163]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 05 12:54:09 volumioae1-572 volumio[1163]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 05 12:54:09 volumioae1-572 volumio[1163]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 05 12:54:09 volumioae1-572 volumio[1163]: at Parser.emit (events.js:400:28) Oct 05 12:54:09 volumioae1-572 volumio[1163]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 05 12:54:09 volumioae1-572 volumio[1163]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 05 12:54:09 volumioae1-572 volumio[1163]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 05 12:54:09 volumioae1-572 volumio[1163]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 05 12:54:09 volumioae1-572 volumio[1163]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 05 12:54:09 volumioae1-572 volumio[1163]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 05 12:54:09 volumioae1-572 volumio[1163]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 05 12:54:09 volumioae1-572 volumio[1163]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 05 12:54:09 volumioae1-572 volumio[1163]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 05 12:54:09 volumioae1-572 volumio[1163]: at IncomingMessage.emit (events.js:412:35) Oct 05 12:54:09 volumioae1-572 volumio[1163]: at endReadableNT (internal/streams/readable.js:1333:12) Oct 05 12:54:09 volumioae1-572 volumio[1163]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Oct 05 12:54:09 volumioae1-572 volumio[1163]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 12:54:09 volumioae1-572 sudo[30052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-05 12:53 Oct 05 12:54:09 volumioae1-572 sudo[30052]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"