-- Logs begin at Wed 2024-05-08 06:32:14 UTC, end at Wed 2024-05-08 06:38:48 UTC. -- May 08 06:37:04 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:04 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:04 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:04 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:04 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:04 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:04 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:04 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:04 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:04 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:04 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:04 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:04 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:04 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:04 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:04 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:04 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:04 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:04 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:04 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:04 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:04 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:04 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:04 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:09 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 08 06:37:09 volumio volumio[797]: info: CURURI: artists:// May 08 06:37:09 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/347aidan/fed1a132-82b0-4ffb-90a7-62d7e286e092.jpg' May 08 06:37:09 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F/2f8c075c-15bd-4dcc-82ca-cd11d685f5c7.jpg' May 08 06:37:09 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F/2bdc6497-9680-4bf4-be0c-8ca4843a488b.jpg' May 08 06:37:09 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F%3F/eb385bb5-8fcc-41cc-9664-3ccc38979eb0.jpg' May 08 06:37:09 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F%3F%3F%3F/378ea82f-5461-423d-a7d2-17cce29b4f4b.jpg' May 08 06:37:09 volumio volumio[797]: info: CoreCommandRouter::volumioGetQueue May 08 06:37:09 volumio volumio[797]: info: CoreStateMachine::getQueue May 08 06:37:09 volumio volumio[797]: info: CorePlayQueue::getQueue May 08 06:37:09 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:09 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:09 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:09 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:09 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:09 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:09 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:09 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:09 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:09 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:09 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:09 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:09 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:09 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:09 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:09 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:09 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:09 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:09 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:09 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:09 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:09 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:09 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:09 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:09 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 08 06:37:09 volumio volumio[797]: info: CURURI: artists:// May 08 06:37:14 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:14 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:14 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:14 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:14 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:14 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:14 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:14 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:14 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:14 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:14 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:14 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:14 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:14 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:14 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:14 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:14 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:14 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:14 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:14 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:14 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:14 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:14 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:14 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:16 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Armik/b0af0c22-c51b-4caf-80fb-e7ca4fa2a2f5.jpg' May 08 06:37:16 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ava%20Max/c47b368e-633b-4325-8317-17451a4bbc05.jpg' May 08 06:37:17 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Babyface/e9c2fb42-deea-4c84-aff4-41ae0ebba038.jpg' May 08 06:37:17 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Barry%20Manilow/47d1359b-20f2-42b0-9636-261419c0bf48.jpg' May 08 06:37:17 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Beyonc%C3%A9/fd9d9eab-a344-4392-a499-d59b0000915c.jpg' May 08 06:37:17 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Booka%20Shade/7ff91dc6-ee1c-4546-8790-8932b4788b8c.jpg' May 08 06:37:17 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Craig%20Ruhnke/5b4ff6a9-ec11-41a9-8e76-d1fc6ad4e37f.jpg' May 08 06:37:17 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/C%C3%A9line%20Dion/2a30ab31-c43b-4bdd-9bc9-9f6a6e8da35e.jpg' May 08 06:37:17 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Elize/c84b9b30-0d1c-4ab1-849f-fa7c0df271b7.jpg' May 08 06:37:17 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Enrique%20Iglesias/aa7b038f-075b-4662-811d-4580c8aa7583.jpg' May 08 06:37:17 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/G.E.M.%E9%82%93%E7%B4%AB%E6%A3%8B/75c00951-36e3-4609-9889-a5a2d3c910db.jpg' May 08 06:37:17 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Gala/43feb3cc-4ec6-4e01-b27d-0555089ed7ce.jpg' May 08 06:37:17 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/George%20Michael/e77c8cff-fa86-40b5-9c80-45f1f347abff.jpg' May 08 06:37:17 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/George%20Michael%2FWham!/79c27803-a560-4637-8f0b-bba64e7f7eb9.jpg' May 08 06:37:17 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Glee%20Cast/467c0967-ea69-41b1-8dea-d3938ae954c4.jpg' May 08 06:37:17 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Jazz/f6e7af13-2a95-42cf-9c3b-a7eaa561ba5c.jpg' May 08 06:37:17 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Jennifer%20Warnes/069c9fea-06e8-4e8d-ab25-783e9f58ac6a.jpg' May 08 06:37:17 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Kenny%20G/085f1586-3c8e-4c11-8caf-6b5ab2b83c03.jpg' May 08 06:37:17 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Kenny%20G%2FToni%20Braxton/5570afbb-89c5-4aa3-9a87-009b5e3796bb.jpg' May 08 06:37:17 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Klartraum/bb1dfd24-2d65-48e9-982d-e66b5145faa6.jpg' May 08 06:37:17 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Kylie%20Minogue/fcad3ad3-b9b1-458a-83da-bfc6ca234f7e.jpg' May 08 06:37:17 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Late%20Night%20Alumni/9070de3c-f2e2-46f5-a05c-596c17979811.jpg' May 08 06:37:17 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 08 06:37:17 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/MEAV/33ff0d6d-e05a-4416-aa70-49114cbc8997.jpg' May 08 06:37:17 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Madonna/a0c2cfe6-d731-4812-ad09-7b534ad35cfd.jpg' May 08 06:37:17 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 08 06:37:17 volumio volumio[797]: info: TuneIn handleBrowseUri: tunein/best May 08 06:37:17 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Mary%20J.%20Blige/fefb2e53-f6a0-4808-8731-d14a073be5cd.jpg' May 08 06:37:18 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/OMR/40fad1d2-5967-411e-b2f1-2e39cb54e351.jpg' May 08 06:37:19 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Prinzhorn%20Dance%20School/1bab3bf5-a672-471d-b505-e795f1b0d2e2.jpg' May 08 06:37:19 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Rai/2a51a1e4-6d6f-4fb4-903c-7acacb1130d1.jpg' May 08 06:37:20 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:20 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:20 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:20 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:20 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:20 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:20 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:20 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:20 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:20 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:20 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:20 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:20 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:20 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:20 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:20 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:20 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:20 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:20 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:20 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:20 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:20 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:20 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:20 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:22 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Police/d40b2e83-f699-45d6-9118-f35610c3cb7b.jpg' May 08 06:37:22 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Various%20Artists/be6c2ddc-b804-4cc6-8c4d-22e87725c3ca.jpg' May 08 06:37:25 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:25 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:25 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:25 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:25 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:25 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:25 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:25 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:25 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:25 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:25 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:25 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:25 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:25 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:25 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:25 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:25 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:25 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:25 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:25 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:25 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:25 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:25 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:25 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:29 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 08 06:37:30 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 08 06:37:30 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:30 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:30 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:30 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:30 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:30 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:30 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:30 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:30 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:30 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:30 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:30 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:30 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:30 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:30 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:30 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:30 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:30 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:30 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:30 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:30 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:30 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:30 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:30 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:30 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 08 06:37:35 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:35 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:35 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:35 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:35 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:35 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:35 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:35 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:35 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:35 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:35 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:35 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:35 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:35 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:35 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:35 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:35 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:35 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:35 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:35 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:35 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:35 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:35 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:35 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:40 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:40 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:40 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:40 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:40 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:40 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:40 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:40 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:40 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:40 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:40 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:40 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:40 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:40 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:40 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:40 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:40 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:40 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:40 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:40 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:40 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:40 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:40 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:40 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:41 volumio volumio[797]: info: CoreCommandRouter::volumioReplaceandPlayItems May 08 06:37:41 volumio volumio[797]: info: CoreStateMachine::ClearQueue May 08 06:37:41 volumio volumio[797]: info: CoreStateMachine::stop May 08 06:37:41 volumio volumio[797]: info: CoreStateMachine::serviceStop May 08 06:37:41 volumio volumio[797]: info: CoreCommandRouter::serviceStop May 08 06:37:41 volumio volumio[797]: info: Airplay Stop May 08 06:37:41 volumio volumio[797]: info: Stopping Airplay Playback and sending pause command to client via USR2 May 08 06:37:41 volumio volumio[797]: info: CorePlayQueue::clearPlayQueue May 08 06:37:41 volumio volumio[797]: info: CorePlayQueue::saveQueue May 08 06:37:41 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:41 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:41 volumio volumio[797]: info: CoreCommandRouter::volumioPushQueue May 08 06:37:41 volumio volumio[797]: info: CoreStateMachine::addQueueItems May 08 06:37:41 volumio volumio[797]: info: CorePlayQueue::addQueueItems May 08 06:37:41 volumio volumio[797]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99552963 May 08 06:37:41 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 08 06:37:41 volumio volumio[797]: info: CorePlayQueue::saveQueue May 08 06:37:41 volumio volumio[797]: info: CoreCommandRouter::volumioPushQueue May 08 06:37:41 volumio volumio[797]: info: CoreStateMachine::updateTrackBlock May 08 06:37:41 volumio volumio[797]: info: CorePlayQueue::getTrackBlock May 08 06:37:41 volumio volumio[797]: info: CoreCommandRouter::volumioPlay May 08 06:37:41 volumio volumio[797]: UNSET VOLATILE May 08 06:37:41 volumio volumio[797]: info: Stopping Airplay Playback and sending pause command to client via USR2 May 08 06:37:41 volumio sudo[2276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1199 May 08 06:37:41 volumio volumio[797]: info: CoreStateMachine::play index 0 May 08 06:37:41 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:37:41 volumio sudo[2276]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 06:37:41 volumio volumio[797]: info: CoreStateMachine::stop May 08 06:37:41 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:37:41 volumio volumio[797]: UNSET VOLATILE May 08 06:37:41 volumio volumio[797]: info: CoreStateMachine::stPlaybackTimer May 08 06:37:41 volumio volumio[797]: info: CoreStateMachine::updateTrackBlock May 08 06:37:41 volumio volumio[797]: info: CorePlayQueue::getTrackBlock May 08 06:37:41 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:41 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:37:41 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:41 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:41 volumio volumio[797]: info: CoreStateMachine::serviceStop May 08 06:37:41 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:37:41 volumio volumio[797]: info: CoreCommandRouter::serviceStop May 08 06:37:41 volumio volumio[797]: info: [1715150261723] ControllerWebradio::stop May 08 06:37:41 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand stop May 08 06:37:41 volumio sudo[2276]: pam_unix(sudo:session): session closed for user root May 08 06:37:41 volumio shairport-sync[1199]: {"time":1715150154762,"response":"startAirplayPlayback Success"} May 08 06:37:41 volumio systemd[1]: shairport-sync.service: main process exited, code=killed, status=12/USR2 May 08 06:37:41 volumio systemd[1]: Unit shairport-sync.service entered failed state. May 08 06:37:41 volumio volumio[797]: info: sendMpdCommand stop took 4 milliseconds May 08 06:37:41 volumio volumio[797]: info: CoreStateMachine::play index undefined May 08 06:37:41 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:37:41 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:37:41 volumio volumio[797]: info: CoreStateMachine::startPlaybackTimer May 08 06:37:41 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:37:41 volumio volumio[797]: info: [1715150261728] ControllerWebradio::clearAddPlayTrack May 08 06:37:41 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand stop May 08 06:37:41 volumio volumio[797]: info: sendMpdCommand stop took 1 milliseconds May 08 06:37:41 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand clear May 08 06:37:41 volumio volumio[797]: info: Shairport-Sync paused with USR2 May 08 06:37:41 volumio volumio[797]: info: May 08 06:37:41 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:37:41 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:37:41 volumio volumio[797]: info: sendMpdCommand clear took 2 milliseconds May 08 06:37:41 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99552963" May 08 06:37:41 volumio volumio[797]: error: updateQueue error: null May 08 06:37:41 volumio volumio[797]: info: ------------------------------ 0ms May 08 06:37:41 volumio sudo[2281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 May 08 06:37:41 volumio sudo[2281]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 06:37:41 volumio sudo[2281]: pam_unix(sudo:session): session closed for user root May 08 06:37:41 volumio volumio[797]: info: Cannot execute Shairport-sync USR2 kill: Error: Command failed: /usr/bin/sudo /bin/kill -USR2 $(pidof shairport-sync) May 08 06:37:41 volumio volumio[797]: kill: invalid argument U May 08 06:37:41 volumio volumio[797]: Usage: May 08 06:37:41 volumio volumio[797]: kill [options] [...] May 08 06:37:41 volumio volumio[797]: Options: May 08 06:37:41 volumio volumio[797]: [...] send signal to every listed May 08 06:37:41 volumio volumio[797]: -, -s, --signal May 08 06:37:41 volumio volumio[797]: specify the to be sent May 08 06:37:41 volumio volumio[797]: -l, --list=[] list all signal names, or convert one to a name May 08 06:37:41 volumio volumio[797]: -L, --table list all signal names in a nice table May 08 06:37:41 volumio volumio[797]: -h, --help display this help and exit May 08 06:37:41 volumio volumio[797]: -V, --version output version information and exit May 08 06:37:41 volumio volumio[797]: For more details see kill(1). May 08 06:37:41 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%B9%8C%E5%85%B0%E5%9B%BE%E9%9B%85/c1568cfe-1ddc-4707-9061-d9fdbb4fc4fb.jpg' May 08 06:37:42 volumio volumio[797]: info: May 08 06:37:42 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:37:42 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:37:42 volumio volumio[797]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99552963" took 572 milliseconds May 08 06:37:42 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService mpd May 08 06:37:42 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand play May 08 06:37:42 volumio volumio[797]: info: ------------------------------ 0ms May 08 06:37:42 volumio volumio[797]: info: sendMpdCommand play took 0 milliseconds May 08 06:37:42 volumio volumio[797]: info: CoreCommandRouter::volumioReplaceandPlayItems May 08 06:37:42 volumio volumio[797]: info: CoreStateMachine::ClearQueue May 08 06:37:42 volumio volumio[797]: info: CoreStateMachine::stop May 08 06:37:42 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:37:42 volumio volumio[797]: UNSET VOLATILE May 08 06:37:42 volumio volumio[797]: info: CorePlayQueue::clearPlayQueue May 08 06:37:42 volumio volumio[797]: info: CorePlayQueue::saveQueue May 08 06:37:42 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:42 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:42 volumio volumio[797]: info: CoreCommandRouter::volumioPushQueue May 08 06:37:42 volumio volumio[797]: info: CoreStateMachine::addQueueItems May 08 06:37:42 volumio volumio[797]: info: CorePlayQueue::addQueueItems May 08 06:37:42 volumio volumio[797]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99552963 May 08 06:37:42 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 08 06:37:42 volumio volumio[797]: info: CorePlayQueue::saveQueue May 08 06:37:42 volumio volumio[797]: info: CoreCommandRouter::volumioPushQueue May 08 06:37:42 volumio volumio[797]: info: CoreStateMachine::updateTrackBlock May 08 06:37:42 volumio volumio[797]: info: CorePlayQueue::getTrackBlock May 08 06:37:42 volumio volumio[797]: info: CoreCommandRouter::volumioPlay May 08 06:37:42 volumio volumio[797]: UNSET VOLATILE May 08 06:37:42 volumio volumio[797]: info: CoreStateMachine::play index 0 May 08 06:37:42 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:37:42 volumio volumio[797]: info: CoreStateMachine::stop May 08 06:37:42 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:37:42 volumio volumio[797]: UNSET VOLATILE May 08 06:37:42 volumio volumio[797]: info: CoreStateMachine::play index undefined May 08 06:37:42 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:37:42 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:37:42 volumio volumio[797]: info: CoreStateMachine::startPlaybackTimer May 08 06:37:42 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:37:42 volumio volumio[797]: info: [1715150262520] ControllerWebradio::clearAddPlayTrack May 08 06:37:42 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand stop May 08 06:37:42 volumio volumio[797]: info: May 08 06:37:42 volumio volumio[797]: ---------------------------- MPD announces state update: player May 08 06:37:42 volumio volumio[797]: info: sendMpdCommand stop took 3 milliseconds May 08 06:37:42 volumio volumio[797]: info: ControllerMpd::getState May 08 06:37:42 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand status May 08 06:37:42 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand clear May 08 06:37:42 volumio volumio[797]: info: May 08 06:37:42 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:37:42 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:37:42 volumio volumio[797]: info: sendMpdCommand status took 1 milliseconds May 08 06:37:42 volumio volumio[797]: info: sendMpdCommand clear took 1 milliseconds May 08 06:37:42 volumio volumio[797]: verbose: ControllerMpd::parseState May 08 06:37:42 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 06:37:42 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99552963" May 08 06:37:42 volumio volumio[797]: error: updateQueue error: null May 08 06:37:42 volumio volumio[797]: info: ------------------------------ 1ms May 08 06:37:42 volumio volumio[797]: info: sendMpdCommand playlistinfo took 0 milliseconds May 08 06:37:42 volumio volumio[797]: verbose: ControllerMpd::parseTrackInfo May 08 06:37:42 volumio volumio[797]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 08 06:37:42 volumio volumio[797]: info: ------------------------------ 3ms May 08 06:37:42 volumio volumio[797]: info: Restarting Shairport-Sync after stop May 08 06:37:42 volumio sudo[2286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 08 06:37:42 volumio sudo[2286]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 06:37:42 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... May 08 06:37:42 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 08 06:37:42 volumio sudo[2286]: pam_unix(sudo:session): session closed for user root May 08 06:37:42 volumio volumio[797]: info: Shairport-Sync retarted May 08 06:37:42 volumio volumio[797]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99552963" took 286 milliseconds May 08 06:37:42 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService mpd May 08 06:37:42 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand play May 08 06:37:42 volumio volumio[797]: info: May 08 06:37:42 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:37:42 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:37:42 volumio volumio[797]: info: sendMpdCommand play took 4 milliseconds May 08 06:37:42 volumio volumio[797]: info: ------------------------------ 4ms May 08 06:37:44 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%B9%8C%E5%85%B0%E6%89%98%E5%A8%85/4ff02e7c-0c64-44b7-a501-35558d5fa016.jpg' May 08 06:37:44 volumio volumio[797]: info: May 08 06:37:44 volumio volumio[797]: ---------------------------- MPD announces state update: player May 08 06:37:44 volumio volumio[797]: info: ControllerMpd::getState May 08 06:37:44 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand status May 08 06:37:44 volumio volumio[797]: info: May 08 06:37:44 volumio volumio[797]: ---------------------------- MPD announces state update: player May 08 06:37:44 volumio volumio[797]: info: sendMpdCommand status took 24 milliseconds May 08 06:37:44 volumio volumio[797]: info: ControllerMpd::getState May 08 06:37:44 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand status May 08 06:37:44 volumio volumio[797]: verbose: ControllerMpd::parseState May 08 06:37:44 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 06:37:44 volumio volumio[797]: info: May 08 06:37:44 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:37:44 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:37:44 volumio volumio[797]: info: sendMpdCommand status took 1 milliseconds May 08 06:37:44 volumio volumio[797]: info: sendMpdCommand playlistinfo took 1 milliseconds May 08 06:37:44 volumio volumio[797]: verbose: ControllerMpd::parseState May 08 06:37:44 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 06:37:44 volumio volumio[797]: verbose: ControllerMpd::parseTrackInfo May 08 06:37:44 volumio volumio[797]: info: ControllerMpd::pushState May 08 06:37:44 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:44 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:37:44 volumio volumio[797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Carl Philipp Emanuel Bach (1714-1788) - Sonata per flauto e clavicembalo in mi Maggiore H506 (14:37) (Fiati con Tasto Köln) {+info: veniceclassicradio.eu}","artist":"VCR | Venice Classic Radio Auditorium","album":null,"uri":"http://116.202.241.212:8010/stream","trackType":""} May 08 06:37:44 volumio volumio[797]: verbose: CURRENT POSITION 0 May 08 06:37:44 volumio volumio[797]: info: CoreStateMachine::syncState stateService play May 08 06:37:44 volumio volumio[797]: info: CoreStateMachine::syncState currentStatus stop May 08 06:37:44 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:44 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:44 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:44 volumio volumio[797]: info: ------------------------------ 31ms May 08 06:37:44 volumio volumio[797]: info: ------------------------------ 7ms May 08 06:37:44 volumio volumio[797]: info: sendMpdCommand playlistinfo took 6 milliseconds May 08 06:37:44 volumio volumio[797]: verbose: ControllerMpd::parseTrackInfo May 08 06:37:44 volumio volumio[797]: info: ControllerMpd::pushState May 08 06:37:44 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:44 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:37:44 volumio volumio[797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":348,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"125 Kbps","isStreaming":false,"title":"Carl Philipp Emanuel Bach (1714-1788) - Sonata per flauto e clavicembalo in mi Maggiore H506 (14:37) (Fiati con Tasto Köln) {+info: veniceclassicradio.eu}","artist":"VCR | Venice Classic Radio Auditorium","album":null,"uri":"http://116.202.241.212:8010/stream","trackType":""} May 08 06:37:44 volumio volumio[797]: verbose: CURRENT POSITION 0 May 08 06:37:44 volumio volumio[797]: info: CoreStateMachine::syncState stateService play May 08 06:37:44 volumio volumio[797]: info: CoreStateMachine::syncState currentStatus play May 08 06:37:44 volumio volumio[797]: info: Received an update from plugin. extracting info from payload May 08 06:37:44 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 08 06:37:44 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:44 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:44 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:44 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:44 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:44 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:44 volumio volumio[797]: info: ------------------------------ 10ms May 08 06:37:44 volumio volumio[797]: info: CoreCommandRouter::volumioGetQueue May 08 06:37:44 volumio volumio[797]: info: CoreStateMachine::getQueue May 08 06:37:44 volumio volumio[797]: info: CorePlayQueue::getQueue May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::volumioReplaceandPlayItems May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::ClearQueue May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::stop May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:37:46 volumio volumio[797]: UNSET VOLATILE May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::stPlaybackTimer May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::updateTrackBlock May 08 06:37:46 volumio volumio[797]: info: CorePlayQueue::getTrackBlock May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:46 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::serviceStop May 08 06:37:46 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::serviceStop May 08 06:37:46 volumio volumio[797]: info: [1715150266286] ControllerWebradio::stop May 08 06:37:46 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand stop May 08 06:37:46 volumio volumio[797]: info: CorePlayQueue::clearPlayQueue May 08 06:37:46 volumio volumio[797]: info: CorePlayQueue::saveQueue May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::volumioPushQueue May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::addQueueItems May 08 06:37:46 volumio volumio[797]: info: CorePlayQueue::addQueueItems May 08 06:37:46 volumio volumio[797]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1773670 May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 08 06:37:46 volumio volumio[797]: info: CorePlayQueue::saveQueue May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::volumioPushQueue May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::updateTrackBlock May 08 06:37:46 volumio volumio[797]: info: CorePlayQueue::getTrackBlock May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::volumioPlay May 08 06:37:46 volumio volumio[797]: UNSET VOLATILE May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::play index 0 May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::stop May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:37:46 volumio volumio[797]: UNSET VOLATILE May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::play index undefined May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:37:46 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::startPlaybackTimer May 08 06:37:46 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:37:46 volumio volumio[797]: info: [1715150266290] ControllerWebradio::clearAddPlayTrack May 08 06:37:46 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand stop May 08 06:37:46 volumio volumio[797]: info: sendMpdCommand stop took 78 milliseconds May 08 06:37:46 volumio volumio[797]: info: May 08 06:37:46 volumio volumio[797]: ---------------------------- MPD announces state update: player May 08 06:37:46 volumio volumio[797]: info: ControllerMpd::getState May 08 06:37:46 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand status May 08 06:37:46 volumio volumio[797]: info: sendMpdCommand stop took 75 milliseconds May 08 06:37:46 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand clear May 08 06:37:46 volumio volumio[797]: info: May 08 06:37:46 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:37:46 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:37:46 volumio volumio[797]: info: sendMpdCommand status took 0 milliseconds May 08 06:37:46 volumio volumio[797]: info: sendMpdCommand clear took 0 milliseconds May 08 06:37:46 volumio volumio[797]: verbose: ControllerMpd::parseState May 08 06:37:46 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 06:37:46 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1773670" May 08 06:37:46 volumio volumio[797]: error: updateQueue error: null May 08 06:37:46 volumio volumio[797]: info: ------------------------------ 1ms May 08 06:37:46 volumio volumio[797]: info: sendMpdCommand playlistinfo took 1 milliseconds May 08 06:37:46 volumio volumio[797]: verbose: ControllerMpd::parseTrackInfo May 08 06:37:46 volumio volumio[797]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 08 06:37:46 volumio volumio[797]: info: ------------------------------ 2ms May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::volumioReplaceandPlayItems May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::ClearQueue May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::stop May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:37:46 volumio volumio[797]: UNSET VOLATILE May 08 06:37:46 volumio volumio[797]: info: CorePlayQueue::clearPlayQueue May 08 06:37:46 volumio volumio[797]: info: CorePlayQueue::saveQueue May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::volumioPushQueue May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::addQueueItems May 08 06:37:46 volumio volumio[797]: info: CorePlayQueue::addQueueItems May 08 06:37:46 volumio volumio[797]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1773670 May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 08 06:37:46 volumio volumio[797]: info: CorePlayQueue::saveQueue May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::volumioPushQueue May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::updateTrackBlock May 08 06:37:46 volumio volumio[797]: info: CorePlayQueue::getTrackBlock May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::volumioPlay May 08 06:37:46 volumio volumio[797]: UNSET VOLATILE May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::play index 0 May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::stop May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:37:46 volumio volumio[797]: UNSET VOLATILE May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::play index undefined May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:37:46 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::startPlaybackTimer May 08 06:37:46 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:37:46 volumio volumio[797]: info: [1715150266598] ControllerWebradio::clearAddPlayTrack May 08 06:37:46 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand stop May 08 06:37:46 volumio volumio[797]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1773670" took 290 milliseconds May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService mpd May 08 06:37:46 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand play May 08 06:37:46 volumio volumio[797]: info: May 08 06:37:46 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:37:46 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:37:46 volumio volumio[797]: info: sendMpdCommand stop took 57 milliseconds May 08 06:37:46 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand clear May 08 06:37:46 volumio volumio[797]: info: May 08 06:37:46 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:37:46 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:37:46 volumio volumio[797]: info: May 08 06:37:46 volumio volumio[797]: ---------------------------- MPD announces state update: player May 08 06:37:46 volumio volumio[797]: info: sendMpdCommand play took 1 milliseconds May 08 06:37:46 volumio volumio[797]: info: ------------------------------ 1ms May 08 06:37:46 volumio volumio[797]: info: sendMpdCommand clear took 1 milliseconds May 08 06:37:46 volumio volumio[797]: info: ControllerMpd::getState May 08 06:37:46 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand status May 08 06:37:46 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1773670" May 08 06:37:46 volumio volumio[797]: error: updateQueue error: null May 08 06:37:46 volumio volumio[797]: info: ------------------------------ 1ms May 08 06:37:46 volumio volumio[797]: info: sendMpdCommand status took 1 milliseconds May 08 06:37:46 volumio volumio[797]: verbose: ControllerMpd::parseState May 08 06:37:46 volumio volumio[797]: info: ControllerMpd::pushState May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:46 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:37:46 volumio volumio[797]: 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 08 06:37:46 volumio volumio[797]: verbose: CURRENT POSITION 0 May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::syncState stateService stop May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::syncState currentStatus stop May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:46 volumio volumio[797]: info: No code May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:46 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:46 volumio volumio[797]: info: ------------------------------ 5ms May 08 06:37:46 volumio volumio[797]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%B9%8C%E8%BE%BE%E6%9C%A8/9ddc018f-1a6b-42fd-89e5-70e93f1db5b7.jpg' May 08 06:37:46 volumio volumio[797]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1773670" took 288 milliseconds May 08 06:37:46 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService mpd May 08 06:37:46 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand play May 08 06:37:46 volumio volumio[797]: info: May 08 06:37:46 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:37:46 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:37:46 volumio volumio[797]: info: sendMpdCommand play took 1 milliseconds May 08 06:37:46 volumio volumio[797]: info: ------------------------------ 2ms May 08 06:37:47 volumio volumio[797]: info: May 08 06:37:47 volumio volumio[797]: ---------------------------- MPD announces state update: player May 08 06:37:47 volumio volumio[797]: info: ControllerMpd::getState May 08 06:37:47 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand status May 08 06:37:47 volumio volumio[797]: info: May 08 06:37:47 volumio volumio[797]: ---------------------------- MPD announces state update: player May 08 06:37:47 volumio volumio[797]: info: sendMpdCommand status took 1 milliseconds May 08 06:37:47 volumio volumio[797]: info: ControllerMpd::getState May 08 06:37:47 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand status May 08 06:37:47 volumio volumio[797]: verbose: ControllerMpd::parseState May 08 06:37:47 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 06:37:47 volumio volumio[797]: info: May 08 06:37:47 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:37:47 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:37:47 volumio volumio[797]: info: sendMpdCommand status took 0 milliseconds May 08 06:37:47 volumio volumio[797]: info: sendMpdCommand playlistinfo took 1 milliseconds May 08 06:37:47 volumio volumio[797]: verbose: ControllerMpd::parseState May 08 06:37:47 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 06:37:47 volumio volumio[797]: verbose: ControllerMpd::parseTrackInfo May 08 06:37:47 volumio volumio[797]: info: ControllerMpd::pushState May 08 06:37:47 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:47 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:37:47 volumio volumio[797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":348,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"44 Kbps","isStreaming":false,"title":"stream","artist":"Focus FM Regional","album":null,"uri":"http://89.40.72.71:8000/stream","trackType":""} May 08 06:37:47 volumio volumio[797]: verbose: CURRENT POSITION 0 May 08 06:37:47 volumio volumio[797]: info: CoreStateMachine::syncState stateService play May 08 06:37:47 volumio volumio[797]: info: CoreStateMachine::syncState currentStatus stop May 08 06:37:47 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:47 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:47 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:47 volumio volumio[797]: info: ------------------------------ 6ms May 08 06:37:47 volumio volumio[797]: info: ------------------------------ 4ms May 08 06:37:47 volumio volumio[797]: info: sendMpdCommand playlistinfo took 3 milliseconds May 08 06:37:47 volumio volumio[797]: verbose: ControllerMpd::parseTrackInfo May 08 06:37:47 volumio volumio[797]: info: ControllerMpd::pushState May 08 06:37:47 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:47 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:37:47 volumio volumio[797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":348,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"46 Kbps","isStreaming":false,"title":"stream","artist":"Focus FM Regional","album":null,"uri":"http://89.40.72.71:8000/stream","trackType":""} May 08 06:37:47 volumio volumio[797]: verbose: CURRENT POSITION 0 May 08 06:37:47 volumio volumio[797]: info: CoreStateMachine::syncState stateService play May 08 06:37:47 volumio volumio[797]: info: CoreStateMachine::syncState currentStatus play May 08 06:37:47 volumio volumio[797]: info: Received an update from plugin. extracting info from payload May 08 06:37:47 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:47 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:47 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:47 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:47 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:47 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:47 volumio volumio[797]: info: ------------------------------ 8ms May 08 06:37:55 volumio volumio[797]: info: CoreCommandRouter::volumioNext May 08 06:37:55 volumio volumio[797]: info: CoreStateMachine::next May 08 06:37:55 volumio volumio[797]: info: CoreStateMachine::stop May 08 06:37:55 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:37:55 volumio volumio[797]: UNSET VOLATILE May 08 06:37:55 volumio volumio[797]: info: CoreStateMachine::stPlaybackTimer May 08 06:37:55 volumio volumio[797]: info: CoreStateMachine::updateTrackBlock May 08 06:37:55 volumio volumio[797]: info: CorePlayQueue::getTrackBlock May 08 06:37:55 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:55 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:37:55 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:55 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:55 volumio volumio[797]: info: CoreStateMachine::serviceStop May 08 06:37:55 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:37:55 volumio volumio[797]: info: CoreCommandRouter::serviceStop May 08 06:37:55 volumio volumio[797]: info: [1715150275496] ControllerWebradio::stop May 08 06:37:55 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand stop May 08 06:37:55 volumio volumio[797]: info: sendMpdCommand stop took 37 milliseconds May 08 06:37:55 volumio volumio[797]: info: CoreStateMachine::play index undefined May 08 06:37:55 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:37:55 volumio volumio[797]: info: CorePlayQueue::getTrack 1 May 08 06:37:55 volumio volumio[797]: info: May 08 06:37:55 volumio volumio[797]: ---------------------------- MPD announces state update: player May 08 06:37:55 volumio volumio[797]: info: ControllerMpd::getState May 08 06:37:55 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand status May 08 06:37:55 volumio volumio[797]: info: sendMpdCommand status took 0 milliseconds May 08 06:37:55 volumio volumio[797]: verbose: ControllerMpd::parseState May 08 06:37:55 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 06:37:55 volumio volumio[797]: info: sendMpdCommand playlistinfo took 1 milliseconds May 08 06:37:55 volumio volumio[797]: verbose: ControllerMpd::parseTrackInfo May 08 06:37:55 volumio volumio[797]: info: ControllerMpd::pushState May 08 06:37:55 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:37:55 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:37:55 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:37:55 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:37:55 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:37:55 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:37:55 volumio volumio[797]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 08 06:37:55 volumio volumio[797]: info: ------------------------------ 2ms May 08 06:38:03 volumio volumio[797]: info: CoreCommandRouter::volumioRandom May 08 06:38:03 volumio volumio[797]: info: CoreCommandRouter::writePlayerControls May 08 06:38:03 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:03 volumio volumio[797]: info: CoreStateMachine::setRandom true May 08 06:38:03 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:38:03 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:03 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:03 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:04 volumio volumio[797]: info: CoreCommandRouter::volumioRandom May 08 06:38:04 volumio volumio[797]: info: CoreCommandRouter::writePlayerControls May 08 06:38:04 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:04 volumio volumio[797]: info: CoreStateMachine::setRandom false May 08 06:38:04 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:38:04 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:04 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:04 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:05 volumio volumio[797]: info: CoreCommandRouter::volumioNext May 08 06:38:05 volumio volumio[797]: info: CoreStateMachine::next May 08 06:38:05 volumio volumio[797]: info: CoreStateMachine::stop May 08 06:38:05 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:05 volumio volumio[797]: UNSET VOLATILE May 08 06:38:05 volumio volumio[797]: info: CoreStateMachine::play index undefined May 08 06:38:05 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:05 volumio volumio[797]: info: CorePlayQueue::getTrack 1 May 08 06:38:08 volumio volumio[797]: info: CoreCommandRouter::volumioReplaceandPlayItems May 08 06:38:08 volumio volumio[797]: info: CoreStateMachine::ClearQueue May 08 06:38:08 volumio volumio[797]: info: CoreStateMachine::stop May 08 06:38:08 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:08 volumio volumio[797]: UNSET VOLATILE May 08 06:38:08 volumio volumio[797]: info: CorePlayQueue::clearPlayQueue May 08 06:38:08 volumio volumio[797]: info: CorePlayQueue::saveQueue May 08 06:38:08 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:08 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:08 volumio volumio[797]: info: CoreCommandRouter::volumioPushQueue May 08 06:38:08 volumio volumio[797]: info: CoreStateMachine::addQueueItems May 08 06:38:08 volumio volumio[797]: info: CorePlayQueue::addQueueItems May 08 06:38:08 volumio volumio[797]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497968 May 08 06:38:08 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 08 06:38:08 volumio volumio[797]: info: CorePlayQueue::saveQueue May 08 06:38:08 volumio volumio[797]: info: CoreCommandRouter::volumioPushQueue May 08 06:38:08 volumio volumio[797]: info: CoreStateMachine::updateTrackBlock May 08 06:38:08 volumio volumio[797]: info: CorePlayQueue::getTrackBlock May 08 06:38:08 volumio volumio[797]: info: CoreCommandRouter::volumioPlay May 08 06:38:08 volumio volumio[797]: UNSET VOLATILE May 08 06:38:08 volumio volumio[797]: info: CoreStateMachine::play index 0 May 08 06:38:08 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:08 volumio volumio[797]: info: CoreStateMachine::stop May 08 06:38:08 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:08 volumio volumio[797]: UNSET VOLATILE May 08 06:38:08 volumio volumio[797]: info: CoreStateMachine::play index undefined May 08 06:38:08 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:08 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:08 volumio volumio[797]: info: CoreStateMachine::startPlaybackTimer May 08 06:38:08 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:08 volumio volumio[797]: info: [1715150288050] ControllerWebradio::clearAddPlayTrack May 08 06:38:08 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand stop May 08 06:38:08 volumio volumio[797]: info: sendMpdCommand stop took 1 milliseconds May 08 06:38:08 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand clear May 08 06:38:08 volumio volumio[797]: info: May 08 06:38:08 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:38:08 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:38:08 volumio volumio[797]: info: sendMpdCommand clear took 1 milliseconds May 08 06:38:08 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497968" May 08 06:38:08 volumio volumio[797]: error: updateQueue error: null May 08 06:38:08 volumio volumio[797]: info: ------------------------------ 1ms May 08 06:38:08 volumio volumio[797]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497968" took 285 milliseconds May 08 06:38:08 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService mpd May 08 06:38:08 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand play May 08 06:38:08 volumio volumio[797]: info: May 08 06:38:08 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:38:08 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:38:08 volumio volumio[797]: info: sendMpdCommand play took 0 milliseconds May 08 06:38:08 volumio volumio[797]: info: ------------------------------ 0ms May 08 06:38:11 volumio volumio[797]: info: May 08 06:38:11 volumio volumio[797]: ---------------------------- MPD announces state update: player May 08 06:38:11 volumio volumio[797]: info: ControllerMpd::getState May 08 06:38:11 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand status May 08 06:38:11 volumio volumio[797]: info: May 08 06:38:11 volumio volumio[797]: ---------------------------- MPD announces state update: player May 08 06:38:11 volumio volumio[797]: info: sendMpdCommand status took 22 milliseconds May 08 06:38:11 volumio volumio[797]: info: ControllerMpd::getState May 08 06:38:11 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand status May 08 06:38:11 volumio volumio[797]: verbose: ControllerMpd::parseState May 08 06:38:11 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 06:38:11 volumio volumio[797]: info: May 08 06:38:11 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:38:11 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:38:11 volumio volumio[797]: info: sendMpdCommand status took 2 milliseconds May 08 06:38:11 volumio volumio[797]: info: sendMpdCommand playlistinfo took 2 milliseconds May 08 06:38:11 volumio volumio[797]: verbose: ControllerMpd::parseState May 08 06:38:11 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 06:38:11 volumio volumio[797]: verbose: ControllerMpd::parseTrackInfo May 08 06:38:11 volumio volumio[797]: info: ControllerMpd::pushState May 08 06:38:11 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:38:11 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:11 volumio volumio[797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"rockantenne-hamburg","artist":"ROCK ANTENNE Hamburg","album":null,"uri":"http://stream.antenne.de:80/rockantenne-hamburg","trackType":""} May 08 06:38:11 volumio volumio[797]: verbose: CURRENT POSITION 0 May 08 06:38:11 volumio volumio[797]: info: CoreStateMachine::syncState stateService play May 08 06:38:11 volumio volumio[797]: info: CoreStateMachine::syncState currentStatus stop May 08 06:38:11 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:38:11 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:11 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:11 volumio volumio[797]: info: ------------------------------ 27ms May 08 06:38:11 volumio volumio[797]: info: ------------------------------ 3ms May 08 06:38:11 volumio volumio[797]: info: sendMpdCommand playlistinfo took 2 milliseconds May 08 06:38:11 volumio volumio[797]: verbose: ControllerMpd::parseTrackInfo May 08 06:38:11 volumio volumio[797]: info: ControllerMpd::pushState May 08 06:38:11 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:38:11 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:11 volumio volumio[797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":174,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"rockantenne-hamburg","artist":"ROCK ANTENNE Hamburg","album":null,"uri":"http://stream.antenne.de:80/rockantenne-hamburg","trackType":""} May 08 06:38:11 volumio volumio[797]: verbose: CURRENT POSITION 0 May 08 06:38:11 volumio volumio[797]: info: CoreStateMachine::syncState stateService play May 08 06:38:11 volumio volumio[797]: info: CoreStateMachine::syncState currentStatus play May 08 06:38:11 volumio volumio[797]: info: Received an update from plugin. extracting info from payload May 08 06:38:11 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 08 06:38:11 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:38:11 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:11 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:11 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:38:11 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:11 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:11 volumio volumio[797]: info: ------------------------------ 7ms May 08 06:38:11 volumio volumio[797]: info: May 08 06:38:11 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:38:11 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:38:11 volumio volumio[797]: info: May 08 06:38:11 volumio volumio[797]: ---------------------------- MPD announces state update: player May 08 06:38:11 volumio volumio[797]: info: ControllerMpd::getState May 08 06:38:11 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand status May 08 06:38:11 volumio volumio[797]: info: ------------------------------ 1ms May 08 06:38:11 volumio volumio[797]: info: sendMpdCommand status took 0 milliseconds May 08 06:38:11 volumio volumio[797]: verbose: ControllerMpd::parseState May 08 06:38:11 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 06:38:11 volumio volumio[797]: info: sendMpdCommand playlistinfo took 0 milliseconds May 08 06:38:11 volumio volumio[797]: verbose: ControllerMpd::parseTrackInfo May 08 06:38:11 volumio volumio[797]: info: ControllerMpd::pushState May 08 06:38:11 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:38:11 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:11 volumio volumio[797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":313,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Metallica - Whiskey in the jar","artist":"ROCK ANTENNE Hamburg","album":null,"uri":"http://stream.antenne.de:80/rockantenne-hamburg","trackType":""} May 08 06:38:11 volumio volumio[797]: verbose: CURRENT POSITION 0 May 08 06:38:11 volumio volumio[797]: info: CoreStateMachine::syncState stateService play May 08 06:38:11 volumio volumio[797]: info: CoreStateMachine::syncState currentStatus play May 08 06:38:11 volumio volumio[797]: info: Received an update from plugin. extracting info from payload May 08 06:38:11 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 08 06:38:11 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:38:11 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:11 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:11 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:38:11 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:11 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:11 volumio volumio[797]: info: ------------------------------ 4ms May 08 06:38:12 volumio volumio[797]: info: May 08 06:38:12 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:38:12 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:38:12 volumio volumio[797]: info: May 08 06:38:12 volumio volumio[797]: ---------------------------- MPD announces state update: player May 08 06:38:12 volumio volumio[797]: info: ControllerMpd::getState May 08 06:38:12 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand status May 08 06:38:12 volumio volumio[797]: info: ------------------------------ 0ms May 08 06:38:12 volumio volumio[797]: info: sendMpdCommand status took 0 milliseconds May 08 06:38:12 volumio volumio[797]: verbose: ControllerMpd::parseState May 08 06:38:12 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 06:38:12 volumio volumio[797]: info: sendMpdCommand playlistinfo took 1 milliseconds May 08 06:38:12 volumio volumio[797]: verbose: ControllerMpd::parseTrackInfo May 08 06:38:12 volumio volumio[797]: info: ControllerMpd::pushState May 08 06:38:12 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:38:12 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:12 volumio volumio[797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1047,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Metallica - Whiskey in the jar","artist":"ROCK ANTENNE Hamburg","album":null,"uri":"http://stream.antenne.de:80/rockantenne-hamburg","trackType":""} May 08 06:38:12 volumio volumio[797]: verbose: CURRENT POSITION 0 May 08 06:38:12 volumio volumio[797]: info: CoreStateMachine::syncState stateService play May 08 06:38:12 volumio volumio[797]: info: CoreStateMachine::syncState currentStatus play May 08 06:38:12 volumio volumio[797]: info: Received an update from plugin. extracting info from payload May 08 06:38:12 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 08 06:38:12 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:38:12 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:12 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:12 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:38:12 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:12 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:12 volumio volumio[797]: info: ------------------------------ 3ms May 08 06:38:13 volumio volumio[797]: info: May 08 06:38:13 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:38:13 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:38:13 volumio volumio[797]: info: May 08 06:38:13 volumio volumio[797]: ---------------------------- MPD announces state update: player May 08 06:38:13 volumio volumio[797]: info: ControllerMpd::getState May 08 06:38:13 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand status May 08 06:38:13 volumio volumio[797]: info: ------------------------------ 2ms May 08 06:38:13 volumio volumio[797]: info: sendMpdCommand status took 3 milliseconds May 08 06:38:13 volumio volumio[797]: verbose: ControllerMpd::parseState May 08 06:38:13 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 06:38:13 volumio volumio[797]: info: sendMpdCommand playlistinfo took 0 milliseconds May 08 06:38:13 volumio volumio[797]: verbose: ControllerMpd::parseTrackInfo May 08 06:38:13 volumio volumio[797]: info: ControllerMpd::pushState May 08 06:38:13 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:38:13 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:13 volumio volumio[797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2078,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Metallica - Whiskey in the jar","artist":"ROCK ANTENNE Hamburg","album":null,"uri":"http://stream.antenne.de:80/rockantenne-hamburg","trackType":""} May 08 06:38:13 volumio volumio[797]: verbose: CURRENT POSITION 0 May 08 06:38:13 volumio volumio[797]: info: CoreStateMachine::syncState stateService play May 08 06:38:13 volumio volumio[797]: info: CoreStateMachine::syncState currentStatus play May 08 06:38:13 volumio volumio[797]: info: Received an update from plugin. extracting info from payload May 08 06:38:13 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 08 06:38:13 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:38:13 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:13 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:13 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:38:13 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:13 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:13 volumio volumio[797]: info: ------------------------------ 6ms May 08 06:38:13 volumio volumio[797]: info: May 08 06:38:13 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:38:13 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:38:13 volumio volumio[797]: info: May 08 06:38:13 volumio volumio[797]: ---------------------------- MPD announces state update: player May 08 06:38:13 volumio volumio[797]: info: ControllerMpd::getState May 08 06:38:13 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand status May 08 06:38:13 volumio volumio[797]: info: ------------------------------ 2ms May 08 06:38:13 volumio volumio[797]: info: sendMpdCommand status took 1 milliseconds May 08 06:38:13 volumio volumio[797]: verbose: ControllerMpd::parseState May 08 06:38:13 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 06:38:13 volumio volumio[797]: info: sendMpdCommand playlistinfo took 0 milliseconds May 08 06:38:13 volumio volumio[797]: verbose: ControllerMpd::parseTrackInfo May 08 06:38:13 volumio volumio[797]: info: ControllerMpd::pushState May 08 06:38:13 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:38:13 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:13 volumio volumio[797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2954,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Metallica - Whiskey in the jar","artist":"ROCK ANTENNE Hamburg","album":null,"uri":"http://stream.antenne.de:80/rockantenne-hamburg","trackType":""} May 08 06:38:13 volumio volumio[797]: verbose: CURRENT POSITION 0 May 08 06:38:13 volumio volumio[797]: info: CoreStateMachine::syncState stateService play May 08 06:38:13 volumio volumio[797]: info: CoreStateMachine::syncState currentStatus play May 08 06:38:13 volumio volumio[797]: info: Received an update from plugin. extracting info from payload May 08 06:38:13 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 08 06:38:13 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:38:13 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:13 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:13 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:38:13 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:13 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:13 volumio volumio[797]: info: ------------------------------ 5ms May 08 06:38:14 volumio volumio[797]: info: May 08 06:38:14 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:38:14 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:38:14 volumio volumio[797]: info: May 08 06:38:14 volumio volumio[797]: ---------------------------- MPD announces state update: player May 08 06:38:14 volumio volumio[797]: info: ControllerMpd::getState May 08 06:38:14 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand status May 08 06:38:14 volumio volumio[797]: info: ------------------------------ 1ms May 08 06:38:14 volumio volumio[797]: info: sendMpdCommand status took 1 milliseconds May 08 06:38:14 volumio volumio[797]: verbose: ControllerMpd::parseState May 08 06:38:14 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 06:38:14 volumio volumio[797]: info: sendMpdCommand playlistinfo took 0 milliseconds May 08 06:38:14 volumio volumio[797]: verbose: ControllerMpd::parseTrackInfo May 08 06:38:14 volumio volumio[797]: info: ControllerMpd::pushState May 08 06:38:14 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:38:14 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:14 volumio volumio[797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3697,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Metallica - Whiskey in the jar","artist":"ROCK ANTENNE Hamburg","album":null,"uri":"http://stream.antenne.de:80/rockantenne-hamburg","trackType":""} May 08 06:38:14 volumio volumio[797]: verbose: CURRENT POSITION 0 May 08 06:38:14 volumio volumio[797]: info: CoreStateMachine::syncState stateService play May 08 06:38:14 volumio volumio[797]: info: CoreStateMachine::syncState currentStatus play May 08 06:38:14 volumio volumio[797]: info: Received an update from plugin. extracting info from payload May 08 06:38:14 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 08 06:38:14 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:38:14 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:14 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:14 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:38:14 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:14 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:14 volumio volumio[797]: info: ------------------------------ 4ms May 08 06:38:18 volumio volumio[797]: info: CoreCommandRouter::volumioGetState May 08 06:38:18 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 08 06:38:18 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 08 06:38:29 volumio volumio[797]: info: CoreCommandRouter::volumioReplaceandPlayItems May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::ClearQueue May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::stop May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:29 volumio volumio[797]: UNSET VOLATILE May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::stPlaybackTimer May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::updateTrackBlock May 08 06:38:29 volumio volumio[797]: info: CorePlayQueue::getTrackBlock May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:38:29 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:29 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:29 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::serviceStop May 08 06:38:29 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:29 volumio volumio[797]: info: CoreCommandRouter::serviceStop May 08 06:38:29 volumio volumio[797]: info: [1715150309614] ControllerWebradio::stop May 08 06:38:29 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand stop May 08 06:38:29 volumio volumio[797]: info: CorePlayQueue::clearPlayQueue May 08 06:38:29 volumio volumio[797]: info: CorePlayQueue::saveQueue May 08 06:38:29 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:29 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:29 volumio volumio[797]: info: CoreCommandRouter::volumioPushQueue May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::addQueueItems May 08 06:38:29 volumio volumio[797]: info: CorePlayQueue::addQueueItems May 08 06:38:29 volumio volumio[797]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1480076 May 08 06:38:29 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 08 06:38:29 volumio volumio[797]: info: CorePlayQueue::saveQueue May 08 06:38:29 volumio volumio[797]: info: CoreCommandRouter::volumioPushQueue May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::updateTrackBlock May 08 06:38:29 volumio volumio[797]: info: CorePlayQueue::getTrackBlock May 08 06:38:29 volumio volumio[797]: info: CoreCommandRouter::volumioPlay May 08 06:38:29 volumio volumio[797]: UNSET VOLATILE May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::play index 0 May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::stop May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:29 volumio volumio[797]: UNSET VOLATILE May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::play index undefined May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:29 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::startPlaybackTimer May 08 06:38:29 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:29 volumio volumio[797]: info: [1715150309618] ControllerWebradio::clearAddPlayTrack May 08 06:38:29 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand stop May 08 06:38:29 volumio volumio[797]: info: sendMpdCommand stop took 9 milliseconds May 08 06:38:29 volumio volumio[797]: info: May 08 06:38:29 volumio volumio[797]: ---------------------------- MPD announces state update: player May 08 06:38:29 volumio volumio[797]: info: ControllerMpd::getState May 08 06:38:29 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand status May 08 06:38:29 volumio volumio[797]: info: sendMpdCommand stop took 6 milliseconds May 08 06:38:29 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand clear May 08 06:38:29 volumio volumio[797]: info: May 08 06:38:29 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:38:29 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:38:29 volumio volumio[797]: info: sendMpdCommand status took 0 milliseconds May 08 06:38:29 volumio volumio[797]: info: sendMpdCommand clear took 0 milliseconds May 08 06:38:29 volumio volumio[797]: verbose: ControllerMpd::parseState May 08 06:38:29 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 06:38:29 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1480076" May 08 06:38:29 volumio volumio[797]: error: updateQueue error: null May 08 06:38:29 volumio volumio[797]: info: ------------------------------ 1ms May 08 06:38:29 volumio volumio[797]: info: sendMpdCommand playlistinfo took 0 milliseconds May 08 06:38:29 volumio volumio[797]: verbose: ControllerMpd::parseTrackInfo May 08 06:38:29 volumio volumio[797]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 08 06:38:29 volumio volumio[797]: info: ------------------------------ 1ms May 08 06:38:29 volumio volumio[797]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1480076" took 285 milliseconds May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService mpd May 08 06:38:29 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand play May 08 06:38:29 volumio volumio[797]: info: sendMpdCommand play took 2 milliseconds May 08 06:38:29 volumio volumio[797]: info: CoreCommandRouter::volumioReplaceandPlayItems May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::ClearQueue May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::stop May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:29 volumio volumio[797]: UNSET VOLATILE May 08 06:38:29 volumio volumio[797]: info: CorePlayQueue::clearPlayQueue May 08 06:38:29 volumio volumio[797]: info: CorePlayQueue::saveQueue May 08 06:38:29 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:29 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:29 volumio volumio[797]: info: CoreCommandRouter::volumioPushQueue May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::addQueueItems May 08 06:38:29 volumio volumio[797]: info: CorePlayQueue::addQueueItems May 08 06:38:29 volumio volumio[797]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1480076 May 08 06:38:29 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 08 06:38:29 volumio volumio[797]: info: CorePlayQueue::saveQueue May 08 06:38:29 volumio volumio[797]: info: CoreCommandRouter::volumioPushQueue May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::updateTrackBlock May 08 06:38:29 volumio volumio[797]: info: CorePlayQueue::getTrackBlock May 08 06:38:29 volumio volumio[797]: info: CoreCommandRouter::volumioPlay May 08 06:38:29 volumio volumio[797]: UNSET VOLATILE May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::play index 0 May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::stop May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:29 volumio volumio[797]: UNSET VOLATILE May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::play index undefined May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:29 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:29 volumio volumio[797]: info: CoreStateMachine::startPlaybackTimer May 08 06:38:29 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:29 volumio volumio[797]: info: [1715150309959] ControllerWebradio::clearAddPlayTrack May 08 06:38:29 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand stop May 08 06:38:29 volumio volumio[797]: info: sendMpdCommand stop took 2 milliseconds May 08 06:38:29 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand clear May 08 06:38:29 volumio volumio[797]: info: May 08 06:38:29 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:38:29 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:38:29 volumio volumio[797]: info: sendMpdCommand clear took 1 milliseconds May 08 06:38:29 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1480076" May 08 06:38:29 volumio volumio[797]: error: updateQueue error: null May 08 06:38:29 volumio volumio[797]: info: ------------------------------ 1ms May 08 06:38:30 volumio volumio[797]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1480076" took 287 milliseconds May 08 06:38:30 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService mpd May 08 06:38:30 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand play May 08 06:38:30 volumio volumio[797]: info: May 08 06:38:30 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:38:30 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:38:30 volumio volumio[797]: info: sendMpdCommand play took 0 milliseconds May 08 06:38:30 volumio volumio[797]: info: ------------------------------ 0ms May 08 06:38:35 volumio volumio[797]: info: CoreCommandRouter::volumioReplaceandPlayItems May 08 06:38:35 volumio volumio[797]: info: CoreStateMachine::ClearQueue May 08 06:38:35 volumio volumio[797]: info: CoreStateMachine::stop May 08 06:38:35 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:35 volumio volumio[797]: UNSET VOLATILE May 08 06:38:35 volumio volumio[797]: info: CorePlayQueue::clearPlayQueue May 08 06:38:35 volumio volumio[797]: info: CorePlayQueue::saveQueue May 08 06:38:35 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:35 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:35 volumio volumio[797]: info: CoreCommandRouter::volumioPushQueue May 08 06:38:35 volumio volumio[797]: info: CoreStateMachine::addQueueItems May 08 06:38:35 volumio volumio[797]: info: CorePlayQueue::addQueueItems May 08 06:38:35 volumio volumio[797]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1439332 May 08 06:38:35 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 08 06:38:35 volumio volumio[797]: info: CorePlayQueue::saveQueue May 08 06:38:35 volumio volumio[797]: info: CoreCommandRouter::volumioPushQueue May 08 06:38:35 volumio volumio[797]: info: CoreStateMachine::updateTrackBlock May 08 06:38:35 volumio volumio[797]: info: CorePlayQueue::getTrackBlock May 08 06:38:35 volumio volumio[797]: info: CoreCommandRouter::volumioPlay May 08 06:38:35 volumio volumio[797]: UNSET VOLATILE May 08 06:38:35 volumio volumio[797]: info: CoreStateMachine::play index 0 May 08 06:38:35 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:35 volumio volumio[797]: info: CoreStateMachine::stop May 08 06:38:35 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:35 volumio volumio[797]: UNSET VOLATILE May 08 06:38:35 volumio volumio[797]: info: CoreStateMachine::play index undefined May 08 06:38:35 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:35 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:35 volumio volumio[797]: info: CoreStateMachine::startPlaybackTimer May 08 06:38:35 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:35 volumio volumio[797]: info: [1715150315553] ControllerWebradio::clearAddPlayTrack May 08 06:38:35 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand stop May 08 06:38:35 volumio volumio[797]: info: May 08 06:38:35 volumio volumio[797]: ---------------------------- MPD announces state update: player May 08 06:38:35 volumio volumio[797]: info: sendMpdCommand stop took 2 milliseconds May 08 06:38:35 volumio volumio[797]: info: ControllerMpd::getState May 08 06:38:35 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand status May 08 06:38:35 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand clear May 08 06:38:35 volumio volumio[797]: info: May 08 06:38:35 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:38:35 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:38:35 volumio volumio[797]: info: sendMpdCommand status took 1 milliseconds May 08 06:38:35 volumio volumio[797]: info: sendMpdCommand clear took 1 milliseconds May 08 06:38:35 volumio volumio[797]: verbose: ControllerMpd::parseState May 08 06:38:35 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 06:38:35 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1439332" May 08 06:38:35 volumio volumio[797]: error: updateQueue error: null May 08 06:38:35 volumio volumio[797]: info: ------------------------------ 1ms May 08 06:38:35 volumio volumio[797]: info: sendMpdCommand playlistinfo took 0 milliseconds May 08 06:38:35 volumio volumio[797]: verbose: ControllerMpd::parseTrackInfo May 08 06:38:35 volumio volumio[797]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 08 06:38:35 volumio volumio[797]: info: ------------------------------ 1ms May 08 06:38:35 volumio volumio[797]: info: May 08 06:38:35 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:38:35 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:38:35 volumio volumio[797]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1439332" took 312 milliseconds May 08 06:38:35 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService mpd May 08 06:38:35 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand play May 08 06:38:35 volumio volumio[797]: info: ------------------------------ 3ms May 08 06:38:35 volumio volumio[797]: info: sendMpdCommand play took 1 milliseconds May 08 06:38:36 volumio volumio[797]: info: May 08 06:38:36 volumio volumio[797]: ---------------------------- MPD announces state update: player May 08 06:38:36 volumio volumio[797]: info: ControllerMpd::getState May 08 06:38:36 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand status May 08 06:38:36 volumio volumio[797]: info: May 08 06:38:36 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:38:36 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:38:36 volumio volumio[797]: info: May 08 06:38:36 volumio volumio[797]: ---------------------------- MPD announces state update: player May 08 06:38:36 volumio volumio[797]: info: ControllerMpd::getState May 08 06:38:36 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand status May 08 06:38:36 volumio volumio[797]: info: sendMpdCommand status took 1 milliseconds May 08 06:38:36 volumio volumio[797]: verbose: ControllerMpd::parseState May 08 06:38:36 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 06:38:36 volumio volumio[797]: info: ------------------------------ 1ms May 08 06:38:36 volumio volumio[797]: info: sendMpdCommand status took 1 milliseconds May 08 06:38:36 volumio volumio[797]: info: sendMpdCommand playlistinfo took 1 milliseconds May 08 06:38:36 volumio volumio[797]: verbose: ControllerMpd::parseState May 08 06:38:36 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 06:38:36 volumio volumio[797]: verbose: ControllerMpd::parseTrackInfo May 08 06:38:36 volumio volumio[797]: info: ControllerMpd::pushState May 08 06:38:36 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:38:36 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:36 volumio volumio[797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":174,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"stream","artist":"AlienWare","album":null,"uri":"http://51.75.145.68:9574/stream","trackType":""} May 08 06:38:36 volumio volumio[797]: verbose: CURRENT POSITION 0 May 08 06:38:36 volumio volumio[797]: info: CoreStateMachine::syncState stateService play May 08 06:38:36 volumio volumio[797]: info: CoreStateMachine::syncState currentStatus stop May 08 06:38:36 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:38:36 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:36 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:36 volumio volumio[797]: info: ------------------------------ 4ms May 08 06:38:36 volumio volumio[797]: info: sendMpdCommand playlistinfo took 2 milliseconds May 08 06:38:36 volumio volumio[797]: verbose: ControllerMpd::parseTrackInfo May 08 06:38:36 volumio volumio[797]: info: ControllerMpd::pushState May 08 06:38:36 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:38:36 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:36 volumio volumio[797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":174,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"stream","artist":"AlienWare","album":null,"uri":"http://51.75.145.68:9574/stream","trackType":""} May 08 06:38:36 volumio volumio[797]: verbose: CURRENT POSITION 0 May 08 06:38:36 volumio volumio[797]: info: CoreStateMachine::syncState stateService play May 08 06:38:36 volumio volumio[797]: info: CoreStateMachine::syncState currentStatus play May 08 06:38:36 volumio volumio[797]: info: Received an update from plugin. extracting info from payload May 08 06:38:36 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 08 06:38:36 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:38:36 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:36 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:36 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:38:36 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:36 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:36 volumio volumio[797]: info: ------------------------------ 7ms May 08 06:38:42 volumio volumio[797]: info: CoreCommandRouter::volumioReplaceandPlayItems May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::ClearQueue May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::stop May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:42 volumio volumio[797]: UNSET VOLATILE May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::stPlaybackTimer May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::updateTrackBlock May 08 06:38:42 volumio volumio[797]: info: CorePlayQueue::getTrackBlock May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:38:42 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:42 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:42 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::serviceStop May 08 06:38:42 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:42 volumio volumio[797]: info: CoreCommandRouter::serviceStop May 08 06:38:42 volumio volumio[797]: info: [1715150322110] ControllerWebradio::stop May 08 06:38:42 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand stop May 08 06:38:42 volumio volumio[797]: info: CorePlayQueue::clearPlayQueue May 08 06:38:42 volumio volumio[797]: info: CorePlayQueue::saveQueue May 08 06:38:42 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:42 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:42 volumio volumio[797]: info: CoreCommandRouter::volumioPushQueue May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::addQueueItems May 08 06:38:42 volumio volumio[797]: info: CorePlayQueue::addQueueItems May 08 06:38:42 volumio volumio[797]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99583701 May 08 06:38:42 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 08 06:38:42 volumio volumio[797]: info: CorePlayQueue::saveQueue May 08 06:38:42 volumio volumio[797]: info: CoreCommandRouter::volumioPushQueue May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::updateTrackBlock May 08 06:38:42 volumio volumio[797]: info: CorePlayQueue::getTrackBlock May 08 06:38:42 volumio volumio[797]: info: CoreCommandRouter::volumioPlay May 08 06:38:42 volumio volumio[797]: UNSET VOLATILE May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::play index 0 May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::stop May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:42 volumio volumio[797]: UNSET VOLATILE May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::play index undefined May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:42 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::startPlaybackTimer May 08 06:38:42 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:42 volumio volumio[797]: info: [1715150322113] ControllerWebradio::clearAddPlayTrack May 08 06:38:42 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand stop May 08 06:38:42 volumio volumio[797]: info: May 08 06:38:42 volumio volumio[797]: ---------------------------- MPD announces state update: player May 08 06:38:42 volumio volumio[797]: info: sendMpdCommand stop took 16 milliseconds May 08 06:38:42 volumio volumio[797]: info: ControllerMpd::getState May 08 06:38:42 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand status May 08 06:38:42 volumio volumio[797]: info: sendMpdCommand stop took 13 milliseconds May 08 06:38:42 volumio volumio[797]: info: sendMpdCommand status took 0 milliseconds May 08 06:38:42 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand clear May 08 06:38:42 volumio volumio[797]: verbose: ControllerMpd::parseState May 08 06:38:42 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 06:38:42 volumio volumio[797]: info: May 08 06:38:42 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:38:42 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:38:42 volumio volumio[797]: info: sendMpdCommand clear took 0 milliseconds May 08 06:38:42 volumio volumio[797]: info: sendMpdCommand playlistinfo took 0 milliseconds May 08 06:38:42 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99583701" May 08 06:38:42 volumio volumio[797]: verbose: ControllerMpd::parseTrackInfo May 08 06:38:42 volumio volumio[797]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 08 06:38:42 volumio volumio[797]: info: ------------------------------ 1ms May 08 06:38:42 volumio volumio[797]: error: updateQueue error: null May 08 06:38:42 volumio volumio[797]: info: ------------------------------ 0ms May 08 06:38:42 volumio volumio[797]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99583701" took 286 milliseconds May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService mpd May 08 06:38:42 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand play May 08 06:38:42 volumio volumio[797]: info: May 08 06:38:42 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:38:42 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:38:42 volumio volumio[797]: info: sendMpdCommand play took 1 milliseconds May 08 06:38:42 volumio volumio[797]: info: ------------------------------ 0ms May 08 06:38:42 volumio volumio[797]: info: CoreCommandRouter::volumioReplaceandPlayItems May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::ClearQueue May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::stop May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:42 volumio volumio[797]: UNSET VOLATILE May 08 06:38:42 volumio volumio[797]: info: CorePlayQueue::clearPlayQueue May 08 06:38:42 volumio volumio[797]: info: CorePlayQueue::saveQueue May 08 06:38:42 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:42 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:42 volumio volumio[797]: info: CoreCommandRouter::volumioPushQueue May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::addQueueItems May 08 06:38:42 volumio volumio[797]: info: CorePlayQueue::addQueueItems May 08 06:38:42 volumio volumio[797]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99583701 May 08 06:38:42 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 08 06:38:42 volumio volumio[797]: info: CorePlayQueue::saveQueue May 08 06:38:42 volumio volumio[797]: info: CoreCommandRouter::volumioPushQueue May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::updateTrackBlock May 08 06:38:42 volumio volumio[797]: info: CorePlayQueue::getTrackBlock May 08 06:38:42 volumio volumio[797]: info: CoreCommandRouter::volumioPlay May 08 06:38:42 volumio volumio[797]: UNSET VOLATILE May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::play index 0 May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::stop May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:42 volumio volumio[797]: UNSET VOLATILE May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::play index undefined May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 06:38:42 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::startPlaybackTimer May 08 06:38:42 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:42 volumio volumio[797]: info: [1715150322467] ControllerWebradio::clearAddPlayTrack May 08 06:38:42 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand stop May 08 06:38:42 volumio volumio[797]: info: May 08 06:38:42 volumio volumio[797]: ---------------------------- MPD announces state update: player May 08 06:38:42 volumio volumio[797]: info: sendMpdCommand stop took 1 milliseconds May 08 06:38:42 volumio volumio[797]: info: ControllerMpd::getState May 08 06:38:42 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand status May 08 06:38:42 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand clear May 08 06:38:42 volumio volumio[797]: info: May 08 06:38:42 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:38:42 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:38:42 volumio volumio[797]: info: sendMpdCommand status took 1 milliseconds May 08 06:38:42 volumio volumio[797]: info: sendMpdCommand clear took 0 milliseconds May 08 06:38:42 volumio volumio[797]: verbose: ControllerMpd::parseState May 08 06:38:42 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 06:38:42 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99583701" May 08 06:38:42 volumio volumio[797]: error: updateQueue error: null May 08 06:38:42 volumio volumio[797]: info: ------------------------------ 0ms May 08 06:38:42 volumio volumio[797]: info: sendMpdCommand playlistinfo took 1 milliseconds May 08 06:38:42 volumio volumio[797]: verbose: ControllerMpd::parseTrackInfo May 08 06:38:42 volumio volumio[797]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 08 06:38:42 volumio volumio[797]: info: ------------------------------ 2ms May 08 06:38:42 volumio volumio[797]: info: May 08 06:38:42 volumio volumio[797]: ---------------------------- MPD announces system playlist update May 08 06:38:42 volumio volumio[797]: info: Ignoring MPD Status Update May 08 06:38:42 volumio volumio[797]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99583701" took 286 milliseconds May 08 06:38:42 volumio volumio[797]: info: CoreStateMachine::setConsumeUpdateService mpd May 08 06:38:42 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand play May 08 06:38:42 volumio volumio[797]: info: ------------------------------ 0ms May 08 06:38:42 volumio volumio[797]: info: sendMpdCommand play took 0 milliseconds May 08 06:38:43 volumio volumio[797]: info: May 08 06:38:43 volumio volumio[797]: ---------------------------- MPD announces state update: player May 08 06:38:43 volumio volumio[797]: info: ControllerMpd::getState May 08 06:38:43 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand status May 08 06:38:43 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand clearerror May 08 06:38:43 volumio volumio[797]: info: sendMpdCommand status took 1 milliseconds May 08 06:38:43 volumio volumio[797]: verbose: ControllerMpd::parseState May 08 06:38:43 volumio volumio[797]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 06:38:43 volumio volumio[797]: info: sendMpdCommand clearerror took 1 milliseconds May 08 06:38:43 volumio volumio[797]: info: sendMpdCommand playlistinfo took 1 milliseconds May 08 06:38:43 volumio volumio[797]: verbose: ControllerMpd::parseTrackInfo May 08 06:38:43 volumio volumio[797]: info: ControllerMpd::pushState May 08 06:38:43 volumio volumio[797]: info: CoreCommandRouter::servicePushState May 08 06:38:43 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:38:43 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:43 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:43 volumio volumio[797]: info: CorePlayQueue::getTrack 0 May 08 06:38:43 volumio volumio[797]: 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":"turkuradyo","artist":"(#1 - 238/2000) T?rk? Radyo","album":null,"uri":"https://198.244.186.166:4591/turkuradyo","trackType":""} May 08 06:38:43 volumio volumio[797]: verbose: CURRENT POSITION 0 May 08 06:38:43 volumio volumio[797]: info: CoreStateMachine::syncState stateService stop May 08 06:38:43 volumio volumio[797]: info: CoreStateMachine::syncState currentStatus stop May 08 06:38:43 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:38:43 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:43 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:43 volumio volumio[797]: info: No code May 08 06:38:43 volumio volumio[797]: info: CoreStateMachine::pushState May 08 06:38:43 volumio volumio[797]: info: CoreCommandRouter::volumioPushState May 08 06:38:43 volumio volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 06:38:43 volumio volumio[797]: info: ------------------------------ 4ms May 08 06:38:48 volumio volumio[797]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 06:38:48 volumio volumio[797]: { Error: connect ETIMEDOUT 162.125.80.3:443 May 08 06:38:48 volumio volumio[797]: at Object._errnoException (util.js:1022:11) May 08 06:38:48 volumio volumio[797]: at _exceptionWithHostPort (util.js:1044:20) May 08 06:38:48 volumio volumio[797]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) May 08 06:38:48 volumio volumio[797]: code: 'ETIMEDOUT', May 08 06:38:48 volumio volumio[797]: errno: 'ETIMEDOUT', May 08 06:38:48 volumio volumio[797]: syscall: 'connect', May 08 06:38:48 volumio volumio[797]: address: '162.125.80.3', May 08 06:38:48 volumio volumio[797]: port: 443 } May 08 06:38:48 volumio volumio[797]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 06:38:48 volumio sudo[2469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-08 06:37 May 08 06:38:48 volumio sudo[2469]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cd11dc738d0d82822d0aa776d0729686b82e3758" VOLUMIO_FE_VERSION="537e3f50b5a8aa9bdf42f94adc80b348609b5c47" VOLUMIO_BE_VERSION="d7e6c614214d5dc616fddcbcfefc47e1990da7b1" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Sep 8 12:33:16 CEST 2021" VOLUMIO_VERSION="2.909" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="6e5c976a6d85b8b7d7e4e3388d208cd8"