-- Logs begin at Thu 2019-02-14 13:11:59 MSK, end at Wed 2025-09-17 20:26:41 MSK. -- Sep 17 20:25:00 volumio volumio[876]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 20:25:00 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 17 20:25:00 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Sep 17 20:25:00 volumio sudo[5302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Sep 17 20:25:00 volumio sudo[5302]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 20:25:00 volumio sudo[5302]: pam_unix(sudo:session): session closed for user root Sep 17 20:25:00 volumio sudo[5308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 17 20:25:00 volumio sudo[5308]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 20:25:00 volumio sudo[5308]: pam_unix(sudo:session): session closed for user root Sep 17 20:25:00 volumio sudo[5316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 17 20:25:00 volumio sudo[5316]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 20:25:00 volumio sudo[5316]: pam_unix(sudo:session): session closed for user root Sep 17 20:25:00 volumio sudo[5320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 17 20:25:00 volumio sudo[5320]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 20:25:00 volumio sudo[5327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 17 20:25:00 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 17 20:25:00 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 17 20:25:00 volumio sudo[5320]: pam_unix(sudo:session): session closed for user root Sep 17 20:25:00 volumio sudo[5327]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 20:25:00 volumio sudo[5327]: pam_unix(sudo:session): session closed for user root Sep 17 20:25:00 volumio sudo[5330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 17 20:25:00 volumio sudo[5330]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 20:25:00 volumio sudo[5330]: pam_unix(sudo:session): session closed for user root Sep 17 20:25:00 volumio sudo[5333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 17 20:25:00 volumio sudo[5333]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 20:25:01 volumio sudo[5333]: pam_unix(sudo:session): session closed for user root Sep 17 20:25:17 volumio dnsmasq-dhcp[862]: no address range available for DHCP request via wlan0 Sep 17 20:25:17 volumio dnsmasq-dhcp[862]: no address range available for DHCP request via wlan0 Sep 17 20:25:19 volumio dnsmasq-dhcp[862]: no address range available for DHCP request via wlan0 Sep 17 20:25:20 volumio volumio[876]: info: CoreCommandRouter::volumioGetState Sep 17 20:25:32 volumio volumio[876]: info: CoreCommandRouter::volumioGetState Sep 17 20:25:46 volumio dnsmasq-dhcp[862]: no address range available for DHCP request via wlan0 Sep 17 20:25:55 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 17 20:25:55 volumio volumio[876]: info: Preload queue cleared Sep 17 20:25:58 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 17 20:25:58 volumio volumio[876]: info: Preload queue cleared Sep 17 20:26:04 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 17 20:26:04 volumio volumio[876]: info: Preload queue cleared Sep 17 20:26:04 volumio volumio[876]: info: Preloading song: upnp/http://192.168.1.14:8200/ctl/ContentDir@A0$128$146$1528824085$6702760213$6702825749$6702891285$313917700 Sep 17 20:26:04 volumio volumio[876]: info: Preloading song: upnp/http://192.168.1.14:8200/ctl/ContentDir@A0$128$146$1528824085$6702760213$6702825749$6702891285$313983236 Sep 17 20:26:04 volumio volumio[876]: info: Preloading song: upnp/http://192.168.1.14:8200/ctl/ContentDir@A0$128$146$1528824085$6702760213$6702825749$6702891285$314048772 Sep 17 20:26:04 volumio volumio[876]: info: Preloading song: upnp/http://192.168.1.14:8200/ctl/ContentDir@A0$128$146$1528824085$6702760213$6702825749$6702891285$314114308 Sep 17 20:26:04 volumio volumio[876]: info: Preloading song: upnp/http://192.168.1.14:8200/ctl/ContentDir@A0$128$146$1528824085$6702760213$6702825749$6702891285$314179844 Sep 17 20:26:04 volumio volumio[876]: info: Preloading song: upnp/http://192.168.1.14:8200/ctl/ContentDir@A0$128$146$1528824085$6702760213$6702825749$6702891285$314245380 Sep 17 20:26:04 volumio volumio[876]: info: Preloading song: upnp/http://192.168.1.14:8200/ctl/ContentDir@A0$128$146$1528824085$6702760213$6702825749$6702891285$314310916 Sep 17 20:26:04 volumio volumio[876]: info: Preloading song: upnp/http://192.168.1.14:8200/ctl/ContentDir@A0$128$146$1528824085$6702760213$6702825749$6702891285$314376452 Sep 17 20:26:04 volumio volumio[876]: info: Preloading song: upnp/http://192.168.1.14:8200/ctl/ContentDir@A0$128$146$1528824085$6702760213$6702825749$6702891285$314441988 Sep 17 20:26:04 volumio volumio[876]: info: Preloading song: upnp/http://192.168.1.14:8200/ctl/ContentDir@A0$128$146$1528824085$6702760213$6702825749$6702891285$314507524 Sep 17 20:26:04 volumio volumio[876]: info: Preloading song: upnp/http://192.168.1.14:8200/ctl/ContentDir@A0$128$146$1528824085$6702760213$6702825749$6702891285$314573060 Sep 17 20:26:04 volumio volumio[876]: info: Exploding uri upnp/http://192.168.1.14:8200/ctl/ContentDir@A0$128$146$1528824085$6702760213$6702825749$6702891285$313917700 in service upnp_browser Sep 17 20:26:05 volumio volumio[876]: info: Exploding uri upnp/http://192.168.1.14:8200/ctl/ContentDir@A0$128$146$1528824085$6702760213$6702825749$6702891285$313983236 in service upnp_browser Sep 17 20:26:05 volumio volumio[876]: info: Exploding uri upnp/http://192.168.1.14:8200/ctl/ContentDir@A0$128$146$1528824085$6702760213$6702825749$6702891285$314048772 in service upnp_browser Sep 17 20:26:05 volumio volumio[876]: info: Exploding uri upnp/http://192.168.1.14:8200/ctl/ContentDir@A0$128$146$1528824085$6702760213$6702825749$6702891285$314114308 in service upnp_browser Sep 17 20:26:05 volumio volumio[876]: info: Exploding uri upnp/http://192.168.1.14:8200/ctl/ContentDir@A0$128$146$1528824085$6702760213$6702825749$6702891285$314179844 in service upnp_browser Sep 17 20:26:05 volumio volumio[876]: info: Exploding uri upnp/http://192.168.1.14:8200/ctl/ContentDir@A0$128$146$1528824085$6702760213$6702825749$6702891285$314245380 in service upnp_browser Sep 17 20:26:05 volumio volumio[876]: info: Exploding uri upnp/http://192.168.1.14:8200/ctl/ContentDir@A0$128$146$1528824085$6702760213$6702825749$6702891285$314310916 in service upnp_browser Sep 17 20:26:05 volumio volumio[876]: info: Exploding uri upnp/http://192.168.1.14:8200/ctl/ContentDir@A0$128$146$1528824085$6702760213$6702825749$6702891285$314376452 in service upnp_browser Sep 17 20:26:05 volumio volumio[876]: info: Exploding uri upnp/http://192.168.1.14:8200/ctl/ContentDir@A0$128$146$1528824085$6702760213$6702825749$6702891285$314441988 in service upnp_browser Sep 17 20:26:05 volumio volumio[876]: info: Exploding uri upnp/http://192.168.1.14:8200/ctl/ContentDir@A0$128$146$1528824085$6702760213$6702825749$6702891285$314507524 in service upnp_browser Sep 17 20:26:05 volumio volumio[876]: info: Exploding uri upnp/http://192.168.1.14:8200/ctl/ContentDir@A0$128$146$1528824085$6702760213$6702825749$6702891285$314573060 in service upnp_browser Sep 17 20:26:15 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 17 20:26:16 volumio volumio[876]: info: Preload queue cleared Sep 17 20:26:29 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 17 20:26:30 volumio volumio[876]: info: Preload queue cleared Sep 17 20:26:30 volumio dhcpcd[936]: eth0: failed to renew DHCP, rebinding Sep 17 20:26:30 volumio dhcpcd[936]: eth0: NAK: from 192.168.1.1 Sep 17 20:26:30 volumio dhcpcd[936]: eth0: deleting route to 192.168.0.0/16 Sep 17 20:26:30 volumio dhcpcd[3363]: eth0: pid 0 deleted route to 192.168.0.0/16 Sep 17 20:26:30 volumio dhcpcd[3363]: eth0: pid 0 deleted default route via 192.168.1.1 Sep 17 20:26:30 volumio avahi-daemon[628]: Withdrawing address record for 192.168.1.29 on eth0. Sep 17 20:26:30 volumio avahi-daemon[628]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.29. Sep 17 20:26:30 volumio avahi-daemon[628]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.30. Sep 17 20:26:30 volumio dnsmasq[862]: reading /etc/resolv.conf Sep 17 20:26:30 volumio dnsmasq[862]: using nameserver 192.168.1.1#53 Sep 17 20:26:30 volumio dnsmasq[862]: using nameserver 208.67.222.222#53 Sep 17 20:26:30 volumio dnsmasq[862]: using nameserver 208.67.220.220#53 Sep 17 20:26:30 volumio dhcpcd[936]: eth0: soliciting a DHCP lease Sep 17 20:26:30 volumio dnsmasq-dhcp[862]: no address range available for DHCP request via wlan0 Sep 17 20:26:30 volumio dhcpcd[936]: eth0: offered 192.168.1.31 from 192.168.1.1 Sep 17 20:26:30 volumio dhcpcd[936]: eth0: probing address 192.168.1.31/16 Sep 17 20:26:31 volumio dnsmasq-dhcp[862]: no address range available for DHCP request via wlan0 Sep 17 20:26:31 volumio ntpd[776]: Deleting interface #5 eth0, 192.168.1.29#123, interface stats: received=183, sent=186, dropped=0, active_time=1575 secs Sep 17 20:26:31 volumio ntpd[776]: 45.90.217.6 local addr 192.168.1.29 -> Sep 17 20:26:31 volumio ntpd[776]: 94.100.180.133 local addr 192.168.1.29 -> Sep 17 20:26:31 volumio ntpd[776]: 90.188.9.144 local addr 192.168.1.29 -> Sep 17 20:26:31 volumio ntpd[776]: 93.95.100.104 local addr 192.168.1.29 -> Sep 17 20:26:31 volumio ntpd[776]: 217.28.222.15 local addr 192.168.1.29 -> Sep 17 20:26:31 volumio ntpd[776]: 193.192.36.120 local addr 192.168.1.29 -> Sep 17 20:26:36 volumio dhcpcd[936]: eth0: leased 192.168.1.31 for 1800 seconds Sep 17 20:26:36 volumio dhcpcd[936]: eth0: adding route to 192.168.0.0/16 Sep 17 20:26:36 volumio dhcpcd[936]: eth0: adding default route via 192.168.1.1 Sep 17 20:26:36 volumio avahi-daemon[628]: Registering new address record for 192.168.1.31 on eth0.IPv4. Sep 17 20:26:36 volumio dnsmasq[862]: reading /etc/resolv.conf Sep 17 20:26:36 volumio dnsmasq[862]: using nameserver 192.168.1.1#53 Sep 17 20:26:36 volumio dnsmasq[862]: using nameserver 208.67.222.222#53 Sep 17 20:26:36 volumio dnsmasq[862]: using nameserver 208.67.220.220#53 Sep 17 20:26:37 volumio ntpd[776]: Listen normally on 8 eth0 192.168.1.31:123 Sep 17 20:26:37 volumio ntpd[776]: new interface(s) found: waking up resolver Sep 17 20:26:39 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.29 from 192.168.1.17 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::volumioGetVisibleSources Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::volumioGetState Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::volumioGetState Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 17 20:26:39 volumio volumio[876]: info: Received Get System Info Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 20:26:39 volumio volumio[876]: info: Discovery: Getting this device information Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::volumioGetState Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::volumioGetState Sep 17 20:26:39 volumio volumio[876]: info: Listing playlists Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::volumioGetQueue Sep 17 20:26:39 volumio volumio[876]: info: CoreStateMachine::getQueue Sep 17 20:26:39 volumio volumio[876]: info: CorePlayQueue::getQueue Sep 17 20:26:39 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.29 from 192.168.1.17 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 6 Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::volumioGetVisibleSources Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::volumioGetState Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::volumioGetState Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 17 20:26:39 volumio volumio[876]: info: Received Get System Info Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 20:26:39 volumio volumio[876]: info: Discovery: Getting this device information Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::volumioGetState Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 20:26:39 volumio volumio[876]: info: CoreCommandRouter::volumioGetState Sep 17 20:26:39 volumio volumio[876]: info: Listing playlists Sep 17 20:26:41 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 17 20:26:41 volumio volumio[876]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 20:26:41 volumio volumio[876]: TypeError: Cannot read property 'length' of undefined Sep 17 20:26:41 volumio volumio[876]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Sep 17 20:26:41 volumio volumio[876]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Sep 17 20:26:41 volumio volumio[876]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Sep 17 20:26:41 volumio volumio[876]: at Parser.emit (events.js:315:20) Sep 17 20:26:41 volumio volumio[876]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Sep 17 20:26:41 volumio volumio[876]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Sep 17 20:26:41 volumio volumio[876]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Sep 17 20:26:41 volumio volumio[876]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Sep 17 20:26:41 volumio volumio[876]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Sep 17 20:26:41 volumio volumio[876]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Sep 17 20:26:41 volumio volumio[876]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Sep 17 20:26:41 volumio volumio[876]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Sep 17 20:26:41 volumio volumio[876]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Sep 17 20:26:41 volumio volumio[876]: at IncomingMessage.emit (events.js:327:22) Sep 17 20:26:41 volumio volumio[876]: at endReadableNT (internal/streams/readable.js:1327:12) Sep 17 20:26:41 volumio volumio[876]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 17 20:26:41 volumio volumio[876]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 20:26:41 volumio sudo[5585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-17 20:25 Sep 17 20:26:41 volumio sudo[5585]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"