-- Logs begin at Fri 2024-08-30 16:05:51 CEST, end at Mon 2024-09-02 11:59:33 CEST. -- Sep 02 11:58:04 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetState Sep 02 11:58:05 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 02 11:58:05 volumio-henning volumio[816]: info: Preload queue cleared Sep 02 11:58:07 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 02 11:58:08 volumio-henning volumio[816]: info: Preload queue cleared Sep 02 11:58:14 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 02 11:58:14 volumio-henning volumio[816]: info: Preload queue cleared Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d6052284490133591829-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-3604348221405403609-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-3015095160803822746-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d5067502357144099322-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-5475681011292129958-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-8773310524255808673-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-4792950620244450323-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-8941041080195617322-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-2217683246500514234-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d4792494809827572223-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d7025509058884720865-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d8021166678665927096-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d3095388174321119103-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d5673906911928136051-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-6479683859258216994-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d6249916659261199657-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-374355531962840293-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d4475445932334381907-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-1388515200291395208-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-8256021735499288836-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d8570775416841475879-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d6540476664869660712-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d7277887947147434060-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d5723959702770111198-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d7605154348904151908-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d1565337178500428094-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d4125581837481086339-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-8635434614722559377-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-8730425108200120472-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-2349777205188706206-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-8276652851198143569-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d2751144935109393966-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d3058201660770075238-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d5486814158138223282-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-3024522602751101379-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d5086658527061822892-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d8979835695636668523-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d8681082700975353166-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d4708317541052139963-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d1034310772766103285-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d4523066622352886090-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-4205189757771337152-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-7651236163534975440-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d9097836358386304666-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d4377684999396459506-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-3869579948788499357-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d9026606147730704513-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-4958435519237092292-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d1404548606337617321-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-338976250536266993-co75CD5E833A29E30F Sep 02 11:58:14 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetState Sep 02 11:58:14 volumio-henning volumio[816]: info: Listing playlists Sep 02 11:58:14 volumio-henning volumio[816]: info: Listing playlists Sep 02 11:58:14 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d6052284490133591829-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:14 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-3604348221405403609-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:14 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-3015095160803822746-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:14 volumio-henning volumio[816]: info: No valid Plugin REST Endpoint Sep 02 11:58:14 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d5067502357144099322-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:14 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-5475681011292129958-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:14 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-8773310524255808673-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:14 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-4792950620244450323-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:14 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-8941041080195617322-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:14 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-2217683246500514234-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:14 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d4792494809827572223-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:14 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d7025509058884720865-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:14 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d8021166678665927096-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:15 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d3095388174321119103-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:15 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d5673906911928136051-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:15 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-6479683859258216994-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:15 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d6249916659261199657-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:15 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-374355531962840293-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:15 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d4475445932334381907-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:15 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-1388515200291395208-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:15 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-8256021735499288836-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:15 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d8570775416841475879-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:15 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d6540476664869660712-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:15 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d7277887947147434060-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:15 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d5723959702770111198-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:15 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d7605154348904151908-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:15 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d1565337178500428094-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:15 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d4125581837481086339-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:15 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-8635434614722559377-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:15 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-8730425108200120472-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:15 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-2349777205188706206-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:15 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-8276652851198143569-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:15 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d2751144935109393966-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:16 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d3058201660770075238-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:16 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d5486814158138223282-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:16 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d-3024522602751101379-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:16 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d5086658527061822892-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:16 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d8979835695636668523-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:16 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d8681082700975353166-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:16 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d4708317541052139963-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:16 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d1034310772766103285-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:16 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d4523066622352886090-co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:16 volumio-henning volumio[816]: info: Preload queue cleared Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::ClearQueue Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::stop Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::serviceStop Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreCommandRouter::serviceStop Sep 02 11:58:16 volumio-henning volumio[816]: info: Spotify Stop Sep 02 11:58:16 volumio-henning volumio[816]: SPOTIFY: SPOTIFY STOP Sep 02 11:58:16 volumio-henning volumio[816]: SPOTIFY: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":null,"repeat":null,"repeatSingle":false,"consume":false} Sep 02 11:58:16 volumio-henning volumio[816]: info: Sending Spotify command to local API: /player/pause Sep 02 11:58:16 volumio-henning volumio[816]: info: CorePlayQueue::clearPlayQueue Sep 02 11:58:16 volumio-henning volumio[816]: info: CorePlayQueue::saveQueue Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushQueue Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::addQueueItems Sep 02 11:58:16 volumio-henning volumio[816]: info: CorePlayQueue::addQueueItems Sep 02 11:58:16 volumio-henning volumio[816]: info: Preload queue cleared Sep 02 11:58:16 volumio-henning volumio[816]: info: Adding Item to queue: upnp/folder/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@co75CD5E833A29E30F Sep 02 11:58:16 volumio-henning volumio[816]: info: Exploding uri upnp/folder/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@co75CD5E833A29E30F in service upnp_browser Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushQueue Sep 02 11:58:16 volumio-henning volumio[816]: info: CorePlayQueue::saveQueue Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::updateTrackBlock Sep 02 11:58:16 volumio-henning volumio[816]: info: CorePlayQueue::getTrackBlock Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPlay Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::play index 0 Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::stop Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::updateTrackBlock Sep 02 11:58:16 volumio-henning volumio[816]: info: CorePlayQueue::getTrackBlock Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::stPlaybackTimer Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:16 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::serviceStop Sep 02 11:58:16 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreCommandRouter::serviceStop Sep 02 11:58:16 volumio-henning volumio[816]: info: [1725271096662] ControllerUPNPBrowser::stop Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand stop Sep 02 11:58:16 volumio-henning volumio[816]: info: sendMpdCommand stop took 11 milliseconds Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::play index undefined Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:58:16 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::startPlaybackTimer Sep 02 11:58:16 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:16 volumio-henning volumio[816]: info: [1725271096680] ControllerUPNPBrowser::clearAddPlayTrack Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand stop Sep 02 11:58:16 volumio-henning volumio[816]: info: sendMpdCommand stop took 8 milliseconds Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand clear Sep 02 11:58:16 volumio-henning volumio[816]: info: Sep 02 11:58:16 volumio-henning volumio[816]: ---------------------------- MPD announces system playlist update Sep 02 11:58:16 volumio-henning volumio[816]: info: Ignoring MPD Status Update Sep 02 11:58:16 volumio-henning volumio[816]: info: sendMpdCommand clear took 23 milliseconds Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.75.4:26125/content/c2/b16/f48000/d6052284490133591829-co75CD5E833A29E30F.mp3" Sep 02 11:58:16 volumio-henning volumio[816]: info: Sep 02 11:58:16 volumio-henning volumio[816]: ---------------------------- MPD announces system playlist update Sep 02 11:58:16 volumio-henning volumio[816]: info: Ignoring MPD Status Update Sep 02 11:58:16 volumio-henning volumio[816]: info: Sep 02 11:58:16 volumio-henning volumio[816]: ---------------------------- MPD announces system playlist update Sep 02 11:58:16 volumio-henning volumio[816]: info: Ignoring MPD Status Update Sep 02 11:58:16 volumio-henning volumio[816]: error: updateQueue error: null Sep 02 11:58:16 volumio-henning volumio[816]: info: ------------------------------ 16ms Sep 02 11:58:16 volumio-henning volumio[816]: error: Upnp client error: Error: This socket has been ended by the other party Sep 02 11:58:16 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:16 volumio-henning volumio[816]: error: updateQueue error: null Sep 02 11:58:16 volumio-henning volumio[816]: error: updateQueue error: null Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.75.4:26125/content/c2/b16/f48000/d6052284490133591829-co75CD5E833A29E30F.mp3" Sep 02 11:58:16 volumio-henning volumio[816]: info: ------------------------------ 46ms Sep 02 11:58:16 volumio-henning volumio[816]: info: ------------------------------ 44ms Sep 02 11:58:16 volumio-henning volumio[816]: info: Sep 02 11:58:16 volumio-henning volumio[816]: ---------------------------- MPD announces system playlist update Sep 02 11:58:16 volumio-henning volumio[816]: info: Ignoring MPD Status Update Sep 02 11:58:16 volumio-henning volumio[816]: info: sendMpdCommand add "http://192.168.75.4:26125/content/c2/b16/f48000/d6052284490133591829-co75CD5E833A29E30F.mp3" took 2 milliseconds Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand play Sep 02 11:58:16 volumio-henning volumio[816]: info: Sep 02 11:58:16 volumio-henning volumio[816]: ---------------------------- MPD announces system playlist update Sep 02 11:58:16 volumio-henning volumio[816]: info: Ignoring MPD Status Update Sep 02 11:58:16 volumio-henning volumio[816]: info: Sep 02 11:58:16 volumio-henning volumio[816]: ---------------------------- MPD announces system playlist update Sep 02 11:58:16 volumio-henning volumio[816]: info: Ignoring MPD Status Update Sep 02 11:58:16 volumio-henning volumio[816]: info: ------------------------------ 10ms Sep 02 11:58:16 volumio-henning volumio[816]: info: sendMpdCommand play took 7 milliseconds Sep 02 11:58:16 volumio-henning volumio[816]: info: ------------------------------ 6ms Sep 02 11:58:16 volumio-henning volumio[816]: info: ------------------------------ 5ms Sep 02 11:58:16 volumio-henning volumio[816]: info: Sep 02 11:58:16 volumio-henning volumio[816]: ---------------------------- MPD announces state update: player Sep 02 11:58:16 volumio-henning volumio[816]: info: ControllerMpd::getState Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:58:16 volumio-henning volumio[816]: info: Sep 02 11:58:16 volumio-henning volumio[816]: ---------------------------- MPD announces state update: player Sep 02 11:58:16 volumio-henning kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Sep 02 11:58:16 volumio-henning volumio[816]: info: ControllerMpd::getState Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:58:16 volumio-henning volumio[816]: info: Sep 02 11:58:16 volumio-henning volumio[816]: ---------------------------- MPD announces state update: player Sep 02 11:58:16 volumio-henning volumio[816]: info: ControllerMpd::getState Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:58:16 volumio-henning volumio[816]: info: Sep 02 11:58:16 volumio-henning volumio[816]: ---------------------------- MPD announces state update: player Sep 02 11:58:16 volumio-henning volumio[816]: info: sendMpdCommand status took 19 milliseconds Sep 02 11:58:16 volumio-henning volumio[816]: info: ControllerMpd::getState Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:58:16 volumio-henning volumio[816]: info: sendMpdCommand status took 16 milliseconds Sep 02 11:58:16 volumio-henning volumio[816]: info: sendMpdCommand status took 13 milliseconds Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::parseState Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::parseState Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::parseState Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:58:16 volumio-henning volumio[816]: info: Sep 02 11:58:16 volumio-henning volumio[816]: ---------------------------- MPD announces state update: player Sep 02 11:58:16 volumio-henning volumio[816]: info: ControllerMpd::getState Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:58:16 volumio-henning volumio[816]: info: Sep 02 11:58:16 volumio-henning volumio[816]: ---------------------------- MPD announces state update: player Sep 02 11:58:16 volumio-henning volumio[816]: info: ControllerMpd::getState Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:58:16 volumio-henning volumio[816]: info: Sep 02 11:58:16 volumio-henning volumio[816]: ---------------------------- MPD announces system playlist update Sep 02 11:58:16 volumio-henning volumio[816]: info: Ignoring MPD Status Update Sep 02 11:58:16 volumio-henning volumio[816]: info: Sep 02 11:58:16 volumio-henning volumio[816]: ---------------------------- MPD announces state update: player Sep 02 11:58:16 volumio-henning volumio[816]: info: ControllerMpd::getState Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:58:16 volumio-henning volumio[816]: info: sendMpdCommand status took 34 milliseconds Sep 02 11:58:16 volumio-henning volumio[816]: info: sendMpdCommand playlistinfo took 30 milliseconds Sep 02 11:58:16 volumio-henning volumio[816]: info: sendMpdCommand playlistinfo took 31 milliseconds Sep 02 11:58:16 volumio-henning volumio[816]: info: sendMpdCommand playlistinfo took 31 milliseconds Sep 02 11:58:16 volumio-henning volumio[816]: info: sendMpdCommand status took 29 milliseconds Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::parseState Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::parseState Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:58:16 volumio-henning volumio[816]: info: ControllerMpd::pushState Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreCommandRouter::servicePushState Sep 02 11:58:16 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:16 volumio-henning volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":181,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bounce Generation","artist":"TJR ft. VINAI","album":"Beatport Top 50 Downloads (May 2014)","uri":"http://192.168.75.4:26125/content/c2/b16/f48000/d6052284490133591829-co75CD5E833A29E30F.mp3","trackType":"mp3"} Sep 02 11:58:16 volumio-henning volumio[816]: verbose: CURRENT POSITION 0 Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::syncState stateService play Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::syncState currentStatus stop Sep 02 11:58:16 volumio-henning volumio[816]: info: ControllerMpd::pushState Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreCommandRouter::servicePushState Sep 02 11:58:16 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:16 volumio-henning volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":181,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bounce Generation","artist":"TJR ft. VINAI","album":"Beatport Top 50 Downloads (May 2014)","uri":"http://192.168.75.4:26125/content/c2/b16/f48000/d6052284490133591829-co75CD5E833A29E30F.mp3","trackType":"mp3"} Sep 02 11:58:16 volumio-henning volumio[816]: verbose: CURRENT POSITION 0 Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::syncState stateService play Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:58:16 volumio-henning volumio[816]: info: Received an update from plugin. extracting info from payload Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:16 volumio-henning volumio[816]: info: ControllerMpd::pushState Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreCommandRouter::servicePushState Sep 02 11:58:16 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:16 volumio-henning volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":181,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bounce Generation","artist":"TJR ft. VINAI","album":"Beatport Top 50 Downloads (May 2014)","uri":"http://192.168.75.4:26125/content/c2/b16/f48000/d6052284490133591829-co75CD5E833A29E30F.mp3","trackType":"mp3"} Sep 02 11:58:16 volumio-henning volumio[816]: verbose: CURRENT POSITION 0 Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::syncState stateService play Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:58:16 volumio-henning volumio[816]: info: Received an update from plugin. extracting info from payload Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:16 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:16 volumio-henning volumio[816]: info: ------------------------------ 125ms Sep 02 11:58:16 volumio-henning volumio[816]: info: ------------------------------ 134ms Sep 02 11:58:16 volumio-henning volumio[816]: info: ------------------------------ 131ms Sep 02 11:58:16 volumio-henning volumio[816]: info: Sep 02 11:58:16 volumio-henning volumio[816]: ---------------------------- MPD announces system playlist update Sep 02 11:58:16 volumio-henning volumio[816]: info: Ignoring MPD Status Update Sep 02 11:58:16 volumio-henning volumio[816]: info: Sep 02 11:58:16 volumio-henning volumio[816]: ---------------------------- MPD announces state update: player Sep 02 11:58:16 volumio-henning volumio[816]: info: ControllerMpd::getState Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:58:16 volumio-henning volumio[816]: info: Sep 02 11:58:16 volumio-henning volumio[816]: ---------------------------- MPD announces system playlist update Sep 02 11:58:16 volumio-henning volumio[816]: info: Ignoring MPD Status Update Sep 02 11:58:16 volumio-henning volumio[816]: info: Sep 02 11:58:16 volumio-henning volumio[816]: ---------------------------- MPD announces state update: player Sep 02 11:58:16 volumio-henning volumio[816]: info: ControllerMpd::getState Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:58:16 volumio-henning volumio[816]: info: sendMpdCommand status took 119 milliseconds Sep 02 11:58:16 volumio-henning volumio[816]: info: ------------------------------ 106ms Sep 02 11:58:16 volumio-henning volumio[816]: info: sendMpdCommand status took 101 milliseconds Sep 02 11:58:16 volumio-henning volumio[816]: info: sendMpdCommand playlistinfo took 92 milliseconds Sep 02 11:58:16 volumio-henning volumio[816]: info: sendMpdCommand playlistinfo took 85 milliseconds Sep 02 11:58:16 volumio-henning volumio[816]: info: ------------------------------ 20ms Sep 02 11:58:16 volumio-henning volumio[816]: info: sendMpdCommand status took 15 milliseconds Sep 02 11:58:16 volumio-henning volumio[816]: info: ------------------------------ 15ms Sep 02 11:58:16 volumio-henning volumio[816]: info: sendMpdCommand status took 11 milliseconds Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::parseState Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:58:16 volumio-henning volumio[816]: verbose: ControllerMpd::parseState Sep 02 11:58:17 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:58:17 volumio-henning volumio[816]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:58:17 volumio-henning volumio[816]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:58:17 volumio-henning volumio[816]: verbose: ControllerMpd::parseState Sep 02 11:58:17 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:58:17 volumio-henning volumio[816]: verbose: ControllerMpd::parseState Sep 02 11:58:17 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:58:17 volumio-henning volumio[816]: info: ControllerMpd::pushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::servicePushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:17 volumio-henning volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":181,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Bounce Generation","artist":"TJR ft. VINAI","album":"Beatport Top 50 Downloads (May 2014)","uri":"http://192.168.75.4:26125/content/c2/b16/f48000/d6052284490133591829-co75CD5E833A29E30F.mp3","trackType":"mp3"} Sep 02 11:58:17 volumio-henning volumio[816]: verbose: CURRENT POSITION 0 Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::syncState stateService play Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:58:17 volumio-henning volumio[816]: info: Received an update from plugin. extracting info from payload Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:17 volumio-henning volumio[816]: info: ControllerMpd::pushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::servicePushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:17 volumio-henning volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":181,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Bounce Generation","artist":"TJR ft. VINAI","album":"Beatport Top 50 Downloads (May 2014)","uri":"http://192.168.75.4:26125/content/c2/b16/f48000/d6052284490133591829-co75CD5E833A29E30F.mp3","trackType":"mp3"} Sep 02 11:58:17 volumio-henning volumio[816]: verbose: CURRENT POSITION 0 Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::syncState stateService play Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:58:17 volumio-henning volumio[816]: info: Received an update from plugin. extracting info from payload Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:17 volumio-henning volumio[816]: info: ------------------------------ 184ms Sep 02 11:58:17 volumio-henning volumio[816]: info: ------------------------------ 173ms Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:17 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:17 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:17 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:17 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:17 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:17 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:17 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:17 volumio-henning volumio[816]: info: sendMpdCommand playlistinfo took 98 milliseconds Sep 02 11:58:17 volumio-henning volumio[816]: info: sendMpdCommand playlistinfo took 97 milliseconds Sep 02 11:58:17 volumio-henning volumio[816]: info: sendMpdCommand playlistinfo took 93 milliseconds Sep 02 11:58:17 volumio-henning volumio[816]: info: sendMpdCommand playlistinfo took 92 milliseconds Sep 02 11:58:17 volumio-henning volumio[816]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:58:17 volumio-henning volumio[816]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:58:17 volumio-henning volumio[816]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:58:17 volumio-henning volumio[816]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:58:17 volumio-henning volumio[816]: info: ControllerMpd::pushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::servicePushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:17 volumio-henning volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":181,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Bounce Generation","artist":"TJR ft. VINAI","album":"Beatport Top 50 Downloads (May 2014)","uri":"http://192.168.75.4:26125/content/c2/b16/f48000/d6052284490133591829-co75CD5E833A29E30F.mp3","trackType":"mp3"} Sep 02 11:58:17 volumio-henning volumio[816]: verbose: CURRENT POSITION 0 Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::syncState stateService play Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:58:17 volumio-henning volumio[816]: info: Received an update from plugin. extracting info from payload Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:17 volumio-henning volumio[816]: info: ControllerMpd::pushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::servicePushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:17 volumio-henning volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":181,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Bounce Generation","artist":"TJR ft. VINAI","album":"Beatport Top 50 Downloads (May 2014)","uri":"http://192.168.75.4:26125/content/c2/b16/f48000/d6052284490133591829-co75CD5E833A29E30F.mp3","trackType":"mp3"} Sep 02 11:58:17 volumio-henning volumio[816]: verbose: CURRENT POSITION 0 Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::syncState stateService play Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:58:17 volumio-henning volumio[816]: info: Received an update from plugin. extracting info from payload Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:17 volumio-henning volumio[816]: info: ControllerMpd::pushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::servicePushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:17 volumio-henning volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":181,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Bounce Generation","artist":"TJR ft. VINAI","album":"Beatport Top 50 Downloads (May 2014)","uri":"http://192.168.75.4:26125/content/c2/b16/f48000/d6052284490133591829-co75CD5E833A29E30F.mp3","trackType":"mp3"} Sep 02 11:58:17 volumio-henning volumio[816]: verbose: CURRENT POSITION 0 Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::syncState stateService play Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:58:17 volumio-henning volumio[816]: info: Received an update from plugin. extracting info from payload Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:17 volumio-henning volumio[816]: info: ControllerMpd::pushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::servicePushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:17 volumio-henning volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1112,"duration":181,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Bounce Generation","artist":"TJR ft. VINAI","album":"Beatport Top 50 Downloads (May 2014)","uri":"http://192.168.75.4:26125/content/c2/b16/f48000/d6052284490133591829-co75CD5E833A29E30F.mp3","trackType":"mp3"} Sep 02 11:58:17 volumio-henning volumio[816]: verbose: CURRENT POSITION 0 Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::syncState stateService play Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:58:17 volumio-henning volumio[816]: info: Received an update from plugin. extracting info from payload Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:17 volumio-henning volumio[816]: info: ------------------------------ 269ms Sep 02 11:58:17 volumio-henning volumio[816]: info: ------------------------------ 253ms Sep 02 11:58:17 volumio-henning volumio[816]: info: ------------------------------ 164ms Sep 02 11:58:17 volumio-henning volumio[816]: info: ------------------------------ 159ms Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:17 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:17 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:17 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:17 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:17 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:17 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:17 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:17 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:20 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Of%20Monsters%20And%20Men/My%20Head%20Is%20An%20Animal/48b81061-b9af-4b97-bd2c-479d0a13144b.png' Sep 02 11:58:20 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Of%20Monsters%20And%20Men/My%20Head%20Is%20An%20Animal/48b81061-b9af-4b97-bd2c-479d0a13144b.png' Sep 02 11:58:23 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 02 11:58:23 volumio-henning volumio[816]: info: Preload queue cleared Sep 02 11:58:24 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetState Sep 02 11:58:24 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 02 11:58:25 volumio-henning volumio[816]: info: Preload queue cleared Sep 02 11:58:27 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 02 11:58:27 volumio-henning volumio[816]: info: Preload queue cleared Sep 02 11:58:32 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 02 11:58:32 volumio-henning volumio[816]: info: Preload queue cleared Sep 02 11:58:34 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetState Sep 02 11:58:34 volumio-henning volumio[816]: info: Listing playlists Sep 02 11:58:34 volumio-henning volumio[816]: info: Listing playlists Sep 02 11:58:43 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 02 11:58:43 volumio-henning volumio[816]: info: Preload queue cleared Sep 02 11:58:43 volumio-henning volumio[816]: info: Preloading song: upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d2075453177355434167-co9715C316A46A4E37 Sep 02 11:58:43 volumio-henning volumio[816]: info: Exploding uri upnp/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@d2075453177355434167-co9715C316A46A4E37 in service upnp_browser Sep 02 11:58:43 volumio-henning volumio[816]: info: No valid Plugin REST Endpoint Sep 02 11:58:44 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetState Sep 02 11:58:45 volumio-henning volumio[816]: info: Preload queue cleared Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::ClearQueue Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::stop Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::stPlaybackTimer Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::updateTrackBlock Sep 02 11:58:45 volumio-henning volumio[816]: info: CorePlayQueue::getTrackBlock Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::serviceStop Sep 02 11:58:45 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::serviceStop Sep 02 11:58:45 volumio-henning volumio[816]: info: [1725271125670] ControllerUPNPBrowser::stop Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand stop Sep 02 11:58:45 volumio-henning volumio[816]: info: CorePlayQueue::clearPlayQueue Sep 02 11:58:45 volumio-henning volumio[816]: info: CorePlayQueue::saveQueue Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushQueue Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::addQueueItems Sep 02 11:58:45 volumio-henning volumio[816]: info: CorePlayQueue::addQueueItems Sep 02 11:58:45 volumio-henning volumio[816]: info: Preload queue cleared Sep 02 11:58:45 volumio-henning volumio[816]: info: Adding Item to queue: upnp/folder/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@co9715C316A46A4E37 Sep 02 11:58:45 volumio-henning volumio[816]: info: Exploding uri upnp/folder/http://192.168.75.4:26125/ContentDirectory/3485E607-CDCE-518C-0-123456789abc/control.xml@co9715C316A46A4E37 in service upnp_browser Sep 02 11:58:45 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushQueue Sep 02 11:58:45 volumio-henning volumio[816]: info: CorePlayQueue::saveQueue Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::updateTrackBlock Sep 02 11:58:45 volumio-henning volumio[816]: info: CorePlayQueue::getTrackBlock Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPlay Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::play index 0 Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::stop Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::play index undefined Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:58:45 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::startPlaybackTimer Sep 02 11:58:45 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:45 volumio-henning volumio[816]: info: [1725271125698] ControllerUPNPBrowser::clearAddPlayTrack Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand stop Sep 02 11:58:45 volumio-henning volumio[816]: info: Sep 02 11:58:45 volumio-henning volumio[816]: ---------------------------- MPD announces state update: player Sep 02 11:58:45 volumio-henning volumio[816]: info: sendMpdCommand stop took 23 milliseconds Sep 02 11:58:45 volumio-henning volumio[816]: info: ControllerMpd::getState Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:58:45 volumio-henning volumio[816]: info: sendMpdCommand stop took 2 milliseconds Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand clear Sep 02 11:58:45 volumio-henning volumio[816]: info: Sep 02 11:58:45 volumio-henning volumio[816]: ---------------------------- MPD announces state update: player Sep 02 11:58:45 volumio-henning volumio[816]: info: ControllerMpd::getState Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:58:45 volumio-henning volumio[816]: info: Sep 02 11:58:45 volumio-henning volumio[816]: ---------------------------- MPD announces state update: player Sep 02 11:58:45 volumio-henning volumio[816]: info: ControllerMpd::getState Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:58:45 volumio-henning volumio[816]: info: Sep 02 11:58:45 volumio-henning volumio[816]: ---------------------------- MPD announces system playlist update Sep 02 11:58:45 volumio-henning volumio[816]: info: Ignoring MPD Status Update Sep 02 11:58:45 volumio-henning volumio[816]: info: sendMpdCommand status took 6 milliseconds Sep 02 11:58:45 volumio-henning volumio[816]: info: sendMpdCommand clear took 5 milliseconds Sep 02 11:58:45 volumio-henning volumio[816]: info: sendMpdCommand status took 5 milliseconds Sep 02 11:58:45 volumio-henning volumio[816]: info: sendMpdCommand status took 4 milliseconds Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::parseState Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.75.4:26125/content/c2/b16/f44100/d2075453177355434167-co9715C316A46A4E37.mp3" Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::parseState Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::parseState Sep 02 11:58:45 volumio-henning volumio[816]: info: ControllerMpd::pushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::servicePushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:45 volumio-henning volumio[816]: 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} Sep 02 11:58:45 volumio-henning volumio[816]: verbose: CURRENT POSITION 0 Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::syncState stateService stop Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::syncState currentStatus stop Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:45 volumio-henning volumio[816]: info: No code Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:45 volumio-henning volumio[816]: info: ControllerMpd::pushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::servicePushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:45 volumio-henning volumio[816]: 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} Sep 02 11:58:45 volumio-henning volumio[816]: verbose: CURRENT POSITION 0 Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::syncState stateService stop Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::syncState currentStatus stop Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:45 volumio-henning volumio[816]: info: No code Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:45 volumio-henning volumio[816]: info: ------------------------------ 33ms Sep 02 11:58:45 volumio-henning volumio[816]: info: ------------------------------ 32ms Sep 02 11:58:45 volumio-henning volumio[816]: info: Sep 02 11:58:45 volumio-henning volumio[816]: ---------------------------- MPD announces system playlist update Sep 02 11:58:45 volumio-henning volumio[816]: info: Ignoring MPD Status Update Sep 02 11:58:45 volumio-henning volumio[816]: info: Sep 02 11:58:45 volumio-henning volumio[816]: ---------------------------- MPD announces system playlist update Sep 02 11:58:45 volumio-henning volumio[816]: info: Ignoring MPD Status Update Sep 02 11:58:45 volumio-henning volumio[816]: error: updateQueue error: null Sep 02 11:58:45 volumio-henning volumio[816]: info: ------------------------------ 34ms Sep 02 11:58:45 volumio-henning volumio[816]: info: sendMpdCommand playlistinfo took 32 milliseconds Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:58:45 volumio-henning volumio[816]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 02 11:58:45 volumio-henning volumio[816]: info: ------------------------------ 42ms Sep 02 11:58:45 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:45 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:45 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:45 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:45 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:45 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.75.4:26125/content/c2/b16/f44100/d2075453177355434167-co9715C316A46A4E37.mp3" Sep 02 11:58:45 volumio-henning volumio[816]: error: updateQueue error: null Sep 02 11:58:45 volumio-henning volumio[816]: error: updateQueue error: null Sep 02 11:58:45 volumio-henning volumio[816]: info: Sep 02 11:58:45 volumio-henning volumio[816]: ---------------------------- MPD announces system playlist update Sep 02 11:58:45 volumio-henning volumio[816]: info: Ignoring MPD Status Update Sep 02 11:58:45 volumio-henning volumio[816]: info: ------------------------------ 104ms Sep 02 11:58:45 volumio-henning volumio[816]: info: ------------------------------ 104ms Sep 02 11:58:45 volumio-henning volumio[816]: info: sendMpdCommand add "http://192.168.75.4:26125/content/c2/b16/f44100/d2075453177355434167-co9715C316A46A4E37.mp3" took 2 milliseconds Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand play Sep 02 11:58:45 volumio-henning volumio[816]: info: Sep 02 11:58:45 volumio-henning volumio[816]: ---------------------------- MPD announces system playlist update Sep 02 11:58:45 volumio-henning volumio[816]: info: Ignoring MPD Status Update Sep 02 11:58:45 volumio-henning volumio[816]: info: Sep 02 11:58:45 volumio-henning volumio[816]: ---------------------------- MPD announces system playlist update Sep 02 11:58:45 volumio-henning volumio[816]: info: Ignoring MPD Status Update Sep 02 11:58:45 volumio-henning volumio[816]: info: ------------------------------ 6ms Sep 02 11:58:45 volumio-henning volumio[816]: info: sendMpdCommand play took 5 milliseconds Sep 02 11:58:45 volumio-henning volumio[816]: info: ------------------------------ 5ms Sep 02 11:58:45 volumio-henning volumio[816]: info: ------------------------------ 3ms Sep 02 11:58:45 volumio-henning volumio[816]: info: Sep 02 11:58:45 volumio-henning volumio[816]: ---------------------------- MPD announces state update: player Sep 02 11:58:45 volumio-henning volumio[816]: info: ControllerMpd::getState Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:58:45 volumio-henning kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Sep 02 11:58:45 volumio-henning volumio[816]: info: Sep 02 11:58:45 volumio-henning volumio[816]: ---------------------------- MPD announces state update: player Sep 02 11:58:45 volumio-henning volumio[816]: info: ControllerMpd::getState Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:58:45 volumio-henning volumio[816]: info: Sep 02 11:58:45 volumio-henning volumio[816]: ---------------------------- MPD announces state update: player Sep 02 11:58:45 volumio-henning volumio[816]: info: ControllerMpd::getState Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:58:45 volumio-henning volumio[816]: info: Sep 02 11:58:45 volumio-henning volumio[816]: ---------------------------- MPD announces state update: player Sep 02 11:58:45 volumio-henning volumio[816]: info: sendMpdCommand status took 18 milliseconds Sep 02 11:58:45 volumio-henning volumio[816]: info: ControllerMpd::getState Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:58:45 volumio-henning volumio[816]: info: sendMpdCommand status took 10 milliseconds Sep 02 11:58:45 volumio-henning volumio[816]: info: sendMpdCommand status took 7 milliseconds Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::parseState Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::parseState Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::parseState Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:58:45 volumio-henning volumio[816]: info: Sep 02 11:58:45 volumio-henning volumio[816]: ---------------------------- MPD announces state update: player Sep 02 11:58:45 volumio-henning volumio[816]: info: ControllerMpd::getState Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:58:45 volumio-henning volumio[816]: info: Sep 02 11:58:45 volumio-henning volumio[816]: ---------------------------- MPD announces state update: player Sep 02 11:58:45 volumio-henning volumio[816]: info: ControllerMpd::getState Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:58:45 volumio-henning volumio[816]: info: Sep 02 11:58:45 volumio-henning volumio[816]: ---------------------------- MPD announces system playlist update Sep 02 11:58:45 volumio-henning volumio[816]: info: Ignoring MPD Status Update Sep 02 11:58:45 volumio-henning volumio[816]: info: Sep 02 11:58:45 volumio-henning volumio[816]: ---------------------------- MPD announces state update: player Sep 02 11:58:45 volumio-henning volumio[816]: info: ControllerMpd::getState Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:58:45 volumio-henning volumio[816]: info: sendMpdCommand status took 27 milliseconds Sep 02 11:58:45 volumio-henning volumio[816]: info: sendMpdCommand playlistinfo took 24 milliseconds Sep 02 11:58:45 volumio-henning volumio[816]: info: sendMpdCommand playlistinfo took 21 milliseconds Sep 02 11:58:45 volumio-henning volumio[816]: info: sendMpdCommand playlistinfo took 21 milliseconds Sep 02 11:58:45 volumio-henning volumio[816]: info: sendMpdCommand status took 16 milliseconds Sep 02 11:58:45 volumio-henning volumio[816]: info: sendMpdCommand status took 13 milliseconds Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::parseState Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::parseState Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::parseState Sep 02 11:58:45 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:58:45 volumio-henning volumio[816]: info: ControllerMpd::pushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::servicePushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:45 volumio-henning volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"In Your Arms","artist":"Stanfour","album":"In Your Arms","uri":"http://192.168.75.4:26125/content/c2/b16/f44100/d2075453177355434167-co9715C316A46A4E37.mp3","trackType":"mp3"} Sep 02 11:58:45 volumio-henning volumio[816]: verbose: CURRENT POSITION 0 Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::syncState stateService play Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::syncState currentStatus stop Sep 02 11:58:45 volumio-henning volumio[816]: info: ControllerMpd::pushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::servicePushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:45 volumio-henning volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"In Your Arms","artist":"Stanfour","album":"In Your Arms","uri":"http://192.168.75.4:26125/content/c2/b16/f44100/d2075453177355434167-co9715C316A46A4E37.mp3","trackType":"mp3"} Sep 02 11:58:45 volumio-henning volumio[816]: verbose: CURRENT POSITION 0 Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::syncState stateService play Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:58:45 volumio-henning volumio[816]: info: Received an update from plugin. extracting info from payload Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:45 volumio-henning volumio[816]: info: ControllerMpd::pushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::servicePushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:45 volumio-henning volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"In Your Arms","artist":"Stanfour","album":"In Your Arms","uri":"http://192.168.75.4:26125/content/c2/b16/f44100/d2075453177355434167-co9715C316A46A4E37.mp3","trackType":"mp3"} Sep 02 11:58:45 volumio-henning volumio[816]: verbose: CURRENT POSITION 0 Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::syncState stateService play Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:58:45 volumio-henning volumio[816]: info: Received an update from plugin. extracting info from payload Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:45 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:45 volumio-henning volumio[816]: info: ------------------------------ 100ms Sep 02 11:58:46 volumio-henning volumio[816]: info: ------------------------------ 121ms Sep 02 11:58:46 volumio-henning volumio[816]: info: ------------------------------ 119ms Sep 02 11:58:46 volumio-henning volumio[816]: info: Sep 02 11:58:46 volumio-henning volumio[816]: ---------------------------- MPD announces system playlist update Sep 02 11:58:46 volumio-henning volumio[816]: info: Ignoring MPD Status Update Sep 02 11:58:46 volumio-henning volumio[816]: info: Sep 02 11:58:46 volumio-henning volumio[816]: ---------------------------- MPD announces state update: player Sep 02 11:58:46 volumio-henning volumio[816]: info: ControllerMpd::getState Sep 02 11:58:46 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:58:46 volumio-henning volumio[816]: info: Sep 02 11:58:46 volumio-henning volumio[816]: ---------------------------- MPD announces system playlist update Sep 02 11:58:46 volumio-henning volumio[816]: info: Ignoring MPD Status Update Sep 02 11:58:46 volumio-henning volumio[816]: info: Sep 02 11:58:46 volumio-henning volumio[816]: ---------------------------- MPD announces state update: player Sep 02 11:58:46 volumio-henning volumio[816]: info: ControllerMpd::getState Sep 02 11:58:46 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:58:46 volumio-henning volumio[816]: info: ------------------------------ 99ms Sep 02 11:58:46 volumio-henning volumio[816]: info: sendMpdCommand status took 95 milliseconds Sep 02 11:58:46 volumio-henning volumio[816]: info: sendMpdCommand playlistinfo took 86 milliseconds Sep 02 11:58:46 volumio-henning volumio[816]: info: sendMpdCommand playlistinfo took 82 milliseconds Sep 02 11:58:46 volumio-henning volumio[816]: info: sendMpdCommand playlistinfo took 81 milliseconds Sep 02 11:58:46 volumio-henning volumio[816]: info: ------------------------------ 10ms Sep 02 11:58:46 volumio-henning volumio[816]: info: sendMpdCommand status took 6 milliseconds Sep 02 11:58:46 volumio-henning volumio[816]: info: ------------------------------ 6ms Sep 02 11:58:46 volumio-henning volumio[816]: info: sendMpdCommand status took 4 milliseconds Sep 02 11:58:46 volumio-henning volumio[816]: verbose: ControllerMpd::parseState Sep 02 11:58:46 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:58:46 volumio-henning volumio[816]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:58:46 volumio-henning volumio[816]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:58:46 volumio-henning volumio[816]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:58:46 volumio-henning volumio[816]: verbose: ControllerMpd::parseState Sep 02 11:58:46 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:58:46 volumio-henning volumio[816]: verbose: ControllerMpd::parseState Sep 02 11:58:46 volumio-henning volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:58:46 volumio-henning volumio[816]: info: ControllerMpd::pushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::servicePushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:46 volumio-henning volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"In Your Arms","artist":"Stanfour","album":"In Your Arms","uri":"http://192.168.75.4:26125/content/c2/b16/f44100/d2075453177355434167-co9715C316A46A4E37.mp3","trackType":"mp3"} Sep 02 11:58:46 volumio-henning volumio[816]: verbose: CURRENT POSITION 0 Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::syncState stateService play Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:58:46 volumio-henning volumio[816]: info: Received an update from plugin. extracting info from payload Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:46 volumio-henning volumio[816]: info: ControllerMpd::pushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::servicePushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:46 volumio-henning volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"In Your Arms","artist":"Stanfour","album":"In Your Arms","uri":"http://192.168.75.4:26125/content/c2/b16/f44100/d2075453177355434167-co9715C316A46A4E37.mp3","trackType":"mp3"} Sep 02 11:58:46 volumio-henning volumio[816]: verbose: CURRENT POSITION 0 Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::syncState stateService play Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:58:46 volumio-henning volumio[816]: info: Received an update from plugin. extracting info from payload Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:46 volumio-henning volumio[816]: info: ControllerMpd::pushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::servicePushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:46 volumio-henning volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"In Your Arms","artist":"Stanfour","album":"In Your Arms","uri":"http://192.168.75.4:26125/content/c2/b16/f44100/d2075453177355434167-co9715C316A46A4E37.mp3","trackType":"mp3"} Sep 02 11:58:46 volumio-henning volumio[816]: verbose: CURRENT POSITION 0 Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::syncState stateService play Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:58:46 volumio-henning volumio[816]: info: Received an update from plugin. extracting info from payload Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:46 volumio-henning volumio[816]: info: ------------------------------ 158ms Sep 02 11:58:46 volumio-henning volumio[816]: info: ------------------------------ 143ms Sep 02 11:58:46 volumio-henning volumio[816]: info: ------------------------------ 139ms Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:46 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:46 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:46 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:46 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:46 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:46 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:46 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:46 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:46 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:46 volumio-henning volumio[816]: info: sendMpdCommand playlistinfo took 73 milliseconds Sep 02 11:58:46 volumio-henning volumio[816]: info: sendMpdCommand playlistinfo took 72 milliseconds Sep 02 11:58:46 volumio-henning volumio[816]: info: sendMpdCommand playlistinfo took 73 milliseconds Sep 02 11:58:46 volumio-henning volumio[816]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:58:46 volumio-henning volumio[816]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:58:46 volumio-henning volumio[816]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:58:46 volumio-henning volumio[816]: info: ControllerMpd::pushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::servicePushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:46 volumio-henning volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"In Your Arms","artist":"Stanfour","album":"In Your Arms","uri":"http://192.168.75.4:26125/content/c2/b16/f44100/d2075453177355434167-co9715C316A46A4E37.mp3","trackType":"mp3"} Sep 02 11:58:46 volumio-henning volumio[816]: verbose: CURRENT POSITION 0 Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::syncState stateService play Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:58:46 volumio-henning volumio[816]: info: Received an update from plugin. extracting info from payload Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:46 volumio-henning volumio[816]: info: ControllerMpd::pushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::servicePushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:46 volumio-henning volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"In Your Arms","artist":"Stanfour","album":"In Your Arms","uri":"http://192.168.75.4:26125/content/c2/b16/f44100/d2075453177355434167-co9715C316A46A4E37.mp3","trackType":"mp3"} Sep 02 11:58:46 volumio-henning volumio[816]: verbose: CURRENT POSITION 0 Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::syncState stateService play Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:58:46 volumio-henning volumio[816]: info: Received an update from plugin. extracting info from payload Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:46 volumio-henning volumio[816]: info: ControllerMpd::pushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::servicePushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CorePlayQueue::getTrack 0 Sep 02 11:58:46 volumio-henning volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"In Your Arms","artist":"Stanfour","album":"In Your Arms","uri":"http://192.168.75.4:26125/content/c2/b16/f44100/d2075453177355434167-co9715C316A46A4E37.mp3","trackType":"mp3"} Sep 02 11:58:46 volumio-henning volumio[816]: verbose: CURRENT POSITION 0 Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::syncState stateService play Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:58:46 volumio-henning volumio[816]: info: Received an update from plugin. extracting info from payload Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::pushState Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioPushState Sep 02 11:58:46 volumio-henning volumio[816]: info: ------------------------------ 200ms Sep 02 11:58:46 volumio-henning volumio[816]: info: ------------------------------ 110ms Sep 02 11:58:46 volumio-henning volumio[816]: info: ------------------------------ 106ms Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CoreStateMachine::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: info: CorePlayQueue::getQueue Sep 02 11:58:46 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:46 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:46 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:46 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:46 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:46 volumio-henning volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 11:58:48 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 02 11:58:48 volumio-henning volumio[816]: info: Preload queue cleared Sep 02 11:58:50 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 02 11:58:50 volumio-henning volumio[816]: info: Preload queue cleared Sep 02 11:58:54 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetState Sep 02 11:58:54 volumio-henning volumio[816]: info: Listing playlists Sep 02 11:58:54 volumio-henning volumio[816]: info: Listing playlists Sep 02 11:58:56 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 02 11:58:56 volumio-henning volumio[816]: info: Preload queue cleared Sep 02 11:58:56 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:56 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:56 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:56 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:56 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:56 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:57 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:58 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:58 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:58 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:58 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:58 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:58 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:58 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:58 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:58 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:58 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:58 volumio-henning volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 02 11:58:59 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 02 11:59:02 volumio-henning volumio[816]: info: Preload queue cleared Sep 02 11:59:04 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetState Sep 02 11:59:07 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 02 11:59:07 volumio-henning volumio[816]: info: Preload queue cleared Sep 02 11:59:08 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 02 11:59:09 volumio-henning volumio[816]: info: Preload queue cleared Sep 02 11:59:11 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 02 11:59:11 volumio-henning volumio[816]: info: Preload queue cleared Sep 02 11:59:14 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetState Sep 02 11:59:14 volumio-henning volumio[816]: info: Listing playlists Sep 02 11:59:14 volumio-henning volumio[816]: info: Listing playlists Sep 02 11:59:16 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 02 11:59:16 volumio-henning volumio[816]: info: Preload queue cleared Sep 02 11:59:23 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 02 11:59:23 volumio-henning volumio[816]: info: Preload queue cleared Sep 02 11:59:24 volumio-henning volumio[816]: info: CoreCommandRouter::volumioGetState Sep 02 11:59:29 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 02 11:59:29 volumio-henning volumio[816]: info: Preload queue cleared Sep 02 11:59:32 volumio-henning volumio[816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 02 11:59:33 volumio-henning volumio[816]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 11:59:33 volumio-henning volumio[816]: TypeError: Cannot read property 'length' of undefined Sep 02 11:59:33 volumio-henning volumio[816]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Sep 02 11:59:33 volumio-henning volumio[816]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Sep 02 11:59:33 volumio-henning volumio[816]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Sep 02 11:59:33 volumio-henning volumio[816]: at Parser.emit (events.js:315:20) Sep 02 11:59:33 volumio-henning volumio[816]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Sep 02 11:59:33 volumio-henning volumio[816]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Sep 02 11:59:33 volumio-henning volumio[816]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Sep 02 11:59:33 volumio-henning volumio[816]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Sep 02 11:59:33 volumio-henning volumio[816]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Sep 02 11:59:33 volumio-henning volumio[816]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Sep 02 11:59:33 volumio-henning volumio[816]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Sep 02 11:59:33 volumio-henning volumio[816]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Sep 02 11:59:33 volumio-henning volumio[816]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Sep 02 11:59:33 volumio-henning volumio[816]: at IncomingMessage.emit (events.js:327:22) Sep 02 11:59:33 volumio-henning volumio[816]: at endReadableNT (internal/streams/readable.js:1327:12) Sep 02 11:59:33 volumio-henning volumio[816]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 02 11:59:33 volumio-henning volumio[816]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 11:59:33 volumio-henning sudo[7130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-02 11:58 Sep 02 11:59:33 volumio-henning sudo[7130]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"