Jul 08 20:02:00 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:00 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:00 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:00 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:00 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:00 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:01 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 08 20:02:01 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:01 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:01 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:01 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:02 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:02 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:02 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:02 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:03 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:03 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:03 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:03 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:03 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:03 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:04 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:04 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:04 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 20:02:04 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 08 20:02:04 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 08 20:02:04 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 08 20:02:04 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jul 08 20:02:04 rpi5-ws840 volumio[1359]: info: Received Get System Version Jul 08 20:02:04 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 08 20:02:04 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:04 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:05 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:05 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:05 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:05 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:05 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:05 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:06 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:06 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:06 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:06 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:07 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:07 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:07 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:07 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:07 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:07 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:08 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:08 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:08 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:08 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:09 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:09 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:09 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 08 20:02:09 rpi5-ws840 volumio[1359]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 08 20:02:09 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 08 20:02:09 rpi5-ws840 volumio[1359]: info: Received Get System Version Jul 08 20:02:09 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 08 20:02:09 rpi5-ws840 volumio[1359]: info: Received Get System Info Jul 08 20:02:09 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 20:02:09 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 20:02:09 rpi5-ws840 volumio[1359]: info: Discovery: Getting this device information Jul 08 20:02:09 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:09 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:09 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 20:02:09 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:09 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:09 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:09 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:10 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:10 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:10 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:10 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:11 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:11 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:11 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:11 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:11 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:11 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:11 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:11 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:12 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:12 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:12 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:12 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:13 rpi5-ws840 volumio[1359]: info: Executing endpoint metavolumio Jul 08 20:02:13 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 08 20:02:13 rpi5-ws840 volumio[1359]: info: Executing endpoint metavolumio Jul 08 20:02:13 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 08 20:02:13 rpi5-ws840 volumio[1359]: info: Executing endpoint metavolumio Jul 08 20:02:13 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 08 20:02:13 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:13 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:13 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 20:02:13 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 20:02:13 rpi5-ws840 volumio[1359]: info: Discovery: Getting this device information Jul 08 20:02:13 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:13 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:13 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 20:02:13 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 08 20:02:13 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 08 20:02:13 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:13 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:14 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:14 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:14 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:14 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:14 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:14 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:15 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:15 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:15 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:15 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:16 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:16 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:16 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:16 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:16 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:16 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:17 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:17 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:17 rpi5-ws840 go-librespot[2473]: time="2025-07-08T20:02:17+02:00" level=trace msg="sent dealer ping" Jul 08 20:02:17 rpi5-ws840 go-librespot[2473]: time="2025-07-08T20:02:17+02:00" level=trace msg="received dealer pong" Jul 08 20:02:17 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:17 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:17 rpi5-ws840 systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 08 20:02:18 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:18 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:18 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:18 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:18 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:18 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:19 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:19 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:19 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:19 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:20 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:20 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:20 rpi5-ws840 volumio[1359]: verbose: New Socket.io Connection to 192.168.1.215:3000 from 192.168.1.171 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 13 Jul 08 20:02:20 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:20 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:20 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:20 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:21 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:21 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:21 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:21 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:22 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:22 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:22 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:22 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:22 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:22 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:23 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:23 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:23 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:23 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:24 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:24 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:24 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:24 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:25 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:25 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:25 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:25 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:25 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:25 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:26 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:26 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:26 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:26 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:27 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:27 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:27 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:27 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:27 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:27 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:28 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:28 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:28 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 08 20:02:28 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:28 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:29 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:29 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:29 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:29 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:29 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:29 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:30 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:30 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:30 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:30 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:31 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:31 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:31 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:31 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:31 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:31 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:32 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:32 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:32 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:32 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:33 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:33 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:33 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:33 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:33 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:33 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:34 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:34 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:34 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:34 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:35 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:35 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:35 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:35 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:36 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:36 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:36 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:36 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:36 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:36 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:37 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:37 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:37 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:37 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:38 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:38 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:38 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:38 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:38 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:38 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:39 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:39 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:39 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:39 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:40 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:40 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:40 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:40 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:40 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:40 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:41 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:41 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:41 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:41 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:42 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:42 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:42 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:42 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:42 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:42 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:43 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 20:02:43 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:43 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:43 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:43 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:44 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:44 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:44 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:44 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:45 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:45 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:45 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:45 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:45 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:45 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:45 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:45 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:46 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:46 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:46 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:46 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:47 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:47 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:47 rpi5-ws840 go-librespot[2473]: time="2025-07-08T20:02:47+02:00" level=trace msg="received accesspoint ping" Jul 08 20:02:47 rpi5-ws840 go-librespot[2473]: time="2025-07-08T20:02:47+02:00" level=trace msg="received accesspoint pong ack" Jul 08 20:02:47 rpi5-ws840 go-librespot[2473]: time="2025-07-08T20:02:47+02:00" level=trace msg="sent dealer ping" Jul 08 20:02:47 rpi5-ws840 go-librespot[2473]: time="2025-07-08T20:02:47+02:00" level=trace msg="received dealer pong" Jul 08 20:02:47 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:47 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:47 rpi5-ws840 volumio[1359]: info: Executing endpoint metavolumio Jul 08 20:02:47 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 08 20:02:47 rpi5-ws840 volumio[1359]: info: Executing endpoint metavolumio Jul 08 20:02:47 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 08 20:02:47 rpi5-ws840 volumio[1359]: info: Executing endpoint metavolumio Jul 08 20:02:47 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 08 20:02:47 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:47 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:48 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:48 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:48 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:48 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:48 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jul 08 20:02:48 rpi5-ws840 volumio[1359]: info: In handleBrowseUri, curUri=spotify Jul 08 20:02:48 rpi5-ws840 volumio[1359]: info: Preload queue cleared Jul 08 20:02:48 rpi5-ws840 volumio[1359]: info: Preload queue cleared Jul 08 20:02:48 rpi5-ws840 volumio[1359]: info: Preload queue cleared Jul 08 20:02:48 rpi5-ws840 volumio[1359]: info: Preload queue cleared Jul 08 20:02:49 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:49 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:49 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:49 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:49 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:49 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:50 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:50 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:50 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jul 08 20:02:50 rpi5-ws840 volumio[1359]: info: In handleBrowseUri, curUri=spotify:playlist:37i9dQZF1DWSBi5svWQ9Nk Jul 08 20:02:50 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:50 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preload queue cleared Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:46Zi8b9UbvjwNpwngsyckC Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:0QAUgW2vTmlUWhbiNeSkWm Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:7ps3LdQn8lKdaa2sbIKFTR Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:1ufm3zszexVlsNpayLSsiA Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:3s4WNh0WnfhwvIlrp6RTBj Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:0QCIpQV3twfqo9kh0t8Zza Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:2MPWNU7QkwmBNAoHdYLYfW Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:78nx0HDJIFD5xDq2L5420Z Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:3JzuluW7MzrmMkNpfZwtyp Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:59zH1gO9Ky2xPUEdrUrgcC Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:6EkLMgBYvDlRnj2UOtG622 Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:7ubZ97TycJ5fWKKgbFQlue Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:2lCkncy6bIB0LTMT7kvrD1 Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:2LddWQtqjD4ORObbXr9pKX Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:6WatFBLVB0x077xWeoVc2k Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:1lzzSzBejW6m8UN2PdRsDv Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:6GOOcBV0wLoHrH5D9AWA32 Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:0fROjdyTv6S6E65qpQfwHa Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:2zqqu4TbRW3yOI5wqdrgDL Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:5ITV0zqzjOYfFWpW0xBmRa Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:29OyY2QfQnTP9KgdAmNviU Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:72RjC359lcwhf1cON9ppls Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:05mRvdy1w3YtLhz2sHYzrB Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:7yNQ5oPcuCC4wTqTcMRIrE Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:7tW2Md8xOwnKPIYhCqPiU4 Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:5lXLzaZoj4j4hfCYO687bN Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:6vM1GSndPtQk7AmqEmNAPH Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:2FAZskT9yRjp2Oow9szJD8 Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:4IunKjJY3tOuERmzYufNcL Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:0GRc3eGTg8HBdWLRGYgqIc Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:30k0WUGuf3QCotkXbxnrOx Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:03LXMI84EALrqIKgh5Zl6w Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:4NZ15Gn3KJrmflE8jwiMBU Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:4TwEdnSiTPDR1vg1QZ5K8W Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:7tqg41RdA6MLUrUbcNrFoJ Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:04emojnbYkrRmv5qtJcgVP Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:6zdmJ79GtRfpJJdaDPECYm Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:6mkkFCI8p2eR7D5FOjfmzm Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:6xOEgzkMSZJKz6qtCJsQL5 Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:0IXL5SPeI849hcz7Cynpk4 Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:2ipIPsgrgd0j2beDf4Ki70 Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:42UBPzRMh5yyz0EDPr6fr1 Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:5d0KtdnoPpniNFngV5ehcP Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:0qmhTHMVxnXRmT5N92wTD9 Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:0GYx8Q9iS05CRe9kyzwAIL Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:1UNEuG9DYOWiikf00ayr52 Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:3f3ujFD6YndqRRVYuBcq7T Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Preloading song: spotify:track:2yWlGEgEfPot0lv3OAjuG3 Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:46Zi8b9UbvjwNpwngsyckC in service spop Jul 08 20:02:51 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:46Zi8b9UbvjwNpwngsyckC Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:0QAUgW2vTmlUWhbiNeSkWm in service spop Jul 08 20:02:51 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:0QAUgW2vTmlUWhbiNeSkWm Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:7ps3LdQn8lKdaa2sbIKFTR in service spop Jul 08 20:02:51 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:7ps3LdQn8lKdaa2sbIKFTR Jul 08 20:02:51 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:46Zi8b9UbvjwNpwngsyckC","service":"spop","name":"LIES","artist":"Lil Kleine","album":"LIES","type":"song","duration":199,"albumart":"https://i.scdn.co/image/ab67616d0000b27347e5fc33cbcd23035c2be024","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:51 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:1ufm3zszexVlsNpayLSsiA in service spop Jul 08 20:02:51 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:1ufm3zszexVlsNpayLSsiA Jul 08 20:02:51 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0QAUgW2vTmlUWhbiNeSkWm","service":"spop","name":"Vaitimbora","artist":"Mariana Froes","album":"Vaitimbora","type":"song","duration":135,"albumart":"https://i.scdn.co/image/ab67616d0000b273160776da9ff57876b8352350","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:3s4WNh0WnfhwvIlrp6RTBj in service spop Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:3s4WNh0WnfhwvIlrp6RTBj Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:0QCIpQV3twfqo9kh0t8Zza in service spop Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:0QCIpQV3twfqo9kh0t8Zza Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7ps3LdQn8lKdaa2sbIKFTR","service":"spop","name":"Lotje - Lil Kleine Remix","artist":"Roeland Beelen","album":"Lotje (Lil Kleine Remix)","type":"song","duration":146,"albumart":"https://i.scdn.co/image/ab67616d0000b2730dd461395a02eb3a8714efe2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1ufm3zszexVlsNpayLSsiA","service":"spop","name":"Alles Kan Kapot","artist":"Samuel Welten","album":"Alles Kan Kapot","type":"song","duration":172,"albumart":"https://i.scdn.co/image/ab67616d0000b273880d69ad44d4d0a7638365f3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:2MPWNU7QkwmBNAoHdYLYfW in service spop Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:2MPWNU7QkwmBNAoHdYLYfW Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:78nx0HDJIFD5xDq2L5420Z in service spop Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:78nx0HDJIFD5xDq2L5420Z Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3s4WNh0WnfhwvIlrp6RTBj","service":"spop","name":"ZAAZAA","artist":"Frenna","album":"ZAAZAA","type":"song","duration":156,"albumart":"https://i.scdn.co/image/ab67616d0000b273b42e403365ae43b13e89b215","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0QCIpQV3twfqo9kh0t8Zza","service":"spop","name":"Shake It To The Max (FLY) - Remix","artist":"MOLIY","album":"Shake It To The Max (FLY) (Remix)","type":"song","duration":178,"albumart":"https://i.scdn.co/image/ab67616d0000b2730069ee4e37f913fb1653b389","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:3JzuluW7MzrmMkNpfZwtyp in service spop Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:3JzuluW7MzrmMkNpfZwtyp Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2MPWNU7QkwmBNAoHdYLYfW","service":"spop","name":"1+1 (Outside)","artist":"Dior","album":"1+1 (Outside)","type":"song","duration":158,"albumart":"https://i.scdn.co/image/ab67616d0000b2736919ff47eff2aa8a3d50ebc5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:59zH1gO9Ky2xPUEdrUrgcC in service spop Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:59zH1gO9Ky2xPUEdrUrgcC Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:78nx0HDJIFD5xDq2L5420Z","service":"spop","name":"Blessings","artist":"Calvin Harris","album":"Blessings","type":"song","duration":219,"albumart":"https://i.scdn.co/image/ab67616d0000b2734ba976bec2d9619b6a88aad2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:6EkLMgBYvDlRnj2UOtG622 in service spop Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:6EkLMgBYvDlRnj2UOtG622 Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3JzuluW7MzrmMkNpfZwtyp","service":"spop","name":"Ze Komt Uit Amsterdam","artist":"Kris Kross Amsterdam","album":"Ze Komt Uit Amsterdam","type":"song","duration":152,"albumart":"https://i.scdn.co/image/ab67616d0000b273c0aa4b82cb83d42e55f69c43","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:7ubZ97TycJ5fWKKgbFQlue in service spop Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:7ubZ97TycJ5fWKKgbFQlue Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:59zH1gO9Ky2xPUEdrUrgcC","service":"spop","name":"Borderline","artist":"Ely Oaks","album":"Borderline","type":"song","duration":118,"albumart":"https://i.scdn.co/image/ab67616d0000b27380dfed5ad6ec586d705f8daf","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:2lCkncy6bIB0LTMT7kvrD1 in service spop Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:2lCkncy6bIB0LTMT7kvrD1 Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6EkLMgBYvDlRnj2UOtG622","service":"spop","name":"Met Of Zonder Jou","artist":"Tino Martin","album":"Met Of Zonder Jou","type":"song","duration":182,"albumart":"https://i.scdn.co/image/ab67616d0000b27399e79fe12519ed492e614aa7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:2LddWQtqjD4ORObbXr9pKX in service spop Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:2LddWQtqjD4ORObbXr9pKX Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:6WatFBLVB0x077xWeoVc2k in service spop Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:6WatFBLVB0x077xWeoVc2k Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7ubZ97TycJ5fWKKgbFQlue","service":"spop","name":"Beneden","artist":"Bizzey","album":"Beneden","type":"song","duration":135,"albumart":"https://i.scdn.co/image/ab67616d0000b273728e5f5aeec374e6e627370d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2lCkncy6bIB0LTMT7kvrD1","service":"spop","name":"Azul","artist":"J Balvin","album":"Colores","type":"song","duration":205,"albumart":"https://i.scdn.co/image/ab67616d0000b27335692baca45542527d742d0d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:1lzzSzBejW6m8UN2PdRsDv in service spop Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:1lzzSzBejW6m8UN2PdRsDv Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:6GOOcBV0wLoHrH5D9AWA32 in service spop Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:6GOOcBV0wLoHrH5D9AWA32 Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6WatFBLVB0x077xWeoVc2k","service":"spop","name":"Si Antes Te Hubiera Conocido","artist":"KAROL G","album":"Si Antes Te Hubiera Conocido","type":"song","duration":195,"albumart":"https://i.scdn.co/image/ab67616d0000b273491678beaffcefac517a699e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:0fROjdyTv6S6E65qpQfwHa in service spop Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:0fROjdyTv6S6E65qpQfwHa Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1lzzSzBejW6m8UN2PdRsDv","service":"spop","name":"100%","artist":"FLEMMING","album":"100%","type":"song","duration":151,"albumart":"https://i.scdn.co/image/ab67616d0000b2735b4d7e6c29de184860fe0468","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2LddWQtqjD4ORObbXr9pKX","service":"spop","name":"Matcha Coco","artist":"Noano","album":"Matcha Coco","type":"song","duration":177,"albumart":"https://i.scdn.co/image/ab67616d0000b2737a2451d4ff8336936f66b865","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:4Q0qVhFQa7j6jRKzo3HDmP in service spop Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6GOOcBV0wLoHrH5D9AWA32","service":"spop","name":"Say My Name - Remix","artist":"Morgan Seatree","album":"Say My Name (Remix)","type":"song","duration":168,"albumart":"https://i.scdn.co/image/ab67616d0000b27384673b6094ab58850c189e5d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:2zqqu4TbRW3yOI5wqdrgDL in service spop Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:2zqqu4TbRW3yOI5wqdrgDL Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0fROjdyTv6S6E65qpQfwHa","service":"spop","name":"Ik Zing (feat. Snelle)","artist":"Zoë Livay","album":"Ik Zing (feat. Snelle)","type":"song","duration":174,"albumart":"https://i.scdn.co/image/ab67616d0000b273a01c7e76d709d27f22a30d6a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:5ITV0zqzjOYfFWpW0xBmRa in service spop Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:5ITV0zqzjOYfFWpW0xBmRa Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:29OyY2QfQnTP9KgdAmNviU in service spop Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:29OyY2QfQnTP9KgdAmNviU Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4Q0qVhFQa7j6jRKzo3HDmP","service":"spop","name":"Sapphire","artist":"Ed Sheeran","album":"Sapphire","type":"song","duration":179,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fbb60d6a7e03ccb940a518e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2zqqu4TbRW3yOI5wqdrgDL","service":"spop","name":"Beetje Van Mij","artist":"Antoon","album":"Beetje Van Mij","type":"song","duration":150,"albumart":"https://i.scdn.co/image/ab67616d0000b273dd294a43bfac4cdbe071985b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:72RjC359lcwhf1cON9ppls in service spop Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:72RjC359lcwhf1cON9ppls Jul 08 20:02:52 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:05mRvdy1w3YtLhz2sHYzrB in service spop Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:05mRvdy1w3YtLhz2sHYzrB Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5ITV0zqzjOYfFWpW0xBmRa","service":"spop","name":"Show Me Love","artist":"WizTheMc","album":"Show Me Love","type":"song","duration":176,"albumart":"https://i.scdn.co/image/ab67616d0000b2732b613d01851c007886c478f4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:52 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:29OyY2QfQnTP9KgdAmNviU","service":"spop","name":"Don Julio Daddy","artist":"Jonna Fraser","album":"Don Julio Daddy","type":"song","duration":176,"albumart":"https://i.scdn.co/image/ab67616d0000b2733d008ee275ff8cf9d4f35c61","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:7yNQ5oPcuCC4wTqTcMRIrE in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:7yNQ5oPcuCC4wTqTcMRIrE Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:72RjC359lcwhf1cON9ppls","service":"spop","name":"Tamo Bien","artist":"Enrique Iglesias","album":"Tamo Bien","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e25dc727b5fda6731ea49d2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:7tW2Md8xOwnKPIYhCqPiU4 in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:7tW2Md8xOwnKPIYhCqPiU4 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:05mRvdy1w3YtLhz2sHYzrB","service":"spop","name":"Mona Lisa","artist":"Robert van Hemert","album":"Niet Voor Lief","type":"song","duration":147,"albumart":"https://i.scdn.co/image/ab67616d0000b2739e39b1fe40c60722975e482b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:5lXLzaZoj4j4hfCYO687bN in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:5lXLzaZoj4j4hfCYO687bN Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7yNQ5oPcuCC4wTqTcMRIrE","service":"spop","name":"Running Around","artist":"Ely Oaks","album":"Running Around","type":"song","duration":124,"albumart":"https://i.scdn.co/image/ab67616d0000b2736e2c3a9218378061c1a1b2b0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:6vM1GSndPtQk7AmqEmNAPH in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:6vM1GSndPtQk7AmqEmNAPH Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:2FAZskT9yRjp2Oow9szJD8 in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:2FAZskT9yRjp2Oow9szJD8 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7tW2Md8xOwnKPIYhCqPiU4","service":"spop","name":"Echte Liefde Is Te Koop","artist":"Samuel Welten","album":"Echte Liefde Is Te Koop","type":"song","duration":152,"albumart":"https://i.scdn.co/image/ab67616d0000b273d58c5aba90745ffefc98705a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5lXLzaZoj4j4hfCYO687bN","service":"spop","name":"NINAO","artist":"GIMS","album":"NINAO","type":"song","duration":167,"albumart":"https://i.scdn.co/image/ab67616d0000b273ddfe430ec4417e80852f6142","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:4IunKjJY3tOuERmzYufNcL in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:4IunKjJY3tOuERmzYufNcL Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6vM1GSndPtQk7AmqEmNAPH","service":"spop","name":"MAD","artist":"Martin Garrix","album":"MAD","type":"song","duration":199,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c0e5abe73559c2de9d2eee0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:0GRc3eGTg8HBdWLRGYgqIc in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:0GRc3eGTg8HBdWLRGYgqIc Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2FAZskT9yRjp2Oow9szJD8","service":"spop","name":"The Days - NOTION Remix","artist":"Chrystal","album":"The Days (NOTION Remix)","type":"song","duration":233,"albumart":"https://i.scdn.co/image/ab67616d0000b2739041b9965925059507fb44af","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:30k0WUGuf3QCotkXbxnrOx in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:30k0WUGuf3QCotkXbxnrOx Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:03LXMI84EALrqIKgh5Zl6w in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:03LXMI84EALrqIKgh5Zl6w Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0GRc3eGTg8HBdWLRGYgqIc","service":"spop","name":"Azizam","artist":"Ed Sheeran","album":"Azizam","type":"song","duration":162,"albumart":"https://i.scdn.co/image/ab67616d0000b273d29a003b6e8d4c1c47bd4da4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:4NZ15Gn3KJrmflE8jwiMBU in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:4NZ15Gn3KJrmflE8jwiMBU Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4IunKjJY3tOuERmzYufNcL","service":"spop","name":"Hit me up (feat. Chavanté & Kleine John)","artist":"KATNUF","album":"Hit me up (feat. Chavanté & Kleine John)","type":"song","duration":152,"albumart":"https://i.scdn.co/image/ab67616d0000b2731b02a3b90ac1d6960dadb88e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:30k0WUGuf3QCotkXbxnrOx","service":"spop","name":"Hart In Brand","artist":"Froukje","album":"Hart In Brand","type":"song","duration":146,"albumart":"https://i.scdn.co/image/ab67616d0000b273f63ea3838b0d1c2632207c2b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:4TwEdnSiTPDR1vg1QZ5K8W in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:4TwEdnSiTPDR1vg1QZ5K8W Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:03LXMI84EALrqIKgh5Zl6w","service":"spop","name":"Never Forget You","artist":"AFROJACK","album":"Never Forget You (Amél Remix)","type":"song","duration":184,"albumart":"https://i.scdn.co/image/ab67616d0000b2733090f6974afc7f9d1e3cc5f5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:7tqg41RdA6MLUrUbcNrFoJ in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:7tqg41RdA6MLUrUbcNrFoJ Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:04emojnbYkrRmv5qtJcgVP in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:04emojnbYkrRmv5qtJcgVP Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4NZ15Gn3KJrmflE8jwiMBU","service":"spop","name":"Wereldwonder","artist":"LA$$A","album":"Wereldwonder","type":"song","duration":151,"albumart":"https://i.scdn.co/image/ab67616d0000b273eb54b2ce54f4d017f46d560f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4TwEdnSiTPDR1vg1QZ5K8W","service":"spop","name":"Beautiful People","artist":"David Guetta","album":"Beautiful People","type":"song","duration":187,"albumart":"https://i.scdn.co/image/ab67616d0000b2737091ac4e6518b4c612831312","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:6zdmJ79GtRfpJJdaDPECYm in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:6zdmJ79GtRfpJJdaDPECYm Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7tqg41RdA6MLUrUbcNrFoJ","service":"spop","name":"Lil Freakje","artist":"Kraantje Pappie","album":"Lil Freakje","type":"song","duration":214,"albumart":"https://i.scdn.co/image/ab67616d0000b273ff423a1593d5733479c3723c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:6mkkFCI8p2eR7D5FOjfmzm in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:6mkkFCI8p2eR7D5FOjfmzm Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:6xOEgzkMSZJKz6qtCJsQL5 in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:6xOEgzkMSZJKz6qtCJsQL5 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Preload queue cleared Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CoreStateMachine::ClearQueue Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CoreStateMachine::stop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CorePlayQueue::clearPlayQueue Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CorePlayQueue::saveQueue Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioPushQueue Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CoreStateMachine::addQueueItems Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CorePlayQueue::addQueueItems Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Preload queue cleared Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:46Zi8b9UbvjwNpwngsyckC Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:46Zi8b9UbvjwNpwngsyckC Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:0QAUgW2vTmlUWhbiNeSkWm Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:0QAUgW2vTmlUWhbiNeSkWm Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:7ps3LdQn8lKdaa2sbIKFTR Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:7ps3LdQn8lKdaa2sbIKFTR Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioPushQueue Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CorePlayQueue::saveQueue Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CoreStateMachine::updateTrackBlock Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrackBlock Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioPlay Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CoreStateMachine::play index 2 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CoreStateMachine::addQueueItems Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CorePlayQueue::addQueueItems Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Preload queue cleared Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:1ufm3zszexVlsNpayLSsiA Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:1ufm3zszexVlsNpayLSsiA Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:3s4WNh0WnfhwvIlrp6RTBj Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:3s4WNh0WnfhwvIlrp6RTBj Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:0QCIpQV3twfqo9kh0t8Zza Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:0QCIpQV3twfqo9kh0t8Zza Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:2MPWNU7QkwmBNAoHdYLYfW Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:2MPWNU7QkwmBNAoHdYLYfW Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:78nx0HDJIFD5xDq2L5420Z Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:78nx0HDJIFD5xDq2L5420Z Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:3JzuluW7MzrmMkNpfZwtyp Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:3JzuluW7MzrmMkNpfZwtyp Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:59zH1gO9Ky2xPUEdrUrgcC Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:59zH1gO9Ky2xPUEdrUrgcC Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:6EkLMgBYvDlRnj2UOtG622 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:6EkLMgBYvDlRnj2UOtG622 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:7ubZ97TycJ5fWKKgbFQlue Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:7ubZ97TycJ5fWKKgbFQlue Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:2lCkncy6bIB0LTMT7kvrD1 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:2lCkncy6bIB0LTMT7kvrD1 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:2LddWQtqjD4ORObbXr9pKX Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:2LddWQtqjD4ORObbXr9pKX Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:6WatFBLVB0x077xWeoVc2k Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:6WatFBLVB0x077xWeoVc2k Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:1lzzSzBejW6m8UN2PdRsDv Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:1lzzSzBejW6m8UN2PdRsDv Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:6GOOcBV0wLoHrH5D9AWA32 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:6GOOcBV0wLoHrH5D9AWA32 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:0fROjdyTv6S6E65qpQfwHa Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:0fROjdyTv6S6E65qpQfwHa Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:2zqqu4TbRW3yOI5wqdrgDL Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:2zqqu4TbRW3yOI5wqdrgDL Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:5ITV0zqzjOYfFWpW0xBmRa Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:5ITV0zqzjOYfFWpW0xBmRa Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:29OyY2QfQnTP9KgdAmNviU Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:29OyY2QfQnTP9KgdAmNviU Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:72RjC359lcwhf1cON9ppls Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:72RjC359lcwhf1cON9ppls Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:05mRvdy1w3YtLhz2sHYzrB Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:05mRvdy1w3YtLhz2sHYzrB Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:7yNQ5oPcuCC4wTqTcMRIrE Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:7yNQ5oPcuCC4wTqTcMRIrE Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:7tW2Md8xOwnKPIYhCqPiU4 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:7tW2Md8xOwnKPIYhCqPiU4 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:5lXLzaZoj4j4hfCYO687bN Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:5lXLzaZoj4j4hfCYO687bN Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:6vM1GSndPtQk7AmqEmNAPH Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:6vM1GSndPtQk7AmqEmNAPH Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:2FAZskT9yRjp2Oow9szJD8 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:2FAZskT9yRjp2Oow9szJD8 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:4IunKjJY3tOuERmzYufNcL Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:4IunKjJY3tOuERmzYufNcL Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:0GRc3eGTg8HBdWLRGYgqIc Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:0GRc3eGTg8HBdWLRGYgqIc Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:30k0WUGuf3QCotkXbxnrOx Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:30k0WUGuf3QCotkXbxnrOx Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:03LXMI84EALrqIKgh5Zl6w Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:03LXMI84EALrqIKgh5Zl6w Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:4NZ15Gn3KJrmflE8jwiMBU Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:4NZ15Gn3KJrmflE8jwiMBU Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:4TwEdnSiTPDR1vg1QZ5K8W Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:4TwEdnSiTPDR1vg1QZ5K8W Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:7tqg41RdA6MLUrUbcNrFoJ Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:7tqg41RdA6MLUrUbcNrFoJ Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:04emojnbYkrRmv5qtJcgVP Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:04emojnbYkrRmv5qtJcgVP in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:04emojnbYkrRmv5qtJcgVP Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:6zdmJ79GtRfpJJdaDPECYm Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:6zdmJ79GtRfpJJdaDPECYm in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:6zdmJ79GtRfpJJdaDPECYm Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:6mkkFCI8p2eR7D5FOjfmzm Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:6mkkFCI8p2eR7D5FOjfmzm in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:6mkkFCI8p2eR7D5FOjfmzm Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:6xOEgzkMSZJKz6qtCJsQL5 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:6xOEgzkMSZJKz6qtCJsQL5 in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:6xOEgzkMSZJKz6qtCJsQL5 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:6qqrTXSdwiJaq8SO0X2lSe in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:6qqrTXSdwiJaq8SO0X2lSe Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:0IXL5SPeI849hcz7Cynpk4 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:0IXL5SPeI849hcz7Cynpk4 in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:0IXL5SPeI849hcz7Cynpk4 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:2ipIPsgrgd0j2beDf4Ki70 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:2ipIPsgrgd0j2beDf4Ki70 in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:2ipIPsgrgd0j2beDf4Ki70 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:42UBPzRMh5yyz0EDPr6fr1 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:42UBPzRMh5yyz0EDPr6fr1 in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:42UBPzRMh5yyz0EDPr6fr1 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:5d0KtdnoPpniNFngV5ehcP Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:5d0KtdnoPpniNFngV5ehcP in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:5d0KtdnoPpniNFngV5ehcP Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:0qmhTHMVxnXRmT5N92wTD9 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:0qmhTHMVxnXRmT5N92wTD9 in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:0qmhTHMVxnXRmT5N92wTD9 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:0GYx8Q9iS05CRe9kyzwAIL Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:0GYx8Q9iS05CRe9kyzwAIL in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:0GYx8Q9iS05CRe9kyzwAIL Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:1UNEuG9DYOWiikf00ayr52 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:1UNEuG9DYOWiikf00ayr52 in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:1UNEuG9DYOWiikf00ayr52 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:3f3ujFD6YndqRRVYuBcq7T Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:3f3ujFD6YndqRRVYuBcq7T in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:3f3ujFD6YndqRRVYuBcq7T Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:2yWlGEgEfPot0lv3OAjuG3 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Exploding uri spotify:track:2yWlGEgEfPot0lv3OAjuG3 in service spop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: EXPLODING URI:spotify:track:2yWlGEgEfPot0lv3OAjuG3 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CoreStateMachine::stop Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CoreStateMachine::play index undefined Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CoreStateMachine::startPlaybackTimer Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetVisibleSources Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: [1751997773807] ControllerSpotify::clearAddPlayTrack Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: Sending Spotify command with payload to local API: /player/play Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:04emojnbYkrRmv5qtJcgVP","service":"spop","name":"What I Want (feat. Tate McRae)","artist":"Morgan Wallen","album":"I’m The Problem","type":"song","duration":184,"albumart":"https://i.scdn.co/image/ab67616d0000b27335ea219ce47813b5e2dc3745","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6zdmJ79GtRfpJJdaDPECYm","service":"spop","name":"Atlas","artist":"Pommelien Thijs","album":"Atlas","type":"song","duration":180,"albumart":"https://i.scdn.co/image/ab67616d0000b273e61632b7644a3ba3880f62b6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6mkkFCI8p2eR7D5FOjfmzm","service":"spop","name":"Waka","artist":"Noano","album":"Waka","type":"song","duration":160,"albumart":"https://i.scdn.co/image/ab67616d0000b273a63f635300681f9151955b66","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:53 rpi5-ws840 go-librespot[2473]: time="2025-07-08T20:02:53+02:00" level=debug msg="resolved context of track" uri="spotify:track:7ps3LdQn8lKdaa2sbIKFTR" Jul 08 20:02:53 rpi5-ws840 go-librespot[2473]: time="2025-07-08T20:02:53+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:7ps3LdQn8lKdaa2sbIKFTR" Jul 08 20:02:53 rpi5-ws840 go-librespot[2473]: time="2025-07-08T20:02:53+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:7ps3LdQn8lKdaa2sbIKFTR" Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6xOEgzkMSZJKz6qtCJsQL5","service":"spop","name":"La Plena - W Sound 05","artist":"W Sound","album":"La Plena (W Sound 05)","type":"song","duration":150,"albumart":"https://i.scdn.co/image/ab67616d0000b273d629a6041cde59d7ba67143c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:04emojnbYkrRmv5qtJcgVP","service":"spop","name":"What I Want (feat. Tate McRae)","artist":"Morgan Wallen","album":"I’m The Problem","type":"song","duration":184,"albumart":"https://i.scdn.co/image/ab67616d0000b27335ea219ce47813b5e2dc3745","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:53 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6mkkFCI8p2eR7D5FOjfmzm","service":"spop","name":"Waka","artist":"Noano","album":"Waka","type":"song","duration":160,"albumart":"https://i.scdn.co/image/ab67616d0000b273a63f635300681f9151955b66","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:53 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6zdmJ79GtRfpJJdaDPECYm","service":"spop","name":"Atlas","artist":"Pommelien Thijs","album":"Atlas","type":"song","duration":180,"albumart":"https://i.scdn.co/image/ab67616d0000b273e61632b7644a3ba3880f62b6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:54 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6xOEgzkMSZJKz6qtCJsQL5","service":"spop","name":"La Plena - W Sound 05","artist":"W Sound","album":"La Plena (W Sound 05)","type":"song","duration":150,"albumart":"https://i.scdn.co/image/ab67616d0000b273d629a6041cde59d7ba67143c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:54 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6qqrTXSdwiJaq8SO0X2lSe","service":"spop","name":"Ordinary","artist":"Alex Warren","album":"Ordinary","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b273fa2bcf3df669bde08b2e7d36","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:54 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0IXL5SPeI849hcz7Cynpk4","service":"spop","name":"COMO TU","artist":"DYSTINCT","album":"BABABA WORLD","type":"song","duration":154,"albumart":"https://i.scdn.co/image/ab67616d0000b2733d2c983272044ebaa23b8db6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:54 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:42UBPzRMh5yyz0EDPr6fr1","service":"spop","name":"Manchild","artist":"Sabrina Carpenter","album":"Manchild","type":"song","duration":213,"albumart":"https://i.scdn.co/image/ab67616d0000b273062c6573009fdebd43de443b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:54 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2ipIPsgrgd0j2beDf4Ki70","service":"spop","name":"Mystical Magical","artist":"Benson Boone","album":"American Heart","type":"song","duration":165,"albumart":"https://i.scdn.co/image/ab67616d0000b27311b6c087ac030b4e396e32c0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:54 rpi5-ws840 go-librespot[2473]: time="2025-07-08T20:02:54+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 08 20:02:54 rpi5-ws840 go-librespot[2473]: time="2025-07-08T20:02:54+02:00" level=trace msg="emitting websocket event: will_play" Jul 08 20:02:54 rpi5-ws840 volumio[1359]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7ps3LdQn8lKdaa2sbIKFTR","play_origin":"go-librespot"}} Jul 08 20:02:54 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5d0KtdnoPpniNFngV5ehcP","service":"spop","name":"Alles Wordt Beter","artist":"Emma Heesters","album":"Alles Wordt Beter","type":"song","duration":191,"albumart":"https://i.scdn.co/image/ab67616d0000b273a8f366dab6a2c1feec87c653","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:54 rpi5-ws840 go-librespot[2473]: time="2025-07-08T20:02:54+02:00" level=debug msg="selected format OGG_VORBIS_320 (f93ea8fe0fd5a559ece77e3607295dfc3e1a9ed6)" uri="spotify:track:7ps3LdQn8lKdaa2sbIKFTR" Jul 08 20:02:54 rpi5-ws840 go-librespot[2473]: time="2025-07-08T20:02:54+02:00" level=debug msg="requested aes key for file f93ea8fe0fd5a559ece77e3607295dfc3e1a9ed6, gid: 7ps3LdQn8lKdaa2sbIKFTR" Jul 08 20:02:54 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0qmhTHMVxnXRmT5N92wTD9","service":"spop","name":"Bloodline (with Jelly Roll)","artist":"Alex Warren","album":"Bloodline (with Jelly Roll)","type":"song","duration":182,"albumart":"https://i.scdn.co/image/ab67616d0000b273258f26eb6ce7851a80fcf3df","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:54 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0GYx8Q9iS05CRe9kyzwAIL","service":"spop","name":"Still Into You","artist":"CYRIL","album":"Still Into You","type":"song","duration":151,"albumart":"https://i.scdn.co/image/ab67616d0000b2735a326566f4ad2e2c107ed580","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:54 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2yWlGEgEfPot0lv3OAjuG3","service":"spop","name":"Just Keep Watching (From F1® The Movie)","artist":"Tate McRae","album":"Just Keep Watching (From F1® The Movie)","type":"song","duration":142,"albumart":"https://i.scdn.co/image/ab67616d0000b27396e4cfdd4c3aa8088685b262","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:54 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1UNEuG9DYOWiikf00ayr52","service":"spop","name":"Love Me Not","artist":"Ravyn Lenae","album":"Love Me Not / Love Is Blind","type":"song","duration":213,"albumart":"https://i.scdn.co/image/ab67616d0000b2735511ceaa955a2bfd9ae90c21","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:54 rpi5-ws840 volumio[1359]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3f3ujFD6YndqRRVYuBcq7T","service":"spop","name":"Blink Twice","artist":"Shaboozey","album":"Blink Twice","type":"song","duration":156,"albumart":"https://i.scdn.co/image/ab67616d0000b273d3b3026eafc7def669b0213a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 08 20:02:54 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioPushQueue Jul 08 20:02:54 rpi5-ws840 volumio[1359]: info: CorePlayQueue::saveQueue Jul 08 20:02:54 rpi5-ws840 volumio[1359]: info: CoreStateMachine::updateTrackBlock Jul 08 20:02:54 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrackBlock Jul 08 20:02:54 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:54 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:54 rpi5-ws840 go-librespot[2473]: time="2025-07-08T20:02:54+02:00" level=debug msg="fetched first chunk of 13, total size is 6431362 bytes" uri="spotify:track:7ps3LdQn8lKdaa2sbIKFTR" Jul 08 20:02:54 rpi5-ws840 go-librespot[2473]: time="2025-07-08T20:02:54+02:00" level=debug msg="created new output device" Jul 08 20:02:54 rpi5-ws840 go-librespot[2473]: time="2025-07-08T20:02:54+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Jul 08 20:02:54 rpi5-ws840 go-librespot[2473]: time="2025-07-08T20:02:54+02:00" level=debug msg="fetched chunk 1/12, size: 524288" uri="spotify:track:7ps3LdQn8lKdaa2sbIKFTR" Jul 08 20:02:54 rpi5-ws840 go-librespot[2473]: time="2025-07-08T20:02:54+02:00" level=info msg="loaded track \"Lotje - Lil Kleine Remix\" (paused: false, position: 0ms, duration: 146727ms, prefetched: false)" uri="spotify:track:7ps3LdQn8lKdaa2sbIKFTR" Jul 08 20:02:54 rpi5-ws840 go-librespot[2473]: go-librespot: pcm_meter.c:1220: snd_pcm_scope_s16_get_channel_buffer: Assertion `s16->buf_areas' failed. Jul 08 20:02:54 rpi5-ws840 go-librespot[2472]: Aborted Jul 08 20:02:54 rpi5-ws840 volumio[1359]: info: Connection to go-librespot Websocket closed Jul 08 20:02:54 rpi5-ws840 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Jul 08 20:02:54 rpi5-ws840 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 08 20:02:54 rpi5-ws840 volumio[1359]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Jul 08 20:02:54 rpi5-ws840 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 08 20:02:54 rpi5-ws840 dbus-daemon[955]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.32' (uid=0 pid=2841 comm="timedatectl show --property=NTPSynchronized --valu") Jul 08 20:02:54 rpi5-ws840 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 08 20:02:54 rpi5-ws840 dbus-daemon[955]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 08 20:02:54 rpi5-ws840 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 08 20:02:54 rpi5-ws840 setdatetime-helper.sh[2840]: Time is not synchronized. Attempting to sync... Jul 08 20:02:54 rpi5-ws840 sudo[2849]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Tue, 08 Jul 2025 18:02:55 GMT#015' Jul 08 20:02:54 rpi5-ws840 sudo[2849]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 08 20:02:55 rpi5-ws840 setdatetime-helper.sh[2850]: Tue Jul 8 20:02:55 CEST 2025 Jul 08 20:02:55 rpi5-ws840 sudo[2849]: pam_unix(sudo:session): session closed for user root Jul 08 20:02:55 rpi5-ws840 setdatetime-helper.sh[2840]: Time synchronized successfully. Jul 08 20:02:55 rpi5-ws840 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 08 20:02:55 rpi5-ws840 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 08 20:02:55 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:55 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:55 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:55 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:55 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:55 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:56 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:56 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:56 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:56 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:57 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:57 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:57 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:57 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:57 rpi5-ws840 volumio[1359]: info: Initializing connection to go-librespot Websocket Jul 08 20:02:57 rpi5-ws840 volumio[1359]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 08 20:02:57 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:57 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:58 rpi5-ws840 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Jul 08 20:02:58 rpi5-ws840 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 08 20:02:58 rpi5-ws840 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 08 20:02:58 rpi5-ws840 go-librespot[2851]: go-librespot daemon starting... Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=info msg="running go-librespot 0.2.0" Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=debug msg="app state loaded" Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=info msg="zeroconf server listening on port 44279" Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=debug msg="obtained new client token: AAD7gkSJZhOTrns5xJy/Iu/oVHAkGuNAHMqK2omtt/r65yYJ2V17fN98HHABF2amJ9IPxhbfATgV6kei2dCc+s7F/sNYiHmnYtRAM/FMQu6RcL+c7ieFggdCfEr3Xv3HMfQDwEMecKvmSluY0txHc5oIqPJ+Cafh75Q1p5K/NmaN499BEWOk8LJ66bVjB2/J8QudZAdcf06P7LD1Rl/FtXW6Ew2wQf/nL5EU6N7ClvlIbLAwZrzgmfZCiIQ=" Jul 08 20:02:58 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:58 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=debug msg="completed keyexchange" Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=debug msg="completed challenge" Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=info msg="authenticated AP as z4ujt0w8jsm3yt93kz6hrseoy" Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=info msg="authenticated Login5 as z4ujt0w8jsm3yt93kz6hrseoy" Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=debug msg="initializing zeroconf session, username: z4ujt0w8jsm3yt93kz6hrseoy" Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=debug msg="dealer connection opened" Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=trace msg="starting accesspoint recv loop" Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=trace msg="starting dealer recv loop" Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=trace msg="received accesspoint ping" Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=debug msg="received connection id: YmViNDNkMTYtZDcwZS00NDJhLThhMzAtMDQwZDAyMGFhNWI3K2RlYWxlcit0Y3A6Ly8wYWIxNTEyOS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArNTRDOTgwQjRGNzVENEI4NjRCOEZDNTVFRDM4OTNGRDZGRjE1RTlCNTlENjI5MkREQzA1QzlCNjQxRjRFRjJBMg==" Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=trace msg="received accesspoint pong ack" Jul 08 20:02:58 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:58 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:58 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:02:58+02:00" level=debug msg="put connect state because NEW_DEVICE" Jul 08 20:02:59 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:59 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:59 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:59 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:02:59 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:02:59 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:03:00 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:03:00 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:03:00 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:03:00 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:03:00 rpi5-ws840 volumio[1359]: info: Initializing connection to go-librespot Websocket Jul 08 20:03:00 rpi5-ws840 volumio[1359]: info: Connection to go-librespot Websocket established Jul 08 20:03:00 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:03:00+02:00" level=debug msg="new websocket client" Jul 08 20:03:01 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:03:01 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:03:01 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:03:01 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:03:01 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:03:01 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 2 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Preload queue cleared Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CoreStateMachine::ClearQueue Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CoreStateMachine::stop Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CorePlayQueue::clearPlayQueue Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CorePlayQueue::saveQueue Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioPushQueue Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CoreStateMachine::addQueueItems Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CorePlayQueue::addQueueItems Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Preload queue cleared Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:46Zi8b9UbvjwNpwngsyckC Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:46Zi8b9UbvjwNpwngsyckC Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:0QAUgW2vTmlUWhbiNeSkWm Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:0QAUgW2vTmlUWhbiNeSkWm Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:7ps3LdQn8lKdaa2sbIKFTR Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:7ps3LdQn8lKdaa2sbIKFTR Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:1ufm3zszexVlsNpayLSsiA Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:1ufm3zszexVlsNpayLSsiA Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioPushQueue Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CorePlayQueue::saveQueue Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CoreStateMachine::updateTrackBlock Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrackBlock Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioPlay Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CoreStateMachine::play index 3 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CoreStateMachine::addQueueItems Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CorePlayQueue::addQueueItems Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Preload queue cleared Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:3s4WNh0WnfhwvIlrp6RTBj Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:3s4WNh0WnfhwvIlrp6RTBj Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:0QCIpQV3twfqo9kh0t8Zza Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:0QCIpQV3twfqo9kh0t8Zza Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:2MPWNU7QkwmBNAoHdYLYfW Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:2MPWNU7QkwmBNAoHdYLYfW Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:78nx0HDJIFD5xDq2L5420Z Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:78nx0HDJIFD5xDq2L5420Z Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:3JzuluW7MzrmMkNpfZwtyp Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:3JzuluW7MzrmMkNpfZwtyp Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:59zH1gO9Ky2xPUEdrUrgcC Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:59zH1gO9Ky2xPUEdrUrgcC Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:6EkLMgBYvDlRnj2UOtG622 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:6EkLMgBYvDlRnj2UOtG622 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:7ubZ97TycJ5fWKKgbFQlue Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:7ubZ97TycJ5fWKKgbFQlue Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:2lCkncy6bIB0LTMT7kvrD1 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:2lCkncy6bIB0LTMT7kvrD1 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:2LddWQtqjD4ORObbXr9pKX Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:2LddWQtqjD4ORObbXr9pKX Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:6WatFBLVB0x077xWeoVc2k Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:6WatFBLVB0x077xWeoVc2k Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:1lzzSzBejW6m8UN2PdRsDv Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:1lzzSzBejW6m8UN2PdRsDv Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:6GOOcBV0wLoHrH5D9AWA32 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:6GOOcBV0wLoHrH5D9AWA32 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:0fROjdyTv6S6E65qpQfwHa Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:0fROjdyTv6S6E65qpQfwHa Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:2zqqu4TbRW3yOI5wqdrgDL Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:2zqqu4TbRW3yOI5wqdrgDL Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:5ITV0zqzjOYfFWpW0xBmRa Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:5ITV0zqzjOYfFWpW0xBmRa Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:29OyY2QfQnTP9KgdAmNviU Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:29OyY2QfQnTP9KgdAmNviU Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:72RjC359lcwhf1cON9ppls Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:72RjC359lcwhf1cON9ppls Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:05mRvdy1w3YtLhz2sHYzrB Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:05mRvdy1w3YtLhz2sHYzrB Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:7yNQ5oPcuCC4wTqTcMRIrE Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:7yNQ5oPcuCC4wTqTcMRIrE Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:7tW2Md8xOwnKPIYhCqPiU4 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:7tW2Md8xOwnKPIYhCqPiU4 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:5lXLzaZoj4j4hfCYO687bN Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:5lXLzaZoj4j4hfCYO687bN Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:6vM1GSndPtQk7AmqEmNAPH Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:6vM1GSndPtQk7AmqEmNAPH Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:2FAZskT9yRjp2Oow9szJD8 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:2FAZskT9yRjp2Oow9szJD8 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:4IunKjJY3tOuERmzYufNcL Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:4IunKjJY3tOuERmzYufNcL Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:0GRc3eGTg8HBdWLRGYgqIc Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:0GRc3eGTg8HBdWLRGYgqIc Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:30k0WUGuf3QCotkXbxnrOx Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:30k0WUGuf3QCotkXbxnrOx Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:03LXMI84EALrqIKgh5Zl6w Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:03LXMI84EALrqIKgh5Zl6w Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:4NZ15Gn3KJrmflE8jwiMBU Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:4NZ15Gn3KJrmflE8jwiMBU Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:4TwEdnSiTPDR1vg1QZ5K8W Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:4TwEdnSiTPDR1vg1QZ5K8W Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:7tqg41RdA6MLUrUbcNrFoJ Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:7tqg41RdA6MLUrUbcNrFoJ Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:04emojnbYkrRmv5qtJcgVP Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:04emojnbYkrRmv5qtJcgVP Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:6zdmJ79GtRfpJJdaDPECYm Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:6zdmJ79GtRfpJJdaDPECYm Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:6mkkFCI8p2eR7D5FOjfmzm Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:6mkkFCI8p2eR7D5FOjfmzm Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:6xOEgzkMSZJKz6qtCJsQL5 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:6xOEgzkMSZJKz6qtCJsQL5 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:0IXL5SPeI849hcz7Cynpk4 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:0IXL5SPeI849hcz7Cynpk4 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:2ipIPsgrgd0j2beDf4Ki70 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:2ipIPsgrgd0j2beDf4Ki70 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:42UBPzRMh5yyz0EDPr6fr1 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:42UBPzRMh5yyz0EDPr6fr1 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:5d0KtdnoPpniNFngV5ehcP Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:5d0KtdnoPpniNFngV5ehcP Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:0qmhTHMVxnXRmT5N92wTD9 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:0qmhTHMVxnXRmT5N92wTD9 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:0GYx8Q9iS05CRe9kyzwAIL Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:0GYx8Q9iS05CRe9kyzwAIL Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:1UNEuG9DYOWiikf00ayr52 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:1UNEuG9DYOWiikf00ayr52 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:3f3ujFD6YndqRRVYuBcq7T Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:3f3ujFD6YndqRRVYuBcq7T Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Adding Item to queue: spotify:track:2yWlGEgEfPot0lv3OAjuG3 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Using cached record of: spotify:track:2yWlGEgEfPot0lv3OAjuG3 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CoreStateMachine::stop Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioPushQueue Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CorePlayQueue::saveQueue Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CoreStateMachine::play index undefined Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CoreStateMachine::updateTrackBlock Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrackBlock Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 3 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CoreStateMachine::startPlaybackTimer Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 3 Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: [1751997782101] ControllerSpotify::clearAddPlayTrack Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Sending Spotify command with payload to local API: /player/play Jul 08 20:03:02 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:03:02+02:00" level=debug msg="resolved context of track" uri="spotify:track:1ufm3zszexVlsNpayLSsiA" Jul 08 20:03:02 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:03:02+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:1ufm3zszexVlsNpayLSsiA" Jul 08 20:03:02 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:03:02+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:1ufm3zszexVlsNpayLSsiA" Jul 08 20:03:02 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:03:02+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 08 20:03:02 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:03:02+02:00" level=trace msg="emitting websocket event: will_play" Jul 08 20:03:02 rpi5-ws840 volumio[1359]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1ufm3zszexVlsNpayLSsiA","play_origin":"go-librespot"}} Jul 08 20:03:02 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:03:02+02:00" level=debug msg="selected format OGG_VORBIS_320 (8f4b15d56dfd4bd575ef8ca7bc938ce4a4a9a48c)" uri="spotify:track:1ufm3zszexVlsNpayLSsiA" Jul 08 20:03:02 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:03:02+02:00" level=debug msg="requested aes key for file 8f4b15d56dfd4bd575ef8ca7bc938ce4a4a9a48c, gid: 1ufm3zszexVlsNpayLSsiA" Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 3 Jul 08 20:03:02 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:03:02+02:00" level=debug msg="fetched first chunk of 15, total size is 7569815 bytes" uri="spotify:track:1ufm3zszexVlsNpayLSsiA" Jul 08 20:03:02 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:03:02+02:00" level=debug msg="created new output device" Jul 08 20:03:02 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:03:02+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Jul 08 20:03:02 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:03:02+02:00" level=debug msg="fetched chunk 2/14, size: 524288" uri="spotify:track:1ufm3zszexVlsNpayLSsiA" Jul 08 20:03:02 rpi5-ws840 go-librespot[2852]: time="2025-07-08T20:03:02+02:00" level=info msg="loaded track \"Alles Kan Kapot\" (paused: false, position: 0ms, duration: 172211ms, prefetched: false)" uri="spotify:track:1ufm3zszexVlsNpayLSsiA" Jul 08 20:03:02 rpi5-ws840 go-librespot[2852]: go-librespot: pcm_meter.c:1220: snd_pcm_scope_s16_get_channel_buffer: Assertion `s16->buf_areas' failed. Jul 08 20:03:02 rpi5-ws840 go-librespot[2851]: Aborted Jul 08 20:03:02 rpi5-ws840 volumio[1359]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: Connection to go-librespot Websocket closed Jul 08 20:03:02 rpi5-ws840 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Jul 08 20:03:02 rpi5-ws840 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:03:02 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 3 Jul 08 20:03:03 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:03:03 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 3 Jul 08 20:03:03 rpi5-ws840 volumio[1359]: info: CoreCommandRouter::volumioGetState Jul 08 20:03:03 rpi5-ws840 volumio[1359]: info: CorePlayQueue::getTrack 3 Jul 08 20:03:03 rpi5-ws840 volumio[1359]: info: Getting Spotify volume Jul 08 20:03:03 rpi5-ws840 volumio[1359]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 20:03:03 rpi5-ws840 volumio[1359]: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 08 20:03:03 rpi5-ws840 volumio[1359]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Jul 08 20:03:03 rpi5-ws840 volumio[1359]: errno: -111, Jul 08 20:03:03 rpi5-ws840 volumio[1359]: code: 'ECONNREFUSED', Jul 08 20:03:03 rpi5-ws840 volumio[1359]: syscall: 'connect', Jul 08 20:03:03 rpi5-ws840 volumio[1359]: address: '127.0.0.1', Jul 08 20:03:03 rpi5-ws840 volumio[1359]: port: 9879, Jul 08 20:03:03 rpi5-ws840 volumio[1359]: response: undefined Jul 08 20:03:03 rpi5-ws840 volumio[1359]: } Jul 08 20:03:03 rpi5-ws840 volumio[1359]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 20:03:04 rpi5-ws840 sudo[2889]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-08 20:02' Jul 08 20:03:04 rpi5-ws840 sudo[2889]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="4f980d5cf693b4272997a2fa2da8bb8036ddf1ab" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jul 5 02:50:33 UTC 2025" VOLUMIO_VERSION="4.015" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d42ab79cc13d24482826e04fda2e8350"