-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Mon 2025-09-08 07:05:50 BST. -- Sep 08 07:04:00 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:04:00 volumio66 volumio[1141]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 07:04:00 volumio66 volumio[1141]: info: CoreStateMachine::ClearQueue Sep 08 07:04:00 volumio66 volumio[1141]: info: CoreStateMachine::stop Sep 08 07:04:00 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:00 volumio66 volumio[1141]: info: CorePlayQueue::clearPlayQueue Sep 08 07:04:00 volumio66 volumio[1141]: info: CorePlayQueue::saveQueue Sep 08 07:04:00 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushQueue Sep 08 07:04:00 volumio66 volumio[1141]: info: CoreStateMachine::addQueueItems Sep 08 07:04:00 volumio66 volumio[1141]: info: CorePlayQueue::addQueueItems Sep 08 07:04:00 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:04:00 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:2Hq0EaKTtjV6ieYHe3e7Tg Sep 08 07:04:00 volumio66 volumio[1141]: info: Using cached record of: spotify:track:2Hq0EaKTtjV6ieYHe3e7Tg Sep 08 07:04:00 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:63T7DJ1AFDD6Bn8VzG6JE8 Sep 08 07:04:00 volumio66 volumio[1141]: info: Using cached record of: spotify:track:63T7DJ1AFDD6Bn8VzG6JE8 Sep 08 07:04:00 volumio66 volumio[1141]: info: Adding Item to queue: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:00 volumio66 volumio[1141]: info: Exploding uri mnt/USB/D254-8C79/a day in a life.mp3 in service mpd Sep 08 07:04:00 volumio66 volumio[1141]: error: scanFolder - failure to stat '/mnt/USB/D254-8C79/a day in a life.mp3' Sep 08 07:04:00 volumio66 volumio[1141]: info: Adding Item to queue: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:00 volumio66 volumio[1141]: info: Exploding uri mnt/USB/D254-8C79/a day in a life.mp3 in service mpd Sep 08 07:04:00 volumio66 volumio[1141]: error: scanFolder - failure to stat '/mnt/USB/D254-8C79/a day in a life.mp3' Sep 08 07:04:00 volumio66 volumio[1141]: info: Adding Item to queue: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:00 volumio66 volumio[1141]: info: Exploding uri mnt/USB/D254-8C79/a day in a life.mp3 in service mpd Sep 08 07:04:00 volumio66 volumio[1141]: error: scanFolder - failure to stat '/mnt/USB/D254-8C79/a day in a life.mp3' Sep 08 07:04:00 volumio66 volumio[1141]: info: Adding Item to queue: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:00 volumio66 volumio[1141]: info: Exploding uri mnt/USB/D254-8C79/a day in a life.mp3 in service mpd Sep 08 07:04:00 volumio66 volumio[1141]: error: scanFolder - failure to stat '/mnt/USB/D254-8C79/a day in a life.mp3' Sep 08 07:04:00 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:1MyVadO3ei1DGHVbDowQGv Sep 08 07:04:00 volumio66 volumio[1141]: info: Using cached record of: spotify:track:1MyVadO3ei1DGHVbDowQGv Sep 08 07:04:00 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:1MyVadO3ei1DGHVbDowQGv Sep 08 07:04:00 volumio66 volumio[1141]: info: Using cached record of: spotify:track:1MyVadO3ei1DGHVbDowQGv Sep 08 07:04:00 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:4JEylZNW8SbO4zUyfVrpb7 Sep 08 07:04:00 volumio66 volumio[1141]: info: Using cached record of: spotify:track:4JEylZNW8SbO4zUyfVrpb7 Sep 08 07:04:00 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushQueue Sep 08 07:04:00 volumio66 volumio[1141]: info: CorePlayQueue::saveQueue Sep 08 07:04:00 volumio66 volumio[1141]: info: CoreStateMachine::updateTrackBlock Sep 08 07:04:00 volumio66 volumio[1141]: info: CorePlayQueue::getTrackBlock Sep 08 07:04:00 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPlay Sep 08 07:04:00 volumio66 volumio[1141]: info: CoreStateMachine::play index 8 Sep 08 07:04:00 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:00 volumio66 volumio[1141]: info: CoreStateMachine::stop Sep 08 07:04:00 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:00 volumio66 volumio[1141]: info: CoreStateMachine::play index undefined Sep 08 07:04:00 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:00 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 8 Sep 08 07:04:01 volumio66 go-librespot[1316]: time="2025-09-08T07:04:01+01:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 192.168.0.79:37892->104.199.65.9:443: read: connection timed out" Sep 08 07:04:01 volumio66 go-librespot[1316]: time="2025-09-08T07:04:01+01:00" level=debug msg="connected to ap-gew1.spotify.com:80" Sep 08 07:04:01 volumio66 go-librespot[1316]: time="2025-09-08T07:04:01+01:00" level=debug msg="completed keyexchange" Sep 08 07:04:01 volumio66 go-librespot[1316]: time="2025-09-08T07:04:01+01:00" level=debug msg="completed challenge" Sep 08 07:04:01 volumio66 go-librespot[1316]: time="2025-09-08T07:04:01+01:00" level=info msg="authenticated AP" username="ji*****59" Sep 08 07:04:01 volumio66 go-librespot[1316]: time="2025-09-08T07:04:01+01:00" level=debug msg="re-established accesspoint connection" Sep 08 07:04:01 volumio66 go-librespot[1316]: time="2025-09-08T07:04:01+01:00" level=trace msg="received accesspoint ping" Sep 08 07:04:01 volumio66 go-librespot[1316]: time="2025-09-08T07:04:01+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 08 07:04:01 volumio66 go-librespot[1316]: time="2025-09-08T07:04:01+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 08 07:04:03 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::ClearQueue Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::stop Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:03 volumio66 volumio[1141]: info: CorePlayQueue::clearPlayQueue Sep 08 07:04:03 volumio66 volumio[1141]: info: CorePlayQueue::saveQueue Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushQueue Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::addQueueItems Sep 08 07:04:03 volumio66 volumio[1141]: info: CorePlayQueue::addQueueItems Sep 08 07:04:03 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:2Hq0EaKTtjV6ieYHe3e7Tg Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: spotify:track:2Hq0EaKTtjV6ieYHe3e7Tg Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:63T7DJ1AFDD6Bn8VzG6JE8 Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: spotify:track:63T7DJ1AFDD6Bn8VzG6JE8 Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:1MyVadO3ei1DGHVbDowQGv Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: spotify:track:1MyVadO3ei1DGHVbDowQGv Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:1MyVadO3ei1DGHVbDowQGv Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: spotify:track:1MyVadO3ei1DGHVbDowQGv Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:4JEylZNW8SbO4zUyfVrpb7 Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: spotify:track:4JEylZNW8SbO4zUyfVrpb7 Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushQueue Sep 08 07:04:03 volumio66 volumio[1141]: info: CorePlayQueue::saveQueue Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::updateTrackBlock Sep 08 07:04:03 volumio66 volumio[1141]: info: CorePlayQueue::getTrackBlock Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPlay Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::play index 8 Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::stop Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::play index undefined Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:03 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 8 Sep 08 07:04:03 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::ClearQueue Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::stop Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:03 volumio66 volumio[1141]: info: CorePlayQueue::clearPlayQueue Sep 08 07:04:03 volumio66 volumio[1141]: info: CorePlayQueue::saveQueue Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushQueue Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::addQueueItems Sep 08 07:04:03 volumio66 volumio[1141]: info: CorePlayQueue::addQueueItems Sep 08 07:04:03 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:2Hq0EaKTtjV6ieYHe3e7Tg Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: spotify:track:2Hq0EaKTtjV6ieYHe3e7Tg Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:63T7DJ1AFDD6Bn8VzG6JE8 Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: spotify:track:63T7DJ1AFDD6Bn8VzG6JE8 Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:1MyVadO3ei1DGHVbDowQGv Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: spotify:track:1MyVadO3ei1DGHVbDowQGv Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:1MyVadO3ei1DGHVbDowQGv Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: spotify:track:1MyVadO3ei1DGHVbDowQGv Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:4JEylZNW8SbO4zUyfVrpb7 Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: spotify:track:4JEylZNW8SbO4zUyfVrpb7 Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushQueue Sep 08 07:04:03 volumio66 volumio[1141]: info: CorePlayQueue::saveQueue Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::updateTrackBlock Sep 08 07:04:03 volumio66 volumio[1141]: info: CorePlayQueue::getTrackBlock Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPlay Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::play index 8 Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::stop Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::play index undefined Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:03 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 8 Sep 08 07:04:03 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::ClearQueue Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::stop Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:03 volumio66 volumio[1141]: info: CorePlayQueue::clearPlayQueue Sep 08 07:04:03 volumio66 volumio[1141]: info: CorePlayQueue::saveQueue Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushQueue Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::addQueueItems Sep 08 07:04:03 volumio66 volumio[1141]: info: CorePlayQueue::addQueueItems Sep 08 07:04:03 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:2Hq0EaKTtjV6ieYHe3e7Tg Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: spotify:track:2Hq0EaKTtjV6ieYHe3e7Tg Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:63T7DJ1AFDD6Bn8VzG6JE8 Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: spotify:track:63T7DJ1AFDD6Bn8VzG6JE8 Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:1MyVadO3ei1DGHVbDowQGv Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: spotify:track:1MyVadO3ei1DGHVbDowQGv Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:1MyVadO3ei1DGHVbDowQGv Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: spotify:track:1MyVadO3ei1DGHVbDowQGv Sep 08 07:04:03 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:4JEylZNW8SbO4zUyfVrpb7 Sep 08 07:04:03 volumio66 volumio[1141]: info: Using cached record of: spotify:track:4JEylZNW8SbO4zUyfVrpb7 Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushQueue Sep 08 07:04:03 volumio66 volumio[1141]: info: CorePlayQueue::saveQueue Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::updateTrackBlock Sep 08 07:04:03 volumio66 volumio[1141]: info: CorePlayQueue::getTrackBlock Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPlay Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::play index 8 Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::stop Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::play index undefined Sep 08 07:04:03 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:03 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 8 Sep 08 07:04:04 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:04:04 volumio66 volumio[1141]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 07:04:04 volumio66 volumio[1141]: info: CoreStateMachine::ClearQueue Sep 08 07:04:04 volumio66 volumio[1141]: info: CoreStateMachine::stop Sep 08 07:04:04 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:04 volumio66 volumio[1141]: info: CorePlayQueue::clearPlayQueue Sep 08 07:04:04 volumio66 volumio[1141]: info: CorePlayQueue::saveQueue Sep 08 07:04:04 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushQueue Sep 08 07:04:04 volumio66 volumio[1141]: info: CoreStateMachine::addQueueItems Sep 08 07:04:04 volumio66 volumio[1141]: info: CorePlayQueue::addQueueItems Sep 08 07:04:04 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:04:04 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:2Hq0EaKTtjV6ieYHe3e7Tg Sep 08 07:04:04 volumio66 volumio[1141]: info: Using cached record of: spotify:track:2Hq0EaKTtjV6ieYHe3e7Tg Sep 08 07:04:04 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:63T7DJ1AFDD6Bn8VzG6JE8 Sep 08 07:04:04 volumio66 volumio[1141]: info: Using cached record of: spotify:track:63T7DJ1AFDD6Bn8VzG6JE8 Sep 08 07:04:04 volumio66 volumio[1141]: info: Adding Item to queue: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:04 volumio66 volumio[1141]: info: Using cached record of: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:04 volumio66 volumio[1141]: info: Adding Item to queue: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:04 volumio66 volumio[1141]: info: Using cached record of: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:04 volumio66 volumio[1141]: info: Adding Item to queue: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:04 volumio66 volumio[1141]: info: Using cached record of: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:04 volumio66 volumio[1141]: info: Adding Item to queue: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:04 volumio66 volumio[1141]: info: Using cached record of: mnt/USB/D254-8C79/a day in a life.mp3 Sep 08 07:04:04 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:1MyVadO3ei1DGHVbDowQGv Sep 08 07:04:04 volumio66 volumio[1141]: info: Using cached record of: spotify:track:1MyVadO3ei1DGHVbDowQGv Sep 08 07:04:04 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:1MyVadO3ei1DGHVbDowQGv Sep 08 07:04:04 volumio66 volumio[1141]: info: Using cached record of: spotify:track:1MyVadO3ei1DGHVbDowQGv Sep 08 07:04:04 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:4JEylZNW8SbO4zUyfVrpb7 Sep 08 07:04:04 volumio66 volumio[1141]: info: Using cached record of: spotify:track:4JEylZNW8SbO4zUyfVrpb7 Sep 08 07:04:04 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushQueue Sep 08 07:04:04 volumio66 volumio[1141]: info: CorePlayQueue::saveQueue Sep 08 07:04:04 volumio66 volumio[1141]: info: CoreStateMachine::updateTrackBlock Sep 08 07:04:04 volumio66 volumio[1141]: info: CorePlayQueue::getTrackBlock Sep 08 07:04:04 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPlay Sep 08 07:04:04 volumio66 volumio[1141]: info: CoreStateMachine::play index 8 Sep 08 07:04:04 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:04 volumio66 volumio[1141]: info: CoreStateMachine::stop Sep 08 07:04:04 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:04 volumio66 volumio[1141]: info: CoreStateMachine::play index undefined Sep 08 07:04:04 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:04 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 8 Sep 08 07:04:10 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:04:10 volumio66 volumio[1141]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 07:04:10 volumio66 volumio[1141]: info: CoreStateMachine::ClearQueue Sep 08 07:04:10 volumio66 volumio[1141]: info: CoreStateMachine::stop Sep 08 07:04:10 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:10 volumio66 volumio[1141]: info: CorePlayQueue::clearPlayQueue Sep 08 07:04:10 volumio66 volumio[1141]: info: CorePlayQueue::saveQueue Sep 08 07:04:10 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushQueue Sep 08 07:04:10 volumio66 volumio[1141]: info: CoreStateMachine::addQueueItems Sep 08 07:04:10 volumio66 volumio[1141]: info: CorePlayQueue::addQueueItems Sep 08 07:04:10 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:04:10 volumio66 volumio[1141]: info: Adding Item to queue: spotify:album:1rMmiDKa8V5H9yYTPAbLng Sep 08 07:04:10 volumio66 volumio[1141]: info: Using cached record of: spotify:album:1rMmiDKa8V5H9yYTPAbLng Sep 08 07:04:10 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushQueue Sep 08 07:04:10 volumio66 volumio[1141]: info: CorePlayQueue::saveQueue Sep 08 07:04:10 volumio66 volumio[1141]: info: CoreStateMachine::updateTrackBlock Sep 08 07:04:10 volumio66 volumio[1141]: info: CorePlayQueue::getTrackBlock Sep 08 07:04:10 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPlay Sep 08 07:04:10 volumio66 volumio[1141]: info: CoreStateMachine::play index 0 Sep 08 07:04:10 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:10 volumio66 volumio[1141]: info: CoreStateMachine::stop Sep 08 07:04:10 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:10 volumio66 volumio[1141]: info: CoreStateMachine::play index undefined Sep 08 07:04:10 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:10 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:04:10 volumio66 volumio[1141]: info: CoreStateMachine::startPlaybackTimer Sep 08 07:04:10 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:04:10 volumio66 volumio[1141]: info: [1757311450266] ControllerSpotify::clearAddPlayTrack Sep 08 07:04:10 volumio66 volumio[1141]: info: Sending Spotify command with payload to local API: /player/play Sep 08 07:04:21 volumio66 volumio[1141]: verbose: New Socket.io Connection to 192.168.0.80 from 192.168.0.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Sep 08 07:04:22 volumio66 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 08 07:04:22 volumio66 volumio[1141]: info: CoreCommandRouter::volumioGetState Sep 08 07:04:22 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:04:22 volumio66 volumio[1141]: info: CoreCommandRouter::volumioGetVisibleSources Sep 08 07:04:22 volumio66 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 07:04:22 volumio66 volumio[1141]: info: CoreCommandRouter::volumioGetQueue Sep 08 07:04:22 volumio66 volumio[1141]: info: CoreStateMachine::getQueue Sep 08 07:04:22 volumio66 volumio[1141]: info: CorePlayQueue::getQueue Sep 08 07:04:22 volumio66 volumio[1141]: info: Listing playlists Sep 08 07:04:27 volumio66 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 07:04:27 volumio66 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 07:04:27 volumio66 volumio[1141]: info: Discovery: Getting this device information Sep 08 07:04:27 volumio66 volumio[1141]: info: CoreCommandRouter::volumioGetState Sep 08 07:04:27 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:04:27 volumio66 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 07:04:27 volumio66 go-librespot[1316]: time="2025-09-08T07:04:27+01:00" level=error msg="did not receive last pong from dealer, 115s passed" Sep 08 07:04:34 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:04:34 volumio66 volumio[1141]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 07:04:34 volumio66 volumio[1141]: info: CoreStateMachine::ClearQueue Sep 08 07:04:34 volumio66 volumio[1141]: info: CoreStateMachine::stop Sep 08 07:04:34 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:34 volumio66 volumio[1141]: info: CorePlayQueue::clearPlayQueue Sep 08 07:04:34 volumio66 volumio[1141]: info: CorePlayQueue::saveQueue Sep 08 07:04:34 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushQueue Sep 08 07:04:34 volumio66 volumio[1141]: info: CoreStateMachine::addQueueItems Sep 08 07:04:34 volumio66 volumio[1141]: info: CorePlayQueue::addQueueItems Sep 08 07:04:34 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:04:34 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:58zsLZPvfflaiIbNWoA22O Sep 08 07:04:34 volumio66 volumio[1141]: info: Using cached record of: spotify:track:58zsLZPvfflaiIbNWoA22O Sep 08 07:04:34 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushQueue Sep 08 07:04:34 volumio66 volumio[1141]: info: CorePlayQueue::saveQueue Sep 08 07:04:34 volumio66 volumio[1141]: info: CoreStateMachine::updateTrackBlock Sep 08 07:04:34 volumio66 volumio[1141]: info: CorePlayQueue::getTrackBlock Sep 08 07:04:34 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPlay Sep 08 07:04:34 volumio66 volumio[1141]: info: CoreStateMachine::play index 0 Sep 08 07:04:34 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:34 volumio66 volumio[1141]: info: CoreStateMachine::stop Sep 08 07:04:34 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:34 volumio66 volumio[1141]: info: CoreStateMachine::play index undefined Sep 08 07:04:34 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:34 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:04:34 volumio66 volumio[1141]: info: CoreStateMachine::startPlaybackTimer Sep 08 07:04:34 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:04:34 volumio66 volumio[1141]: info: [1757311474552] ControllerSpotify::clearAddPlayTrack Sep 08 07:04:34 volumio66 volumio[1141]: info: Sending Spotify command with payload to local API: /player/play Sep 08 07:04:47 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:04:47 volumio66 volumio[1141]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 07:04:47 volumio66 volumio[1141]: info: CoreStateMachine::ClearQueue Sep 08 07:04:47 volumio66 volumio[1141]: info: CoreStateMachine::stop Sep 08 07:04:47 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:47 volumio66 volumio[1141]: info: CorePlayQueue::clearPlayQueue Sep 08 07:04:47 volumio66 volumio[1141]: info: CorePlayQueue::saveQueue Sep 08 07:04:47 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushQueue Sep 08 07:04:47 volumio66 volumio[1141]: info: CoreStateMachine::addQueueItems Sep 08 07:04:47 volumio66 volumio[1141]: info: CorePlayQueue::addQueueItems Sep 08 07:04:47 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:04:47 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:58zsLZPvfflaiIbNWoA22O Sep 08 07:04:47 volumio66 volumio[1141]: info: Using cached record of: spotify:track:58zsLZPvfflaiIbNWoA22O Sep 08 07:04:47 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushQueue Sep 08 07:04:47 volumio66 volumio[1141]: info: CorePlayQueue::saveQueue Sep 08 07:04:47 volumio66 volumio[1141]: info: CoreStateMachine::updateTrackBlock Sep 08 07:04:47 volumio66 volumio[1141]: info: CorePlayQueue::getTrackBlock Sep 08 07:04:47 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPlay Sep 08 07:04:47 volumio66 volumio[1141]: info: CoreStateMachine::play index 0 Sep 08 07:04:47 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:47 volumio66 volumio[1141]: info: CoreStateMachine::addQueueItems Sep 08 07:04:47 volumio66 volumio[1141]: info: CorePlayQueue::addQueueItems Sep 08 07:04:47 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:04:47 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:73fmLB2FHjg6BGWna9qpA0 Sep 08 07:04:47 volumio66 volumio[1141]: info: Using cached record of: spotify:track:73fmLB2FHjg6BGWna9qpA0 Sep 08 07:04:47 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:6y2Kaz9QI01XBKJ8mTb7Pf Sep 08 07:04:47 volumio66 volumio[1141]: info: Using cached record of: spotify:track:6y2Kaz9QI01XBKJ8mTb7Pf Sep 08 07:04:47 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:1vA5dEJTnUXHQEkm5hRgnW Sep 08 07:04:47 volumio66 volumio[1141]: info: Using cached record of: spotify:track:1vA5dEJTnUXHQEkm5hRgnW Sep 08 07:04:47 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:5R4XdfbRHahAwS5YSZwFTx Sep 08 07:04:47 volumio66 volumio[1141]: info: Using cached record of: spotify:track:5R4XdfbRHahAwS5YSZwFTx Sep 08 07:04:47 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:4VkjUjmnyidaY4Fkxju6LP Sep 08 07:04:47 volumio66 volumio[1141]: info: Using cached record of: spotify:track:4VkjUjmnyidaY4Fkxju6LP Sep 08 07:04:47 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:4YLWvrg2Nob2AT9rrjJS4u Sep 08 07:04:47 volumio66 volumio[1141]: info: Using cached record of: spotify:track:4YLWvrg2Nob2AT9rrjJS4u Sep 08 07:04:47 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:08w1hnWW4GhVm61DoVojsY Sep 08 07:04:47 volumio66 volumio[1141]: info: Using cached record of: spotify:track:08w1hnWW4GhVm61DoVojsY Sep 08 07:04:47 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:7negGXQVV3htbu8ylS59Ur Sep 08 07:04:47 volumio66 volumio[1141]: info: Using cached record of: spotify:track:7negGXQVV3htbu8ylS59Ur Sep 08 07:04:47 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:5TWK4SiCMI3bOxVvyG0zyY Sep 08 07:04:47 volumio66 volumio[1141]: info: Using cached record of: spotify:track:5TWK4SiCMI3bOxVvyG0zyY Sep 08 07:04:47 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:5oWSIIJyMb0xAtAUcGJmy1 Sep 08 07:04:47 volumio66 volumio[1141]: info: Using cached record of: spotify:track:5oWSIIJyMb0xAtAUcGJmy1 Sep 08 07:04:47 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:7s85izscOJfT2QkWTzMARa Sep 08 07:04:47 volumio66 volumio[1141]: info: Using cached record of: spotify:track:7s85izscOJfT2QkWTzMARa Sep 08 07:04:47 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:5Opv3rAPRL93wJ9yXGJPiM Sep 08 07:04:47 volumio66 volumio[1141]: info: Using cached record of: spotify:track:5Opv3rAPRL93wJ9yXGJPiM Sep 08 07:04:47 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:5LUg3woLYpiaNwXHDVWcY5 Sep 08 07:04:47 volumio66 volumio[1141]: info: Using cached record of: spotify:track:5LUg3woLYpiaNwXHDVWcY5 Sep 08 07:04:47 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:0p8NXSUkgtylXNBIUdnZT3 Sep 08 07:04:47 volumio66 volumio[1141]: info: Using cached record of: spotify:track:0p8NXSUkgtylXNBIUdnZT3 Sep 08 07:04:47 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:4v4JL46Vg1kB78xArvfJ99 Sep 08 07:04:47 volumio66 volumio[1141]: info: Using cached record of: spotify:track:4v4JL46Vg1kB78xArvfJ99 Sep 08 07:04:47 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:6xWrFmTaczQo9DsdA9IG7c Sep 08 07:04:47 volumio66 volumio[1141]: info: Using cached record of: spotify:track:6xWrFmTaczQo9DsdA9IG7c Sep 08 07:04:47 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:2oLo4vGUIP3eJ9T001keTu Sep 08 07:04:47 volumio66 volumio[1141]: info: Using cached record of: spotify:track:2oLo4vGUIP3eJ9T001keTu Sep 08 07:04:47 volumio66 volumio[1141]: info: Adding Item to queue: spotify:track:3qSYMMN1E7y70HJlS7twv4 Sep 08 07:04:47 volumio66 volumio[1141]: info: Using cached record of: spotify:track:3qSYMMN1E7y70HJlS7twv4 Sep 08 07:04:47 volumio66 volumio[1141]: info: CoreStateMachine::stop Sep 08 07:04:47 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:47 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushQueue Sep 08 07:04:47 volumio66 volumio[1141]: info: CorePlayQueue::saveQueue Sep 08 07:04:47 volumio66 volumio[1141]: info: CoreStateMachine::play index undefined Sep 08 07:04:47 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:04:47 volumio66 volumio[1141]: info: CoreStateMachine::updateTrackBlock Sep 08 07:04:47 volumio66 volumio[1141]: info: CorePlayQueue::getTrackBlock Sep 08 07:04:47 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:04:47 volumio66 volumio[1141]: info: CoreStateMachine::startPlaybackTimer Sep 08 07:04:47 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:04:47 volumio66 volumio[1141]: info: [1757311487371] ControllerSpotify::clearAddPlayTrack Sep 08 07:04:47 volumio66 volumio[1141]: info: Sending Spotify command with payload to local API: /player/play Sep 08 07:04:57 volumio66 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 07:04:57 volumio66 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 07:04:57 volumio66 volumio[1141]: info: Discovery: Getting this device information Sep 08 07:04:57 volumio66 volumio[1141]: info: CoreCommandRouter::volumioGetState Sep 08 07:04:57 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:04:57 volumio66 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 07:04:57 volumio66 go-librespot[1316]: time="2025-09-08T07:04:57+01:00" level=error msg="did not receive last pong from dealer, 145s passed" Sep 08 07:04:57 volumio66 go-librespot[1316]: time="2025-09-08T07:04:57+01:00" level=error msg="did not receive last pong ack from accesspoint, 240s passed" Sep 08 07:05:02 volumio66 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alarm-clock , getSleep Sep 08 07:05:19 volumio66 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alarm-clock , setSleep Sep 08 07:05:19 volumio66 volumio[1141]: info: [1757311519522] SetSleep: 0 hours 0 minutes , enabled: true Sep 08 07:05:19 volumio66 volumio[1141]: info: Set Sleep at Mon Sep 08 2025 07:05:00 GMT+0100 (British Summer Time) Sep 08 07:05:24 volumio66 volumio[1141]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 07:05:24 volumio66 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 08 07:05:24 volumio66 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 08 07:05:24 volumio66 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 08 07:05:24 volumio66 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Sep 08 07:05:24 volumio66 volumio[1141]: info: Received Get System Version Sep 08 07:05:24 volumio66 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 07:05:24 volumio66 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Sep 08 07:05:27 volumio66 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 07:05:27 volumio66 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 07:05:27 volumio66 volumio[1141]: info: Discovery: Getting this device information Sep 08 07:05:27 volumio66 volumio[1141]: info: CoreCommandRouter::volumioGetState Sep 08 07:05:27 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:27 volumio66 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 07:05:27 volumio66 go-librespot[1316]: time="2025-09-08T07:05:27+01:00" level=error msg="did not receive last pong from dealer, 175s passed" Sep 08 07:05:31 volumio66 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Sep 08 07:05:31 volumio66 volumio[1141]: info: [1757311531714] [80s80s] handleBrowseUri curUri: 80s80s Sep 08 07:05:31 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:05:32 volumio66 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Sep 08 07:05:32 volumio66 volumio[1141]: info: [1757311532081] [80s80s] handleBrowseUri curUri: 80s80s Sep 08 07:05:32 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:05:34 volumio66 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Sep 08 07:05:34 volumio66 volumio[1141]: info: [1757311534529] [80s80s] handleBrowseUri curUri: 80s80s/nineties Sep 08 07:05:34 volumio66 volumio[1141]: info: [1757311534530] [80s80s] getRadioContent url: nineties Sep 08 07:05:34 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:05:34 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:05:34 volumio66 volumio[1141]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 07:05:34 volumio66 volumio[1141]: info: CoreStateMachine::ClearQueue Sep 08 07:05:34 volumio66 volumio[1141]: info: CoreStateMachine::stop Sep 08 07:05:34 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:05:34 volumio66 volumio[1141]: info: CorePlayQueue::clearPlayQueue Sep 08 07:05:34 volumio66 volumio[1141]: info: CorePlayQueue::saveQueue Sep 08 07:05:34 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushQueue Sep 08 07:05:34 volumio66 volumio[1141]: info: CoreStateMachine::addQueueItems Sep 08 07:05:34 volumio66 volumio[1141]: info: CorePlayQueue::addQueueItems Sep 08 07:05:34 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:05:34 volumio66 volumio[1141]: info: Adding Item to queue: webnineties/1 Sep 08 07:05:34 volumio66 volumio[1141]: info: Exploding uri webnineties/1 in service 80s80s Sep 08 07:05:34 volumio66 volumio[1141]: info: [1757311534815] [80s80s] explodeUri: webnineties/1 Sep 08 07:05:34 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushQueue Sep 08 07:05:34 volumio66 volumio[1141]: info: CorePlayQueue::saveQueue Sep 08 07:05:34 volumio66 volumio[1141]: info: CoreStateMachine::updateTrackBlock Sep 08 07:05:34 volumio66 volumio[1141]: info: CorePlayQueue::getTrackBlock Sep 08 07:05:34 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPlay Sep 08 07:05:34 volumio66 volumio[1141]: info: CoreStateMachine::play index 0 Sep 08 07:05:34 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:05:34 volumio66 volumio[1141]: info: CoreStateMachine::stop Sep 08 07:05:34 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:05:34 volumio66 volumio[1141]: info: CoreStateMachine::play index undefined Sep 08 07:05:34 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:05:34 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:34 volumio66 volumio[1141]: info: CoreStateMachine::startPlaybackTimer Sep 08 07:05:34 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:34 volumio66 volumio[1141]: info: [1757311534822] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/grunge/mp3-192/volumio Sep 08 07:05:34 volumio66 volumio[1141]: info: [1757311534822] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=253&count=2 Sep 08 07:05:35 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreStateMachine::ClearQueue Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreStateMachine::stop Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:05:35 volumio66 volumio[1141]: info: CorePlayQueue::clearPlayQueue Sep 08 07:05:35 volumio66 volumio[1141]: info: CorePlayQueue::saveQueue Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushQueue Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreStateMachine::addQueueItems Sep 08 07:05:35 volumio66 volumio[1141]: info: CorePlayQueue::addQueueItems Sep 08 07:05:35 volumio66 volumio[1141]: info: Preload queue cleared Sep 08 07:05:35 volumio66 volumio[1141]: info: Adding Item to queue: webnineties/1 Sep 08 07:05:35 volumio66 volumio[1141]: info: Using cached record of: webnineties/1 Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushQueue Sep 08 07:05:35 volumio66 volumio[1141]: info: CorePlayQueue::saveQueue Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreStateMachine::updateTrackBlock Sep 08 07:05:35 volumio66 volumio[1141]: info: CorePlayQueue::getTrackBlock Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPlay Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreStateMachine::play index 0 Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreStateMachine::stop Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreStateMachine::play index undefined Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 07:05:35 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreStateMachine::startPlaybackTimer Sep 08 07:05:35 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:35 volumio66 volumio[1141]: info: [1757311535049] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/grunge/mp3-192/volumio Sep 08 07:05:35 volumio66 volumio[1141]: info: [1757311535049] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=253&count=2 Sep 08 07:05:35 volumio66 volumio[1141]: info: [1757311535225] [80s80s] received new event containing 2 songs. Sep 08 07:05:35 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 07:05:35 volumio66 volumio[1141]: info: sendMpdCommand stop took 6 milliseconds Sep 08 07:05:35 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand clear Sep 08 07:05:35 volumio66 volumio[1141]: info: Sep 08 07:05:35 volumio66 volumio[1141]: ---------------------------- MPD announces system playlist update Sep 08 07:05:35 volumio66 volumio[1141]: info: Ignoring MPD Status Update Sep 08 07:05:35 volumio66 volumio[1141]: info: sendMpdCommand clear took 2 milliseconds Sep 08 07:05:35 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 08 07:05:35 volumio66 volumio[1141]: info: Sep 08 07:05:35 volumio66 volumio[1141]: ---------------------------- MPD announces system playlist update Sep 08 07:05:35 volumio66 volumio[1141]: info: Ignoring MPD Status Update Sep 08 07:05:35 volumio66 volumio[1141]: info: Sep 08 07:05:35 volumio66 volumio[1141]: ---------------------------- MPD announces system playlist update Sep 08 07:05:35 volumio66 volumio[1141]: info: Ignoring MPD Status Update Sep 08 07:05:35 volumio66 volumio[1141]: error: updateQueue error: null Sep 08 07:05:35 volumio66 volumio[1141]: info: Sep 08 07:05:35 volumio66 volumio[1141]: ---------------------------- MPD announces state update: options Sep 08 07:05:35 volumio66 volumio[1141]: error: updateQueue error: null Sep 08 07:05:35 volumio66 volumio[1141]: error: updateQueue error: null Sep 08 07:05:35 volumio66 volumio[1141]: info: ------------------------------ 6ms Sep 08 07:05:35 volumio66 volumio[1141]: info: sendMpdCommand consume 1 took 4 milliseconds Sep 08 07:05:35 volumio66 volumio[1141]: info: ControllerMpd::getState Sep 08 07:05:35 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand status Sep 08 07:05:35 volumio66 volumio[1141]: info: ------------------------------ 5ms Sep 08 07:05:35 volumio66 volumio[1141]: info: ------------------------------ 4ms Sep 08 07:05:35 volumio66 volumio[1141]: info: [1757311535242] [80s80s] adding url: http://streams.90s90s.de/grunge/mp3-192/volumio Sep 08 07:05:35 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/grunge/mp3-192/volumio" Sep 08 07:05:35 volumio66 volumio[1141]: info: Sep 08 07:05:35 volumio66 volumio[1141]: ---------------------------- MPD announces state update: options Sep 08 07:05:35 volumio66 volumio[1141]: info: ControllerMpd::getState Sep 08 07:05:35 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand status Sep 08 07:05:35 volumio66 volumio[1141]: info: Sep 08 07:05:35 volumio66 volumio[1141]: ---------------------------- MPD announces state update: options Sep 08 07:05:35 volumio66 volumio[1141]: info: ControllerMpd::getState Sep 08 07:05:35 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand status Sep 08 07:05:35 volumio66 volumio[1141]: error: Upnp client error: Error: This socket has been ended by the other party Sep 08 07:05:35 volumio66 volumio[1141]: info: Sep 08 07:05:35 volumio66 volumio[1141]: ---------------------------- MPD announces system playlist update Sep 08 07:05:35 volumio66 volumio[1141]: info: Ignoring MPD Status Update Sep 08 07:05:35 volumio66 volumio[1141]: info: sendMpdCommand status took 6 milliseconds Sep 08 07:05:35 volumio66 volumio[1141]: info: sendMpdCommand add "http://streams.90s90s.de/grunge/mp3-192/volumio" took 5 milliseconds Sep 08 07:05:35 volumio66 volumio[1141]: info: sendMpdCommand status took 5 milliseconds Sep 08 07:05:35 volumio66 volumio[1141]: info: sendMpdCommand status took 4 milliseconds Sep 08 07:05:35 volumio66 volumio[1141]: verbose: ControllerMpd::parseState Sep 08 07:05:35 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand play Sep 08 07:05:35 volumio66 volumio[1141]: verbose: ControllerMpd::parseState Sep 08 07:05:35 volumio66 volumio[1141]: verbose: ControllerMpd::parseState Sep 08 07:05:35 volumio66 volumio[1141]: info: ControllerMpd::pushState Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreCommandRouter::servicePushState Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreStateMachine::pushState Sep 08 07:05:35 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushState Sep 08 07:05:35 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:35 volumio66 volumio[1141]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 07:05:35 volumio66 volumio[1141]: info: ControllerMpd::pushState Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreCommandRouter::servicePushState Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreStateMachine::pushState Sep 08 07:05:35 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushState Sep 08 07:05:35 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:35 volumio66 volumio[1141]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 07:05:35 volumio66 volumio[1141]: info: ControllerMpd::pushState Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreCommandRouter::servicePushState Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreStateMachine::pushState Sep 08 07:05:35 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreCommandRouter::volumioPushState Sep 08 07:05:35 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:35 volumio66 volumio[1141]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 07:05:35 volumio66 volumio[1141]: info: ------------------------------ 28ms Sep 08 07:05:35 volumio66 volumio[1141]: info: ------------------------------ 35ms Sep 08 07:05:35 volumio66 volumio[1141]: info: ------------------------------ 33ms Sep 08 07:05:35 volumio66 volumio[1141]: info: Sep 08 07:05:35 volumio66 volumio[1141]: ---------------------------- MPD announces system playlist update Sep 08 07:05:35 volumio66 volumio[1141]: info: Ignoring MPD Status Update Sep 08 07:05:35 volumio66 volumio[1141]: info: Sep 08 07:05:35 volumio66 volumio[1141]: ---------------------------- MPD announces system playlist update Sep 08 07:05:35 volumio66 volumio[1141]: info: Ignoring MPD Status Update Sep 08 07:05:35 volumio66 volumio[1141]: info: ------------------------------ 35ms Sep 08 07:05:35 volumio66 volumio[1141]: info: sendMpdCommand play took 29 milliseconds Sep 08 07:05:35 volumio66 volumio[1141]: info: ------------------------------ 3ms Sep 08 07:05:35 volumio66 volumio[1141]: info: ------------------------------ 3ms Sep 08 07:05:35 volumio66 volumio[1141]: info: [1757311535283] [80s80s] Pushing the next song state: Sonic Youth - 100% Sep 08 07:05:35 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreCommandRouter::servicePushState Sep 08 07:05:35 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:35 volumio66 volumio[1141]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is1-ssl.mzstatic.com/image/thumb/Music118/v4/ce/35/62/ce3562bc-f4d5-5029-5b89-7d11a040c8ea/source/600x600bb.jpg","name":"Sonic Youth - 100%","title":"100%","artist":"90s90s Grunge","album":"","streaming":true,"disableUiControls":true,"duration":"145","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 08 07:05:35 volumio66 volumio[1141]: verbose: CURRENT POSITION 0 Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreStateMachine::syncState stateService play Sep 08 07:05:35 volumio66 volumio[1141]: info: CoreStateMachine::syncState currentStatus stop Sep 08 07:05:35 volumio66 volumio[1141]: info: [1757311535286] [80s80s] PlayNextTrack API delay: 30 Sep 08 07:05:35 volumio66 volumio[1141]: info: [1757311535297] [80s80s] Setting timer to: 145000 milliseconds. Sep 08 07:05:35 volumio66 volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Sep 08 07:05:35 volumio66 volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Sep 08 07:05:35 volumio66 volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Sep 08 07:05:35 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:35 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 1 Sep 08 07:05:38 volumio66 volumio[1141]: info: Sep 08 07:05:38 volumio66 volumio[1141]: ---------------------------- MPD announces state update: player Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::getState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand status Sep 08 07:05:38 volumio66 volumio[1141]: info: Sep 08 07:05:38 volumio66 volumio[1141]: ---------------------------- MPD announces state update: player Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::getState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand status Sep 08 07:05:38 volumio66 volumio[1141]: info: Sep 08 07:05:38 volumio66 volumio[1141]: ---------------------------- MPD announces state update: player Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::getState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand status Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand status took 3 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand status took 2 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 07:05:38 volumio66 volumio[1141]: info: Sep 08 07:05:38 volumio66 volumio[1141]: ---------------------------- MPD announces state update: player Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::getState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand status Sep 08 07:05:38 volumio66 volumio[1141]: info: Sep 08 07:05:38 volumio66 volumio[1141]: ---------------------------- MPD announces state update: player Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::getState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand status Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand status took 5 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand status took 2 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand status took 1 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseTrackInfo Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseTrackInfo Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::pushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CoreCommandRouter::servicePushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:38 volumio66 volumio[1141]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::pushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CoreCommandRouter::servicePushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:38 volumio66 volumio[1141]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 07:05:38 volumio66 volumio[1141]: info: ------------------------------ 12ms Sep 08 07:05:38 volumio66 volumio[1141]: info: ------------------------------ 11ms Sep 08 07:05:38 volumio66 volumio[1141]: info: Sep 08 07:05:38 volumio66 volumio[1141]: ---------------------------- MPD announces state update: player Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::getState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand status Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand status took 2 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseTrackInfo Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseTrackInfo Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseTrackInfo Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::pushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CoreCommandRouter::servicePushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:38 volumio66 volumio[1141]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::pushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CoreCommandRouter::servicePushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:38 volumio66 volumio[1141]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::pushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CoreCommandRouter::servicePushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:38 volumio66 volumio[1141]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 07:05:38 volumio66 volumio[1141]: info: ------------------------------ 14ms Sep 08 07:05:38 volumio66 volumio[1141]: info: ------------------------------ 11ms Sep 08 07:05:38 volumio66 volumio[1141]: info: ------------------------------ 11ms Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseTrackInfo Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::pushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CoreCommandRouter::servicePushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:38 volumio66 volumio[1141]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 07:05:38 volumio66 volumio[1141]: info: ------------------------------ 6ms Sep 08 07:05:38 volumio66 volumio[1141]: info: Sep 08 07:05:38 volumio66 volumio[1141]: ---------------------------- MPD announces system playlist update Sep 08 07:05:38 volumio66 volumio[1141]: info: Ignoring MPD Status Update Sep 08 07:05:38 volumio66 volumio[1141]: info: Sep 08 07:05:38 volumio66 volumio[1141]: ---------------------------- MPD announces state update: player Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::getState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand status Sep 08 07:05:38 volumio66 volumio[1141]: info: Sep 08 07:05:38 volumio66 volumio[1141]: ---------------------------- MPD announces system playlist update Sep 08 07:05:38 volumio66 volumio[1141]: info: Ignoring MPD Status Update Sep 08 07:05:38 volumio66 volumio[1141]: info: Sep 08 07:05:38 volumio66 volumio[1141]: ---------------------------- MPD announces state update: player Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::getState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand status Sep 08 07:05:38 volumio66 volumio[1141]: info: Sep 08 07:05:38 volumio66 volumio[1141]: ---------------------------- MPD announces system playlist update Sep 08 07:05:38 volumio66 volumio[1141]: info: Ignoring MPD Status Update Sep 08 07:05:38 volumio66 volumio[1141]: info: Sep 08 07:05:38 volumio66 volumio[1141]: ---------------------------- MPD announces state update: player Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::getState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand status Sep 08 07:05:38 volumio66 volumio[1141]: info: Sep 08 07:05:38 volumio66 volumio[1141]: ---------------------------- MPD announces system playlist update Sep 08 07:05:38 volumio66 volumio[1141]: info: Ignoring MPD Status Update Sep 08 07:05:38 volumio66 volumio[1141]: info: Sep 08 07:05:38 volumio66 volumio[1141]: ---------------------------- MPD announces state update: player Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::getState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand status Sep 08 07:05:38 volumio66 volumio[1141]: info: Sep 08 07:05:38 volumio66 volumio[1141]: ---------------------------- MPD announces system playlist update Sep 08 07:05:38 volumio66 volumio[1141]: info: Ignoring MPD Status Update Sep 08 07:05:38 volumio66 volumio[1141]: info: Sep 08 07:05:38 volumio66 volumio[1141]: ---------------------------- MPD announces state update: player Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::getState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand status Sep 08 07:05:38 volumio66 volumio[1141]: info: Sep 08 07:05:38 volumio66 volumio[1141]: ---------------------------- MPD announces system playlist update Sep 08 07:05:38 volumio66 volumio[1141]: info: Ignoring MPD Status Update Sep 08 07:05:38 volumio66 volumio[1141]: info: Sep 08 07:05:38 volumio66 volumio[1141]: ---------------------------- MPD announces state update: player Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::getState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand status Sep 08 07:05:38 volumio66 volumio[1141]: info: ------------------------------ 8ms Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand status took 8 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: info: ------------------------------ 8ms Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand status took 7 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: info: ------------------------------ 7ms Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand status took 6 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: info: ------------------------------ 5ms Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand status took 5 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: info: ------------------------------ 4ms Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand status took 3 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 07:05:38 volumio66 volumio[1141]: info: ------------------------------ 6ms Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand status took 5 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseState Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseTrackInfo Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseTrackInfo Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseTrackInfo Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseTrackInfo Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseTrackInfo Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::pushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CoreCommandRouter::servicePushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:38 volumio66 volumio[1141]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::pushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CoreCommandRouter::servicePushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:38 volumio66 volumio[1141]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::pushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CoreCommandRouter::servicePushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:38 volumio66 volumio[1141]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::pushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CoreCommandRouter::servicePushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:38 volumio66 volumio[1141]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::pushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CoreCommandRouter::servicePushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:38 volumio66 volumio[1141]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 07:05:38 volumio66 volumio[1141]: info: ------------------------------ 16ms Sep 08 07:05:38 volumio66 volumio[1141]: info: ------------------------------ 15ms Sep 08 07:05:38 volumio66 volumio[1141]: info: ------------------------------ 14ms Sep 08 07:05:38 volumio66 volumio[1141]: info: ------------------------------ 13ms Sep 08 07:05:38 volumio66 volumio[1141]: info: ------------------------------ 12ms Sep 08 07:05:38 volumio66 volumio[1141]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 08 07:05:38 volumio66 volumio[1141]: verbose: ControllerMpd::parseTrackInfo Sep 08 07:05:38 volumio66 volumio[1141]: info: ControllerMpd::pushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CoreCommandRouter::servicePushState Sep 08 07:05:38 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:38 volumio66 volumio[1141]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 07:05:38 volumio66 volumio[1141]: info: ------------------------------ 12ms Sep 08 07:05:39 volumio66 volumio[1141]: info: Sep 08 07:05:39 volumio66 volumio[1141]: ---------------------------- MPD announces system playlist update Sep 08 07:05:39 volumio66 volumio[1141]: info: Ignoring MPD Status Update Sep 08 07:05:39 volumio66 volumio[1141]: info: Sep 08 07:05:39 volumio66 volumio[1141]: ---------------------------- MPD announces state update: player Sep 08 07:05:39 volumio66 volumio[1141]: info: ControllerMpd::getState Sep 08 07:05:39 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand status Sep 08 07:05:39 volumio66 volumio[1141]: info: Sep 08 07:05:39 volumio66 volumio[1141]: ---------------------------- MPD announces system playlist update Sep 08 07:05:39 volumio66 volumio[1141]: info: Ignoring MPD Status Update Sep 08 07:05:39 volumio66 volumio[1141]: info: Sep 08 07:05:39 volumio66 volumio[1141]: ---------------------------- MPD announces state update: player Sep 08 07:05:39 volumio66 volumio[1141]: info: ControllerMpd::getState Sep 08 07:05:39 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand status Sep 08 07:05:39 volumio66 volumio[1141]: info: Sep 08 07:05:39 volumio66 volumio[1141]: ---------------------------- MPD announces system playlist update Sep 08 07:05:39 volumio66 volumio[1141]: info: Ignoring MPD Status Update Sep 08 07:05:39 volumio66 volumio[1141]: info: Sep 08 07:05:39 volumio66 volumio[1141]: ---------------------------- MPD announces state update: player Sep 08 07:05:39 volumio66 volumio[1141]: info: ControllerMpd::getState Sep 08 07:05:39 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand status Sep 08 07:05:39 volumio66 volumio[1141]: info: ------------------------------ 5ms Sep 08 07:05:39 volumio66 volumio[1141]: info: sendMpdCommand status took 4 milliseconds Sep 08 07:05:39 volumio66 volumio[1141]: info: ------------------------------ 4ms Sep 08 07:05:39 volumio66 volumio[1141]: info: sendMpdCommand status took 4 milliseconds Sep 08 07:05:39 volumio66 volumio[1141]: info: ------------------------------ 3ms Sep 08 07:05:39 volumio66 volumio[1141]: info: sendMpdCommand status took 2 milliseconds Sep 08 07:05:39 volumio66 volumio[1141]: verbose: ControllerMpd::parseState Sep 08 07:05:39 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 07:05:39 volumio66 volumio[1141]: verbose: ControllerMpd::parseState Sep 08 07:05:39 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 07:05:39 volumio66 volumio[1141]: verbose: ControllerMpd::parseState Sep 08 07:05:39 volumio66 volumio[1141]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 07:05:39 volumio66 volumio[1141]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 08 07:05:39 volumio66 volumio[1141]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 08 07:05:39 volumio66 volumio[1141]: verbose: ControllerMpd::parseTrackInfo Sep 08 07:05:39 volumio66 volumio[1141]: info: ControllerMpd::pushState Sep 08 07:05:39 volumio66 volumio[1141]: info: CoreCommandRouter::servicePushState Sep 08 07:05:39 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:39 volumio66 volumio[1141]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 07:05:39 volumio66 volumio[1141]: info: ControllerMpd::pushState Sep 08 07:05:39 volumio66 volumio[1141]: info: CoreCommandRouter::servicePushState Sep 08 07:05:39 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:39 volumio66 volumio[1141]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 07:05:39 volumio66 volumio[1141]: info: ControllerMpd::pushState Sep 08 07:05:39 volumio66 volumio[1141]: info: CoreCommandRouter::servicePushState Sep 08 07:05:39 volumio66 volumio[1141]: info: CorePlayQueue::getTrack 0 Sep 08 07:05:39 volumio66 volumio[1141]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 07:05:39 volumio66 volumio[1141]: info: ------------------------------ 11ms Sep 08 07:05:39 volumio66 volumio[1141]: info: ------------------------------ 9ms Sep 08 07:05:39 volumio66 volumio[1141]: info: ------------------------------ 8ms Sep 08 07:05:50 volumio66 volumio[1141]: info: [1757311550040] [80s80s] Failed to query api, status code: 503 Sep 08 07:05:50 volumio66 volumio[1141]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 07:05:50 volumio66 volumio[1141]: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/80s80s/fake-data.json' Sep 08 07:05:50 volumio66 volumio[1141]: at Object.openSync (fs.js:476:3) Sep 08 07:05:50 volumio66 volumio[1141]: at Object.readFileSync (fs.js:377:35) Sep 08 07:05:50 volumio66 volumio[1141]: at ClientRequest. (/data/plugins/music_service/80s80s/index.js:485:23) Sep 08 07:05:50 volumio66 volumio[1141]: at Object.onceWrapper (events.js:422:26) Sep 08 07:05:50 volumio66 volumio[1141]: at ClientRequest.emit (events.js:315:20) Sep 08 07:05:50 volumio66 volumio[1141]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Sep 08 07:05:50 volumio66 volumio[1141]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Sep 08 07:05:50 volumio66 volumio[1141]: at Socket.socketOnData (_http_client.js:509:22) Sep 08 07:05:50 volumio66 volumio[1141]: at Socket.emit (events.js:315:20) Sep 08 07:05:50 volumio66 volumio[1141]: at addChunk (internal/streams/readable.js:309:12) Sep 08 07:05:50 volumio66 volumio[1141]: at readableAddChunk (internal/streams/readable.js:284:9) Sep 08 07:05:50 volumio66 volumio[1141]: at Socket.Readable.push (internal/streams/readable.js:223:10) Sep 08 07:05:50 volumio66 volumio[1141]: at TCP.onStreamRead (internal/stream_base_commons.js:188:23) { Sep 08 07:05:50 volumio66 volumio[1141]: errno: -2, Sep 08 07:05:50 volumio66 volumio[1141]: syscall: 'open', Sep 08 07:05:50 volumio66 volumio[1141]: code: 'ENOENT', Sep 08 07:05:50 volumio66 volumio[1141]: path: '/data/plugins/music_service/80s80s/fake-data.json' Sep 08 07:05:50 volumio66 volumio[1141]: } Sep 08 07:05:50 volumio66 volumio[1141]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 07:05:50 volumio66 sudo[2572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-08 07:04 Sep 08 07:05:50 volumio66 sudo[2572]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"