-- Logs begin at Thu 2024-11-28 14:20:38 UTC, end at Fri 2024-11-29 11:12:31 UTC. -- Nov 29 11:11:00 volumio volumio[30479]: info: camilladsp spawned new process with pid 427, instance 1, run: true Nov 29 11:11:00 volumio volumio[30479]: info: camilladsp spawned new process with pid 436, instance 1, run: true Nov 29 11:11:00 volumio volumio[30479]: info: camilladsp spawned new process with pid 445, instance 1, run: true Nov 29 11:11:00 volumio volumio[30479]: info: camilladsp spawned new process with pid 455, instance 1, run: true Nov 29 11:11:00 volumio volumio[30479]: info: camilladsp spawned new process with pid 465, instance 1, run: true Nov 29 11:11:00 volumio volumio[30479]: info: camilladsp spawned new process with pid 477, instance 1, run: true Nov 29 11:11:00 volumio volumio[30479]: info: camilladsp spawned new process with pid 485, instance 1, run: true Nov 29 11:11:00 volumio volumio[30479]: info: camilladsp spawned new process with pid 494, instance 1, run: true Nov 29 11:11:00 volumio volumio[30479]: info: camilladsp spawned new process with pid 504, instance 1, run: true Nov 29 11:11:00 volumio volumio[30479]: info: camilladsp spawned new process with pid 512, instance 1, run: true Nov 29 11:11:00 volumio volumio[30479]: info: camilladsp spawned new process with pid 520, instance 1, run: true Nov 29 11:11:00 volumio volumio[30479]: info: camilladsp spawned new process with pid 529, instance 1, run: true Nov 29 11:11:01 volumio volumio[30479]: info: camilladsp spawned new process with pid 537, instance 1, run: true Nov 29 11:11:01 volumio volumio[30479]: info: camilladsp spawned new process with pid 545, instance 1, run: true Nov 29 11:11:01 volumio volumio[30479]: info: camilladsp spawned new process with pid 554, instance 1, run: true Nov 29 11:11:01 volumio volumio[30479]: info: camilladsp spawned new process with pid 562, instance 1, run: true Nov 29 11:11:01 volumio volumio[30479]: info: camilladsp spawned new process with pid 570, instance 1, run: true Nov 29 11:11:01 volumio volumio[30479]: info: camilladsp spawned new process with pid 578, instance 1, run: true Nov 29 11:11:01 volumio volumio[30479]: info: camilladsp spawned new process with pid 586, instance 1, run: true Nov 29 11:11:01 volumio volumio[30479]: info: camilladsp spawned new process with pid 594, instance 1, run: true Nov 29 11:11:01 volumio volumio[30479]: info: camilladsp spawned new process with pid 602, instance 1, run: true Nov 29 11:11:01 volumio volumio[30479]: info: camilladsp spawned new process with pid 610, instance 1, run: true Nov 29 11:11:01 volumio volumio[30479]: info: camilladsp spawned new process with pid 618, instance 1, run: true Nov 29 11:11:02 volumio volumio[30479]: info: camilladsp spawned new process with pid 626, instance 1, run: true Nov 29 11:11:02 volumio volumio[30479]: info: camilladsp spawned new process with pid 635, instance 1, run: true Nov 29 11:11:02 volumio volumio[30479]: info: camilladsp spawned new process with pid 643, instance 1, run: true Nov 29 11:11:02 volumio volumio[30479]: info: camilladsp spawned new process with pid 651, instance 1, run: true Nov 29 11:11:02 volumio volumio[30479]: info: camilladsp spawned new process with pid 659, instance 1, run: true Nov 29 11:11:02 volumio volumio[30479]: info: camilladsp spawned new process with pid 668, instance 1, run: true Nov 29 11:11:02 volumio volumio[30479]: info: camilladsp spawned new process with pid 676, instance 1, run: true Nov 29 11:11:02 volumio volumio[30479]: info: camilladsp spawned new process with pid 684, instance 1, run: true Nov 29 11:11:02 volumio volumio[30479]: info: camilladsp spawned new process with pid 692, instance 1, run: true Nov 29 11:11:02 volumio volumio[30479]: info: camilladsp spawned new process with pid 701, instance 1, run: true Nov 29 11:11:02 volumio volumio[30479]: info: camilladsp spawned new process with pid 711, instance 1, run: true Nov 29 11:11:03 volumio volumio[30479]: info: camilladsp spawned new process with pid 723, instance 1, run: true Nov 29 11:11:03 volumio volumio[30479]: info: camilladsp spawned new process with pid 732, instance 1, run: true Nov 29 11:11:03 volumio volumio[30479]: info: camilladsp spawned new process with pid 741, instance 1, run: true Nov 29 11:11:03 volumio volumio[30479]: info: camilladsp spawned new process with pid 749, instance 1, run: true Nov 29 11:11:03 volumio volumio[30479]: info: camilladsp spawned new process with pid 757, instance 1, run: true Nov 29 11:11:03 volumio volumio[30479]: info: camilladsp spawned new process with pid 765, instance 1, run: true Nov 29 11:11:03 volumio volumio[30479]: info: camilladsp spawned new process with pid 773, instance 1, run: true Nov 29 11:11:03 volumio volumio[30479]: info: camilladsp spawned new process with pid 782, instance 1, run: true Nov 29 11:11:03 volumio volumio[30479]: info: camilladsp spawned new process with pid 790, instance 1, run: true Nov 29 11:11:03 volumio volumio[30479]: info: camilladsp spawned new process with pid 798, instance 1, run: true Nov 29 11:11:03 volumio volumio[30479]: info: camilladsp spawned new process with pid 807, instance 1, run: true Nov 29 11:11:04 volumio volumio[30479]: info: camilladsp spawned new process with pid 815, instance 1, run: true Nov 29 11:11:04 volumio volumio[30479]: info: camilladsp spawned new process with pid 825, instance 1, run: true Nov 29 11:11:04 volumio volumio[30479]: info: camilladsp spawned new process with pid 834, instance 1, run: true Nov 29 11:11:04 volumio volumio[30479]: info: camilladsp spawned new process with pid 844, instance 1, run: true Nov 29 11:11:04 volumio volumio[30479]: info: camilladsp spawned new process with pid 853, instance 1, run: true Nov 29 11:11:04 volumio volumio[30479]: info: camilladsp spawned new process with pid 862, instance 1, run: true Nov 29 11:11:04 volumio volumio[30479]: info: camilladsp spawned new process with pid 871, instance 1, run: true Nov 29 11:11:04 volumio volumio[30479]: info: camilladsp spawned new process with pid 879, instance 1, run: true Nov 29 11:11:04 volumio volumio[30479]: info: camilladsp spawned new process with pid 887, instance 1, run: true Nov 29 11:11:04 volumio volumio[30479]: info: camilladsp spawned new process with pid 895, instance 1, run: true Nov 29 11:11:04 volumio volumio[30479]: info: camilladsp spawned new process with pid 903, instance 1, run: true Nov 29 11:11:04 volumio volumio[30479]: info: camilladsp spawned new process with pid 911, instance 1, run: true Nov 29 11:11:05 volumio volumio[30479]: info: camilladsp spawned new process with pid 919, instance 1, run: true Nov 29 11:11:05 volumio volumio[30479]: info: camilladsp spawned new process with pid 927, instance 1, run: true Nov 29 11:11:05 volumio volumio[30479]: info: camilladsp spawned new process with pid 935, instance 1, run: true Nov 29 11:11:05 volumio volumio[30479]: info: camilladsp spawned new process with pid 944, instance 1, run: true Nov 29 11:11:05 volumio volumio[30479]: info: camilladsp spawned new process with pid 953, instance 1, run: true Nov 29 11:11:05 volumio volumio[30479]: info: camilladsp spawned new process with pid 961, instance 1, run: true Nov 29 11:11:05 volumio volumio[30479]: info: camilladsp spawned new process with pid 969, instance 1, run: true Nov 29 11:11:05 volumio volumio[30479]: info: camilladsp spawned new process with pid 977, instance 1, run: true Nov 29 11:11:05 volumio volumio[30479]: info: camilladsp spawned new process with pid 985, instance 1, run: true Nov 29 11:11:05 volumio volumio[30479]: info: camilladsp spawned new process with pid 993, instance 1, run: true Nov 29 11:11:05 volumio volumio[30479]: info: camilladsp spawned new process with pid 1001, instance 1, run: true Nov 29 11:11:06 volumio volumio[30479]: info: camilladsp spawned new process with pid 1009, instance 1, run: true Nov 29 11:11:06 volumio volumio[30479]: info: camilladsp spawned new process with pid 1017, instance 1, run: true Nov 29 11:11:06 volumio volumio[30479]: info: camilladsp spawned new process with pid 1025, instance 1, run: true Nov 29 11:11:06 volumio volumio[30479]: info: camilladsp spawned new process with pid 1033, instance 1, run: true Nov 29 11:11:06 volumio volumio[30479]: info: camilladsp spawned new process with pid 1041, instance 1, run: true Nov 29 11:11:06 volumio volumio[30479]: info: camilladsp spawned new process with pid 1049, instance 1, run: true Nov 29 11:11:06 volumio volumio[30479]: info: camilladsp spawned new process with pid 1057, instance 1, run: true Nov 29 11:11:06 volumio volumio[30479]: info: camilladsp spawned new process with pid 1066, instance 1, run: true Nov 29 11:11:06 volumio volumio[30479]: info: camilladsp spawned new process with pid 1075, instance 1, run: true Nov 29 11:11:06 volumio volumio[30479]: info: camilladsp spawned new process with pid 1083, instance 1, run: true Nov 29 11:11:06 volumio volumio[30479]: info: camilladsp spawned new process with pid 1098, instance 1, run: true Nov 29 11:11:07 volumio volumio[30479]: info: camilladsp spawned new process with pid 1106, instance 1, run: true Nov 29 11:11:07 volumio volumio[30479]: info: camilladsp spawned new process with pid 1119, instance 1, run: true Nov 29 11:11:07 volumio volumio[30479]: info: camilladsp spawned new process with pid 1127, instance 1, run: true Nov 29 11:11:07 volumio volumio[30479]: info: camilladsp spawned new process with pid 1135, instance 1, run: true Nov 29 11:11:07 volumio volumio[30479]: info: camilladsp spawned new process with pid 1143, instance 1, run: true Nov 29 11:11:07 volumio volumio[30479]: info: camilladsp spawned new process with pid 1151, instance 1, run: true Nov 29 11:11:07 volumio volumio[30479]: info: camilladsp spawned new process with pid 1159, instance 1, run: true Nov 29 11:11:07 volumio volumio[30479]: info: camilladsp spawned new process with pid 1167, instance 1, run: true Nov 29 11:11:07 volumio volumio[30479]: info: camilladsp spawned new process with pid 1175, instance 1, run: true Nov 29 11:11:07 volumio volumio[30479]: info: camilladsp spawned new process with pid 1183, instance 1, run: true Nov 29 11:11:07 volumio volumio[30479]: info: camilladsp spawned new process with pid 1191, instance 1, run: true Nov 29 11:11:08 volumio volumio[30479]: info: camilladsp spawned new process with pid 1199, instance 1, run: true Nov 29 11:11:08 volumio volumio[30479]: info: camilladsp spawned new process with pid 1207, instance 1, run: true Nov 29 11:11:08 volumio volumio[30479]: info: camilladsp spawned new process with pid 1215, instance 1, run: true Nov 29 11:11:08 volumio volumio[30479]: info: camilladsp spawned new process with pid 1223, instance 1, run: true Nov 29 11:11:08 volumio volumio[30479]: info: camilladsp spawned new process with pid 1231, instance 1, run: true Nov 29 11:11:08 volumio volumio[30479]: info: camilladsp spawned new process with pid 1239, instance 1, run: true Nov 29 11:11:08 volumio volumio[30479]: info: camilladsp spawned new process with pid 1247, instance 1, run: true Nov 29 11:11:08 volumio volumio[30479]: info: camilladsp spawned new process with pid 1255, instance 1, run: true Nov 29 11:11:08 volumio volumio[30479]: info: camilladsp spawned new process with pid 1263, instance 1, run: true Nov 29 11:11:08 volumio volumio[30479]: info: camilladsp spawned new process with pid 1271, instance 1, run: true Nov 29 11:11:08 volumio volumio[30479]: info: camilladsp spawned new process with pid 1279, instance 1, run: true Nov 29 11:11:09 volumio volumio[30479]: info: camilladsp spawned new process with pid 1287, instance 1, run: true Nov 29 11:11:09 volumio volumio[30479]: info: camilladsp spawned new process with pid 1296, instance 1, run: true Nov 29 11:11:09 volumio volumio[30479]: info: camilladsp spawned new process with pid 1304, instance 1, run: true Nov 29 11:11:09 volumio volumio[30479]: info: camilladsp spawned new process with pid 1312, instance 1, run: true Nov 29 11:11:09 volumio volumio[30479]: info: camilladsp spawned new process with pid 1320, instance 1, run: true Nov 29 11:11:09 volumio volumio[30479]: info: camilladsp spawned new process with pid 1328, instance 1, run: true Nov 29 11:11:09 volumio volumio[30479]: info: camilladsp spawned new process with pid 1338, instance 1, run: true Nov 29 11:11:09 volumio volumio[30479]: info: camilladsp spawned new process with pid 1346, instance 1, run: true Nov 29 11:11:09 volumio volumio[30479]: info: camilladsp spawned new process with pid 1354, instance 1, run: true Nov 29 11:11:09 volumio volumio[30479]: info: camilladsp spawned new process with pid 1362, instance 1, run: true Nov 29 11:11:09 volumio volumio[30479]: info: camilladsp spawned new process with pid 1379, instance 1, run: true Nov 29 11:11:10 volumio volumio[30479]: info: camilladsp spawned new process with pid 1389, instance 1, run: true Nov 29 11:11:10 volumio volumio[30479]: info: camilladsp spawned new process with pid 1397, instance 1, run: true Nov 29 11:11:10 volumio volumio[30479]: info: camilladsp spawned new process with pid 1405, instance 1, run: true Nov 29 11:11:10 volumio volumio[30479]: info: camilladsp spawned new process with pid 1414, instance 1, run: true Nov 29 11:11:10 volumio volumio[30479]: info: camilladsp spawned new process with pid 1424, instance 1, run: true Nov 29 11:11:10 volumio volumio[30479]: info: camilladsp spawned new process with pid 1432, instance 1, run: true Nov 29 11:11:10 volumio volumio[30479]: info: camilladsp spawned new process with pid 1440, instance 1, run: true Nov 29 11:11:10 volumio volumio[30479]: info: camilladsp spawned new process with pid 1449, instance 1, run: true Nov 29 11:11:10 volumio volumio[30479]: info: camilladsp spawned new process with pid 1458, instance 1, run: true Nov 29 11:11:10 volumio volumio[30479]: info: camilladsp spawned new process with pid 1469, instance 1, run: true Nov 29 11:11:10 volumio volumio[30479]: info: camilladsp spawned new process with pid 1477, instance 1, run: true Nov 29 11:11:11 volumio volumio[30479]: info: camilladsp spawned new process with pid 1510, instance 1, run: true Nov 29 11:11:11 volumio volumio[30479]: info: camilladsp spawned new process with pid 1525, instance 1, run: true Nov 29 11:11:11 volumio volumio[30479]: info: camilladsp spawned new process with pid 1543, instance 1, run: true Nov 29 11:11:11 volumio volumio[30479]: info: camilladsp spawned new process with pid 1553, instance 1, run: true Nov 29 11:11:11 volumio volumio[30479]: info: camilladsp spawned new process with pid 1561, instance 1, run: true Nov 29 11:11:11 volumio volumio[30479]: info: camilladsp spawned new process with pid 1571, instance 1, run: true Nov 29 11:11:11 volumio volumio[30479]: info: camilladsp spawned new process with pid 1579, instance 1, run: true Nov 29 11:11:11 volumio volumio[30479]: info: camilladsp spawned new process with pid 1596, instance 1, run: true Nov 29 11:11:11 volumio volumio[30479]: info: camilladsp spawned new process with pid 1615, instance 1, run: true Nov 29 11:11:11 volumio volumio[30479]: info: camilladsp spawned new process with pid 1623, instance 1, run: true Nov 29 11:11:11 volumio volumio[30479]: info: camilladsp spawned new process with pid 1631, instance 1, run: true Nov 29 11:11:12 volumio volumio[30479]: info: camilladsp spawned new process with pid 1639, instance 1, run: true Nov 29 11:11:12 volumio volumio[30479]: info: camilladsp spawned new process with pid 1647, instance 1, run: true Nov 29 11:11:12 volumio volumio[30479]: info: camilladsp spawned new process with pid 1655, instance 1, run: true Nov 29 11:11:12 volumio volumio[30479]: info: camilladsp spawned new process with pid 1663, instance 1, run: true Nov 29 11:11:12 volumio volumio[30479]: info: camilladsp spawned new process with pid 1671, instance 1, run: true Nov 29 11:11:12 volumio volumio[30479]: info: camilladsp spawned new process with pid 1679, instance 1, run: true Nov 29 11:11:12 volumio volumio[30479]: info: camilladsp spawned new process with pid 1687, instance 1, run: true Nov 29 11:11:12 volumio volumio[30479]: info: camilladsp spawned new process with pid 1695, instance 1, run: true Nov 29 11:11:12 volumio volumio[30479]: info: camilladsp spawned new process with pid 1703, instance 1, run: true Nov 29 11:11:12 volumio volumio[30479]: info: camilladsp spawned new process with pid 1711, instance 1, run: true Nov 29 11:11:12 volumio volumio[30479]: info: camilladsp spawned new process with pid 1719, instance 1, run: true Nov 29 11:11:13 volumio volumio[30479]: info: camilladsp spawned new process with pid 1727, instance 1, run: true Nov 29 11:11:13 volumio volumio[30479]: info: camilladsp spawned new process with pid 1735, instance 1, run: true Nov 29 11:11:13 volumio volumio[30479]: info: camilladsp spawned new process with pid 1743, instance 1, run: true Nov 29 11:11:13 volumio volumio[30479]: info: camilladsp spawned new process with pid 1751, instance 1, run: true Nov 29 11:11:13 volumio volumio[30479]: info: camilladsp spawned new process with pid 1760, instance 1, run: true Nov 29 11:11:13 volumio volumio[30479]: info: camilladsp spawned new process with pid 1768, instance 1, run: true Nov 29 11:11:13 volumio volumio[30479]: info: camilladsp spawned new process with pid 1776, instance 1, run: true Nov 29 11:11:13 volumio volumio[30479]: info: camilladsp spawned new process with pid 1785, instance 1, run: true Nov 29 11:11:13 volumio volumio[30479]: info: camilladsp spawned new process with pid 1793, instance 1, run: true Nov 29 11:11:13 volumio volumio[30479]: info: camilladsp spawned new process with pid 1810, instance 1, run: true Nov 29 11:11:13 volumio volumio[30479]: info: camilladsp spawned new process with pid 1820, instance 1, run: true Nov 29 11:11:14 volumio volumio[30479]: info: camilladsp spawned new process with pid 1828, instance 1, run: true Nov 29 11:11:14 volumio volumio[30479]: info: camilladsp spawned new process with pid 1836, instance 1, run: true Nov 29 11:11:14 volumio volumio[30479]: info: camilladsp spawned new process with pid 1844, instance 1, run: true Nov 29 11:11:14 volumio volumio[30479]: info: camilladsp spawned new process with pid 1852, instance 1, run: true Nov 29 11:11:14 volumio volumio[30479]: info: camilladsp spawned new process with pid 1860, instance 1, run: true Nov 29 11:11:14 volumio volumio[30479]: info: camilladsp spawned new process with pid 1868, instance 1, run: true Nov 29 11:11:14 volumio volumio[30479]: info: camilladsp spawned new process with pid 1876, instance 1, run: true Nov 29 11:11:14 volumio volumio[30479]: info: camilladsp spawned new process with pid 1884, instance 1, run: true Nov 29 11:11:14 volumio volumio[30479]: info: camilladsp spawned new process with pid 1892, instance 1, run: true Nov 29 11:11:14 volumio volumio[30479]: info: camilladsp spawned new process with pid 1900, instance 1, run: true Nov 29 11:11:14 volumio volumio[30479]: info: camilladsp spawned new process with pid 1908, instance 1, run: true Nov 29 11:11:15 volumio volumio[30479]: info: camilladsp spawned new process with pid 1916, instance 1, run: true Nov 29 11:11:15 volumio volumio[30479]: info: camilladsp spawned new process with pid 1924, instance 1, run: true Nov 29 11:11:15 volumio volumio[30479]: info: camilladsp spawned new process with pid 1932, instance 1, run: true Nov 29 11:11:15 volumio volumio[30479]: info: camilladsp spawned new process with pid 1940, instance 1, run: true Nov 29 11:11:15 volumio volumio[30479]: info: camilladsp spawned new process with pid 1948, instance 1, run: true Nov 29 11:11:15 volumio volumio[30479]: info: camilladsp spawned new process with pid 1956, instance 1, run: true Nov 29 11:11:15 volumio volumio[30479]: info: camilladsp spawned new process with pid 1964, instance 1, run: true Nov 29 11:11:15 volumio volumio[30479]: info: camilladsp spawned new process with pid 1972, instance 1, run: true Nov 29 11:11:15 volumio volumio[30479]: info: camilladsp spawned new process with pid 1980, instance 1, run: true Nov 29 11:11:15 volumio volumio[30479]: info: camilladsp spawned new process with pid 1988, instance 1, run: true Nov 29 11:11:15 volumio volumio[30479]: info: camilladsp spawned new process with pid 1996, instance 1, run: true Nov 29 11:11:16 volumio volumio[30479]: info: camilladsp spawned new process with pid 2004, instance 1, run: true Nov 29 11:11:16 volumio volumio[30479]: info: camilladsp spawned new process with pid 2012, instance 1, run: true Nov 29 11:11:16 volumio volumio[30479]: info: camilladsp spawned new process with pid 2020, instance 1, run: true Nov 29 11:11:16 volumio volumio[30479]: info: camilladsp spawned new process with pid 2028, instance 1, run: true Nov 29 11:11:16 volumio volumio[30479]: info: camilladsp spawned new process with pid 2036, instance 1, run: true Nov 29 11:11:16 volumio volumio[30479]: info: camilladsp spawned new process with pid 2044, instance 1, run: true Nov 29 11:11:16 volumio volumio[30479]: info: camilladsp spawned new process with pid 2052, instance 1, run: true Nov 29 11:11:16 volumio volumio[30479]: info: camilladsp spawned new process with pid 2061, instance 1, run: true Nov 29 11:11:16 volumio volumio[30479]: info: camilladsp spawned new process with pid 2069, instance 1, run: true Nov 29 11:11:16 volumio volumio[30479]: info: camilladsp spawned new process with pid 2077, instance 1, run: true Nov 29 11:11:16 volumio volumio[30479]: info: camilladsp spawned new process with pid 2085, instance 1, run: true Nov 29 11:11:17 volumio volumio[30479]: info: camilladsp spawned new process with pid 2093, instance 1, run: true Nov 29 11:11:17 volumio volumio[30479]: info: camilladsp spawned new process with pid 2101, instance 1, run: true Nov 29 11:11:17 volumio volumio[30479]: info: camilladsp spawned new process with pid 2109, instance 1, run: true Nov 29 11:11:17 volumio volumio[30479]: info: camilladsp spawned new process with pid 2133, instance 1, run: true Nov 29 11:11:17 volumio volumio[30479]: info: camilladsp spawned new process with pid 2166, instance 1, run: true Nov 29 11:11:17 volumio volumio[30479]: info: camilladsp spawned new process with pid 2174, instance 1, run: true Nov 29 11:11:17 volumio volumio[30479]: info: camilladsp spawned new process with pid 2182, instance 1, run: true Nov 29 11:11:17 volumio volumio[30479]: info: Preload queue cleared Nov 29 11:11:17 volumio volumio[30479]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 29 11:11:17 volumio volumio[30479]: info: CoreStateMachine::ClearQueue Nov 29 11:11:17 volumio volumio[30479]: info: CoreStateMachine::stop Nov 29 11:11:17 volumio volumio[30479]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 11:11:17 volumio volumio[30479]: info: CoreStateMachine::stPlaybackTimer Nov 29 11:11:17 volumio volumio[30479]: info: CoreStateMachine::updateTrackBlock Nov 29 11:11:17 volumio volumio[30479]: info: CorePlayQueue::getTrackBlock Nov 29 11:11:17 volumio volumio[30479]: info: CoreStateMachine::pushState Nov 29 11:11:17 volumio volumio[30479]: info: CorePlayQueue::getTrack 0 Nov 29 11:11:17 volumio volumio[30479]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 11:11:17 volumio volumio[30479]: info: CoreCommandRouter::volumioPushState Nov 29 11:11:17 volumio volumio[30479]: info: MRS: Pushing multiroomSync output update for this device Nov 29 11:11:17 volumio volumio[30479]: info: MRS: Pushing multiroomSync output Nov 29 11:11:17 volumio volumio[30479]: info: CoreStateMachine::serviceStop Nov 29 11:11:17 volumio volumio[30479]: info: CorePlayQueue::getTrack 0 Nov 29 11:11:17 volumio volumio[30479]: info: CoreCommandRouter::serviceStop Nov 29 11:11:17 volumio volumio[30479]: info: [1732878677571] ControllerUPNPBrowser::stop Nov 29 11:11:17 volumio volumio[30479]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 11:11:17 volumio volumio[30479]: info: CorePlayQueue::clearPlayQueue Nov 29 11:11:17 volumio volumio[30479]: info: CorePlayQueue::saveQueue Nov 29 11:11:17 volumio volumio[30479]: info: CoreCommandRouter::volumioPushQueue Nov 29 11:11:17 volumio volumio[30479]: info: CoreStateMachine::addQueueItems Nov 29 11:11:17 volumio volumio[30479]: info: CorePlayQueue::addQueueItems Nov 29 11:11:17 volumio volumio[30479]: info: Preload queue cleared Nov 29 11:11:17 volumio volumio[30479]: info: Adding Item to queue: upnp/http://192.168.1.127:50001/ContentDirectory/control@22$@37897 Nov 29 11:11:17 volumio volumio[30479]: info: Using cached record of: upnp/http://192.168.1.127:50001/ContentDirectory/control@22$@37897 Nov 29 11:11:17 volumio volumio[30479]: info: Adding Item to queue: upnp/http://192.168.1.127:50001/ContentDirectory/control@22$@37898 Nov 29 11:11:17 volumio volumio[30479]: info: Using cached record of: upnp/http://192.168.1.127:50001/ContentDirectory/control@22$@37898 Nov 29 11:11:17 volumio volumio[30479]: info: Adding Item to queue: upnp/http://192.168.1.127:50001/ContentDirectory/control@22$@37899 Nov 29 11:11:17 volumio volumio[30479]: info: Using cached record of: upnp/http://192.168.1.127:50001/ContentDirectory/control@22$@37899 Nov 29 11:11:17 volumio volumio[30479]: info: Adding Item to queue: upnp/http://192.168.1.127:50001/ContentDirectory/control@22$@37900 Nov 29 11:11:17 volumio volumio[30479]: info: Using cached record of: upnp/http://192.168.1.127:50001/ContentDirectory/control@22$@37900 Nov 29 11:11:17 volumio volumio[30479]: info: Adding Item to queue: upnp/http://192.168.1.127:50001/ContentDirectory/control@22$@37901 Nov 29 11:11:17 volumio volumio[30479]: info: Using cached record of: upnp/http://192.168.1.127:50001/ContentDirectory/control@22$@37901 Nov 29 11:11:17 volumio volumio[30479]: info: Adding Item to queue: upnp/http://192.168.1.127:50001/ContentDirectory/control@22$@37902 Nov 29 11:11:17 volumio volumio[30479]: info: Using cached record of: upnp/http://192.168.1.127:50001/ContentDirectory/control@22$@37902 Nov 29 11:11:17 volumio volumio[30479]: info: Adding Item to queue: upnp/http://192.168.1.127:50001/ContentDirectory/control@22$@37903 Nov 29 11:11:17 volumio volumio[30479]: info: Using cached record of: upnp/http://192.168.1.127:50001/ContentDirectory/control@22$@37903 Nov 29 11:11:17 volumio volumio[30479]: info: Adding Item to queue: upnp/http://192.168.1.127:50001/ContentDirectory/control@22$@37904 Nov 29 11:11:17 volumio volumio[30479]: info: Using cached record of: upnp/http://192.168.1.127:50001/ContentDirectory/control@22$@37904 Nov 29 11:11:17 volumio volumio[30479]: info: Adding Item to queue: upnp/http://192.168.1.127:50001/ContentDirectory/control@22$@37905 Nov 29 11:11:17 volumio volumio[30479]: info: Using cached record of: upnp/http://192.168.1.127:50001/ContentDirectory/control@22$@37905 Nov 29 11:11:17 volumio volumio[30479]: info: CoreCommandRouter::volumioPushQueue Nov 29 11:11:17 volumio volumio[30479]: info: CorePlayQueue::saveQueue Nov 29 11:11:17 volumio volumio[30479]: info: CoreStateMachine::updateTrackBlock Nov 29 11:11:17 volumio volumio[30479]: info: CorePlayQueue::getTrackBlock Nov 29 11:11:17 volumio volumio[30479]: info: CoreCommandRouter::volumioPlay Nov 29 11:11:17 volumio volumio[30479]: info: CoreStateMachine::play index 1 Nov 29 11:11:17 volumio volumio[30479]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 11:11:17 volumio volumio[30479]: info: CoreStateMachine::stop Nov 29 11:11:17 volumio volumio[30479]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 11:11:17 volumio volumio[30479]: info: CoreStateMachine::play index undefined Nov 29 11:11:17 volumio volumio[30479]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 11:11:17 volumio volumio[30479]: info: CorePlayQueue::getTrack 1 Nov 29 11:11:17 volumio volumio[30479]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 29 11:11:17 volumio volumio[30479]: info: Nov 29 11:11:17 volumio volumio[30479]: ---------------------------- MPD announces state update: player Nov 29 11:11:17 volumio volumio[30479]: info: sendMpdCommand stop took 73 milliseconds Nov 29 11:11:17 volumio volumio[30479]: info: ControllerMpd::getState Nov 29 11:11:17 volumio volumio[30479]: verbose: ControllerMpd::sendMpdCommand status Nov 29 11:11:17 volumio sudo[2191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 29 11:11:17 volumio sudo[2191]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 11:11:17 volumio systemd[1]: Stopping peppymeterbasic Daemon... Nov 29 11:11:17 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Nov 29 11:11:17 volumio volumio[30479]: info: sendMpdCommand status took 56 milliseconds Nov 29 11:11:17 volumio volumio[30479]: verbose: ControllerMpd::parseState Nov 29 11:11:17 volumio volumio[30479]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 11:11:17 volumio volumio[30479]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 29 11:11:17 volumio volumio[30479]: verbose: ControllerMpd::parseTrackInfo Nov 29 11:11:17 volumio volumio[30479]: info: ControllerMpd::pushState Nov 29 11:11:17 volumio volumio[30479]: info: CoreCommandRouter::servicePushState Nov 29 11:11:17 volumio volumio[30479]: info: CoreStateMachine::pushState Nov 29 11:11:17 volumio volumio[30479]: info: CorePlayQueue::getTrack 0 Nov 29 11:11:17 volumio volumio[30479]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 11:11:17 volumio volumio[30479]: info: CoreCommandRouter::volumioPushState Nov 29 11:11:17 volumio volumio[30479]: info: MRS: Pushing multiroomSync output update for this device Nov 29 11:11:17 volumio volumio[30479]: info: MRS: Pushing multiroomSync output Nov 29 11:11:17 volumio volumio[30479]: info: CorePlayQueue::getTrack 0 Nov 29 11:11:17 volumio volumio[30479]: 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":"37897.dsf","artist":null,"album":null,"uri":"http://192.168.1.127:50002/m/NDLNA/37897.dsf","trackType":"dsf"} Nov 29 11:11:17 volumio volumio[30479]: verbose: CURRENT POSITION 0 Nov 29 11:11:17 volumio volumio[30479]: info: CoreStateMachine::syncState stateService stop Nov 29 11:11:17 volumio volumio[30479]: info: CoreStateMachine::syncState currentStatus stop Nov 29 11:11:17 volumio volumio[30479]: info: CoreStateMachine::pushState Nov 29 11:11:17 volumio volumio[30479]: info: CorePlayQueue::getTrack 0 Nov 29 11:11:17 volumio volumio[30479]: info: CoreCommandRouter::volumioPushState Nov 29 11:11:17 volumio volumio[30479]: info: MRS: Pushing multiroomSync output update for this device Nov 29 11:11:17 volumio volumio[30479]: info: MRS: Pushing multiroomSync output Nov 29 11:11:17 volumio volumio[30479]: info: No code Nov 29 11:11:17 volumio volumio[30479]: info: CoreStateMachine::pushState Nov 29 11:11:17 volumio volumio[30479]: info: CorePlayQueue::getTrack 0 Nov 29 11:11:17 volumio volumio[30479]: info: CoreCommandRouter::volumioPushState Nov 29 11:11:17 volumio volumio[30479]: info: MRS: Pushing multiroomSync output update for this device Nov 29 11:11:17 volumio volumio[30479]: info: MRS: Pushing multiroomSync output Nov 29 11:11:17 volumio systemd[1]: peppymeterbasic.service: Succeeded. Nov 29 11:11:17 volumio systemd[1]: Stopped peppymeterbasic Daemon. Nov 29 11:11:17 volumio sudo[2191]: pam_unix(sudo:session): session closed for user root Nov 29 11:11:17 volumio volumio[30479]: info: ------------------------------ 117ms Nov 29 11:11:17 volumio volumio[30479]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 29 11:11:17 volumio volumio[30479]: info: camilladsp spawned new process with pid 2195, instance 1, run: true Nov 29 11:11:17 volumio volumio[30479]: info: peppymeterbasic Daemon Stop Nov 29 11:11:17 volumio volumio[30479]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 29 11:11:17 volumio volumio[30479]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 29 11:11:17 volumio sudo[2204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 29 11:11:17 volumio sudo[2204]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 11:11:17 volumio volumio[30479]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 29 11:11:17 volumio sudo[2204]: pam_unix(sudo:session): session closed for user root Nov 29 11:11:17 volumio volumio[30479]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 29 11:11:17 volumio sudo[2207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 29 11:11:17 volumio sudo[2207]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 11:11:17 volumio sudo[2207]: pam_unix(sudo:session): session closed for user root Nov 29 11:11:17 volumio sudo[2210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 29 11:11:17 volumio sudo[2210]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 11:11:18 volumio volumio[30479]: info: peppymeterbasic Daemon Stop Nov 29 11:11:18 volumio volumio[30479]: info: peppymeterbasic Daemon Stop Nov 29 11:11:18 volumio sudo[2210]: pam_unix(sudo:session): session closed for user root Nov 29 11:11:18 volumio volumio[30479]: info: peppymeterbasic Daemon Stop Nov 29 11:11:26 volumio volumio[30479]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 29 11:11:26 volumio volumio[30479]: info: Preload queue cleared Nov 29 11:11:30 volumio volumio[30479]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 29 11:11:30 volumio volumio[30479]: info: Preload queue cleared Nov 29 11:11:32 volumio volumio[30479]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:34 volumio volumio[30479]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 29 11:11:34 volumio volumio[30479]: info: Preload queue cleared Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@23872 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@36205 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@36206 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@36207 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@36208 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@36209 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@36210 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@36211 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@36212 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@36213 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@36214 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@21235 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@21236 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@21237 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@21238 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@21239 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@21240 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@21241 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@21242 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@21243 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@21244 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@21245 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@21246 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@21247 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28685 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28793 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28661 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28686 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28794 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28662 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28687 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28795 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28663 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28688 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28796 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28664 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28689 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28797 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28665 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28690 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28798 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28666 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28691 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28799 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28667 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28692 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28800 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28668 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28693 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28801 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28669 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28694 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28802 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28670 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28695 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28803 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28671 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28696 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28804 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28672 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28805 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28806 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28807 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28808 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28817 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28818 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28819 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28820 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28821 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28822 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28823 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28824 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28825 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28826 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28827 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28828 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28829 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28830 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28831 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28832 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28833 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@28834 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@22421 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@22422 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@22423 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@22424 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@22425 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@22426 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@22427 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@22428 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@22429 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@22430 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@22431 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@22432 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@22433 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@22434 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@22435 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@38588 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@27188 Nov 29 11:11:34 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@23$@38589 Nov 29 11:11:34 volumio volumio[30479]: info: Preload queue cleared Nov 29 11:11:35 volumio volumio[30479]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 29 11:11:35 volumio volumio[30479]: Cannot compose Albumart path Nov 29 11:11:36 volumio volumio[30479]: info: Preload queue cleared Nov 29 11:11:38 volumio volumio[30479]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 29 11:11:39 volumio volumio[30479]: info: Preload queue cleared Nov 29 11:11:42 volumio volumio[30479]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 29 11:11:42 volumio volumio[30479]: info: Preload queue cleared Nov 29 11:11:42 volumio volumio[30479]: info: Preloading song: upnp/http://192.168.1.127:50001/ContentDirectory/control@22$@20562 Nov 29 11:11:42 volumio volumio[30479]: info: Exploding uri upnp/http://192.168.1.127:50001/ContentDirectory/control@22$@20562 in service upnp_browser Nov 29 11:11:45 volumio volumio[30479]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Queen/A%20Kind%20Of%20Magic/0e011658-8ce9-4f4f-89c1-e3ee9232f195.png' Nov 29 11:11:45 volumio volumio[30479]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Queen/A%20Kind%20Of%20Magic/62bbd663-c355-4317-9bc0-12b2304316fd.png' Nov 29 11:11:46 volumio volumio[30479]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Cyndi%20Lauper/A%20Night%20To%20Remember/67259e21-ffbd-47a2-8176-7b3e733da7a1.png' Nov 29 11:11:46 volumio volumio[30479]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Cyndi%20Lauper/A%20Night%20To%20Remember/b41f580b-5e28-4797-88b0-1946a87b70be.png' Nov 29 11:11:46 volumio volumio[30479]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Cyndi%20Lauper/A%20Night%20To%20Remember/b41f580b-5e28-4797-88b0-1946a87b70be.png' Nov 29 11:11:46 volumio volumio[30479]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Cyndi%20Lauper/A%20Night%20To%20Remember/dd559076-a51f-45bb-824c-76b92ab80009.png' Nov 29 11:11:46 volumio volumio[30479]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Cyndi%20Lauper/A%20Night%20To%20Remember/dd559076-a51f-45bb-824c-76b92ab80009.png' Nov 29 11:11:46 volumio volumio[30479]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Cyndi%20Lauper/A%20Night%20To%20Remember/ed07cbba-5d2f-4bcf-b8d1-2fde897bb1bb.png' Nov 29 11:11:46 volumio volumio[30479]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Cyndi%20Lauper/A%20Night%20To%20Remember/ed07cbba-5d2f-4bcf-b8d1-2fde897bb1bb.png' Nov 29 11:11:48 volumio volumio[30479]: info: Preload queue cleared Nov 29 11:11:48 volumio volumio[30479]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 29 11:11:48 volumio volumio[30479]: info: CoreStateMachine::ClearQueue Nov 29 11:11:48 volumio volumio[30479]: info: CoreStateMachine::stop Nov 29 11:11:48 volumio volumio[30479]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 11:11:48 volumio volumio[30479]: info: CorePlayQueue::clearPlayQueue Nov 29 11:11:48 volumio volumio[30479]: info: CorePlayQueue::saveQueue Nov 29 11:11:48 volumio volumio[30479]: info: CoreCommandRouter::volumioPushQueue Nov 29 11:11:48 volumio volumio[30479]: info: CoreStateMachine::addQueueItems Nov 29 11:11:48 volumio volumio[30479]: info: CorePlayQueue::addQueueItems Nov 29 11:11:48 volumio volumio[30479]: info: Preload queue cleared Nov 29 11:11:48 volumio volumio[30479]: info: Adding Item to queue: upnp/folder/http://192.168.1.127:50001/ContentDirectory/control@22$2384 Nov 29 11:11:48 volumio volumio[30479]: info: Exploding uri upnp/folder/http://192.168.1.127:50001/ContentDirectory/control@22$2384 in service upnp_browser Nov 29 11:11:48 volumio volumio[30479]: info: Adding Item to queue: upnp/http://192.168.1.127:50001/ContentDirectory/control@22$@20562 Nov 29 11:11:48 volumio volumio[30479]: info: Using cached record of: upnp/http://192.168.1.127:50001/ContentDirectory/control@22$@20562 Nov 29 11:11:48 volumio volumio[30479]: info: CoreCommandRouter::volumioPushQueue Nov 29 11:11:48 volumio volumio[30479]: info: CorePlayQueue::saveQueue Nov 29 11:11:48 volumio volumio[30479]: info: CoreStateMachine::updateTrackBlock Nov 29 11:11:48 volumio volumio[30479]: info: CorePlayQueue::getTrackBlock Nov 29 11:11:48 volumio volumio[30479]: info: CoreCommandRouter::volumioPlay Nov 29 11:11:48 volumio volumio[30479]: info: CoreStateMachine::play index 1 Nov 29 11:11:48 volumio volumio[30479]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 11:11:48 volumio volumio[30479]: info: CoreStateMachine::stop Nov 29 11:11:48 volumio volumio[30479]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 11:11:48 volumio volumio[30479]: info: CoreStateMachine::play index undefined Nov 29 11:11:48 volumio volumio[30479]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 11:11:48 volumio volumio[30479]: info: CorePlayQueue::getTrack 1 Nov 29 11:11:53 volumio volumio[30479]: info: CoreCommandRouter::volumioPlay Nov 29 11:11:53 volumio volumio[30479]: info: CoreStateMachine::play index undefined Nov 29 11:11:53 volumio volumio[30479]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 11:11:53 volumio volumio[30479]: info: CorePlayQueue::getTrack 0 Nov 29 11:11:54 volumio volumio[30479]: info: CoreCommandRouter::volumioPlay Nov 29 11:11:54 volumio volumio[30479]: info: CoreStateMachine::play index undefined Nov 29 11:11:54 volumio volumio[30479]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 11:11:54 volumio volumio[30479]: info: CorePlayQueue::getTrack 0 Nov 29 11:12:02 volumio volumio[30479]: info: CoreCommandRouter::volumioGetState Nov 29 11:12:02 volumio volumio[30479]: info: CorePlayQueue::getTrack 0 Nov 29 11:12:08 volumio volumio[30479]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 29 11:12:08 volumio volumio[30479]: info: CURURI: music-library Nov 29 11:12:08 volumio volumio[30479]: info: Preload queue cleared Nov 29 11:12:13 volumio volumio[30479]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 29 11:12:13 volumio volumio[30479]: info: CURURI: music-library/NAS Nov 29 11:12:13 volumio volumio[30479]: info: Preload queue cleared Nov 29 11:12:14 volumio volumio[30479]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 29 11:12:14 volumio volumio[30479]: info: CURURI: music-library/NAS/LMS Nov 29 11:12:14 volumio volumio[30479]: info: Preload queue cleared Nov 29 11:12:18 volumio volumio[30479]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 29 11:12:18 volumio volumio[30479]: info: CURURI: music-library/NAS/LMS/02 FLAC Nov 29 11:12:18 volumio volumio[30479]: info: Preload queue cleared Nov 29 11:12:18 volumio volumio[30479]: info: Preloading song: music-library/NAS/LMS/02 FLAC/000 baby, sleeping running bath.flac Nov 29 11:12:18 volumio volumio[30479]: info: Preloading song: music-library/NAS/LMS/02 FLAC/Aaa111Listening session comparing CD transports.WAV Nov 29 11:12:18 volumio volumio[30479]: info: Exploding uri music-library/NAS/LMS/02 FLAC/000 baby, sleeping running bath.flac in service mpd Nov 29 11:12:18 volumio volumio[30479]: info: ALBUMART /albumart?cacheid=743&web=//extralarge&path=%2Fmnt%2FNAS%2FLMS%2F02%20FLAC%2F000%20baby%2C%20sleeping%20running%20bath.flac&metadata=false Nov 29 11:12:18 volumio volumio[30479]: info: URI /mnt/NAS/LMS/02 FLAC/000 baby, sleeping running bath.flac Nov 29 11:12:18 volumio volumio[30479]: info: Exploding uri music-library/NAS/LMS/02 FLAC/Aaa111Listening session comparing CD transports.WAV in service mpd Nov 29 11:12:18 volumio volumio[30479]: info: ALBUMART /albumart?cacheid=743&web=//extralarge&path=%2Fmnt%2FNAS%2FLMS%2F02%20FLAC%2FAaa111Listening%20session%20comparing%20CD%20transports.WAV&metadata=false Nov 29 11:12:18 volumio volumio[30479]: info: URI /mnt/NAS/LMS/02 FLAC/Aaa111Listening session comparing CD transports.WAV Nov 29 11:12:26 volumio volumio[30479]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 29 11:12:26 volumio volumio[30479]: info: CURURI: music-library/NAS/LMS/02 FLAC/01 CLASSICAL FLACS Nov 29 11:12:26 volumio volumio[30479]: info: Preload queue cleared Nov 29 11:12:31 volumio volumio[30479]: info: Preload queue cleared Nov 29 11:12:31 volumio volumio[30479]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 29 11:12:31 volumio volumio[30479]: info: CoreStateMachine::ClearQueue Nov 29 11:12:31 volumio volumio[30479]: info: CoreStateMachine::stop Nov 29 11:12:31 volumio volumio[30479]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 11:12:31 volumio volumio[30479]: info: CorePlayQueue::clearPlayQueue Nov 29 11:12:31 volumio volumio[30479]: info: CorePlayQueue::saveQueue Nov 29 11:12:31 volumio volumio[30479]: info: CoreCommandRouter::volumioPushQueue Nov 29 11:12:31 volumio volumio[30479]: info: CoreStateMachine::addQueueItems Nov 29 11:12:31 volumio volumio[30479]: info: CorePlayQueue::addQueueItems Nov 29 11:12:31 volumio volumio[30479]: info: Preload queue cleared Nov 29 11:12:31 volumio volumio[30479]: info: Adding Item to queue: music-library/NAS/LMS/02 FLAC/01 CLASSICAL FLACS/Caccini - L'Euridice Nov 29 11:12:31 volumio volumio[30479]: info: Exploding uri music-library/NAS/LMS/02 FLAC/01 CLASSICAL FLACS/Caccini - L'Euridice in service mpd Nov 29 11:12:31 volumio volumio[30479]: info: ALBUMART /albumart?cacheid=743&web=Concerto%20Italiano%20-%20Rinaldo%20Alessandrini/Caccini%20-%20L'Euridice/extralarge&path=%2Fmnt%2FNAS%2FLMS%2F02%20FLAC%2F01%20CLASSICAL%20FLACS%2FCaccini%20-%20L'Euridice%2FCaccini%20-%20L'Euridice.cue&metadata=false Nov 29 11:12:31 volumio volumio[30479]: info: URI /mnt/NAS/LMS/02 FLAC/01 CLASSICAL FLACS/Caccini - L'Euridice/Caccini - L'Euridice.cue Nov 29 11:12:31 volumio volumio[30479]: info: ALBUMART /albumart?cacheid=743&web=Concerto%20Italiano%20-%20Rinaldo%20Alessandrini/Caccini%20-%20L'Euridice/extralarge&path=%2Fmnt%2FNAS%2FLMS%2F02%20FLAC%2F01%20CLASSICAL%20FLACS%2FCaccini%20-%20L'Euridice%2FCaccini%20-%20L'Euridice.cue&metadata=false Nov 29 11:12:31 volumio volumio[30479]: info: URI /mnt/NAS/LMS/02 FLAC/01 CLASSICAL FLACS/Caccini - L'Euridice/Caccini - L'Euridice.cue Nov 29 11:12:31 volumio volumio[30479]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 11:12:31 volumio volumio[30479]: Error: Unable to resolve or reject the same promise twice Nov 29 11:12:31 volumio volumio[30479]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Nov 29 11:12:31 volumio volumio[30479]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Nov 29 11:12:31 volumio volumio[30479]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Nov 29 11:12:31 volumio volumio[30479]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Nov 29 11:12:31 volumio volumio[30479]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Nov 29 11:12:31 volumio volumio[30479]: at Socket.emit (events.js:315:20) Nov 29 11:12:31 volumio volumio[30479]: at addChunk (internal/streams/readable.js:309:12) Nov 29 11:12:31 volumio volumio[30479]: at readableAddChunk (internal/streams/readable.js:280:11) Nov 29 11:12:31 volumio volumio[30479]: at Socket.Readable.push (internal/streams/readable.js:223:10) Nov 29 11:12:31 volumio volumio[30479]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Nov 29 11:12:31 volumio volumio[30479]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 11:12:31 volumio sudo[2268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-29 11:11 Nov 29 11:12:31 volumio sudo[2268]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"