-- Logs begin at Thu 2019-02-14 12:11:59 EET, end at Sun 2024-12-01 18:00:16 EET. -- Dec 01 17:59:00 volumio volumio[931]: info: camilladsp spawned new process with pid 22231, instance 1, run: true Dec 01 17:59:00 volumio volumio[931]: info: camilladsp spawned new process with pid 22238, instance 1, run: true Dec 01 17:59:00 volumio volumio[931]: info: camilladsp spawned new process with pid 22245, instance 1, run: true Dec 01 17:59:00 volumio volumio[931]: info: camilladsp spawned new process with pid 22252, instance 1, run: true Dec 01 17:59:00 volumio volumio[931]: info: camilladsp spawned new process with pid 22259, instance 1, run: true Dec 01 17:59:00 volumio volumio[931]: info: camilladsp spawned new process with pid 22266, instance 1, run: true Dec 01 17:59:00 volumio volumio[931]: info: camilladsp spawned new process with pid 22273, instance 1, run: true Dec 01 17:59:00 volumio volumio[931]: info: camilladsp spawned new process with pid 22280, instance 1, run: true Dec 01 17:59:00 volumio volumio[931]: info: camilladsp spawned new process with pid 22287, instance 1, run: true Dec 01 17:59:00 volumio volumio[931]: info: camilladsp spawned new process with pid 22294, instance 1, run: true Dec 01 17:59:00 volumio volumio[931]: info: camilladsp spawned new process with pid 22301, instance 1, run: true Dec 01 17:59:00 volumio volumio[931]: info: camilladsp spawned new process with pid 22308, instance 1, run: true Dec 01 17:59:00 volumio volumio[931]: info: camilladsp spawned new process with pid 22315, instance 1, run: true Dec 01 17:59:00 volumio volumio[931]: info: camilladsp spawned new process with pid 22322, instance 1, run: true Dec 01 17:59:00 volumio volumio[931]: info: camilladsp spawned new process with pid 22329, instance 1, run: true Dec 01 17:59:00 volumio volumio[931]: info: camilladsp spawned new process with pid 22336, instance 1, run: true Dec 01 17:59:00 volumio volumio[931]: info: camilladsp spawned new process with pid 22343, instance 1, run: true Dec 01 17:59:00 volumio volumio[931]: info: camilladsp spawned new process with pid 22350, instance 1, run: true Dec 01 17:59:00 volumio volumio[931]: info: camilladsp spawned new process with pid 22357, instance 1, run: true Dec 01 17:59:00 volumio volumio[931]: info: camilladsp spawned new process with pid 22364, instance 1, run: true Dec 01 17:59:00 volumio volumio[931]: info: camilladsp spawned new process with pid 22371, instance 1, run: true Dec 01 17:59:00 volumio volumio[931]: info: camilladsp spawned new process with pid 22378, instance 1, run: true Dec 01 17:59:01 volumio volumio[931]: info: camilladsp spawned new process with pid 22385, instance 1, run: true Dec 01 17:59:01 volumio volumio[931]: info: camilladsp spawned new process with pid 22392, instance 1, run: true Dec 01 17:59:01 volumio volumio[931]: info: camilladsp spawned new process with pid 22399, instance 1, run: true Dec 01 17:59:01 volumio volumio[931]: info: camilladsp spawned new process with pid 22406, instance 1, run: true Dec 01 17:59:01 volumio volumio[931]: info: camilladsp spawned new process with pid 22413, instance 1, run: true Dec 01 17:59:01 volumio volumio[931]: info: camilladsp spawned new process with pid 22420, instance 1, run: true Dec 01 17:59:01 volumio volumio[931]: info: camilladsp spawned new process with pid 22427, instance 1, run: true Dec 01 17:59:01 volumio volumio[931]: info: camilladsp spawned new process with pid 22434, instance 1, run: true Dec 01 17:59:01 volumio volumio[931]: info: camilladsp spawned new process with pid 22441, instance 1, run: true Dec 01 17:59:01 volumio volumio[931]: info: camilladsp spawned new process with pid 22448, instance 1, run: true Dec 01 17:59:01 volumio volumio[931]: info: camilladsp spawned new process with pid 22455, instance 1, run: true Dec 01 17:59:01 volumio volumio[931]: info: camilladsp spawned new process with pid 22462, instance 1, run: true Dec 01 17:59:01 volumio volumio[931]: info: camilladsp spawned new process with pid 22469, instance 1, run: true Dec 01 17:59:01 volumio volumio[931]: info: camilladsp spawned new process with pid 22476, instance 1, run: true Dec 01 17:59:01 volumio volumio[931]: info: camilladsp spawned new process with pid 22483, instance 1, run: true Dec 01 17:59:01 volumio volumio[931]: info: camilladsp spawned new process with pid 22490, instance 1, run: true Dec 01 17:59:01 volumio volumio[931]: info: camilladsp spawned new process with pid 22497, instance 1, run: true Dec 01 17:59:01 volumio volumio[931]: info: camilladsp spawned new process with pid 22504, instance 1, run: true Dec 01 17:59:01 volumio volumio[931]: info: camilladsp spawned new process with pid 22511, instance 1, run: true Dec 01 17:59:01 volumio volumio[931]: info: camilladsp spawned new process with pid 22518, instance 1, run: true Dec 01 17:59:01 volumio volumio[931]: info: camilladsp spawned new process with pid 22525, instance 1, run: true Dec 01 17:59:01 volumio volumio[931]: info: camilladsp spawned new process with pid 22532, instance 1, run: true Dec 01 17:59:02 volumio volumio[931]: info: camilladsp spawned new process with pid 22542, instance 1, run: true Dec 01 17:59:02 volumio volumio[931]: info: camilladsp spawned new process with pid 22549, instance 1, run: true Dec 01 17:59:02 volumio volumio[931]: info: camilladsp spawned new process with pid 22560, instance 1, run: true Dec 01 17:59:02 volumio volumio[931]: info: camilladsp spawned new process with pid 22577, instance 1, run: true Dec 01 17:59:02 volumio volumio[931]: info: camilladsp spawned new process with pid 22584, instance 1, run: true Dec 01 17:59:02 volumio volumio[931]: info: camilladsp spawned new process with pid 22591, instance 1, run: true Dec 01 17:59:02 volumio volumio[931]: info: camilladsp spawned new process with pid 22598, instance 1, run: true Dec 01 17:59:02 volumio volumio[931]: info: camilladsp spawned new process with pid 22605, instance 1, run: true Dec 01 17:59:02 volumio volumio[931]: info: camilladsp spawned new process with pid 22612, instance 1, run: true Dec 01 17:59:02 volumio volumio[931]: info: camilladsp spawned new process with pid 22619, instance 1, run: true Dec 01 17:59:02 volumio volumio[931]: info: camilladsp spawned new process with pid 22626, instance 1, run: true Dec 01 17:59:02 volumio volumio[931]: info: camilladsp spawned new process with pid 22633, instance 1, run: true Dec 01 17:59:02 volumio volumio[931]: info: camilladsp spawned new process with pid 22640, instance 1, run: true Dec 01 17:59:02 volumio volumio[931]: info: camilladsp spawned new process with pid 22647, instance 1, run: true Dec 01 17:59:02 volumio volumio[931]: info: camilladsp spawned new process with pid 22654, instance 1, run: true Dec 01 17:59:02 volumio volumio[931]: info: camilladsp spawned new process with pid 22661, instance 1, run: true Dec 01 17:59:02 volumio volumio[931]: info: camilladsp spawned new process with pid 22668, instance 1, run: true Dec 01 17:59:02 volumio volumio[931]: info: camilladsp spawned new process with pid 22675, instance 1, run: true Dec 01 17:59:02 volumio volumio[931]: info: camilladsp spawned new process with pid 22682, instance 1, run: true Dec 01 17:59:02 volumio volumio[931]: info: camilladsp spawned new process with pid 22689, instance 1, run: true Dec 01 17:59:02 volumio volumio[931]: info: camilladsp spawned new process with pid 22696, instance 1, run: true Dec 01 17:59:03 volumio volumio[931]: info: camilladsp spawned new process with pid 22703, instance 1, run: true Dec 01 17:59:03 volumio volumio[931]: info: camilladsp spawned new process with pid 22710, instance 1, run: true Dec 01 17:59:03 volumio volumio[931]: info: camilladsp spawned new process with pid 22717, instance 1, run: true Dec 01 17:59:03 volumio volumio[931]: info: camilladsp spawned new process with pid 22724, instance 1, run: true Dec 01 17:59:03 volumio volumio[931]: info: camilladsp spawned new process with pid 22731, instance 1, run: true Dec 01 17:59:03 volumio volumio[931]: info: camilladsp spawned new process with pid 22738, instance 1, run: true Dec 01 17:59:03 volumio volumio[931]: info: camilladsp spawned new process with pid 22745, instance 1, run: true Dec 01 17:59:03 volumio volumio[931]: info: camilladsp spawned new process with pid 22752, instance 1, run: true Dec 01 17:59:03 volumio volumio[931]: info: camilladsp spawned new process with pid 22759, instance 1, run: true Dec 01 17:59:03 volumio volumio[931]: info: camilladsp spawned new process with pid 22766, instance 1, run: true Dec 01 17:59:03 volumio volumio[931]: info: camilladsp spawned new process with pid 22773, instance 1, run: true Dec 01 17:59:03 volumio volumio[931]: info: camilladsp spawned new process with pid 22780, instance 1, run: true Dec 01 17:59:03 volumio volumio[931]: info: camilladsp spawned new process with pid 22787, instance 1, run: true Dec 01 17:59:03 volumio volumio[931]: info: camilladsp spawned new process with pid 22794, instance 1, run: true Dec 01 17:59:03 volumio volumio[931]: info: camilladsp spawned new process with pid 22801, instance 1, run: true Dec 01 17:59:03 volumio volumio[931]: info: camilladsp spawned new process with pid 22808, instance 1, run: true Dec 01 17:59:03 volumio volumio[931]: info: camilladsp spawned new process with pid 22815, instance 1, run: true Dec 01 17:59:03 volumio volumio[931]: info: camilladsp spawned new process with pid 22822, instance 1, run: true Dec 01 17:59:03 volumio volumio[931]: info: camilladsp spawned new process with pid 22829, instance 1, run: true Dec 01 17:59:03 volumio volumio[931]: info: camilladsp spawned new process with pid 22836, instance 1, run: true Dec 01 17:59:03 volumio volumio[931]: info: camilladsp spawned new process with pid 22843, instance 1, run: true Dec 01 17:59:03 volumio volumio[931]: info: camilladsp spawned new process with pid 22850, instance 1, run: true Dec 01 17:59:04 volumio volumio[931]: info: camilladsp spawned new process with pid 22857, instance 1, run: true Dec 01 17:59:04 volumio volumio[931]: info: camilladsp spawned new process with pid 22864, instance 1, run: true Dec 01 17:59:04 volumio volumio[931]: info: camilladsp spawned new process with pid 22871, instance 1, run: true Dec 01 17:59:04 volumio volumio[931]: info: camilladsp spawned new process with pid 22878, instance 1, run: true Dec 01 17:59:04 volumio volumio[931]: info: camilladsp spawned new process with pid 22885, instance 1, run: true Dec 01 17:59:04 volumio volumio[931]: info: camilladsp spawned new process with pid 22892, instance 1, run: true Dec 01 17:59:04 volumio volumio[931]: info: camilladsp spawned new process with pid 22899, instance 1, run: true Dec 01 17:59:04 volumio volumio[931]: info: camilladsp spawned new process with pid 22906, instance 1, run: true Dec 01 17:59:04 volumio volumio[931]: info: camilladsp spawned new process with pid 22913, instance 1, run: true Dec 01 17:59:04 volumio volumio[931]: info: camilladsp spawned new process with pid 22920, instance 1, run: true Dec 01 17:59:04 volumio volumio[931]: info: camilladsp spawned new process with pid 22927, instance 1, run: true Dec 01 17:59:04 volumio volumio[931]: info: camilladsp spawned new process with pid 22934, instance 1, run: true Dec 01 17:59:04 volumio volumio[931]: info: camilladsp spawned new process with pid 22941, instance 1, run: true Dec 01 17:59:04 volumio volumio[931]: info: camilladsp spawned new process with pid 22948, instance 1, run: true Dec 01 17:59:04 volumio volumio[931]: info: camilladsp spawned new process with pid 22955, instance 1, run: true Dec 01 17:59:04 volumio volumio[931]: info: camilladsp spawned new process with pid 22962, instance 1, run: true Dec 01 17:59:04 volumio volumio[931]: info: camilladsp spawned new process with pid 22969, instance 1, run: true Dec 01 17:59:04 volumio volumio[931]: info: camilladsp spawned new process with pid 22976, instance 1, run: true Dec 01 17:59:04 volumio volumio[931]: info: camilladsp spawned new process with pid 22983, instance 1, run: true Dec 01 17:59:04 volumio volumio[931]: info: camilladsp spawned new process with pid 22990, instance 1, run: true Dec 01 17:59:04 volumio volumio[931]: info: camilladsp spawned new process with pid 22997, instance 1, run: true Dec 01 17:59:05 volumio volumio[931]: info: camilladsp spawned new process with pid 23004, instance 1, run: true Dec 01 17:59:05 volumio volumio[931]: info: camilladsp spawned new process with pid 23011, instance 1, run: true Dec 01 17:59:05 volumio volumio[931]: info: camilladsp spawned new process with pid 23018, instance 1, run: true Dec 01 17:59:05 volumio volumio[931]: info: camilladsp spawned new process with pid 23025, instance 1, run: true Dec 01 17:59:05 volumio volumio[931]: info: camilladsp spawned new process with pid 23032, instance 1, run: true Dec 01 17:59:05 volumio volumio[931]: info: camilladsp spawned new process with pid 23039, instance 1, run: true Dec 01 17:59:05 volumio volumio[931]: info: camilladsp spawned new process with pid 23046, instance 1, run: true Dec 01 17:59:05 volumio volumio[931]: info: camilladsp spawned new process with pid 23053, instance 1, run: true Dec 01 17:59:05 volumio volumio[931]: info: camilladsp spawned new process with pid 23060, instance 1, run: true Dec 01 17:59:05 volumio volumio[931]: info: camilladsp spawned new process with pid 23067, instance 1, run: true Dec 01 17:59:05 volumio volumio[931]: info: camilladsp spawned new process with pid 23074, instance 1, run: true Dec 01 17:59:05 volumio volumio[931]: info: camilladsp spawned new process with pid 23081, instance 1, run: true Dec 01 17:59:05 volumio volumio[931]: info: camilladsp spawned new process with pid 23088, instance 1, run: true Dec 01 17:59:05 volumio volumio[931]: info: camilladsp spawned new process with pid 23095, instance 1, run: true Dec 01 17:59:05 volumio volumio[931]: info: camilladsp spawned new process with pid 23102, instance 1, run: true Dec 01 17:59:05 volumio volumio[931]: info: camilladsp spawned new process with pid 23109, instance 1, run: true Dec 01 17:59:05 volumio volumio[931]: info: camilladsp spawned new process with pid 23116, instance 1, run: true Dec 01 17:59:05 volumio volumio[931]: info: camilladsp spawned new process with pid 23123, instance 1, run: true Dec 01 17:59:05 volumio volumio[931]: info: camilladsp spawned new process with pid 23130, instance 1, run: true Dec 01 17:59:05 volumio volumio[931]: info: camilladsp spawned new process with pid 23137, instance 1, run: true Dec 01 17:59:05 volumio volumio[931]: info: camilladsp spawned new process with pid 23144, instance 1, run: true Dec 01 17:59:05 volumio volumio[931]: info: camilladsp spawned new process with pid 23151, instance 1, run: true Dec 01 17:59:06 volumio volumio[931]: info: camilladsp spawned new process with pid 23158, instance 1, run: true Dec 01 17:59:06 volumio volumio[931]: info: camilladsp spawned new process with pid 23165, instance 1, run: true Dec 01 17:59:06 volumio volumio[931]: info: camilladsp spawned new process with pid 23172, instance 1, run: true Dec 01 17:59:06 volumio volumio[931]: info: camilladsp spawned new process with pid 23179, instance 1, run: true Dec 01 17:59:06 volumio volumio[931]: info: camilladsp spawned new process with pid 23186, instance 1, run: true Dec 01 17:59:06 volumio volumio[931]: info: camilladsp spawned new process with pid 23193, instance 1, run: true Dec 01 17:59:06 volumio volumio[931]: info: camilladsp spawned new process with pid 23200, instance 1, run: true Dec 01 17:59:06 volumio volumio[931]: info: camilladsp spawned new process with pid 23207, instance 1, run: true Dec 01 17:59:06 volumio volumio[931]: info: camilladsp spawned new process with pid 23214, instance 1, run: true Dec 01 17:59:06 volumio volumio[931]: info: camilladsp spawned new process with pid 23221, instance 1, run: true Dec 01 17:59:06 volumio volumio[931]: info: camilladsp spawned new process with pid 23228, instance 1, run: true Dec 01 17:59:06 volumio volumio[931]: info: camilladsp spawned new process with pid 23235, instance 1, run: true Dec 01 17:59:06 volumio volumio[931]: info: camilladsp spawned new process with pid 23242, instance 1, run: true Dec 01 17:59:06 volumio volumio[931]: info: camilladsp spawned new process with pid 23249, instance 1, run: true Dec 01 17:59:06 volumio volumio[931]: info: camilladsp spawned new process with pid 23256, instance 1, run: true Dec 01 17:59:06 volumio volumio[931]: info: camilladsp spawned new process with pid 23263, instance 1, run: true Dec 01 17:59:06 volumio volumio[931]: info: camilladsp spawned new process with pid 23270, instance 1, run: true Dec 01 17:59:06 volumio volumio[931]: info: camilladsp spawned new process with pid 23277, instance 1, run: true Dec 01 17:59:06 volumio volumio[931]: info: camilladsp spawned new process with pid 23284, instance 1, run: true Dec 01 17:59:06 volumio volumio[931]: info: camilladsp spawned new process with pid 23291, instance 1, run: true Dec 01 17:59:06 volumio volumio[931]: info: camilladsp spawned new process with pid 23298, instance 1, run: true Dec 01 17:59:07 volumio volumio[931]: info: camilladsp spawned new process with pid 23305, instance 1, run: true Dec 01 17:59:07 volumio volumio[931]: info: camilladsp spawned new process with pid 23312, instance 1, run: true Dec 01 17:59:07 volumio volumio[931]: info: camilladsp spawned new process with pid 23319, instance 1, run: true Dec 01 17:59:07 volumio volumio[931]: info: camilladsp spawned new process with pid 23326, instance 1, run: true Dec 01 17:59:07 volumio volumio[931]: info: camilladsp spawned new process with pid 23333, instance 1, run: true Dec 01 17:59:07 volumio volumio[931]: info: camilladsp spawned new process with pid 23340, instance 1, run: true Dec 01 17:59:07 volumio volumio[931]: info: camilladsp spawned new process with pid 23347, instance 1, run: true Dec 01 17:59:07 volumio volumio[931]: info: camilladsp spawned new process with pid 23354, instance 1, run: true Dec 01 17:59:07 volumio volumio[931]: info: camilladsp spawned new process with pid 23361, instance 1, run: true Dec 01 17:59:07 volumio volumio[931]: info: camilladsp spawned new process with pid 23368, instance 1, run: true Dec 01 17:59:07 volumio volumio[931]: info: camilladsp spawned new process with pid 23375, instance 1, run: true Dec 01 17:59:07 volumio volumio[931]: info: camilladsp spawned new process with pid 23382, instance 1, run: true Dec 01 17:59:07 volumio volumio[931]: info: camilladsp spawned new process with pid 23389, instance 1, run: true Dec 01 17:59:07 volumio volumio[931]: info: camilladsp spawned new process with pid 23396, instance 1, run: true Dec 01 17:59:07 volumio volumio[931]: info: camilladsp spawned new process with pid 23403, instance 1, run: true Dec 01 17:59:07 volumio volumio[931]: info: camilladsp spawned new process with pid 23410, instance 1, run: true Dec 01 17:59:07 volumio volumio[931]: info: camilladsp spawned new process with pid 23417, instance 1, run: true Dec 01 17:59:07 volumio volumio[931]: info: camilladsp spawned new process with pid 23424, instance 1, run: true Dec 01 17:59:07 volumio volumio[931]: info: camilladsp spawned new process with pid 23431, instance 1, run: true Dec 01 17:59:07 volumio volumio[931]: info: camilladsp spawned new process with pid 23438, instance 1, run: true Dec 01 17:59:07 volumio volumio[931]: info: camilladsp spawned new process with pid 23445, instance 1, run: true Dec 01 17:59:07 volumio volumio[931]: info: camilladsp spawned new process with pid 23452, instance 1, run: true Dec 01 17:59:08 volumio volumio[931]: info: camilladsp spawned new process with pid 23459, instance 1, run: true Dec 01 17:59:08 volumio volumio[931]: info: camilladsp spawned new process with pid 23466, instance 1, run: true Dec 01 17:59:08 volumio volumio[931]: info: camilladsp spawned new process with pid 23473, instance 1, run: true Dec 01 17:59:08 volumio volumio[931]: info: camilladsp spawned new process with pid 23480, instance 1, run: true Dec 01 17:59:08 volumio volumio[931]: info: camilladsp spawned new process with pid 23487, instance 1, run: true Dec 01 17:59:08 volumio volumio[931]: info: camilladsp spawned new process with pid 23494, instance 1, run: true Dec 01 17:59:08 volumio volumio[931]: info: camilladsp spawned new process with pid 23501, instance 1, run: true Dec 01 17:59:08 volumio volumio[931]: info: camilladsp spawned new process with pid 23508, instance 1, run: true Dec 01 17:59:08 volumio volumio[931]: info: camilladsp spawned new process with pid 23515, instance 1, run: true Dec 01 17:59:08 volumio volumio[931]: info: camilladsp spawned new process with pid 23522, instance 1, run: true Dec 01 17:59:08 volumio volumio[931]: info: camilladsp spawned new process with pid 23529, instance 1, run: true Dec 01 17:59:08 volumio volumio[931]: info: camilladsp spawned new process with pid 23536, instance 1, run: true Dec 01 17:59:08 volumio volumio[931]: info: camilladsp spawned new process with pid 23543, instance 1, run: true Dec 01 17:59:08 volumio volumio[931]: info: camilladsp spawned new process with pid 23550, instance 1, run: true Dec 01 17:59:08 volumio volumio[931]: info: camilladsp spawned new process with pid 23557, instance 1, run: true Dec 01 17:59:08 volumio volumio[931]: info: camilladsp spawned new process with pid 23564, instance 1, run: true Dec 01 17:59:08 volumio volumio[931]: info: CoreCommandRouter::volumioGetState Dec 01 17:59:08 volumio volumio[931]: info: CorePlayQueue::getTrack 3 Dec 01 17:59:08 volumio volumio[931]: info: camilladsp spawned new process with pid 23571, instance 1, run: true Dec 01 17:59:08 volumio volumio[931]: info: camilladsp spawned new process with pid 23578, instance 1, run: true Dec 01 17:59:08 volumio volumio[931]: info: camilladsp spawned new process with pid 23585, instance 1, run: true Dec 01 17:59:08 volumio volumio[931]: info: camilladsp spawned new process with pid 23592, instance 1, run: true Dec 01 17:59:08 volumio volumio[931]: info: camilladsp spawned new process with pid 23599, instance 1, run: true Dec 01 17:59:08 volumio volumio[931]: info: camilladsp spawned new process with pid 23607, instance 1, run: true Dec 01 17:59:09 volumio volumio[931]: info: camilladsp spawned new process with pid 23632, instance 1, run: true Dec 01 17:59:09 volumio volumio[931]: info: camilladsp spawned new process with pid 23660, instance 1, run: true Dec 01 17:59:09 volumio volumio[931]: info: camilladsp spawned new process with pid 23680, instance 1, run: true Dec 01 17:59:09 volumio volumio[931]: info: camilladsp spawned new process with pid 23687, instance 1, run: true Dec 01 17:59:09 volumio volumio[931]: info: camilladsp spawned new process with pid 23694, instance 1, run: true Dec 01 17:59:09 volumio volumio[931]: info: camilladsp spawned new process with pid 23701, instance 1, run: true Dec 01 17:59:09 volumio volumio[931]: info: camilladsp spawned new process with pid 23708, instance 1, run: true Dec 01 17:59:09 volumio volumio[931]: info: camilladsp spawned new process with pid 23715, instance 1, run: true Dec 01 17:59:09 volumio volumio[931]: info: camilladsp spawned new process with pid 23722, instance 1, run: true Dec 01 17:59:09 volumio volumio[931]: info: camilladsp spawned new process with pid 23729, instance 1, run: true Dec 01 17:59:09 volumio volumio[931]: info: camilladsp spawned new process with pid 23736, instance 1, run: true Dec 01 17:59:09 volumio volumio[931]: info: camilladsp spawned new process with pid 23743, instance 1, run: true Dec 01 17:59:09 volumio volumio[931]: info: camilladsp spawned new process with pid 23750, instance 1, run: true Dec 01 17:59:09 volumio volumio[931]: info: camilladsp spawned new process with pid 23757, instance 1, run: true Dec 01 17:59:09 volumio volumio[931]: info: camilladsp spawned new process with pid 23764, instance 1, run: true Dec 01 17:59:09 volumio volumio[931]: info: camilladsp spawned new process with pid 23771, instance 1, run: true Dec 01 17:59:09 volumio volumio[931]: info: camilladsp spawned new process with pid 23778, instance 1, run: true Dec 01 17:59:09 volumio volumio[931]: info: camilladsp spawned new process with pid 23785, instance 1, run: true Dec 01 17:59:09 volumio volumio[931]: info: camilladsp spawned new process with pid 23792, instance 1, run: true Dec 01 17:59:09 volumio volumio[931]: info: camilladsp spawned new process with pid 23799, instance 1, run: true Dec 01 17:59:09 volumio volumio[931]: info: camilladsp spawned new process with pid 23806, instance 1, run: true Dec 01 17:59:10 volumio volumio[931]: info: camilladsp spawned new process with pid 23813, instance 1, run: true Dec 01 17:59:10 volumio volumio[931]: info: camilladsp spawned new process with pid 23820, instance 1, run: true Dec 01 17:59:10 volumio volumio[931]: info: camilladsp spawned new process with pid 23827, instance 1, run: true Dec 01 17:59:10 volumio volumio[931]: info: camilladsp spawned new process with pid 23834, instance 1, run: true Dec 01 17:59:10 volumio volumio[931]: info: camilladsp spawned new process with pid 23841, instance 1, run: true Dec 01 17:59:10 volumio volumio[931]: info: camilladsp spawned new process with pid 23848, instance 1, run: true Dec 01 17:59:10 volumio volumio[931]: info: camilladsp spawned new process with pid 23855, instance 1, run: true Dec 01 17:59:10 volumio volumio[931]: info: camilladsp spawned new process with pid 23862, instance 1, run: true Dec 01 17:59:10 volumio volumio[931]: info: camilladsp spawned new process with pid 23869, instance 1, run: true Dec 01 17:59:10 volumio volumio[931]: info: camilladsp spawned new process with pid 23876, instance 1, run: true Dec 01 17:59:10 volumio volumio[931]: info: camilladsp spawned new process with pid 23883, instance 1, run: true Dec 01 17:59:10 volumio volumio[931]: info: camilladsp spawned new process with pid 23890, instance 1, run: true Dec 01 17:59:10 volumio volumio[931]: info: camilladsp spawned new process with pid 23897, instance 1, run: true Dec 01 17:59:10 volumio volumio[931]: info: camilladsp spawned new process with pid 23904, instance 1, run: true Dec 01 17:59:10 volumio volumio[931]: info: camilladsp spawned new process with pid 23911, instance 1, run: true Dec 01 17:59:10 volumio volumio[931]: info: camilladsp spawned new process with pid 23918, instance 1, run: true Dec 01 17:59:10 volumio volumio[931]: info: camilladsp spawned new process with pid 23925, instance 1, run: true Dec 01 17:59:10 volumio volumio[931]: info: camilladsp spawned new process with pid 23932, instance 1, run: true Dec 01 17:59:10 volumio volumio[931]: info: camilladsp spawned new process with pid 23939, instance 1, run: true Dec 01 17:59:10 volumio volumio[931]: info: camilladsp spawned new process with pid 23946, instance 1, run: true Dec 01 17:59:10 volumio volumio[931]: info: camilladsp spawned new process with pid 23953, instance 1, run: true Dec 01 17:59:11 volumio volumio[931]: info: camilladsp spawned new process with pid 23960, instance 1, run: true Dec 01 17:59:11 volumio volumio[931]: info: camilladsp spawned new process with pid 23967, instance 1, run: true Dec 01 17:59:11 volumio volumio[931]: info: camilladsp spawned new process with pid 23974, instance 1, run: true Dec 01 17:59:11 volumio volumio[931]: info: camilladsp spawned new process with pid 23981, instance 1, run: true Dec 01 17:59:11 volumio volumio[931]: info: camilladsp spawned new process with pid 23988, instance 1, run: true Dec 01 17:59:11 volumio volumio[931]: info: camilladsp spawned new process with pid 23995, instance 1, run: true Dec 01 17:59:11 volumio volumio[931]: info: camilladsp spawned new process with pid 24002, instance 1, run: true Dec 01 17:59:11 volumio volumio[931]: info: camilladsp spawned new process with pid 24009, instance 1, run: true Dec 01 17:59:11 volumio volumio[931]: info: camilladsp spawned new process with pid 24016, instance 1, run: true Dec 01 17:59:11 volumio volumio[931]: info: camilladsp spawned new process with pid 24023, instance 1, run: true Dec 01 17:59:11 volumio volumio[931]: info: camilladsp spawned new process with pid 24030, instance 1, run: true Dec 01 17:59:11 volumio volumio[931]: info: camilladsp spawned new process with pid 24037, instance 1, run: true Dec 01 17:59:11 volumio volumio[931]: info: camilladsp spawned new process with pid 24044, instance 1, run: true Dec 01 17:59:11 volumio volumio[931]: info: camilladsp spawned new process with pid 24051, instance 1, run: true Dec 01 17:59:11 volumio volumio[931]: info: camilladsp spawned new process with pid 24058, instance 1, run: true Dec 01 17:59:11 volumio volumio[931]: info: camilladsp spawned new process with pid 24065, instance 1, run: true Dec 01 17:59:11 volumio volumio[931]: info: camilladsp spawned new process with pid 24072, instance 1, run: true Dec 01 17:59:11 volumio volumio[931]: info: camilladsp spawned new process with pid 24079, instance 1, run: true Dec 01 17:59:11 volumio volumio[931]: info: camilladsp spawned new process with pid 24086, instance 1, run: true Dec 01 17:59:11 volumio volumio[931]: info: camilladsp spawned new process with pid 24093, instance 1, run: true Dec 01 17:59:11 volumio volumio[931]: info: camilladsp spawned new process with pid 24100, instance 1, run: true Dec 01 17:59:11 volumio volumio[931]: info: camilladsp spawned new process with pid 24107, instance 1, run: true Dec 01 17:59:12 volumio volumio[931]: info: camilladsp spawned new process with pid 24114, instance 1, run: true Dec 01 17:59:12 volumio volumio[931]: info: camilladsp spawned new process with pid 24124, instance 1, run: true Dec 01 17:59:12 volumio volumio[931]: info: camilladsp spawned new process with pid 24131, instance 1, run: true Dec 01 17:59:12 volumio volumio[931]: info: camilladsp spawned new process with pid 24138, instance 1, run: true Dec 01 17:59:12 volumio volumio[931]: info: camilladsp spawned new process with pid 24159, instance 1, run: true Dec 01 17:59:12 volumio volumio[931]: info: camilladsp spawned new process with pid 24166, instance 1, run: true Dec 01 17:59:12 volumio volumio[931]: info: camilladsp spawned new process with pid 24173, instance 1, run: true Dec 01 17:59:12 volumio volumio[931]: info: camilladsp spawned new process with pid 24180, instance 1, run: true Dec 01 17:59:12 volumio volumio[931]: info: camilladsp spawned new process with pid 24187, instance 1, run: true Dec 01 17:59:12 volumio volumio[931]: info: camilladsp spawned new process with pid 24194, instance 1, run: true Dec 01 17:59:12 volumio volumio[931]: info: camilladsp spawned new process with pid 24201, instance 1, run: true Dec 01 17:59:12 volumio volumio[931]: info: camilladsp spawned new process with pid 24208, instance 1, run: true Dec 01 17:59:12 volumio volumio[931]: info: camilladsp spawned new process with pid 24215, instance 1, run: true Dec 01 17:59:12 volumio volumio[931]: info: camilladsp spawned new process with pid 24222, instance 1, run: true Dec 01 17:59:12 volumio volumio[931]: info: camilladsp spawned new process with pid 24229, instance 1, run: true Dec 01 17:59:12 volumio volumio[931]: info: camilladsp spawned new process with pid 24236, instance 1, run: true Dec 01 17:59:12 volumio volumio[931]: info: camilladsp spawned new process with pid 24243, instance 1, run: true Dec 01 17:59:12 volumio volumio[931]: info: camilladsp spawned new process with pid 24250, instance 1, run: true Dec 01 17:59:12 volumio volumio[931]: info: camilladsp spawned new process with pid 24257, instance 1, run: true Dec 01 17:59:12 volumio volumio[931]: info: camilladsp spawned new process with pid 24264, instance 1, run: true Dec 01 17:59:12 volumio volumio[931]: info: camilladsp spawned new process with pid 24271, instance 1, run: true Dec 01 17:59:13 volumio volumio[931]: info: camilladsp spawned new process with pid 24278, instance 1, run: true Dec 01 17:59:13 volumio volumio[931]: info: camilladsp spawned new process with pid 24285, instance 1, run: true Dec 01 17:59:13 volumio volumio[931]: info: camilladsp spawned new process with pid 24292, instance 1, run: true Dec 01 17:59:13 volumio volumio[931]: info: camilladsp spawned new process with pid 24299, instance 1, run: true Dec 01 17:59:13 volumio volumio[931]: info: camilladsp spawned new process with pid 24306, instance 1, run: true Dec 01 17:59:13 volumio volumio[931]: info: camilladsp spawned new process with pid 24313, instance 1, run: true Dec 01 17:59:13 volumio volumio[931]: info: camilladsp spawned new process with pid 24320, instance 1, run: true Dec 01 17:59:13 volumio volumio[931]: info: camilladsp spawned new process with pid 24327, instance 1, run: true Dec 01 17:59:13 volumio volumio[931]: info: camilladsp spawned new process with pid 24334, instance 1, run: true Dec 01 17:59:13 volumio volumio[931]: info: camilladsp spawned new process with pid 24341, instance 1, run: true Dec 01 17:59:13 volumio volumio[931]: info: camilladsp spawned new process with pid 24348, instance 1, run: true Dec 01 17:59:13 volumio volumio[931]: info: camilladsp spawned new process with pid 24355, instance 1, run: true Dec 01 17:59:13 volumio volumio[931]: info: camilladsp spawned new process with pid 24362, instance 1, run: true Dec 01 17:59:13 volumio volumio[931]: info: camilladsp spawned new process with pid 24369, instance 1, run: true Dec 01 17:59:13 volumio volumio[931]: info: camilladsp spawned new process with pid 24376, instance 1, run: true Dec 01 17:59:13 volumio volumio[931]: info: camilladsp spawned new process with pid 24383, instance 1, run: true Dec 01 17:59:13 volumio volumio[931]: info: camilladsp spawned new process with pid 24390, instance 1, run: true Dec 01 17:59:13 volumio volumio[931]: info: camilladsp spawned new process with pid 24397, instance 1, run: true Dec 01 17:59:13 volumio volumio[931]: info: camilladsp spawned new process with pid 24404, instance 1, run: true Dec 01 17:59:13 volumio volumio[931]: info: camilladsp spawned new process with pid 24411, instance 1, run: true Dec 01 17:59:13 volumio volumio[931]: info: camilladsp spawned new process with pid 24418, instance 1, run: true Dec 01 17:59:13 volumio volumio[931]: info: camilladsp spawned new process with pid 24425, instance 1, run: true Dec 01 17:59:14 volumio volumio[931]: info: camilladsp spawned new process with pid 24432, instance 1, run: true Dec 01 17:59:14 volumio volumio[931]: info: camilladsp spawned new process with pid 24439, instance 1, run: true Dec 01 17:59:14 volumio volumio[931]: info: camilladsp spawned new process with pid 24446, instance 1, run: true Dec 01 17:59:14 volumio volumio[931]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/buster/armhf Dec 01 17:59:14 volumio volumio[931]: info: camilladsp spawned new process with pid 24455, instance 1, run: true Dec 01 17:59:14 volumio volumio[931]: info: camilladsp spawned new process with pid 24462, instance 1, run: true Dec 01 17:59:14 volumio volumio[931]: info: camilladsp spawned new process with pid 24469, instance 1, run: true Dec 01 17:59:14 volumio volumio[931]: info: camilladsp spawned new process with pid 24476, instance 1, run: true Dec 01 17:59:14 volumio volumio[931]: info: camilladsp spawned new process with pid 24483, instance 1, run: true Dec 01 17:59:14 volumio volumio[931]: info: camilladsp spawned new process with pid 24490, instance 1, run: true Dec 01 17:59:14 volumio volumio[931]: info: camilladsp spawned new process with pid 24498, instance 1, run: true Dec 01 17:59:14 volumio volumio[931]: info: camilladsp spawned new process with pid 24505, instance 1, run: true Dec 01 17:59:14 volumio volumio[931]: info: camilladsp spawned new process with pid 24512, instance 1, run: true Dec 01 17:59:14 volumio volumio[931]: info: camilladsp spawned new process with pid 24519, instance 1, run: true Dec 01 17:59:14 volumio volumio[931]: info: camilladsp spawned new process with pid 24526, instance 1, run: true Dec 01 17:59:14 volumio volumio[931]: info: camilladsp spawned new process with pid 24533, instance 1, run: true Dec 01 17:59:14 volumio volumio[931]: info: camilladsp spawned new process with pid 24540, instance 1, run: true Dec 01 17:59:14 volumio volumio[931]: info: camilladsp spawned new process with pid 24547, instance 1, run: true Dec 01 17:59:14 volumio volumio[931]: info: camilladsp spawned new process with pid 24554, instance 1, run: true Dec 01 17:59:14 volumio volumio[931]: info: camilladsp spawned new process with pid 24561, instance 1, run: true Dec 01 17:59:14 volumio volumio[931]: info: camilladsp spawned new process with pid 24568, instance 1, run: true Dec 01 17:59:14 volumio volumio[931]: info: camilladsp spawned new process with pid 24575, instance 1, run: true Dec 01 17:59:15 volumio volumio[931]: info: camilladsp spawned new process with pid 24582, instance 1, run: true Dec 01 17:59:15 volumio volumio[931]: info: camilladsp spawned new process with pid 24589, instance 1, run: true Dec 01 17:59:15 volumio volumio[931]: info: camilladsp spawned new process with pid 24596, instance 1, run: true Dec 01 17:59:15 volumio volumio[931]: info: camilladsp spawned new process with pid 24603, instance 1, run: true Dec 01 17:59:15 volumio volumio[931]: info: camilladsp spawned new process with pid 24610, instance 1, run: true Dec 01 17:59:15 volumio volumio[931]: info: camilladsp spawned new process with pid 24617, instance 1, run: true Dec 01 17:59:15 volumio volumio[931]: info: camilladsp spawned new process with pid 24624, instance 1, run: true Dec 01 17:59:15 volumio volumio[931]: info: camilladsp spawned new process with pid 24631, instance 1, run: true Dec 01 17:59:15 volumio volumio[931]: info: camilladsp spawned new process with pid 24638, instance 1, run: true Dec 01 17:59:15 volumio volumio[931]: info: camilladsp spawned new process with pid 24645, instance 1, run: true Dec 01 17:59:15 volumio volumio[931]: info: camilladsp spawned new process with pid 24652, instance 1, run: true Dec 01 17:59:15 volumio volumio[931]: info: camilladsp spawned new process with pid 24659, instance 1, run: true Dec 01 17:59:15 volumio volumio[931]: info: camilladsp spawned new process with pid 24666, instance 1, run: true Dec 01 17:59:15 volumio volumio[931]: info: camilladsp spawned new process with pid 24673, instance 1, run: true Dec 01 17:59:15 volumio volumio[931]: info: camilladsp spawned new process with pid 24680, instance 1, run: true Dec 01 17:59:15 volumio volumio[931]: info: camilladsp spawned new process with pid 24687, instance 1, run: true Dec 01 17:59:15 volumio volumio[931]: info: camilladsp spawned new process with pid 24694, instance 1, run: true Dec 01 17:59:15 volumio volumio[931]: info: camilladsp spawned new process with pid 24701, instance 1, run: true Dec 01 17:59:15 volumio volumio[931]: info: camilladsp spawned new process with pid 24708, instance 1, run: true Dec 01 17:59:15 volumio volumio[931]: info: camilladsp spawned new process with pid 24715, instance 1, run: true Dec 01 17:59:15 volumio volumio[931]: info: camilladsp spawned new process with pid 24722, instance 1, run: true Dec 01 17:59:15 volumio volumio[931]: info: camilladsp spawned new process with pid 24729, instance 1, run: true Dec 01 17:59:16 volumio volumio[931]: info: camilladsp spawned new process with pid 24736, instance 1, run: true Dec 01 17:59:16 volumio volumio[931]: info: camilladsp spawned new process with pid 24743, instance 1, run: true Dec 01 17:59:16 volumio volumio[931]: info: camilladsp spawned new process with pid 24750, instance 1, run: true Dec 01 17:59:16 volumio volumio[931]: info: camilladsp spawned new process with pid 24757, instance 1, run: true Dec 01 17:59:16 volumio volumio[931]: info: camilladsp spawned new process with pid 24764, instance 1, run: true Dec 01 17:59:16 volumio volumio[931]: info: camilladsp spawned new process with pid 24771, instance 1, run: true Dec 01 17:59:16 volumio volumio[931]: info: camilladsp spawned new process with pid 24778, instance 1, run: true Dec 01 17:59:16 volumio volumio[931]: info: camilladsp spawned new process with pid 24785, instance 1, run: true Dec 01 17:59:16 volumio volumio[931]: info: camilladsp spawned new process with pid 24792, instance 1, run: true Dec 01 17:59:16 volumio volumio[931]: info: camilladsp spawned new process with pid 24799, instance 1, run: true Dec 01 17:59:16 volumio volumio[931]: info: camilladsp spawned new process with pid 24806, instance 1, run: true Dec 01 17:59:16 volumio volumio[931]: info: camilladsp spawned new process with pid 24813, instance 1, run: true Dec 01 17:59:16 volumio volumio[931]: info: camilladsp spawned new process with pid 24820, instance 1, run: true Dec 01 17:59:16 volumio volumio[931]: info: camilladsp spawned new process with pid 24827, instance 1, run: true Dec 01 17:59:16 volumio volumio[931]: info: camilladsp spawned new process with pid 24834, instance 1, run: true Dec 01 17:59:16 volumio volumio[931]: info: camilladsp spawned new process with pid 24841, instance 1, run: true Dec 01 17:59:16 volumio volumio[931]: info: camilladsp spawned new process with pid 24848, instance 1, run: true Dec 01 17:59:16 volumio volumio[931]: info: camilladsp spawned new process with pid 24855, instance 1, run: true Dec 01 17:59:16 volumio volumio[931]: info: camilladsp spawned new process with pid 24862, instance 1, run: true Dec 01 17:59:16 volumio volumio[931]: info: camilladsp spawned new process with pid 24869, instance 1, run: true Dec 01 17:59:17 volumio volumio[931]: info: camilladsp spawned new process with pid 24876, instance 1, run: true Dec 01 17:59:17 volumio volumio[931]: info: camilladsp spawned new process with pid 24883, instance 1, run: true Dec 01 17:59:17 volumio volumio[931]: info: camilladsp spawned new process with pid 24890, instance 1, run: true Dec 01 17:59:17 volumio volumio[931]: info: camilladsp spawned new process with pid 24897, instance 1, run: true Dec 01 17:59:17 volumio volumio[931]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/buster/armhf Dec 01 17:59:17 volumio volumio[931]: info: Folder /tmp/plugins removed Dec 01 17:59:17 volumio volumio[931]: info: camilladsp spawned new process with pid 24906, instance 1, run: true Dec 01 17:59:17 volumio volumio[931]: info: camilladsp spawned new process with pid 24913, instance 1, run: true Dec 01 17:59:17 volumio volumio[931]: info: camilladsp spawned new process with pid 24920, instance 1, run: true Dec 01 17:59:17 volumio volumio[931]: info: camilladsp spawned new process with pid 24927, instance 1, run: true Dec 01 17:59:17 volumio volumio[931]: info: camilladsp spawned new process with pid 24934, instance 1, run: true Dec 01 17:59:17 volumio volumio[931]: info: camilladsp spawned new process with pid 24941, instance 1, run: true Dec 01 17:59:17 volumio volumio[931]: info: camilladsp spawned new process with pid 24948, instance 1, run: true Dec 01 17:59:17 volumio volumio[931]: info: camilladsp spawned new process with pid 24955, instance 1, run: true Dec 01 17:59:17 volumio volumio[931]: info: camilladsp spawned new process with pid 24962, instance 1, run: true Dec 01 17:59:17 volumio volumio[931]: info: camilladsp spawned new process with pid 24969, instance 1, run: true Dec 01 17:59:17 volumio volumio[931]: info: camilladsp spawned new process with pid 24976, instance 1, run: true Dec 01 17:59:17 volumio volumio[931]: info: camilladsp spawned new process with pid 24983, instance 1, run: true Dec 01 17:59:17 volumio volumio[931]: info: camilladsp spawned new process with pid 24990, instance 1, run: true Dec 01 17:59:17 volumio volumio[931]: info: camilladsp spawned new process with pid 24997, instance 1, run: true Dec 01 17:59:17 volumio volumio[931]: info: camilladsp spawned new process with pid 25004, instance 1, run: true Dec 01 17:59:18 volumio volumio[931]: info: camilladsp spawned new process with pid 25011, instance 1, run: true Dec 01 17:59:18 volumio volumio[931]: info: camilladsp spawned new process with pid 25018, instance 1, run: true Dec 01 17:59:18 volumio volumio[931]: info: camilladsp spawned new process with pid 25025, instance 1, run: true Dec 01 17:59:18 volumio volumio[931]: info: camilladsp spawned new process with pid 25032, instance 1, run: true Dec 01 17:59:18 volumio volumio[931]: info: Check plugin dependencies Dec 01 17:59:18 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 17:59:18 volumio volumio[931]: info: Checking if plugin already exists Dec 01 17:59:18 volumio volumio[931]: info: Rename folder Dec 01 17:59:18 volumio volumio[931]: info: Folder /tmp/downloaded_plugin.zip removed Dec 01 17:59:18 volumio volumio[931]: info: Move to category Dec 01 17:59:18 volumio volumio[931]: info: camilladsp spawned new process with pid 25043, instance 1, run: true Dec 01 17:59:20 volumio volumio[931]: info: Checking if install.sh is present Dec 01 17:59:20 volumio volumio[931]: info: Executing install.sh Dec 01 17:59:20 volumio volumio[931]: info: camilladsp spawned new process with pid 25057, instance 1, run: true Dec 01 17:59:20 volumio volumio[931]: info: CoreCommandRouter::volumioGetState Dec 01 17:59:20 volumio volumio[931]: info: CorePlayQueue::getTrack 3 Dec 01 17:59:20 volumio volumio[931]: info: Listing playlists Dec 01 17:59:20 volumio volumio[931]: info: Listing playlists Dec 01 17:59:20 volumio sudo[25056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/now_playing/install.sh Dec 01 17:59:20 volumio sudo[25056]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 17:59:20 volumio volumio[931]: info: camilladsp spawned new process with pid 25067, instance 1, run: true Dec 01 17:59:20 volumio volumio[931]: info: Installing geo-tz node dependency Dec 01 17:59:20 volumio volumio[931]: info: camilladsp spawned new process with pid 25080, instance 1, run: true Dec 01 17:59:20 volumio volumio[931]: info: camilladsp spawned new process with pid 25087, instance 1, run: true Dec 01 17:59:20 volumio volumio[931]: info: camilladsp spawned new process with pid 25094, instance 1, run: true Dec 01 17:59:20 volumio volumio[931]: info: camilladsp spawned new process with pid 25101, instance 1, run: true Dec 01 17:59:20 volumio volumio[931]: info: camilladsp spawned new process with pid 25108, instance 1, run: true Dec 01 17:59:20 volumio volumio[931]: info: camilladsp spawned new process with pid 25115, instance 1, run: true Dec 01 17:59:20 volumio volumio[931]: info: camilladsp spawned new process with pid 25122, instance 1, run: true Dec 01 17:59:20 volumio volumio[931]: info: camilladsp spawned new process with pid 25129, instance 1, run: true Dec 01 17:59:20 volumio volumio[931]: info: camilladsp spawned new process with pid 25136, instance 1, run: true Dec 01 17:59:20 volumio volumio[931]: info: camilladsp spawned new process with pid 25143, instance 1, run: true Dec 01 17:59:20 volumio volumio[931]: info: camilladsp spawned new process with pid 25150, instance 1, run: true Dec 01 17:59:20 volumio volumio[931]: info: camilladsp spawned new process with pid 25157, instance 1, run: true Dec 01 17:59:20 volumio volumio[931]: info: camilladsp spawned new process with pid 25164, instance 1, run: true Dec 01 17:59:20 volumio volumio[931]: info: camilladsp spawned new process with pid 25171, instance 1, run: true Dec 01 17:59:20 volumio volumio[931]: info: camilladsp spawned new process with pid 25178, instance 1, run: true Dec 01 17:59:21 volumio volumio[931]: info: camilladsp spawned new process with pid 25185, instance 1, run: true Dec 01 17:59:21 volumio volumio[931]: info: camilladsp spawned new process with pid 25192, instance 1, run: true Dec 01 17:59:21 volumio volumio[931]: info: camilladsp spawned new process with pid 25199, instance 1, run: true Dec 01 17:59:21 volumio volumio[931]: info: camilladsp spawned new process with pid 25206, instance 1, run: true Dec 01 17:59:21 volumio volumio[931]: info: camilladsp spawned new process with pid 25217, instance 1, run: true Dec 01 17:59:21 volumio volumio[931]: info: camilladsp spawned new process with pid 25224, instance 1, run: true Dec 01 17:59:21 volumio volumio[931]: info: camilladsp spawned new process with pid 25231, instance 1, run: true Dec 01 17:59:21 volumio volumio[931]: info: camilladsp spawned new process with pid 25238, instance 1, run: true Dec 01 17:59:21 volumio volumio[931]: info: camilladsp spawned new process with pid 25245, instance 1, run: true Dec 01 17:59:21 volumio volumio[931]: info: camilladsp spawned new process with pid 25252, instance 1, run: true Dec 01 17:59:21 volumio volumio[931]: info: camilladsp spawned new process with pid 25259, instance 1, run: true Dec 01 17:59:21 volumio volumio[931]: info: camilladsp spawned new process with pid 25266, instance 1, run: true Dec 01 17:59:21 volumio volumio[931]: info: camilladsp spawned new process with pid 25273, instance 1, run: true Dec 01 17:59:21 volumio volumio[931]: info: camilladsp spawned new process with pid 25280, instance 1, run: true Dec 01 17:59:21 volumio volumio[931]: info: camilladsp spawned new process with pid 25287, instance 1, run: true Dec 01 17:59:21 volumio volumio[931]: info: camilladsp spawned new process with pid 25294, instance 1, run: true Dec 01 17:59:21 volumio volumio[931]: info: camilladsp spawned new process with pid 25301, instance 1, run: true Dec 01 17:59:21 volumio volumio[931]: info: camilladsp spawned new process with pid 25308, instance 1, run: true Dec 01 17:59:21 volumio volumio[931]: info: camilladsp spawned new process with pid 25315, instance 1, run: true Dec 01 17:59:22 volumio volumio[931]: info: camilladsp spawned new process with pid 25322, instance 1, run: true Dec 01 17:59:22 volumio volumio[931]: info: camilladsp spawned new process with pid 25332, instance 1, run: true Dec 01 17:59:22 volumio volumio[931]: info: camilladsp spawned new process with pid 25339, instance 1, run: true Dec 01 17:59:22 volumio volumio[931]: info: camilladsp spawned new process with pid 25346, instance 1, run: true Dec 01 17:59:22 volumio volumio[931]: info: camilladsp spawned new process with pid 25367, instance 1, run: true Dec 01 17:59:22 volumio volumio[931]: info: camilladsp spawned new process with pid 25374, instance 1, run: true Dec 01 17:59:22 volumio volumio[931]: info: camilladsp spawned new process with pid 25381, instance 1, run: true Dec 01 17:59:22 volumio volumio[931]: info: camilladsp spawned new process with pid 25388, instance 1, run: true Dec 01 17:59:22 volumio volumio[931]: info: camilladsp spawned new process with pid 25395, instance 1, run: true Dec 01 17:59:22 volumio volumio[931]: info: camilladsp spawned new process with pid 25402, instance 1, run: true Dec 01 17:59:22 volumio volumio[931]: info: camilladsp spawned new process with pid 25409, instance 1, run: true Dec 01 17:59:22 volumio volumio[931]: info: camilladsp spawned new process with pid 25416, instance 1, run: true Dec 01 17:59:22 volumio volumio[931]: info: camilladsp spawned new process with pid 25423, instance 1, run: true Dec 01 17:59:22 volumio volumio[931]: info: camilladsp spawned new process with pid 25430, instance 1, run: true Dec 01 17:59:22 volumio volumio[931]: info: camilladsp spawned new process with pid 25437, instance 1, run: true Dec 01 17:59:22 volumio volumio[931]: info: camilladsp spawned new process with pid 25444, instance 1, run: true Dec 01 17:59:22 volumio volumio[931]: info: camilladsp spawned new process with pid 25451, instance 1, run: true Dec 01 17:59:22 volumio volumio[931]: info: camilladsp spawned new process with pid 25458, instance 1, run: true Dec 01 17:59:23 volumio volumio[931]: info: camilladsp spawned new process with pid 25465, instance 1, run: true Dec 01 17:59:23 volumio volumio[931]: info: camilladsp spawned new process with pid 25472, instance 1, run: true Dec 01 17:59:23 volumio volumio[931]: info: camilladsp spawned new process with pid 25479, instance 1, run: true Dec 01 17:59:23 volumio volumio[931]: info: camilladsp spawned new process with pid 25486, instance 1, run: true Dec 01 17:59:23 volumio volumio[931]: info: camilladsp spawned new process with pid 25493, instance 1, run: true Dec 01 17:59:23 volumio volumio[931]: info: camilladsp spawned new process with pid 25500, instance 1, run: true Dec 01 17:59:23 volumio volumio[931]: info: camilladsp spawned new process with pid 25507, instance 1, run: true Dec 01 17:59:23 volumio volumio[931]: info: camilladsp spawned new process with pid 25514, instance 1, run: true Dec 01 17:59:23 volumio volumio[931]: info: camilladsp spawned new process with pid 25521, instance 1, run: true Dec 01 17:59:23 volumio volumio[931]: info: camilladsp spawned new process with pid 25528, instance 1, run: true Dec 01 17:59:23 volumio volumio[931]: info: camilladsp spawned new process with pid 25535, instance 1, run: true Dec 01 17:59:23 volumio volumio[931]: info: camilladsp spawned new process with pid 25542, instance 1, run: true Dec 01 17:59:23 volumio volumio[931]: info: camilladsp spawned new process with pid 25549, instance 1, run: true Dec 01 17:59:23 volumio volumio[931]: info: camilladsp spawned new process with pid 25556, instance 1, run: true Dec 01 17:59:23 volumio volumio[931]: info: camilladsp spawned new process with pid 25563, instance 1, run: true Dec 01 17:59:23 volumio volumio[931]: info: camilladsp spawned new process with pid 25570, instance 1, run: true Dec 01 17:59:23 volumio volumio[931]: info: camilladsp spawned new process with pid 25577, instance 1, run: true Dec 01 17:59:23 volumio volumio[931]: info: camilladsp spawned new process with pid 25584, instance 1, run: true Dec 01 17:59:23 volumio volumio[931]: info: camilladsp spawned new process with pid 25591, instance 1, run: true Dec 01 17:59:24 volumio volumio[931]: info: camilladsp spawned new process with pid 25598, instance 1, run: true Dec 01 17:59:24 volumio volumio[931]: info: camilladsp spawned new process with pid 25605, instance 1, run: true Dec 01 17:59:24 volumio volumio[931]: info: camilladsp spawned new process with pid 25612, instance 1, run: true Dec 01 17:59:24 volumio volumio[931]: info: camilladsp spawned new process with pid 25619, instance 1, run: true Dec 01 17:59:24 volumio volumio[931]: info: camilladsp spawned new process with pid 25626, instance 1, run: true Dec 01 17:59:24 volumio volumio[931]: info: camilladsp spawned new process with pid 25633, instance 1, run: true Dec 01 17:59:24 volumio volumio[931]: info: camilladsp spawned new process with pid 25640, instance 1, run: true Dec 01 17:59:24 volumio volumio[931]: info: camilladsp spawned new process with pid 25647, instance 1, run: true Dec 01 17:59:24 volumio volumio[931]: info: camilladsp spawned new process with pid 25654, instance 1, run: true Dec 01 17:59:24 volumio volumio[931]: info: camilladsp spawned new process with pid 25661, instance 1, run: true Dec 01 17:59:24 volumio volumio[931]: info: camilladsp spawned new process with pid 25668, instance 1, run: true Dec 01 17:59:24 volumio volumio[931]: info: camilladsp spawned new process with pid 25675, instance 1, run: true Dec 01 17:59:24 volumio volumio[931]: info: camilladsp spawned new process with pid 25682, instance 1, run: true Dec 01 17:59:24 volumio volumio[931]: info: camilladsp spawned new process with pid 25689, instance 1, run: true Dec 01 17:59:24 volumio volumio[931]: info: camilladsp spawned new process with pid 25696, instance 1, run: true Dec 01 17:59:24 volumio volumio[931]: info: camilladsp spawned new process with pid 25703, instance 1, run: true Dec 01 17:59:24 volumio volumio[931]: info: camilladsp spawned new process with pid 25710, instance 1, run: true Dec 01 17:59:24 volumio volumio[931]: info: camilladsp spawned new process with pid 25717, instance 1, run: true Dec 01 17:59:24 volumio volumio[931]: info: camilladsp spawned new process with pid 25724, instance 1, run: true Dec 01 17:59:25 volumio volumio[931]: info: camilladsp spawned new process with pid 25731, instance 1, run: true Dec 01 17:59:25 volumio volumio[931]: info: camilladsp spawned new process with pid 25738, instance 1, run: true Dec 01 17:59:25 volumio volumio[931]: info: camilladsp spawned new process with pid 25745, instance 1, run: true Dec 01 17:59:25 volumio volumio[931]: info: camilladsp spawned new process with pid 25752, instance 1, run: true Dec 01 17:59:25 volumio volumio[931]: info: camilladsp spawned new process with pid 25759, instance 1, run: true Dec 01 17:59:25 volumio volumio[931]: info: camilladsp spawned new process with pid 25766, instance 1, run: true Dec 01 17:59:25 volumio volumio[931]: info: camilladsp spawned new process with pid 25773, instance 1, run: true Dec 01 17:59:25 volumio volumio[931]: info: camilladsp spawned new process with pid 25780, instance 1, run: true Dec 01 17:59:25 volumio volumio[931]: info: camilladsp spawned new process with pid 25787, instance 1, run: true Dec 01 17:59:25 volumio volumio[931]: info: camilladsp spawned new process with pid 25794, instance 1, run: true Dec 01 17:59:25 volumio volumio[931]: info: camilladsp spawned new process with pid 25801, instance 1, run: true Dec 01 17:59:25 volumio volumio[931]: info: camilladsp spawned new process with pid 25808, instance 1, run: true Dec 01 17:59:25 volumio volumio[931]: info: camilladsp spawned new process with pid 25815, instance 1, run: true Dec 01 17:59:25 volumio volumio[931]: info: camilladsp spawned new process with pid 25822, instance 1, run: true Dec 01 17:59:25 volumio volumio[931]: info: camilladsp spawned new process with pid 25829, instance 1, run: true Dec 01 17:59:25 volumio volumio[931]: info: camilladsp spawned new process with pid 25836, instance 1, run: true Dec 01 17:59:25 volumio volumio[931]: info: camilladsp spawned new process with pid 25843, instance 1, run: true Dec 01 17:59:25 volumio volumio[931]: info: camilladsp spawned new process with pid 25850, instance 1, run: true Dec 01 17:59:25 volumio volumio[931]: info: camilladsp spawned new process with pid 25857, instance 1, run: true Dec 01 17:59:26 volumio volumio[931]: info: camilladsp spawned new process with pid 25864, instance 1, run: true Dec 01 17:59:26 volumio volumio[931]: info: camilladsp spawned new process with pid 25871, instance 1, run: true Dec 01 17:59:26 volumio volumio[931]: info: camilladsp spawned new process with pid 25878, instance 1, run: true Dec 01 17:59:26 volumio volumio[931]: info: camilladsp spawned new process with pid 25885, instance 1, run: true Dec 01 17:59:26 volumio volumio[931]: info: camilladsp spawned new process with pid 25892, instance 1, run: true Dec 01 17:59:26 volumio volumio[931]: info: camilladsp spawned new process with pid 25899, instance 1, run: true Dec 01 17:59:26 volumio volumio[931]: info: camilladsp spawned new process with pid 25906, instance 1, run: true Dec 01 17:59:26 volumio volumio[931]: info: camilladsp spawned new process with pid 25913, instance 1, run: true Dec 01 17:59:26 volumio volumio[931]: info: camilladsp spawned new process with pid 25920, instance 1, run: true Dec 01 17:59:26 volumio volumio[931]: info: camilladsp spawned new process with pid 25927, instance 1, run: true Dec 01 17:59:26 volumio volumio[931]: info: camilladsp spawned new process with pid 25934, instance 1, run: true Dec 01 17:59:26 volumio volumio[931]: info: camilladsp spawned new process with pid 25941, instance 1, run: true Dec 01 17:59:26 volumio volumio[931]: info: camilladsp spawned new process with pid 25948, instance 1, run: true Dec 01 17:59:26 volumio volumio[931]: info: camilladsp spawned new process with pid 25955, instance 1, run: true Dec 01 17:59:26 volumio volumio[931]: info: camilladsp spawned new process with pid 25962, instance 1, run: true Dec 01 17:59:26 volumio volumio[931]: info: camilladsp spawned new process with pid 25969, instance 1, run: true Dec 01 17:59:26 volumio volumio[931]: info: camilladsp spawned new process with pid 25976, instance 1, run: true Dec 01 17:59:26 volumio volumio[931]: info: camilladsp spawned new process with pid 25983, instance 1, run: true Dec 01 17:59:27 volumio volumio[931]: info: camilladsp spawned new process with pid 25990, instance 1, run: true Dec 01 17:59:27 volumio volumio[931]: info: camilladsp spawned new process with pid 25997, instance 1, run: true Dec 01 17:59:27 volumio volumio[931]: info: camilladsp spawned new process with pid 26004, instance 1, run: true Dec 01 17:59:27 volumio volumio[931]: info: camilladsp spawned new process with pid 26011, instance 1, run: true Dec 01 17:59:27 volumio volumio[931]: info: camilladsp spawned new process with pid 26018, instance 1, run: true Dec 01 17:59:27 volumio volumio[931]: info: camilladsp spawned new process with pid 26025, instance 1, run: true Dec 01 17:59:27 volumio volumio[931]: info: camilladsp spawned new process with pid 26032, instance 1, run: true Dec 01 17:59:27 volumio volumio[931]: info: camilladsp spawned new process with pid 26039, instance 1, run: true Dec 01 17:59:27 volumio volumio[931]: info: camilladsp spawned new process with pid 26046, instance 1, run: true Dec 01 17:59:27 volumio volumio[931]: info: camilladsp spawned new process with pid 26053, instance 1, run: true Dec 01 17:59:27 volumio volumio[931]: info: camilladsp spawned new process with pid 26060, instance 1, run: true Dec 01 17:59:27 volumio volumio[931]: info: camilladsp spawned new process with pid 26067, instance 1, run: true Dec 01 17:59:27 volumio volumio[931]: info: camilladsp spawned new process with pid 26074, instance 1, run: true Dec 01 17:59:27 volumio volumio[931]: info: camilladsp spawned new process with pid 26081, instance 1, run: true Dec 01 17:59:27 volumio volumio[931]: info: camilladsp spawned new process with pid 26088, instance 1, run: true Dec 01 17:59:27 volumio volumio[931]: info: camilladsp spawned new process with pid 26095, instance 1, run: true Dec 01 17:59:27 volumio volumio[931]: info: camilladsp spawned new process with pid 26102, instance 1, run: true Dec 01 17:59:27 volumio volumio[931]: info: camilladsp spawned new process with pid 26109, instance 1, run: true Dec 01 17:59:27 volumio volumio[931]: info: camilladsp spawned new process with pid 26116, instance 1, run: true Dec 01 17:59:28 volumio volumio[931]: info: camilladsp spawned new process with pid 26123, instance 1, run: true Dec 01 17:59:28 volumio volumio[931]: info: camilladsp spawned new process with pid 26130, instance 1, run: true Dec 01 17:59:28 volumio volumio[931]: info: camilladsp spawned new process with pid 26137, instance 1, run: true Dec 01 17:59:28 volumio volumio[931]: info: camilladsp spawned new process with pid 26144, instance 1, run: true Dec 01 17:59:28 volumio volumio[931]: info: camilladsp spawned new process with pid 26151, instance 1, run: true Dec 01 17:59:28 volumio volumio[931]: info: camilladsp spawned new process with pid 26158, instance 1, run: true Dec 01 17:59:28 volumio volumio[931]: info: camilladsp spawned new process with pid 26165, instance 1, run: true Dec 01 17:59:28 volumio volumio[931]: info: camilladsp spawned new process with pid 26172, instance 1, run: true Dec 01 17:59:28 volumio volumio[931]: info: camilladsp spawned new process with pid 26179, instance 1, run: true Dec 01 17:59:28 volumio volumio[931]: info: camilladsp spawned new process with pid 26186, instance 1, run: true Dec 01 17:59:28 volumio volumio[931]: info: camilladsp spawned new process with pid 26193, instance 1, run: true Dec 01 17:59:28 volumio volumio[931]: info: camilladsp spawned new process with pid 26200, instance 1, run: true Dec 01 17:59:28 volumio volumio[931]: info: camilladsp spawned new process with pid 26207, instance 1, run: true Dec 01 17:59:28 volumio volumio[931]: info: camilladsp spawned new process with pid 26214, instance 1, run: true Dec 01 17:59:28 volumio volumio[931]: info: CoreCommandRouter::volumioGetState Dec 01 17:59:28 volumio volumio[931]: info: CorePlayQueue::getTrack 3 Dec 01 17:59:28 volumio volumio[931]: info: camilladsp spawned new process with pid 26221, instance 1, run: true Dec 01 17:59:28 volumio volumio[931]: info: camilladsp spawned new process with pid 26228, instance 1, run: true Dec 01 17:59:28 volumio volumio[931]: info: camilladsp spawned new process with pid 26235, instance 1, run: true Dec 01 17:59:28 volumio volumio[931]: info: camilladsp spawned new process with pid 26242, instance 1, run: true Dec 01 17:59:28 volumio volumio[931]: info: camilladsp spawned new process with pid 26249, instance 1, run: true Dec 01 17:59:29 volumio volumio[931]: info: camilladsp spawned new process with pid 26256, instance 1, run: true Dec 01 17:59:29 volumio volumio[931]: info: camilladsp spawned new process with pid 26263, instance 1, run: true Dec 01 17:59:29 volumio volumio[931]: info: camilladsp spawned new process with pid 26270, instance 1, run: true Dec 01 17:59:29 volumio volumio[931]: info: camilladsp spawned new process with pid 26277, instance 1, run: true Dec 01 17:59:29 volumio volumio[931]: info: camilladsp spawned new process with pid 26284, instance 1, run: true Dec 01 17:59:29 volumio volumio[931]: info: camilladsp spawned new process with pid 26291, instance 1, run: true Dec 01 17:59:29 volumio volumio[931]: info: camilladsp spawned new process with pid 26298, instance 1, run: true Dec 01 17:59:29 volumio volumio[931]: info: camilladsp spawned new process with pid 26305, instance 1, run: true Dec 01 17:59:29 volumio volumio[931]: info: camilladsp spawned new process with pid 26312, instance 1, run: true Dec 01 17:59:29 volumio volumio[931]: info: camilladsp spawned new process with pid 26319, instance 1, run: true Dec 01 17:59:29 volumio volumio[931]: info: camilladsp spawned new process with pid 26326, instance 1, run: true Dec 01 17:59:29 volumio volumio[931]: info: camilladsp spawned new process with pid 26333, instance 1, run: true Dec 01 17:59:29 volumio volumio[931]: info: camilladsp spawned new process with pid 26340, instance 1, run: true Dec 01 17:59:29 volumio volumio[931]: info: camilladsp spawned new process with pid 26347, instance 1, run: true Dec 01 17:59:29 volumio volumio[931]: info: camilladsp spawned new process with pid 26354, instance 1, run: true Dec 01 17:59:29 volumio volumio[931]: info: camilladsp spawned new process with pid 26361, instance 1, run: true Dec 01 17:59:29 volumio volumio[931]: info: camilladsp spawned new process with pid 26368, instance 1, run: true Dec 01 17:59:29 volumio volumio[931]: info: camilladsp spawned new process with pid 26375, instance 1, run: true Dec 01 17:59:29 volumio volumio[931]: info: camilladsp spawned new process with pid 26382, instance 1, run: true Dec 01 17:59:30 volumio volumio[931]: info: camilladsp spawned new process with pid 26389, instance 1, run: true Dec 01 17:59:30 volumio volumio[931]: info: camilladsp spawned new process with pid 26396, instance 1, run: true Dec 01 17:59:30 volumio volumio[931]: info: camilladsp spawned new process with pid 26403, instance 1, run: true Dec 01 17:59:30 volumio volumio[931]: info: camilladsp spawned new process with pid 26410, instance 1, run: true Dec 01 17:59:30 volumio volumio[931]: info: camilladsp spawned new process with pid 26417, instance 1, run: true Dec 01 17:59:30 volumio volumio[931]: info: camilladsp spawned new process with pid 26424, instance 1, run: true Dec 01 17:59:30 volumio volumio[931]: info: camilladsp spawned new process with pid 26431, instance 1, run: true Dec 01 17:59:30 volumio volumio[931]: info: camilladsp spawned new process with pid 26438, instance 1, run: true Dec 01 17:59:30 volumio volumio[931]: info: camilladsp spawned new process with pid 26445, instance 1, run: true Dec 01 17:59:30 volumio volumio[931]: info: camilladsp spawned new process with pid 26452, instance 1, run: true Dec 01 17:59:30 volumio volumio[931]: info: camilladsp spawned new process with pid 26459, instance 1, run: true Dec 01 17:59:30 volumio volumio[931]: info: camilladsp spawned new process with pid 26466, instance 1, run: true Dec 01 17:59:30 volumio volumio[931]: info: camilladsp spawned new process with pid 26473, instance 1, run: true Dec 01 17:59:30 volumio volumio[931]: info: camilladsp spawned new process with pid 26480, instance 1, run: true Dec 01 17:59:30 volumio volumio[931]: info: camilladsp spawned new process with pid 26487, instance 1, run: true Dec 01 17:59:30 volumio volumio[931]: info: camilladsp spawned new process with pid 26494, instance 1, run: true Dec 01 17:59:30 volumio volumio[931]: info: camilladsp spawned new process with pid 26501, instance 1, run: true Dec 01 17:59:30 volumio volumio[931]: info: CorePlayQueue::getTrack 3 Dec 01 17:59:30 volumio volumio[931]: info: CorePlayQueue::getTrack 58 Dec 01 17:59:30 volumio volumio[931]: info: Prefetching next song Dec 01 17:59:30 volumio volumio[931]: info: [1733068770928] ControllerSpotify::prefetch Dec 01 17:59:30 volumio volumio[931]: info: Sending Spotify command with payload to local API: /player/add_to_queue Dec 01 17:59:30 volumio volumio[931]: info: camilladsp spawned new process with pid 26508, instance 1, run: true Dec 01 17:59:31 volumio volumio[931]: info: camilladsp spawned new process with pid 26515, instance 1, run: true Dec 01 17:59:31 volumio volumio[931]: info: camilladsp spawned new process with pid 26522, instance 1, run: true Dec 01 17:59:31 volumio go-librespot[5497]: time="2024-12-01T17:59:31+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 17:59:31 volumio go-librespot[5497]: time="2024-12-01T17:59:31+02:00" level=debug msg="prefetching next track" uri="spotify:track:1mH6tZC6iXIHPI6EixCwxw" Dec 01 17:59:31 volumio go-librespot[5497]: time="2024-12-01T17:59:31+02:00" level=debug msg="selected format OGG_VORBIS_320 (0b3e71455ee91756a213d9d40f41b01e01814cd4)" uri="spotify:track:1mH6tZC6iXIHPI6EixCwxw" Dec 01 17:59:31 volumio go-librespot[5497]: time="2024-12-01T17:59:31+02:00" level=debug msg="requested aes key for file 0b3e71455ee91756a213d9d40f41b01e01814cd4, gid: 1mH6tZC6iXIHPI6EixCwxw" Dec 01 17:59:31 volumio volumio[931]: info: camilladsp spawned new process with pid 26529, instance 1, run: true Dec 01 17:59:31 volumio volumio[931]: info: camilladsp spawned new process with pid 26536, instance 1, run: true Dec 01 17:59:31 volumio volumio[931]: info: camilladsp spawned new process with pid 26544, instance 1, run: true Dec 01 17:59:31 volumio go-librespot[5497]: time="2024-12-01T17:59:31+02:00" level=debug msg="fetched first chunk of 22, total size is 11153500 bytes" uri="spotify:track:1mH6tZC6iXIHPI6EixCwxw" Dec 01 17:59:31 volumio go-librespot[5497]: time="2024-12-01T17:59:31+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:1mH6tZC6iXIHPI6EixCwxw" Dec 01 17:59:31 volumio go-librespot[5497]: time="2024-12-01T17:59:31+02:00" level=info msg="prefetched track \"I Wish It Could Be Christmas Everyday\" (duration: 278946ms)" uri="spotify:track:1mH6tZC6iXIHPI6EixCwxw" Dec 01 17:59:31 volumio volumio[931]: info: camilladsp spawned new process with pid 26551, instance 1, run: true Dec 01 17:59:31 volumio go-librespot[5497]: time="2024-12-01T17:59:31+02:00" level=debug msg="fetched chunk 3/21, size: 524288" uri="spotify:track:1mH6tZC6iXIHPI6EixCwxw" Dec 01 17:59:31 volumio volumio[931]: info: camilladsp spawned new process with pid 26558, instance 1, run: true Dec 01 17:59:31 volumio volumio[931]: info: camilladsp spawned new process with pid 26565, instance 1, run: true Dec 01 17:59:31 volumio volumio[931]: info: camilladsp spawned new process with pid 26572, instance 1, run: true Dec 01 17:59:31 volumio go-librespot[5497]: time="2024-12-01T17:59:31+02:00" level=debug msg="fetched chunk 2/21, size: 524288" uri="spotify:track:1mH6tZC6iXIHPI6EixCwxw" Dec 01 17:59:31 volumio go-librespot[5497]: time="2024-12-01T17:59:31+02:00" level=debug msg="fetched chunk 1/21, size: 524288" uri="spotify:track:1mH6tZC6iXIHPI6EixCwxw" Dec 01 17:59:31 volumio volumio[931]: info: camilladsp spawned new process with pid 26579, instance 1, run: true Dec 01 17:59:31 volumio volumio[931]: info: camilladsp spawned new process with pid 26586, instance 1, run: true Dec 01 17:59:31 volumio volumio[931]: info: camilladsp spawned new process with pid 26593, instance 1, run: true Dec 01 17:59:31 volumio volumio[931]: info: camilladsp spawned new process with pid 26600, instance 1, run: true Dec 01 17:59:31 volumio volumio[931]: info: camilladsp spawned new process with pid 26607, instance 1, run: true Dec 01 17:59:31 volumio volumio[931]: info: camilladsp spawned new process with pid 26614, instance 1, run: true Dec 01 17:59:31 volumio volumio[931]: info: camilladsp spawned new process with pid 26621, instance 1, run: true Dec 01 17:59:31 volumio volumio[931]: info: camilladsp spawned new process with pid 26628, instance 1, run: true Dec 01 17:59:32 volumio volumio[931]: info: camilladsp spawned new process with pid 26635, instance 1, run: true Dec 01 17:59:32 volumio volumio[931]: info: camilladsp spawned new process with pid 26645, instance 1, run: true Dec 01 17:59:32 volumio volumio[931]: info: camilladsp spawned new process with pid 26652, instance 1, run: true Dec 01 17:59:32 volumio volumio[931]: info: camilladsp spawned new process with pid 26659, instance 1, run: true Dec 01 17:59:32 volumio volumio[931]: info: camilladsp spawned new process with pid 26678, instance 1, run: true Dec 01 17:59:32 volumio volumio[931]: info: camilladsp spawned new process with pid 26687, instance 1, run: true Dec 01 17:59:32 volumio volumio[931]: info: camilladsp spawned new process with pid 26694, instance 1, run: true Dec 01 17:59:32 volumio volumio[931]: info: camilladsp spawned new process with pid 26701, instance 1, run: true Dec 01 17:59:32 volumio volumio[931]: info: camilladsp spawned new process with pid 26708, instance 1, run: true Dec 01 17:59:32 volumio volumio[931]: info: camilladsp spawned new process with pid 26715, instance 1, run: true Dec 01 17:59:32 volumio volumio[931]: info: camilladsp spawned new process with pid 26722, instance 1, run: true Dec 01 17:59:32 volumio volumio[931]: info: camilladsp spawned new process with pid 26729, instance 1, run: true Dec 01 17:59:32 volumio volumio[931]: info: camilladsp spawned new process with pid 26737, instance 1, run: true Dec 01 17:59:32 volumio volumio[931]: info: camilladsp spawned new process with pid 26744, instance 1, run: true Dec 01 17:59:32 volumio volumio[931]: info: camilladsp spawned new process with pid 26751, instance 1, run: true Dec 01 17:59:32 volumio volumio[931]: info: camilladsp spawned new process with pid 26758, instance 1, run: true Dec 01 17:59:32 volumio volumio[931]: info: camilladsp spawned new process with pid 26765, instance 1, run: true Dec 01 17:59:32 volumio volumio[931]: info: camilladsp spawned new process with pid 26772, instance 1, run: true Dec 01 17:59:33 volumio volumio[931]: info: camilladsp spawned new process with pid 26779, instance 1, run: true Dec 01 17:59:33 volumio volumio[931]: info: camilladsp spawned new process with pid 26786, instance 1, run: true Dec 01 17:59:33 volumio volumio[931]: info: camilladsp spawned new process with pid 26793, instance 1, run: true Dec 01 17:59:33 volumio volumio[931]: info: camilladsp spawned new process with pid 26800, instance 1, run: true Dec 01 17:59:33 volumio volumio[931]: info: camilladsp spawned new process with pid 26807, instance 1, run: true Dec 01 17:59:33 volumio volumio[931]: info: camilladsp spawned new process with pid 26814, instance 1, run: true Dec 01 17:59:33 volumio volumio[931]: info: camilladsp spawned new process with pid 26821, instance 1, run: true Dec 01 17:59:33 volumio volumio[931]: info: camilladsp spawned new process with pid 26828, instance 1, run: true Dec 01 17:59:33 volumio volumio[931]: info: camilladsp spawned new process with pid 26835, instance 1, run: true Dec 01 17:59:33 volumio volumio[931]: info: camilladsp spawned new process with pid 26842, instance 1, run: true Dec 01 17:59:33 volumio volumio[931]: info: camilladsp spawned new process with pid 26849, instance 1, run: true Dec 01 17:59:33 volumio volumio[931]: info: camilladsp spawned new process with pid 26856, instance 1, run: true Dec 01 17:59:33 volumio volumio[931]: info: camilladsp spawned new process with pid 26863, instance 1, run: true Dec 01 17:59:33 volumio volumio[931]: info: camilladsp spawned new process with pid 26870, instance 1, run: true Dec 01 17:59:33 volumio volumio[931]: info: camilladsp spawned new process with pid 26877, instance 1, run: true Dec 01 17:59:33 volumio volumio[931]: info: camilladsp spawned new process with pid 26884, instance 1, run: true Dec 01 17:59:33 volumio volumio[931]: info: camilladsp spawned new process with pid 26891, instance 1, run: true Dec 01 17:59:33 volumio volumio[931]: info: camilladsp spawned new process with pid 26898, instance 1, run: true Dec 01 17:59:33 volumio volumio[931]: info: camilladsp spawned new process with pid 26905, instance 1, run: true Dec 01 17:59:34 volumio volumio[931]: info: camilladsp spawned new process with pid 26912, instance 1, run: true Dec 01 17:59:34 volumio volumio[931]: info: camilladsp spawned new process with pid 26919, instance 1, run: true Dec 01 17:59:34 volumio volumio[931]: info: camilladsp spawned new process with pid 26926, instance 1, run: true Dec 01 17:59:34 volumio volumio[931]: info: camilladsp spawned new process with pid 26933, instance 1, run: true Dec 01 17:59:34 volumio volumio[931]: info: camilladsp spawned new process with pid 26940, instance 1, run: true Dec 01 17:59:34 volumio volumio[931]: info: camilladsp spawned new process with pid 26947, instance 1, run: true Dec 01 17:59:34 volumio volumio[931]: info: camilladsp spawned new process with pid 26954, instance 1, run: true Dec 01 17:59:34 volumio volumio[931]: info: camilladsp spawned new process with pid 26962, instance 1, run: true Dec 01 17:59:34 volumio volumio[931]: info: camilladsp spawned new process with pid 26969, instance 1, run: true Dec 01 17:59:34 volumio volumio[931]: info: camilladsp spawned new process with pid 26976, instance 1, run: true Dec 01 17:59:34 volumio volumio[931]: info: camilladsp spawned new process with pid 26983, instance 1, run: true Dec 01 17:59:34 volumio volumio[931]: info: camilladsp spawned new process with pid 26990, instance 1, run: true Dec 01 17:59:34 volumio volumio[931]: info: camilladsp spawned new process with pid 26997, instance 1, run: true Dec 01 17:59:34 volumio volumio[931]: info: camilladsp spawned new process with pid 27004, instance 1, run: true Dec 01 17:59:34 volumio volumio[931]: info: camilladsp spawned new process with pid 27011, instance 1, run: true Dec 01 17:59:34 volumio volumio[931]: info: camilladsp spawned new process with pid 27018, instance 1, run: true Dec 01 17:59:34 volumio volumio[931]: info: camilladsp spawned new process with pid 27025, instance 1, run: true Dec 01 17:59:35 volumio volumio[931]: info: camilladsp spawned new process with pid 27032, instance 1, run: true Dec 01 17:59:35 volumio go-librespot[5497]: time="2024-12-01T17:59:35+02:00" level=trace msg="emitting websocket event: not_playing" Dec 01 17:59:35 volumio go-librespot[5497]: time="2024-12-01T17:59:35+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:1mH6tZC6iXIHPI6EixCwxw" Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:2FRnf9qhLbvw8fu4IBXx78","play_origin":"go-librespot"}} Dec 01 17:59:35 volumio volumio[931]: error: Failed to decode event: not_playing Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:2FRnf9qhLbvw8fu4IBXx78","play_origin":"go-librespot"}} Dec 01 17:59:35 volumio volumio[931]: error: Failed to decode event: not_playing Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:2FRnf9qhLbvw8fu4IBXx78","play_origin":"go-librespot"}} Dec 01 17:59:35 volumio volumio[931]: error: Failed to decode event: not_playing Dec 01 17:59:35 volumio volumio[931]: info: camilladsp spawned new process with pid 27039, instance 1, run: true Dec 01 17:59:35 volumio go-librespot[5497]: time="2024-12-01T17:59:35+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 17:59:35 volumio go-librespot[5497]: time="2024-12-01T17:59:35+02:00" level=trace msg="emitting websocket event: will_play" Dec 01 17:59:35 volumio volumio[931]: info: camilladsp spawned new process with pid 27046, instance 1, run: true Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","play_origin":"go-librespot"}} Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","play_origin":"go-librespot"}} Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","play_origin":"go-librespot"}} Dec 01 17:59:35 volumio go-librespot[5497]: time="2024-12-01T17:59:35+02:00" level=info msg="loaded track \"I Wish It Could Be Christmas Everyday\" (paused: false, position: 0ms, duration: 278946ms, prefetched: true)" uri="spotify:track:1mH6tZC6iXIHPI6EixCwxw" Dec 01 17:59:35 volumio volumio[931]: info: camilladsp spawned new process with pid 27053, instance 1, run: true Dec 01 17:59:35 volumio go-librespot[5497]: time="2024-12-01T17:59:35+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 17:59:35 volumio go-librespot[5497]: time="2024-12-01T17:59:35+02:00" level=trace msg="scheduling prefetch in 249s" Dec 01 17:59:35 volumio go-librespot[5497]: time="2024-12-01T17:59:35+02:00" level=trace msg="emitting websocket event: metadata" Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","name":"I Wish It Could Be Christmas Everyday","artist_names":["Wizzard"],"album_name":"I Wish It Could Be Christmas Everyday","album_cover_url":"https://i.scdn.co/image/ab67616d00001e029c462c3958ef80ae2835540c","position":0,"duration":278946,"release_date":"year:2007 month:11 day:19","track_number":1,"disc_number":1}} Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","name":"I Wish It Could Be Christmas Everyday","artist_names":["Wizzard"],"album_name":"I Wish It Could Be Christmas Everyday","album_cover_url":"https://i.scdn.co/image/ab67616d00001e029c462c3958ef80ae2835540c","position":0,"duration":278946,"release_date":"year:2007 month:11 day:19","track_number":1,"disc_number":1}} Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","name":"I Wish It Could Be Christmas Everyday","artist_names":["Wizzard"],"album_name":"I Wish It Could Be Christmas Everyday","album_cover_url":"https://i.scdn.co/image/ab67616d00001e029c462c3958ef80ae2835540c","position":0,"duration":278946,"release_date":"year:2007 month:11 day:19","track_number":1,"disc_number":1}} Dec 01 17:59:35 volumio volumio[931]: info: camilladsp spawned new process with pid 27060, instance 1, run: true Dec 01 17:59:35 volumio go-librespot[5497]: time="2024-12-01T17:59:35+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 17:59:35 volumio go-librespot[5497]: time="2024-12-01T17:59:35+02:00" level=trace msg="emitting websocket event: playing" Dec 01 17:59:35 volumio volumio[931]: info: camilladsp spawned new process with pid 27067, instance 1, run: true Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","play_origin":"go-librespot"}} Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: {"status":"play","service":"spop","title":"I Wish It Could Be Christmas Everyday","artist":"Wizzard","album":"I Wish It Could Be Christmas Everyday","albumart":"https://i.scdn.co/image/ab67616d00001e029c462c3958ef80ae2835540c","uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","trackType":"spotify","seek":0,"duration":278,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:35 volumio volumio[931]: info: CoreCommandRouter::servicePushState Dec 01 17:59:35 volumio volumio[931]: info: CorePlayQueue::getTrack 3 Dec 01 17:59:35 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"I Wish It Could Be Christmas Everyday","artist":"Wizzard","album":"I Wish It Could Be Christmas Everyday","albumart":"https://i.scdn.co/image/ab67616d00001e029c462c3958ef80ae2835540c","uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","trackType":"spotify","seek":0,"duration":278,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:35 volumio volumio[931]: verbose: CURRENT POSITION 3 Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Dec 01 17:59:35 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:35 volumio volumio[931]: info: CorePlayQueue::getTrack 3 Dec 01 17:59:35 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 17:59:35 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:35 volumio volumio[931]: info: CorePlayQueue::getTrack 3 Dec 01 17:59:35 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","play_origin":"go-librespot"}} Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: {"status":"play","service":"spop","title":"I Wish It Could Be Christmas Everyday","artist":"Wizzard","album":"I Wish It Could Be Christmas Everyday","albumart":"https://i.scdn.co/image/ab67616d00001e029c462c3958ef80ae2835540c","uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","trackType":"spotify","seek":0,"duration":278,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:35 volumio volumio[931]: info: CoreCommandRouter::servicePushState Dec 01 17:59:35 volumio volumio[931]: info: CorePlayQueue::getTrack 3 Dec 01 17:59:35 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"I Wish It Could Be Christmas Everyday","artist":"Wizzard","album":"I Wish It Could Be Christmas Everyday","albumart":"https://i.scdn.co/image/ab67616d00001e029c462c3958ef80ae2835540c","uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","trackType":"spotify","seek":0,"duration":278,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:35 volumio volumio[931]: verbose: CURRENT POSITION 3 Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Dec 01 17:59:35 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:35 volumio volumio[931]: info: CorePlayQueue::getTrack 3 Dec 01 17:59:35 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:35 volumio volumio[931]: info: CorePlayQueue::getTrack 3 Dec 01 17:59:35 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","play_origin":"go-librespot"}} Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: {"status":"play","service":"spop","title":"I Wish It Could Be Christmas Everyday","artist":"Wizzard","album":"I Wish It Could Be Christmas Everyday","albumart":"https://i.scdn.co/image/ab67616d00001e029c462c3958ef80ae2835540c","uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","trackType":"spotify","seek":0,"duration":278,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:35 volumio volumio[931]: info: CoreCommandRouter::servicePushState Dec 01 17:59:35 volumio volumio[931]: info: CorePlayQueue::getTrack 3 Dec 01 17:59:35 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"I Wish It Could Be Christmas Everyday","artist":"Wizzard","album":"I Wish It Could Be Christmas Everyday","albumart":"https://i.scdn.co/image/ab67616d00001e029c462c3958ef80ae2835540c","uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","trackType":"spotify","seek":0,"duration":278,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:35 volumio volumio[931]: verbose: CURRENT POSITION 3 Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Dec 01 17:59:35 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:35 volumio volumio[931]: info: CorePlayQueue::getTrack 3 Dec 01 17:59:35 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:35 volumio volumio[931]: info: CorePlayQueue::getTrack 3 Dec 01 17:59:35 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::startPlaybackTimer Dec 01 17:59:35 volumio volumio[931]: info: CorePlayQueue::getTrack 58 Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:35 volumio volumio[931]: info: camilladsp spawned new process with pid 27074, instance 1, run: true Dec 01 17:59:35 volumio volumio[931]: info: camilladsp spawned new process with pid 27081, instance 1, run: true Dec 01 17:59:35 volumio volumio[931]: info: camilladsp spawned new process with pid 27088, instance 1, run: true Dec 01 17:59:35 volumio volumio[931]: info: camilladsp spawned new process with pid 27095, instance 1, run: true Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: {"status":"play","service":"spop","title":"I Wish It Could Be Christmas Everyday","artist":"Wizzard","album":"I Wish It Could Be Christmas Everyday","albumart":"https://i.scdn.co/image/ab67616d00001e029c462c3958ef80ae2835540c","uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","trackType":"spotify","seek":0,"duration":278,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:35 volumio volumio[931]: info: CoreCommandRouter::servicePushState Dec 01 17:59:35 volumio volumio[931]: info: CorePlayQueue::getTrack 58 Dec 01 17:59:35 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"I Wish It Could Be Christmas Everyday","artist":"Wizzard","album":"I Wish It Could Be Christmas Everyday","albumart":"https://i.scdn.co/image/ab67616d00001e029c462c3958ef80ae2835540c","uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","trackType":"spotify","seek":0,"duration":278,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:35 volumio volumio[931]: verbose: CURRENT POSITION 58 Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Dec 01 17:59:35 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:35 volumio volumio[931]: info: CorePlayQueue::getTrack 58 Dec 01 17:59:35 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 17:59:35 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:35 volumio volumio[931]: info: CorePlayQueue::getTrack 58 Dec 01 17:59:35 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: {"status":"play","service":"spop","title":"I Wish It Could Be Christmas Everyday","artist":"Wizzard","album":"I Wish It Could Be Christmas Everyday","albumart":"https://i.scdn.co/image/ab67616d00001e029c462c3958ef80ae2835540c","uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","trackType":"spotify","seek":0,"duration":278,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:35 volumio volumio[931]: info: CoreCommandRouter::servicePushState Dec 01 17:59:35 volumio volumio[931]: info: CorePlayQueue::getTrack 58 Dec 01 17:59:35 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"I Wish It Could Be Christmas Everyday","artist":"Wizzard","album":"I Wish It Could Be Christmas Everyday","albumart":"https://i.scdn.co/image/ab67616d00001e029c462c3958ef80ae2835540c","uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","trackType":"spotify","seek":0,"duration":278,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:35 volumio volumio[931]: verbose: CURRENT POSITION 58 Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Dec 01 17:59:35 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:35 volumio volumio[931]: info: CorePlayQueue::getTrack 58 Dec 01 17:59:35 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:35 volumio volumio[931]: info: CorePlayQueue::getTrack 58 Dec 01 17:59:35 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:35 volumio volumio[931]: info: camilladsp spawned new process with pid 27102, instance 1, run: true Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: {"status":"play","service":"spop","title":"I Wish It Could Be Christmas Everyday","artist":"Wizzard","album":"I Wish It Could Be Christmas Everyday","albumart":"https://i.scdn.co/image/ab67616d00001e029c462c3958ef80ae2835540c","uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","trackType":"spotify","seek":0,"duration":278,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:35 volumio volumio[931]: info: CoreCommandRouter::servicePushState Dec 01 17:59:35 volumio volumio[931]: info: CorePlayQueue::getTrack 58 Dec 01 17:59:35 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"I Wish It Could Be Christmas Everyday","artist":"Wizzard","album":"I Wish It Could Be Christmas Everyday","albumart":"https://i.scdn.co/image/ab67616d00001e029c462c3958ef80ae2835540c","uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","trackType":"spotify","seek":0,"duration":278,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:35 volumio volumio[931]: verbose: CURRENT POSITION 58 Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Dec 01 17:59:35 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:35 volumio volumio[931]: info: CorePlayQueue::getTrack 58 Dec 01 17:59:35 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:35 volumio volumio[931]: info: CorePlayQueue::getTrack 58 Dec 01 17:59:35 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:35 volumio volumio[931]: info: camilladsp spawned new process with pid 27109, instance 1, run: true Dec 01 17:59:35 volumio volumio[931]: info: camilladsp spawned new process with pid 27116, instance 1, run: true Dec 01 17:59:35 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:35 volumio volumio[931]: info: CorePlayQueue::getTrack 58 Dec 01 17:59:35 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:35 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:35 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:35 volumio volumio[931]: info: camilladsp spawned new process with pid 27123, instance 1, run: true Dec 01 17:59:35 volumio volumio[931]: info: camilladsp spawned new process with pid 27130, instance 1, run: true Dec 01 17:59:35 volumio volumio[931]: info: camilladsp spawned new process with pid 27137, instance 1, run: true Dec 01 17:59:36 volumio volumio[931]: info: camilladsp spawned new process with pid 27144, instance 1, run: true Dec 01 17:59:36 volumio volumio[931]: info: camilladsp spawned new process with pid 27151, instance 1, run: true Dec 01 17:59:36 volumio volumio[931]: info: camilladsp spawned new process with pid 27158, instance 1, run: true Dec 01 17:59:36 volumio volumio[931]: info: camilladsp spawned new process with pid 27165, instance 1, run: true Dec 01 17:59:36 volumio volumio[931]: info: camilladsp spawned new process with pid 27172, instance 1, run: true Dec 01 17:59:36 volumio volumio[931]: info: camilladsp spawned new process with pid 27179, instance 1, run: true Dec 01 17:59:36 volumio volumio[931]: info: camilladsp spawned new process with pid 27186, instance 1, run: true Dec 01 17:59:36 volumio volumio[931]: info: camilladsp spawned new process with pid 27193, instance 1, run: true Dec 01 17:59:36 volumio volumio[931]: info: camilladsp spawned new process with pid 27200, instance 1, run: true Dec 01 17:59:36 volumio volumio[931]: info: camilladsp spawned new process with pid 27207, instance 1, run: true Dec 01 17:59:36 volumio volumio[931]: info: camilladsp spawned new process with pid 27214, instance 1, run: true Dec 01 17:59:36 volumio volumio[931]: info: camilladsp spawned new process with pid 27221, instance 1, run: true Dec 01 17:59:36 volumio volumio[931]: info: camilladsp spawned new process with pid 27228, instance 1, run: true Dec 01 17:59:36 volumio volumio[931]: info: camilladsp spawned new process with pid 27235, instance 1, run: true Dec 01 17:59:36 volumio volumio[931]: info: camilladsp spawned new process with pid 27242, instance 1, run: true Dec 01 17:59:36 volumio volumio[931]: info: camilladsp spawned new process with pid 27249, instance 1, run: true Dec 01 17:59:36 volumio volumio[931]: info: camilladsp spawned new process with pid 27256, instance 1, run: true Dec 01 17:59:36 volumio volumio[931]: info: camilladsp spawned new process with pid 27263, instance 1, run: true Dec 01 17:59:36 volumio volumio[931]: info: camilladsp spawned new process with pid 27270, instance 1, run: true Dec 01 17:59:36 volumio volumio[931]: info: camilladsp spawned new process with pid 27277, instance 1, run: true Dec 01 17:59:37 volumio volumio[931]: info: camilladsp spawned new process with pid 27284, instance 1, run: true Dec 01 17:59:37 volumio volumio[931]: info: camilladsp spawned new process with pid 27291, instance 1, run: true Dec 01 17:59:37 volumio volumio[931]: info: camilladsp spawned new process with pid 27298, instance 1, run: true Dec 01 17:59:37 volumio volumio[931]: info: camilladsp spawned new process with pid 27305, instance 1, run: true Dec 01 17:59:37 volumio volumio[931]: info: camilladsp spawned new process with pid 27312, instance 1, run: true Dec 01 17:59:37 volumio volumio[931]: info: camilladsp spawned new process with pid 27319, instance 1, run: true Dec 01 17:59:37 volumio volumio[931]: info: camilladsp spawned new process with pid 27326, instance 1, run: true Dec 01 17:59:37 volumio volumio[931]: info: camilladsp spawned new process with pid 27333, instance 1, run: true Dec 01 17:59:37 volumio volumio[931]: info: camilladsp spawned new process with pid 27340, instance 1, run: true Dec 01 17:59:37 volumio volumio[931]: info: camilladsp spawned new process with pid 27347, instance 1, run: true Dec 01 17:59:37 volumio volumio[931]: info: camilladsp spawned new process with pid 27354, instance 1, run: true Dec 01 17:59:37 volumio volumio[931]: info: camilladsp spawned new process with pid 27361, instance 1, run: true Dec 01 17:59:37 volumio volumio[931]: info: camilladsp spawned new process with pid 27368, instance 1, run: true Dec 01 17:59:37 volumio volumio[931]: info: camilladsp spawned new process with pid 27375, instance 1, run: true Dec 01 17:59:37 volumio volumio[931]: info: camilladsp spawned new process with pid 27382, instance 1, run: true Dec 01 17:59:37 volumio volumio[931]: info: camilladsp spawned new process with pid 27389, instance 1, run: true Dec 01 17:59:37 volumio volumio[931]: info: camilladsp spawned new process with pid 27396, instance 1, run: true Dec 01 17:59:37 volumio volumio[931]: info: camilladsp spawned new process with pid 27403, instance 1, run: true Dec 01 17:59:37 volumio volumio[931]: info: camilladsp spawned new process with pid 27410, instance 1, run: true Dec 01 17:59:37 volumio volumio[931]: info: camilladsp spawned new process with pid 27417, instance 1, run: true Dec 01 17:59:37 volumio volumio[931]: info: camilladsp spawned new process with pid 27424, instance 1, run: true Dec 01 17:59:38 volumio volumio[931]: info: camilladsp spawned new process with pid 27431, instance 1, run: true Dec 01 17:59:38 volumio volumio[931]: info: camilladsp spawned new process with pid 27438, instance 1, run: true Dec 01 17:59:38 volumio volumio[931]: info: camilladsp spawned new process with pid 27445, instance 1, run: true Dec 01 17:59:38 volumio volumio[931]: info: camilladsp spawned new process with pid 27452, instance 1, run: true Dec 01 17:59:38 volumio volumio[931]: info: camilladsp spawned new process with pid 27459, instance 1, run: true Dec 01 17:59:38 volumio volumio[931]: info: camilladsp spawned new process with pid 27466, instance 1, run: true Dec 01 17:59:38 volumio volumio[931]: info: camilladsp spawned new process with pid 27473, instance 1, run: true Dec 01 17:59:38 volumio volumio[931]: info: camilladsp spawned new process with pid 27480, instance 1, run: true Dec 01 17:59:38 volumio volumio[931]: info: camilladsp spawned new process with pid 27488, instance 1, run: true Dec 01 17:59:38 volumio volumio[931]: info: camilladsp spawned new process with pid 27495, instance 1, run: true Dec 01 17:59:38 volumio volumio[931]: info: camilladsp spawned new process with pid 27502, instance 1, run: true Dec 01 17:59:38 volumio volumio[931]: info: camilladsp spawned new process with pid 27509, instance 1, run: true Dec 01 17:59:38 volumio volumio[931]: info: camilladsp spawned new process with pid 27516, instance 1, run: true Dec 01 17:59:38 volumio volumio[931]: info: camilladsp spawned new process with pid 27523, instance 1, run: true Dec 01 17:59:38 volumio volumio[931]: info: camilladsp spawned new process with pid 27530, instance 1, run: true Dec 01 17:59:38 volumio volumio[931]: info: CoreCommandRouter::volumioGetState Dec 01 17:59:38 volumio volumio[931]: info: CorePlayQueue::getTrack 58 Dec 01 17:59:38 volumio volumio[931]: info: Listing playlists Dec 01 17:59:38 volumio volumio[931]: info: Listing playlists Dec 01 17:59:38 volumio volumio[931]: info: camilladsp spawned new process with pid 27537, instance 1, run: true Dec 01 17:59:38 volumio volumio[931]: info: camilladsp spawned new process with pid 27544, instance 1, run: true Dec 01 17:59:38 volumio volumio[931]: info: camilladsp spawned new process with pid 27551, instance 1, run: true Dec 01 17:59:38 volumio volumio[931]: info: camilladsp spawned new process with pid 27558, instance 1, run: true Dec 01 17:59:38 volumio volumio[931]: info: camilladsp spawned new process with pid 27565, instance 1, run: true Dec 01 17:59:39 volumio volumio[931]: info: camilladsp spawned new process with pid 27572, instance 1, run: true Dec 01 17:59:39 volumio volumio[931]: info: camilladsp spawned new process with pid 27579, instance 1, run: true Dec 01 17:59:39 volumio volumio[931]: info: camilladsp spawned new process with pid 27586, instance 1, run: true Dec 01 17:59:39 volumio volumio[931]: info: camilladsp spawned new process with pid 27593, instance 1, run: true Dec 01 17:59:39 volumio volumio[931]: info: camilladsp spawned new process with pid 27600, instance 1, run: true Dec 01 17:59:39 volumio volumio[931]: info: camilladsp spawned new process with pid 27607, instance 1, run: true Dec 01 17:59:39 volumio volumio[931]: info: camilladsp spawned new process with pid 27614, instance 1, run: true Dec 01 17:59:39 volumio volumio[931]: info: camilladsp spawned new process with pid 27621, instance 1, run: true Dec 01 17:59:39 volumio volumio[931]: info: camilladsp spawned new process with pid 27628, instance 1, run: true Dec 01 17:59:39 volumio volumio[931]: info: camilladsp spawned new process with pid 27635, instance 1, run: true Dec 01 17:59:39 volumio volumio[931]: info: camilladsp spawned new process with pid 27642, instance 1, run: true Dec 01 17:59:39 volumio volumio[931]: info: camilladsp spawned new process with pid 27649, instance 1, run: true Dec 01 17:59:39 volumio volumio[931]: info: camilladsp spawned new process with pid 27656, instance 1, run: true Dec 01 17:59:39 volumio volumio[931]: info: camilladsp spawned new process with pid 27663, instance 1, run: true Dec 01 17:59:39 volumio volumio[931]: info: camilladsp spawned new process with pid 27670, instance 1, run: true Dec 01 17:59:39 volumio volumio[931]: info: camilladsp spawned new process with pid 27677, instance 1, run: true Dec 01 17:59:39 volumio volumio[931]: info: camilladsp spawned new process with pid 27684, instance 1, run: true Dec 01 17:59:39 volumio volumio[931]: info: camilladsp spawned new process with pid 27691, instance 1, run: true Dec 01 17:59:39 volumio volumio[931]: info: camilladsp spawned new process with pid 27698, instance 1, run: true Dec 01 17:59:39 volumio volumio[931]: info: camilladsp spawned new process with pid 27705, instance 1, run: true Dec 01 17:59:39 volumio volumio[931]: info: camilladsp spawned new process with pid 27712, instance 1, run: true Dec 01 17:59:40 volumio volumio[931]: info: camilladsp spawned new process with pid 27719, instance 1, run: true Dec 01 17:59:40 volumio volumio[931]: info: camilladsp spawned new process with pid 27726, instance 1, run: true Dec 01 17:59:40 volumio volumio[931]: info: camilladsp spawned new process with pid 27733, instance 1, run: true Dec 01 17:59:40 volumio volumio[931]: info: camilladsp spawned new process with pid 27740, instance 1, run: true Dec 01 17:59:40 volumio volumio[931]: info: camilladsp spawned new process with pid 27747, instance 1, run: true Dec 01 17:59:40 volumio volumio[931]: info: camilladsp spawned new process with pid 27754, instance 1, run: true Dec 01 17:59:40 volumio volumio[931]: info: camilladsp spawned new process with pid 27761, instance 1, run: true Dec 01 17:59:40 volumio volumio[931]: info: camilladsp spawned new process with pid 27768, instance 1, run: true Dec 01 17:59:40 volumio volumio[931]: info: camilladsp spawned new process with pid 27775, instance 1, run: true Dec 01 17:59:40 volumio volumio[931]: info: camilladsp spawned new process with pid 27782, instance 1, run: true Dec 01 17:59:40 volumio volumio[931]: info: camilladsp spawned new process with pid 27789, instance 1, run: true Dec 01 17:59:40 volumio volumio[931]: info: camilladsp spawned new process with pid 27796, instance 1, run: true Dec 01 17:59:40 volumio volumio[931]: info: camilladsp spawned new process with pid 27803, instance 1, run: true Dec 01 17:59:40 volumio volumio[931]: info: camilladsp spawned new process with pid 27810, instance 1, run: true Dec 01 17:59:40 volumio volumio[931]: info: camilladsp spawned new process with pid 27817, instance 1, run: true Dec 01 17:59:40 volumio volumio[931]: info: camilladsp spawned new process with pid 27824, instance 1, run: true Dec 01 17:59:40 volumio volumio[931]: info: camilladsp spawned new process with pid 27831, instance 1, run: true Dec 01 17:59:40 volumio volumio[931]: info: camilladsp spawned new process with pid 27838, instance 1, run: true Dec 01 17:59:40 volumio volumio[931]: info: camilladsp spawned new process with pid 27845, instance 1, run: true Dec 01 17:59:41 volumio volumio[931]: info: camilladsp spawned new process with pid 27852, instance 1, run: true Dec 01 17:59:41 volumio volumio[931]: info: camilladsp spawned new process with pid 27859, instance 1, run: true Dec 01 17:59:41 volumio volumio[931]: info: camilladsp spawned new process with pid 27866, instance 1, run: true Dec 01 17:59:41 volumio volumio[931]: info: camilladsp spawned new process with pid 27873, instance 1, run: true Dec 01 17:59:41 volumio volumio[931]: info: camilladsp spawned new process with pid 27880, instance 1, run: true Dec 01 17:59:41 volumio volumio[931]: info: camilladsp spawned new process with pid 27887, instance 1, run: true Dec 01 17:59:41 volumio volumio[931]: info: camilladsp spawned new process with pid 27894, instance 1, run: true Dec 01 17:59:41 volumio volumio[931]: info: camilladsp spawned new process with pid 27901, instance 1, run: true Dec 01 17:59:41 volumio volumio[931]: info: camilladsp spawned new process with pid 27908, instance 1, run: true Dec 01 17:59:41 volumio volumio[931]: info: camilladsp spawned new process with pid 27915, instance 1, run: true Dec 01 17:59:41 volumio volumio[931]: info: camilladsp spawned new process with pid 27922, instance 1, run: true Dec 01 17:59:41 volumio volumio[931]: info: camilladsp spawned new process with pid 27929, instance 1, run: true Dec 01 17:59:41 volumio volumio[931]: info: camilladsp spawned new process with pid 27936, instance 1, run: true Dec 01 17:59:41 volumio volumio[931]: info: camilladsp spawned new process with pid 27943, instance 1, run: true Dec 01 17:59:41 volumio volumio[931]: info: camilladsp spawned new process with pid 27950, instance 1, run: true Dec 01 17:59:41 volumio volumio[931]: info: camilladsp spawned new process with pid 27957, instance 1, run: true Dec 01 17:59:41 volumio volumio[931]: info: camilladsp spawned new process with pid 27964, instance 1, run: true Dec 01 17:59:41 volumio volumio[931]: info: camilladsp spawned new process with pid 27971, instance 1, run: true Dec 01 17:59:42 volumio volumio[931]: info: camilladsp spawned new process with pid 27978, instance 1, run: true Dec 01 17:59:42 volumio volumio[931]: info: camilladsp spawned new process with pid 27987, instance 1, run: true Dec 01 17:59:42 volumio volumio[931]: info: camilladsp spawned new process with pid 27995, instance 1, run: true Dec 01 17:59:42 volumio volumio[931]: info: camilladsp spawned new process with pid 28002, instance 1, run: true Dec 01 17:59:42 volumio volumio[931]: info: camilladsp spawned new process with pid 28009, instance 1, run: true Dec 01 17:59:42 volumio volumio[931]: info: camilladsp spawned new process with pid 28020, instance 1, run: true Dec 01 17:59:42 volumio volumio[931]: info: camilladsp spawned new process with pid 28037, instance 1, run: true Dec 01 17:59:42 volumio volumio[931]: info: camilladsp spawned new process with pid 28044, instance 1, run: true Dec 01 17:59:42 volumio volumio[931]: info: camilladsp spawned new process with pid 28051, instance 1, run: true Dec 01 17:59:42 volumio volumio[931]: info: camilladsp spawned new process with pid 28058, instance 1, run: true Dec 01 17:59:42 volumio volumio[931]: info: camilladsp spawned new process with pid 28065, instance 1, run: true Dec 01 17:59:42 volumio volumio[931]: info: camilladsp spawned new process with pid 28072, instance 1, run: true Dec 01 17:59:42 volumio volumio[931]: info: camilladsp spawned new process with pid 28079, instance 1, run: true Dec 01 17:59:42 volumio volumio[931]: info: camilladsp spawned new process with pid 28086, instance 1, run: true Dec 01 17:59:42 volumio volumio[931]: info: camilladsp spawned new process with pid 28093, instance 1, run: true Dec 01 17:59:42 volumio volumio[931]: info: camilladsp spawned new process with pid 28100, instance 1, run: true Dec 01 17:59:42 volumio volumio[931]: info: camilladsp spawned new process with pid 28107, instance 1, run: true Dec 01 17:59:42 volumio volumio[931]: info: camilladsp spawned new process with pid 28114, instance 1, run: true Dec 01 17:59:43 volumio volumio[931]: info: camilladsp spawned new process with pid 28121, instance 1, run: true Dec 01 17:59:43 volumio volumio[931]: info: camilladsp spawned new process with pid 28128, instance 1, run: true Dec 01 17:59:43 volumio volumio[931]: info: camilladsp spawned new process with pid 28135, instance 1, run: true Dec 01 17:59:43 volumio volumio[931]: info: camilladsp spawned new process with pid 28142, instance 1, run: true Dec 01 17:59:43 volumio volumio[931]: info: camilladsp spawned new process with pid 28149, instance 1, run: true Dec 01 17:59:43 volumio volumio[931]: info: camilladsp spawned new process with pid 28156, instance 1, run: true Dec 01 17:59:43 volumio volumio[931]: info: camilladsp spawned new process with pid 28163, instance 1, run: true Dec 01 17:59:43 volumio volumio[931]: info: camilladsp spawned new process with pid 28170, instance 1, run: true Dec 01 17:59:43 volumio volumio[931]: info: camilladsp spawned new process with pid 28177, instance 1, run: true Dec 01 17:59:43 volumio volumio[931]: info: camilladsp spawned new process with pid 28184, instance 1, run: true Dec 01 17:59:43 volumio volumio[931]: info: camilladsp spawned new process with pid 28191, instance 1, run: true Dec 01 17:59:43 volumio volumio[931]: info: camilladsp spawned new process with pid 28198, instance 1, run: true Dec 01 17:59:43 volumio volumio[931]: info: camilladsp spawned new process with pid 28205, instance 1, run: true Dec 01 17:59:43 volumio volumio[931]: info: camilladsp spawned new process with pid 28212, instance 1, run: true Dec 01 17:59:43 volumio volumio[931]: info: camilladsp spawned new process with pid 28219, instance 1, run: true Dec 01 17:59:43 volumio volumio[931]: info: camilladsp spawned new process with pid 28226, instance 1, run: true Dec 01 17:59:43 volumio volumio[931]: info: camilladsp spawned new process with pid 28233, instance 1, run: true Dec 01 17:59:43 volumio volumio[931]: info: camilladsp spawned new process with pid 28240, instance 1, run: true Dec 01 17:59:44 volumio volumio[931]: info: camilladsp spawned new process with pid 28247, instance 1, run: true Dec 01 17:59:44 volumio volumio[931]: info: camilladsp spawned new process with pid 28254, instance 1, run: true Dec 01 17:59:44 volumio volumio[931]: info: camilladsp spawned new process with pid 28261, instance 1, run: true Dec 01 17:59:44 volumio volumio[931]: info: camilladsp spawned new process with pid 28268, instance 1, run: true Dec 01 17:59:44 volumio volumio[931]: info: camilladsp spawned new process with pid 28275, instance 1, run: true Dec 01 17:59:44 volumio volumio[931]: info: camilladsp spawned new process with pid 28282, instance 1, run: true Dec 01 17:59:44 volumio volumio[931]: info: camilladsp spawned new process with pid 28289, instance 1, run: true Dec 01 17:59:44 volumio volumio[931]: info: camilladsp spawned new process with pid 28296, instance 1, run: true Dec 01 17:59:44 volumio volumio[931]: info: camilladsp spawned new process with pid 28303, instance 1, run: true Dec 01 17:59:44 volumio volumio[931]: info: camilladsp spawned new process with pid 28310, instance 1, run: true Dec 01 17:59:44 volumio volumio[931]: info: camilladsp spawned new process with pid 28317, instance 1, run: true Dec 01 17:59:44 volumio volumio[931]: info: camilladsp spawned new process with pid 28324, instance 1, run: true Dec 01 17:59:44 volumio volumio[931]: info: camilladsp spawned new process with pid 28331, instance 1, run: true Dec 01 17:59:44 volumio volumio[931]: info: camilladsp spawned new process with pid 28338, instance 1, run: true Dec 01 17:59:44 volumio volumio[931]: info: camilladsp spawned new process with pid 28345, instance 1, run: true Dec 01 17:59:44 volumio volumio[931]: info: camilladsp spawned new process with pid 28352, instance 1, run: true Dec 01 17:59:44 volumio volumio[931]: info: camilladsp spawned new process with pid 28359, instance 1, run: true Dec 01 17:59:44 volumio volumio[931]: info: camilladsp spawned new process with pid 28366, instance 1, run: true Dec 01 17:59:44 volumio volumio[931]: info: camilladsp spawned new process with pid 28373, instance 1, run: true Dec 01 17:59:44 volumio volumio[931]: info: camilladsp spawned new process with pid 28380, instance 1, run: true Dec 01 17:59:45 volumio volumio[931]: info: camilladsp spawned new process with pid 28387, instance 1, run: true Dec 01 17:59:45 volumio volumio[931]: info: camilladsp spawned new process with pid 28394, instance 1, run: true Dec 01 17:59:45 volumio volumio[931]: info: camilladsp spawned new process with pid 28401, instance 1, run: true Dec 01 17:59:45 volumio volumio[931]: info: camilladsp spawned new process with pid 28408, instance 1, run: true Dec 01 17:59:45 volumio volumio[931]: info: camilladsp spawned new process with pid 28415, instance 1, run: true Dec 01 17:59:45 volumio volumio[931]: info: camilladsp spawned new process with pid 28422, instance 1, run: true Dec 01 17:59:45 volumio volumio[931]: info: camilladsp spawned new process with pid 28429, instance 1, run: true Dec 01 17:59:45 volumio volumio[931]: info: camilladsp spawned new process with pid 28436, instance 1, run: true Dec 01 17:59:45 volumio volumio[931]: info: camilladsp spawned new process with pid 28443, instance 1, run: true Dec 01 17:59:45 volumio volumio[931]: info: Dec 01 17:59:45 volumio volumio[931]: ---------------------------- Client requests Volumio next Dec 01 17:59:45 volumio volumio[931]: info: CoreCommandRouter::volumioNext Dec 01 17:59:45 volumio volumio[931]: info: CoreStateMachine::next Dec 01 17:59:45 volumio volumio[931]: info: CoreStateMachine::stop Dec 01 17:59:45 volumio volumio[931]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 17:59:45 volumio volumio[931]: info: CoreStateMachine::stPlaybackTimer Dec 01 17:59:45 volumio volumio[931]: info: CoreStateMachine::updateTrackBlock Dec 01 17:59:45 volumio volumio[931]: info: CorePlayQueue::getTrackBlock Dec 01 17:59:45 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:45 volumio volumio[931]: info: CorePlayQueue::getTrack 58 Dec 01 17:59:45 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 17:59:45 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:45 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:45 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:45 volumio volumio[931]: info: CoreStateMachine::serviceStop Dec 01 17:59:45 volumio volumio[931]: info: CorePlayQueue::getTrack 58 Dec 01 17:59:45 volumio volumio[931]: info: CoreCommandRouter::serviceStop Dec 01 17:59:45 volumio volumio[931]: info: Spotify Stop Dec 01 17:59:45 volumio volumio[931]: SPOTIFY: SPOTIFY STOP Dec 01 17:59:45 volumio volumio[931]: SPOTIFY: {"status":"play","position":58,"title":"I Wish It Could Be Christmas Everyday","artist":"Wizzard","album":"I Wish It Could Be Christmas Everyday","albumart":"https://i.scdn.co/image/ab67616d0000b2739c462c3958ef80ae2835540c","uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","trackType":"spotify","codec":"ogg","seek":0,"duration":278,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":true,"repeat":true,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Dec 01 17:59:45 volumio volumio[931]: info: Sending Spotify command to local API: /player/pause Dec 01 17:59:45 volumio volumio[931]: info: CoreStateMachine::play index undefined Dec 01 17:59:45 volumio volumio[931]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 17:59:45 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:45 volumio volumio[931]: info: CoreStateMachine::startPlaybackTimer Dec 01 17:59:45 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:45 volumio volumio[931]: info: [1733068785522] ControllerSpotify::clearAddPlayTrack Dec 01 17:59:45 volumio volumio[931]: info: Sending Spotify command with payload to local API: /player/play Dec 01 17:59:45 volumio volumio[931]: info: CoreStateMachine::updateTrackBlock Dec 01 17:59:45 volumio volumio[931]: info: CorePlayQueue::getTrackBlock Dec 01 17:59:45 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:45 volumio volumio[931]: info: CoreCommandRouter::volumioGetState Dec 01 17:59:45 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:45 volumio go-librespot[5497]: time="2024-12-01T17:59:45+02:00" level=debug msg="pause track at 10663ms" Dec 01 17:59:45 volumio volumio[931]: info: camilladsp spawned new process with pid 28450, instance 1, run: true Dec 01 17:59:45 volumio go-librespot[5497]: time="2024-12-01T17:59:45+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 17:59:45 volumio go-librespot[5497]: time="2024-12-01T17:59:45+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 17:59:45 volumio go-librespot[5497]: time="2024-12-01T17:59:45+02:00" level=trace msg="emitting websocket event: paused" Dec 01 17:59:45 volumio volumio[931]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","play_origin":"go-librespot"}} Dec 01 17:59:45 volumio volumio[931]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 17:59:45 volumio volumio[931]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:45 volumio volumio[931]: info: CoreCommandRouter::servicePushState Dec 01 17:59:45 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:45 volumio volumio[931]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:45 volumio volumio[931]: verbose: CURRENT POSITION 52 Dec 01 17:59:45 volumio volumio[931]: info: CoreStateMachine::syncState stateService pause Dec 01 17:59:45 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus stop Dec 01 17:59:45 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:45 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:45 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 17:59:45 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:45 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:45 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:45 volumio volumio[931]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","play_origin":"go-librespot"}} Dec 01 17:59:45 volumio volumio[931]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 17:59:45 volumio volumio[931]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:45 volumio volumio[931]: info: CoreCommandRouter::servicePushState Dec 01 17:59:45 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:45 volumio volumio[931]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:45 volumio volumio[931]: verbose: CURRENT POSITION 52 Dec 01 17:59:45 volumio volumio[931]: info: CoreStateMachine::syncState stateService pause Dec 01 17:59:45 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus stop Dec 01 17:59:45 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:45 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:45 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:45 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:45 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:45 volumio volumio[931]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:1mH6tZC6iXIHPI6EixCwxw","play_origin":"go-librespot"}} Dec 01 17:59:45 volumio volumio[931]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 17:59:45 volumio volumio[931]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:45 volumio volumio[931]: info: CoreCommandRouter::servicePushState Dec 01 17:59:45 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:45 volumio volumio[931]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:45 volumio volumio[931]: verbose: CURRENT POSITION 52 Dec 01 17:59:45 volumio volumio[931]: info: CoreStateMachine::syncState stateService pause Dec 01 17:59:45 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus stop Dec 01 17:59:45 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:45 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:45 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:45 volumio go-librespot[5497]: time="2024-12-01T17:59:45+02:00" level=debug msg="resolved context of track" uri="spotify:track:0Ie5uiv54KgCr7P4sYDTHl" Dec 01 17:59:45 volumio go-librespot[5497]: time="2024-12-01T17:59:45+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:0Ie5uiv54KgCr7P4sYDTHl" Dec 01 17:59:45 volumio go-librespot[5497]: time="2024-12-01T17:59:45+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0Ie5uiv54KgCr7P4sYDTHl" Dec 01 17:59:45 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:45 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:45 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:45 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:45 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:45 volumio go-librespot[5497]: time="2024-12-01T17:59:45+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 17:59:45 volumio go-librespot[5497]: time="2024-12-01T17:59:45+02:00" level=trace msg="emitting websocket event: will_play" Dec 01 17:59:45 volumio volumio[931]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0Ie5uiv54KgCr7P4sYDTHl","play_origin":"go-librespot"}} Dec 01 17:59:45 volumio volumio[931]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0Ie5uiv54KgCr7P4sYDTHl","play_origin":"go-librespot"}} Dec 01 17:59:45 volumio volumio[931]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0Ie5uiv54KgCr7P4sYDTHl","play_origin":"go-librespot"}} Dec 01 17:59:45 volumio go-librespot[5497]: time="2024-12-01T17:59:45+02:00" level=debug msg="selected format OGG_VORBIS_320 (fafad4b753b9542e8e6be1f048e39c8eb0ad8507)" uri="spotify:track:0Ie5uiv54KgCr7P4sYDTHl" Dec 01 17:59:45 volumio go-librespot[5497]: time="2024-12-01T17:59:45+02:00" level=debug msg="requested aes key for file fafad4b753b9542e8e6be1f048e39c8eb0ad8507, gid: 0Ie5uiv54KgCr7P4sYDTHl" Dec 01 17:59:46 volumio go-librespot[5497]: time="2024-12-01T17:59:46+02:00" level=debug msg="fetched first chunk of 14, total size is 7210136 bytes" uri="spotify:track:0Ie5uiv54KgCr7P4sYDTHl" Dec 01 17:59:46 volumio go-librespot[5497]: time="2024-12-01T17:59:46+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:0Ie5uiv54KgCr7P4sYDTHl" Dec 01 17:59:46 volumio go-librespot[5497]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Dec 01 17:59:46 volumio go-librespot[5497]: time="2024-12-01T17:59:46+02:00" level=info msg="loaded track \"Baby It's Cold Outside (with Michael Bublé)\" (paused: false, position: 0ms, duration: 166146ms, prefetched: false)" uri="spotify:track:0Ie5uiv54KgCr7P4sYDTHl" Dec 01 17:59:46 volumio go-librespot[5497]: time="2024-12-01T17:59:46+02:00" level=debug msg="fetched chunk 1/13, size: 524288" uri="spotify:track:0Ie5uiv54KgCr7P4sYDTHl" Dec 01 17:59:46 volumio go-librespot[5497]: time="2024-12-01T17:59:46+02:00" level=debug msg="fetched chunk 3/13, size: 524288" uri="spotify:track:0Ie5uiv54KgCr7P4sYDTHl" Dec 01 17:59:46 volumio go-librespot[5497]: time="2024-12-01T17:59:46+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 17:59:46 volumio go-librespot[5497]: time="2024-12-01T17:59:46+02:00" level=trace msg="scheduling prefetch in 136s" Dec 01 17:59:46 volumio go-librespot[5497]: time="2024-12-01T17:59:46+02:00" level=trace msg="emitting websocket event: metadata" Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0Ie5uiv54KgCr7P4sYDTHl","name":"Baby It's Cold Outside (with Michael Bublé)","artist_names":["Idina Menzel","Michael Bublé"],"album_name":"Holiday Wishes","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02b942e9ff43d692b700328ecc","position":0,"duration":166146,"release_date":"year:2014 month:10 day:10","track_number":3,"disc_number":1}} Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0Ie5uiv54KgCr7P4sYDTHl","name":"Baby It's Cold Outside (with Michael Bublé)","artist_names":["Idina Menzel","Michael Bublé"],"album_name":"Holiday Wishes","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02b942e9ff43d692b700328ecc","position":0,"duration":166146,"release_date":"year:2014 month:10 day:10","track_number":3,"disc_number":1}} Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0Ie5uiv54KgCr7P4sYDTHl","name":"Baby It's Cold Outside (with Michael Bublé)","artist_names":["Idina Menzel","Michael Bublé"],"album_name":"Holiday Wishes","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02b942e9ff43d692b700328ecc","position":0,"duration":166146,"release_date":"year:2014 month:10 day:10","track_number":3,"disc_number":1}} Dec 01 17:59:46 volumio go-librespot[5497]: time="2024-12-01T17:59:46+02:00" level=debug msg="fetched chunk 2/13, size: 524288" uri="spotify:track:0Ie5uiv54KgCr7P4sYDTHl" Dec 01 17:59:46 volumio go-librespot[5497]: time="2024-12-01T17:59:46+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 17:59:46 volumio go-librespot[5497]: time="2024-12-01T17:59:46+02:00" level=trace msg="emitting websocket event: playing" Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0Ie5uiv54KgCr7P4sYDTHl","play_origin":"go-librespot"}} Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: {"status":"play","service":"spop","title":"Baby It's Cold Outside (with Michael Bublé)","artist":"Idina Menzel, Michael Bublé","album":"Holiday Wishes","albumart":"https://i.scdn.co/image/ab67616d00001e02b942e9ff43d692b700328ecc","uri":"spotify:track:0Ie5uiv54KgCr7P4sYDTHl","trackType":"spotify","seek":0,"duration":166,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:46 volumio volumio[931]: info: CoreCommandRouter::servicePushState Dec 01 17:59:46 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:46 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Baby It's Cold Outside (with Michael Bublé)","artist":"Idina Menzel, Michael Bublé","album":"Holiday Wishes","albumart":"https://i.scdn.co/image/ab67616d00001e02b942e9ff43d692b700328ecc","uri":"spotify:track:0Ie5uiv54KgCr7P4sYDTHl","trackType":"spotify","seek":0,"duration":166,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:46 volumio volumio[931]: verbose: CURRENT POSITION 52 Dec 01 17:59:46 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Dec 01 17:59:46 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus stop Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0Ie5uiv54KgCr7P4sYDTHl","play_origin":"go-librespot"}} Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: {"status":"play","service":"spop","title":"Baby It's Cold Outside (with Michael Bublé)","artist":"Idina Menzel, Michael Bublé","album":"Holiday Wishes","albumart":"https://i.scdn.co/image/ab67616d00001e02b942e9ff43d692b700328ecc","uri":"spotify:track:0Ie5uiv54KgCr7P4sYDTHl","trackType":"spotify","seek":0,"duration":166,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:46 volumio volumio[931]: info: CoreCommandRouter::servicePushState Dec 01 17:59:46 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:46 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Baby It's Cold Outside (with Michael Bublé)","artist":"Idina Menzel, Michael Bublé","album":"Holiday Wishes","albumart":"https://i.scdn.co/image/ab67616d00001e02b942e9ff43d692b700328ecc","uri":"spotify:track:0Ie5uiv54KgCr7P4sYDTHl","trackType":"spotify","seek":0,"duration":166,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:46 volumio volumio[931]: verbose: CURRENT POSITION 52 Dec 01 17:59:46 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Dec 01 17:59:46 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Dec 01 17:59:46 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Dec 01 17:59:46 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:46 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:46 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 17:59:46 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:46 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:46 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:46 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:46 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:46 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:46 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:46 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0Ie5uiv54KgCr7P4sYDTHl","play_origin":"go-librespot"}} Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: {"status":"play","service":"spop","title":"Baby It's Cold Outside (with Michael Bublé)","artist":"Idina Menzel, Michael Bublé","album":"Holiday Wishes","albumart":"https://i.scdn.co/image/ab67616d00001e02b942e9ff43d692b700328ecc","uri":"spotify:track:0Ie5uiv54KgCr7P4sYDTHl","trackType":"spotify","seek":0,"duration":166,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:46 volumio volumio[931]: info: CoreCommandRouter::servicePushState Dec 01 17:59:46 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:46 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Baby It's Cold Outside (with Michael Bublé)","artist":"Idina Menzel, Michael Bublé","album":"Holiday Wishes","albumart":"https://i.scdn.co/image/ab67616d00001e02b942e9ff43d692b700328ecc","uri":"spotify:track:0Ie5uiv54KgCr7P4sYDTHl","trackType":"spotify","seek":0,"duration":166,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:46 volumio volumio[931]: verbose: CURRENT POSITION 52 Dec 01 17:59:46 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Dec 01 17:59:46 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Dec 01 17:59:46 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Dec 01 17:59:46 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:46 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:46 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:46 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:46 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:46 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:46 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:46 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:46 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:46 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: {"status":"play","service":"spop","title":"Baby It's Cold Outside (with Michael Bublé)","artist":"Idina Menzel, Michael Bublé","album":"Holiday Wishes","albumart":"https://i.scdn.co/image/ab67616d00001e02b942e9ff43d692b700328ecc","uri":"spotify:track:0Ie5uiv54KgCr7P4sYDTHl","trackType":"spotify","seek":0,"duration":166,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:46 volumio volumio[931]: info: CoreCommandRouter::servicePushState Dec 01 17:59:46 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:46 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Baby It's Cold Outside (with Michael Bublé)","artist":"Idina Menzel, Michael Bublé","album":"Holiday Wishes","albumart":"https://i.scdn.co/image/ab67616d00001e02b942e9ff43d692b700328ecc","uri":"spotify:track:0Ie5uiv54KgCr7P4sYDTHl","trackType":"spotify","seek":0,"duration":166,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:46 volumio volumio[931]: verbose: CURRENT POSITION 52 Dec 01 17:59:46 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Dec 01 17:59:46 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Dec 01 17:59:46 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Dec 01 17:59:46 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:46 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:46 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:46 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:46 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:46 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:46 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:46 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:46 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:46 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: {"status":"play","service":"spop","title":"Baby It's Cold Outside (with Michael Bublé)","artist":"Idina Menzel, Michael Bublé","album":"Holiday Wishes","albumart":"https://i.scdn.co/image/ab67616d00001e02b942e9ff43d692b700328ecc","uri":"spotify:track:0Ie5uiv54KgCr7P4sYDTHl","trackType":"spotify","seek":0,"duration":166,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:46 volumio volumio[931]: info: CoreCommandRouter::servicePushState Dec 01 17:59:46 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:46 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Baby It's Cold Outside (with Michael Bublé)","artist":"Idina Menzel, Michael Bublé","album":"Holiday Wishes","albumart":"https://i.scdn.co/image/ab67616d00001e02b942e9ff43d692b700328ecc","uri":"spotify:track:0Ie5uiv54KgCr7P4sYDTHl","trackType":"spotify","seek":0,"duration":166,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:46 volumio volumio[931]: verbose: CURRENT POSITION 52 Dec 01 17:59:46 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Dec 01 17:59:46 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Dec 01 17:59:46 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Dec 01 17:59:46 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:46 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:46 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:46 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:46 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:46 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:46 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:46 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:46 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:46 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: {"status":"play","service":"spop","title":"Baby It's Cold Outside (with Michael Bublé)","artist":"Idina Menzel, Michael Bublé","album":"Holiday Wishes","albumart":"https://i.scdn.co/image/ab67616d00001e02b942e9ff43d692b700328ecc","uri":"spotify:track:0Ie5uiv54KgCr7P4sYDTHl","trackType":"spotify","seek":0,"duration":166,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:46 volumio volumio[931]: info: CoreCommandRouter::servicePushState Dec 01 17:59:46 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:46 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Baby It's Cold Outside (with Michael Bublé)","artist":"Idina Menzel, Michael Bublé","album":"Holiday Wishes","albumart":"https://i.scdn.co/image/ab67616d00001e02b942e9ff43d692b700328ecc","uri":"spotify:track:0Ie5uiv54KgCr7P4sYDTHl","trackType":"spotify","seek":0,"duration":166,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 17:59:46 volumio volumio[931]: verbose: CURRENT POSITION 52 Dec 01 17:59:46 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Dec 01 17:59:46 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Dec 01 17:59:46 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Dec 01 17:59:46 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:46 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:46 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:46 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:46 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:46 volumio volumio[931]: info: CoreStateMachine::pushState Dec 01 17:59:46 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:46 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Dec 01 17:59:46 volumio volumio[931]: info: MRS: Pushing multiroomSync output update for this device Dec 01 17:59:46 volumio volumio[931]: info: MRS: Pushing multiroomSync output Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:46 volumio volumio[931]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 01 17:59:48 volumio volumio[931]: info: CoreCommandRouter::volumioGetState Dec 01 17:59:48 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:49 volumio volumio[931]: info: + geo-tz@8.1.2 Dec 01 17:59:49 volumio volumio[931]: info: added 29 packages from 33 contributors and audited 353 packages in 26.467s Dec 01 17:59:49 volumio volumio[931]: info: Dec 01 17:59:49 volumio volumio[931]: info: 21 packages are looking for funding Dec 01 17:59:49 volumio volumio[931]: info: run `npm fund` for details Dec 01 17:59:49 volumio volumio[931]: info: Dec 01 17:59:49 volumio volumio[931]: info: found 11 vulnerabilities (5 low, 3 moderate, 3 high) Dec 01 17:59:49 volumio volumio[931]: info: run `npm audit fix` to fix them, or `npm audit` for details Dec 01 17:59:49 volumio volumio[931]: info: Creating user directories (if not exist) Dec 01 17:59:49 volumio volumio[931]: info: Now Playing plugin installed Dec 01 17:59:49 volumio sudo[25056]: pam_unix(sudo:session): session closed for user root Dec 01 17:59:49 volumio volumio[931]: Plugin install end detected on script Dec 01 17:59:49 volumio volumio[931]: info: Install script completed Dec 01 17:59:49 volumio volumio[931]: info: Adding reference to registry Dec 01 17:59:49 volumio volumio[931]: info: Done installing plugin. Dec 01 17:59:49 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 01 17:59:49 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 01 17:59:49 volumio volumio[931]: info: Folder /tmp/plugins removed Dec 01 17:59:49 volumio volumio[931]: info: Folder /tmp/downloaded_plugin.zip removed Dec 01 17:59:49 volumio volumio[931]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 17:59:49 volumio volumio[931]: info: Folder /data/temp removed Dec 01 17:59:52 volumio volumio[931]: info: Enabling plugin now_playing Dec 01 17:59:52 volumio volumio[931]: info: Loading plugin "now_playing"... Dec 01 17:59:52 volumio volumio[931]: error: !!!! WARNING !!!! Dec 01 17:59:52 volumio volumio[931]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error [ERR_REQUIRE_ESM]: Must use import to load ES Module: /data/plugins/user_interface/now_playing/node_modules/point-in-polygon-hao/dist/pointInPolygon.mjs Dec 01 17:59:52 volumio volumio[931]: error: Stack trace: Error [ERR_REQUIRE_ESM]: Must use import to load ES Module: /data/plugins/user_interface/now_playing/node_modules/point-in-polygon-hao/dist/pointInPolygon.mjs Dec 01 17:59:52 volumio volumio[931]: at Module.load (internal/modules/cjs/loader.js:926:11) Dec 01 17:59:52 volumio volumio[931]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Dec 01 17:59:52 volumio volumio[931]: at Module.require (internal/modules/cjs/loader.js:952:19) Dec 01 17:59:52 volumio volumio[931]: at require (internal/modules/cjs/helpers.js:88:18) Dec 01 17:59:52 volumio volumio[931]: at Object. (/data/plugins/user_interface/now_playing/node_modules/@turf/boolean-point-in-polygon/dist/cjs/index.cjs:2:26) Dec 01 17:59:52 volumio volumio[931]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Dec 01 17:59:52 volumio volumio[931]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Dec 01 17:59:52 volumio volumio[931]: at Module.load (internal/modules/cjs/loader.js:928:32) Dec 01 17:59:52 volumio volumio[931]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Dec 01 17:59:52 volumio volumio[931]: at Module.require (internal/modules/cjs/loader.js:952:19) Dec 01 17:59:52 volumio volumio[931]: at require (internal/modules/cjs/helpers.js:88:18) Dec 01 17:59:52 volumio volumio[931]: at Object. (/data/plugins/user_interface/now_playing/node_modules/geo-tz/dist/find-1970.js:1:61) Dec 01 17:59:52 volumio volumio[931]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Dec 01 17:59:52 volumio volumio[931]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Dec 01 17:59:52 volumio volumio[931]: at Module.load (internal/modules/cjs/loader.js:928:32) Dec 01 17:59:52 volumio volumio[931]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Dec 01 17:59:52 volumio volumio[931]: error: !!!! WARNING !!!! Dec 01 17:59:52 volumio volumio[931]: info: Done. Dec 01 17:59:52 volumio volumio[931]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 17:59:57 volumio volumio[931]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 17:59:58 volumio volumio[931]: info: CoreCommandRouter::volumioGetState Dec 01 17:59:58 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 17:59:58 volumio volumio[931]: info: Listing playlists Dec 01 17:59:58 volumio volumio[931]: info: Listing playlists Dec 01 18:00:02 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 01 18:00:02 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 01 18:00:07 volumio volumio[931]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 18:00:08 volumio volumio[931]: info: CoreCommandRouter::volumioGetState Dec 01 18:00:08 volumio volumio[931]: info: CorePlayQueue::getTrack 52 Dec 01 18:00:16 volumio volumio[931]: info: CALLMETHOD: audio_interface fusiondsp choosedsp [object Object] Dec 01 18:00:16 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , choosedsp Dec 01 18:00:16 volumio volumio[931]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 01 18:00:16 volumio volumio[931]: info: FusionDsp - Nulleq applied Dec 01 18:00:16 volumio volumio[931]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 18:00:16 volumio volumio[931]: info: CoreCommandRouter::Close All Modals sent Dec 01 18:00:16 volumio volumio[931]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 18:00:16 volumio volumio[931]: TypeError: Cannot read property 'broadcastMessage' of null Dec 01 18:00:16 volumio volumio[931]: at /volumio/app/index.js:1529:32 Dec 01 18:00:16 volumio volumio[931]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Dec 01 18:00:16 volumio volumio[931]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Dec 01 18:00:16 volumio volumio[931]: at CoreCommandRouter.broadcastMessage (/volumio/app/index.js:1527:13) Dec 01 18:00:16 volumio volumio[931]: at CoreCommandRouter.closeModals (/volumio/app/index.js:1949:15) Dec 01 18:00:16 volumio volumio[931]: at Timeout._onTimeout (/data/plugins/audio_interface/fusiondsp/index.js:1822:24) Dec 01 18:00:16 volumio volumio[931]: at listOnTimeout (internal/timers.js:554:17) Dec 01 18:00:16 volumio volumio[931]: at processTimers (internal/timers.js:497:7) Dec 01 18:00:16 volumio volumio[931]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 18:00:16 volumio sudo[28579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-01 17:59 Dec 01 18:00:16 volumio sudo[28579]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"