-- Logs begin at Thu 2019-02-14 18:11:58 +08, end at Mon 2025-09-15 18:16:52 +08. -- Sep 15 18:15:00 gary-volumio sudo[2434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Sep 15 18:15:00 gary-volumio sudo[2434]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:15:00 gary-volumio volumiobt[2420]: pulseaudio: no process found Sep 15 18:15:00 gary-volumio sudo[2434]: pam_unix(sudo:session): session closed for user root Sep 15 18:15:00 gary-volumio dbus-daemon[573]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.27' (uid=1000 pid=2445 comm="/usr/bin/pulseaudio --high-priority=true ") Sep 15 18:15:00 gary-volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Sep 15 18:15:00 gary-volumio volumio[1134]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 15 18:15:00 gary-volumio kernel: IPVS: Creating netns size=1496 id=3 Sep 15 18:15:00 gary-volumio kernel: IPVS: ftp: loaded support on port[0] = 21 Sep 15 18:15:00 gary-volumio dbus-daemon[573]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Sep 15 18:15:00 gary-volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. Sep 15 18:15:00 gary-volumio rtkit-daemon[2446]: Successfully called chroot. Sep 15 18:15:00 gary-volumio rtkit-daemon[2446]: Successfully dropped privileges. Sep 15 18:15:00 gary-volumio rtkit-daemon[2446]: Successfully limited resources. Sep 15 18:15:00 gary-volumio rtkit-daemon[2446]: Running. Sep 15 18:15:00 gary-volumio rtkit-daemon[2446]: Watchdog thread running. Sep 15 18:15:00 gary-volumio rtkit-daemon[2446]: Canary thread running. Sep 15 18:15:00 gary-volumio dbus-daemon[573]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.28' (uid=0 pid=2446 comm="/usr/lib/rtkit/rtkit-daemon ") Sep 15 18:15:00 gary-volumio systemd[1]: Starting Authorization Manager... Sep 15 18:15:00 gary-volumio volumio[1134]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 15 18:15:00 gary-volumio polkitd[2450]: started daemon version 0.105 using authority implementation `local' version `0.105' Sep 15 18:15:00 gary-volumio dbus-daemon[573]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Sep 15 18:15:00 gary-volumio systemd[1]: Started Authorization Manager. Sep 15 18:15:00 gary-volumio pulseaudio[2445]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 15 18:15:00 gary-volumio pulseaudio[2445]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 15 18:15:00 gary-volumio volumiobt[2420]: Applying permissions Sep 15 18:15:00 gary-volumio volumiobt[2420]: Setting BT discoverable and pairable Sep 15 18:15:00 gary-volumio bluetoothd[628]: Endpoint registered: sender=:1.48 path=/MediaEndpoint/A2DPSource Sep 15 18:15:00 gary-volumio bluetoothd[628]: Endpoint registered: sender=:1.48 path=/MediaEndpoint/A2DPSink Sep 15 18:15:00 gary-volumio pulseaudio[2445]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Sep 15 18:15:00 gary-volumio volumiobt[2420]: [bluetooth]# -e power on Sep 15 18:15:00 gary-volumio volumiobt[2420]: Invalid command in menu main: -e Sep 15 18:15:00 gary-volumio volumiobt[2420]: Sep 15 18:15:00 gary-volumio volumiobt[2420]: Use "help" for a list of available commands in a menu. Sep 15 18:15:00 gary-volumio volumiobt[2420]: Use "menu " if you want to enter any submenu. Sep 15 18:15:00 gary-volumio volumiobt[2420]: Use "back" if you want to return to menu main. Sep 15 18:15:00 gary-volumio volumiobt[2420]: [bluetooth]# agent on Sep 15 18:15:00 gary-volumio volumiobt[2420]: Failed to register agent object Sep 15 18:15:00 gary-volumio volumiobt[2420]: [bluetooth]# discoverable on Sep 15 18:15:00 gary-volumio volumiobt[2420]: [bluetooth]# pairable on Sep 15 18:15:00 gary-volumio volumiobt[2420]: [bluetooth]# agent NoInputNoOutput Sep 15 18:15:00 gary-volumio volumiobt[2420]: Failed to register agent object Sep 15 18:15:00 gary-volumio volumiobt[2420]: [bluetooth]# default-agent Sep 15 18:15:00 gary-volumio volumiobt[2420]: No agent is registered Sep 15 18:15:00 gary-volumio volumiobt[2420]: [bluetooth]# quit Sep 15 18:15:00 gary-volumio volumiobt[2420]: [59B blob data] Sep 15 18:15:01 gary-volumio volumio[1134]: info: MRS: Getting audio outputs on start Sep 15 18:15:01 gary-volumio volumio[1134]: info: MRS: Requesting all other devices output Sep 15 18:15:03 gary-volumio volumio[1134]: info: BOOT COMPLETED Sep 15 18:15:54 gary-volumio volumiologrotate[574]: ls: cannot access '/var/log/samba/log.wb-GARY': No such file or directory Sep 15 18:15:54 gary-volumio volumiologrotate[574]: ls: cannot access 'VOLUMIO': No such file or directory Sep 15 18:16:00 gary-volumio volumio[1134]: info: CoreCommandRouter::volumioGetVisibleSources Sep 15 18:16:00 gary-volumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 18:16:00 gary-volumio volumio[1134]: info: CoreCommandRouter::volumioGetQueue Sep 15 18:16:00 gary-volumio volumio[1134]: info: CoreStateMachine::getQueue Sep 15 18:16:00 gary-volumio volumio[1134]: info: CorePlayQueue::getQueue Sep 15 18:16:01 gary-volumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 15 18:16:01 gary-volumio volumio[1134]: info: Preload queue cleared Sep 15 18:16:03 gary-volumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 15 18:16:03 gary-volumio volumio[1134]: info: Preload queue cleared Sep 15 18:16:04 gary-volumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 15 18:16:12 gary-volumio volumio[1134]: info: Preload queue cleared Sep 15 18:16:15 gary-volumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 15 18:16:15 gary-volumio volumio[1134]: info: Preload queue cleared Sep 15 18:16:17 gary-volumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 15 18:16:17 gary-volumio volumio[1134]: info: Preload queue cleared Sep 15 18:16:19 gary-volumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 15 18:16:19 gary-volumio volumio[1134]: info: Preload queue cleared Sep 15 18:16:37 gary-volumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 15 18:16:38 gary-volumio volumio[1134]: info: Preload queue cleared Sep 15 18:16:38 gary-volumio volumio[1134]: info: Preloading song: upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308092164 Sep 15 18:16:38 gary-volumio volumio[1134]: info: Preloading song: upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10309075204 Sep 15 18:16:38 gary-volumio volumio[1134]: info: Preloading song: upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10309009668 Sep 15 18:16:38 gary-volumio volumio[1134]: info: Preloading song: upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308419844 Sep 15 18:16:38 gary-volumio volumio[1134]: info: Preloading song: upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308944132 Sep 15 18:16:38 gary-volumio volumio[1134]: info: Preloading song: upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308681988 Sep 15 18:16:38 gary-volumio volumio[1134]: info: Preloading song: upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308878596 Sep 15 18:16:38 gary-volumio volumio[1134]: info: Preloading song: upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308813060 Sep 15 18:16:38 gary-volumio volumio[1134]: info: Preloading song: upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308747524 Sep 15 18:16:38 gary-volumio volumio[1134]: info: Preloading song: upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308354308 Sep 15 18:16:38 gary-volumio volumio[1134]: info: Preloading song: upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308288772 Sep 15 18:16:38 gary-volumio volumio[1134]: info: Preloading song: upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308223236 Sep 15 18:16:38 gary-volumio volumio[1134]: info: Preloading song: upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308485380 Sep 15 18:16:38 gary-volumio volumio[1134]: info: Preloading song: upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308550916 Sep 15 18:16:38 gary-volumio volumio[1134]: info: Preloading song: upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308616452 Sep 15 18:16:38 gary-volumio volumio[1134]: info: Preloading song: upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308157700 Sep 15 18:16:38 gary-volumio volumio[1134]: info: Exploding uri upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308092164 in service upnp_browser Sep 15 18:16:38 gary-volumio volumio[1134]: info: Exploding uri upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10309075204 in service upnp_browser Sep 15 18:16:38 gary-volumio volumio[1134]: info: Exploding uri upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10309009668 in service upnp_browser Sep 15 18:16:38 gary-volumio volumio[1134]: info: Exploding uri upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308419844 in service upnp_browser Sep 15 18:16:38 gary-volumio volumio[1134]: info: Exploding uri upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308944132 in service upnp_browser Sep 15 18:16:38 gary-volumio volumio[1134]: info: Exploding uri upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308681988 in service upnp_browser Sep 15 18:16:38 gary-volumio volumio[1134]: info: Exploding uri upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308878596 in service upnp_browser Sep 15 18:16:38 gary-volumio volumio[1134]: info: Exploding uri upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308813060 in service upnp_browser Sep 15 18:16:38 gary-volumio volumio[1134]: info: Exploding uri upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308747524 in service upnp_browser Sep 15 18:16:38 gary-volumio volumio[1134]: info: Exploding uri upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308354308 in service upnp_browser Sep 15 18:16:38 gary-volumio volumio[1134]: info: Exploding uri upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308288772 in service upnp_browser Sep 15 18:16:38 gary-volumio volumio[1134]: info: Exploding uri upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308223236 in service upnp_browser Sep 15 18:16:38 gary-volumio volumio[1134]: info: Exploding uri upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308485380 in service upnp_browser Sep 15 18:16:38 gary-volumio volumio[1134]: info: Exploding uri upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308550916 in service upnp_browser Sep 15 18:16:38 gary-volumio volumio[1134]: info: Exploding uri upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308616452 in service upnp_browser Sep 15 18:16:39 gary-volumio volumio[1134]: info: Exploding uri upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$886702357$10308157700 in service upnp_browser Sep 15 18:16:42 gary-volumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 15 18:16:42 gary-volumio volumio[1134]: info: Preload queue cleared Sep 15 18:16:44 gary-volumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 15 18:16:44 gary-volumio volumio[1134]: info: Preload queue cleared Sep 15 18:16:44 gary-volumio volumio[1134]: info: Preloading song: upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$1251410197$1251475733$14184546564 Sep 15 18:16:44 gary-volumio volumio[1134]: info: Preloading song: upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$1251410197$1251475733$14184743172 Sep 15 18:16:44 gary-volumio volumio[1134]: info: Preloading song: upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$1251410197$1251475733$14184481028 Sep 15 18:16:44 gary-volumio volumio[1134]: info: Preloading song: upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$1251410197$1251475733$14184874244 Sep 15 18:16:44 gary-volumio volumio[1134]: info: Preloading song: upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$1251410197$1251475733$14184677636 Sep 15 18:16:44 gary-volumio volumio[1134]: info: Preloading song: upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$1251410197$1251475733$14184612100 Sep 15 18:16:44 gary-volumio volumio[1134]: info: Preloading song: upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$1251410197$1251475733$14184808708 Sep 15 18:16:44 gary-volumio volumio[1134]: info: Exploding uri upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$1251410197$1251475733$14184546564 in service upnp_browser Sep 15 18:16:44 gary-volumio volumio[1134]: info: Exploding uri upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$1251410197$1251475733$14184743172 in service upnp_browser Sep 15 18:16:44 gary-volumio volumio[1134]: info: Exploding uri upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$1251410197$1251475733$14184481028 in service upnp_browser Sep 15 18:16:44 gary-volumio volumio[1134]: info: Exploding uri upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$1251410197$1251475733$14184874244 in service upnp_browser Sep 15 18:16:44 gary-volumio volumio[1134]: info: Exploding uri upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$1251410197$1251475733$14184677636 in service upnp_browser Sep 15 18:16:44 gary-volumio volumio[1134]: info: Exploding uri upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$1251410197$1251475733$14184612100 in service upnp_browser Sep 15 18:16:44 gary-volumio volumio[1134]: info: Exploding uri upnp/http://192.168.50.181:8200/ctl/ContentDir@A0$128$146$697434389$702152981$1251410197$1251475733$14184808708 in service upnp_browser Sep 15 18:16:52 gary-volumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 15 18:16:52 gary-volumio volumio[1134]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 18:16:52 gary-volumio volumio[1134]: TypeError: Cannot read property 'length' of undefined Sep 15 18:16:52 gary-volumio volumio[1134]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Sep 15 18:16:52 gary-volumio volumio[1134]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Sep 15 18:16:52 gary-volumio volumio[1134]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Sep 15 18:16:52 gary-volumio volumio[1134]: at Parser.emit (events.js:400:28) Sep 15 18:16:52 gary-volumio volumio[1134]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Sep 15 18:16:52 gary-volumio volumio[1134]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Sep 15 18:16:52 gary-volumio volumio[1134]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Sep 15 18:16:52 gary-volumio volumio[1134]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Sep 15 18:16:52 gary-volumio volumio[1134]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Sep 15 18:16:52 gary-volumio volumio[1134]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Sep 15 18:16:52 gary-volumio volumio[1134]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Sep 15 18:16:52 gary-volumio volumio[1134]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Sep 15 18:16:52 gary-volumio volumio[1134]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Sep 15 18:16:52 gary-volumio volumio[1134]: at IncomingMessage.emit (events.js:412:35) Sep 15 18:16:52 gary-volumio volumio[1134]: at endReadableNT (internal/streams/readable.js:1333:12) Sep 15 18:16:52 gary-volumio volumio[1134]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Sep 15 18:16:52 gary-volumio volumio[1134]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 18:16:52 gary-volumio sudo[2685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-15 18:15 Sep 15 18:16:52 gary-volumio sudo[2685]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:55:15 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="2f3686ade78abf3b92c180a0c5b20c6b"