-- Logs begin at Sat 2024-11-23 06:34:16 CET, end at Sat 2024-11-23 07:20:53 CET. -- Nov 23 07:19:00 rivo volumio[19717]: info: camilladsp spawned new process with pid 15317, instance 2, run: true Nov 23 07:19:00 rivo volumio[19717]: info: camilladsp spawned new process with pid 15324, instance 2, run: true Nov 23 07:19:00 rivo volumio[19717]: info: camilladsp spawned new process with pid 15331, instance 2, run: true Nov 23 07:19:00 rivo volumio[19717]: info: camilladsp spawned new process with pid 15338, instance 2, run: true Nov 23 07:19:00 rivo volumio[19717]: info: camilladsp spawned new process with pid 15345, instance 2, run: true Nov 23 07:19:00 rivo volumio[19717]: info: camilladsp spawned new process with pid 15357, instance 2, run: true Nov 23 07:19:00 rivo volumio[19717]: info: camilladsp spawned new process with pid 15374, instance 2, run: true Nov 23 07:19:00 rivo volumio[19717]: info: camilladsp spawned new process with pid 15383, instance 2, run: true Nov 23 07:19:01 rivo volumio[19717]: info: camilladsp spawned new process with pid 15390, instance 2, run: true Nov 23 07:19:01 rivo volumio[19717]: info: camilladsp spawned new process with pid 15397, instance 2, run: true Nov 23 07:19:01 rivo volumio[19717]: info: camilladsp spawned new process with pid 15404, instance 2, run: true Nov 23 07:19:01 rivo volumio[19717]: info: camilladsp spawned new process with pid 15412, instance 2, run: true Nov 23 07:19:01 rivo volumio[19717]: info: camilladsp spawned new process with pid 15419, instance 2, run: true Nov 23 07:19:01 rivo volumio[19717]: info: camilladsp spawned new process with pid 15426, instance 2, run: true Nov 23 07:19:01 rivo volumio[19717]: info: camilladsp spawned new process with pid 15433, instance 2, run: true Nov 23 07:19:02 rivo volumio[19717]: info: camilladsp spawned new process with pid 15440, instance 2, run: true Nov 23 07:19:02 rivo volumio[19717]: info: camilladsp spawned new process with pid 15447, instance 2, run: true Nov 23 07:19:02 rivo volumio[19717]: info: camilladsp spawned new process with pid 15454, instance 2, run: true Nov 23 07:19:02 rivo volumio[19717]: info: camilladsp spawned new process with pid 15461, instance 2, run: true Nov 23 07:19:02 rivo volumio[19717]: info: camilladsp spawned new process with pid 15468, instance 2, run: true Nov 23 07:19:02 rivo volumio[19717]: info: camilladsp spawned new process with pid 15475, instance 2, run: true Nov 23 07:19:02 rivo volumio[19717]: info: camilladsp spawned new process with pid 15482, instance 2, run: true Nov 23 07:19:02 rivo volumio[19717]: info: camilladsp spawned new process with pid 15489, instance 2, run: true Nov 23 07:19:02 rivo volumio[19717]: info: camilladsp spawned new process with pid 15496, instance 2, run: true Nov 23 07:19:03 rivo volumio[19717]: info: camilladsp spawned new process with pid 15503, instance 2, run: true Nov 23 07:19:03 rivo volumio[19717]: info: camilladsp spawned new process with pid 15510, instance 2, run: true Nov 23 07:19:03 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Nov 23 07:19:03 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand update Nov 23 07:19:03 rivo volumio[19717]: info: camilladsp spawned new process with pid 15517, instance 2, run: true Nov 23 07:19:03 rivo volumio[19717]: info: camilladsp spawned new process with pid 15524, instance 2, run: true Nov 23 07:19:03 rivo volumio[19717]: info: camilladsp spawned new process with pid 15531, instance 2, run: true Nov 23 07:19:03 rivo volumio[19717]: info: camilladsp spawned new process with pid 15538, instance 2, run: true Nov 23 07:19:03 rivo volumio[19717]: info: camilladsp spawned new process with pid 15545, instance 2, run: true Nov 23 07:19:03 rivo volumio[19717]: info: camilladsp spawned new process with pid 15552, instance 2, run: true Nov 23 07:19:03 rivo volumio[19717]: info: camilladsp spawned new process with pid 15559, instance 2, run: true Nov 23 07:19:03 rivo volumio[19717]: info: camilladsp spawned new process with pid 15566, instance 2, run: true Nov 23 07:19:04 rivo volumio[19717]: info: camilladsp spawned new process with pid 15573, instance 2, run: true Nov 23 07:19:04 rivo volumio[19717]: info: camilladsp spawned new process with pid 15580, instance 2, run: true Nov 23 07:19:04 rivo volumio[19717]: info: camilladsp spawned new process with pid 15587, instance 2, run: true Nov 23 07:19:04 rivo volumio[19717]: info: camilladsp spawned new process with pid 15594, instance 2, run: true Nov 23 07:19:04 rivo volumio[19717]: info: camilladsp spawned new process with pid 15601, instance 2, run: true Nov 23 07:19:04 rivo volumio[19717]: info: camilladsp spawned new process with pid 15608, instance 2, run: true Nov 23 07:19:04 rivo volumio[19717]: info: camilladsp spawned new process with pid 15615, instance 2, run: true Nov 23 07:19:04 rivo volumio[19717]: info: camilladsp spawned new process with pid 15622, instance 2, run: true Nov 23 07:19:04 rivo volumio[19717]: info: camilladsp spawned new process with pid 15629, instance 2, run: true Nov 23 07:19:04 rivo volumio[19717]: info: camilladsp spawned new process with pid 15636, instance 2, run: true Nov 23 07:19:04 rivo volumio[19717]: info: camilladsp spawned new process with pid 15643, instance 2, run: true Nov 23 07:19:05 rivo volumio[19717]: info: camilladsp spawned new process with pid 15650, instance 2, run: true Nov 23 07:19:05 rivo volumio[19717]: info: camilladsp spawned new process with pid 15657, instance 2, run: true Nov 23 07:19:05 rivo volumio[19717]: info: camilladsp spawned new process with pid 15664, instance 2, run: true Nov 23 07:19:05 rivo volumio[19717]: info: camilladsp spawned new process with pid 15671, instance 2, run: true Nov 23 07:19:05 rivo volumio[19717]: info: camilladsp spawned new process with pid 15678, instance 2, run: true Nov 23 07:19:05 rivo volumio[19717]: info: camilladsp spawned new process with pid 15685, instance 2, run: true Nov 23 07:19:05 rivo volumio[19717]: info: camilladsp spawned new process with pid 15692, instance 2, run: true Nov 23 07:19:05 rivo volumio[19717]: info: camilladsp spawned new process with pid 15699, instance 2, run: true Nov 23 07:19:05 rivo volumio[19717]: info: camilladsp spawned new process with pid 15706, instance 2, run: true Nov 23 07:19:05 rivo volumio[19717]: info: camilladsp spawned new process with pid 15713, instance 2, run: true Nov 23 07:19:05 rivo volumio[19717]: info: camilladsp spawned new process with pid 15720, instance 2, run: true Nov 23 07:19:06 rivo volumio[19717]: info: camilladsp spawned new process with pid 15727, instance 2, run: true Nov 23 07:19:06 rivo volumio[19717]: info: camilladsp spawned new process with pid 15734, instance 2, run: true Nov 23 07:19:06 rivo volumio[19717]: info: camilladsp spawned new process with pid 15741, instance 2, run: true Nov 23 07:19:06 rivo volumio[19717]: info: camilladsp spawned new process with pid 15748, instance 2, run: true Nov 23 07:19:06 rivo volumio[19717]: info: camilladsp spawned new process with pid 15755, instance 2, run: true Nov 23 07:19:06 rivo volumio[19717]: info: camilladsp spawned new process with pid 15762, instance 2, run: true Nov 23 07:19:06 rivo volumio[19717]: info: camilladsp spawned new process with pid 15769, instance 2, run: true Nov 23 07:19:06 rivo volumio[19717]: info: camilladsp spawned new process with pid 15776, instance 2, run: true Nov 23 07:19:06 rivo volumio[19717]: info: camilladsp spawned new process with pid 15783, instance 2, run: true Nov 23 07:19:06 rivo volumio[19717]: info: camilladsp spawned new process with pid 15790, instance 2, run: true Nov 23 07:19:06 rivo volumio[19717]: info: camilladsp spawned new process with pid 15797, instance 2, run: true Nov 23 07:19:07 rivo volumio[19717]: info: camilladsp spawned new process with pid 15804, instance 2, run: true Nov 23 07:19:07 rivo volumio[19717]: info: camilladsp spawned new process with pid 15811, instance 2, run: true Nov 23 07:19:07 rivo volumio[19717]: info: camilladsp spawned new process with pid 15818, instance 2, run: true Nov 23 07:19:07 rivo volumio[19717]: info: camilladsp spawned new process with pid 15825, instance 2, run: true Nov 23 07:19:07 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 07:19:07 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 07:19:07 rivo volumio[19717]: info: Discovery: Getting this device information Nov 23 07:19:07 rivo volumio[19717]: info: CoreCommandRouter::volumioGetState Nov 23 07:19:07 rivo volumio[19717]: info: CorePlayQueue::getTrack 1 Nov 23 07:19:07 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 07:19:07 rivo volumio[19717]: info: camilladsp spawned new process with pid 15832, instance 2, run: true Nov 23 07:19:07 rivo volumio[19717]: info: camilladsp spawned new process with pid 15839, instance 2, run: true Nov 23 07:19:07 rivo volumio[19717]: info: camilladsp spawned new process with pid 15846, instance 2, run: true Nov 23 07:19:07 rivo volumio[19717]: info: camilladsp spawned new process with pid 15856, instance 2, run: true Nov 23 07:19:07 rivo volumio[19717]: info: camilladsp spawned new process with pid 15863, instance 2, run: true Nov 23 07:19:07 rivo volumio[19717]: info: camilladsp spawned new process with pid 15870, instance 2, run: true Nov 23 07:19:07 rivo volumio[19717]: info: camilladsp spawned new process with pid 15877, instance 2, run: true Nov 23 07:19:08 rivo volumio[19717]: info: camilladsp spawned new process with pid 15884, instance 2, run: true Nov 23 07:19:08 rivo volumio[19717]: info: camilladsp spawned new process with pid 15891, instance 2, run: true Nov 23 07:19:08 rivo volumio[19717]: info: camilladsp spawned new process with pid 15898, instance 2, run: true Nov 23 07:19:08 rivo volumio[19717]: info: camilladsp spawned new process with pid 15905, instance 2, run: true Nov 23 07:19:08 rivo volumio[19717]: info: camilladsp spawned new process with pid 15912, instance 2, run: true Nov 23 07:19:08 rivo volumio[19717]: info: camilladsp spawned new process with pid 15919, instance 2, run: true Nov 23 07:19:08 rivo volumio[19717]: info: camilladsp spawned new process with pid 15926, instance 2, run: true Nov 23 07:19:08 rivo volumio[19717]: info: camilladsp spawned new process with pid 15933, instance 2, run: true Nov 23 07:19:08 rivo volumio[19717]: info: camilladsp spawned new process with pid 15942, instance 2, run: true Nov 23 07:19:08 rivo volumio[19717]: info: camilladsp spawned new process with pid 15950, instance 2, run: true Nov 23 07:19:08 rivo volumio[19717]: info: camilladsp spawned new process with pid 15957, instance 2, run: true Nov 23 07:19:09 rivo volumio[19717]: info: camilladsp spawned new process with pid 15964, instance 2, run: true Nov 23 07:19:09 rivo volumio[19717]: info: camilladsp spawned new process with pid 15971, instance 2, run: true Nov 23 07:19:09 rivo volumio[19717]: info: camilladsp spawned new process with pid 15978, instance 2, run: true Nov 23 07:19:09 rivo volumio[19717]: info: camilladsp spawned new process with pid 15985, instance 2, run: true Nov 23 07:19:09 rivo volumio[19717]: info: camilladsp spawned new process with pid 15992, instance 2, run: true Nov 23 07:19:09 rivo volumio[19717]: info: camilladsp spawned new process with pid 15999, instance 2, run: true Nov 23 07:19:09 rivo volumio[19717]: info: camilladsp spawned new process with pid 16006, instance 2, run: true Nov 23 07:19:09 rivo volumio[19717]: info: camilladsp spawned new process with pid 16013, instance 2, run: true Nov 23 07:19:09 rivo volumio[19717]: info: camilladsp spawned new process with pid 16020, instance 2, run: true Nov 23 07:19:09 rivo volumio[19717]: info: camilladsp spawned new process with pid 16027, instance 2, run: true Nov 23 07:19:10 rivo volumio[19717]: info: camilladsp spawned new process with pid 16034, instance 2, run: true Nov 23 07:19:10 rivo volumio[19717]: info: camilladsp spawned new process with pid 16041, instance 2, run: true Nov 23 07:19:10 rivo volumio[19717]: info: camilladsp spawned new process with pid 16048, instance 2, run: true Nov 23 07:19:10 rivo volumio[19717]: info: camilladsp spawned new process with pid 16055, instance 2, run: true Nov 23 07:19:10 rivo volumio[19717]: info: camilladsp spawned new process with pid 16062, instance 2, run: true Nov 23 07:19:10 rivo volumio[19717]: info: camilladsp spawned new process with pid 16069, instance 2, run: true Nov 23 07:19:10 rivo volumio[19717]: info: camilladsp spawned new process with pid 16076, instance 2, run: true Nov 23 07:19:10 rivo volumio[19717]: info: camilladsp spawned new process with pid 16083, instance 2, run: true Nov 23 07:19:10 rivo volumio[19717]: info: camilladsp spawned new process with pid 16090, instance 2, run: true Nov 23 07:19:10 rivo volumio[19717]: info: camilladsp spawned new process with pid 16097, instance 2, run: true Nov 23 07:19:10 rivo volumio[19717]: info: camilladsp spawned new process with pid 16109, instance 2, run: true Nov 23 07:19:10 rivo volumio[19717]: info: camilladsp spawned new process with pid 16128, instance 2, run: true Nov 23 07:19:11 rivo volumio[19717]: info: camilladsp spawned new process with pid 16135, instance 2, run: true Nov 23 07:19:11 rivo volumio[19717]: info: camilladsp spawned new process with pid 16142, instance 2, run: true Nov 23 07:19:11 rivo volumio[19717]: info: camilladsp spawned new process with pid 16149, instance 2, run: true Nov 23 07:19:11 rivo volumio[19717]: info: camilladsp spawned new process with pid 16156, instance 2, run: true Nov 23 07:19:11 rivo volumio[19717]: info: camilladsp spawned new process with pid 16163, instance 2, run: true Nov 23 07:19:11 rivo volumio[19717]: info: camilladsp spawned new process with pid 16170, instance 2, run: true Nov 23 07:19:11 rivo volumio[19717]: info: camilladsp spawned new process with pid 16177, instance 2, run: true Nov 23 07:19:11 rivo volumio[19717]: info: camilladsp spawned new process with pid 16184, instance 2, run: true Nov 23 07:19:11 rivo volumio[19717]: info: camilladsp spawned new process with pid 16191, instance 2, run: true Nov 23 07:19:11 rivo volumio[19717]: info: camilladsp spawned new process with pid 16198, instance 2, run: true Nov 23 07:19:12 rivo volumio[19717]: info: camilladsp spawned new process with pid 16205, instance 2, run: true Nov 23 07:19:12 rivo volumio[19717]: info: camilladsp spawned new process with pid 16212, instance 2, run: true Nov 23 07:19:12 rivo volumio[19717]: info: camilladsp spawned new process with pid 16219, instance 2, run: true Nov 23 07:19:12 rivo volumio[19717]: info: camilladsp spawned new process with pid 16226, instance 2, run: true Nov 23 07:19:12 rivo volumio[19717]: info: camilladsp spawned new process with pid 16233, instance 2, run: true Nov 23 07:19:12 rivo volumio[19717]: info: camilladsp spawned new process with pid 16240, instance 2, run: true Nov 23 07:19:12 rivo volumio[19717]: info: camilladsp spawned new process with pid 16248, instance 2, run: true Nov 23 07:19:12 rivo volumio[19717]: info: camilladsp spawned new process with pid 16255, instance 2, run: true Nov 23 07:19:12 rivo volumio[19717]: info: camilladsp spawned new process with pid 16262, instance 2, run: true Nov 23 07:19:12 rivo volumio[19717]: info: camilladsp spawned new process with pid 16269, instance 2, run: true Nov 23 07:19:12 rivo volumio[19717]: info: camilladsp spawned new process with pid 16276, instance 2, run: true Nov 23 07:19:13 rivo volumio[19717]: info: camilladsp spawned new process with pid 16283, instance 2, run: true Nov 23 07:19:13 rivo volumio[19717]: info: camilladsp spawned new process with pid 16290, instance 2, run: true Nov 23 07:19:13 rivo volumio[19717]: info: camilladsp spawned new process with pid 16297, instance 2, run: true Nov 23 07:19:13 rivo volumio[19717]: info: camilladsp spawned new process with pid 16304, instance 2, run: true Nov 23 07:19:13 rivo volumio[19717]: info: camilladsp spawned new process with pid 16311, instance 2, run: true Nov 23 07:19:13 rivo volumio[19717]: info: camilladsp spawned new process with pid 16318, instance 2, run: true Nov 23 07:19:13 rivo volumio[19717]: info: camilladsp spawned new process with pid 16325, instance 2, run: true Nov 23 07:19:13 rivo volumio[19717]: info: camilladsp spawned new process with pid 16332, instance 2, run: true Nov 23 07:19:13 rivo volumio[19717]: info: camilladsp spawned new process with pid 16339, instance 2, run: true Nov 23 07:19:13 rivo volumio[19717]: info: camilladsp spawned new process with pid 16346, instance 2, run: true Nov 23 07:19:13 rivo volumio[19717]: info: camilladsp spawned new process with pid 16353, instance 2, run: true Nov 23 07:19:14 rivo volumio[19717]: info: camilladsp spawned new process with pid 16360, instance 2, run: true Nov 23 07:19:14 rivo volumio[19717]: info: camilladsp spawned new process with pid 16367, instance 2, run: true Nov 23 07:19:14 rivo volumio[19717]: info: camilladsp spawned new process with pid 16374, instance 2, run: true Nov 23 07:19:14 rivo volumio[19717]: info: camilladsp spawned new process with pid 16381, instance 2, run: true Nov 23 07:19:14 rivo volumio[19717]: info: camilladsp spawned new process with pid 16388, instance 2, run: true Nov 23 07:19:14 rivo volumio[19717]: info: camilladsp spawned new process with pid 16395, instance 2, run: true Nov 23 07:19:14 rivo volumio[19717]: info: camilladsp spawned new process with pid 16402, instance 2, run: true Nov 23 07:19:14 rivo volumio[19717]: info: camilladsp spawned new process with pid 16409, instance 2, run: true Nov 23 07:19:14 rivo volumio[19717]: info: camilladsp spawned new process with pid 16416, instance 2, run: true Nov 23 07:19:14 rivo volumio[19717]: info: camilladsp spawned new process with pid 16423, instance 2, run: true Nov 23 07:19:14 rivo volumio[19717]: info: camilladsp spawned new process with pid 16430, instance 2, run: true Nov 23 07:19:15 rivo volumio[19717]: info: camilladsp spawned new process with pid 16437, instance 2, run: true Nov 23 07:19:15 rivo volumio[19717]: info: camilladsp spawned new process with pid 16444, instance 2, run: true Nov 23 07:19:15 rivo volumio[19717]: info: camilladsp spawned new process with pid 16451, instance 2, run: true Nov 23 07:19:15 rivo volumio[19717]: info: camilladsp spawned new process with pid 16458, instance 2, run: true Nov 23 07:19:15 rivo volumio[19717]: info: camilladsp spawned new process with pid 16465, instance 2, run: true Nov 23 07:19:15 rivo volumio[19717]: info: camilladsp spawned new process with pid 16472, instance 2, run: true Nov 23 07:19:15 rivo volumio[19717]: info: camilladsp spawned new process with pid 16479, instance 2, run: true Nov 23 07:19:15 rivo volumio[19717]: info: camilladsp spawned new process with pid 16486, instance 2, run: true Nov 23 07:19:16 rivo volumio[19717]: info: camilladsp spawned new process with pid 16493, instance 2, run: true Nov 23 07:19:16 rivo volumio[19717]: info: camilladsp spawned new process with pid 16500, instance 2, run: true Nov 23 07:19:16 rivo volumio[19717]: info: camilladsp spawned new process with pid 16507, instance 2, run: true Nov 23 07:19:16 rivo volumio[19717]: info: camilladsp spawned new process with pid 16514, instance 2, run: true Nov 23 07:19:16 rivo volumio[19717]: info: camilladsp spawned new process with pid 16521, instance 2, run: true Nov 23 07:19:16 rivo volumio[19717]: info: camilladsp spawned new process with pid 16528, instance 2, run: true Nov 23 07:19:16 rivo volumio[19717]: info: camilladsp spawned new process with pid 16535, instance 2, run: true Nov 23 07:19:16 rivo volumio[19717]: info: camilladsp spawned new process with pid 16542, instance 2, run: true Nov 23 07:19:17 rivo volumio[19717]: info: camilladsp spawned new process with pid 16549, instance 2, run: true Nov 23 07:19:17 rivo volumio[19717]: info: camilladsp spawned new process with pid 16556, instance 2, run: true Nov 23 07:19:17 rivo volumio[19717]: info: camilladsp spawned new process with pid 16563, instance 2, run: true Nov 23 07:19:17 rivo volumio[19717]: info: camilladsp spawned new process with pid 16570, instance 2, run: true Nov 23 07:19:17 rivo volumio[19717]: info: camilladsp spawned new process with pid 16577, instance 2, run: true Nov 23 07:19:17 rivo volumio[19717]: info: camilladsp spawned new process with pid 16584, instance 2, run: true Nov 23 07:19:17 rivo volumio[19717]: info: camilladsp spawned new process with pid 16595, instance 2, run: true Nov 23 07:19:17 rivo volumio[19717]: info: camilladsp spawned new process with pid 16602, instance 2, run: true Nov 23 07:19:18 rivo volumio[19717]: info: camilladsp spawned new process with pid 16609, instance 2, run: true Nov 23 07:19:18 rivo volumio[19717]: info: camilladsp spawned new process with pid 16616, instance 2, run: true Nov 23 07:19:18 rivo volumio[19717]: info: camilladsp spawned new process with pid 16623, instance 2, run: true Nov 23 07:19:18 rivo volumio[19717]: info: camilladsp spawned new process with pid 16630, instance 2, run: true Nov 23 07:19:18 rivo volumio[19717]: info: camilladsp spawned new process with pid 16637, instance 2, run: true Nov 23 07:19:18 rivo volumio[19717]: info: camilladsp spawned new process with pid 16644, instance 2, run: true Nov 23 07:19:18 rivo volumio[19717]: info: camilladsp spawned new process with pid 16651, instance 2, run: true Nov 23 07:19:18 rivo volumio[19717]: info: camilladsp spawned new process with pid 16658, instance 2, run: true Nov 23 07:19:19 rivo volumio[19717]: info: camilladsp spawned new process with pid 16665, instance 2, run: true Nov 23 07:19:19 rivo volumio[19717]: info: camilladsp spawned new process with pid 16672, instance 2, run: true Nov 23 07:19:19 rivo volumio[19717]: info: camilladsp spawned new process with pid 16679, instance 2, run: true Nov 23 07:19:19 rivo volumio[19717]: info: camilladsp spawned new process with pid 16686, instance 2, run: true Nov 23 07:19:19 rivo volumio[19717]: info: camilladsp spawned new process with pid 16693, instance 2, run: true Nov 23 07:19:19 rivo volumio[19717]: info: camilladsp spawned new process with pid 16700, instance 2, run: true Nov 23 07:19:19 rivo volumio[19717]: info: camilladsp spawned new process with pid 16707, instance 2, run: true Nov 23 07:19:19 rivo volumio[19717]: info: camilladsp spawned new process with pid 16714, instance 2, run: true Nov 23 07:19:20 rivo volumio[19717]: info: camilladsp spawned new process with pid 16721, instance 2, run: true Nov 23 07:19:20 rivo volumio[19717]: info: camilladsp spawned new process with pid 16728, instance 2, run: true Nov 23 07:19:20 rivo volumio[19717]: info: camilladsp spawned new process with pid 16735, instance 2, run: true Nov 23 07:19:20 rivo volumio[19717]: info: camilladsp spawned new process with pid 16742, instance 2, run: true Nov 23 07:19:20 rivo volumio[19717]: info: camilladsp spawned new process with pid 16749, instance 2, run: true Nov 23 07:19:20 rivo volumio[19717]: info: camilladsp spawned new process with pid 16756, instance 2, run: true Nov 23 07:19:20 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 23 07:19:20 rivo volumio[19717]: info: camilladsp spawned new process with pid 16763, instance 2, run: true Nov 23 07:19:20 rivo volumio[19717]: info: camilladsp spawned new process with pid 16770, instance 2, run: true Nov 23 07:19:21 rivo volumio[19717]: info: camilladsp spawned new process with pid 16787, instance 2, run: true Nov 23 07:19:21 rivo volumio[19717]: info: Preload queue cleared Nov 23 07:19:21 rivo volumio[19717]: info: Preloading song: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$288R16214529 Nov 23 07:19:21 rivo volumio[19717]: info: Exploding uri upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$288R16214529 in service upnp_browser Nov 23 07:19:21 rivo volumio[19717]: info: camilladsp spawned new process with pid 16801, instance 2, run: true Nov 23 07:19:21 rivo volumio[19717]: info: Executing endpoint getSimilarAlbums Nov 23 07:19:21 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Nov 23 07:19:21 rivo volumio[19717]: info: camilladsp spawned new process with pid 16808, instance 2, run: true Nov 23 07:19:21 rivo volumio[19717]: info: Executing endpoint metavolumio Nov 23 07:19:21 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 23 07:19:21 rivo volumio[19717]: info: Executing endpoint metavolumio Nov 23 07:19:21 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 23 07:19:21 rivo volumio[19717]: info: camilladsp spawned new process with pid 16815, instance 2, run: true Nov 23 07:19:21 rivo volumio[19717]: info: camilladsp spawned new process with pid 16822, instance 2, run: true Nov 23 07:19:21 rivo volumio[19717]: info: camilladsp spawned new process with pid 16829, instance 2, run: true Nov 23 07:19:21 rivo volumio[19717]: info: camilladsp spawned new process with pid 16836, instance 2, run: true Nov 23 07:19:22 rivo volumio[19717]: info: camilladsp spawned new process with pid 16843, instance 2, run: true Nov 23 07:19:22 rivo volumio[19717]: info: camilladsp spawned new process with pid 16850, instance 2, run: true Nov 23 07:19:22 rivo volumio[19717]: info: camilladsp spawned new process with pid 16857, instance 2, run: true Nov 23 07:19:22 rivo volumio[19717]: info: camilladsp spawned new process with pid 16864, instance 2, run: true Nov 23 07:19:22 rivo volumio[19717]: info: camilladsp spawned new process with pid 16871, instance 2, run: true Nov 23 07:19:22 rivo volumio[19717]: info: camilladsp spawned new process with pid 16878, instance 2, run: true Nov 23 07:19:22 rivo volumio[19717]: info: camilladsp spawned new process with pid 16885, instance 2, run: true Nov 23 07:19:22 rivo volumio[19717]: info: camilladsp spawned new process with pid 16892, instance 2, run: true Nov 23 07:19:23 rivo volumio[19717]: info: camilladsp spawned new process with pid 16899, instance 2, run: true Nov 23 07:19:23 rivo volumio[19717]: info: camilladsp spawned new process with pid 16906, instance 2, run: true Nov 23 07:19:23 rivo volumio[19717]: info: camilladsp spawned new process with pid 16913, instance 2, run: true Nov 23 07:19:23 rivo volumio[19717]: info: camilladsp spawned new process with pid 16920, instance 2, run: true Nov 23 07:19:23 rivo volumio[19717]: info: camilladsp spawned new process with pid 16927, instance 2, run: true Nov 23 07:19:23 rivo volumio[19717]: info: camilladsp spawned new process with pid 16934, instance 2, run: true Nov 23 07:19:23 rivo volumio[19717]: info: camilladsp spawned new process with pid 16941, instance 2, run: true Nov 23 07:19:23 rivo volumio[19717]: info: camilladsp spawned new process with pid 16948, instance 2, run: true Nov 23 07:19:24 rivo volumio[19717]: info: camilladsp spawned new process with pid 16955, instance 2, run: true Nov 23 07:19:24 rivo volumio[19717]: info: camilladsp spawned new process with pid 16962, instance 2, run: true Nov 23 07:19:24 rivo volumio[19717]: info: camilladsp spawned new process with pid 16969, instance 2, run: true Nov 23 07:19:24 rivo volumio[19717]: info: camilladsp spawned new process with pid 16976, instance 2, run: true Nov 23 07:19:24 rivo volumio[19717]: info: camilladsp spawned new process with pid 16983, instance 2, run: true Nov 23 07:19:24 rivo volumio[19717]: info: camilladsp spawned new process with pid 16990, instance 2, run: true Nov 23 07:19:24 rivo volumio[19717]: info: camilladsp spawned new process with pid 16997, instance 2, run: true Nov 23 07:19:24 rivo volumio[19717]: info: camilladsp spawned new process with pid 17004, instance 2, run: true Nov 23 07:19:25 rivo volumio[19717]: info: camilladsp spawned new process with pid 17011, instance 2, run: true Nov 23 07:19:25 rivo volumio[19717]: info: camilladsp spawned new process with pid 17018, instance 2, run: true Nov 23 07:19:25 rivo volumio[19717]: info: camilladsp spawned new process with pid 17025, instance 2, run: true Nov 23 07:19:25 rivo volumio[19717]: info: camilladsp spawned new process with pid 17032, instance 2, run: true Nov 23 07:19:25 rivo volumio[19717]: info: camilladsp spawned new process with pid 17039, instance 2, run: true Nov 23 07:19:25 rivo volumio[19717]: info: camilladsp spawned new process with pid 17046, instance 2, run: true Nov 23 07:19:25 rivo volumio[19717]: info: camilladsp spawned new process with pid 17053, instance 2, run: true Nov 23 07:19:26 rivo volumio[19717]: info: camilladsp spawned new process with pid 17060, instance 2, run: true Nov 23 07:19:26 rivo volumio[19717]: info: camilladsp spawned new process with pid 17067, instance 2, run: true Nov 23 07:19:26 rivo volumio[19717]: info: camilladsp spawned new process with pid 17074, instance 2, run: true Nov 23 07:19:26 rivo volumio[19717]: info: camilladsp spawned new process with pid 17081, instance 2, run: true Nov 23 07:19:26 rivo volumio[19717]: info: camilladsp spawned new process with pid 17088, instance 2, run: true Nov 23 07:19:26 rivo volumio[19717]: info: camilladsp spawned new process with pid 17095, instance 2, run: true Nov 23 07:19:26 rivo volumio[19717]: info: Preload queue cleared Nov 23 07:19:26 rivo volumio[19717]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 23 07:19:26 rivo volumio[19717]: info: CoreStateMachine::ClearQueue Nov 23 07:19:26 rivo volumio[19717]: info: CoreStateMachine::stop Nov 23 07:19:26 rivo volumio[19717]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 07:19:26 rivo volumio[19717]: info: CoreStateMachine::stPlaybackTimer Nov 23 07:19:26 rivo volumio[19717]: info: CoreStateMachine::updateTrackBlock Nov 23 07:19:26 rivo volumio[19717]: info: CorePlayQueue::getTrackBlock Nov 23 07:19:26 rivo volumio[19717]: info: CoreStateMachine::pushState Nov 23 07:19:26 rivo volumio[19717]: info: CorePlayQueue::getTrack 1 Nov 23 07:19:26 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 07:19:26 rivo volumio[19717]: info: CoreCommandRouter::volumioPushState Nov 23 07:19:26 rivo volumio[19717]: info: CoreStateMachine::serviceStop Nov 23 07:19:26 rivo volumio[19717]: info: CorePlayQueue::getTrack 1 Nov 23 07:19:26 rivo volumio[19717]: info: CoreCommandRouter::serviceStop Nov 23 07:19:26 rivo volumio[19717]: info: [1732342766664] ControllerUPNPBrowser::stop Nov 23 07:19:26 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 07:19:26 rivo volumio[19717]: info: CorePlayQueue::clearPlayQueue Nov 23 07:19:26 rivo volumio[19717]: info: CorePlayQueue::saveQueue Nov 23 07:19:26 rivo volumio[19717]: info: CoreCommandRouter::volumioPushQueue Nov 23 07:19:26 rivo volumio[19717]: info: CoreStateMachine::addQueueItems Nov 23 07:19:26 rivo volumio[19717]: info: CorePlayQueue::addQueueItems Nov 23 07:19:26 rivo volumio[19717]: info: Preload queue cleared Nov 23 07:19:26 rivo volumio[19717]: info: Adding Item to queue: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$288R16214529 Nov 23 07:19:26 rivo volumio[19717]: info: Using cached record of: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$288R16214529 Nov 23 07:19:26 rivo volumio[19717]: info: CoreCommandRouter::volumioPushQueue Nov 23 07:19:26 rivo volumio[19717]: info: CorePlayQueue::saveQueue Nov 23 07:19:26 rivo volumio[19717]: info: CoreStateMachine::updateTrackBlock Nov 23 07:19:26 rivo volumio[19717]: info: CorePlayQueue::getTrackBlock Nov 23 07:19:26 rivo volumio[19717]: info: CoreCommandRouter::volumioPlay Nov 23 07:19:26 rivo volumio[19717]: info: CoreStateMachine::play index 0 Nov 23 07:19:26 rivo volumio[19717]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 07:19:26 rivo volumio[19717]: info: CoreStateMachine::stop Nov 23 07:19:26 rivo volumio[19717]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 07:19:26 rivo volumio[19717]: info: CoreStateMachine::play index undefined Nov 23 07:19:26 rivo volumio[19717]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 07:19:26 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:19:26 rivo volumio[19717]: info: CoreStateMachine::startPlaybackTimer Nov 23 07:19:26 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:19:26 rivo volumio[19717]: info: CoreCommandRouter::volumioGetVisibleSources Nov 23 07:19:26 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 07:19:26 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 23 07:19:26 rivo volumio[19717]: info: [1732342766718] ControllerUPNPBrowser::clearAddPlayTrack Nov 23 07:19:26 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 07:19:26 rivo volumio[19717]: info: Updating RAAT Signal Path Nov 23 07:19:26 rivo volumio[19717]: info: Nov 23 07:19:26 rivo volumio[19717]: ---------------------------- MPD announces state update: player Nov 23 07:19:26 rivo volumio[19717]: info: sendMpdCommand stop took 82 milliseconds Nov 23 07:19:26 rivo volumio[19717]: info: ControllerMpd::getState Nov 23 07:19:26 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand status Nov 23 07:19:26 rivo volumio[19717]: info: sendMpdCommand stop took 30 milliseconds Nov 23 07:19:26 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 07:19:26 rivo volumio[19717]: info: Nov 23 07:19:26 rivo volumio[19717]: ---------------------------- MPD announces system playlist update Nov 23 07:19:26 rivo volumio[19717]: info: Ignoring MPD Status Update Nov 23 07:19:26 rivo volumio[19717]: info: sendMpdCommand status took 6 milliseconds Nov 23 07:19:26 rivo volumio[19717]: info: sendMpdCommand clear took 6 milliseconds Nov 23 07:19:26 rivo volumio[19717]: verbose: ControllerMpd::parseState Nov 23 07:19:26 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 07:19:26 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I16214529.flac" Nov 23 07:19:26 rivo volumio[19717]: info: camilladsp spawned new process with pid 17102, instance 2, run: true Nov 23 07:19:26 rivo volumio[19717]: error: updateQueue error: null Nov 23 07:19:26 rivo volumio[19717]: info: ------------------------------ 69ms Nov 23 07:19:26 rivo volumio[19717]: info: sendMpdCommand playlistinfo took 67 milliseconds Nov 23 07:19:26 rivo volumio[19717]: verbose: ControllerMpd::parseTrackInfo Nov 23 07:19:26 rivo volumio[19717]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 23 07:19:26 rivo volumio[19717]: info: ------------------------------ 89ms Nov 23 07:19:26 rivo volumio[19717]: info: MCU Signalled Playback Inactive Nov 23 07:19:26 rivo volumio[19717]: info: camilladsp spawned new process with pid 17109, instance 2, run: true Nov 23 07:19:27 rivo volumio[19717]: info: camilladsp spawned new process with pid 17116, instance 1, run: true Nov 23 07:19:27 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I16214529.flac" Nov 23 07:19:27 rivo volumio[19717]: info: Nov 23 07:19:27 rivo volumio[19717]: ---------------------------- MPD announces system playlist update Nov 23 07:19:27 rivo volumio[19717]: info: Ignoring MPD Status Update Nov 23 07:19:27 rivo volumio[19717]: info: sendMpdCommand add "http://192.168.0.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I16214529.flac" took 3 milliseconds Nov 23 07:19:27 rivo volumio[19717]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 07:19:27 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand play Nov 23 07:19:27 rivo volumio[19717]: info: ------------------------------ 13ms Nov 23 07:19:27 rivo volumio[19717]: info: sendMpdCommand play took 4 milliseconds Nov 23 07:19:27 rivo volumio[19717]: info: camilladsp spawned new process with pid 17124, instance 1, run: true Nov 23 07:19:27 rivo volumio[19717]: info: Nov 23 07:19:27 rivo volumio[19717]: ---------------------------- MPD announces state update: player Nov 23 07:19:27 rivo volumio[19717]: info: ControllerMpd::getState Nov 23 07:19:27 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand status Nov 23 07:19:27 rivo volumio[19717]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Nov 23 07:19:27 rivo volumio[19717]: info: FusionDsp - ---- read samplerate from file: 192000 Nov 23 07:19:27 rivo volumio[19717]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 23 07:19:27 rivo volumio[19717]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Nov 23 07:19:27 rivo volumio[19717]: info: FusionDsp - ---- read samplerate from file: 192000 Nov 23 07:19:27 rivo volumio[19717]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 23 07:19:27 rivo volumio[19717]: info: Nov 23 07:19:27 rivo volumio[19717]: ---------------------------- MPD announces system playlist update Nov 23 07:19:27 rivo volumio[19717]: info: Ignoring MPD Status Update Nov 23 07:19:27 rivo volumio[19717]: info: Nov 23 07:19:27 rivo volumio[19717]: ---------------------------- MPD announces state update: player Nov 23 07:19:27 rivo volumio[19717]: info: ControllerMpd::getState Nov 23 07:19:27 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand status Nov 23 07:19:27 rivo volumio[19717]: info: sendMpdCommand status took 48 milliseconds Nov 23 07:19:27 rivo volumio[19717]: verbose: ControllerMpd::parseState Nov 23 07:19:27 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 07:19:27 rivo volumio[19717]: info: ------------------------------ 14ms Nov 23 07:19:27 rivo volumio[19717]: info: sendMpdCommand status took 8 milliseconds Nov 23 07:19:27 rivo volumio[19717]: info: sendMpdCommand playlistinfo took 11 milliseconds Nov 23 07:19:27 rivo volumio[19717]: verbose: ControllerMpd::parseState Nov 23 07:19:27 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 07:19:27 rivo volumio[19717]: verbose: ControllerMpd::parseTrackInfo Nov 23 07:19:27 rivo volumio[19717]: info: ControllerMpd::pushState Nov 23 07:19:27 rivo volumio[19717]: info: CoreCommandRouter::servicePushState Nov 23 07:19:27 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:19:27 rivo volumio[19717]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3733,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"244 Kbps","isStreaming":false,"title":"O0$1$8I16214529.flac","artist":"Alunni del Sole","album":"I successi","uri":"http://192.168.0.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I16214529.flac","trackType":"flac"} Nov 23 07:19:27 rivo volumio[19717]: verbose: CURRENT POSITION 0 Nov 23 07:19:27 rivo volumio[19717]: info: CoreStateMachine::syncState stateService play Nov 23 07:19:27 rivo volumio[19717]: info: CoreStateMachine::syncState currentStatus stop Nov 23 07:19:27 rivo volumio[19717]: info: ------------------------------ 72ms Nov 23 07:19:27 rivo volumio[19717]: info: sendMpdCommand playlistinfo took 9 milliseconds Nov 23 07:19:27 rivo volumio[19717]: verbose: ControllerMpd::parseTrackInfo Nov 23 07:19:27 rivo volumio[19717]: info: ControllerMpd::pushState Nov 23 07:19:27 rivo volumio[19717]: info: CoreCommandRouter::servicePushState Nov 23 07:19:27 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:19:27 rivo volumio[19717]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":3733,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"244 Kbps","isStreaming":false,"title":"O0$1$8I16214529.flac","artist":"Alunni del Sole","album":"I successi","uri":"http://192.168.0.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I16214529.flac","trackType":"flac"} Nov 23 07:19:27 rivo volumio[19717]: verbose: CURRENT POSITION 0 Nov 23 07:19:27 rivo volumio[19717]: info: CoreStateMachine::syncState stateService play Nov 23 07:19:27 rivo volumio[19717]: info: CoreStateMachine::syncState currentStatus play Nov 23 07:19:27 rivo volumio[19717]: info: Received an update from plugin. extracting info from payload Nov 23 07:19:27 rivo volumio[19717]: info: CoreStateMachine::pushState Nov 23 07:19:27 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 07:19:27 rivo volumio[19717]: info: CoreCommandRouter::volumioPushState Nov 23 07:19:27 rivo volumio[19717]: info: CoreStateMachine::pushState Nov 23 07:19:27 rivo volumio[19717]: info: CoreCommandRouter::volumioPushState Nov 23 07:19:27 rivo volumio[19717]: info: ------------------------------ 125ms Nov 23 07:19:27 rivo volumio[19717]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 23 07:19:27 rivo volumio[19717]: info: CoreCommandRouter::volumioGetQueue Nov 23 07:19:27 rivo volumio[19717]: info: CoreStateMachine::getQueue Nov 23 07:19:27 rivo volumio[19717]: info: CorePlayQueue::getQueue Nov 23 07:19:27 rivo volumio[19717]: info: CoreCommandRouter::volumioGetQueue Nov 23 07:19:27 rivo volumio[19717]: info: CoreStateMachine::getQueue Nov 23 07:19:27 rivo volumio[19717]: info: CorePlayQueue::getQueue Nov 23 07:19:27 rivo volumio[19717]: info: Signalling Playback active due to playback status change Nov 23 07:19:27 rivo volumio[19717]: info: Signalling Playback active due to playback status change Nov 23 07:19:27 rivo volumio[19717]: info: Updating RAAT Signal Path Nov 23 07:19:27 rivo volumio[19717]: info: Updating RAAT Signal Path Nov 23 07:19:27 rivo volumio[19717]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 23 07:19:27 rivo volumio[19717]: info: camilladsp spawned new process with pid 17133, instance 1, run: true Nov 23 07:19:27 rivo volumio[19717]: info: MCU Signalled Playback Active Nov 23 07:19:27 rivo volumio[19717]: info: camilladsp spawned new process with pid 17143, instance 1, run: true Nov 23 07:19:27 rivo volumio[19717]: info: camilladsp spawned new process with pid 17150, instance 1, run: true Nov 23 07:19:28 rivo volumio[19717]: info: camilladsp spawned new process with pid 17157, instance 1, run: true Nov 23 07:19:28 rivo volumio[19717]: info: camilladsp spawned new process with pid 17164, instance 1, run: true Nov 23 07:19:28 rivo volumio[19717]: info: camilladsp spawned new process with pid 17171, instance 1, run: true Nov 23 07:19:28 rivo volumio[19717]: info: camilladsp spawned new process with pid 17178, instance 1, run: true Nov 23 07:19:28 rivo volumio[19717]: info: camilladsp spawned new process with pid 17185, instance 1, run: true Nov 23 07:19:28 rivo volumio[19717]: info: camilladsp spawned new process with pid 17192, instance 1, run: true Nov 23 07:19:28 rivo volumio[19717]: info: camilladsp spawned new process with pid 17199, instance 1, run: true Nov 23 07:19:28 rivo volumio[19717]: info: camilladsp spawned new process with pid 17206, instance 1, run: true Nov 23 07:19:28 rivo volumio[19717]: info: camilladsp spawned new process with pid 17213, instance 1, run: true Nov 23 07:19:29 rivo volumio[19717]: info: camilladsp spawned new process with pid 17220, instance 1, run: true Nov 23 07:19:29 rivo volumio[19717]: info: camilladsp spawned new process with pid 17227, instance 1, run: true Nov 23 07:19:29 rivo volumio[19717]: info: camilladsp spawned new process with pid 17234, instance 1, run: true Nov 23 07:19:29 rivo volumio[19717]: info: camilladsp spawned new process with pid 17241, instance 1, run: true Nov 23 07:19:29 rivo volumio[19717]: info: camilladsp spawned new process with pid 17248, instance 1, run: true Nov 23 07:19:29 rivo volumio[19717]: info: Executing endpoint metavolumio Nov 23 07:19:29 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 23 07:19:29 rivo volumio[19717]: info: Executing endpoint metavolumio Nov 23 07:19:29 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 23 07:19:29 rivo volumio[19717]: info: Executing endpoint metavolumio Nov 23 07:19:29 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 23 07:19:29 rivo volumio[19717]: info: camilladsp spawned new process with pid 17255, instance 1, run: true Nov 23 07:19:29 rivo volumio[19717]: info: camilladsp spawned new process with pid 17262, instance 1, run: true Nov 23 07:19:29 rivo volumio[19717]: info: camilladsp spawned new process with pid 17269, instance 1, run: true Nov 23 07:19:29 rivo volumio[19717]: info: camilladsp spawned new process with pid 17276, instance 1, run: true Nov 23 07:19:29 rivo volumio[19717]: info: camilladsp spawned new process with pid 17283, instance 1, run: true Nov 23 07:19:29 rivo volumio[19717]: info: camilladsp spawned new process with pid 17290, instance 1, run: true Nov 23 07:19:30 rivo volumio[19717]: info: camilladsp spawned new process with pid 17297, instance 1, run: true Nov 23 07:19:30 rivo volumio[19717]: info: camilladsp spawned new process with pid 17304, instance 1, run: true Nov 23 07:19:30 rivo volumio[19717]: info: Executing endpoint metavolumio Nov 23 07:19:30 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 23 07:19:30 rivo volumio[19717]: info: Executing endpoint metavolumio Nov 23 07:19:30 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 23 07:19:30 rivo volumio[19717]: info: Executing endpoint metavolumio Nov 23 07:19:30 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 23 07:19:30 rivo volumio[19717]: info: camilladsp spawned new process with pid 17311, instance 1, run: true Nov 23 07:19:30 rivo volumio[19717]: info: camilladsp spawned new process with pid 17318, instance 1, run: true Nov 23 07:19:30 rivo volumio[19717]: info: camilladsp spawned new process with pid 17325, instance 1, run: true Nov 23 07:19:30 rivo volumio[19717]: info: camilladsp spawned new process with pid 17332, instance 1, run: true Nov 23 07:19:30 rivo volumio[19717]: info: camilladsp spawned new process with pid 17339, instance 1, run: true Nov 23 07:19:30 rivo volumio[19717]: info: camilladsp spawned new process with pid 17346, instance 1, run: true Nov 23 07:19:30 rivo volumio[19717]: info: camilladsp spawned new process with pid 17353, instance 1, run: true Nov 23 07:19:30 rivo volumio[19717]: info: camilladsp spawned new process with pid 17360, instance 1, run: true Nov 23 07:19:31 rivo volumio[19717]: info: camilladsp spawned new process with pid 17367, instance 1, run: true Nov 23 07:19:31 rivo volumio[19717]: info: camilladsp spawned new process with pid 17374, instance 1, run: true Nov 23 07:19:31 rivo volumio[19717]: info: camilladsp spawned new process with pid 17381, instance 1, run: true Nov 23 07:19:31 rivo volumio[19717]: info: camilladsp spawned new process with pid 17403, instance 1, run: true Nov 23 07:19:31 rivo volumio[19717]: info: camilladsp spawned new process with pid 17412, instance 1, run: true Nov 23 07:19:31 rivo volumio[19717]: info: camilladsp spawned new process with pid 17419, instance 1, run: true Nov 23 07:19:31 rivo volumio[19717]: info: camilladsp spawned new process with pid 17426, instance 1, run: true Nov 23 07:19:31 rivo volumio[19717]: info: camilladsp spawned new process with pid 17433, instance 1, run: true Nov 23 07:19:31 rivo volumio[19717]: info: camilladsp spawned new process with pid 17440, instance 1, run: true Nov 23 07:19:31 rivo volumio[19717]: info: camilladsp spawned new process with pid 17447, instance 1, run: true Nov 23 07:19:31 rivo volumio[19717]: info: camilladsp spawned new process with pid 17454, instance 1, run: true Nov 23 07:19:32 rivo volumio[19717]: info: camilladsp spawned new process with pid 17461, instance 1, run: true Nov 23 07:19:32 rivo volumio[19717]: info: camilladsp spawned new process with pid 17468, instance 1, run: true Nov 23 07:19:32 rivo volumio[19717]: info: camilladsp spawned new process with pid 17475, instance 1, run: true Nov 23 07:19:32 rivo volumio[19717]: info: camilladsp spawned new process with pid 17482, instance 1, run: true Nov 23 07:19:32 rivo volumio[19717]: info: camilladsp spawned new process with pid 17489, instance 1, run: true Nov 23 07:19:32 rivo volumio[19717]: info: camilladsp spawned new process with pid 17496, instance 1, run: true Nov 23 07:19:32 rivo volumio[19717]: info: camilladsp spawned new process with pid 17504, instance 1, run: true Nov 23 07:19:32 rivo volumio[19717]: info: camilladsp spawned new process with pid 17511, instance 1, run: true Nov 23 07:19:32 rivo volumio[19717]: info: Preload queue cleared Nov 23 07:19:32 rivo volumio[19717]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 23 07:19:32 rivo volumio[19717]: info: CoreStateMachine::ClearQueue Nov 23 07:19:32 rivo volumio[19717]: info: CoreStateMachine::stop Nov 23 07:19:32 rivo volumio[19717]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 07:19:32 rivo volumio[19717]: info: CoreStateMachine::stPlaybackTimer Nov 23 07:19:32 rivo volumio[19717]: info: CoreStateMachine::updateTrackBlock Nov 23 07:19:32 rivo volumio[19717]: info: CorePlayQueue::getTrackBlock Nov 23 07:19:32 rivo volumio[19717]: info: CoreStateMachine::pushState Nov 23 07:19:32 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:19:32 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 07:19:32 rivo volumio[19717]: info: CoreCommandRouter::volumioPushState Nov 23 07:19:32 rivo volumio[19717]: info: CoreStateMachine::serviceStop Nov 23 07:19:32 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:19:32 rivo volumio[19717]: info: CoreCommandRouter::serviceStop Nov 23 07:19:32 rivo volumio[19717]: info: [1732342772656] ControllerUPNPBrowser::stop Nov 23 07:19:32 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 07:19:32 rivo volumio[19717]: info: CorePlayQueue::clearPlayQueue Nov 23 07:19:32 rivo volumio[19717]: info: CorePlayQueue::saveQueue Nov 23 07:19:32 rivo volumio[19717]: info: CoreCommandRouter::volumioPushQueue Nov 23 07:19:32 rivo volumio[19717]: info: CoreStateMachine::addQueueItems Nov 23 07:19:32 rivo volumio[19717]: info: CorePlayQueue::addQueueItems Nov 23 07:19:32 rivo volumio[19717]: info: Preload queue cleared Nov 23 07:19:32 rivo volumio[19717]: info: Adding Item to queue: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$288R16214529 Nov 23 07:19:32 rivo volumio[19717]: info: Using cached record of: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$288R16214529 Nov 23 07:19:32 rivo volumio[19717]: info: CoreCommandRouter::volumioPushQueue Nov 23 07:19:32 rivo volumio[19717]: info: CorePlayQueue::saveQueue Nov 23 07:19:32 rivo volumio[19717]: info: CoreStateMachine::updateTrackBlock Nov 23 07:19:32 rivo volumio[19717]: info: CorePlayQueue::getTrackBlock Nov 23 07:19:32 rivo volumio[19717]: info: CoreCommandRouter::volumioPlay Nov 23 07:19:32 rivo volumio[19717]: info: CoreStateMachine::play index 0 Nov 23 07:19:32 rivo volumio[19717]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 07:19:32 rivo volumio[19717]: info: CoreStateMachine::stop Nov 23 07:19:32 rivo volumio[19717]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 07:19:32 rivo volumio[19717]: info: CoreStateMachine::play index undefined Nov 23 07:19:32 rivo volumio[19717]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 07:19:32 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:19:32 rivo volumio[19717]: info: CoreStateMachine::startPlaybackTimer Nov 23 07:19:32 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:19:32 rivo volumio[19717]: info: CoreCommandRouter::volumioGetVisibleSources Nov 23 07:19:32 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 07:19:32 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 23 07:19:32 rivo volumio[19717]: info: [1732342772700] ControllerUPNPBrowser::clearAddPlayTrack Nov 23 07:19:32 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 07:19:32 rivo volumio[19717]: info: Updating RAAT Signal Path Nov 23 07:19:32 rivo volumio[19717]: info: Nov 23 07:19:32 rivo volumio[19717]: ---------------------------- MPD announces state update: player Nov 23 07:19:32 rivo volumio[19717]: info: sendMpdCommand stop took 64 milliseconds Nov 23 07:19:32 rivo volumio[19717]: info: ControllerMpd::getState Nov 23 07:19:32 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand status Nov 23 07:19:32 rivo volumio[19717]: info: sendMpdCommand stop took 20 milliseconds Nov 23 07:19:32 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 07:19:32 rivo volumio[19717]: info: Nov 23 07:19:32 rivo volumio[19717]: ---------------------------- MPD announces system playlist update Nov 23 07:19:32 rivo volumio[19717]: info: Ignoring MPD Status Update Nov 23 07:19:32 rivo volumio[19717]: info: sendMpdCommand status took 5 milliseconds Nov 23 07:19:32 rivo volumio[19717]: info: sendMpdCommand clear took 3 milliseconds Nov 23 07:19:32 rivo volumio[19717]: verbose: ControllerMpd::parseState Nov 23 07:19:32 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 07:19:32 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I16214529.flac" Nov 23 07:19:32 rivo volumio[19717]: error: updateQueue error: null Nov 23 07:19:32 rivo volumio[19717]: info: ------------------------------ 10ms Nov 23 07:19:32 rivo volumio[19717]: info: sendMpdCommand playlistinfo took 8 milliseconds Nov 23 07:19:32 rivo volumio[19717]: verbose: ControllerMpd::parseTrackInfo Nov 23 07:19:32 rivo volumio[19717]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 23 07:19:32 rivo volumio[19717]: info: ------------------------------ 16ms Nov 23 07:19:32 rivo volumio[19717]: info: camilladsp spawned new process with pid 17518, instance 1, run: true Nov 23 07:19:32 rivo volumio[19717]: info: MCU Signalled Playback Inactive Nov 23 07:19:32 rivo volumio[19717]: info: camilladsp spawned new process with pid 17525, instance 1, run: true Nov 23 07:19:32 rivo volumio[19717]: info: camilladsp spawned new process with pid 17532, instance 2, run: true Nov 23 07:19:32 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I16214529.flac" Nov 23 07:19:32 rivo volumio[19717]: info: Nov 23 07:19:32 rivo volumio[19717]: ---------------------------- MPD announces system playlist update Nov 23 07:19:32 rivo volumio[19717]: info: Ignoring MPD Status Update Nov 23 07:19:32 rivo volumio[19717]: info: sendMpdCommand add "http://192.168.0.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I16214529.flac" took 2 milliseconds Nov 23 07:19:32 rivo volumio[19717]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 07:19:32 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand play Nov 23 07:19:32 rivo volumio[19717]: info: ------------------------------ 4ms Nov 23 07:19:32 rivo volumio[19717]: info: sendMpdCommand play took 3 milliseconds Nov 23 07:19:33 rivo volumio[19717]: info: camilladsp spawned new process with pid 17539, instance 2, run: true Nov 23 07:19:33 rivo volumio[19717]: info: Nov 23 07:19:33 rivo volumio[19717]: ---------------------------- MPD announces state update: player Nov 23 07:19:33 rivo volumio[19717]: info: ControllerMpd::getState Nov 23 07:19:33 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand status Nov 23 07:19:33 rivo volumio[19717]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Nov 23 07:19:33 rivo volumio[19717]: info: FusionDsp - ---- read samplerate from file: 192000 Nov 23 07:19:33 rivo volumio[19717]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 23 07:19:33 rivo volumio[19717]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Nov 23 07:19:33 rivo volumio[19717]: info: FusionDsp - ---- read samplerate from file: 192000 Nov 23 07:19:33 rivo volumio[19717]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 23 07:19:33 rivo volumio[19717]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Nov 23 07:19:33 rivo volumio[19717]: info: FusionDsp - ---- read samplerate from file: 192000 Nov 23 07:19:33 rivo volumio[19717]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 23 07:19:33 rivo volumio[19717]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Nov 23 07:19:33 rivo volumio[19717]: info: FusionDsp - ---- read samplerate from file: 192000 Nov 23 07:19:33 rivo volumio[19717]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 23 07:19:33 rivo volumio[19717]: info: Nov 23 07:19:33 rivo volumio[19717]: ---------------------------- MPD announces system playlist update Nov 23 07:19:33 rivo volumio[19717]: info: Ignoring MPD Status Update Nov 23 07:19:33 rivo volumio[19717]: info: Nov 23 07:19:33 rivo volumio[19717]: ---------------------------- MPD announces state update: player Nov 23 07:19:33 rivo volumio[19717]: info: sendMpdCommand status took 53 milliseconds Nov 23 07:19:33 rivo volumio[19717]: info: ControllerMpd::getState Nov 23 07:19:33 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand status Nov 23 07:19:33 rivo volumio[19717]: verbose: ControllerMpd::parseState Nov 23 07:19:33 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 07:19:33 rivo volumio[19717]: info: camilladsp spawned new process with pid 17550, instance 2, run: true Nov 23 07:19:33 rivo volumio[19717]: info: ------------------------------ 66ms Nov 23 07:19:33 rivo volumio[19717]: info: sendMpdCommand status took 65 milliseconds Nov 23 07:19:33 rivo volumio[19717]: info: sendMpdCommand playlistinfo took 70 milliseconds Nov 23 07:19:33 rivo volumio[19717]: verbose: ControllerMpd::parseState Nov 23 07:19:33 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 07:19:33 rivo volumio[19717]: verbose: ControllerMpd::parseTrackInfo Nov 23 07:19:33 rivo volumio[19717]: info: ControllerMpd::pushState Nov 23 07:19:33 rivo volumio[19717]: info: CoreCommandRouter::servicePushState Nov 23 07:19:33 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:19:33 rivo volumio[19717]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3733,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I16214529.flac","artist":"Alunni del Sole","album":"I successi","uri":"http://192.168.0.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I16214529.flac","trackType":"flac"} Nov 23 07:19:33 rivo volumio[19717]: verbose: CURRENT POSITION 0 Nov 23 07:19:33 rivo volumio[19717]: info: CoreStateMachine::syncState stateService play Nov 23 07:19:33 rivo volumio[19717]: info: CoreStateMachine::syncState currentStatus stop Nov 23 07:19:33 rivo volumio[19717]: info: ------------------------------ 133ms Nov 23 07:19:33 rivo volumio[19717]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 23 07:19:33 rivo volumio[19717]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 23 07:19:33 rivo volumio[19717]: info: sendMpdCommand playlistinfo took 10 milliseconds Nov 23 07:19:33 rivo volumio[19717]: verbose: ControllerMpd::parseTrackInfo Nov 23 07:19:33 rivo volumio[19717]: info: ControllerMpd::pushState Nov 23 07:19:33 rivo volumio[19717]: info: CoreCommandRouter::servicePushState Nov 23 07:19:33 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:19:33 rivo volumio[19717]: verbose: STATE SERVICE {"status":"play","position":0,"seek":228,"duration":3733,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"244 Kbps","isStreaming":false,"title":"O0$1$8I16214529.flac","artist":"Alunni del Sole","album":"I successi","uri":"http://192.168.0.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I16214529.flac","trackType":"flac"} Nov 23 07:19:33 rivo volumio[19717]: verbose: CURRENT POSITION 0 Nov 23 07:19:33 rivo volumio[19717]: info: CoreStateMachine::syncState stateService play Nov 23 07:19:33 rivo volumio[19717]: info: CoreStateMachine::syncState currentStatus play Nov 23 07:19:33 rivo volumio[19717]: info: Received an update from plugin. extracting info from payload Nov 23 07:19:33 rivo volumio[19717]: info: CoreStateMachine::pushState Nov 23 07:19:33 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 07:19:33 rivo volumio[19717]: info: CoreCommandRouter::volumioPushState Nov 23 07:19:33 rivo volumio[19717]: info: CoreStateMachine::pushState Nov 23 07:19:33 rivo volumio[19717]: info: CoreCommandRouter::volumioPushState Nov 23 07:19:33 rivo volumio[19717]: info: ------------------------------ 164ms Nov 23 07:19:33 rivo volumio[19717]: info: CoreCommandRouter::volumioGetQueue Nov 23 07:19:33 rivo volumio[19717]: info: CoreStateMachine::getQueue Nov 23 07:19:33 rivo volumio[19717]: info: CorePlayQueue::getQueue Nov 23 07:19:33 rivo volumio[19717]: info: CoreCommandRouter::volumioGetQueue Nov 23 07:19:33 rivo volumio[19717]: info: CoreStateMachine::getQueue Nov 23 07:19:33 rivo volumio[19717]: info: CorePlayQueue::getQueue Nov 23 07:19:33 rivo volumio[19717]: info: Signalling Playback active due to playback status change Nov 23 07:19:33 rivo volumio[19717]: info: Signalling Playback active due to playback status change Nov 23 07:19:33 rivo volumio[19717]: info: Updating RAAT Signal Path Nov 23 07:19:33 rivo volumio[19717]: info: Updating RAAT Signal Path Nov 23 07:19:33 rivo volumio[19717]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 23 07:19:33 rivo volumio[19717]: info: camilladsp spawned new process with pid 17558, instance 2, run: true Nov 23 07:19:33 rivo volumio[19717]: info: MCU Signalled Playback Active Nov 23 07:19:33 rivo volumio[19717]: info: camilladsp spawned new process with pid 17565, instance 2, run: true Nov 23 07:19:33 rivo volumio[19717]: info: camilladsp spawned new process with pid 17572, instance 2, run: true Nov 23 07:19:33 rivo volumio[19717]: info: camilladsp spawned new process with pid 17579, instance 2, run: true Nov 23 07:19:33 rivo volumio[19717]: info: camilladsp spawned new process with pid 17586, instance 2, run: true Nov 23 07:19:34 rivo volumio[19717]: info: camilladsp spawned new process with pid 17593, instance 2, run: true Nov 23 07:19:34 rivo volumio[19717]: info: camilladsp spawned new process with pid 17600, instance 2, run: true Nov 23 07:19:34 rivo volumio[19717]: info: camilladsp spawned new process with pid 17607, instance 2, run: true Nov 23 07:19:34 rivo volumio[19717]: info: camilladsp spawned new process with pid 17614, instance 2, run: true Nov 23 07:19:34 rivo volumio[19717]: info: camilladsp spawned new process with pid 17621, instance 2, run: true Nov 23 07:19:34 rivo volumio[19717]: info: camilladsp spawned new process with pid 17628, instance 2, run: true Nov 23 07:19:34 rivo volumio[19717]: info: camilladsp spawned new process with pid 17635, instance 2, run: true Nov 23 07:19:34 rivo volumio[19717]: info: camilladsp spawned new process with pid 17642, instance 2, run: true Nov 23 07:19:34 rivo volumio[19717]: info: camilladsp spawned new process with pid 17649, instance 2, run: true Nov 23 07:19:34 rivo volumio[19717]: info: camilladsp spawned new process with pid 17656, instance 2, run: true Nov 23 07:19:34 rivo volumio[19717]: info: camilladsp spawned new process with pid 17663, instance 2, run: true Nov 23 07:19:35 rivo volumio[19717]: info: camilladsp spawned new process with pid 17670, instance 2, run: true Nov 23 07:19:35 rivo volumio[19717]: info: camilladsp spawned new process with pid 17677, instance 2, run: true Nov 23 07:19:35 rivo volumio[19717]: info: camilladsp spawned new process with pid 17684, instance 2, run: true Nov 23 07:19:35 rivo volumio[19717]: info: camilladsp spawned new process with pid 17691, instance 2, run: true Nov 23 07:19:35 rivo volumio[19717]: info: camilladsp spawned new process with pid 17698, instance 2, run: true Nov 23 07:19:35 rivo volumio[19717]: info: camilladsp spawned new process with pid 17705, instance 2, run: true Nov 23 07:19:35 rivo volumio[19717]: info: camilladsp spawned new process with pid 17712, instance 2, run: true Nov 23 07:19:35 rivo volumio[19717]: info: camilladsp spawned new process with pid 17719, instance 2, run: true Nov 23 07:19:35 rivo volumio[19717]: info: camilladsp spawned new process with pid 17726, instance 2, run: true Nov 23 07:19:35 rivo volumio[19717]: info: camilladsp spawned new process with pid 17733, instance 2, run: true Nov 23 07:19:35 rivo volumio[19717]: info: camilladsp spawned new process with pid 17740, instance 2, run: true Nov 23 07:19:36 rivo volumio[19717]: info: camilladsp spawned new process with pid 17747, instance 2, run: true Nov 23 07:19:36 rivo volumio[19717]: info: camilladsp spawned new process with pid 17754, instance 2, run: true Nov 23 07:19:36 rivo volumio[19717]: info: camilladsp spawned new process with pid 17761, instance 2, run: true Nov 23 07:19:36 rivo volumio[19717]: info: camilladsp spawned new process with pid 17768, instance 2, run: true Nov 23 07:19:36 rivo volumio[19717]: info: camilladsp spawned new process with pid 17775, instance 2, run: true Nov 23 07:19:36 rivo volumio[19717]: info: camilladsp spawned new process with pid 17782, instance 2, run: true Nov 23 07:19:36 rivo volumio[19717]: info: camilladsp spawned new process with pid 17789, instance 2, run: true Nov 23 07:19:36 rivo volumio[19717]: info: camilladsp spawned new process with pid 17796, instance 2, run: true Nov 23 07:19:36 rivo volumio[19717]: info: camilladsp spawned new process with pid 17803, instance 2, run: true Nov 23 07:19:36 rivo volumio[19717]: info: camilladsp spawned new process with pid 17810, instance 2, run: true Nov 23 07:19:36 rivo volumio[19717]: info: camilladsp spawned new process with pid 17817, instance 2, run: true Nov 23 07:19:37 rivo volumio[19717]: info: camilladsp spawned new process with pid 17824, instance 2, run: true Nov 23 07:19:37 rivo volumio[19717]: info: camilladsp spawned new process with pid 17831, instance 2, run: true Nov 23 07:19:37 rivo volumio[19717]: info: camilladsp spawned new process with pid 17838, instance 2, run: true Nov 23 07:19:37 rivo volumio[19717]: info: camilladsp spawned new process with pid 17845, instance 2, run: true Nov 23 07:19:37 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 07:19:37 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 07:19:37 rivo volumio[19717]: info: Discovery: Getting this device information Nov 23 07:19:37 rivo volumio[19717]: info: CoreCommandRouter::volumioGetState Nov 23 07:19:37 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 07:19:37 rivo volumio[19717]: info: camilladsp spawned new process with pid 17852, instance 2, run: true Nov 23 07:19:37 rivo volumio[19717]: info: camilladsp spawned new process with pid 17859, instance 2, run: true Nov 23 07:19:37 rivo volumio[19717]: info: camilladsp spawned new process with pid 17866, instance 2, run: true Nov 23 07:19:37 rivo volumio[19717]: info: camilladsp spawned new process with pid 17873, instance 2, run: true Nov 23 07:19:37 rivo volumio[19717]: info: camilladsp spawned new process with pid 17880, instance 2, run: true Nov 23 07:19:37 rivo volumio[19717]: info: camilladsp spawned new process with pid 17890, instance 2, run: true Nov 23 07:19:37 rivo volumio[19717]: info: camilladsp spawned new process with pid 17897, instance 2, run: true Nov 23 07:19:38 rivo volumio[19717]: info: camilladsp spawned new process with pid 17904, instance 2, run: true Nov 23 07:19:38 rivo volumio[19717]: info: camilladsp spawned new process with pid 17911, instance 2, run: true Nov 23 07:19:38 rivo volumio[19717]: info: camilladsp spawned new process with pid 17918, instance 2, run: true Nov 23 07:19:38 rivo volumio[19717]: info: camilladsp spawned new process with pid 17925, instance 2, run: true Nov 23 07:19:38 rivo volumio[19717]: info: camilladsp spawned new process with pid 17932, instance 2, run: true Nov 23 07:19:38 rivo volumio[19717]: info: camilladsp spawned new process with pid 17939, instance 2, run: true Nov 23 07:19:38 rivo volumio[19717]: info: camilladsp spawned new process with pid 17946, instance 2, run: true Nov 23 07:19:38 rivo volumio[19717]: info: camilladsp spawned new process with pid 17953, instance 2, run: true Nov 23 07:19:38 rivo volumio[19717]: info: camilladsp spawned new process with pid 17960, instance 2, run: true Nov 23 07:19:38 rivo volumio[19717]: info: camilladsp spawned new process with pid 17967, instance 2, run: true Nov 23 07:19:38 rivo volumio[19717]: info: camilladsp spawned new process with pid 17974, instance 2, run: true Nov 23 07:19:39 rivo volumio[19717]: info: camilladsp spawned new process with pid 17981, instance 2, run: true Nov 23 07:19:39 rivo volumio[19717]: info: camilladsp spawned new process with pid 17988, instance 2, run: true Nov 23 07:19:39 rivo volumio[19717]: info: camilladsp spawned new process with pid 17995, instance 2, run: true Nov 23 07:19:39 rivo volumio[19717]: info: camilladsp spawned new process with pid 18002, instance 2, run: true Nov 23 07:19:39 rivo volumio[19717]: info: camilladsp spawned new process with pid 18009, instance 2, run: true Nov 23 07:19:39 rivo volumio[19717]: info: camilladsp spawned new process with pid 18016, instance 2, run: true Nov 23 07:19:39 rivo volumio[19717]: info: camilladsp spawned new process with pid 18023, instance 2, run: true Nov 23 07:19:39 rivo volumio[19717]: info: camilladsp spawned new process with pid 18030, instance 2, run: true Nov 23 07:19:39 rivo volumio[19717]: info: camilladsp spawned new process with pid 18037, instance 2, run: true Nov 23 07:19:39 rivo volumio[19717]: info: camilladsp spawned new process with pid 18044, instance 2, run: true Nov 23 07:19:39 rivo volumio[19717]: info: camilladsp spawned new process with pid 18051, instance 2, run: true Nov 23 07:19:39 rivo volumio[19717]: info: camilladsp spawned new process with pid 18058, instance 2, run: true Nov 23 07:19:40 rivo volumio[19717]: info: camilladsp spawned new process with pid 18065, instance 2, run: true Nov 23 07:19:40 rivo volumio[19717]: info: camilladsp spawned new process with pid 18072, instance 2, run: true Nov 23 07:19:40 rivo volumio[19717]: info: camilladsp spawned new process with pid 18079, instance 2, run: true Nov 23 07:19:40 rivo volumio[19717]: info: camilladsp spawned new process with pid 18086, instance 2, run: true Nov 23 07:19:40 rivo volumio[19717]: info: camilladsp spawned new process with pid 18093, instance 2, run: true Nov 23 07:19:40 rivo volumio[19717]: info: camilladsp spawned new process with pid 18100, instance 2, run: true Nov 23 07:19:40 rivo volumio[19717]: info: camilladsp spawned new process with pid 18107, instance 2, run: true Nov 23 07:19:40 rivo volumio[19717]: info: camilladsp spawned new process with pid 18114, instance 2, run: true Nov 23 07:19:40 rivo volumio[19717]: info: camilladsp spawned new process with pid 18121, instance 2, run: true Nov 23 07:19:40 rivo volumio[19717]: info: camilladsp spawned new process with pid 18128, instance 2, run: true Nov 23 07:19:40 rivo volumio[19717]: info: camilladsp spawned new process with pid 18135, instance 2, run: true Nov 23 07:19:41 rivo volumio[19717]: info: camilladsp spawned new process with pid 18142, instance 2, run: true Nov 23 07:19:41 rivo volumio[19717]: info: camilladsp spawned new process with pid 18149, instance 2, run: true Nov 23 07:19:41 rivo volumio[19717]: info: camilladsp spawned new process with pid 18156, instance 2, run: true Nov 23 07:19:41 rivo volumio[19717]: info: camilladsp spawned new process with pid 18172, instance 2, run: true Nov 23 07:19:41 rivo volumio[19717]: info: camilladsp spawned new process with pid 18187, instance 2, run: true Nov 23 07:19:41 rivo volumio[19717]: info: camilladsp spawned new process with pid 18194, instance 2, run: true Nov 23 07:19:41 rivo volumio[19717]: info: camilladsp spawned new process with pid 18201, instance 2, run: true Nov 23 07:19:41 rivo volumio[19717]: info: camilladsp spawned new process with pid 18208, instance 2, run: true Nov 23 07:19:41 rivo volumio[19717]: info: camilladsp spawned new process with pid 18215, instance 2, run: true Nov 23 07:19:41 rivo volumio[19717]: info: camilladsp spawned new process with pid 18222, instance 2, run: true Nov 23 07:19:41 rivo volumio[19717]: info: camilladsp spawned new process with pid 18229, instance 2, run: true Nov 23 07:19:42 rivo volumio[19717]: info: camilladsp spawned new process with pid 18236, instance 2, run: true Nov 23 07:19:42 rivo volumio[19717]: info: camilladsp spawned new process with pid 18243, instance 2, run: true Nov 23 07:19:42 rivo volumio[19717]: info: camilladsp spawned new process with pid 18250, instance 2, run: true Nov 23 07:19:42 rivo volumio[19717]: info: camilladsp spawned new process with pid 18257, instance 2, run: true Nov 23 07:19:42 rivo volumio[19717]: info: camilladsp spawned new process with pid 18264, instance 2, run: true Nov 23 07:19:42 rivo volumio[19717]: info: camilladsp spawned new process with pid 18271, instance 2, run: true Nov 23 07:19:42 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 23 07:19:42 rivo volumio[19717]: info: camilladsp spawned new process with pid 18278, instance 2, run: true Nov 23 07:19:42 rivo volumio[19717]: info: camilladsp spawned new process with pid 18285, instance 2, run: true Nov 23 07:19:42 rivo volumio[19717]: info: camilladsp spawned new process with pid 18292, instance 2, run: true Nov 23 07:19:42 rivo volumio[19717]: info: camilladsp spawned new process with pid 18299, instance 2, run: true Nov 23 07:19:43 rivo volumio[19717]: info: camilladsp spawned new process with pid 18306, instance 2, run: true Nov 23 07:19:43 rivo volumio[19717]: info: camilladsp spawned new process with pid 18313, instance 2, run: true Nov 23 07:19:43 rivo volumio[19717]: info: camilladsp spawned new process with pid 18320, instance 2, run: true Nov 23 07:19:43 rivo volumio[19717]: info: camilladsp spawned new process with pid 18327, instance 2, run: true Nov 23 07:19:43 rivo volumio[19717]: info: Preload queue cleared Nov 23 07:19:43 rivo volumio[19717]: info: Preloading song: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$1024R17100545 Nov 23 07:19:43 rivo volumio[19717]: info: Preloading song: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$1024R17102081 Nov 23 07:19:43 rivo volumio[19717]: info: Preloading song: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$1024R17101057 Nov 23 07:19:43 rivo volumio[19717]: info: Preloading song: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$1024R17102593 Nov 23 07:19:43 rivo volumio[19717]: info: Preloading song: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$1024R17102337 Nov 23 07:19:43 rivo volumio[19717]: info: Preloading song: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$1024R17101825 Nov 23 07:19:43 rivo volumio[19717]: info: Preloading song: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$1024R17103361 Nov 23 07:19:43 rivo volumio[19717]: info: Preloading song: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$1024R17100801 Nov 23 07:19:43 rivo volumio[19717]: info: Preloading song: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$1024R16435713 Nov 23 07:19:43 rivo volumio[19717]: info: Preloading song: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$1024R17101313 Nov 23 07:19:43 rivo volumio[19717]: info: Preloading song: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$1024R17101569 Nov 23 07:19:43 rivo volumio[19717]: info: Exploding uri upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$1024R17100545 in service upnp_browser Nov 23 07:19:43 rivo volumio[19717]: info: camilladsp spawned new process with pid 18334, instance 2, run: true Nov 23 07:19:43 rivo volumio[19717]: info: Exploding uri upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$1024R17102081 in service upnp_browser Nov 23 07:19:43 rivo volumio[19717]: info: Executing endpoint getSimilarAlbums Nov 23 07:19:43 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Nov 23 07:19:43 rivo volumio[19717]: info: Exploding uri upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$1024R17101057 in service upnp_browser Nov 23 07:19:43 rivo volumio[19717]: info: camilladsp spawned new process with pid 18341, instance 2, run: true Nov 23 07:19:43 rivo volumio[19717]: info: Executing endpoint metavolumio Nov 23 07:19:43 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 23 07:19:43 rivo volumio[19717]: info: Executing endpoint metavolumio Nov 23 07:19:43 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 23 07:19:43 rivo volumio[19717]: info: Exploding uri upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$1024R17102593 in service upnp_browser Nov 23 07:19:43 rivo volumio[19717]: info: Exploding uri upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$1024R17102337 in service upnp_browser Nov 23 07:19:43 rivo volumio[19717]: info: camilladsp spawned new process with pid 18348, instance 2, run: true Nov 23 07:19:43 rivo volumio[19717]: info: Exploding uri upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$1024R17101825 in service upnp_browser Nov 23 07:19:43 rivo volumio[19717]: info: Exploding uri upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$1024R17103361 in service upnp_browser Nov 23 07:19:43 rivo volumio[19717]: info: camilladsp spawned new process with pid 18355, instance 2, run: true Nov 23 07:19:43 rivo volumio[19717]: info: Exploding uri upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$1024R17100801 in service upnp_browser Nov 23 07:19:43 rivo volumio[19717]: info: Exploding uri upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$1024R16435713 in service upnp_browser Nov 23 07:19:43 rivo volumio[19717]: info: camilladsp spawned new process with pid 18362, instance 2, run: true Nov 23 07:19:43 rivo volumio[19717]: info: Exploding uri upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$1024R17101313 in service upnp_browser Nov 23 07:19:43 rivo volumio[19717]: info: Exploding uri upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$1024R17101569 in service upnp_browser Nov 23 07:19:43 rivo volumio[19717]: info: camilladsp spawned new process with pid 18369, instance 2, run: true Nov 23 07:19:43 rivo volumio[19717]: info: camilladsp spawned new process with pid 18376, instance 2, run: true Nov 23 07:19:44 rivo volumio[19717]: info: camilladsp spawned new process with pid 18383, instance 2, run: true Nov 23 07:19:44 rivo volumio[19717]: info: camilladsp spawned new process with pid 18390, instance 2, run: true Nov 23 07:19:44 rivo volumio[19717]: info: camilladsp spawned new process with pid 18397, instance 2, run: true Nov 23 07:19:44 rivo volumio[19717]: info: camilladsp spawned new process with pid 18404, instance 2, run: true Nov 23 07:19:44 rivo volumio[19717]: info: camilladsp spawned new process with pid 18411, instance 2, run: true Nov 23 07:19:44 rivo volumio[19717]: info: camilladsp spawned new process with pid 18431, instance 2, run: true Nov 23 07:19:44 rivo volumio[19717]: info: camilladsp spawned new process with pid 18453, instance 2, run: true Nov 23 07:19:44 rivo volumio[19717]: info: camilladsp spawned new process with pid 18477, instance 2, run: true Nov 23 07:19:44 rivo volumio[19717]: info: camilladsp spawned new process with pid 18495, instance 2, run: true Nov 23 07:19:44 rivo volumio[19717]: info: camilladsp spawned new process with pid 18502, instance 2, run: true Nov 23 07:19:45 rivo volumio[19717]: info: camilladsp spawned new process with pid 18509, instance 2, run: true Nov 23 07:19:45 rivo volumio[19717]: info: camilladsp spawned new process with pid 18516, instance 2, run: true Nov 23 07:19:45 rivo volumio[19717]: info: camilladsp spawned new process with pid 18523, instance 2, run: true Nov 23 07:19:45 rivo volumio[19717]: info: camilladsp spawned new process with pid 18530, instance 2, run: true Nov 23 07:19:45 rivo volumio[19717]: info: camilladsp spawned new process with pid 18537, instance 2, run: true Nov 23 07:19:45 rivo volumio[19717]: info: camilladsp spawned new process with pid 18544, instance 2, run: true Nov 23 07:19:45 rivo volumio[19717]: info: camilladsp spawned new process with pid 18551, instance 2, run: true Nov 23 07:19:45 rivo volumio[19717]: info: camilladsp spawned new process with pid 18558, instance 2, run: true Nov 23 07:19:45 rivo volumio[19717]: info: camilladsp spawned new process with pid 18566, instance 2, run: true Nov 23 07:19:45 rivo volumio[19717]: info: camilladsp spawned new process with pid 18573, instance 2, run: true Nov 23 07:19:46 rivo volumio[19717]: info: camilladsp spawned new process with pid 18580, instance 2, run: true Nov 23 07:19:46 rivo volumio[19717]: info: camilladsp spawned new process with pid 18587, instance 2, run: true Nov 23 07:19:46 rivo volumio[19717]: info: camilladsp spawned new process with pid 18594, instance 2, run: true Nov 23 07:19:46 rivo volumio[19717]: info: camilladsp spawned new process with pid 18601, instance 2, run: true Nov 23 07:19:46 rivo volumio[19717]: info: camilladsp spawned new process with pid 18608, instance 2, run: true Nov 23 07:19:46 rivo volumio[19717]: info: camilladsp spawned new process with pid 18615, instance 2, run: true Nov 23 07:19:46 rivo volumio[19717]: info: camilladsp spawned new process with pid 18622, instance 2, run: true Nov 23 07:19:46 rivo volumio[19717]: info: camilladsp spawned new process with pid 18630, instance 2, run: true Nov 23 07:19:46 rivo volumio[19717]: info: camilladsp spawned new process with pid 18637, instance 2, run: true Nov 23 07:19:46 rivo volumio[19717]: info: camilladsp spawned new process with pid 18644, instance 2, run: true Nov 23 07:19:46 rivo volumio[19717]: info: camilladsp spawned new process with pid 18651, instance 2, run: true Nov 23 07:19:47 rivo volumio[19717]: info: camilladsp spawned new process with pid 18658, instance 2, run: true Nov 23 07:19:47 rivo volumio[19717]: info: camilladsp spawned new process with pid 18665, instance 2, run: true Nov 23 07:19:47 rivo volumio[19717]: info: camilladsp spawned new process with pid 18672, instance 2, run: true Nov 23 07:19:47 rivo volumio[19717]: info: camilladsp spawned new process with pid 18679, instance 2, run: true Nov 23 07:19:47 rivo volumio[19717]: info: camilladsp spawned new process with pid 18686, instance 2, run: true Nov 23 07:19:47 rivo volumio[19717]: info: camilladsp spawned new process with pid 18693, instance 2, run: true Nov 23 07:19:47 rivo volumio[19717]: info: camilladsp spawned new process with pid 18700, instance 2, run: true Nov 23 07:19:47 rivo volumio[19717]: info: camilladsp spawned new process with pid 18707, instance 2, run: true Nov 23 07:19:47 rivo volumio[19717]: info: camilladsp spawned new process with pid 18714, instance 2, run: true Nov 23 07:19:47 rivo volumio[19717]: info: camilladsp spawned new process with pid 18724, instance 2, run: true Nov 23 07:19:47 rivo volumio[19717]: info: camilladsp spawned new process with pid 18731, instance 2, run: true Nov 23 07:19:48 rivo volumio[19717]: info: camilladsp spawned new process with pid 18738, instance 2, run: true Nov 23 07:19:48 rivo volumio[19717]: info: camilladsp spawned new process with pid 18745, instance 2, run: true Nov 23 07:19:48 rivo volumio[19717]: info: camilladsp spawned new process with pid 18752, instance 2, run: true Nov 23 07:19:48 rivo volumio[19717]: info: camilladsp spawned new process with pid 18759, instance 2, run: true Nov 23 07:19:48 rivo volumio[19717]: info: camilladsp spawned new process with pid 18766, instance 2, run: true Nov 23 07:19:48 rivo kernel: [35B blob data] Nov 23 07:19:48 rivo volumio[19717]: info: camilladsp spawned new process with pid 18773, instance 2, run: true Nov 23 07:19:48 rivo kernel: CFG80211-ERROR) wl_notify_rx_mgmt_frame : TDLS Action Frame Received type = 2 Nov 23 07:19:48 rivo kernel: CFG80211-ERROR) wl_tdls_event_handler : TDLS PEER CONNECTED : 9e:fe:9f:dd:56:21 on primary ndev Nov 23 07:19:48 rivo volumio[19717]: info: camilladsp spawned new process with pid 18780, instance 2, run: true Nov 23 07:19:48 rivo volumio[19717]: info: camilladsp spawned new process with pid 18787, instance 2, run: true Nov 23 07:19:48 rivo volumio[19717]: info: camilladsp spawned new process with pid 18794, instance 2, run: true Nov 23 07:19:48 rivo volumio[19717]: info: camilladsp spawned new process with pid 18801, instance 2, run: true Nov 23 07:19:49 rivo volumio[19717]: info: camilladsp spawned new process with pid 18808, instance 2, run: true Nov 23 07:19:49 rivo volumio[19717]: info: camilladsp spawned new process with pid 18815, instance 2, run: true Nov 23 07:19:49 rivo volumio[19717]: info: camilladsp spawned new process with pid 18822, instance 2, run: true Nov 23 07:19:49 rivo volumio[19717]: info: camilladsp spawned new process with pid 18829, instance 2, run: true Nov 23 07:19:49 rivo volumio[19717]: info: camilladsp spawned new process with pid 18836, instance 2, run: true Nov 23 07:19:49 rivo volumio[19717]: info: camilladsp spawned new process with pid 18843, instance 2, run: true Nov 23 07:19:49 rivo volumio[19717]: info: camilladsp spawned new process with pid 18850, instance 2, run: true Nov 23 07:19:49 rivo volumio[19717]: info: camilladsp spawned new process with pid 18857, instance 2, run: true Nov 23 07:19:49 rivo volumio[19717]: info: camilladsp spawned new process with pid 18864, instance 2, run: true Nov 23 07:19:49 rivo volumio[19717]: info: camilladsp spawned new process with pid 18871, instance 2, run: true Nov 23 07:19:49 rivo volumio[19717]: info: camilladsp spawned new process with pid 18878, instance 2, run: true Nov 23 07:19:50 rivo volumio[19717]: info: camilladsp spawned new process with pid 18885, instance 2, run: true Nov 23 07:19:50 rivo volumio[19717]: info: camilladsp spawned new process with pid 18892, instance 2, run: true Nov 23 07:19:50 rivo volumio[19717]: info: camilladsp spawned new process with pid 18899, instance 2, run: true Nov 23 07:19:50 rivo volumio[19717]: info: camilladsp spawned new process with pid 18906, instance 2, run: true Nov 23 07:19:50 rivo volumio[19717]: info: camilladsp spawned new process with pid 18913, instance 2, run: true Nov 23 07:19:50 rivo volumio[19717]: info: camilladsp spawned new process with pid 18920, instance 2, run: true Nov 23 07:19:50 rivo volumio[19717]: info: camilladsp spawned new process with pid 18927, instance 2, run: true Nov 23 07:19:50 rivo volumio[19717]: info: camilladsp spawned new process with pid 18934, instance 2, run: true Nov 23 07:19:50 rivo volumio[19717]: info: camilladsp spawned new process with pid 18941, instance 2, run: true Nov 23 07:19:50 rivo volumio[19717]: info: camilladsp spawned new process with pid 18948, instance 2, run: true Nov 23 07:19:50 rivo volumio[19717]: info: camilladsp spawned new process with pid 18955, instance 2, run: true Nov 23 07:19:51 rivo volumio[19717]: info: camilladsp spawned new process with pid 18962, instance 2, run: true Nov 23 07:19:51 rivo volumio[19717]: info: camilladsp spawned new process with pid 18969, instance 2, run: true Nov 23 07:19:51 rivo volumio[19717]: info: camilladsp spawned new process with pid 18976, instance 2, run: true Nov 23 07:19:51 rivo volumio[19717]: info: camilladsp spawned new process with pid 18983, instance 2, run: true Nov 23 07:19:51 rivo volumio[19717]: info: camilladsp spawned new process with pid 19000, instance 2, run: true Nov 23 07:19:51 rivo volumio[19717]: info: camilladsp spawned new process with pid 19014, instance 2, run: true Nov 23 07:19:51 rivo volumio[19717]: info: camilladsp spawned new process with pid 19021, instance 2, run: true Nov 23 07:19:51 rivo volumio[19717]: info: camilladsp spawned new process with pid 19028, instance 2, run: true Nov 23 07:19:51 rivo volumio[19717]: info: camilladsp spawned new process with pid 19035, instance 2, run: true Nov 23 07:19:51 rivo volumio[19717]: info: camilladsp spawned new process with pid 19042, instance 2, run: true Nov 23 07:19:52 rivo volumio[19717]: info: camilladsp spawned new process with pid 19049, instance 2, run: true Nov 23 07:19:52 rivo volumio[19717]: info: camilladsp spawned new process with pid 19056, instance 2, run: true Nov 23 07:19:52 rivo volumio[19717]: info: camilladsp spawned new process with pid 19063, instance 2, run: true Nov 23 07:19:52 rivo volumio[19717]: info: camilladsp spawned new process with pid 19070, instance 2, run: true Nov 23 07:19:52 rivo volumio[19717]: info: camilladsp spawned new process with pid 19077, instance 2, run: true Nov 23 07:19:52 rivo volumio[19717]: info: camilladsp spawned new process with pid 19084, instance 2, run: true Nov 23 07:19:52 rivo volumio[19717]: info: camilladsp spawned new process with pid 19091, instance 2, run: true Nov 23 07:19:52 rivo volumio[19717]: info: camilladsp spawned new process with pid 19098, instance 2, run: true Nov 23 07:19:52 rivo volumio[19717]: info: camilladsp spawned new process with pid 19105, instance 2, run: true Nov 23 07:19:52 rivo volumio[19717]: info: camilladsp spawned new process with pid 19112, instance 2, run: true Nov 23 07:19:52 rivo volumio[19717]: info: camilladsp spawned new process with pid 19119, instance 2, run: true Nov 23 07:19:53 rivo volumio[19717]: info: camilladsp spawned new process with pid 19126, instance 2, run: true Nov 23 07:19:53 rivo volumio[19717]: info: camilladsp spawned new process with pid 19133, instance 2, run: true Nov 23 07:19:53 rivo volumio[19717]: info: camilladsp spawned new process with pid 19140, instance 2, run: true Nov 23 07:19:53 rivo volumio[19717]: info: camilladsp spawned new process with pid 19147, instance 2, run: true Nov 23 07:19:53 rivo volumio[19717]: info: camilladsp spawned new process with pid 19154, instance 2, run: true Nov 23 07:19:53 rivo volumio[19717]: info: camilladsp spawned new process with pid 19161, instance 2, run: true Nov 23 07:19:53 rivo volumio[19717]: info: camilladsp spawned new process with pid 19168, instance 2, run: true Nov 23 07:19:53 rivo volumio[19717]: info: camilladsp spawned new process with pid 19175, instance 2, run: true Nov 23 07:19:53 rivo volumio[19717]: info: camilladsp spawned new process with pid 19182, instance 2, run: true Nov 23 07:19:53 rivo volumio[19717]: info: camilladsp spawned new process with pid 19189, instance 2, run: true Nov 23 07:19:53 rivo volumio[19717]: info: camilladsp spawned new process with pid 19196, instance 2, run: true Nov 23 07:19:54 rivo volumio[19717]: info: camilladsp spawned new process with pid 19203, instance 2, run: true Nov 23 07:19:54 rivo volumio[19717]: info: camilladsp spawned new process with pid 19210, instance 2, run: true Nov 23 07:19:54 rivo volumio[19717]: info: camilladsp spawned new process with pid 19217, instance 2, run: true Nov 23 07:19:54 rivo volumio[19717]: info: camilladsp spawned new process with pid 19224, instance 2, run: true Nov 23 07:19:54 rivo volumio[19717]: info: camilladsp spawned new process with pid 19231, instance 2, run: true Nov 23 07:19:54 rivo volumio[19717]: info: camilladsp spawned new process with pid 19238, instance 2, run: true Nov 23 07:19:54 rivo volumio[19717]: info: camilladsp spawned new process with pid 19245, instance 2, run: true Nov 23 07:19:54 rivo volumio[19717]: info: camilladsp spawned new process with pid 19252, instance 2, run: true Nov 23 07:19:54 rivo volumio[19717]: info: camilladsp spawned new process with pid 19259, instance 2, run: true Nov 23 07:19:54 rivo volumio[19717]: info: camilladsp spawned new process with pid 19266, instance 2, run: true Nov 23 07:19:54 rivo volumio[19717]: info: camilladsp spawned new process with pid 19273, instance 2, run: true Nov 23 07:19:55 rivo volumio[19717]: info: camilladsp spawned new process with pid 19280, instance 2, run: true Nov 23 07:19:55 rivo volumio[19717]: info: camilladsp spawned new process with pid 19287, instance 2, run: true Nov 23 07:19:55 rivo volumio[19717]: info: camilladsp spawned new process with pid 19294, instance 2, run: true Nov 23 07:19:55 rivo volumio[19717]: info: camilladsp spawned new process with pid 19301, instance 2, run: true Nov 23 07:19:55 rivo volumio[19717]: info: camilladsp spawned new process with pid 19308, instance 2, run: true Nov 23 07:19:55 rivo volumio[19717]: info: camilladsp spawned new process with pid 19315, instance 2, run: true Nov 23 07:19:55 rivo volumio[19717]: info: camilladsp spawned new process with pid 19322, instance 2, run: true Nov 23 07:19:55 rivo volumio[19717]: info: camilladsp spawned new process with pid 19329, instance 2, run: true Nov 23 07:19:55 rivo volumio[19717]: info: camilladsp spawned new process with pid 19336, instance 2, run: true Nov 23 07:19:55 rivo volumio[19717]: info: camilladsp spawned new process with pid 19343, instance 2, run: true Nov 23 07:19:55 rivo volumio[19717]: info: camilladsp spawned new process with pid 19350, instance 2, run: true Nov 23 07:19:56 rivo volumio[19717]: info: camilladsp spawned new process with pid 19357, instance 2, run: true Nov 23 07:19:56 rivo volumio[19717]: info: camilladsp spawned new process with pid 19364, instance 2, run: true Nov 23 07:19:56 rivo volumio[19717]: info: camilladsp spawned new process with pid 19371, instance 2, run: true Nov 23 07:19:56 rivo volumio[19717]: info: camilladsp spawned new process with pid 19378, instance 2, run: true Nov 23 07:19:56 rivo volumio[19717]: info: camilladsp spawned new process with pid 19385, instance 2, run: true Nov 23 07:19:56 rivo volumio[19717]: info: camilladsp spawned new process with pid 19392, instance 2, run: true Nov 23 07:19:56 rivo volumio[19717]: info: camilladsp spawned new process with pid 19399, instance 2, run: true Nov 23 07:19:56 rivo volumio[19717]: info: camilladsp spawned new process with pid 19406, instance 2, run: true Nov 23 07:19:56 rivo volumio[19717]: info: camilladsp spawned new process with pid 19413, instance 2, run: true Nov 23 07:19:56 rivo volumio[19717]: info: camilladsp spawned new process with pid 19420, instance 2, run: true Nov 23 07:19:56 rivo volumio[19717]: info: camilladsp spawned new process with pid 19427, instance 2, run: true Nov 23 07:19:57 rivo volumio[19717]: info: camilladsp spawned new process with pid 19434, instance 2, run: true Nov 23 07:19:57 rivo volumio[19717]: info: camilladsp spawned new process with pid 19441, instance 2, run: true Nov 23 07:19:57 rivo volumio[19717]: info: camilladsp spawned new process with pid 19448, instance 2, run: true Nov 23 07:19:57 rivo volumio[19717]: info: camilladsp spawned new process with pid 19455, instance 2, run: true Nov 23 07:19:57 rivo volumio[19717]: info: camilladsp spawned new process with pid 19462, instance 2, run: true Nov 23 07:19:57 rivo volumio[19717]: info: camilladsp spawned new process with pid 19469, instance 2, run: true Nov 23 07:19:57 rivo volumio[19717]: info: camilladsp spawned new process with pid 19476, instance 2, run: true Nov 23 07:19:57 rivo volumio[19717]: info: camilladsp spawned new process with pid 19483, instance 2, run: true Nov 23 07:19:57 rivo volumio[19717]: info: camilladsp spawned new process with pid 19490, instance 2, run: true Nov 23 07:19:57 rivo volumio[19717]: info: camilladsp spawned new process with pid 19499, instance 2, run: true Nov 23 07:19:57 rivo volumio[19717]: info: camilladsp spawned new process with pid 19507, instance 2, run: true Nov 23 07:19:58 rivo volumio[19717]: info: camilladsp spawned new process with pid 19514, instance 2, run: true Nov 23 07:19:58 rivo volumio[19717]: info: camilladsp spawned new process with pid 19521, instance 2, run: true Nov 23 07:19:58 rivo volumio[19717]: info: camilladsp spawned new process with pid 19528, instance 2, run: true Nov 23 07:19:58 rivo volumio[19717]: info: camilladsp spawned new process with pid 19535, instance 2, run: true Nov 23 07:19:58 rivo volumio[19717]: info: camilladsp spawned new process with pid 19542, instance 2, run: true Nov 23 07:19:58 rivo volumio[19717]: info: camilladsp spawned new process with pid 19549, instance 2, run: true Nov 23 07:19:58 rivo volumio[19717]: info: camilladsp spawned new process with pid 19556, instance 2, run: true Nov 23 07:19:58 rivo volumio[19717]: info: camilladsp spawned new process with pid 19563, instance 2, run: true Nov 23 07:19:58 rivo volumio[19717]: info: camilladsp spawned new process with pid 19570, instance 2, run: true Nov 23 07:19:58 rivo volumio[19717]: info: camilladsp spawned new process with pid 19577, instance 2, run: true Nov 23 07:19:58 rivo volumio[19717]: info: camilladsp spawned new process with pid 19584, instance 2, run: true Nov 23 07:19:59 rivo volumio[19717]: info: camilladsp spawned new process with pid 19591, instance 2, run: true Nov 23 07:19:59 rivo volumio[19717]: info: camilladsp spawned new process with pid 19598, instance 2, run: true Nov 23 07:19:59 rivo volumio[19717]: info: camilladsp spawned new process with pid 19605, instance 2, run: true Nov 23 07:19:59 rivo volumio[19717]: info: camilladsp spawned new process with pid 19612, instance 2, run: true Nov 23 07:19:59 rivo volumio[19717]: info: camilladsp spawned new process with pid 19619, instance 2, run: true Nov 23 07:19:59 rivo volumio[19717]: info: camilladsp spawned new process with pid 19626, instance 2, run: true Nov 23 07:19:59 rivo volumio[19717]: info: camilladsp spawned new process with pid 19633, instance 2, run: true Nov 23 07:19:59 rivo volumio[19717]: info: camilladsp spawned new process with pid 19640, instance 2, run: true Nov 23 07:19:59 rivo volumio[19717]: info: camilladsp spawned new process with pid 19647, instance 2, run: true Nov 23 07:19:59 rivo volumio[19717]: info: camilladsp spawned new process with pid 19654, instance 2, run: true Nov 23 07:19:59 rivo volumio[19717]: info: camilladsp spawned new process with pid 19661, instance 2, run: true Nov 23 07:20:00 rivo volumio[19717]: info: camilladsp spawned new process with pid 19668, instance 2, run: true Nov 23 07:20:00 rivo volumio[19717]: info: camilladsp spawned new process with pid 19675, instance 2, run: true Nov 23 07:20:00 rivo volumio[19717]: info: camilladsp spawned new process with pid 19682, instance 2, run: true Nov 23 07:20:00 rivo volumio[19717]: info: camilladsp spawned new process with pid 19689, instance 2, run: true Nov 23 07:20:00 rivo volumio[19717]: info: camilladsp spawned new process with pid 19696, instance 2, run: true Nov 23 07:20:00 rivo volumio[19717]: info: camilladsp spawned new process with pid 19703, instance 2, run: true Nov 23 07:20:00 rivo volumio[19717]: info: camilladsp spawned new process with pid 19710, instance 2, run: true Nov 23 07:20:00 rivo volumio[19717]: info: camilladsp spawned new process with pid 19718, instance 2, run: true Nov 23 07:20:00 rivo volumio[19717]: info: camilladsp spawned new process with pid 19730, instance 2, run: true Nov 23 07:20:00 rivo volumio[19717]: info: camilladsp spawned new process with pid 19742, instance 2, run: true Nov 23 07:20:00 rivo volumio[19717]: info: camilladsp spawned new process with pid 19749, instance 2, run: true Nov 23 07:20:01 rivo volumio[19717]: info: camilladsp spawned new process with pid 19757, instance 2, run: true Nov 23 07:20:01 rivo volumio[19717]: info: camilladsp spawned new process with pid 19764, instance 2, run: true Nov 23 07:20:01 rivo volumio[19717]: info: camilladsp spawned new process with pid 19771, instance 2, run: true Nov 23 07:20:01 rivo volumio[19717]: info: camilladsp spawned new process with pid 19778, instance 2, run: true Nov 23 07:20:01 rivo volumio[19717]: info: camilladsp spawned new process with pid 19785, instance 2, run: true Nov 23 07:20:01 rivo volumio[19717]: info: camilladsp spawned new process with pid 19801, instance 2, run: true Nov 23 07:20:01 rivo volumio[19717]: info: camilladsp spawned new process with pid 19861, instance 2, run: true Nov 23 07:20:01 rivo volumio[19717]: info: camilladsp spawned new process with pid 19872, instance 2, run: true Nov 23 07:20:01 rivo volumio[19717]: info: camilladsp spawned new process with pid 19879, instance 2, run: true Nov 23 07:20:01 rivo volumio[19717]: info: camilladsp spawned new process with pid 19886, instance 2, run: true Nov 23 07:20:01 rivo volumio[19717]: info: camilladsp spawned new process with pid 19894, instance 2, run: true Nov 23 07:20:02 rivo volumio[19717]: info: camilladsp spawned new process with pid 19901, instance 2, run: true Nov 23 07:20:02 rivo volumio[19717]: info: camilladsp spawned new process with pid 19908, instance 2, run: true Nov 23 07:20:02 rivo volumio[19717]: info: camilladsp spawned new process with pid 19915, instance 2, run: true Nov 23 07:20:02 rivo volumio[19717]: info: camilladsp spawned new process with pid 19922, instance 2, run: true Nov 23 07:20:02 rivo volumio[19717]: info: camilladsp spawned new process with pid 19929, instance 2, run: true Nov 23 07:20:02 rivo volumio[19717]: info: camilladsp spawned new process with pid 19936, instance 2, run: true Nov 23 07:20:02 rivo volumio[19717]: info: camilladsp spawned new process with pid 19943, instance 2, run: true Nov 23 07:20:02 rivo volumio[19717]: info: camilladsp spawned new process with pid 19950, instance 2, run: true Nov 23 07:20:02 rivo volumio[19717]: info: camilladsp spawned new process with pid 19957, instance 2, run: true Nov 23 07:20:02 rivo volumio[19717]: info: camilladsp spawned new process with pid 19964, instance 2, run: true Nov 23 07:20:03 rivo volumio[19717]: info: camilladsp spawned new process with pid 19974, instance 2, run: true Nov 23 07:20:03 rivo volumio[19717]: info: camilladsp spawned new process with pid 19981, instance 2, run: true Nov 23 07:20:03 rivo volumio[19717]: info: camilladsp spawned new process with pid 19989, instance 2, run: true Nov 23 07:20:03 rivo volumio[19717]: info: camilladsp spawned new process with pid 19997, instance 2, run: true Nov 23 07:20:03 rivo volumio[19717]: info: camilladsp spawned new process with pid 20007, instance 2, run: true Nov 23 07:20:03 rivo volumio[19717]: info: camilladsp spawned new process with pid 20015, instance 2, run: true Nov 23 07:20:03 rivo volumio[19717]: info: camilladsp spawned new process with pid 20028, instance 2, run: true Nov 23 07:20:03 rivo volumio[19717]: info: camilladsp spawned new process with pid 20037, instance 2, run: true Nov 23 07:20:03 rivo volumio[19717]: info: camilladsp spawned new process with pid 20044, instance 2, run: true Nov 23 07:20:03 rivo volumio[19717]: info: camilladsp spawned new process with pid 20051, instance 2, run: true Nov 23 07:20:03 rivo volumio[19717]: info: camilladsp spawned new process with pid 20058, instance 2, run: true Nov 23 07:20:03 rivo kernel: CFG80211-ERROR) wl_tdls_event_handler : TDLS PEER DISCONNECTED : 9e:fe:9f:dd:56:21 on primary ndev Nov 23 07:20:03 rivo kernel: [35B blob data] Nov 23 07:20:03 rivo kernel: CFG80211-ERROR) wl_notify_rx_mgmt_frame : TDLS Action Frame Received type = 3 Nov 23 07:20:04 rivo volumio[19717]: info: camilladsp spawned new process with pid 20065, instance 2, run: true Nov 23 07:20:04 rivo volumio[19717]: info: camilladsp spawned new process with pid 20072, instance 2, run: true Nov 23 07:20:04 rivo volumio[19717]: info: camilladsp spawned new process with pid 20079, instance 2, run: true Nov 23 07:20:04 rivo volumio[19717]: info: camilladsp spawned new process with pid 20086, instance 2, run: true Nov 23 07:20:04 rivo volumio[19717]: info: camilladsp spawned new process with pid 20093, instance 2, run: true Nov 23 07:20:04 rivo volumio[19717]: info: camilladsp spawned new process with pid 20100, instance 2, run: true Nov 23 07:20:04 rivo volumio[19717]: info: camilladsp spawned new process with pid 20107, instance 2, run: true Nov 23 07:20:04 rivo volumio[19717]: info: camilladsp spawned new process with pid 20114, instance 2, run: true Nov 23 07:20:04 rivo volumio[19717]: info: camilladsp spawned new process with pid 20121, instance 2, run: true Nov 23 07:20:04 rivo volumio[19717]: info: camilladsp spawned new process with pid 20128, instance 2, run: true Nov 23 07:20:04 rivo volumio[19717]: info: camilladsp spawned new process with pid 20135, instance 2, run: true Nov 23 07:20:05 rivo volumio[19717]: info: camilladsp spawned new process with pid 20142, instance 2, run: true Nov 23 07:20:05 rivo volumio[19717]: info: camilladsp spawned new process with pid 20149, instance 2, run: true Nov 23 07:20:05 rivo volumio[19717]: info: camilladsp spawned new process with pid 20156, instance 2, run: true Nov 23 07:20:05 rivo volumio[19717]: info: camilladsp spawned new process with pid 20163, instance 2, run: true Nov 23 07:20:05 rivo volumio[19717]: info: camilladsp spawned new process with pid 20170, instance 2, run: true Nov 23 07:20:05 rivo volumio[19717]: info: camilladsp spawned new process with pid 20177, instance 2, run: true Nov 23 07:20:05 rivo volumio[19717]: info: camilladsp spawned new process with pid 20184, instance 2, run: true Nov 23 07:20:05 rivo volumio[19717]: info: camilladsp spawned new process with pid 20191, instance 2, run: true Nov 23 07:20:05 rivo volumio[19717]: info: camilladsp spawned new process with pid 20198, instance 2, run: true Nov 23 07:20:05 rivo volumio[19717]: info: camilladsp spawned new process with pid 20205, instance 2, run: true Nov 23 07:20:05 rivo volumio[19717]: info: camilladsp spawned new process with pid 20212, instance 2, run: true Nov 23 07:20:06 rivo volumio[19717]: info: camilladsp spawned new process with pid 20219, instance 2, run: true Nov 23 07:20:06 rivo volumio[19717]: info: camilladsp spawned new process with pid 20226, instance 2, run: true Nov 23 07:20:06 rivo volumio[19717]: info: camilladsp spawned new process with pid 20233, instance 2, run: true Nov 23 07:20:06 rivo volumio[19717]: info: camilladsp spawned new process with pid 20240, instance 2, run: true Nov 23 07:20:06 rivo volumio[19717]: info: camilladsp spawned new process with pid 20247, instance 2, run: true Nov 23 07:20:06 rivo volumio[19717]: info: camilladsp spawned new process with pid 20254, instance 2, run: true Nov 23 07:20:06 rivo volumio[19717]: info: camilladsp spawned new process with pid 20261, instance 2, run: true Nov 23 07:20:06 rivo volumio[19717]: info: camilladsp spawned new process with pid 20268, instance 2, run: true Nov 23 07:20:06 rivo volumio[19717]: info: camilladsp spawned new process with pid 20275, instance 2, run: true Nov 23 07:20:06 rivo volumio[19717]: info: camilladsp spawned new process with pid 20282, instance 2, run: true Nov 23 07:20:06 rivo volumio[19717]: info: camilladsp spawned new process with pid 20289, instance 2, run: true Nov 23 07:20:07 rivo volumio[19717]: info: camilladsp spawned new process with pid 20296, instance 2, run: true Nov 23 07:20:07 rivo volumio[19717]: info: camilladsp spawned new process with pid 20303, instance 2, run: true Nov 23 07:20:07 rivo volumio[19717]: info: camilladsp spawned new process with pid 20310, instance 2, run: true Nov 23 07:20:07 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 07:20:07 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 07:20:07 rivo volumio[19717]: info: Discovery: Getting this device information Nov 23 07:20:07 rivo volumio[19717]: info: CoreCommandRouter::volumioGetState Nov 23 07:20:07 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 07:20:07 rivo volumio[19717]: info: camilladsp spawned new process with pid 20317, instance 2, run: true Nov 23 07:20:07 rivo volumio[19717]: info: camilladsp spawned new process with pid 20324, instance 2, run: true Nov 23 07:20:07 rivo volumio[19717]: info: camilladsp spawned new process with pid 20331, instance 2, run: true Nov 23 07:20:07 rivo volumio[19717]: info: camilladsp spawned new process with pid 20338, instance 2, run: true Nov 23 07:20:07 rivo volumio[19717]: info: camilladsp spawned new process with pid 20345, instance 2, run: true Nov 23 07:20:07 rivo volumio[19717]: info: camilladsp spawned new process with pid 20352, instance 2, run: true Nov 23 07:20:07 rivo volumio[19717]: info: camilladsp spawned new process with pid 20362, instance 2, run: true Nov 23 07:20:08 rivo volumio[19717]: info: camilladsp spawned new process with pid 20369, instance 2, run: true Nov 23 07:20:08 rivo volumio[19717]: info: camilladsp spawned new process with pid 20376, instance 2, run: true Nov 23 07:20:08 rivo volumio[19717]: info: camilladsp spawned new process with pid 20383, instance 2, run: true Nov 23 07:20:08 rivo volumio[19717]: info: camilladsp spawned new process with pid 20390, instance 2, run: true Nov 23 07:20:08 rivo volumio[19717]: info: camilladsp spawned new process with pid 20397, instance 2, run: true Nov 23 07:20:08 rivo volumio[19717]: info: camilladsp spawned new process with pid 20404, instance 2, run: true Nov 23 07:20:08 rivo volumio[19717]: info: camilladsp spawned new process with pid 20411, instance 2, run: true Nov 23 07:20:08 rivo volumio[19717]: info: camilladsp spawned new process with pid 20418, instance 2, run: true Nov 23 07:20:08 rivo volumio[19717]: info: camilladsp spawned new process with pid 20425, instance 2, run: true Nov 23 07:20:08 rivo volumio[19717]: info: camilladsp spawned new process with pid 20432, instance 2, run: true Nov 23 07:20:09 rivo volumio[19717]: info: camilladsp spawned new process with pid 20439, instance 2, run: true Nov 23 07:20:09 rivo volumio[19717]: info: camilladsp spawned new process with pid 20446, instance 2, run: true Nov 23 07:20:09 rivo volumio[19717]: info: camilladsp spawned new process with pid 20453, instance 2, run: true Nov 23 07:20:09 rivo volumio[19717]: info: camilladsp spawned new process with pid 20460, instance 2, run: true Nov 23 07:20:09 rivo volumio[19717]: info: camilladsp spawned new process with pid 20467, instance 2, run: true Nov 23 07:20:09 rivo volumio[19717]: info: camilladsp spawned new process with pid 20474, instance 2, run: true Nov 23 07:20:09 rivo volumio[19717]: info: camilladsp spawned new process with pid 20481, instance 2, run: true Nov 23 07:20:09 rivo volumio[19717]: info: camilladsp spawned new process with pid 20488, instance 2, run: true Nov 23 07:20:09 rivo volumio[19717]: info: camilladsp spawned new process with pid 20495, instance 2, run: true Nov 23 07:20:09 rivo volumio[19717]: info: camilladsp spawned new process with pid 20502, instance 2, run: true Nov 23 07:20:09 rivo volumio[19717]: info: camilladsp spawned new process with pid 20509, instance 2, run: true Nov 23 07:20:10 rivo volumio[19717]: info: camilladsp spawned new process with pid 20516, instance 2, run: true Nov 23 07:20:10 rivo volumio[19717]: info: camilladsp spawned new process with pid 20523, instance 2, run: true Nov 23 07:20:10 rivo volumio[19717]: info: camilladsp spawned new process with pid 20530, instance 2, run: true Nov 23 07:20:10 rivo volumio[19717]: info: camilladsp spawned new process with pid 20537, instance 2, run: true Nov 23 07:20:10 rivo volumio[19717]: info: camilladsp spawned new process with pid 20544, instance 2, run: true Nov 23 07:20:10 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 23 07:20:10 rivo volumio[19717]: info: camilladsp spawned new process with pid 20551, instance 2, run: true Nov 23 07:20:10 rivo volumio[19717]: info: camilladsp spawned new process with pid 20558, instance 2, run: true Nov 23 07:20:10 rivo volumio[19717]: info: camilladsp spawned new process with pid 20565, instance 2, run: true Nov 23 07:20:10 rivo volumio[19717]: info: camilladsp spawned new process with pid 20572, instance 2, run: true Nov 23 07:20:10 rivo volumio[19717]: info: camilladsp spawned new process with pid 20579, instance 2, run: true Nov 23 07:20:10 rivo volumio[19717]: info: camilladsp spawned new process with pid 20586, instance 2, run: true Nov 23 07:20:11 rivo volumio[19717]: info: camilladsp spawned new process with pid 20593, instance 2, run: true Nov 23 07:20:11 rivo volumio[19717]: info: camilladsp spawned new process with pid 20600, instance 2, run: true Nov 23 07:20:11 rivo volumio[19717]: info: camilladsp spawned new process with pid 20607, instance 2, run: true Nov 23 07:20:11 rivo volumio[19717]: info: camilladsp spawned new process with pid 20614, instance 2, run: true Nov 23 07:20:11 rivo volumio[19717]: info: camilladsp spawned new process with pid 20621, instance 2, run: true Nov 23 07:20:11 rivo volumio[19717]: info: camilladsp spawned new process with pid 20628, instance 2, run: true Nov 23 07:20:11 rivo volumio[19717]: info: camilladsp spawned new process with pid 20635, instance 2, run: true Nov 23 07:20:11 rivo volumio[19717]: info: camilladsp spawned new process with pid 20658, instance 2, run: true Nov 23 07:20:11 rivo volumio[19717]: info: camilladsp spawned new process with pid 20666, instance 2, run: true Nov 23 07:20:11 rivo volumio[19717]: info: camilladsp spawned new process with pid 20673, instance 2, run: true Nov 23 07:20:11 rivo volumio[19717]: info: camilladsp spawned new process with pid 20680, instance 2, run: true Nov 23 07:20:12 rivo volumio[19717]: info: camilladsp spawned new process with pid 20687, instance 2, run: true Nov 23 07:20:12 rivo volumio[19717]: info: camilladsp spawned new process with pid 20694, instance 2, run: true Nov 23 07:20:12 rivo volumio[19717]: info: camilladsp spawned new process with pid 20701, instance 2, run: true Nov 23 07:20:12 rivo volumio[19717]: info: camilladsp spawned new process with pid 20708, instance 2, run: true Nov 23 07:20:12 rivo volumio[19717]: info: camilladsp spawned new process with pid 20715, instance 2, run: true Nov 23 07:20:12 rivo volumio[19717]: info: Preload queue cleared Nov 23 07:20:12 rivo volumio[19717]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 23 07:20:12 rivo volumio[19717]: info: CoreStateMachine::ClearQueue Nov 23 07:20:12 rivo volumio[19717]: info: CoreStateMachine::stop Nov 23 07:20:12 rivo volumio[19717]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 07:20:12 rivo volumio[19717]: info: CoreStateMachine::stPlaybackTimer Nov 23 07:20:12 rivo volumio[19717]: info: CoreStateMachine::updateTrackBlock Nov 23 07:20:12 rivo volumio[19717]: info: CorePlayQueue::getTrackBlock Nov 23 07:20:12 rivo volumio[19717]: info: CoreStateMachine::pushState Nov 23 07:20:12 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:20:12 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 07:20:12 rivo volumio[19717]: info: CoreCommandRouter::volumioPushState Nov 23 07:20:12 rivo volumio[19717]: info: CoreStateMachine::serviceStop Nov 23 07:20:12 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:20:12 rivo volumio[19717]: info: CoreCommandRouter::serviceStop Nov 23 07:20:12 rivo volumio[19717]: info: [1732342812494] ControllerUPNPBrowser::stop Nov 23 07:20:12 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 07:20:12 rivo volumio[19717]: info: CorePlayQueue::clearPlayQueue Nov 23 07:20:12 rivo volumio[19717]: info: CorePlayQueue::saveQueue Nov 23 07:20:12 rivo volumio[19717]: info: CoreCommandRouter::volumioPushQueue Nov 23 07:20:12 rivo volumio[19717]: info: CoreStateMachine::addQueueItems Nov 23 07:20:12 rivo volumio[19717]: info: CorePlayQueue::addQueueItems Nov 23 07:20:12 rivo volumio[19717]: info: Preload queue cleared Nov 23 07:20:12 rivo volumio[19717]: info: Adding Item to queue: upnp/folder/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$884 Nov 23 07:20:12 rivo volumio[19717]: info: Exploding uri upnp/folder/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$884 in service upnp_browser Nov 23 07:20:12 rivo volumio[19717]: info: Updating RAAT Signal Path Nov 23 07:20:12 rivo volumio[19717]: info: camilladsp spawned new process with pid 20722, instance 2, run: true Nov 23 07:20:12 rivo volumio[19717]: info: Nov 23 07:20:12 rivo volumio[19717]: ---------------------------- MPD announces state update: player Nov 23 07:20:12 rivo volumio[19717]: info: sendMpdCommand stop took 79 milliseconds Nov 23 07:20:12 rivo volumio[19717]: info: ControllerMpd::getState Nov 23 07:20:12 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand status Nov 23 07:20:12 rivo volumio[19717]: info: sendMpdCommand status took 3 milliseconds Nov 23 07:20:12 rivo volumio[19717]: verbose: ControllerMpd::parseState Nov 23 07:20:12 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 07:20:12 rivo volumio[19717]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 23 07:20:12 rivo volumio[19717]: verbose: ControllerMpd::parseTrackInfo Nov 23 07:20:12 rivo volumio[19717]: info: ControllerMpd::pushState Nov 23 07:20:12 rivo volumio[19717]: info: CoreCommandRouter::servicePushState Nov 23 07:20:12 rivo volumio[19717]: info: CoreStateMachine::pushState Nov 23 07:20:12 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:20:12 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 07:20:12 rivo volumio[19717]: info: CoreCommandRouter::volumioPushState Nov 23 07:20:12 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:20:12 rivo volumio[19717]: 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":"O0$1$8I16214529.flac","artist":"Alunni del Sole","album":"I successi","uri":"http://192.168.0.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I16214529.flac","trackType":"flac"} Nov 23 07:20:12 rivo volumio[19717]: verbose: CURRENT POSITION 0 Nov 23 07:20:12 rivo volumio[19717]: info: CoreStateMachine::syncState stateService stop Nov 23 07:20:12 rivo volumio[19717]: info: CoreStateMachine::syncState currentStatus stop Nov 23 07:20:12 rivo volumio[19717]: info: CoreStateMachine::pushState Nov 23 07:20:12 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:20:12 rivo volumio[19717]: info: CoreCommandRouter::volumioPushState Nov 23 07:20:12 rivo volumio[19717]: info: No code Nov 23 07:20:12 rivo volumio[19717]: info: CoreStateMachine::pushState Nov 23 07:20:12 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:20:12 rivo volumio[19717]: info: CoreCommandRouter::volumioPushState Nov 23 07:20:12 rivo volumio[19717]: info: ------------------------------ 61ms Nov 23 07:20:12 rivo volumio[19717]: info: Updating RAAT Signal Path Nov 23 07:20:12 rivo volumio[19717]: info: Updating RAAT Signal Path Nov 23 07:20:12 rivo volumio[19717]: info: Updating RAAT Signal Path Nov 23 07:20:12 rivo volumio[19717]: info: MCU Signalled Playback Inactive Nov 23 07:20:12 rivo volumio[19717]: info: camilladsp spawned new process with pid 20729, instance 2, run: true Nov 23 07:20:12 rivo volumio[19717]: info: camilladsp spawned new process with pid 20736, instance 1, run: true Nov 23 07:20:12 rivo volumio[19717]: info: camilladsp spawned new process with pid 20743, instance 1, run: true Nov 23 07:20:12 rivo volumio[19717]: info: camilladsp spawned new process with pid 20750, instance 1, run: true Nov 23 07:20:13 rivo volumio[19717]: info: camilladsp spawned new process with pid 20757, instance 1, run: true Nov 23 07:20:13 rivo volumio[19717]: info: camilladsp spawned new process with pid 20764, instance 1, run: true Nov 23 07:20:13 rivo volumio[19717]: info: camilladsp spawned new process with pid 20771, instance 1, run: true Nov 23 07:20:13 rivo volumio[19717]: info: camilladsp spawned new process with pid 20778, instance 1, run: true Nov 23 07:20:13 rivo volumio[19717]: info: camilladsp spawned new process with pid 20785, instance 1, run: true Nov 23 07:20:13 rivo volumio[19717]: info: camilladsp spawned new process with pid 20792, instance 1, run: true Nov 23 07:20:13 rivo volumio[19717]: info: CoreCommandRouter::volumioPushQueue Nov 23 07:20:13 rivo volumio[19717]: info: CorePlayQueue::saveQueue Nov 23 07:20:13 rivo volumio[19717]: info: CoreStateMachine::updateTrackBlock Nov 23 07:20:13 rivo volumio[19717]: info: CorePlayQueue::getTrackBlock Nov 23 07:20:13 rivo volumio[19717]: info: CoreCommandRouter::volumioPlay Nov 23 07:20:13 rivo volumio[19717]: info: CoreStateMachine::play index 0 Nov 23 07:20:13 rivo volumio[19717]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 07:20:13 rivo volumio[19717]: info: CoreStateMachine::stop Nov 23 07:20:13 rivo volumio[19717]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 07:20:13 rivo volumio[19717]: info: CoreStateMachine::play index undefined Nov 23 07:20:13 rivo volumio[19717]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 07:20:13 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:20:13 rivo volumio[19717]: info: CoreStateMachine::startPlaybackTimer Nov 23 07:20:13 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:20:13 rivo volumio[19717]: info: [1732342813503] ControllerUPNPBrowser::clearAddPlayTrack Nov 23 07:20:13 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 07:20:13 rivo volumio[19717]: info: sendMpdCommand stop took 12 milliseconds Nov 23 07:20:13 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 07:20:13 rivo volumio[19717]: info: camilladsp spawned new process with pid 20799, instance 1, run: true Nov 23 07:20:13 rivo volumio[19717]: info: Nov 23 07:20:13 rivo volumio[19717]: ---------------------------- MPD announces system playlist update Nov 23 07:20:13 rivo volumio[19717]: info: Ignoring MPD Status Update Nov 23 07:20:13 rivo volumio[19717]: info: sendMpdCommand clear took 40 milliseconds Nov 23 07:20:13 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I16373761.flac" Nov 23 07:20:13 rivo volumio[19717]: error: updateQueue error: null Nov 23 07:20:13 rivo volumio[19717]: info: ------------------------------ 4ms Nov 23 07:20:13 rivo volumio[19717]: info: camilladsp spawned new process with pid 20806, instance 1, run: true Nov 23 07:20:13 rivo volumio[19717]: info: camilladsp spawned new process with pid 20813, instance 1, run: true Nov 23 07:20:13 rivo volumio[19717]: info: Preload queue cleared Nov 23 07:20:13 rivo volumio[19717]: info: Preloading song: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$884R16373761 Nov 23 07:20:13 rivo volumio[19717]: info: Preloading song: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$884R16375297 Nov 23 07:20:13 rivo volumio[19717]: info: Preloading song: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$884R16374529 Nov 23 07:20:13 rivo volumio[19717]: info: Preloading song: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$884R16374785 Nov 23 07:20:13 rivo volumio[19717]: info: Preloading song: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$884R16375041 Nov 23 07:20:13 rivo volumio[19717]: info: Preloading song: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$884R16372993 Nov 23 07:20:13 rivo volumio[19717]: info: Preloading song: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$884R16372737 Nov 23 07:20:13 rivo volumio[19717]: info: camilladsp spawned new process with pid 20820, instance 1, run: true Nov 23 07:20:13 rivo volumio[19717]: info: Exploding uri upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$884R16373761 in service upnp_browser Nov 23 07:20:13 rivo volumio[19717]: info: Exploding uri upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$884R16375297 in service upnp_browser Nov 23 07:20:13 rivo volumio[19717]: info: Executing endpoint getSimilarAlbums Nov 23 07:20:13 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Nov 23 07:20:13 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I16373761.flac" Nov 23 07:20:13 rivo volumio[19717]: info: Nov 23 07:20:13 rivo volumio[19717]: ---------------------------- MPD announces system playlist update Nov 23 07:20:13 rivo volumio[19717]: info: Ignoring MPD Status Update Nov 23 07:20:13 rivo volumio[19717]: info: sendMpdCommand add "http://192.168.0.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I16373761.flac" took 3 milliseconds Nov 23 07:20:13 rivo volumio[19717]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 07:20:13 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand play Nov 23 07:20:13 rivo volumio[19717]: info: ------------------------------ 6ms Nov 23 07:20:13 rivo volumio[19717]: info: sendMpdCommand play took 3 milliseconds Nov 23 07:20:13 rivo volumio[19717]: info: camilladsp spawned new process with pid 20828, instance 1, run: true Nov 23 07:20:13 rivo volumio[19717]: info: Exploding uri upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$884R16374529 in service upnp_browser Nov 23 07:20:13 rivo volumio[19717]: info: Executing endpoint metavolumio Nov 23 07:20:13 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 23 07:20:13 rivo volumio[19717]: info: Exploding uri upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$884R16374785 in service upnp_browser Nov 23 07:20:13 rivo volumio[19717]: info: Executing endpoint metavolumio Nov 23 07:20:13 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 23 07:20:13 rivo volumio[19717]: info: camilladsp spawned new process with pid 20835, instance 1, run: true Nov 23 07:20:13 rivo volumio[19717]: info: Exploding uri upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$884R16375041 in service upnp_browser Nov 23 07:20:14 rivo volumio[19717]: info: Exploding uri upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$884R16372993 in service upnp_browser Nov 23 07:20:14 rivo volumio[19717]: info: camilladsp spawned new process with pid 20842, instance 1, run: true Nov 23 07:20:14 rivo volumio[19717]: info: Exploding uri upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$884R16372737 in service upnp_browser Nov 23 07:20:14 rivo volumio[19717]: info: camilladsp spawned new process with pid 20849, instance 1, run: true Nov 23 07:20:14 rivo volumio[19717]: info: camilladsp spawned new process with pid 20856, instance 1, run: true Nov 23 07:20:14 rivo volumio[19717]: info: camilladsp spawned new process with pid 20863, instance 1, run: true Nov 23 07:20:14 rivo volumio[19717]: info: camilladsp spawned new process with pid 20870, instance 1, run: true Nov 23 07:20:14 rivo volumio[19717]: info: camilladsp spawned new process with pid 20879, instance 1, run: true Nov 23 07:20:14 rivo volumio[19717]: info: Nov 23 07:20:14 rivo volumio[19717]: ---------------------------- MPD announces state update: player Nov 23 07:20:14 rivo volumio[19717]: info: ControllerMpd::getState Nov 23 07:20:14 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand status Nov 23 07:20:14 rivo volumio[19717]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Nov 23 07:20:14 rivo volumio[19717]: info: FusionDsp - ---- read samplerate from file: 192000 Nov 23 07:20:14 rivo volumio[19717]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 23 07:20:14 rivo volumio[19717]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Nov 23 07:20:14 rivo volumio[19717]: info: FusionDsp - ---- read samplerate from file: 192000 Nov 23 07:20:14 rivo volumio[19717]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 23 07:20:14 rivo volumio[19717]: info: Nov 23 07:20:14 rivo volumio[19717]: ---------------------------- MPD announces system playlist update Nov 23 07:20:14 rivo volumio[19717]: info: Ignoring MPD Status Update Nov 23 07:20:14 rivo volumio[19717]: info: Nov 23 07:20:14 rivo volumio[19717]: ---------------------------- MPD announces state update: player Nov 23 07:20:14 rivo volumio[19717]: info: ControllerMpd::getState Nov 23 07:20:14 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand status Nov 23 07:20:14 rivo volumio[19717]: info: sendMpdCommand status took 49 milliseconds Nov 23 07:20:14 rivo volumio[19717]: verbose: ControllerMpd::parseState Nov 23 07:20:14 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 07:20:14 rivo volumio[19717]: info: ------------------------------ 12ms Nov 23 07:20:14 rivo volumio[19717]: info: sendMpdCommand status took 11 milliseconds Nov 23 07:20:14 rivo volumio[19717]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 23 07:20:14 rivo volumio[19717]: verbose: ControllerMpd::parseState Nov 23 07:20:14 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 07:20:14 rivo volumio[19717]: verbose: ControllerMpd::parseTrackInfo Nov 23 07:20:14 rivo volumio[19717]: info: ControllerMpd::pushState Nov 23 07:20:14 rivo volumio[19717]: info: CoreCommandRouter::servicePushState Nov 23 07:20:14 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:20:14 rivo volumio[19717]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"458 Kbps","isStreaming":false,"title":"Autumn in New York","artist":"Milcho Leviev","album":"Live at Vartan Jazz","uri":"http://192.168.0.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I16373761.flac","trackType":"flac"} Nov 23 07:20:14 rivo volumio[19717]: verbose: CURRENT POSITION 0 Nov 23 07:20:14 rivo volumio[19717]: info: CoreStateMachine::syncState stateService play Nov 23 07:20:14 rivo volumio[19717]: info: CoreStateMachine::syncState currentStatus stop Nov 23 07:20:14 rivo volumio[19717]: info: ------------------------------ 82ms Nov 23 07:20:14 rivo volumio[19717]: info: sendMpdCommand playlistinfo took 17 milliseconds Nov 23 07:20:14 rivo volumio[19717]: verbose: ControllerMpd::parseTrackInfo Nov 23 07:20:14 rivo volumio[19717]: info: ControllerMpd::pushState Nov 23 07:20:14 rivo volumio[19717]: info: CoreCommandRouter::servicePushState Nov 23 07:20:14 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:20:14 rivo volumio[19717]: verbose: STATE SERVICE {"status":"play","position":0,"seek":251,"duration":360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"440 Kbps","isStreaming":false,"title":"Autumn in New York","artist":"Milcho Leviev","album":"Live at Vartan Jazz","uri":"http://192.168.0.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I16373761.flac","trackType":"flac"} Nov 23 07:20:14 rivo volumio[19717]: verbose: CURRENT POSITION 0 Nov 23 07:20:14 rivo volumio[19717]: info: CoreStateMachine::syncState stateService play Nov 23 07:20:14 rivo volumio[19717]: info: CoreStateMachine::syncState currentStatus play Nov 23 07:20:14 rivo volumio[19717]: info: Received an update from plugin. extracting info from payload Nov 23 07:20:14 rivo volumio[19717]: info: CoreStateMachine::pushState Nov 23 07:20:14 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 07:20:14 rivo volumio[19717]: info: CoreCommandRouter::volumioPushState Nov 23 07:20:14 rivo volumio[19717]: info: CoreStateMachine::pushState Nov 23 07:20:14 rivo volumio[19717]: info: CoreCommandRouter::volumioPushState Nov 23 07:20:14 rivo volumio[19717]: info: ------------------------------ 90ms Nov 23 07:20:14 rivo volumio[19717]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 23 07:20:14 rivo volumio[19717]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 23 07:20:14 rivo volumio[19717]: info: CoreCommandRouter::volumioGetQueue Nov 23 07:20:14 rivo volumio[19717]: info: CoreStateMachine::getQueue Nov 23 07:20:14 rivo volumio[19717]: info: CorePlayQueue::getQueue Nov 23 07:20:14 rivo volumio[19717]: info: CoreCommandRouter::volumioGetQueue Nov 23 07:20:14 rivo volumio[19717]: info: CoreStateMachine::getQueue Nov 23 07:20:14 rivo volumio[19717]: info: CorePlayQueue::getQueue Nov 23 07:20:14 rivo volumio[19717]: info: Signalling Playback active due to playback status change Nov 23 07:20:14 rivo volumio[19717]: info: Signalling Playback active due to playback status change Nov 23 07:20:14 rivo volumio[19717]: info: Updating RAAT Signal Path Nov 23 07:20:14 rivo volumio[19717]: info: Updating RAAT Signal Path Nov 23 07:20:14 rivo volumio[19717]: info: camilladsp spawned new process with pid 20888, instance 1, run: true Nov 23 07:20:14 rivo volumio[19717]: info: MCU Signalled Playback Active Nov 23 07:20:15 rivo volumio[19717]: info: camilladsp spawned new process with pid 20895, instance 1, run: true Nov 23 07:20:15 rivo volumio[19717]: info: camilladsp spawned new process with pid 20902, instance 1, run: true Nov 23 07:20:15 rivo volumio[19717]: info: camilladsp spawned new process with pid 20909, instance 1, run: true Nov 23 07:20:15 rivo volumio[19717]: info: camilladsp spawned new process with pid 20916, instance 1, run: true Nov 23 07:20:15 rivo volumio[19717]: info: camilladsp spawned new process with pid 20923, instance 1, run: true Nov 23 07:20:15 rivo volumio[19717]: info: camilladsp spawned new process with pid 20930, instance 1, run: true Nov 23 07:20:15 rivo volumio[19717]: info: camilladsp spawned new process with pid 20937, instance 1, run: true Nov 23 07:20:15 rivo volumio[19717]: info: camilladsp spawned new process with pid 20944, instance 1, run: true Nov 23 07:20:16 rivo volumio[19717]: info: camilladsp spawned new process with pid 20951, instance 1, run: true Nov 23 07:20:16 rivo volumio[19717]: info: camilladsp spawned new process with pid 20958, instance 1, run: true Nov 23 07:20:16 rivo volumio[19717]: info: camilladsp spawned new process with pid 20965, instance 1, run: true Nov 23 07:20:16 rivo volumio[19717]: info: camilladsp spawned new process with pid 20972, instance 1, run: true Nov 23 07:20:16 rivo volumio[19717]: info: camilladsp spawned new process with pid 20979, instance 1, run: true Nov 23 07:20:16 rivo volumio[19717]: info: camilladsp spawned new process with pid 20986, instance 1, run: true Nov 23 07:20:16 rivo volumio[19717]: info: Executing endpoint metavolumio Nov 23 07:20:16 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 23 07:20:16 rivo volumio[19717]: info: Executing endpoint metavolumio Nov 23 07:20:16 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 23 07:20:16 rivo volumio[19717]: info: Executing endpoint metavolumio Nov 23 07:20:16 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 23 07:20:16 rivo volumio[19717]: info: camilladsp spawned new process with pid 20993, instance 1, run: true Nov 23 07:20:16 rivo volumio[19717]: info: camilladsp spawned new process with pid 21000, instance 1, run: true Nov 23 07:20:17 rivo volumio[19717]: info: camilladsp spawned new process with pid 21007, instance 1, run: true Nov 23 07:20:17 rivo volumio[19717]: info: Executing endpoint metavolumio Nov 23 07:20:17 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 23 07:20:17 rivo volumio[19717]: info: Executing endpoint metavolumio Nov 23 07:20:17 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 23 07:20:17 rivo volumio[19717]: info: Executing endpoint metavolumio Nov 23 07:20:17 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 23 07:20:17 rivo volumio[19717]: info: camilladsp spawned new process with pid 21014, instance 1, run: true Nov 23 07:20:17 rivo volumio[19717]: info: camilladsp spawned new process with pid 21021, instance 1, run: true Nov 23 07:20:17 rivo volumio[19717]: info: camilladsp spawned new process with pid 21028, instance 1, run: true Nov 23 07:20:17 rivo volumio[19717]: info: camilladsp spawned new process with pid 21035, instance 1, run: true Nov 23 07:20:17 rivo volumio[19717]: info: camilladsp spawned new process with pid 21042, instance 1, run: true Nov 23 07:20:17 rivo volumio[19717]: info: camilladsp spawned new process with pid 21049, instance 1, run: true Nov 23 07:20:17 rivo volumio[19717]: info: camilladsp spawned new process with pid 21056, instance 1, run: true Nov 23 07:20:17 rivo volumio[19717]: info: camilladsp spawned new process with pid 21066, instance 1, run: true Nov 23 07:20:18 rivo volumio[19717]: info: camilladsp spawned new process with pid 21073, instance 1, run: true Nov 23 07:20:18 rivo volumio[19717]: info: camilladsp spawned new process with pid 21080, instance 1, run: true Nov 23 07:20:18 rivo volumio[19717]: info: camilladsp spawned new process with pid 21087, instance 1, run: true Nov 23 07:20:18 rivo volumio[19717]: info: camilladsp spawned new process with pid 21094, instance 1, run: true Nov 23 07:20:18 rivo volumio[19717]: info: camilladsp spawned new process with pid 21101, instance 1, run: true Nov 23 07:20:18 rivo volumio[19717]: info: camilladsp spawned new process with pid 21108, instance 1, run: true Nov 23 07:20:18 rivo volumio[19717]: info: camilladsp spawned new process with pid 21115, instance 1, run: true Nov 23 07:20:18 rivo volumio[19717]: info: camilladsp spawned new process with pid 21122, instance 1, run: true Nov 23 07:20:18 rivo volumio[19717]: info: camilladsp spawned new process with pid 21130, instance 1, run: true Nov 23 07:20:18 rivo volumio[19717]: info: camilladsp spawned new process with pid 21137, instance 1, run: true Nov 23 07:20:18 rivo volumio[19717]: info: camilladsp spawned new process with pid 21144, instance 1, run: true Nov 23 07:20:19 rivo volumio[19717]: info: camilladsp spawned new process with pid 21151, instance 1, run: true Nov 23 07:20:19 rivo volumio[19717]: info: camilladsp spawned new process with pid 21158, instance 1, run: true Nov 23 07:20:19 rivo volumio[19717]: info: camilladsp spawned new process with pid 21165, instance 1, run: true Nov 23 07:20:19 rivo volumio[19717]: info: camilladsp spawned new process with pid 21172, instance 1, run: true Nov 23 07:20:19 rivo volumio[19717]: info: camilladsp spawned new process with pid 21179, instance 1, run: true Nov 23 07:20:19 rivo volumio[19717]: info: camilladsp spawned new process with pid 21186, instance 1, run: true Nov 23 07:20:19 rivo volumio[19717]: info: camilladsp spawned new process with pid 21193, instance 1, run: true Nov 23 07:20:19 rivo volumio[19717]: info: camilladsp spawned new process with pid 21200, instance 1, run: true Nov 23 07:20:19 rivo volumio[19717]: info: camilladsp spawned new process with pid 21207, instance 1, run: true Nov 23 07:20:19 rivo volumio[19717]: info: camilladsp spawned new process with pid 21214, instance 1, run: true Nov 23 07:20:20 rivo volumio[19717]: info: camilladsp spawned new process with pid 21221, instance 1, run: true Nov 23 07:20:20 rivo volumio[19717]: info: camilladsp spawned new process with pid 21228, instance 1, run: true Nov 23 07:20:20 rivo volumio[19717]: info: camilladsp spawned new process with pid 21235, instance 1, run: true Nov 23 07:20:20 rivo volumio[19717]: info: camilladsp spawned new process with pid 21242, instance 1, run: true Nov 23 07:20:20 rivo volumio[19717]: info: camilladsp spawned new process with pid 21249, instance 1, run: true Nov 23 07:20:20 rivo volumio[19717]: info: camilladsp spawned new process with pid 21256, instance 1, run: true Nov 23 07:20:20 rivo volumio[19717]: info: camilladsp spawned new process with pid 21263, instance 1, run: true Nov 23 07:20:20 rivo volumio[19717]: info: camilladsp spawned new process with pid 21270, instance 1, run: true Nov 23 07:20:20 rivo volumio[19717]: info: camilladsp spawned new process with pid 21277, instance 1, run: true Nov 23 07:20:20 rivo volumio[19717]: info: camilladsp spawned new process with pid 21284, instance 1, run: true Nov 23 07:20:20 rivo volumio[19717]: info: camilladsp spawned new process with pid 21291, instance 1, run: true Nov 23 07:20:21 rivo volumio[19717]: info: camilladsp spawned new process with pid 21298, instance 1, run: true Nov 23 07:20:21 rivo volumio[19717]: info: camilladsp spawned new process with pid 21305, instance 1, run: true Nov 23 07:20:21 rivo volumio[19717]: info: camilladsp spawned new process with pid 21312, instance 1, run: true Nov 23 07:20:21 rivo volumio[19717]: info: camilladsp spawned new process with pid 21319, instance 1, run: true Nov 23 07:20:21 rivo volumio[19717]: info: camilladsp spawned new process with pid 21326, instance 1, run: true Nov 23 07:20:21 rivo volumio[19717]: info: camilladsp spawned new process with pid 21333, instance 1, run: true Nov 23 07:20:21 rivo volumio[19717]: info: camilladsp spawned new process with pid 21340, instance 1, run: true Nov 23 07:20:21 rivo volumio[19717]: info: camilladsp spawned new process with pid 21347, instance 1, run: true Nov 23 07:20:21 rivo volumio[19717]: info: camilladsp spawned new process with pid 21364, instance 1, run: true Nov 23 07:20:21 rivo volumio[19717]: info: camilladsp spawned new process with pid 21378, instance 1, run: true Nov 23 07:20:21 rivo volumio[19717]: info: camilladsp spawned new process with pid 21385, instance 1, run: true Nov 23 07:20:22 rivo volumio[19717]: info: camilladsp spawned new process with pid 21392, instance 1, run: true Nov 23 07:20:22 rivo volumio[19717]: info: camilladsp spawned new process with pid 21399, instance 1, run: true Nov 23 07:20:22 rivo volumio[19717]: info: camilladsp spawned new process with pid 21406, instance 1, run: true Nov 23 07:20:22 rivo volumio[19717]: info: camilladsp spawned new process with pid 21413, instance 1, run: true Nov 23 07:20:22 rivo volumio[19717]: info: camilladsp spawned new process with pid 21420, instance 1, run: true Nov 23 07:20:22 rivo volumio[19717]: info: camilladsp spawned new process with pid 21427, instance 1, run: true Nov 23 07:20:22 rivo volumio[19717]: info: camilladsp spawned new process with pid 21434, instance 1, run: true Nov 23 07:20:22 rivo volumio[19717]: info: camilladsp spawned new process with pid 21441, instance 1, run: true Nov 23 07:20:22 rivo volumio[19717]: info: camilladsp spawned new process with pid 21448, instance 1, run: true Nov 23 07:20:22 rivo volumio[19717]: info: camilladsp spawned new process with pid 21455, instance 1, run: true Nov 23 07:20:22 rivo volumio[19717]: info: camilladsp spawned new process with pid 21462, instance 1, run: true Nov 23 07:20:23 rivo volumio[19717]: info: camilladsp spawned new process with pid 21469, instance 1, run: true Nov 23 07:20:23 rivo volumio[19717]: info: camilladsp spawned new process with pid 21476, instance 1, run: true Nov 23 07:20:23 rivo volumio[19717]: info: camilladsp spawned new process with pid 21483, instance 1, run: true Nov 23 07:20:23 rivo volumio[19717]: info: camilladsp spawned new process with pid 21490, instance 1, run: true Nov 23 07:20:23 rivo volumio[19717]: info: camilladsp spawned new process with pid 21497, instance 1, run: true Nov 23 07:20:23 rivo volumio[19717]: info: camilladsp spawned new process with pid 21504, instance 1, run: true Nov 23 07:20:23 rivo volumio[19717]: info: camilladsp spawned new process with pid 21511, instance 1, run: true Nov 23 07:20:23 rivo volumio[19717]: info: camilladsp spawned new process with pid 21518, instance 1, run: true Nov 23 07:20:23 rivo volumio[19717]: info: camilladsp spawned new process with pid 21525, instance 1, run: true Nov 23 07:20:23 rivo volumio[19717]: info: camilladsp spawned new process with pid 21532, instance 1, run: true Nov 23 07:20:23 rivo volumio[19717]: info: camilladsp spawned new process with pid 21539, instance 1, run: true Nov 23 07:20:24 rivo volumio[19717]: info: camilladsp spawned new process with pid 21546, instance 1, run: true Nov 23 07:20:24 rivo volumio[19717]: info: camilladsp spawned new process with pid 21553, instance 1, run: true Nov 23 07:20:24 rivo volumio[19717]: info: camilladsp spawned new process with pid 21560, instance 1, run: true Nov 23 07:20:24 rivo volumio[19717]: info: camilladsp spawned new process with pid 21567, instance 1, run: true Nov 23 07:20:24 rivo volumio[19717]: info: camilladsp spawned new process with pid 21574, instance 1, run: true Nov 23 07:20:24 rivo volumio[19717]: info: camilladsp spawned new process with pid 21581, instance 1, run: true Nov 23 07:20:24 rivo volumio[19717]: info: camilladsp spawned new process with pid 21588, instance 1, run: true Nov 23 07:20:24 rivo volumio[19717]: info: camilladsp spawned new process with pid 21595, instance 1, run: true Nov 23 07:20:24 rivo volumio[19717]: info: camilladsp spawned new process with pid 21602, instance 1, run: true Nov 23 07:20:24 rivo volumio[19717]: info: camilladsp spawned new process with pid 21609, instance 1, run: true Nov 23 07:20:24 rivo volumio[19717]: info: camilladsp spawned new process with pid 21616, instance 1, run: true Nov 23 07:20:25 rivo volumio[19717]: info: camilladsp spawned new process with pid 21623, instance 1, run: true Nov 23 07:20:25 rivo volumio[19717]: info: camilladsp spawned new process with pid 21630, instance 1, run: true Nov 23 07:20:25 rivo volumio[19717]: info: camilladsp spawned new process with pid 21637, instance 1, run: true Nov 23 07:20:25 rivo volumio[19717]: info: camilladsp spawned new process with pid 21644, instance 1, run: true Nov 23 07:20:25 rivo volumio[19717]: info: camilladsp spawned new process with pid 21651, instance 1, run: true Nov 23 07:20:25 rivo volumio[19717]: info: camilladsp spawned new process with pid 21658, instance 1, run: true Nov 23 07:20:25 rivo volumio[19717]: info: camilladsp spawned new process with pid 21665, instance 1, run: true Nov 23 07:20:25 rivo volumio[19717]: info: camilladsp spawned new process with pid 21672, instance 1, run: true Nov 23 07:20:25 rivo volumio[19717]: info: camilladsp spawned new process with pid 21679, instance 1, run: true Nov 23 07:20:25 rivo volumio[19717]: info: camilladsp spawned new process with pid 21686, instance 1, run: true Nov 23 07:20:25 rivo volumio[19717]: info: camilladsp spawned new process with pid 21693, instance 1, run: true Nov 23 07:20:26 rivo volumio[19717]: info: camilladsp spawned new process with pid 21700, instance 1, run: true Nov 23 07:20:26 rivo volumio[19717]: info: camilladsp spawned new process with pid 21707, instance 1, run: true Nov 23 07:20:26 rivo volumio[19717]: info: camilladsp spawned new process with pid 21714, instance 1, run: true Nov 23 07:20:26 rivo volumio[19717]: info: camilladsp spawned new process with pid 21721, instance 1, run: true Nov 23 07:20:26 rivo volumio[19717]: info: camilladsp spawned new process with pid 21728, instance 1, run: true Nov 23 07:20:26 rivo volumio[19717]: info: camilladsp spawned new process with pid 21735, instance 1, run: true Nov 23 07:20:26 rivo volumio[19717]: info: camilladsp spawned new process with pid 21742, instance 1, run: true Nov 23 07:20:26 rivo volumio[19717]: info: camilladsp spawned new process with pid 21749, instance 1, run: true Nov 23 07:20:26 rivo volumio[19717]: info: camilladsp spawned new process with pid 21756, instance 1, run: true Nov 23 07:20:26 rivo volumio[19717]: info: camilladsp spawned new process with pid 21763, instance 1, run: true Nov 23 07:20:26 rivo volumio[19717]: info: camilladsp spawned new process with pid 21770, instance 1, run: true Nov 23 07:20:27 rivo volumio[19717]: info: camilladsp spawned new process with pid 21777, instance 1, run: true Nov 23 07:20:27 rivo volumio[19717]: info: camilladsp spawned new process with pid 21784, instance 1, run: true Nov 23 07:20:27 rivo volumio[19717]: info: camilladsp spawned new process with pid 21791, instance 1, run: true Nov 23 07:20:27 rivo volumio[19717]: info: camilladsp spawned new process with pid 21798, instance 1, run: true Nov 23 07:20:27 rivo volumio[19717]: info: camilladsp spawned new process with pid 21805, instance 1, run: true Nov 23 07:20:27 rivo volumio[19717]: info: camilladsp spawned new process with pid 21812, instance 1, run: true Nov 23 07:20:27 rivo volumio[19717]: info: camilladsp spawned new process with pid 21819, instance 1, run: true Nov 23 07:20:27 rivo volumio[19717]: info: camilladsp spawned new process with pid 21826, instance 1, run: true Nov 23 07:20:27 rivo volumio[19717]: info: camilladsp spawned new process with pid 21833, instance 1, run: true Nov 23 07:20:27 rivo volumio[19717]: info: camilladsp spawned new process with pid 21842, instance 1, run: true Nov 23 07:20:28 rivo volumio[19717]: info: camilladsp spawned new process with pid 21850, instance 1, run: true Nov 23 07:20:28 rivo volumio[19717]: info: camilladsp spawned new process with pid 21857, instance 1, run: true Nov 23 07:20:28 rivo volumio[19717]: info: camilladsp spawned new process with pid 21864, instance 1, run: true Nov 23 07:20:28 rivo volumio[19717]: info: camilladsp spawned new process with pid 21871, instance 1, run: true Nov 23 07:20:28 rivo volumio[19717]: info: camilladsp spawned new process with pid 21878, instance 1, run: true Nov 23 07:20:28 rivo volumio[19717]: info: camilladsp spawned new process with pid 21885, instance 1, run: true Nov 23 07:20:28 rivo volumio[19717]: info: camilladsp spawned new process with pid 21892, instance 1, run: true Nov 23 07:20:28 rivo volumio[19717]: info: camilladsp spawned new process with pid 21899, instance 1, run: true Nov 23 07:20:28 rivo volumio[19717]: info: camilladsp spawned new process with pid 21906, instance 1, run: true Nov 23 07:20:28 rivo volumio[19717]: info: camilladsp spawned new process with pid 21913, instance 1, run: true Nov 23 07:20:28 rivo volumio[19717]: info: camilladsp spawned new process with pid 21920, instance 1, run: true Nov 23 07:20:29 rivo volumio[19717]: info: camilladsp spawned new process with pid 21927, instance 1, run: true Nov 23 07:20:29 rivo volumio[19717]: info: camilladsp spawned new process with pid 21934, instance 1, run: true Nov 23 07:20:29 rivo volumio[19717]: info: camilladsp spawned new process with pid 21941, instance 1, run: true Nov 23 07:20:29 rivo volumio[19717]: info: camilladsp spawned new process with pid 21948, instance 1, run: true Nov 23 07:20:29 rivo volumio[19717]: info: camilladsp spawned new process with pid 21955, instance 1, run: true Nov 23 07:20:29 rivo volumio[19717]: info: camilladsp spawned new process with pid 21962, instance 1, run: true Nov 23 07:20:29 rivo volumio[19717]: info: camilladsp spawned new process with pid 21969, instance 1, run: true Nov 23 07:20:29 rivo volumio[19717]: info: camilladsp spawned new process with pid 21976, instance 1, run: true Nov 23 07:20:29 rivo volumio[19717]: info: camilladsp spawned new process with pid 21983, instance 1, run: true Nov 23 07:20:29 rivo volumio[19717]: info: camilladsp spawned new process with pid 21990, instance 1, run: true Nov 23 07:20:29 rivo volumio[19717]: info: camilladsp spawned new process with pid 21997, instance 1, run: true Nov 23 07:20:30 rivo volumio[19717]: info: camilladsp spawned new process with pid 22004, instance 1, run: true Nov 23 07:20:30 rivo volumio[19717]: info: camilladsp spawned new process with pid 22011, instance 1, run: true Nov 23 07:20:30 rivo volumio[19717]: info: camilladsp spawned new process with pid 22018, instance 1, run: true Nov 23 07:20:30 rivo volumio[19717]: info: camilladsp spawned new process with pid 22025, instance 1, run: true Nov 23 07:20:30 rivo volumio[19717]: info: camilladsp spawned new process with pid 22032, instance 1, run: true Nov 23 07:20:30 rivo volumio[19717]: info: camilladsp spawned new process with pid 22039, instance 1, run: true Nov 23 07:20:30 rivo volumio[19717]: info: camilladsp spawned new process with pid 22046, instance 1, run: true Nov 23 07:20:30 rivo volumio[19717]: info: camilladsp spawned new process with pid 22053, instance 1, run: true Nov 23 07:20:30 rivo volumio[19717]: info: camilladsp spawned new process with pid 22060, instance 1, run: true Nov 23 07:20:30 rivo volumio[19717]: info: camilladsp spawned new process with pid 22067, instance 1, run: true Nov 23 07:20:30 rivo volumio[19717]: info: camilladsp spawned new process with pid 22074, instance 1, run: true Nov 23 07:20:31 rivo volumio[19717]: info: camilladsp spawned new process with pid 22081, instance 1, run: true Nov 23 07:20:31 rivo volumio[19717]: info: camilladsp spawned new process with pid 22088, instance 1, run: true Nov 23 07:20:31 rivo volumio[19717]: info: camilladsp spawned new process with pid 22095, instance 1, run: true Nov 23 07:20:31 rivo volumio[19717]: info: camilladsp spawned new process with pid 22102, instance 1, run: true Nov 23 07:20:31 rivo volumio[19717]: info: camilladsp spawned new process with pid 22109, instance 1, run: true Nov 23 07:20:31 rivo volumio[19717]: info: camilladsp spawned new process with pid 22116, instance 1, run: true Nov 23 07:20:31 rivo volumio[19717]: info: camilladsp spawned new process with pid 22123, instance 1, run: true Nov 23 07:20:31 rivo volumio[19717]: info: camilladsp spawned new process with pid 22130, instance 1, run: true Nov 23 07:20:31 rivo volumio[19717]: info: camilladsp spawned new process with pid 22137, instance 1, run: true Nov 23 07:20:31 rivo volumio[19717]: info: camilladsp spawned new process with pid 22149, instance 1, run: true Nov 23 07:20:31 rivo volumio[19717]: info: camilladsp spawned new process with pid 22168, instance 1, run: true Nov 23 07:20:32 rivo volumio[19717]: info: camilladsp spawned new process with pid 22175, instance 1, run: true Nov 23 07:20:32 rivo volumio[19717]: info: camilladsp spawned new process with pid 22182, instance 1, run: true Nov 23 07:20:32 rivo volumio[19717]: info: camilladsp spawned new process with pid 22189, instance 1, run: true Nov 23 07:20:32 rivo volumio[19717]: info: camilladsp spawned new process with pid 22196, instance 1, run: true Nov 23 07:20:32 rivo volumio[19717]: info: camilladsp spawned new process with pid 22203, instance 1, run: true Nov 23 07:20:32 rivo volumio[19717]: info: camilladsp spawned new process with pid 22210, instance 1, run: true Nov 23 07:20:32 rivo volumio[19717]: info: camilladsp spawned new process with pid 22217, instance 1, run: true Nov 23 07:20:32 rivo volumio[19717]: info: camilladsp spawned new process with pid 22224, instance 1, run: true Nov 23 07:20:32 rivo volumio[19717]: info: camilladsp spawned new process with pid 22231, instance 1, run: true Nov 23 07:20:32 rivo volumio[19717]: info: camilladsp spawned new process with pid 22238, instance 1, run: true Nov 23 07:20:32 rivo volumio[19717]: info: camilladsp spawned new process with pid 22245, instance 1, run: true Nov 23 07:20:33 rivo volumio[19717]: info: camilladsp spawned new process with pid 22252, instance 1, run: true Nov 23 07:20:33 rivo volumio[19717]: info: camilladsp spawned new process with pid 22259, instance 1, run: true Nov 23 07:20:33 rivo volumio[19717]: info: camilladsp spawned new process with pid 22266, instance 1, run: true Nov 23 07:20:33 rivo volumio[19717]: info: camilladsp spawned new process with pid 22273, instance 1, run: true Nov 23 07:20:33 rivo volumio[19717]: info: camilladsp spawned new process with pid 22280, instance 1, run: true Nov 23 07:20:33 rivo volumio[19717]: info: camilladsp spawned new process with pid 22287, instance 1, run: true Nov 23 07:20:33 rivo volumio[19717]: info: camilladsp spawned new process with pid 22294, instance 1, run: true Nov 23 07:20:33 rivo volumio[19717]: info: camilladsp spawned new process with pid 22301, instance 1, run: true Nov 23 07:20:33 rivo volumio[19717]: info: camilladsp spawned new process with pid 22308, instance 1, run: true Nov 23 07:20:33 rivo volumio[19717]: info: camilladsp spawned new process with pid 22315, instance 1, run: true Nov 23 07:20:33 rivo volumio[19717]: info: camilladsp spawned new process with pid 22322, instance 1, run: true Nov 23 07:20:34 rivo volumio[19717]: info: camilladsp spawned new process with pid 22329, instance 1, run: true Nov 23 07:20:34 rivo volumio[19717]: info: camilladsp spawned new process with pid 22336, instance 1, run: true Nov 23 07:20:34 rivo volumio[19717]: info: camilladsp spawned new process with pid 22343, instance 1, run: true Nov 23 07:20:34 rivo volumio[19717]: info: camilladsp spawned new process with pid 22350, instance 1, run: true Nov 23 07:20:34 rivo volumio[19717]: info: camilladsp spawned new process with pid 22357, instance 1, run: true Nov 23 07:20:34 rivo volumio[19717]: info: camilladsp spawned new process with pid 22364, instance 1, run: true Nov 23 07:20:34 rivo volumio[19717]: info: camilladsp spawned new process with pid 22371, instance 1, run: true Nov 23 07:20:34 rivo volumio[19717]: info: camilladsp spawned new process with pid 22378, instance 1, run: true Nov 23 07:20:34 rivo volumio[19717]: info: camilladsp spawned new process with pid 22385, instance 1, run: true Nov 23 07:20:34 rivo volumio[19717]: info: camilladsp spawned new process with pid 22392, instance 1, run: true Nov 23 07:20:34 rivo volumio[19717]: info: camilladsp spawned new process with pid 22399, instance 1, run: true Nov 23 07:20:35 rivo volumio[19717]: info: camilladsp spawned new process with pid 22406, instance 1, run: true Nov 23 07:20:35 rivo volumio[19717]: info: camilladsp spawned new process with pid 22413, instance 1, run: true Nov 23 07:20:35 rivo volumio[19717]: info: camilladsp spawned new process with pid 22420, instance 1, run: true Nov 23 07:20:35 rivo volumio[19717]: info: camilladsp spawned new process with pid 22427, instance 1, run: true Nov 23 07:20:35 rivo volumio[19717]: info: camilladsp spawned new process with pid 22434, instance 1, run: true Nov 23 07:20:35 rivo volumio[19717]: info: camilladsp spawned new process with pid 22441, instance 1, run: true Nov 23 07:20:35 rivo volumio[19717]: info: camilladsp spawned new process with pid 22448, instance 1, run: true Nov 23 07:20:35 rivo volumio[19717]: info: camilladsp spawned new process with pid 22455, instance 1, run: true Nov 23 07:20:35 rivo volumio[19717]: info: camilladsp spawned new process with pid 22462, instance 1, run: true Nov 23 07:20:35 rivo volumio[19717]: info: camilladsp spawned new process with pid 22469, instance 1, run: true Nov 23 07:20:35 rivo volumio[19717]: info: camilladsp spawned new process with pid 22476, instance 1, run: true Nov 23 07:20:36 rivo volumio[19717]: info: camilladsp spawned new process with pid 22483, instance 1, run: true Nov 23 07:20:36 rivo volumio[19717]: info: camilladsp spawned new process with pid 22490, instance 1, run: true Nov 23 07:20:36 rivo volumio[19717]: info: camilladsp spawned new process with pid 22497, instance 1, run: true Nov 23 07:20:36 rivo volumio[19717]: info: camilladsp spawned new process with pid 22504, instance 1, run: true Nov 23 07:20:36 rivo volumio[19717]: info: camilladsp spawned new process with pid 22511, instance 1, run: true Nov 23 07:20:36 rivo volumio[19717]: info: camilladsp spawned new process with pid 22518, instance 1, run: true Nov 23 07:20:36 rivo volumio[19717]: info: camilladsp spawned new process with pid 22525, instance 1, run: true Nov 23 07:20:36 rivo volumio[19717]: info: camilladsp spawned new process with pid 22532, instance 1, run: true Nov 23 07:20:36 rivo volumio[19717]: info: camilladsp spawned new process with pid 22539, instance 1, run: true Nov 23 07:20:36 rivo volumio[19717]: info: camilladsp spawned new process with pid 22546, instance 1, run: true Nov 23 07:20:36 rivo volumio[19717]: info: camilladsp spawned new process with pid 22553, instance 1, run: true Nov 23 07:20:37 rivo volumio[19717]: info: camilladsp spawned new process with pid 22560, instance 1, run: true Nov 23 07:20:37 rivo volumio[19717]: info: camilladsp spawned new process with pid 22567, instance 1, run: true Nov 23 07:20:37 rivo volumio[19717]: info: camilladsp spawned new process with pid 22574, instance 1, run: true Nov 23 07:20:37 rivo volumio[19717]: info: camilladsp spawned new process with pid 22581, instance 1, run: true Nov 23 07:20:37 rivo volumio[19717]: info: camilladsp spawned new process with pid 22588, instance 1, run: true Nov 23 07:20:37 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 07:20:37 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 07:20:37 rivo volumio[19717]: info: Discovery: Getting this device information Nov 23 07:20:37 rivo volumio[19717]: info: CoreCommandRouter::volumioGetState Nov 23 07:20:37 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 07:20:37 rivo volumio[19717]: info: camilladsp spawned new process with pid 22595, instance 1, run: true Nov 23 07:20:37 rivo volumio[19717]: info: camilladsp spawned new process with pid 22602, instance 1, run: true Nov 23 07:20:37 rivo volumio[19717]: info: camilladsp spawned new process with pid 22609, instance 1, run: true Nov 23 07:20:37 rivo volumio[19717]: info: camilladsp spawned new process with pid 22616, instance 1, run: true Nov 23 07:20:37 rivo volumio[19717]: info: camilladsp spawned new process with pid 22623, instance 1, run: true Nov 23 07:20:38 rivo volumio[19717]: info: camilladsp spawned new process with pid 22633, instance 1, run: true Nov 23 07:20:38 rivo volumio[19717]: info: camilladsp spawned new process with pid 22640, instance 1, run: true Nov 23 07:20:38 rivo volumio[19717]: info: camilladsp spawned new process with pid 22647, instance 1, run: true Nov 23 07:20:38 rivo volumio[19717]: info: camilladsp spawned new process with pid 22654, instance 1, run: true Nov 23 07:20:38 rivo volumio[19717]: info: camilladsp spawned new process with pid 22661, instance 1, run: true Nov 23 07:20:38 rivo volumio[19717]: info: camilladsp spawned new process with pid 22668, instance 1, run: true Nov 23 07:20:38 rivo volumio[19717]: info: camilladsp spawned new process with pid 22675, instance 1, run: true Nov 23 07:20:38 rivo volumio[19717]: info: camilladsp spawned new process with pid 22682, instance 1, run: true Nov 23 07:20:38 rivo volumio[19717]: info: camilladsp spawned new process with pid 22690, instance 1, run: true Nov 23 07:20:38 rivo volumio[19717]: info: camilladsp spawned new process with pid 22697, instance 1, run: true Nov 23 07:20:38 rivo volumio[19717]: info: camilladsp spawned new process with pid 22704, instance 1, run: true Nov 23 07:20:39 rivo volumio[19717]: info: camilladsp spawned new process with pid 22711, instance 1, run: true Nov 23 07:20:39 rivo volumio[19717]: info: camilladsp spawned new process with pid 22718, instance 1, run: true Nov 23 07:20:39 rivo volumio[19717]: info: camilladsp spawned new process with pid 22725, instance 1, run: true Nov 23 07:20:39 rivo volumio[19717]: info: camilladsp spawned new process with pid 22732, instance 1, run: true Nov 23 07:20:39 rivo volumio[19717]: info: camilladsp spawned new process with pid 22739, instance 1, run: true Nov 23 07:20:39 rivo volumio[19717]: info: camilladsp spawned new process with pid 22746, instance 1, run: true Nov 23 07:20:39 rivo volumio[19717]: info: camilladsp spawned new process with pid 22753, instance 1, run: true Nov 23 07:20:39 rivo volumio[19717]: info: camilladsp spawned new process with pid 22760, instance 1, run: true Nov 23 07:20:39 rivo volumio[19717]: info: camilladsp spawned new process with pid 22767, instance 1, run: true Nov 23 07:20:39 rivo volumio[19717]: info: camilladsp spawned new process with pid 22774, instance 1, run: true Nov 23 07:20:40 rivo volumio[19717]: info: camilladsp spawned new process with pid 22781, instance 1, run: true Nov 23 07:20:40 rivo volumio[19717]: info: camilladsp spawned new process with pid 22788, instance 1, run: true Nov 23 07:20:40 rivo volumio[19717]: info: camilladsp spawned new process with pid 22795, instance 1, run: true Nov 23 07:20:40 rivo volumio[19717]: info: camilladsp spawned new process with pid 22802, instance 1, run: true Nov 23 07:20:40 rivo volumio[19717]: info: camilladsp spawned new process with pid 22809, instance 1, run: true Nov 23 07:20:40 rivo volumio[19717]: info: camilladsp spawned new process with pid 22816, instance 1, run: true Nov 23 07:20:40 rivo volumio[19717]: info: camilladsp spawned new process with pid 22823, instance 1, run: true Nov 23 07:20:40 rivo volumio[19717]: info: camilladsp spawned new process with pid 22830, instance 1, run: true Nov 23 07:20:40 rivo volumio[19717]: info: camilladsp spawned new process with pid 22837, instance 1, run: true Nov 23 07:20:40 rivo volumio[19717]: info: camilladsp spawned new process with pid 22844, instance 1, run: true Nov 23 07:20:40 rivo volumio[19717]: info: camilladsp spawned new process with pid 22851, instance 1, run: true Nov 23 07:20:41 rivo volumio[19717]: info: camilladsp spawned new process with pid 22858, instance 1, run: true Nov 23 07:20:41 rivo volumio[19717]: info: camilladsp spawned new process with pid 22865, instance 1, run: true Nov 23 07:20:41 rivo volumio[19717]: info: camilladsp spawned new process with pid 22872, instance 1, run: true Nov 23 07:20:41 rivo volumio[19717]: info: camilladsp spawned new process with pid 22879, instance 1, run: true Nov 23 07:20:41 rivo volumio[19717]: info: camilladsp spawned new process with pid 22886, instance 1, run: true Nov 23 07:20:41 rivo volumio[19717]: info: camilladsp spawned new process with pid 22893, instance 1, run: true Nov 23 07:20:41 rivo volumio[19717]: info: camilladsp spawned new process with pid 22900, instance 1, run: true Nov 23 07:20:41 rivo volumio[19717]: info: camilladsp spawned new process with pid 22907, instance 1, run: true Nov 23 07:20:41 rivo volumio[19717]: info: camilladsp spawned new process with pid 22914, instance 1, run: true Nov 23 07:20:41 rivo volumio[19717]: info: camilladsp spawned new process with pid 22921, instance 1, run: true Nov 23 07:20:41 rivo volumio[19717]: info: camilladsp spawned new process with pid 22932, instance 1, run: true Nov 23 07:20:42 rivo volumio[19717]: info: camilladsp spawned new process with pid 22952, instance 1, run: true Nov 23 07:20:42 rivo volumio[19717]: info: camilladsp spawned new process with pid 22959, instance 1, run: true Nov 23 07:20:42 rivo volumio[19717]: info: camilladsp spawned new process with pid 22966, instance 1, run: true Nov 23 07:20:42 rivo volumio[19717]: info: camilladsp spawned new process with pid 22973, instance 1, run: true Nov 23 07:20:42 rivo volumio[19717]: info: camilladsp spawned new process with pid 22980, instance 1, run: true Nov 23 07:20:42 rivo volumio[19717]: info: camilladsp spawned new process with pid 22987, instance 1, run: true Nov 23 07:20:42 rivo volumio[19717]: info: camilladsp spawned new process with pid 22994, instance 1, run: true Nov 23 07:20:42 rivo volumio[19717]: info: camilladsp spawned new process with pid 23001, instance 1, run: true Nov 23 07:20:42 rivo volumio[19717]: info: camilladsp spawned new process with pid 23008, instance 1, run: true Nov 23 07:20:42 rivo volumio[19717]: info: camilladsp spawned new process with pid 23015, instance 1, run: true Nov 23 07:20:43 rivo volumio[19717]: info: camilladsp spawned new process with pid 23022, instance 1, run: true Nov 23 07:20:43 rivo volumio[19717]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::ClearQueue Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::stop Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::stPlaybackTimer Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::updateTrackBlock Nov 23 07:20:43 rivo volumio[19717]: info: CorePlayQueue::getTrackBlock Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::pushState Nov 23 07:20:43 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:20:43 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 07:20:43 rivo volumio[19717]: info: CoreCommandRouter::volumioPushState Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::serviceStop Nov 23 07:20:43 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:20:43 rivo volumio[19717]: info: CoreCommandRouter::serviceStop Nov 23 07:20:43 rivo volumio[19717]: info: [1732342843082] ControllerUPNPBrowser::stop Nov 23 07:20:43 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 07:20:43 rivo volumio[19717]: info: CorePlayQueue::clearPlayQueue Nov 23 07:20:43 rivo volumio[19717]: info: CorePlayQueue::saveQueue Nov 23 07:20:43 rivo volumio[19717]: info: CoreCommandRouter::volumioPushQueue Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::addQueueItems Nov 23 07:20:43 rivo volumio[19717]: info: CorePlayQueue::addQueueItems Nov 23 07:20:43 rivo volumio[19717]: info: Preload queue cleared Nov 23 07:20:43 rivo volumio[19717]: info: Adding Item to queue: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$884R16373761 Nov 23 07:20:43 rivo volumio[19717]: info: Using cached record of: upnp/http://192.168.0.110:9000/TMSContentDirectory/Control@0$1$11$884R16373761 Nov 23 07:20:43 rivo volumio[19717]: info: CoreCommandRouter::volumioPushQueue Nov 23 07:20:43 rivo volumio[19717]: info: CorePlayQueue::saveQueue Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::updateTrackBlock Nov 23 07:20:43 rivo volumio[19717]: info: CorePlayQueue::getTrackBlock Nov 23 07:20:43 rivo volumio[19717]: info: CoreCommandRouter::volumioPlay Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::play index 0 Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::stop Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::play index undefined Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 07:20:43 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::startPlaybackTimer Nov 23 07:20:43 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:20:43 rivo volumio[19717]: info: CoreCommandRouter::volumioGetVisibleSources Nov 23 07:20:43 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 07:20:43 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 23 07:20:43 rivo volumio[19717]: info: [1732342843140] ControllerUPNPBrowser::clearAddPlayTrack Nov 23 07:20:43 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 07:20:43 rivo volumio[19717]: info: Updating RAAT Signal Path Nov 23 07:20:43 rivo volumio[19717]: info: Nov 23 07:20:43 rivo volumio[19717]: ---------------------------- MPD announces state update: player Nov 23 07:20:43 rivo volumio[19717]: info: sendMpdCommand stop took 77 milliseconds Nov 23 07:20:43 rivo volumio[19717]: info: ControllerMpd::getState Nov 23 07:20:43 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand status Nov 23 07:20:43 rivo volumio[19717]: info: sendMpdCommand stop took 20 milliseconds Nov 23 07:20:43 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 07:20:43 rivo volumio[19717]: info: camilladsp spawned new process with pid 23029, instance 1, run: true Nov 23 07:20:43 rivo volumio[19717]: info: Nov 23 07:20:43 rivo volumio[19717]: ---------------------------- MPD announces system playlist update Nov 23 07:20:43 rivo volumio[19717]: info: Ignoring MPD Status Update Nov 23 07:20:43 rivo volumio[19717]: info: sendMpdCommand status took 48 milliseconds Nov 23 07:20:43 rivo volumio[19717]: info: sendMpdCommand clear took 47 milliseconds Nov 23 07:20:43 rivo volumio[19717]: verbose: ControllerMpd::parseState Nov 23 07:20:43 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 07:20:43 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I16373761.flac" Nov 23 07:20:43 rivo volumio[19717]: error: updateQueue error: null Nov 23 07:20:43 rivo volumio[19717]: info: ------------------------------ 16ms Nov 23 07:20:43 rivo volumio[19717]: info: MCU Signalled Playback Inactive Nov 23 07:20:43 rivo volumio[19717]: info: sendMpdCommand playlistinfo took 48 milliseconds Nov 23 07:20:43 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I16373761.flac" Nov 23 07:20:43 rivo volumio[19717]: verbose: ControllerMpd::parseTrackInfo Nov 23 07:20:43 rivo volumio[19717]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 23 07:20:43 rivo volumio[19717]: info: ------------------------------ 101ms Nov 23 07:20:43 rivo volumio[19717]: info: Nov 23 07:20:43 rivo volumio[19717]: ---------------------------- MPD announces system playlist update Nov 23 07:20:43 rivo volumio[19717]: info: Ignoring MPD Status Update Nov 23 07:20:43 rivo volumio[19717]: info: sendMpdCommand add "http://192.168.0.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I16373761.flac" took 4 milliseconds Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 07:20:43 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand play Nov 23 07:20:43 rivo volumio[19717]: info: ------------------------------ 4ms Nov 23 07:20:43 rivo volumio[19717]: info: sendMpdCommand play took 3 milliseconds Nov 23 07:20:43 rivo volumio[19717]: info: camilladsp spawned new process with pid 23037, instance 1, run: true Nov 23 07:20:43 rivo volumio[19717]: info: camilladsp spawned new process with pid 23044, instance 2, run: true Nov 23 07:20:43 rivo volumio[19717]: info: Nov 23 07:20:43 rivo volumio[19717]: ---------------------------- MPD announces state update: player Nov 23 07:20:43 rivo volumio[19717]: info: ControllerMpd::getState Nov 23 07:20:43 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand status Nov 23 07:20:43 rivo volumio[19717]: info: camilladsp spawned new process with pid 23053, instance 2, run: true Nov 23 07:20:43 rivo volumio[19717]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Nov 23 07:20:43 rivo volumio[19717]: info: FusionDsp - ---- read samplerate from file: 192000 Nov 23 07:20:43 rivo volumio[19717]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 23 07:20:43 rivo volumio[19717]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Nov 23 07:20:43 rivo volumio[19717]: info: FusionDsp - ---- read samplerate from file: 192000 Nov 23 07:20:43 rivo volumio[19717]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 23 07:20:43 rivo volumio[19717]: info: Nov 23 07:20:43 rivo volumio[19717]: ---------------------------- MPD announces system playlist update Nov 23 07:20:43 rivo volumio[19717]: info: Ignoring MPD Status Update Nov 23 07:20:43 rivo volumio[19717]: info: Nov 23 07:20:43 rivo volumio[19717]: ---------------------------- MPD announces state update: player Nov 23 07:20:43 rivo volumio[19717]: info: sendMpdCommand status took 250 milliseconds Nov 23 07:20:43 rivo volumio[19717]: info: ControllerMpd::getState Nov 23 07:20:43 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand status Nov 23 07:20:43 rivo volumio[19717]: verbose: ControllerMpd::parseState Nov 23 07:20:43 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 07:20:43 rivo volumio[19717]: info: ------------------------------ 17ms Nov 23 07:20:43 rivo volumio[19717]: info: sendMpdCommand status took 8 milliseconds Nov 23 07:20:43 rivo volumio[19717]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 23 07:20:43 rivo volumio[19717]: verbose: ControllerMpd::parseState Nov 23 07:20:43 rivo volumio[19717]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 07:20:43 rivo volumio[19717]: verbose: ControllerMpd::parseTrackInfo Nov 23 07:20:43 rivo volumio[19717]: info: ControllerMpd::pushState Nov 23 07:20:43 rivo volumio[19717]: info: CoreCommandRouter::servicePushState Nov 23 07:20:43 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:20:43 rivo volumio[19717]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Autumn in New York","artist":"Milcho Leviev","album":"Live at Vartan Jazz","uri":"http://192.168.0.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I16373761.flac","trackType":"flac"} Nov 23 07:20:43 rivo volumio[19717]: verbose: CURRENT POSITION 0 Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::syncState stateService play Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::syncState currentStatus stop Nov 23 07:20:43 rivo volumio[19717]: info: ------------------------------ 273ms Nov 23 07:20:43 rivo volumio[19717]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 23 07:20:43 rivo volumio[19717]: verbose: ControllerMpd::parseTrackInfo Nov 23 07:20:43 rivo volumio[19717]: info: ControllerMpd::pushState Nov 23 07:20:43 rivo volumio[19717]: info: CoreCommandRouter::servicePushState Nov 23 07:20:43 rivo volumio[19717]: info: CorePlayQueue::getTrack 0 Nov 23 07:20:43 rivo volumio[19717]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1121,"duration":360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"439 Kbps","isStreaming":false,"title":"Autumn in New York","artist":"Milcho Leviev","album":"Live at Vartan Jazz","uri":"http://192.168.0.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I16373761.flac","trackType":"flac"} Nov 23 07:20:43 rivo volumio[19717]: verbose: CURRENT POSITION 0 Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::syncState stateService play Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::syncState currentStatus play Nov 23 07:20:43 rivo volumio[19717]: info: Received an update from plugin. extracting info from payload Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::pushState Nov 23 07:20:43 rivo volumio[19717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 07:20:43 rivo volumio[19717]: info: CoreCommandRouter::volumioPushState Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::pushState Nov 23 07:20:43 rivo volumio[19717]: info: CoreCommandRouter::volumioPushState Nov 23 07:20:43 rivo volumio[19717]: info: ------------------------------ 61ms Nov 23 07:20:43 rivo volumio[19717]: info: CoreCommandRouter::volumioGetQueue Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::getQueue Nov 23 07:20:43 rivo volumio[19717]: info: CorePlayQueue::getQueue Nov 23 07:20:43 rivo volumio[19717]: info: CoreCommandRouter::volumioGetQueue Nov 23 07:20:43 rivo volumio[19717]: info: CoreStateMachine::getQueue Nov 23 07:20:43 rivo volumio[19717]: info: CorePlayQueue::getQueue Nov 23 07:20:43 rivo volumio[19717]: info: Signalling Playback active due to playback status change Nov 23 07:20:43 rivo volumio[19717]: info: Signalling Playback active due to playback status change Nov 23 07:20:43 rivo volumio[19717]: info: Updating RAAT Signal Path Nov 23 07:20:43 rivo volumio[19717]: info: Updating RAAT Signal Path Nov 23 07:20:43 rivo volumio[19717]: info: camilladsp spawned new process with pid 23062, instance 2, run: true Nov 23 07:20:43 rivo volumio[19717]: info: MCU Signalled Playback Active Nov 23 07:20:43 rivo volumio[19717]: info: camilladsp spawned new process with pid 23069, instance 2, run: true Nov 23 07:20:44 rivo volumio[19717]: info: camilladsp spawned new process with pid 23076, instance 2, run: true Nov 23 07:20:44 rivo volumio[19717]: info: camilladsp spawned new process with pid 23083, instance 2, run: true Nov 23 07:20:44 rivo volumio[19717]: info: camilladsp spawned new process with pid 23090, instance 2, run: true Nov 23 07:20:44 rivo volumio[19717]: info: camilladsp spawned new process with pid 23097, instance 2, run: true Nov 23 07:20:44 rivo volumio[19717]: info: camilladsp spawned new process with pid 23104, instance 2, run: true Nov 23 07:20:44 rivo volumio[19717]: info: camilladsp spawned new process with pid 23111, instance 2, run: true Nov 23 07:20:44 rivo volumio[19717]: info: camilladsp spawned new process with pid 23118, instance 2, run: true Nov 23 07:20:44 rivo volumio[19717]: info: camilladsp spawned new process with pid 23135, instance 2, run: true Nov 23 07:20:45 rivo volumio[19717]: info: camilladsp spawned new process with pid 23160, instance 2, run: true Nov 23 07:20:45 rivo volumio[19717]: info: camilladsp spawned new process with pid 23182, instance 2, run: true Nov 23 07:20:45 rivo volumio[19717]: info: camilladsp spawned new process with pid 23202, instance 2, run: true Nov 23 07:20:45 rivo volumio[19717]: info: camilladsp spawned new process with pid 23209, instance 2, run: true Nov 23 07:20:45 rivo volumio[19717]: info: camilladsp spawned new process with pid 23216, instance 2, run: true Nov 23 07:20:45 rivo volumio[19717]: info: camilladsp spawned new process with pid 23223, instance 2, run: true Nov 23 07:20:45 rivo volumio[19717]: info: camilladsp spawned new process with pid 23230, instance 2, run: true Nov 23 07:20:45 rivo volumio[19717]: info: camilladsp spawned new process with pid 23237, instance 2, run: true Nov 23 07:20:45 rivo volumio[19717]: info: camilladsp spawned new process with pid 23244, instance 2, run: true Nov 23 07:20:45 rivo volumio[19717]: info: camilladsp spawned new process with pid 23251, instance 2, run: true Nov 23 07:20:46 rivo volumio[19717]: info: camilladsp spawned new process with pid 23258, instance 2, run: true Nov 23 07:20:46 rivo volumio[19717]: info: camilladsp spawned new process with pid 23265, instance 2, run: true Nov 23 07:20:46 rivo volumio[19717]: info: camilladsp spawned new process with pid 23272, instance 2, run: true Nov 23 07:20:46 rivo volumio[19717]: info: camilladsp spawned new process with pid 23279, instance 2, run: true Nov 23 07:20:46 rivo volumio[19717]: info: camilladsp spawned new process with pid 23286, instance 2, run: true Nov 23 07:20:46 rivo volumio[19717]: info: camilladsp spawned new process with pid 23293, instance 2, run: true Nov 23 07:20:46 rivo volumio[19717]: info: camilladsp spawned new process with pid 23300, instance 2, run: true Nov 23 07:20:46 rivo volumio[19717]: info: camilladsp spawned new process with pid 23307, instance 2, run: true Nov 23 07:20:46 rivo volumio[19717]: info: camilladsp spawned new process with pid 23314, instance 2, run: true Nov 23 07:20:46 rivo volumio[19717]: info: camilladsp spawned new process with pid 23322, instance 2, run: true Nov 23 07:20:47 rivo volumio[19717]: info: camilladsp spawned new process with pid 23330, instance 2, run: true Nov 23 07:20:47 rivo volumio[19717]: info: camilladsp spawned new process with pid 23340, instance 2, run: true Nov 23 07:20:47 rivo volumio[19717]: info: camilladsp spawned new process with pid 23347, instance 2, run: true Nov 23 07:20:47 rivo volumio[19717]: info: camilladsp spawned new process with pid 23354, instance 2, run: true Nov 23 07:20:47 rivo volumio[19717]: info: camilladsp spawned new process with pid 23361, instance 2, run: true Nov 23 07:20:47 rivo volumio[19717]: info: camilladsp spawned new process with pid 23368, instance 2, run: true Nov 23 07:20:47 rivo volumio[19717]: info: camilladsp spawned new process with pid 23375, instance 2, run: true Nov 23 07:20:47 rivo volumio[19717]: info: camilladsp spawned new process with pid 23382, instance 2, run: true Nov 23 07:20:47 rivo volumio[19717]: info: camilladsp spawned new process with pid 23389, instance 2, run: true Nov 23 07:20:47 rivo volumio[19717]: info: camilladsp spawned new process with pid 23396, instance 2, run: true Nov 23 07:20:47 rivo volumio[19717]: info: camilladsp spawned new process with pid 23405, instance 2, run: true Nov 23 07:20:48 rivo volumio[19717]: info: camilladsp spawned new process with pid 23413, instance 2, run: true Nov 23 07:20:48 rivo volumio[19717]: info: camilladsp spawned new process with pid 23420, instance 2, run: true Nov 23 07:20:48 rivo volumio[19717]: info: camilladsp spawned new process with pid 23427, instance 2, run: true Nov 23 07:20:48 rivo volumio[19717]: info: camilladsp spawned new process with pid 23434, instance 2, run: true Nov 23 07:20:48 rivo volumio[19717]: info: camilladsp spawned new process with pid 23441, instance 2, run: true Nov 23 07:20:48 rivo volumio[19717]: info: camilladsp spawned new process with pid 23448, instance 2, run: true Nov 23 07:20:48 rivo volumio[19717]: info: camilladsp spawned new process with pid 23455, instance 2, run: true Nov 23 07:20:48 rivo volumio[19717]: info: camilladsp spawned new process with pid 23462, instance 2, run: true Nov 23 07:20:48 rivo volumio[19717]: info: camilladsp spawned new process with pid 23469, instance 2, run: true Nov 23 07:20:48 rivo volumio[19717]: info: camilladsp spawned new process with pid 23476, instance 2, run: true Nov 23 07:20:48 rivo volumio[19717]: info: camilladsp spawned new process with pid 23483, instance 2, run: true Nov 23 07:20:49 rivo volumio[19717]: info: camilladsp spawned new process with pid 23490, instance 2, run: true Nov 23 07:20:49 rivo volumio[19717]: info: camilladsp spawned new process with pid 23497, instance 2, run: true Nov 23 07:20:49 rivo volumio[19717]: info: camilladsp spawned new process with pid 23504, instance 2, run: true Nov 23 07:20:49 rivo volumio[19717]: info: camilladsp spawned new process with pid 23511, instance 2, run: true Nov 23 07:20:49 rivo volumio[19717]: info: camilladsp spawned new process with pid 23518, instance 2, run: true Nov 23 07:20:49 rivo volumio[19717]: info: camilladsp spawned new process with pid 23525, instance 2, run: true Nov 23 07:20:49 rivo volumio[19717]: info: camilladsp spawned new process with pid 23532, instance 2, run: true Nov 23 07:20:49 rivo volumio[19717]: info: camilladsp spawned new process with pid 23539, instance 2, run: true Nov 23 07:20:49 rivo volumio[19717]: info: camilladsp spawned new process with pid 23546, instance 2, run: true Nov 23 07:20:49 rivo volumio[19717]: info: camilladsp spawned new process with pid 23553, instance 2, run: true Nov 23 07:20:50 rivo volumio[19717]: info: camilladsp spawned new process with pid 23560, instance 2, run: true Nov 23 07:20:50 rivo volumio[19717]: info: camilladsp spawned new process with pid 23567, instance 2, run: true Nov 23 07:20:50 rivo volumio[19717]: info: camilladsp spawned new process with pid 23574, instance 2, run: true Nov 23 07:20:50 rivo volumio[19717]: info: camilladsp spawned new process with pid 23581, instance 2, run: true Nov 23 07:20:50 rivo volumio[19717]: info: camilladsp spawned new process with pid 23588, instance 2, run: true Nov 23 07:20:50 rivo volumio[19717]: info: camilladsp spawned new process with pid 23595, instance 2, run: true Nov 23 07:20:50 rivo volumio[19717]: info: camilladsp spawned new process with pid 23602, instance 2, run: true Nov 23 07:20:50 rivo volumio[19717]: info: camilladsp spawned new process with pid 23609, instance 2, run: true Nov 23 07:20:50 rivo volumio[19717]: info: camilladsp spawned new process with pid 23616, instance 2, run: true Nov 23 07:20:50 rivo volumio[19717]: info: camilladsp spawned new process with pid 23623, instance 2, run: true Nov 23 07:20:50 rivo volumio[19717]: info: camilladsp spawned new process with pid 23630, instance 2, run: true Nov 23 07:20:51 rivo volumio[19717]: info: camilladsp spawned new process with pid 23637, instance 2, run: true Nov 23 07:20:51 rivo volumio[19717]: info: camilladsp spawned new process with pid 23644, instance 2, run: true Nov 23 07:20:51 rivo volumio[19717]: info: camilladsp spawned new process with pid 23651, instance 2, run: true Nov 23 07:20:51 rivo volumio[19717]: info: camilladsp spawned new process with pid 23658, instance 2, run: true Nov 23 07:20:51 rivo volumio[19717]: info: camilladsp spawned new process with pid 23665, instance 2, run: true Nov 23 07:20:51 rivo volumio[19717]: info: camilladsp spawned new process with pid 23672, instance 2, run: true Nov 23 07:20:51 rivo volumio[19717]: info: camilladsp spawned new process with pid 23679, instance 2, run: true Nov 23 07:20:51 rivo volumio[19717]: info: camilladsp spawned new process with pid 23686, instance 2, run: true Nov 23 07:20:51 rivo volumio[19717]: info: camilladsp spawned new process with pid 23693, instance 2, run: true Nov 23 07:20:51 rivo volumio[19717]: info: camilladsp spawned new process with pid 23700, instance 2, run: true Nov 23 07:20:51 rivo volumio[19717]: info: camilladsp spawned new process with pid 23707, instance 2, run: true Nov 23 07:20:52 rivo volumio[19717]: info: camilladsp spawned new process with pid 23714, instance 2, run: true Nov 23 07:20:52 rivo volumio[19717]: info: camilladsp spawned new process with pid 23738, instance 2, run: true Nov 23 07:20:52 rivo volumio[19717]: info: camilladsp spawned new process with pid 23745, instance 2, run: true Nov 23 07:20:52 rivo volumio[19717]: info: camilladsp spawned new process with pid 23752, instance 2, run: true Nov 23 07:20:52 rivo volumio[19717]: info: camilladsp spawned new process with pid 23759, instance 2, run: true Nov 23 07:20:52 rivo volumio[19717]: info: camilladsp spawned new process with pid 23766, instance 2, run: true Nov 23 07:20:52 rivo volumio[19717]: info: camilladsp spawned new process with pid 23773, instance 2, run: true Nov 23 07:20:52 rivo volumio[19717]: info: camilladsp spawned new process with pid 23780, instance 2, run: true Nov 23 07:20:52 rivo volumio[19717]: info: camilladsp spawned new process with pid 23787, instance 2, run: true Nov 23 07:20:52 rivo volumio[19717]: info: camilladsp spawned new process with pid 23794, instance 2, run: true Nov 23 07:20:52 rivo volumio[19717]: info: camilladsp spawned new process with pid 23801, instance 2, run: true Nov 23 07:20:53 rivo volumio[19717]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 07:20:53 rivo volumio[19717]: Error: connect ETIMEDOUT 146.75.54.79:443 Nov 23 07:20:53 rivo volumio[19717]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Nov 23 07:20:53 rivo volumio[19717]: errno: -110, Nov 23 07:20:53 rivo volumio[19717]: code: 'ETIMEDOUT', Nov 23 07:20:53 rivo volumio[19717]: syscall: 'connect', Nov 23 07:20:53 rivo volumio[19717]: address: '146.75.54.79', Nov 23 07:20:53 rivo volumio[19717]: port: 443 Nov 23 07:20:53 rivo volumio[19717]: } Nov 23 07:20:53 rivo volumio[19717]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 07:20:53 rivo sudo[23817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-23 07:19 Nov 23 07:20:53 rivo sudo[23817]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 05:04:03 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b3e66dc85ea2c87aca7db72b97d00dff"