-- Logs begin at Tue 2025-05-13 19:37:24 CST, end at Tue 2025-05-13 20:01:54 CST. -- May 13 20:00:04 volumio volumio[7377]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 20:00:04 volumio volumio[7377]: info: Preload queue cleared May 13 20:00:12 volumio volumio[7377]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 20:00:12 volumio volumio[7377]: info: Preload queue cleared May 13 20:00:13 volumio volumio[7377]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 20:00:13 volumio volumio[7377]: info: Preload queue cleared May 13 20:00:15 volumio volumio[7377]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 20:00:15 volumio volumio[7377]: info: Preload queue cleared May 13 20:00:19 volumio volumio[7377]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 20:00:19 volumio volumio[7377]: info: Preload queue cleared May 13 20:00:20 volumio ntpd[971]: Soliciting pool server 185.209.85.222 May 13 20:00:21 volumio volumio[7377]: 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' May 13 20:00:22 volumio volumio[7377]: 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' May 13 20:00:22 volumio volumio[7377]: 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' May 13 20:00:24 volumio volumio[7377]: 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' May 13 20:00:24 volumio ntpd[971]: Soliciting pool server 193.182.111.14 May 13 20:00:24 volumio volumio[7377]: 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' May 13 20:00:25 volumio ntpd[971]: Soliciting pool server 116.203.151.74 May 13 20:00:26 volumio volumio[7377]: 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' May 13 20:00:26 volumio volumio[7377]: 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' May 13 20:00:27 volumio volumio[7377]: 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' May 13 20:00:27 volumio volumio[7377]: 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' May 13 20:00:28 volumio volumio[7377]: 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' May 13 20:00:28 volumio volumio[7377]: 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' May 13 20:00:29 volumio ntpd[971]: Soliciting pool server 111.230.189.174 May 13 20:00:29 volumio volumio[7377]: 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' May 13 20:00:29 volumio volumio[7377]: 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' May 13 20:00:30 volumio volumio[7377]: 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' May 13 20:00:31 volumio volumio[7377]: 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' May 13 20:00:31 volumio volumio[7377]: 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' May 13 20:00:32 volumio volumio[7377]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 20:00:32 volumio volumio[7377]: 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' May 13 20:00:33 volumio volumio[7377]: 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' May 13 20:00:33 volumio volumio[7377]: info: Preload queue cleared May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@0 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@1 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@2 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@3 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@4 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@5 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@6 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@7 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@8 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@9 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@10 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@11 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@12 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@13 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@14 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@15 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@16 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@17 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@18 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@19 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@20 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@21 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@22 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@23 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@24 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@25 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@26 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@27 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@28 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@29 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@30 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@31 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@32 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@33 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@34 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@35 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@36 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@37 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@38 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@39 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@40 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@41 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@42 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@43 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@44 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@45 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@46 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@47 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@48 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@49 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@50 May 13 20:00:33 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@51 May 13 20:00:33 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@0 in service upnp_browser May 13 20:00:33 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@1 in service upnp_browser May 13 20:00:33 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@2 in service upnp_browser May 13 20:00:33 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@3 in service upnp_browser May 13 20:00:33 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@4 in service upnp_browser May 13 20:00:33 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@5 in service upnp_browser May 13 20:00:33 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@6 in service upnp_browser May 13 20:00:33 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@7 in service upnp_browser May 13 20:00:33 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@8 in service upnp_browser May 13 20:00:33 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@9 in service upnp_browser May 13 20:00:33 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@10 in service upnp_browser May 13 20:00:33 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@11 in service upnp_browser May 13 20:00:33 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@12 in service upnp_browser May 13 20:00:33 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@13 in service upnp_browser May 13 20:00:34 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@14 in service upnp_browser May 13 20:00:34 volumio volumio[7377]: 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' May 13 20:00:34 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@15 in service upnp_browser May 13 20:00:34 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@16 in service upnp_browser May 13 20:00:34 volumio volumio[7377]: 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' May 13 20:00:34 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@17 in service upnp_browser May 13 20:00:34 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@18 in service upnp_browser May 13 20:00:34 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@19 in service upnp_browser May 13 20:00:34 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@20 in service upnp_browser May 13 20:00:34 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@21 in service upnp_browser May 13 20:00:34 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@22 in service upnp_browser May 13 20:00:34 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@23 in service upnp_browser May 13 20:00:34 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@24 in service upnp_browser May 13 20:00:34 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@25 in service upnp_browser May 13 20:00:34 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@26 in service upnp_browser May 13 20:00:34 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@27 in service upnp_browser May 13 20:00:34 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@28 in service upnp_browser May 13 20:00:34 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@29 in service upnp_browser May 13 20:00:34 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@30 in service upnp_browser May 13 20:00:34 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@31 in service upnp_browser May 13 20:00:34 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@32 in service upnp_browser May 13 20:00:34 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@33 in service upnp_browser May 13 20:00:35 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@34 in service upnp_browser May 13 20:00:35 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@35 in service upnp_browser May 13 20:00:35 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@36 in service upnp_browser May 13 20:00:35 volumio volumio[7377]: 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' May 13 20:00:35 volumio volumio[7377]: 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' May 13 20:00:35 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@37 in service upnp_browser May 13 20:00:35 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@38 in service upnp_browser May 13 20:00:35 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@39 in service upnp_browser May 13 20:00:35 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@40 in service upnp_browser May 13 20:00:35 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@41 in service upnp_browser May 13 20:00:35 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@42 in service upnp_browser May 13 20:00:35 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@43 in service upnp_browser May 13 20:00:35 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@44 in service upnp_browser May 13 20:00:35 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@45 in service upnp_browser May 13 20:00:35 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@46 in service upnp_browser May 13 20:00:35 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@47 in service upnp_browser May 13 20:00:35 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@48 in service upnp_browser May 13 20:00:35 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@49 in service upnp_browser May 13 20:00:35 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@50 in service upnp_browser May 13 20:00:35 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@32$0$@51 in service upnp_browser May 13 20:00:36 volumio volumio[7377]: 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' May 13 20:00:36 volumio volumio[7377]: 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' May 13 20:00:36 volumio volumio[7377]: info: Preload queue cleared May 13 20:00:36 volumio volumio[7377]: info: CoreCommandRouter::volumioReplaceandPlayItems May 13 20:00:36 volumio volumio[7377]: info: CoreStateMachine::ClearQueue May 13 20:00:36 volumio volumio[7377]: info: CoreStateMachine::stop May 13 20:00:36 volumio volumio[7377]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 20:00:36 volumio volumio[7377]: info: CorePlayQueue::clearPlayQueue May 13 20:00:36 volumio volumio[7377]: info: CorePlayQueue::saveQueue May 13 20:00:36 volumio volumio[7377]: info: CoreCommandRouter::volumioPushQueue May 13 20:00:36 volumio volumio[7377]: info: CoreStateMachine::addQueueItems May 13 20:00:36 volumio volumio[7377]: info: CorePlayQueue::addQueueItems May 13 20:00:36 volumio volumio[7377]: info: Preload queue cleared May 13 20:00:36 volumio volumio[7377]: info: Adding Item to queue: upnp/folder/http://192.168.1.106:50001/ContentDirectory/control@32$0 May 13 20:00:36 volumio volumio[7377]: info: Exploding uri upnp/folder/http://192.168.1.106:50001/ContentDirectory/control@32$0 in service upnp_browser May 13 20:00:37 volumio volumio[7377]: info: CoreCommandRouter::volumioPushQueue May 13 20:00:37 volumio volumio[7377]: info: CorePlayQueue::saveQueue May 13 20:00:37 volumio volumio[7377]: info: CoreStateMachine::updateTrackBlock May 13 20:00:37 volumio volumio[7377]: info: CorePlayQueue::getTrackBlock May 13 20:00:37 volumio volumio[7377]: info: CoreCommandRouter::volumioPlay May 13 20:00:37 volumio volumio[7377]: info: CoreStateMachine::play index 0 May 13 20:00:37 volumio volumio[7377]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 20:00:37 volumio volumio[7377]: info: CoreStateMachine::stop May 13 20:00:37 volumio volumio[7377]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 20:00:37 volumio volumio[7377]: info: CoreStateMachine::play index undefined May 13 20:00:37 volumio volumio[7377]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 20:00:37 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:00:37 volumio volumio[7377]: info: CoreStateMachine::startPlaybackTimer May 13 20:00:37 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:00:37 volumio volumio[7377]: info: [1747137637020] ControllerUPNPBrowser::clearAddPlayTrack May 13 20:00:37 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand stop May 13 20:00:37 volumio volumio[7377]: info: sendMpdCommand stop took 11 milliseconds May 13 20:00:37 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand clear May 13 20:00:37 volumio volumio[7377]: info: May 13 20:00:37 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:00:37 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:00:37 volumio volumio[7377]: info: sendMpdCommand clear took 31 milliseconds May 13 20:00:37 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.106:50002/transcoder/genericoder.cgi/id=radio.wav?radio=http%3A%2F%2Fstreampoint.radioio.com%2Fstreams%2F2162%2F4669bcfe1f305%2Flisten.pls" May 13 20:00:37 volumio volumio[7377]: info: May 13 20:00:37 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:00:37 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:00:37 volumio volumio[7377]: info: May 13 20:00:37 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:00:37 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:00:37 volumio volumio[7377]: error: updateQueue error: null May 13 20:00:37 volumio volumio[7377]: info: ------------------------------ 11ms May 13 20:00:37 volumio volumio[7377]: 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' May 13 20:00:37 volumio volumio[7377]: 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' May 13 20:00:38 volumio volumio[7377]: error: updateQueue error: null May 13 20:00:38 volumio volumio[7377]: error: updateQueue error: null May 13 20:00:38 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.106:50002/transcoder/genericoder.cgi/id=radio.wav?radio=http%3A%2F%2Fstreampoint.radioio.com%2Fstreams%2F2162%2F4669bcfe1f305%2Flisten.pls" May 13 20:00:38 volumio volumio[7377]: info: ------------------------------ 961ms May 13 20:00:38 volumio volumio[7377]: info: ------------------------------ 960ms May 13 20:00:38 volumio volumio[7377]: info: May 13 20:00:38 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:00:38 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:00:38 volumio volumio[7377]: info: sendMpdCommand add "http://192.168.1.106:50002/transcoder/genericoder.cgi/id=radio.wav?radio=http%3A%2F%2Fstreampoint.radioio.com%2Fstreams%2F2162%2F4669bcfe1f305%2Flisten.pls" took 2 milliseconds May 13 20:00:38 volumio volumio[7377]: info: CoreStateMachine::setConsumeUpdateService mpd May 13 20:00:38 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand play May 13 20:00:38 volumio volumio[7377]: info: May 13 20:00:38 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:00:38 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:00:38 volumio volumio[7377]: info: May 13 20:00:38 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:00:38 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:00:38 volumio volumio[7377]: info: ------------------------------ 6ms May 13 20:00:38 volumio volumio[7377]: info: sendMpdCommand play took 4 milliseconds May 13 20:00:38 volumio volumio[7377]: info: ------------------------------ 4ms May 13 20:00:38 volumio volumio[7377]: info: ------------------------------ 3ms May 13 20:00:38 volumio volumio[7377]: info: May 13 20:00:38 volumio volumio[7377]: ---------------------------- MPD announces state update: player May 13 20:00:38 volumio volumio[7377]: info: ControllerMpd::getState May 13 20:00:38 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand status May 13 20:00:38 volumio volumio[7377]: info: May 13 20:00:38 volumio volumio[7377]: ---------------------------- MPD announces state update: player May 13 20:00:38 volumio volumio[7377]: info: ControllerMpd::getState May 13 20:00:38 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand status May 13 20:00:38 volumio volumio[7377]: info: May 13 20:00:38 volumio volumio[7377]: ---------------------------- MPD announces state update: player May 13 20:00:38 volumio volumio[7377]: info: ControllerMpd::getState May 13 20:00:38 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand status May 13 20:00:38 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand clearerror May 13 20:00:38 volumio volumio[7377]: info: sendMpdCommand status took 13 milliseconds May 13 20:00:38 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand clearerror May 13 20:00:38 volumio volumio[7377]: info: sendMpdCommand status took 13 milliseconds May 13 20:00:38 volumio volumio[7377]: verbose: ControllerMpd::parseState May 13 20:00:38 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 20:00:38 volumio volumio[7377]: verbose: ControllerMpd::parseState May 13 20:00:38 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 20:00:38 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand clearerror May 13 20:00:38 volumio volumio[7377]: info: sendMpdCommand status took 26 milliseconds May 13 20:00:38 volumio volumio[7377]: info: sendMpdCommand clearerror took 17 milliseconds May 13 20:00:38 volumio volumio[7377]: info: sendMpdCommand clearerror took 15 milliseconds May 13 20:00:38 volumio volumio[7377]: info: sendMpdCommand playlistinfo took 9 milliseconds May 13 20:00:38 volumio volumio[7377]: info: sendMpdCommand playlistinfo took 9 milliseconds May 13 20:00:38 volumio volumio[7377]: verbose: ControllerMpd::parseState May 13 20:00:38 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 20:00:38 volumio volumio[7377]: verbose: ControllerMpd::parseTrackInfo May 13 20:00:38 volumio volumio[7377]: verbose: ControllerMpd::parseTrackInfo May 13 20:00:38 volumio volumio[7377]: info: ControllerMpd::pushState May 13 20:00:38 volumio volumio[7377]: info: CoreCommandRouter::servicePushState May 13 20:00:38 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:00:38 volumio volumio[7377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 20:00:38 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:00:38 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:00:38 volumio volumio[7377]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=radio.wav?radio=http%3A%2F%2Fstreampoint.radioio.com%2Fstreams%2F2162%2F4669bcfe1f305%2Flisten.pls","artist":null,"album":null,"uri":"http://192.168.1.106:50002/transcoder/genericoder.cgi/id=radio.wav?radio=http%3A%2F%2Fstreampoint.radioio.com%2Fstreams%2F2162%2F4669bcfe1f305%2Flisten.pls","trackType":"pls"} May 13 20:00:38 volumio volumio[7377]: verbose: CURRENT POSITION 0 May 13 20:00:38 volumio volumio[7377]: info: CoreStateMachine::syncState stateService stop May 13 20:00:38 volumio volumio[7377]: info: CoreStateMachine::syncState currentStatus stop May 13 20:00:38 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:00:38 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:00:38 volumio volumio[7377]: info: No code May 13 20:00:38 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:00:38 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:00:38 volumio volumio[7377]: info: ControllerMpd::pushState May 13 20:00:38 volumio volumio[7377]: info: CoreCommandRouter::servicePushState May 13 20:00:38 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:00:38 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:00:38 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:00:38 volumio volumio[7377]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=radio.wav?radio=http%3A%2F%2Fstreampoint.radioio.com%2Fstreams%2F2162%2F4669bcfe1f305%2Flisten.pls","artist":null,"album":null,"uri":"http://192.168.1.106:50002/transcoder/genericoder.cgi/id=radio.wav?radio=http%3A%2F%2Fstreampoint.radioio.com%2Fstreams%2F2162%2F4669bcfe1f305%2Flisten.pls","trackType":"pls"} May 13 20:00:38 volumio volumio[7377]: verbose: CURRENT POSITION 0 May 13 20:00:38 volumio volumio[7377]: info: CoreStateMachine::syncState stateService stop May 13 20:00:38 volumio volumio[7377]: info: CoreStateMachine::syncState currentStatus stop May 13 20:00:38 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:00:38 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:00:38 volumio volumio[7377]: info: No code May 13 20:00:38 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:00:38 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:00:38 volumio volumio[7377]: info: ------------------------------ 94ms May 13 20:00:38 volumio volumio[7377]: info: ------------------------------ 92ms May 13 20:00:38 volumio volumio[7377]: info: sendMpdCommand clearerror took 72 milliseconds May 13 20:00:38 volumio volumio[7377]: info: sendMpdCommand playlistinfo took 70 milliseconds May 13 20:00:38 volumio volumio[7377]: verbose: ControllerMpd::parseTrackInfo May 13 20:00:38 volumio volumio[7377]: info: ControllerMpd::pushState May 13 20:00:38 volumio volumio[7377]: info: CoreCommandRouter::servicePushState May 13 20:00:38 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:00:38 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:00:38 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:00:38 volumio volumio[7377]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=radio.wav?radio=http%3A%2F%2Fstreampoint.radioio.com%2Fstreams%2F2162%2F4669bcfe1f305%2Flisten.pls","artist":null,"album":null,"uri":"http://192.168.1.106:50002/transcoder/genericoder.cgi/id=radio.wav?radio=http%3A%2F%2Fstreampoint.radioio.com%2Fstreams%2F2162%2F4669bcfe1f305%2Flisten.pls","trackType":"pls"} May 13 20:00:38 volumio volumio[7377]: verbose: CURRENT POSITION 0 May 13 20:00:38 volumio volumio[7377]: info: CoreStateMachine::syncState stateService stop May 13 20:00:38 volumio volumio[7377]: info: CoreStateMachine::syncState currentStatus stop May 13 20:00:38 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:00:38 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:00:38 volumio volumio[7377]: info: No code May 13 20:00:38 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:00:38 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:00:38 volumio volumio[7377]: info: ------------------------------ 123ms May 13 20:00:38 volumio volumio[7377]: 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' May 13 20:00:43 volumio ntpd[971]: Soliciting pool server 193.182.111.142 May 13 20:00:48 volumio volumio[7377]: info: Preload queue cleared May 13 20:00:48 volumio volumio[7377]: info: CoreCommandRouter::volumioReplaceandPlayItems May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::ClearQueue May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::stop May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 20:00:48 volumio volumio[7377]: info: CorePlayQueue::clearPlayQueue May 13 20:00:48 volumio volumio[7377]: info: CorePlayQueue::saveQueue May 13 20:00:48 volumio volumio[7377]: info: CoreCommandRouter::volumioPushQueue May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::addQueueItems May 13 20:00:48 volumio volumio[7377]: info: CorePlayQueue::addQueueItems May 13 20:00:48 volumio volumio[7377]: info: Preload queue cleared May 13 20:00:48 volumio volumio[7377]: info: Adding Item to queue: upnp/folder/http://192.168.1.106:50001/ContentDirectory/control@32$0 May 13 20:00:48 volumio volumio[7377]: info: Using cached record of: upnp/folder/http://192.168.1.106:50001/ContentDirectory/control@32$0 May 13 20:00:48 volumio volumio[7377]: info: CoreCommandRouter::volumioPushQueue May 13 20:00:48 volumio volumio[7377]: info: CorePlayQueue::saveQueue May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::updateTrackBlock May 13 20:00:48 volumio volumio[7377]: info: CorePlayQueue::getTrackBlock May 13 20:00:48 volumio volumio[7377]: info: CoreCommandRouter::volumioPlay May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::play index 0 May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::stop May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::play index undefined May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 20:00:48 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::startPlaybackTimer May 13 20:00:48 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:00:48 volumio volumio[7377]: info: [1747137648262] ControllerUPNPBrowser::clearAddPlayTrack May 13 20:00:48 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand stop May 13 20:00:48 volumio volumio[7377]: info: sendMpdCommand stop took 5 milliseconds May 13 20:00:48 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand clear May 13 20:00:48 volumio volumio[7377]: info: May 13 20:00:48 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:00:48 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:00:48 volumio volumio[7377]: info: sendMpdCommand clear took 2 milliseconds May 13 20:00:48 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.106:50002/transcoder/genericoder.cgi/id=radio.wav?radio=http%3A%2F%2Fstreampoint.radioio.com%2Fstreams%2F2162%2F4669bcfe1f305%2Flisten.pls" May 13 20:00:48 volumio volumio[7377]: info: May 13 20:00:48 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:00:48 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:00:48 volumio volumio[7377]: info: May 13 20:00:48 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:00:48 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:00:48 volumio volumio[7377]: error: updateQueue error: null May 13 20:00:48 volumio volumio[7377]: info: ------------------------------ 5ms May 13 20:00:48 volumio volumio[7377]: error: updateQueue error: null May 13 20:00:48 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.106:50002/transcoder/genericoder.cgi/id=radio.wav?radio=http%3A%2F%2Fstreampoint.radioio.com%2Fstreams%2F2162%2F4669bcfe1f305%2Flisten.pls" May 13 20:00:48 volumio volumio[7377]: info: ------------------------------ 278ms May 13 20:00:48 volumio volumio[7377]: info: May 13 20:00:48 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:00:48 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:00:48 volumio volumio[7377]: info: sendMpdCommand add "http://192.168.1.106:50002/transcoder/genericoder.cgi/id=radio.wav?radio=http%3A%2F%2Fstreampoint.radioio.com%2Fstreams%2F2162%2F4669bcfe1f305%2Flisten.pls" took 5 milliseconds May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::setConsumeUpdateService mpd May 13 20:00:48 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand play May 13 20:00:48 volumio volumio[7377]: info: May 13 20:00:48 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:00:48 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:00:48 volumio volumio[7377]: info: May 13 20:00:48 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:00:48 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:00:48 volumio volumio[7377]: info: ------------------------------ 7ms May 13 20:00:48 volumio volumio[7377]: info: sendMpdCommand play took 4 milliseconds May 13 20:00:48 volumio volumio[7377]: info: ------------------------------ 4ms May 13 20:00:48 volumio volumio[7377]: info: ------------------------------ 2ms May 13 20:00:48 volumio volumio[7377]: info: May 13 20:00:48 volumio volumio[7377]: ---------------------------- MPD announces state update: player May 13 20:00:48 volumio volumio[7377]: info: ControllerMpd::getState May 13 20:00:48 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand status May 13 20:00:48 volumio volumio[7377]: info: May 13 20:00:48 volumio volumio[7377]: ---------------------------- MPD announces state update: player May 13 20:00:48 volumio volumio[7377]: info: ControllerMpd::getState May 13 20:00:48 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand status May 13 20:00:48 volumio volumio[7377]: info: May 13 20:00:48 volumio volumio[7377]: ---------------------------- MPD announces state update: player May 13 20:00:48 volumio volumio[7377]: info: ControllerMpd::getState May 13 20:00:48 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand status May 13 20:00:48 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand clearerror May 13 20:00:48 volumio volumio[7377]: info: sendMpdCommand status took 8 milliseconds May 13 20:00:48 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand clearerror May 13 20:00:48 volumio volumio[7377]: info: sendMpdCommand status took 8 milliseconds May 13 20:00:48 volumio volumio[7377]: verbose: ControllerMpd::parseState May 13 20:00:48 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 20:00:48 volumio volumio[7377]: verbose: ControllerMpd::parseState May 13 20:00:48 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 20:00:48 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand clearerror May 13 20:00:48 volumio volumio[7377]: info: sendMpdCommand status took 17 milliseconds May 13 20:00:48 volumio volumio[7377]: info: sendMpdCommand clearerror took 11 milliseconds May 13 20:00:48 volumio volumio[7377]: info: sendMpdCommand clearerror took 11 milliseconds May 13 20:00:48 volumio volumio[7377]: info: sendMpdCommand playlistinfo took 11 milliseconds May 13 20:00:48 volumio volumio[7377]: verbose: ControllerMpd::parseState May 13 20:00:48 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 20:00:48 volumio volumio[7377]: verbose: ControllerMpd::parseTrackInfo May 13 20:00:48 volumio volumio[7377]: verbose: ControllerMpd::parseTrackInfo May 13 20:00:48 volumio volumio[7377]: info: ControllerMpd::pushState May 13 20:00:48 volumio volumio[7377]: info: CoreCommandRouter::servicePushState May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:00:48 volumio volumio[7377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 20:00:48 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:00:48 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:00:48 volumio volumio[7377]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=radio.wav?radio=http%3A%2F%2Fstreampoint.radioio.com%2Fstreams%2F2162%2F4669bcfe1f305%2Flisten.pls","artist":null,"album":null,"uri":"http://192.168.1.106:50002/transcoder/genericoder.cgi/id=radio.wav?radio=http%3A%2F%2Fstreampoint.radioio.com%2Fstreams%2F2162%2F4669bcfe1f305%2Flisten.pls","trackType":"pls"} May 13 20:00:48 volumio volumio[7377]: verbose: CURRENT POSITION 0 May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::syncState stateService stop May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::syncState currentStatus stop May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:00:48 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:00:48 volumio volumio[7377]: info: No code May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:00:48 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:00:48 volumio volumio[7377]: info: ControllerMpd::pushState May 13 20:00:48 volumio volumio[7377]: info: CoreCommandRouter::servicePushState May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:00:48 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:00:48 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:00:48 volumio volumio[7377]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=radio.wav?radio=http%3A%2F%2Fstreampoint.radioio.com%2Fstreams%2F2162%2F4669bcfe1f305%2Flisten.pls","artist":null,"album":null,"uri":"http://192.168.1.106:50002/transcoder/genericoder.cgi/id=radio.wav?radio=http%3A%2F%2Fstreampoint.radioio.com%2Fstreams%2F2162%2F4669bcfe1f305%2Flisten.pls","trackType":"pls"} May 13 20:00:48 volumio volumio[7377]: verbose: CURRENT POSITION 0 May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::syncState stateService stop May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::syncState currentStatus stop May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:00:48 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:00:48 volumio volumio[7377]: info: No code May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:00:48 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:00:48 volumio volumio[7377]: info: ------------------------------ 78ms May 13 20:00:48 volumio volumio[7377]: info: ------------------------------ 78ms May 13 20:00:48 volumio volumio[7377]: info: sendMpdCommand clearerror took 66 milliseconds May 13 20:00:48 volumio volumio[7377]: info: sendMpdCommand playlistinfo took 59 milliseconds May 13 20:00:48 volumio volumio[7377]: verbose: ControllerMpd::parseTrackInfo May 13 20:00:48 volumio volumio[7377]: info: ControllerMpd::pushState May 13 20:00:48 volumio volumio[7377]: info: CoreCommandRouter::servicePushState May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:00:48 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:00:48 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:00:48 volumio volumio[7377]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=radio.wav?radio=http%3A%2F%2Fstreampoint.radioio.com%2Fstreams%2F2162%2F4669bcfe1f305%2Flisten.pls","artist":null,"album":null,"uri":"http://192.168.1.106:50002/transcoder/genericoder.cgi/id=radio.wav?radio=http%3A%2F%2Fstreampoint.radioio.com%2Fstreams%2F2162%2F4669bcfe1f305%2Flisten.pls","trackType":"pls"} May 13 20:00:48 volumio volumio[7377]: verbose: CURRENT POSITION 0 May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::syncState stateService stop May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::syncState currentStatus stop May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:00:48 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:00:48 volumio volumio[7377]: info: No code May 13 20:00:48 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:00:48 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:00:48 volumio volumio[7377]: info: ------------------------------ 111ms May 13 20:01:11 volumio volumio[7377]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 20:01:11 volumio volumio[7377]: info: Preload queue cleared May 13 20:01:12 volumio volumio[7377]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 20:01:12 volumio volumio[7377]: info: Preload queue cleared May 13 20:01:14 volumio volumio[7377]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 20:01:14 volumio volumio[7377]: info: Preload queue cleared May 13 20:01:25 volumio volumio[7377]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 20:01:25 volumio volumio[7377]: info: Preload queue cleared May 13 20:01:25 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@883 May 13 20:01:25 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@884 May 13 20:01:25 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@885 May 13 20:01:25 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@886 May 13 20:01:25 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@887 May 13 20:01:25 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@888 May 13 20:01:25 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@889 May 13 20:01:25 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@890 May 13 20:01:25 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@891 May 13 20:01:25 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@892 May 13 20:01:25 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@893 May 13 20:01:25 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@894 May 13 20:01:25 volumio volumio[7377]: info: Preloading song: upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@895 May 13 20:01:25 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@883 in service upnp_browser May 13 20:01:25 volumio ntpd[971]: Soliciting pool server 193.182.111.142 May 13 20:01:25 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@884 in service upnp_browser May 13 20:01:25 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@885 in service upnp_browser May 13 20:01:25 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@886 in service upnp_browser May 13 20:01:25 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@887 in service upnp_browser May 13 20:01:25 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@888 in service upnp_browser May 13 20:01:25 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@889 in service upnp_browser May 13 20:01:25 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@890 in service upnp_browser May 13 20:01:25 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@891 in service upnp_browser May 13 20:01:25 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@892 in service upnp_browser May 13 20:01:25 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@893 in service upnp_browser May 13 20:01:25 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@894 in service upnp_browser May 13 20:01:25 volumio volumio[7377]: info: Exploding uri upnp/http://192.168.1.106:50001/ContentDirectory/control@22$@895 in service upnp_browser May 13 20:01:26 volumio volumio[7377]: info: Preload queue cleared May 13 20:01:26 volumio volumio[7377]: info: CoreCommandRouter::volumioReplaceandPlayItems May 13 20:01:26 volumio volumio[7377]: info: CoreStateMachine::ClearQueue May 13 20:01:26 volumio volumio[7377]: info: CoreStateMachine::stop May 13 20:01:26 volumio volumio[7377]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 20:01:26 volumio volumio[7377]: info: CorePlayQueue::clearPlayQueue May 13 20:01:26 volumio volumio[7377]: info: CorePlayQueue::saveQueue May 13 20:01:26 volumio volumio[7377]: info: CoreCommandRouter::volumioPushQueue May 13 20:01:26 volumio volumio[7377]: info: CoreStateMachine::addQueueItems May 13 20:01:26 volumio volumio[7377]: info: CorePlayQueue::addQueueItems May 13 20:01:26 volumio volumio[7377]: info: Preload queue cleared May 13 20:01:26 volumio volumio[7377]: info: Adding Item to queue: upnp/folder/http://192.168.1.106:50001/ContentDirectory/control@22$1418 May 13 20:01:26 volumio volumio[7377]: info: Exploding uri upnp/folder/http://192.168.1.106:50001/ContentDirectory/control@22$1418 in service upnp_browser May 13 20:01:27 volumio volumio[7377]: info: CoreCommandRouter::volumioPushQueue May 13 20:01:27 volumio volumio[7377]: info: CorePlayQueue::saveQueue May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::updateTrackBlock May 13 20:01:27 volumio volumio[7377]: info: CorePlayQueue::getTrackBlock May 13 20:01:27 volumio volumio[7377]: info: CoreCommandRouter::volumioPlay May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::play index 0 May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::stop May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::play index undefined May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 20:01:27 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::startPlaybackTimer May 13 20:01:27 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:01:27 volumio volumio[7377]: info: [1747137687073] ControllerUPNPBrowser::clearAddPlayTrack May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand stop May 13 20:01:27 volumio volumio[7377]: info: sendMpdCommand stop took 6 milliseconds May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand clear May 13 20:01:27 volumio volumio[7377]: info: May 13 20:01:27 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:01:27 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:01:27 volumio volumio[7377]: info: sendMpdCommand clear took 2 milliseconds May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.106:50002/m/NDLNA/883.wav" May 13 20:01:27 volumio volumio[7377]: info: May 13 20:01:27 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:01:27 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:01:27 volumio volumio[7377]: info: May 13 20:01:27 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:01:27 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:01:27 volumio volumio[7377]: error: updateQueue error: null May 13 20:01:27 volumio volumio[7377]: info: ------------------------------ 5ms May 13 20:01:27 volumio volumio[7377]: error: updateQueue error: null May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.106:50002/m/NDLNA/883.wav" May 13 20:01:27 volumio volumio[7377]: info: ------------------------------ 152ms May 13 20:01:27 volumio volumio[7377]: info: ------------------------------ 151ms May 13 20:01:27 volumio volumio[7377]: info: May 13 20:01:27 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:01:27 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:01:27 volumio volumio[7377]: info: sendMpdCommand add "http://192.168.1.106:50002/m/NDLNA/883.wav" took 2 milliseconds May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::setConsumeUpdateService mpd May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand play May 13 20:01:27 volumio volumio[7377]: info: May 13 20:01:27 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:01:27 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:01:27 volumio volumio[7377]: info: May 13 20:01:27 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:01:27 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:01:27 volumio volumio[7377]: info: ------------------------------ 5ms May 13 20:01:27 volumio volumio[7377]: info: sendMpdCommand play took 4 milliseconds May 13 20:01:27 volumio volumio[7377]: info: ------------------------------ 3ms May 13 20:01:27 volumio volumio[7377]: info: ------------------------------ 2ms May 13 20:01:27 volumio volumio[7377]: info: May 13 20:01:27 volumio volumio[7377]: ---------------------------- MPD announces state update: player May 13 20:01:27 volumio volumio[7377]: info: ControllerMpd::getState May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand status May 13 20:01:27 volumio volumio[7377]: info: May 13 20:01:27 volumio volumio[7377]: ---------------------------- MPD announces state update: player May 13 20:01:27 volumio volumio[7377]: info: ControllerMpd::getState May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand status May 13 20:01:27 volumio volumio[7377]: info: May 13 20:01:27 volumio volumio[7377]: ---------------------------- MPD announces state update: player May 13 20:01:27 volumio volumio[7377]: info: ControllerMpd::getState May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand status May 13 20:01:27 volumio volumio[7377]: info: May 13 20:01:27 volumio volumio[7377]: ---------------------------- MPD announces state update: player May 13 20:01:27 volumio volumio[7377]: info: ControllerMpd::getState May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand status May 13 20:01:27 volumio volumio[7377]: info: May 13 20:01:27 volumio volumio[7377]: ---------------------------- MPD announces state update: player May 13 20:01:27 volumio volumio[7377]: info: ControllerMpd::getState May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand status May 13 20:01:27 volumio volumio[7377]: info: May 13 20:01:27 volumio volumio[7377]: ---------------------------- MPD announces state update: player May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand clearerror May 13 20:01:27 volumio volumio[7377]: info: sendMpdCommand status took 17 milliseconds May 13 20:01:27 volumio volumio[7377]: info: ControllerMpd::getState May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand status May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand clearerror May 13 20:01:27 volumio volumio[7377]: info: sendMpdCommand status took 16 milliseconds May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand clearerror May 13 20:01:27 volumio volumio[7377]: info: sendMpdCommand status took 14 milliseconds May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand clearerror May 13 20:01:27 volumio volumio[7377]: info: sendMpdCommand status took 13 milliseconds May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::parseState May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::parseState May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::parseState May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::parseState May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand clearerror May 13 20:01:27 volumio volumio[7377]: info: sendMpdCommand status took 37 milliseconds May 13 20:01:27 volumio volumio[7377]: info: sendMpdCommand clearerror took 29 milliseconds May 13 20:01:27 volumio volumio[7377]: info: sendMpdCommand status took 29 milliseconds May 13 20:01:27 volumio volumio[7377]: info: sendMpdCommand clearerror took 28 milliseconds May 13 20:01:27 volumio volumio[7377]: info: sendMpdCommand clearerror took 27 milliseconds May 13 20:01:27 volumio volumio[7377]: info: sendMpdCommand playlistinfo took 15 milliseconds May 13 20:01:27 volumio volumio[7377]: info: sendMpdCommand playlistinfo took 14 milliseconds May 13 20:01:27 volumio volumio[7377]: info: sendMpdCommand playlistinfo took 13 milliseconds May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::parseState May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::parseState May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::parseTrackInfo May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::parseTrackInfo May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::parseTrackInfo May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::parseTrackInfo May 13 20:01:27 volumio volumio[7377]: info: ControllerMpd::pushState May 13 20:01:27 volumio volumio[7377]: info: CoreCommandRouter::servicePushState May 13 20:01:27 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:01:27 volumio volumio[7377]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"883.wav","artist":null,"album":null,"uri":"http://192.168.1.106:50002/m/NDLNA/883.wav","trackType":"wav"} May 13 20:01:27 volumio volumio[7377]: verbose: CURRENT POSITION 0 May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::syncState stateService pause May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::syncState currentStatus stop May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:01:27 volumio volumio[7377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 20:01:27 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:01:27 volumio volumio[7377]: info: ControllerMpd::pushState May 13 20:01:27 volumio volumio[7377]: info: CoreCommandRouter::servicePushState May 13 20:01:27 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:01:27 volumio volumio[7377]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"883.wav","artist":null,"album":null,"uri":"http://192.168.1.106:50002/m/NDLNA/883.wav","trackType":"wav"} May 13 20:01:27 volumio volumio[7377]: verbose: CURRENT POSITION 0 May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::syncState stateService pause May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::syncState currentStatus stop May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:01:27 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:01:27 volumio volumio[7377]: info: ControllerMpd::pushState May 13 20:01:27 volumio volumio[7377]: info: CoreCommandRouter::servicePushState May 13 20:01:27 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:01:27 volumio volumio[7377]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"883.wav","artist":null,"album":null,"uri":"http://192.168.1.106:50002/m/NDLNA/883.wav","trackType":"wav"} May 13 20:01:27 volumio volumio[7377]: verbose: CURRENT POSITION 0 May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::syncState stateService pause May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::syncState currentStatus stop May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:01:27 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:01:27 volumio volumio[7377]: info: ControllerMpd::pushState May 13 20:01:27 volumio volumio[7377]: info: CoreCommandRouter::servicePushState May 13 20:01:27 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:01:27 volumio volumio[7377]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"883.wav","artist":null,"album":null,"uri":"http://192.168.1.106:50002/m/NDLNA/883.wav","trackType":"wav"} May 13 20:01:27 volumio volumio[7377]: verbose: CURRENT POSITION 0 May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::syncState stateService pause May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::syncState currentStatus stop May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:01:27 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:01:27 volumio volumio[7377]: info: ------------------------------ 99ms May 13 20:01:27 volumio volumio[7377]: info: ------------------------------ 97ms May 13 20:01:27 volumio volumio[7377]: info: ------------------------------ 96ms May 13 20:01:27 volumio volumio[7377]: info: ------------------------------ 93ms May 13 20:01:27 volumio volumio[7377]: info: sendMpdCommand clearerror took 61 milliseconds May 13 20:01:27 volumio volumio[7377]: info: sendMpdCommand playlistinfo took 58 milliseconds May 13 20:01:27 volumio volumio[7377]: verbose: ControllerMpd::parseTrackInfo May 13 20:01:27 volumio volumio[7377]: info: ControllerMpd::pushState May 13 20:01:27 volumio volumio[7377]: info: CoreCommandRouter::servicePushState May 13 20:01:27 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:01:27 volumio volumio[7377]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"883.wav","artist":null,"album":null,"uri":"http://192.168.1.106:50002/m/NDLNA/883.wav","trackType":"wav"} May 13 20:01:27 volumio volumio[7377]: verbose: CURRENT POSITION 0 May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::syncState stateService pause May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::syncState currentStatus stop May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:01:27 volumio volumio[7377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 20:01:27 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:01:27 volumio volumio[7377]: info: ControllerMpd::pushState May 13 20:01:27 volumio volumio[7377]: info: CoreCommandRouter::servicePushState May 13 20:01:27 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:01:27 volumio volumio[7377]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"883.wav","artist":null,"album":null,"uri":"http://192.168.1.106:50002/m/NDLNA/883.wav","trackType":"wav"} May 13 20:01:27 volumio volumio[7377]: verbose: CURRENT POSITION 0 May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::syncState stateService pause May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::syncState currentStatus stop May 13 20:01:27 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:01:27 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:01:27 volumio volumio[7377]: info: ------------------------------ 137ms May 13 20:01:27 volumio volumio[7377]: info: ------------------------------ 134ms May 13 20:01:31 volumio ntpd[971]: Soliciting pool server 2408:4003:10b1:4003:85b9:b3b4:90f9:9b41 May 13 20:01:33 volumio volumio[7377]: info: CoreCommandRouter::volumioGetState May 13 20:01:36 volumio ntpd[971]: Soliciting pool server 84.16.67.12 May 13 20:01:37 volumio volumio[7377]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 20:01:37 volumio volumio[7377]: info: Preload queue cleared May 13 20:01:38 volumio volumio[7377]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 20:01:38 volumio volumio[7377]: info: Preload queue cleared May 13 20:01:40 volumio volumio[7377]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 20:01:41 volumio volumio[7377]: info: Preload queue cleared May 13 20:01:44 volumio volumio[7377]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 20:01:44 volumio volumio[7377]: info: Preload queue cleared May 13 20:01:45 volumio volumio[7377]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 20:01:45 volumio volumio[7377]: info: Preload queue cleared May 13 20:01:49 volumio ntpd[971]: Soliciting pool server 45.76.221.157 May 13 20:01:49 volumio volumio[7377]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 13 20:01:50 volumio volumio[7377]: info: Preload queue cleared May 13 20:01:51 volumio volumio[7377]: info: Preload queue cleared May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::volumioReplaceandPlayItems May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::ClearQueue May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::stop May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 20:01:51 volumio volumio[7377]: info: CorePlayQueue::clearPlayQueue May 13 20:01:51 volumio volumio[7377]: info: CorePlayQueue::saveQueue May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::volumioPushQueue May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::addQueueItems May 13 20:01:51 volumio volumio[7377]: info: CorePlayQueue::addQueueItems May 13 20:01:51 volumio volumio[7377]: info: Preload queue cleared May 13 20:01:51 volumio volumio[7377]: info: Adding Item to queue: upnp/folder/http://192.168.1.106:50001/ContentDirectory/control@22$1418 May 13 20:01:51 volumio volumio[7377]: info: Using cached record of: upnp/folder/http://192.168.1.106:50001/ContentDirectory/control@22$1418 May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::volumioPushQueue May 13 20:01:51 volumio volumio[7377]: info: CorePlayQueue::saveQueue May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::updateTrackBlock May 13 20:01:51 volumio volumio[7377]: info: CorePlayQueue::getTrackBlock May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::volumioPlay May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::play index 0 May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::stop May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::play index undefined May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 20:01:51 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::startPlaybackTimer May 13 20:01:51 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:01:51 volumio volumio[7377]: info: [1747137711745] ControllerUPNPBrowser::clearAddPlayTrack May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand stop May 13 20:01:51 volumio volumio[7377]: info: May 13 20:01:51 volumio volumio[7377]: ---------------------------- MPD announces state update: player May 13 20:01:51 volumio volumio[7377]: info: sendMpdCommand stop took 7 milliseconds May 13 20:01:51 volumio volumio[7377]: info: ControllerMpd::getState May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand status May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand clear May 13 20:01:51 volumio volumio[7377]: info: May 13 20:01:51 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:01:51 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:01:51 volumio volumio[7377]: info: sendMpdCommand status took 6 milliseconds May 13 20:01:51 volumio volumio[7377]: info: sendMpdCommand clear took 5 milliseconds May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::parseState May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.106:50002/m/NDLNA/883.wav" May 13 20:01:51 volumio volumio[7377]: info: May 13 20:01:51 volumio volumio[7377]: ---------------------------- MPD announces state update: player May 13 20:01:51 volumio volumio[7377]: info: ControllerMpd::getState May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand status May 13 20:01:51 volumio volumio[7377]: info: May 13 20:01:51 volumio volumio[7377]: ---------------------------- MPD announces state update: player May 13 20:01:51 volumio volumio[7377]: info: ControllerMpd::getState May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand status May 13 20:01:51 volumio volumio[7377]: info: May 13 20:01:51 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:01:51 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:01:51 volumio volumio[7377]: error: updateQueue error: null May 13 20:01:51 volumio volumio[7377]: info: ------------------------------ 17ms May 13 20:01:51 volumio volumio[7377]: info: sendMpdCommand playlistinfo took 15 milliseconds May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.106:50002/m/NDLNA/883.wav" May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::parseTrackInfo May 13 20:01:51 volumio volumio[7377]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 13 20:01:51 volumio volumio[7377]: info: ------------------------------ 26ms May 13 20:01:51 volumio volumio[7377]: info: May 13 20:01:51 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:01:51 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:01:51 volumio volumio[7377]: error: updateQueue error: null May 13 20:01:51 volumio volumio[7377]: info: May 13 20:01:51 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:01:51 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:01:51 volumio volumio[7377]: info: sendMpdCommand status took 18 milliseconds May 13 20:01:51 volumio volumio[7377]: info: sendMpdCommand status took 17 milliseconds May 13 20:01:51 volumio volumio[7377]: info: ------------------------------ 10ms May 13 20:01:51 volumio volumio[7377]: info: sendMpdCommand add "http://192.168.1.106:50002/m/NDLNA/883.wav" took 7 milliseconds May 13 20:01:51 volumio volumio[7377]: info: ------------------------------ 5ms May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::parseState May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::setConsumeUpdateService mpd May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand play May 13 20:01:51 volumio volumio[7377]: info: ControllerMpd::pushState May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::servicePushState May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:01:51 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:01:51 volumio volumio[7377]: 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} May 13 20:01:51 volumio volumio[7377]: verbose: CURRENT POSITION 0 May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::syncState stateService stop May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::syncState currentStatus stop May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:01:51 volumio volumio[7377]: info: No code May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:01:51 volumio volumio[7377]: info: ControllerMpd::pushState May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::servicePushState May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:01:51 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:01:51 volumio volumio[7377]: 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} May 13 20:01:51 volumio volumio[7377]: verbose: CURRENT POSITION 0 May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::syncState stateService stop May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::syncState currentStatus stop May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:01:51 volumio volumio[7377]: info: No code May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:01:51 volumio volumio[7377]: info: ------------------------------ 84ms May 13 20:01:51 volumio volumio[7377]: info: ------------------------------ 83ms May 13 20:01:51 volumio volumio[7377]: info: May 13 20:01:51 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:01:51 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:01:51 volumio volumio[7377]: info: May 13 20:01:51 volumio volumio[7377]: ---------------------------- MPD announces state update: player May 13 20:01:51 volumio volumio[7377]: info: ControllerMpd::getState May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand status May 13 20:01:51 volumio volumio[7377]: info: May 13 20:01:51 volumio volumio[7377]: ---------------------------- MPD announces state update: player May 13 20:01:51 volumio volumio[7377]: info: May 13 20:01:51 volumio volumio[7377]: ---------------------------- MPD announces state update: player May 13 20:01:51 volumio volumio[7377]: info: ------------------------------ 75ms May 13 20:01:51 volumio volumio[7377]: info: sendMpdCommand play took 71 milliseconds May 13 20:01:51 volumio volumio[7377]: info: ControllerMpd::getState May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand status May 13 20:01:51 volumio volumio[7377]: info: ------------------------------ 8ms May 13 20:01:51 volumio volumio[7377]: info: ControllerMpd::getState May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand status May 13 20:01:51 volumio volumio[7377]: info: May 13 20:01:51 volumio volumio[7377]: ---------------------------- MPD announces system playlist update May 13 20:01:51 volumio volumio[7377]: info: Ignoring MPD Status Update May 13 20:01:51 volumio volumio[7377]: info: May 13 20:01:51 volumio volumio[7377]: ---------------------------- MPD announces state update: player May 13 20:01:51 volumio volumio[7377]: info: ControllerMpd::getState May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand status May 13 20:01:51 volumio volumio[7377]: info: May 13 20:01:51 volumio volumio[7377]: ---------------------------- MPD announces state update: player May 13 20:01:51 volumio volumio[7377]: info: ControllerMpd::getState May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand status May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand clearerror May 13 20:01:51 volumio volumio[7377]: info: sendMpdCommand status took 30 milliseconds May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand clearerror May 13 20:01:51 volumio volumio[7377]: info: sendMpdCommand status took 29 milliseconds May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand clearerror May 13 20:01:51 volumio volumio[7377]: info: sendMpdCommand status took 29 milliseconds May 13 20:01:51 volumio volumio[7377]: info: ------------------------------ 28ms May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand clearerror May 13 20:01:51 volumio volumio[7377]: info: sendMpdCommand status took 24 milliseconds May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::parseState May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::parseState May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::parseState May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::parseState May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand clearerror May 13 20:01:51 volumio volumio[7377]: info: sendMpdCommand status took 33 milliseconds May 13 20:01:51 volumio volumio[7377]: info: sendMpdCommand clearerror took 27 milliseconds May 13 20:01:51 volumio volumio[7377]: info: sendMpdCommand clearerror took 26 milliseconds May 13 20:01:51 volumio volumio[7377]: info: sendMpdCommand clearerror took 26 milliseconds May 13 20:01:51 volumio volumio[7377]: info: sendMpdCommand clearerror took 25 milliseconds May 13 20:01:51 volumio volumio[7377]: info: sendMpdCommand playlistinfo took 12 milliseconds May 13 20:01:51 volumio volumio[7377]: info: sendMpdCommand playlistinfo took 11 milliseconds May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::parseState May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::parseTrackInfo May 13 20:01:51 volumio volumio[7377]: info: ControllerMpd::pushState May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::servicePushState May 13 20:01:51 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:01:51 volumio volumio[7377]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"883.wav","artist":null,"album":null,"uri":"http://192.168.1.106:50002/m/NDLNA/883.wav","trackType":"wav"} May 13 20:01:51 volumio volumio[7377]: verbose: CURRENT POSITION 0 May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::syncState stateService pause May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::syncState currentStatus stop May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:01:51 volumio volumio[7377]: info: ControllerMpd::pushState May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::servicePushState May 13 20:01:51 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:01:51 volumio volumio[7377]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"883.wav","artist":null,"album":null,"uri":"http://192.168.1.106:50002/m/NDLNA/883.wav","trackType":"wav"} May 13 20:01:51 volumio volumio[7377]: verbose: CURRENT POSITION 0 May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::syncState stateService pause May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::syncState currentStatus stop May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:01:51 volumio volumio[7377]: info: ControllerMpd::pushState May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::servicePushState May 13 20:01:51 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:01:51 volumio volumio[7377]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"883.wav","artist":null,"album":null,"uri":"http://192.168.1.106:50002/m/NDLNA/883.wav","trackType":"wav"} May 13 20:01:51 volumio volumio[7377]: verbose: CURRENT POSITION 0 May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::syncState stateService pause May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::syncState currentStatus stop May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:01:51 volumio volumio[7377]: info: ControllerMpd::pushState May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::servicePushState May 13 20:01:51 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:01:51 volumio volumio[7377]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"883.wav","artist":null,"album":null,"uri":"http://192.168.1.106:50002/m/NDLNA/883.wav","trackType":"wav"} May 13 20:01:51 volumio volumio[7377]: verbose: CURRENT POSITION 0 May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::syncState stateService pause May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::syncState currentStatus stop May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:01:51 volumio volumio[7377]: info: ------------------------------ 114ms May 13 20:01:51 volumio volumio[7377]: info: ------------------------------ 112ms May 13 20:01:51 volumio volumio[7377]: info: ------------------------------ 112ms May 13 20:01:51 volumio volumio[7377]: info: ------------------------------ 110ms May 13 20:01:51 volumio volumio[7377]: info: sendMpdCommand clearerror took 61 milliseconds May 13 20:01:51 volumio volumio[7377]: info: sendMpdCommand playlistinfo took 58 milliseconds May 13 20:01:51 volumio volumio[7377]: verbose: ControllerMpd::parseTrackInfo May 13 20:01:51 volumio volumio[7377]: info: ControllerMpd::pushState May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::servicePushState May 13 20:01:51 volumio volumio[7377]: info: CorePlayQueue::getTrack 0 May 13 20:01:51 volumio volumio[7377]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"883.wav","artist":null,"album":null,"uri":"http://192.168.1.106:50002/m/NDLNA/883.wav","trackType":"wav"} May 13 20:01:51 volumio volumio[7377]: verbose: CURRENT POSITION 0 May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::syncState stateService pause May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::syncState currentStatus stop May 13 20:01:51 volumio volumio[7377]: info: CoreStateMachine::pushState May 13 20:01:51 volumio volumio[7377]: info: CoreCommandRouter::volumioPushState May 13 20:01:51 volumio volumio[7377]: info: ------------------------------ 107ms May 13 20:01:53 volumio volumio[7377]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 20:01:53 volumio volumio[7377]: Error: connect ETIMEDOUT 199.59.148.206:443 May 13 20:01:53 volumio volumio[7377]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 13 20:01:53 volumio volumio[7377]: errno: -110, May 13 20:01:53 volumio volumio[7377]: code: 'ETIMEDOUT', May 13 20:01:53 volumio volumio[7377]: syscall: 'connect', May 13 20:01:53 volumio volumio[7377]: address: '199.59.148.206', May 13 20:01:53 volumio volumio[7377]: port: 443 May 13 20:01:53 volumio volumio[7377]: } May 13 20:01:53 volumio volumio[7377]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 20:01:54 volumio sudo[8199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-13 20:00 May 13 20:01:54 volumio sudo[8199]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 10:07:11 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"