-- Logs begin at Sat 2025-05-31 19:15:52 CEST, end at Tue 2025-06-03 20:12:36 CEST. -- Jun 03 20:11:01 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 03 20:11:01 volumio-2 volumio[706]: info: Preload queue cleared Jun 03 20:11:03 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 03 20:11:03 volumio-2 volumio[706]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 03 20:11:03 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 03 20:11:03 volumio-2 volumio[706]: info: Received Get System Version Jun 03 20:11:03 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 03 20:11:03 volumio-2 volumio[706]: info: Received Get System Info Jun 03 20:11:03 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 20:11:03 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 20:11:03 volumio-2 volumio[706]: info: Discovery: Getting this device information Jun 03 20:11:03 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetState Jun 03 20:11:03 volumio-2 volumio[706]: info: CorePlayQueue::getTrack 2 Jun 03 20:11:03 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 20:11:03 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 03 20:11:05 volumio-2 volumio[706]: info: Preload queue cleared Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$0 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$5 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$6 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$7 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$9 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$A Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$B Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$C Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$D Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$E Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$F Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$10 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$11 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$12 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$13 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$14 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$15 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$16 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$17 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$18 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$19 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1A Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1B Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1C Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1D Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1E Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1F Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$20 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$21 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$22 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$23 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$24 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$25 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$26 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$27 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$28 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$29 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2A Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2B Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2C Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2D Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2E Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2F Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$30 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$31 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$32 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$33 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$34 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$35 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$36 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$37 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$38 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$39 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3A Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3B Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3C Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3D Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3E Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3F Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$40 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$41 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$42 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$43 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$44 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$45 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$46 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$47 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$48 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$49 Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4A Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4B Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4C Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4D Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4E Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4F Jun 03 20:11:05 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$50 Jun 03 20:11:05 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$0 in service upnp_browser Jun 03 20:11:05 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1 in service upnp_browser Jun 03 20:11:05 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2 in service upnp_browser Jun 03 20:11:05 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3 in service upnp_browser Jun 03 20:11:05 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4 in service upnp_browser Jun 03 20:11:05 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$5 in service upnp_browser Jun 03 20:11:05 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$6 in service upnp_browser Jun 03 20:11:05 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$7 in service upnp_browser Jun 03 20:11:05 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$9 in service upnp_browser Jun 03 20:11:06 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$A in service upnp_browser Jun 03 20:11:06 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$B in service upnp_browser Jun 03 20:11:06 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$C in service upnp_browser Jun 03 20:11:06 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$D in service upnp_browser Jun 03 20:11:06 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$E in service upnp_browser Jun 03 20:11:06 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$F in service upnp_browser Jun 03 20:11:06 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$10 in service upnp_browser Jun 03 20:11:06 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$11 in service upnp_browser Jun 03 20:11:06 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$12 in service upnp_browser Jun 03 20:11:06 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$13 in service upnp_browser Jun 03 20:11:06 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$14 in service upnp_browser Jun 03 20:11:06 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$15 in service upnp_browser Jun 03 20:11:06 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$16 in service upnp_browser Jun 03 20:11:06 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$17 in service upnp_browser Jun 03 20:11:06 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$18 in service upnp_browser Jun 03 20:11:06 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$19 in service upnp_browser Jun 03 20:11:07 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1A in service upnp_browser Jun 03 20:11:07 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1B in service upnp_browser Jun 03 20:11:07 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1C in service upnp_browser Jun 03 20:11:07 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1D in service upnp_browser Jun 03 20:11:07 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1E in service upnp_browser Jun 03 20:11:07 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1F in service upnp_browser Jun 03 20:11:07 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$20 in service upnp_browser Jun 03 20:11:07 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$21 in service upnp_browser Jun 03 20:11:07 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$22 in service upnp_browser Jun 03 20:11:07 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$23 in service upnp_browser Jun 03 20:11:07 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$24 in service upnp_browser Jun 03 20:11:07 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$25 in service upnp_browser Jun 03 20:11:07 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$26 in service upnp_browser Jun 03 20:11:07 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$27 in service upnp_browser Jun 03 20:11:07 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$28 in service upnp_browser Jun 03 20:11:08 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$29 in service upnp_browser Jun 03 20:11:08 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2A in service upnp_browser Jun 03 20:11:08 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2B in service upnp_browser Jun 03 20:11:08 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2C in service upnp_browser Jun 03 20:11:08 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2D in service upnp_browser Jun 03 20:11:08 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2E in service upnp_browser Jun 03 20:11:08 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2F in service upnp_browser Jun 03 20:11:08 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$30 in service upnp_browser Jun 03 20:11:08 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$31 in service upnp_browser Jun 03 20:11:08 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$32 in service upnp_browser Jun 03 20:11:08 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$33 in service upnp_browser Jun 03 20:11:08 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$34 in service upnp_browser Jun 03 20:11:08 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$35 in service upnp_browser Jun 03 20:11:08 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$36 in service upnp_browser Jun 03 20:11:08 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$37 in service upnp_browser Jun 03 20:11:08 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$38 in service upnp_browser Jun 03 20:11:08 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$39 in service upnp_browser Jun 03 20:11:08 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3A in service upnp_browser Jun 03 20:11:08 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3B in service upnp_browser Jun 03 20:11:08 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3C in service upnp_browser Jun 03 20:11:09 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3D in service upnp_browser Jun 03 20:11:09 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3E in service upnp_browser Jun 03 20:11:09 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3F in service upnp_browser Jun 03 20:11:09 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$40 in service upnp_browser Jun 03 20:11:09 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$41 in service upnp_browser Jun 03 20:11:09 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$42 in service upnp_browser Jun 03 20:11:09 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$43 in service upnp_browser Jun 03 20:11:09 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$44 in service upnp_browser Jun 03 20:11:09 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$45 in service upnp_browser Jun 03 20:11:09 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$46 in service upnp_browser Jun 03 20:11:09 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$47 in service upnp_browser Jun 03 20:11:09 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$48 in service upnp_browser Jun 03 20:11:09 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$49 in service upnp_browser Jun 03 20:11:09 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4A in service upnp_browser Jun 03 20:11:09 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4B in service upnp_browser Jun 03 20:11:09 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4C in service upnp_browser Jun 03 20:11:09 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4D in service upnp_browser Jun 03 20:11:10 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4E in service upnp_browser Jun 03 20:11:10 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4F in service upnp_browser Jun 03 20:11:10 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$50 in service upnp_browser Jun 03 20:11:10 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetState Jun 03 20:11:10 volumio-2 volumio[706]: info: CorePlayQueue::getTrack 2 Jun 03 20:11:10 volumio-2 volumio[706]: info: Listing playlists Jun 03 20:11:10 volumio-2 volumio[706]: info: Listing playlists Jun 03 20:11:13 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 20:11:13 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 20:11:13 volumio-2 volumio[706]: info: Discovery: Getting this device information Jun 03 20:11:13 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetState Jun 03 20:11:13 volumio-2 volumio[706]: info: CorePlayQueue::getTrack 2 Jun 03 20:11:13 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 20:11:14 volumio-2 volumio[706]: info: Preload queue cleared Jun 03 20:11:14 volumio-2 volumio[706]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 03 20:11:14 volumio-2 volumio[706]: info: CoreStateMachine::ClearQueue Jun 03 20:11:14 volumio-2 volumio[706]: info: CoreStateMachine::stop Jun 03 20:11:14 volumio-2 volumio[706]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 20:11:14 volumio-2 volumio[706]: info: CoreStateMachine::stPlaybackTimer Jun 03 20:11:14 volumio-2 volumio[706]: info: CoreStateMachine::updateTrackBlock Jun 03 20:11:14 volumio-2 volumio[706]: info: CorePlayQueue::getTrackBlock Jun 03 20:11:14 volumio-2 volumio[706]: info: CoreStateMachine::pushState Jun 03 20:11:14 volumio-2 volumio[706]: info: CorePlayQueue::getTrack 2 Jun 03 20:11:14 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 20:11:14 volumio-2 volumio[706]: info: CoreCommandRouter::volumioPushState Jun 03 20:11:14 volumio-2 volumio[706]: info: CoreStateMachine::serviceStop Jun 03 20:11:14 volumio-2 volumio[706]: info: CorePlayQueue::getTrack 2 Jun 03 20:11:14 volumio-2 volumio[706]: info: CoreCommandRouter::serviceStop Jun 03 20:11:14 volumio-2 volumio[706]: info: [1748974274733] ControllerUPNPBrowser::stop Jun 03 20:11:14 volumio-2 volumio[706]: verbose: ControllerMpd::sendMpdCommand stop Jun 03 20:11:14 volumio-2 volumio[706]: info: CorePlayQueue::clearPlayQueue Jun 03 20:11:14 volumio-2 volumio[706]: info: CorePlayQueue::saveQueue Jun 03 20:11:14 volumio-2 volumio[706]: info: CoreCommandRouter::volumioPushQueue Jun 03 20:11:14 volumio-2 volumio[706]: info: CoreStateMachine::addQueueItems Jun 03 20:11:14 volumio-2 volumio[706]: info: CorePlayQueue::addQueueItems Jun 03 20:11:14 volumio-2 volumio[706]: info: Preload queue cleared Jun 03 20:11:14 volumio-2 volumio[706]: info: Adding Item to queue: upnp/folder/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$8 Jun 03 20:11:14 volumio-2 volumio[706]: info: Exploding uri upnp/folder/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$8 in service upnp_browser Jun 03 20:11:14 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$0 Jun 03 20:11:14 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$0 Jun 03 20:11:14 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1 Jun 03 20:11:14 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1 Jun 03 20:11:14 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2 Jun 03 20:11:14 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2 Jun 03 20:11:14 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3 Jun 03 20:11:14 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3 Jun 03 20:11:14 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4 Jun 03 20:11:14 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4 Jun 03 20:11:14 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$5 Jun 03 20:11:14 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$5 Jun 03 20:11:14 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$6 Jun 03 20:11:14 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$6 Jun 03 20:11:14 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$7 Jun 03 20:11:14 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$7 Jun 03 20:11:14 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$9 Jun 03 20:11:14 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$9 Jun 03 20:11:14 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$A Jun 03 20:11:14 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$A Jun 03 20:11:14 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$B Jun 03 20:11:14 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$B Jun 03 20:11:14 volumio-2 volumio[706]: info: CoreCommandRouter::volumioPushQueue Jun 03 20:11:15 volumio-2 volumio[706]: info: CorePlayQueue::saveQueue Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreStateMachine::updateTrackBlock Jun 03 20:11:15 volumio-2 volumio[706]: info: CorePlayQueue::getTrackBlock Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreCommandRouter::volumioPlay Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreStateMachine::play index 11 Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreStateMachine::addQueueItems Jun 03 20:11:15 volumio-2 volumio[706]: info: CorePlayQueue::addQueueItems Jun 03 20:11:15 volumio-2 volumio[706]: info: Preload queue cleared Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$C Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$C Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$D Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$D Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$E Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$E Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$F Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$F Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$10 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$10 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$11 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$11 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$12 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$12 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$13 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$13 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$14 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$14 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$15 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$15 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$16 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$16 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$17 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$17 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$18 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$18 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$19 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$19 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1A Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1A Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1B Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1B Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1C Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1C Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1D Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1D Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1E Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1E Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1F Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$1F Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$20 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$20 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$21 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$21 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$22 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$22 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$23 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$23 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$24 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$24 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$25 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$25 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$26 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$26 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$27 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$27 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$28 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$28 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$29 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$29 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2A Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2A Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2B Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2B Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2C Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2C Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2D Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2D Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2E Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2E Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2F Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$2F Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$30 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$30 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$31 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$31 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$32 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$32 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$33 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$33 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$34 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$34 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$35 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$35 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$36 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$36 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$37 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$37 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$38 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$38 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$39 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$39 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3A Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3A Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3B Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3B Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3C Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3C Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3D Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3D Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3E Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3E Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3F Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$3F Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$40 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$40 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$41 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$41 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$42 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$42 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$43 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$43 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$44 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$44 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$45 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$45 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$46 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$46 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$47 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$47 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$48 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$48 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$49 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$49 Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4A Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4A Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4B Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4B Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4C Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4C Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4D Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4D Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4E Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4E Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4F Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$4F Jun 03 20:11:15 volumio-2 volumio[706]: info: Adding Item to queue: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$50 Jun 03 20:11:15 volumio-2 volumio[706]: info: Using cached record of: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$50 Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreStateMachine::stop Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreCommandRouter::volumioPushQueue Jun 03 20:11:15 volumio-2 volumio[706]: info: CorePlayQueue::saveQueue Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreStateMachine::play index undefined Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreStateMachine::updateTrackBlock Jun 03 20:11:15 volumio-2 volumio[706]: info: CorePlayQueue::getTrackBlock Jun 03 20:11:15 volumio-2 volumio[706]: info: CorePlayQueue::getTrack 11 Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreStateMachine::startPlaybackTimer Jun 03 20:11:15 volumio-2 volumio[706]: info: CorePlayQueue::getTrack 11 Jun 03 20:11:15 volumio-2 volumio[706]: info: [1748974275538] ControllerUPNPBrowser::clearAddPlayTrack Jun 03 20:11:15 volumio-2 volumio[706]: verbose: ControllerMpd::sendMpdCommand stop Jun 03 20:11:15 volumio-2 volumio[706]: info: Jun 03 20:11:15 volumio-2 volumio[706]: ---------------------------- MPD announces state update: player Jun 03 20:11:15 volumio-2 volumio[706]: info: sendMpdCommand stop took 823 milliseconds Jun 03 20:11:15 volumio-2 volumio[706]: info: ControllerMpd::getState Jun 03 20:11:15 volumio-2 volumio[706]: verbose: ControllerMpd::sendMpdCommand status Jun 03 20:11:15 volumio-2 volumio[706]: info: sendMpdCommand stop took 21 milliseconds Jun 03 20:11:15 volumio-2 volumio[706]: verbose: ControllerMpd::sendMpdCommand clear Jun 03 20:11:15 volumio-2 volumio[706]: info: Jun 03 20:11:15 volumio-2 volumio[706]: ---------------------------- MPD announces state update: player Jun 03 20:11:15 volumio-2 volumio[706]: info: ControllerMpd::getState Jun 03 20:11:15 volumio-2 volumio[706]: verbose: ControllerMpd::sendMpdCommand status Jun 03 20:11:15 volumio-2 volumio[706]: info: Jun 03 20:11:15 volumio-2 volumio[706]: ---------------------------- MPD announces state update: player Jun 03 20:11:15 volumio-2 volumio[706]: info: ControllerMpd::getState Jun 03 20:11:15 volumio-2 volumio[706]: verbose: ControllerMpd::sendMpdCommand status Jun 03 20:11:15 volumio-2 volumio[706]: info: Jun 03 20:11:15 volumio-2 volumio[706]: ---------------------------- MPD announces system playlist update Jun 03 20:11:15 volumio-2 volumio[706]: info: Ignoring MPD Status Update Jun 03 20:11:15 volumio-2 volumio[706]: info: sendMpdCommand status took 149 milliseconds Jun 03 20:11:15 volumio-2 volumio[706]: info: sendMpdCommand clear took 144 milliseconds Jun 03 20:11:15 volumio-2 volumio[706]: info: sendMpdCommand status took 135 milliseconds Jun 03 20:11:15 volumio-2 volumio[706]: info: sendMpdCommand status took 128 milliseconds Jun 03 20:11:15 volumio-2 volumio[706]: verbose: ControllerMpd::parseState Jun 03 20:11:15 volumio-2 volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 20:11:15 volumio-2 volumio[706]: verbose: ControllerMpd::sendMpdCommand load "http://10.104.114.113:9999/MediaItems/81.flac" Jun 03 20:11:15 volumio-2 volumio[706]: verbose: ControllerMpd::parseState Jun 03 20:11:15 volumio-2 volumio[706]: verbose: ControllerMpd::parseState Jun 03 20:11:15 volumio-2 volumio[706]: info: ControllerMpd::pushState Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreCommandRouter::servicePushState Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreStateMachine::pushState Jun 03 20:11:15 volumio-2 volumio[706]: info: CorePlayQueue::getTrack 11 Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreCommandRouter::volumioPushState Jun 03 20:11:15 volumio-2 volumio[706]: info: CorePlayQueue::getTrack 11 Jun 03 20:11:15 volumio-2 volumio[706]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 03 20:11:15 volumio-2 volumio[706]: verbose: CURRENT POSITION 11 Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreStateMachine::syncState stateService stop Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreStateMachine::syncState currentStatus stop Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreStateMachine::pushState Jun 03 20:11:15 volumio-2 volumio[706]: info: CorePlayQueue::getTrack 11 Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreCommandRouter::volumioPushState Jun 03 20:11:15 volumio-2 volumio[706]: info: No code Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreStateMachine::pushState Jun 03 20:11:15 volumio-2 volumio[706]: info: CorePlayQueue::getTrack 11 Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreCommandRouter::volumioPushState Jun 03 20:11:15 volumio-2 volumio[706]: info: ControllerMpd::pushState Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreCommandRouter::servicePushState Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreStateMachine::pushState Jun 03 20:11:15 volumio-2 volumio[706]: info: CorePlayQueue::getTrack 11 Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreCommandRouter::volumioPushState Jun 03 20:11:15 volumio-2 volumio[706]: info: CorePlayQueue::getTrack 11 Jun 03 20:11:15 volumio-2 volumio[706]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 03 20:11:15 volumio-2 volumio[706]: verbose: CURRENT POSITION 11 Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreStateMachine::syncState stateService stop Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreStateMachine::syncState currentStatus stop Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreStateMachine::pushState Jun 03 20:11:15 volumio-2 volumio[706]: info: CorePlayQueue::getTrack 11 Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreCommandRouter::volumioPushState Jun 03 20:11:15 volumio-2 volumio[706]: info: No code Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreStateMachine::pushState Jun 03 20:11:15 volumio-2 volumio[706]: info: CorePlayQueue::getTrack 11 Jun 03 20:11:15 volumio-2 volumio[706]: info: CoreCommandRouter::volumioPushState Jun 03 20:11:16 volumio-2 volumio[706]: info: ------------------------------ 571ms Jun 03 20:11:16 volumio-2 volumio[706]: info: ------------------------------ 570ms Jun 03 20:11:16 volumio-2 volumio[706]: info: Jun 03 20:11:16 volumio-2 volumio[706]: ---------------------------- MPD announces system playlist update Jun 03 20:11:16 volumio-2 volumio[706]: info: Ignoring MPD Status Update Jun 03 20:11:16 volumio-2 volumio[706]: info: Jun 03 20:11:16 volumio-2 volumio[706]: ---------------------------- MPD announces system playlist update Jun 03 20:11:16 volumio-2 volumio[706]: info: Ignoring MPD Status Update Jun 03 20:11:16 volumio-2 volumio[706]: error: updateQueue error: null Jun 03 20:11:16 volumio-2 volumio[706]: info: ------------------------------ 494ms Jun 03 20:11:16 volumio-2 volumio[706]: info: sendMpdCommand playlistinfo took 468 milliseconds Jun 03 20:11:16 volumio-2 volumio[706]: verbose: ControllerMpd::sendMpdCommand add "http://10.104.114.113:9999/MediaItems/81.flac" Jun 03 20:11:16 volumio-2 volumio[706]: info: ------------------------------ 37ms Jun 03 20:11:16 volumio-2 volumio[706]: info: ------------------------------ 32ms Jun 03 20:11:16 volumio-2 volumio[706]: verbose: ControllerMpd::parseTrackInfo Jun 03 20:11:16 volumio-2 volumio[706]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 03 20:11:16 volumio-2 volumio[706]: info: ------------------------------ 653ms Jun 03 20:11:16 volumio-2 volumio[706]: info: Jun 03 20:11:16 volumio-2 volumio[706]: ---------------------------- MPD announces system playlist update Jun 03 20:11:16 volumio-2 volumio[706]: info: Ignoring MPD Status Update Jun 03 20:11:16 volumio-2 volumio[706]: info: sendMpdCommand add "http://10.104.114.113:9999/MediaItems/81.flac" took 68 milliseconds Jun 03 20:11:16 volumio-2 volumio[706]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 03 20:11:16 volumio-2 volumio[706]: verbose: ControllerMpd::sendMpdCommand play Jun 03 20:11:16 volumio-2 volumio[706]: info: Jun 03 20:11:16 volumio-2 volumio[706]: ---------------------------- MPD announces system playlist update Jun 03 20:11:16 volumio-2 volumio[706]: info: Ignoring MPD Status Update Jun 03 20:11:16 volumio-2 volumio[706]: info: Jun 03 20:11:16 volumio-2 volumio[706]: ---------------------------- MPD announces system playlist update Jun 03 20:11:16 volumio-2 volumio[706]: info: Ignoring MPD Status Update Jun 03 20:11:16 volumio-2 volumio[706]: info: Jun 03 20:11:16 volumio-2 volumio[706]: ---------------------------- MPD announces state update: player Jun 03 20:11:16 volumio-2 volumio[706]: info: ------------------------------ 135ms Jun 03 20:11:16 volumio-2 volumio[706]: info: sendMpdCommand play took 127 milliseconds Jun 03 20:11:16 volumio-2 volumio[706]: info: ------------------------------ 123ms Jun 03 20:11:16 volumio-2 volumio[706]: info: ------------------------------ 113ms Jun 03 20:11:16 volumio-2 volumio[706]: info: ControllerMpd::getState Jun 03 20:11:16 volumio-2 volumio[706]: verbose: ControllerMpd::sendMpdCommand status Jun 03 20:11:16 volumio-2 volumio[706]: info: Jun 03 20:11:16 volumio-2 volumio[706]: ---------------------------- MPD announces state update: player Jun 03 20:11:16 volumio-2 volumio[706]: info: ControllerMpd::getState Jun 03 20:11:16 volumio-2 volumio[706]: verbose: ControllerMpd::sendMpdCommand status Jun 03 20:11:16 volumio-2 volumio[706]: info: Jun 03 20:11:16 volumio-2 volumio[706]: ---------------------------- MPD announces state update: player Jun 03 20:11:16 volumio-2 volumio[706]: info: ControllerMpd::getState Jun 03 20:11:16 volumio-2 volumio[706]: verbose: ControllerMpd::sendMpdCommand status Jun 03 20:11:16 volumio-2 volumio[706]: info: sendMpdCommand status took 251 milliseconds Jun 03 20:11:16 volumio-2 volumio[706]: info: sendMpdCommand status took 53 milliseconds Jun 03 20:11:16 volumio-2 volumio[706]: verbose: ControllerMpd::parseState Jun 03 20:11:16 volumio-2 volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 20:11:16 volumio-2 volumio[706]: verbose: ControllerMpd::parseState Jun 03 20:11:16 volumio-2 volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 20:11:16 volumio-2 volumio[706]: info: Jun 03 20:11:16 volumio-2 volumio[706]: ---------------------------- MPD announces system playlist update Jun 03 20:11:16 volumio-2 volumio[706]: info: Ignoring MPD Status Update Jun 03 20:11:16 volumio-2 volumio[706]: info: Jun 03 20:11:16 volumio-2 volumio[706]: ---------------------------- MPD announces state update: player Jun 03 20:11:16 volumio-2 volumio[706]: info: sendMpdCommand status took 96 milliseconds Jun 03 20:11:16 volumio-2 volumio[706]: info: ControllerMpd::getState Jun 03 20:11:16 volumio-2 volumio[706]: verbose: ControllerMpd::sendMpdCommand status Jun 03 20:11:16 volumio-2 volumio[706]: info: sendMpdCommand playlistinfo took 58 milliseconds Jun 03 20:11:16 volumio-2 volumio[706]: verbose: ControllerMpd::parseState Jun 03 20:11:16 volumio-2 volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 20:11:16 volumio-2 volumio[706]: verbose: ControllerMpd::parseTrackInfo Jun 03 20:11:16 volumio-2 volumio[706]: info: ControllerMpd::pushState Jun 03 20:11:16 volumio-2 volumio[706]: info: CoreCommandRouter::servicePushState Jun 03 20:11:16 volumio-2 volumio[706]: info: CorePlayQueue::getTrack 11 Jun 03 20:11:16 volumio-2 volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":175,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beautiful Indians (Minnehaha) - Part 2 (restored)","artist":"Duke Ellington","album":"Beautiful Indians (Minnehaha) - Part 2","uri":"http://10.104.114.113:9999/MediaItems/81.flac","trackType":"flac"} Jun 03 20:11:16 volumio-2 volumio[706]: verbose: CURRENT POSITION 11 Jun 03 20:11:16 volumio-2 volumio[706]: info: CoreStateMachine::syncState stateService play Jun 03 20:11:16 volumio-2 volumio[706]: info: CoreStateMachine::syncState currentStatus stop Jun 03 20:11:16 volumio-2 volumio[706]: info: ------------------------------ 503ms Jun 03 20:11:16 volumio-2 volumio[706]: info: Jun 03 20:11:16 volumio-2 volumio[706]: ---------------------------- MPD announces system playlist update Jun 03 20:11:16 volumio-2 volumio[706]: info: Ignoring MPD Status Update Jun 03 20:11:16 volumio-2 volumio[706]: info: Jun 03 20:11:16 volumio-2 volumio[706]: ---------------------------- MPD announces state update: player Jun 03 20:11:16 volumio-2 volumio[706]: info: ControllerMpd::getState Jun 03 20:11:16 volumio-2 volumio[706]: verbose: ControllerMpd::sendMpdCommand status Jun 03 20:11:16 volumio-2 volumio[706]: info: Jun 03 20:11:16 volumio-2 volumio[706]: ---------------------------- MPD announces system playlist update Jun 03 20:11:16 volumio-2 volumio[706]: info: Ignoring MPD Status Update Jun 03 20:11:16 volumio-2 volumio[706]: info: Jun 03 20:11:16 volumio-2 volumio[706]: ---------------------------- MPD announces state update: player Jun 03 20:11:16 volumio-2 volumio[706]: info: ControllerMpd::getState Jun 03 20:11:16 volumio-2 volumio[706]: verbose: ControllerMpd::sendMpdCommand status Jun 03 20:11:16 volumio-2 volumio[706]: info: sendMpdCommand playlistinfo took 276 milliseconds Jun 03 20:11:16 volumio-2 volumio[706]: info: ------------------------------ 261ms Jun 03 20:11:16 volumio-2 volumio[706]: info: sendMpdCommand status took 237 milliseconds Jun 03 20:11:16 volumio-2 volumio[706]: info: sendMpdCommand playlistinfo took 193 milliseconds Jun 03 20:11:16 volumio-2 volumio[706]: info: ------------------------------ 103ms Jun 03 20:11:17 volumio-2 volumio[706]: info: sendMpdCommand status took 94 milliseconds Jun 03 20:11:17 volumio-2 volumio[706]: verbose: ControllerMpd::parseTrackInfo Jun 03 20:11:17 volumio-2 volumio[706]: verbose: ControllerMpd::parseState Jun 03 20:11:17 volumio-2 volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 20:11:17 volumio-2 volumio[706]: verbose: ControllerMpd::parseTrackInfo Jun 03 20:11:17 volumio-2 volumio[706]: verbose: ControllerMpd::parseState Jun 03 20:11:17 volumio-2 volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 20:11:17 volumio-2 volumio[706]: info: ControllerMpd::pushState Jun 03 20:11:17 volumio-2 volumio[706]: info: CoreCommandRouter::servicePushState Jun 03 20:11:17 volumio-2 volumio[706]: info: CorePlayQueue::getTrack 11 Jun 03 20:11:17 volumio-2 volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":175,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beautiful Indians (Minnehaha) - Part 2 (restored)","artist":"Duke Ellington","album":"Beautiful Indians (Minnehaha) - Part 2","uri":"http://10.104.114.113:9999/MediaItems/81.flac","trackType":"flac"} Jun 03 20:11:17 volumio-2 volumio[706]: verbose: CURRENT POSITION 11 Jun 03 20:11:17 volumio-2 volumio[706]: info: CoreStateMachine::syncState stateService play Jun 03 20:11:17 volumio-2 volumio[706]: info: CoreStateMachine::syncState currentStatus play Jun 03 20:11:17 volumio-2 volumio[706]: info: Received an update from plugin. extracting info from payload Jun 03 20:11:17 volumio-2 volumio[706]: info: CoreStateMachine::pushState Jun 03 20:11:17 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 20:11:17 volumio-2 volumio[706]: info: CoreCommandRouter::volumioPushState Jun 03 20:11:17 volumio-2 volumio[706]: info: CoreStateMachine::pushState Jun 03 20:11:17 volumio-2 volumio[706]: info: CoreCommandRouter::volumioPushState Jun 03 20:11:17 volumio-2 volumio[706]: info: ControllerMpd::pushState Jun 03 20:11:17 volumio-2 volumio[706]: info: CoreCommandRouter::servicePushState Jun 03 20:11:17 volumio-2 volumio[706]: info: CorePlayQueue::getTrack 11 Jun 03 20:11:17 volumio-2 volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10,"duration":175,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1064 Kbps","isStreaming":false,"title":"Beautiful Indians (Minnehaha) - Part 2 (restored)","artist":"Duke Ellington","album":"Beautiful Indians (Minnehaha) - Part 2","uri":"http://10.104.114.113:9999/MediaItems/81.flac","trackType":"flac"} Jun 03 20:11:17 volumio-2 volumio[706]: verbose: CURRENT POSITION 11 Jun 03 20:11:17 volumio-2 volumio[706]: info: CoreStateMachine::syncState stateService play Jun 03 20:11:17 volumio-2 volumio[706]: info: CoreStateMachine::syncState currentStatus play Jun 03 20:11:17 volumio-2 volumio[706]: info: Received an update from plugin. extracting info from payload Jun 03 20:11:17 volumio-2 volumio[706]: info: CoreStateMachine::pushState Jun 03 20:11:17 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 20:11:17 volumio-2 volumio[706]: info: CoreCommandRouter::volumioPushState Jun 03 20:11:17 volumio-2 volumio[706]: info: CoreStateMachine::pushState Jun 03 20:11:17 volumio-2 volumio[706]: info: CoreCommandRouter::volumioPushState Jun 03 20:11:17 volumio-2 volumio[706]: info: ------------------------------ 1184ms Jun 03 20:11:17 volumio-2 volumio[706]: info: ------------------------------ 1196ms Jun 03 20:11:17 volumio-2 volumio[706]: info: ------------------------------ 945ms Jun 03 20:11:17 volumio-2 volumio[706]: info: sendMpdCommand status took 933 milliseconds Jun 03 20:11:17 volumio-2 volumio[706]: info: sendMpdCommand playlistinfo took 877 milliseconds Jun 03 20:11:17 volumio-2 volumio[706]: info: sendMpdCommand playlistinfo took 880 milliseconds Jun 03 20:11:17 volumio-2 volumio[706]: verbose: ControllerMpd::parseState Jun 03 20:11:17 volumio-2 volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 20:11:17 volumio-2 volumio[706]: verbose: ControllerMpd::parseTrackInfo Jun 03 20:11:18 volumio-2 volumio[706]: info: ControllerMpd::pushState Jun 03 20:11:18 volumio-2 volumio[706]: info: CoreCommandRouter::servicePushState Jun 03 20:11:18 volumio-2 volumio[706]: info: CorePlayQueue::getTrack 11 Jun 03 20:11:18 volumio-2 volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":461,"duration":175,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2654 Kbps","isStreaming":false,"title":"Beautiful Indians (Minnehaha) - Part 2 (restored)","artist":"Duke Ellington","album":"Beautiful Indians (Minnehaha) - Part 2","uri":"http://10.104.114.113:9999/MediaItems/81.flac","trackType":"flac"} Jun 03 20:11:18 volumio-2 volumio[706]: verbose: CURRENT POSITION 11 Jun 03 20:11:18 volumio-2 volumio[706]: info: CoreStateMachine::syncState stateService play Jun 03 20:11:18 volumio-2 volumio[706]: info: CoreStateMachine::syncState currentStatus play Jun 03 20:11:18 volumio-2 volumio[706]: info: Received an update from plugin. extracting info from payload Jun 03 20:11:18 volumio-2 volumio[706]: info: CoreStateMachine::pushState Jun 03 20:11:18 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 20:11:18 volumio-2 volumio[706]: info: CoreCommandRouter::volumioPushState Jun 03 20:11:18 volumio-2 volumio[706]: info: CoreStateMachine::pushState Jun 03 20:11:18 volumio-2 volumio[706]: info: CoreCommandRouter::volumioPushState Jun 03 20:11:18 volumio-2 volumio[706]: info: ControllerMpd::pushState Jun 03 20:11:18 volumio-2 volumio[706]: info: CoreCommandRouter::servicePushState Jun 03 20:11:18 volumio-2 volumio[706]: info: CorePlayQueue::getTrack 11 Jun 03 20:11:18 volumio-2 volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":761,"duration":175,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2714 Kbps","isStreaming":false,"title":"Beautiful Indians (Minnehaha) - Part 2 (restored)","artist":"Duke Ellington","album":"Beautiful Indians (Minnehaha) - Part 2","uri":"http://10.104.114.113:9999/MediaItems/81.flac","trackType":"flac"} Jun 03 20:11:18 volumio-2 volumio[706]: verbose: CURRENT POSITION 11 Jun 03 20:11:18 volumio-2 volumio[706]: info: CoreStateMachine::syncState stateService play Jun 03 20:11:18 volumio-2 volumio[706]: info: CoreStateMachine::syncState currentStatus play Jun 03 20:11:18 volumio-2 volumio[706]: info: Received an update from plugin. extracting info from payload Jun 03 20:11:18 volumio-2 volumio[706]: info: CoreStateMachine::pushState Jun 03 20:11:18 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 20:11:18 volumio-2 volumio[706]: info: CoreCommandRouter::volumioPushState Jun 03 20:11:18 volumio-2 volumio[706]: info: CoreStateMachine::pushState Jun 03 20:11:18 volumio-2 volumio[706]: info: CoreCommandRouter::volumioPushState Jun 03 20:11:19 volumio-2 volumio[706]: info: ------------------------------ 2770ms Jun 03 20:11:19 volumio-2 volumio[706]: info: ------------------------------ 2651ms Jun 03 20:11:19 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetQueue Jun 03 20:11:19 volumio-2 volumio[706]: info: CoreStateMachine::getQueue Jun 03 20:11:19 volumio-2 volumio[706]: info: CorePlayQueue::getQueue Jun 03 20:11:19 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetQueue Jun 03 20:11:19 volumio-2 volumio[706]: info: CoreStateMachine::getQueue Jun 03 20:11:19 volumio-2 volumio[706]: info: CorePlayQueue::getQueue Jun 03 20:11:19 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetQueue Jun 03 20:11:19 volumio-2 volumio[706]: info: CoreStateMachine::getQueue Jun 03 20:11:19 volumio-2 volumio[706]: info: CorePlayQueue::getQueue Jun 03 20:11:19 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetQueue Jun 03 20:11:19 volumio-2 volumio[706]: info: CoreStateMachine::getQueue Jun 03 20:11:19 volumio-2 volumio[706]: info: CorePlayQueue::getQueue Jun 03 20:11:19 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetQueue Jun 03 20:11:19 volumio-2 volumio[706]: info: CoreStateMachine::getQueue Jun 03 20:11:19 volumio-2 volumio[706]: info: CorePlayQueue::getQueue Jun 03 20:11:19 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetQueue Jun 03 20:11:19 volumio-2 volumio[706]: info: CoreStateMachine::getQueue Jun 03 20:11:19 volumio-2 volumio[706]: info: CorePlayQueue::getQueue Jun 03 20:11:20 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetQueue Jun 03 20:11:20 volumio-2 volumio[706]: info: CoreStateMachine::getQueue Jun 03 20:11:20 volumio-2 volumio[706]: info: CorePlayQueue::getQueue Jun 03 20:11:20 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetQueue Jun 03 20:11:20 volumio-2 volumio[706]: info: CoreStateMachine::getQueue Jun 03 20:11:20 volumio-2 volumio[706]: info: CorePlayQueue::getQueue Jun 03 20:11:20 volumio-2 volumio[706]: info: sendMpdCommand playlistinfo took 2347 milliseconds Jun 03 20:11:20 volumio-2 volumio[706]: verbose: ControllerMpd::parseTrackInfo Jun 03 20:11:20 volumio-2 volumio[706]: info: ControllerMpd::pushState Jun 03 20:11:20 volumio-2 volumio[706]: info: CoreCommandRouter::servicePushState Jun 03 20:11:20 volumio-2 volumio[706]: info: CorePlayQueue::getTrack 11 Jun 03 20:11:20 volumio-2 volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":761,"duration":175,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2714 Kbps","isStreaming":false,"title":"Beautiful Indians (Minnehaha) - Part 2 (restored)","artist":"Duke Ellington","album":"Beautiful Indians (Minnehaha) - Part 2","uri":"http://10.104.114.113:9999/MediaItems/81.flac","trackType":"flac"} Jun 03 20:11:20 volumio-2 volumio[706]: verbose: CURRENT POSITION 11 Jun 03 20:11:20 volumio-2 volumio[706]: info: CoreStateMachine::syncState stateService play Jun 03 20:11:20 volumio-2 volumio[706]: info: CoreStateMachine::syncState currentStatus play Jun 03 20:11:20 volumio-2 volumio[706]: info: Received an update from plugin. extracting info from payload Jun 03 20:11:20 volumio-2 volumio[706]: info: CoreStateMachine::pushState Jun 03 20:11:20 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 20:11:20 volumio-2 volumio[706]: info: CoreCommandRouter::volumioPushState Jun 03 20:11:21 volumio-2 volumio[706]: info: CoreStateMachine::pushState Jun 03 20:11:21 volumio-2 volumio[706]: info: CoreCommandRouter::volumioPushState Jun 03 20:11:22 volumio-2 volumio[706]: info: ------------------------------ 5693ms Jun 03 20:11:23 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetQueue Jun 03 20:11:23 volumio-2 volumio[706]: info: CoreStateMachine::getQueue Jun 03 20:11:23 volumio-2 volumio[706]: info: CorePlayQueue::getQueue Jun 03 20:11:23 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetQueue Jun 03 20:11:23 volumio-2 volumio[706]: info: CoreStateMachine::getQueue Jun 03 20:11:23 volumio-2 volumio[706]: info: CorePlayQueue::getQueue Jun 03 20:11:24 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetState Jun 03 20:11:24 volumio-2 volumio[706]: Upnp client error: Error: This socket has been ended by the other party Jun 03 20:11:30 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetState Jun 03 20:11:30 volumio-2 volumio[706]: info: Listing playlists Jun 03 20:11:40 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetState Jun 03 20:11:43 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 20:11:43 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 20:11:43 volumio-2 volumio[706]: info: Discovery: Getting this device information Jun 03 20:11:43 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetState Jun 03 20:11:43 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 20:11:44 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 03 20:11:48 volumio-2 volumio[706]: info: Preload queue cleared Jun 03 20:11:48 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 03 20:11:49 volumio-2 volumio[706]: info: Preload queue cleared Jun 03 20:11:49 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$8$0 Jun 03 20:11:49 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$8$1 Jun 03 20:11:49 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$8$2 Jun 03 20:11:49 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$8$3 Jun 03 20:11:49 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$8$4 Jun 03 20:11:49 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$8$5 Jun 03 20:11:49 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$8$6 Jun 03 20:11:49 volumio-2 volumio[706]: info: Preloading song: upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$8$7 Jun 03 20:11:49 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$8$0 in service upnp_browser Jun 03 20:11:50 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$8$1 in service upnp_browser Jun 03 20:11:50 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$8$2 in service upnp_browser Jun 03 20:11:50 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetState Jun 03 20:11:50 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$8$3 in service upnp_browser Jun 03 20:11:50 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$8$4 in service upnp_browser Jun 03 20:11:50 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$8$5 in service upnp_browser Jun 03 20:11:50 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$8$6 in service upnp_browser Jun 03 20:11:50 volumio-2 volumio[706]: info: Exploding uri upnp/http://10.104.114.113:9999/ctl/ContentDir@64$1$0$0$8$7 in service upnp_browser Jun 03 20:11:50 volumio-2 volumio[706]: info: Listing playlists Jun 03 20:11:51 volumio-2 volumio[706]: info: Listing playlists Jun 03 20:11:54 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetState Jun 03 20:11:57 volumio-2 sudo[4554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 03 20:11:57 volumio-2 sudo[4554]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 20:11:57 volumio-2 sudo[4554]: pam_unix(sudo:session): session closed for user root Jun 03 20:11:57 volumio-2 sudo[4556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 03 20:11:57 volumio-2 sudo[4556]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 20:11:57 volumio-2 sudo[4556]: pam_unix(sudo:session): session closed for user root Jun 03 20:11:58 volumio-2 volumio[706]: verbose: New Socket.io Connection to 10.104.114.112 from 10.104.114.146 UA: Mozilla/5.0 (Linux; Android 12; RT3 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jun 03 20:11:58 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetVisibleSources Jun 03 20:11:58 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 20:11:59 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 03 20:11:59 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetState Jun 03 20:11:59 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 03 20:11:59 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 03 20:11:59 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 03 20:11:59 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 03 20:12:00 volumio-2 sudo[4566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 03 20:12:00 volumio-2 sudo[4566]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 20:12:00 volumio-2 sudo[4566]: pam_unix(sudo:session): session closed for user root Jun 03 20:12:00 volumio-2 sudo[4569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 03 20:12:00 volumio-2 sudo[4569]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 20:12:01 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetState Jun 03 20:12:01 volumio-2 sudo[4569]: pam_unix(sudo:session): session closed for user root Jun 03 20:12:01 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetState Jun 03 20:12:02 volumio-2 volumio[706]: verbose: New Socket.io Connection to 10.104.114.112 from 10.104.114.146 UA: Mozilla/5.0 (Linux; Android 12; RT3 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jun 03 20:12:02 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetState Jun 03 20:12:02 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 03 20:12:02 volumio-2 volumio[706]: info: Listing playlists Jun 03 20:12:02 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 03 20:12:02 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 03 20:12:02 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 03 20:12:02 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 03 20:12:04 volumio-2 sudo[4595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 03 20:12:04 volumio-2 sudo[4595]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 20:12:04 volumio-2 sudo[4595]: pam_unix(sudo:session): session closed for user root Jun 03 20:12:04 volumio-2 sudo[4597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 03 20:12:04 volumio-2 sudo[4597]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 20:12:04 volumio-2 sudo[4597]: pam_unix(sudo:session): session closed for user root Jun 03 20:12:05 volumio-2 volumio[706]: verbose: New Socket.io Connection to 10.104.114.112 from 10.104.114.146 UA: Mozilla/5.0 (Linux; Android 12; RT3 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 03 20:12:06 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetVisibleSources Jun 03 20:12:06 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 20:12:06 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 03 20:12:06 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetState Jun 03 20:12:06 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 03 20:12:06 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 03 20:12:06 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 03 20:12:06 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 03 20:12:07 volumio-2 sudo[4608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 03 20:12:07 volumio-2 sudo[4608]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 20:12:07 volumio-2 sudo[4608]: pam_unix(sudo:session): session closed for user root Jun 03 20:12:07 volumio-2 sudo[4610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 03 20:12:07 volumio-2 sudo[4610]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 20:12:07 volumio-2 sudo[4610]: pam_unix(sudo:session): session closed for user root Jun 03 20:12:08 volumio-2 volumio[706]: verbose: New Socket.io Connection to 10.104.114.112 from 10.104.114.146 UA: Mozilla/5.0 (Linux; Android 12; RT3 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jun 03 20:12:08 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetState Jun 03 20:12:09 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 03 20:12:09 volumio-2 volumio[706]: info: Listing playlists Jun 03 20:12:09 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 03 20:12:09 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 03 20:12:09 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 03 20:12:09 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 03 20:12:09 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetState Jun 03 20:12:09 volumio-2 volumio[706]: info: Listing playlists Jun 03 20:12:13 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 20:12:13 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 20:12:13 volumio-2 volumio[706]: info: Discovery: Getting this device information Jun 03 20:12:13 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetState Jun 03 20:12:13 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 20:12:15 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetVisibleSources Jun 03 20:12:15 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 20:12:15 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetQueue Jun 03 20:12:15 volumio-2 volumio[706]: info: CoreStateMachine::getQueue Jun 03 20:12:15 volumio-2 volumio[706]: info: CorePlayQueue::getQueue Jun 03 20:12:18 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 03 20:12:18 volumio-2 volumio[706]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 03 20:12:18 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 03 20:12:18 volumio-2 volumio[706]: info: Received Get System Version Jun 03 20:12:18 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 03 20:12:18 volumio-2 volumio[706]: info: Received Get System Info Jun 03 20:12:18 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 20:12:18 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 20:12:18 volumio-2 volumio[706]: info: Discovery: Getting this device information Jun 03 20:12:18 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetState Jun 03 20:12:18 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 20:12:19 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetState Jun 03 20:12:20 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 03 20:12:20 volumio-2 volumio[706]: info: Preload queue cleared Jun 03 20:12:21 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 03 20:12:22 volumio-2 volumio[706]: info: Preload queue cleared Jun 03 20:12:24 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 03 20:12:24 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 03 20:12:24 volumio-2 volumio[706]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 03 20:12:24 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 03 20:12:24 volumio-2 volumio[706]: info: Received Get System Version Jun 03 20:12:24 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 03 20:12:24 volumio-2 volumio[706]: info: Received Get System Info Jun 03 20:12:24 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 20:12:24 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 20:12:24 volumio-2 volumio[706]: info: Discovery: Getting this device information Jun 03 20:12:24 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetState Jun 03 20:12:24 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 20:12:25 volumio-2 volumio[706]: info: Preload queue cleared Jun 03 20:12:26 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 03 20:12:26 volumio-2 volumio[706]: info: Preload queue cleared Jun 03 20:12:27 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 03 20:12:27 volumio-2 volumio[706]: info: Preload queue cleared Jun 03 20:12:29 volumio-2 volumio[706]: info: CoreCommandRouter::volumioGetState Jun 03 20:12:29 volumio-2 volumio[706]: info: Listing playlists Jun 03 20:12:32 volumio-2 volumio[706]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 03 20:12:32 volumio-2 volumio[706]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 20:12:32 volumio-2 volumio[706]: TypeError: Cannot read property 'length' of undefined Jun 03 20:12:32 volumio-2 volumio[706]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 03 20:12:32 volumio-2 volumio[706]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 03 20:12:32 volumio-2 volumio[706]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 03 20:12:32 volumio-2 volumio[706]: at Parser.emit (events.js:315:20) Jun 03 20:12:32 volumio-2 volumio[706]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 03 20:12:32 volumio-2 volumio[706]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 03 20:12:32 volumio-2 volumio[706]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 03 20:12:32 volumio-2 volumio[706]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 03 20:12:32 volumio-2 volumio[706]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 03 20:12:32 volumio-2 volumio[706]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 03 20:12:32 volumio-2 volumio[706]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 03 20:12:32 volumio-2 volumio[706]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 03 20:12:32 volumio-2 volumio[706]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 03 20:12:32 volumio-2 volumio[706]: at IncomingMessage.emit (events.js:327:22) Jun 03 20:12:32 volumio-2 volumio[706]: at endReadableNT (internal/streams/readable.js:1327:12) Jun 03 20:12:32 volumio-2 volumio[706]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 03 20:12:32 volumio-2 volumio[706]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 20:12:36 volumio-2 sudo[4722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-03 20:11 Jun 03 20:12:36 volumio-2 sudo[4722]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"