-- Logs begin at Tue 2025-05-13 16:21:58 MSK, end at Tue 2025-05-13 16:29:09 MSK. -- May 13 16:28:01 volumio volumio[1119]: info: Moving Files into plugin directory. May 13 16:28:09 volumio volumio[1119]: info: Creating service file. May 13 16:28:09 volumio sudo[3168]: pam_unix(sudo:session): session closed for user root May 13 16:28:09 volumio volumio[1119]: info: Exit Status: 0 May 13 16:28:09 volumio volumio[1119]: Plugin install end detected on script May 13 16:28:09 volumio volumio[1119]: info: Install script completed May 13 16:28:09 volumio volumio[1119]: info: Adding reference to registry May 13 16:28:09 volumio volumio[1119]: info: Done installing plugin. May 13 16:28:09 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 13 16:28:09 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 13 16:28:09 volumio volumio[1119]: info: Folder /tmp/plugins removed May 13 16:28:09 volumio volumio[1119]: info: Folder /tmp/downloaded_plugin.zip removed May 13 16:28:09 volumio volumio[1119]: info: Folder /data/temp removed May 13 16:28:14 volumio volumio[1119]: info: Enabling plugin RoonBridge May 13 16:28:14 volumio volumio[1119]: info: Loading plugin "RoonBridge"... May 13 16:28:15 volumio volumio[1119]: error: Could not copy default configuration to /data/configuration/music_service/RoonBridge/config.json May 13 16:28:15 volumio volumio[1119]: info: PLUGIN START: RoonBridge May 13 16:28:15 volumio sudo[3884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service May 13 16:28:15 volumio sudo[3884]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:15 volumio sudo[3884]: pam_unix(sudo:session): session closed for user root May 13 16:28:15 volumio volumio[1119]: error: Cannot start Roon Bridge Error: Command failed: /usr/bin/sudo /bin/systemctl start roonbridge.service May 13 16:28:15 volumio volumio[1119]: Failed to start roonbridge.service: Unit roonbridge.service not found. May 13 16:28:15 volumio volumio[1119]: info: Error: Error: Command failed: /usr/bin/sudo /bin/systemctl start roonbridge.service May 13 16:28:15 volumio volumio[1119]: Failed to start roonbridge.service: Unit roonbridge.service not found. May 13 16:28:23 volumio volumio[1119]: info: Enabling plugin RoonBridge May 13 16:28:23 volumio volumio[1119]: info: Loading plugin "RoonBridge"... May 13 16:28:23 volumio volumio[1119]: error: Could not copy default configuration to /data/configuration/music_service/RoonBridge/config.json May 13 16:28:23 volumio volumio[1119]: info: PLUGIN START: RoonBridge May 13 16:28:23 volumio sudo[3902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service May 13 16:28:23 volumio sudo[3902]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 16:28:23 volumio sudo[3902]: pam_unix(sudo:session): session closed for user root May 13 16:28:23 volumio volumio[1119]: error: Cannot start Roon Bridge Error: Command failed: /usr/bin/sudo /bin/systemctl start roonbridge.service May 13 16:28:23 volumio volumio[1119]: Failed to start roonbridge.service: Unit roonbridge.service not found. May 13 16:28:23 volumio volumio[1119]: info: Error: Error: Command failed: /usr/bin/sudo /bin/systemctl start roonbridge.service May 13 16:28:23 volumio volumio[1119]: Failed to start roonbridge.service: Unit roonbridge.service not found. May 13 16:28:27 volumio volumio[1119]: info: CoreCommandRouter::getUIConfigOnPlugin May 13 16:28:34 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 13 16:28:34 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 13 16:28:41 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState May 13 16:28:41 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 May 13 16:28:54 volumio volumio[1119]: info: CoreCommandRouter::volumioGetQueue May 13 16:28:54 volumio volumio[1119]: info: CoreStateMachine::getQueue May 13 16:28:54 volumio volumio[1119]: info: CorePlayQueue::getQueue May 13 16:28:57 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 16:28:57 volumio volumio[1119]: info: Preload queue cleared May 13 16:28:59 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 16:29:00 volumio volumio[1119]: info: Preload queue cleared May 13 16:29:02 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 16:29:03 volumio volumio[1119]: info: Preload queue cleared May 13 16:29:03 volumio systemd[1]: Stopping Network Time Service... May 13 16:29:03 volumio ntpd[2829]: ntpd exiting on signal 15 (Terminated) May 13 16:29:03 volumio ntpd[2829]: 51.250.110.169 local addr 192.168.0.246 -> May 13 16:29:03 volumio ntpd[2829]: 51.250.35.68 local addr 192.168.0.246 -> May 13 16:29:03 volumio ntpd[2829]: 92.255.126.11 local addr 192.168.0.246 -> May 13 16:29:03 volumio ntpd[2829]: 92.255.126.17 local addr 192.168.0.246 -> May 13 16:29:03 volumio ntpd[2829]: 151.0.2.54 local addr 192.168.0.246 -> May 13 16:29:03 volumio ntpd[2829]: 94.141.168.72 local addr 192.168.0.246 -> May 13 16:29:03 volumio ntpd[2829]: 213.33.141.134 local addr 192.168.0.246 -> May 13 16:29:03 volumio ntpd[2829]: 51.250.68.198 local addr 192.168.0.246 -> May 13 16:29:03 volumio ntpd[2829]: 82.142.168.18 local addr 192.168.0.246 -> May 13 16:29:03 volumio ntpd[2829]: 151.0.2.53 local addr 192.168.0.246 -> May 13 16:29:03 volumio ntpd[2829]: 45.141.102.99 local addr 192.168.0.246 -> May 13 16:29:03 volumio ntpd[2829]: 192.36.143.130 local addr 192.168.0.246 -> May 13 16:29:03 volumio ntpd[2829]: 62.113.114.46 local addr 192.168.0.246 -> May 13 16:29:03 volumio systemd[1]: ntp.service: Succeeded. May 13 16:29:03 volumio systemd[1]: Stopped Network Time Service. May 13 16:29:03 volumio systemd[1]: Starting Network Time Service... May 13 16:29:03 volumio ntpd[4003]: ntpd 4.2.8p12@1.3728-o (1): Starting May 13 16:29:03 volumio ntpd[4003]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 13 16:29:03 volumio systemd[1]: Started Network Time Service. May 13 16:29:03 volumio ntpd[4010]: proto: precision = 0.065 usec (-24) May 13 16:29:03 volumio ntpd[4010]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 13 16:29:03 volumio ntpd[4010]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 13 16:29:03 volumio ntpd[4010]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 868 days ago May 13 16:29:03 volumio ntpd[4010]: Listen and drop on 0 v6wildcard [::]:123 May 13 16:29:03 volumio ntpd[4010]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 13 16:29:03 volumio ntpd[4010]: Listen normally on 2 lo 127.0.0.1:123 May 13 16:29:03 volumio ntpd[4010]: Listen normally on 3 wlan0 192.168.0.246:123 May 13 16:29:03 volumio ntpd[4010]: Listening on routing socket on fd #20 for interface updates May 13 16:29:03 volumio ntpd[4010]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 13 16:29:03 volumio ntpd[4010]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 13 16:29:04 volumio ntpd[4010]: Soliciting pool server 91.201.254.110 May 13 16:29:05 volumio ntpd[4010]: Soliciting pool server 45.90.217.6 May 13 16:29:05 volumio ntpd[4010]: Soliciting pool server 92.255.126.17 May 13 16:29:06 volumio ntpd[4010]: Soliciting pool server 91.235.144.128 May 13 16:29:06 volumio ntpd[4010]: Soliciting pool server 89.109.251.23 May 13 16:29:06 volumio ntpd[4010]: Soliciting pool server 151.0.2.53 May 13 16:29:07 volumio ntpd[4010]: Soliciting pool server 195.218.227.230 May 13 16:29:07 volumio ntpd[4010]: Soliciting pool server 51.250.35.68 May 13 16:29:07 volumio ntpd[4010]: Soliciting pool server 92.255.126.11 May 13 16:29:07 volumio ntpd[4010]: Soliciting pool server 92.241.12.152 May 13 16:29:08 volumio ntpd[4010]: Soliciting pool server 93.95.100.85 May 13 16:29:08 volumio ntpd[4010]: Soliciting pool server 213.108.170.26 May 13 16:29:08 volumio ntpd[4010]: Soliciting pool server 193.192.36.3 May 13 16:29:09 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 16:29:09 volumio volumio[1119]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 16:29:09 volumio volumio[1119]: TypeError: Cannot read property 'length' of undefined May 13 16:29:09 volumio volumio[1119]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 13 16:29:09 volumio volumio[1119]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 13 16:29:09 volumio volumio[1119]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 13 16:29:09 volumio volumio[1119]: at Parser.emit (events.js:400:28) May 13 16:29:09 volumio volumio[1119]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 13 16:29:09 volumio volumio[1119]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 13 16:29:09 volumio volumio[1119]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 13 16:29:09 volumio volumio[1119]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 13 16:29:09 volumio volumio[1119]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 13 16:29:09 volumio volumio[1119]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 13 16:29:09 volumio volumio[1119]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 13 16:29:09 volumio volumio[1119]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 13 16:29:09 volumio volumio[1119]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 13 16:29:09 volumio volumio[1119]: at IncomingMessage.emit (events.js:412:35) May 13 16:29:09 volumio volumio[1119]: at endReadableNT (internal/streams/readable.js:1333:12) May 13 16:29:09 volumio volumio[1119]: at processTicksAndRejections (internal/process/task_queues.js:82:21) May 13 16:29:09 volumio volumio[1119]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 16:29:09 volumio sudo[4077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-13 16:28 May 13 16:29:09 volumio sudo[4077]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 12:21:23 PM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a9588e47e4b05f1a05bf70b4acc7555c"