-- Logs begin at Fri 2024-10-18 17:29:23 -04, end at Fri 2024-10-18 18:18:46 -04. -- Oct 18 18:17:00 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:17:02 volumio volumio[1044]: info: [1729289822981] [80s80s] Pushing the next song state Pearl Jam - Black and getting next track. Oct 18 18:17:02 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Oct 18 18:17:02 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Oct 18 18:17:02 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Oct 18 18:17:02 volumio volumio[1044]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music115/v4/f7/ba/8b/f7ba8bcd-ce91-aba4-cb2d-141968d94433/source/600x600bb.jpg","name":"Pearl Jam - Black","title":"Black","artist":"90s90s Grunge","album":"","streaming":true,"disableUiControls":true,"duration":"264","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Oct 18 18:17:02 volumio volumio[1044]: verbose: CURRENT POSITION 0 Oct 18 18:17:02 volumio volumio[1044]: info: CoreStateMachine::syncState stateService play Oct 18 18:17:02 volumio volumio[1044]: info: CoreStateMachine::syncState currentStatus play Oct 18 18:17:02 volumio volumio[1044]: info: Received an update from plugin. extracting info from payload Oct 18 18:17:02 volumio volumio[1044]: info: CoreStateMachine::pushState Oct 18 18:17:02 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Oct 18 18:17:02 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 18:17:02 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Oct 18 18:17:02 volumio volumio[1044]: info: CoreStateMachine::pushState Oct 18 18:17:02 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Oct 18 18:17:02 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Oct 18 18:17:02 volumio volumio[1044]: info: [1729289822987] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=253&count=1 Oct 18 18:17:03 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Oct 18 18:17:03 volumio volumio[1044]: info: CorePlayQueue::getTrack 1 Oct 18 18:17:03 volumio volumio[1044]: info: [1729289823544] [80s80s] received new event containing 1 songs. Oct 18 18:17:03 volumio volumio[1044]: info: [1729289823544] [80s80s] PlayNextTrack API delay: 30 Oct 18 18:17:03 volumio volumio[1044]: info: [1729289823547] [80s80s] Setting timer to: 264000 milliseconds. Oct 18 18:17:04 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:17:08 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:17:12 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:17:16 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:17:20 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:17:24 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:17:28 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:17:32 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:17:36 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:17:40 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:17:44 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:17:48 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:17:52 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:17:56 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:18:00 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:18:04 volumio volumio[1044]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/Systeminfo/volumio/buster/amd64 Oct 18 18:18:04 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:18:05 volumio volumio[1044]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/Systeminfo/volumio/buster/amd64 Oct 18 18:18:05 volumio volumio[1044]: info: Folder /tmp/plugins removed Oct 18 18:18:05 volumio volumio[1044]: info: Check plugin dependencies Oct 18 18:18:05 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 18 18:18:05 volumio volumio[1044]: info: Checking if plugin already exists Oct 18 18:18:05 volumio volumio[1044]: info: Rename folder Oct 18 18:18:05 volumio volumio[1044]: info: Folder /tmp/downloaded_plugin.zip removed Oct 18 18:18:05 volumio volumio[1044]: info: Move to category Oct 18 18:18:05 volumio volumio[1044]: info: Checking if install.sh is present Oct 18 18:18:05 volumio volumio[1044]: info: Executing install.sh Oct 18 18:18:05 volumio sudo[6761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/Systeminfo/install.sh Oct 18 18:18:05 volumio sudo[6761]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 18:18:05 volumio volumio[1044]: info: Installing systeminfo Oct 18 18:18:05 volumio volumio[1044]: info: Configuration file doesn't exist, nothing to do Oct 18 18:18:05 volumio volumio[1044]: info: Detected cpu architecture as x86_64 Oct 18 18:18:05 volumio sudo[6766]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/user_interface/Systeminfo/c/hw_params_amd64 /data/plugins/user_interface/Systeminfo/hw_params Oct 18 18:18:05 volumio sudo[6766]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 18:18:05 volumio sudo[6766]: pam_unix(sudo:session): session closed for user root Oct 18 18:18:05 volumio sudo[6768]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/user_interface/Systeminfo/hw_params Oct 18 18:18:05 volumio sudo[6768]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 18:18:05 volumio sudo[6768]: pam_unix(sudo:session): session closed for user root Oct 18 18:18:05 volumio sudo[6770]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/user_interface/Systeminfo/firmware.sh Oct 18 18:18:05 volumio sudo[6770]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 18:18:05 volumio sudo[6770]: pam_unix(sudo:session): session closed for user root Oct 18 18:18:05 volumio sudo[6761]: pam_unix(sudo:session): session closed for user root Oct 18 18:18:05 volumio volumio[1044]: Plugin install end detected on script Oct 18 18:18:05 volumio volumio[1044]: info: Install script completed Oct 18 18:18:05 volumio volumio[1044]: info: Adding reference to registry Oct 18 18:18:05 volumio volumio[1044]: info: Done installing plugin. Oct 18 18:18:05 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 18 18:18:05 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 18 18:18:05 volumio volumio[1044]: info: Folder /tmp/plugins removed Oct 18 18:18:05 volumio volumio[1044]: info: Folder /tmp/downloaded_plugin.zip removed Oct 18 18:18:05 volumio volumio[1044]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 18:18:05 volumio volumio[1044]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 18:18:05 volumio volumio[1044]: info: Folder /data/temp removed Oct 18 18:18:07 volumio volumio[1044]: info: Enabling plugin Systeminfo Oct 18 18:18:07 volumio volumio[1044]: info: Loading plugin "Systeminfo"... Oct 18 18:18:07 volumio volumio[1044]: info: PLUGIN START: Systeminfo Oct 18 18:18:07 volumio volumio[1044]: info: Done. Oct 18 18:18:07 volumio volumio[1044]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 18:18:08 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:18:12 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:18:16 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:18:19 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Oct 18 18:18:19 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Oct 18 18:18:20 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:18:24 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:18:28 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:18:32 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:18:36 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:18:39 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 18 18:18:39 volumio volumio[1044]: info: Preload queue cleared Oct 18 18:18:40 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:18:42 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 18 18:18:43 volumio volumio[1044]: info: Preload queue cleared Oct 18 18:18:44 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 18:18:45 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 18 18:18:46 volumio volumio[1044]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 18 18:18:46 volumio volumio[1044]: TypeError: Cannot read property 'length' of undefined Oct 18 18:18:46 volumio volumio[1044]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 18 18:18:46 volumio volumio[1044]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 18 18:18:46 volumio volumio[1044]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 18 18:18:46 volumio volumio[1044]: at Parser.emit (events.js:400:28) Oct 18 18:18:46 volumio volumio[1044]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 18 18:18:46 volumio volumio[1044]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 18 18:18:46 volumio volumio[1044]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 18 18:18:46 volumio volumio[1044]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 18 18:18:46 volumio volumio[1044]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 18 18:18:46 volumio volumio[1044]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 18 18:18:46 volumio volumio[1044]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 18 18:18:46 volumio volumio[1044]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 18 18:18:46 volumio volumio[1044]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 18 18:18:46 volumio volumio[1044]: at IncomingMessage.emit (events.js:412:35) Oct 18 18:18:46 volumio volumio[1044]: at endReadableNT (internal/streams/readable.js:1333:12) Oct 18 18:18:46 volumio volumio[1044]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Oct 18 18:18:46 volumio volumio[1044]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 18 18:18:46 volumio sudo[6911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-18 18:17 Oct 18 18:18:46 volumio sudo[6911]: 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"