-- Logs begin at Tue 2025-07-08 09:15:02 +05, end at Tue 2025-07-08 09:28:14 +05. -- Jul 08 09:27:01 volumio volumio[5866]: error: updateQueue error: null Jul 08 09:27:01 volumio volumio[5866]: info: ------------------------------ 1370ms Jul 08 09:27:01 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand add "https://air.unmixed.ru/radioc128" Jul 08 09:27:01 volumio volumio[5866]: info: Jul 08 09:27:01 volumio volumio[5866]: ---------------------------- MPD announces system playlist update Jul 08 09:27:01 volumio volumio[5866]: info: Ignoring MPD Status Update Jul 08 09:27:01 volumio volumio[5866]: info: sendMpdCommand add "https://air.unmixed.ru/radioc128" took 1 milliseconds Jul 08 09:27:01 volumio volumio[5866]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 08 09:27:01 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand play Jul 08 09:27:01 volumio volumio[5866]: info: ------------------------------ 4ms Jul 08 09:27:01 volumio volumio[5866]: info: sendMpdCommand play took 3 milliseconds Jul 08 09:27:01 volumio volumio[5866]: info: Jul 08 09:27:01 volumio volumio[5866]: ---------------------------- MPD announces state update: player Jul 08 09:27:01 volumio volumio[5866]: info: ControllerMpd::getState Jul 08 09:27:01 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand status Jul 08 09:27:01 volumio volumio[5866]: info: Jul 08 09:27:01 volumio volumio[5866]: ---------------------------- MPD announces state update: player Jul 08 09:27:01 volumio volumio[5866]: info: sendMpdCommand status took 10 milliseconds Jul 08 09:27:01 volumio volumio[5866]: info: ControllerMpd::getState Jul 08 09:27:01 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand status Jul 08 09:27:01 volumio volumio[5866]: verbose: ControllerMpd::parseState Jul 08 09:27:01 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 09:27:01 volumio volumio[5866]: info: sendMpdCommand status took 3 milliseconds Jul 08 09:27:01 volumio volumio[5866]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 08 09:27:01 volumio volumio[5866]: verbose: ControllerMpd::parseState Jul 08 09:27:01 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 09:27:01 volumio volumio[5866]: verbose: ControllerMpd::parseTrackInfo Jul 08 09:27:01 volumio volumio[5866]: info: ControllerMpd::pushState Jul 08 09:27:01 volumio volumio[5866]: info: CoreCommandRouter::servicePushState Jul 08 09:27:01 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:01 volumio volumio[5866]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radioc128","artist":null,"album":null,"uri":"https://air.unmixed.ru/radioc128","trackType":""} Jul 08 09:27:01 volumio volumio[5866]: verbose: CURRENT POSITION 0 Jul 08 09:27:01 volumio volumio[5866]: info: CoreStateMachine::syncState stateService play Jul 08 09:27:01 volumio volumio[5866]: info: CoreStateMachine::syncState currentStatus stop Jul 08 09:27:01 volumio volumio[5866]: info: ------------------------------ 20ms Jul 08 09:27:01 volumio volumio[5866]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 08 09:27:01 volumio volumio[5866]: verbose: ControllerMpd::parseTrackInfo Jul 08 09:27:01 volumio volumio[5866]: info: ControllerMpd::pushState Jul 08 09:27:01 volumio volumio[5866]: info: CoreCommandRouter::servicePushState Jul 08 09:27:01 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:01 volumio volumio[5866]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radioc128","artist":null,"album":null,"uri":"https://air.unmixed.ru/radioc128","trackType":""} Jul 08 09:27:01 volumio volumio[5866]: verbose: CURRENT POSITION 0 Jul 08 09:27:01 volumio volumio[5866]: info: CoreStateMachine::syncState stateService play Jul 08 09:27:01 volumio volumio[5866]: info: CoreStateMachine::syncState currentStatus play Jul 08 09:27:01 volumio volumio[5866]: info: Received an update from plugin. extracting info from payload Jul 08 09:27:01 volumio volumio[5866]: info: CoreStateMachine::pushState Jul 08 09:27:01 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:01 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 09:27:01 volumio volumio[5866]: info: CoreCommandRouter::volumioPushState Jul 08 09:27:01 volumio volumio[5866]: info: CoreStateMachine::pushState Jul 08 09:27:01 volumio volumio[5866]: info: CoreCommandRouter::volumioPushState Jul 08 09:27:01 volumio volumio[5866]: info: ------------------------------ 33ms Jul 08 09:27:01 volumio volumio[5866]: info: Jul 08 09:27:01 volumio volumio[5866]: ---------------------------- MPD announces system playlist update Jul 08 09:27:01 volumio volumio[5866]: info: Ignoring MPD Status Update Jul 08 09:27:01 volumio volumio[5866]: info: Jul 08 09:27:01 volumio volumio[5866]: ---------------------------- MPD announces state update: player Jul 08 09:27:01 volumio volumio[5866]: info: ControllerMpd::getState Jul 08 09:27:01 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand status Jul 08 09:27:01 volumio volumio[5866]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! RadioC Jul 08 09:27:01 volumio volumio[5866]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! RadioC Jul 08 09:27:01 volumio volumio[5866]: info: PeppyMeterBasic ---peppymeterbasic status play Jul 08 09:27:01 volumio volumio[5866]: info: PeppyMeterBasic ---peppymeterbasic status play Jul 08 09:27:01 volumio sudo[6203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jul 08 09:27:01 volumio sudo[6203]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 09:27:02 volumio volumio[5866]: info: MYVOLUMIO: Adding device Jul 08 09:27:02 volumio volumio[5866]: info: MYVOLUMIO: Evaluating Server Jul 08 09:27:02 volumio volumio[5866]: info: Jul 08 09:27:02 volumio volumio[5866]: ---------------------------- MPD announces system playlist update Jul 08 09:27:02 volumio volumio[5866]: info: Ignoring MPD Status Update Jul 08 09:27:02 volumio volumio[5866]: info: Jul 08 09:27:02 volumio volumio[5866]: ---------------------------- MPD announces state update: player Jul 08 09:27:02 volumio volumio[5866]: info: ControllerMpd::getState Jul 08 09:27:02 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand status Jul 08 09:27:02 volumio volumio[5866]: info: ------------------------------ 62ms Jul 08 09:27:02 volumio volumio[5866]: info: sendMpdCommand status took 61 milliseconds Jul 08 09:27:02 volumio sudo[6207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jul 08 09:27:02 volumio sudo[6207]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 09:27:02 volumio systemd[1]: Started peppymeterbasic Daemon. Jul 08 09:27:02 volumio volumio[5866]: verbose: ControllerMpd::parseState Jul 08 09:27:02 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 09:27:02 volumio volumio[5866]: info: Preload queue cleared Jul 08 09:27:02 volumio volumio[5866]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 08 09:27:02 volumio volumio[5866]: info: CoreStateMachine::ClearQueue Jul 08 09:27:02 volumio volumio[5866]: info: CoreStateMachine::stop Jul 08 09:27:02 volumio volumio[5866]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 09:27:02 volumio volumio[5866]: info: CoreStateMachine::stPlaybackTimer Jul 08 09:27:02 volumio volumio[5866]: info: CoreStateMachine::updateTrackBlock Jul 08 09:27:02 volumio volumio[5866]: info: CorePlayQueue::getTrackBlock Jul 08 09:27:02 volumio volumio[5866]: info: CoreStateMachine::pushState Jul 08 09:27:02 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:02 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 09:27:02 volumio volumio[5866]: info: CoreCommandRouter::volumioPushState Jul 08 09:27:02 volumio volumio[5866]: info: CoreStateMachine::serviceStop Jul 08 09:27:02 volumio sudo[6207]: pam_unix(sudo:session): session closed for user root Jul 08 09:27:02 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:02 volumio sudo[6203]: pam_unix(sudo:session): session closed for user root Jul 08 09:27:02 volumio volumio[5866]: info: CoreCommandRouter::serviceStop Jul 08 09:27:02 volumio volumio[5866]: info: [1751948822037] ControllerWebradio::stop Jul 08 09:27:02 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 09:27:02 volumio volumio[5866]: info: CorePlayQueue::clearPlayQueue Jul 08 09:27:02 volumio volumio[5866]: info: CorePlayQueue::saveQueue Jul 08 09:27:02 volumio volumio[5866]: info: CoreCommandRouter::volumioPushQueue Jul 08 09:27:02 volumio volumio[5866]: info: CoreStateMachine::addQueueItems Jul 08 09:27:02 volumio volumio[5866]: info: CorePlayQueue::addQueueItems Jul 08 09:27:02 volumio volumio[5866]: info: Preload queue cleared Jul 08 09:27:02 volumio volumio[5866]: info: Adding Item to queue: webeighties/2 Jul 08 09:27:02 volumio volumio[5866]: info: Exploding uri webeighties/2 in service 80s80s Jul 08 09:27:02 volumio volumio[5866]: info: [1751948822042] [80s80s] explodeUri: webeighties/2 Jul 08 09:27:02 volumio volumio[5866]: info: CoreCommandRouter::volumioPushQueue Jul 08 09:27:02 volumio volumio[5866]: info: CorePlayQueue::saveQueue Jul 08 09:27:02 volumio volumio[5866]: info: CoreStateMachine::updateTrackBlock Jul 08 09:27:02 volumio volumio[5866]: info: CorePlayQueue::getTrackBlock Jul 08 09:27:02 volumio volumio[5866]: info: CoreCommandRouter::volumioPlay Jul 08 09:27:02 volumio volumio[5866]: info: CoreStateMachine::play index 0 Jul 08 09:27:02 volumio volumio[5866]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 09:27:02 volumio volumio[5866]: info: CoreStateMachine::stop Jul 08 09:27:02 volumio volumio[5866]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 09:27:02 volumio volumio[5866]: info: CoreStateMachine::play index undefined Jul 08 09:27:02 volumio volumio[5866]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 09:27:02 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:02 volumio volumio[5866]: info: CoreStateMachine::startPlaybackTimer Jul 08 09:27:02 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:02 volumio volumio[5866]: info: [1751948822068] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/love/mp3-192/volumio Jul 08 09:27:02 volumio volumio[5866]: info: [1751948822069] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=85&count=2 Jul 08 09:27:02 volumio volumio[6206]: Traceback (most recent call last): Jul 08 09:27:02 volumio volumio[6206]: File "peppymeter.py", line 18, in Jul 08 09:27:02 volumio volumio[6206]: import pygame Jul 08 09:27:02 volumio volumio[6206]: ModuleNotFoundError: No module named 'pygame' Jul 08 09:27:02 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=exited, status=1/FAILURE Jul 08 09:27:02 volumio volumio[5866]: info: Jul 08 09:27:02 volumio volumio[5866]: ---------------------------- MPD announces state update: player Jul 08 09:27:02 volumio systemd[1]: peppymeterbasic.service: Failed with result 'exit-code'. Jul 08 09:27:02 volumio volumio[5866]: info: ------------------------------ 66ms Jul 08 09:27:02 volumio volumio[5866]: info: sendMpdCommand status took 64 milliseconds Jul 08 09:27:02 volumio volumio[5866]: info: sendMpdCommand playlistinfo took 62 milliseconds Jul 08 09:27:02 volumio volumio[5866]: info: sendMpdCommand stop took 40 milliseconds Jul 08 09:27:02 volumio volumio[5866]: info: ControllerMpd::getState Jul 08 09:27:02 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand status Jul 08 09:27:02 volumio volumio[5866]: verbose: ControllerMpd::parseState Jul 08 09:27:02 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 09:27:02 volumio volumio[5866]: verbose: ControllerMpd::parseTrackInfo Jul 08 09:27:02 volumio volumio[5866]: info: ControllerMpd::pushState Jul 08 09:27:02 volumio volumio[5866]: info: CoreCommandRouter::servicePushState Jul 08 09:27:02 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:02 volumio volumio[5866]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 08 09:27:02 volumio volumio[5866]: info: ------------------------------ 129ms Jul 08 09:27:02 volumio volumio[5866]: info: PeppyMeterBasic ---peppymeterbasic status stop Jul 08 09:27:02 volumio volumio[5866]: info: peppymeterbasic Daemon Started Jul 08 09:27:02 volumio volumio[5866]: info: peppymeterbasic Daemon Started Jul 08 09:27:02 volumio volumio[5866]: info: sendMpdCommand status took 28 milliseconds Jul 08 09:27:02 volumio volumio[5866]: info: sendMpdCommand playlistinfo took 28 milliseconds Jul 08 09:27:02 volumio volumio[5866]: verbose: ControllerMpd::parseState Jul 08 09:27:02 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 09:27:02 volumio volumio[5866]: verbose: ControllerMpd::parseTrackInfo Jul 08 09:27:02 volumio volumio[5866]: info: ControllerMpd::pushState Jul 08 09:27:02 volumio volumio[5866]: info: CoreCommandRouter::servicePushState Jul 08 09:27:02 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:02 volumio volumio[5866]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 08 09:27:02 volumio volumio[5866]: info: ------------------------------ 96ms Jul 08 09:27:02 volumio sudo[6211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jul 08 09:27:02 volumio volumio[5866]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 08 09:27:02 volumio volumio[5866]: verbose: ControllerMpd::parseTrackInfo Jul 08 09:27:02 volumio volumio[5866]: info: ControllerMpd::pushState Jul 08 09:27:02 volumio volumio[5866]: info: CoreCommandRouter::servicePushState Jul 08 09:27:02 volumio volumio[5866]: info: CoreStateMachine::pushState Jul 08 09:27:02 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:02 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 09:27:02 volumio sudo[6211]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 09:27:02 volumio volumio[5866]: info: CoreCommandRouter::volumioPushState Jul 08 09:27:02 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:02 volumio volumio[5866]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 08 09:27:02 volumio volumio[5866]: info: ------------------------------ 52ms Jul 08 09:27:02 volumio sudo[6211]: pam_unix(sudo:session): session closed for user root Jul 08 09:27:02 volumio volumio[5866]: info: PeppyMeterBasic ---peppymeterbasic status stop Jul 08 09:27:02 volumio volumio[5866]: info: peppymeterbasic Daemon Stop Jul 08 09:27:02 volumio sudo[6214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jul 08 09:27:02 volumio sudo[6214]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 09:27:02 volumio sudo[6214]: pam_unix(sudo:session): session closed for user root Jul 08 09:27:02 volumio volumio[5866]: info: peppymeterbasic Daemon Stop Jul 08 09:27:02 volumio volumio[5866]: info: Setting Geolocation for MyVolumio to eu7 Jul 08 09:27:02 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 09:27:02 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 09:27:02 volumio volumio[5866]: info: [1751948822686] [80s80s] received new event containing 2 songs. Jul 08 09:27:02 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 09:27:02 volumio volumio[5866]: info: sendMpdCommand stop took 2 milliseconds Jul 08 09:27:02 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand clear Jul 08 09:27:02 volumio volumio[5866]: info: Jul 08 09:27:02 volumio volumio[5866]: ---------------------------- MPD announces system playlist update Jul 08 09:27:02 volumio volumio[5866]: info: Ignoring MPD Status Update Jul 08 09:27:02 volumio volumio[5866]: info: sendMpdCommand clear took 2 milliseconds Jul 08 09:27:02 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 08 09:27:02 volumio volumio[5866]: error: updateQueue error: null Jul 08 09:27:02 volumio volumio[5866]: info: Jul 08 09:27:02 volumio volumio[5866]: ---------------------------- MPD announces state update: options Jul 08 09:27:02 volumio volumio[5866]: info: ------------------------------ 3ms Jul 08 09:27:02 volumio volumio[5866]: info: sendMpdCommand consume 1 took 2 milliseconds Jul 08 09:27:02 volumio volumio[5866]: info: ControllerMpd::getState Jul 08 09:27:02 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand status Jul 08 09:27:02 volumio volumio[5866]: info: [1751948822694] [80s80s] adding url: http://streams.80s80s.de/love/mp3-192/volumio Jul 08 09:27:02 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/love/mp3-192/volumio" Jul 08 09:27:02 volumio volumio[5866]: info: Jul 08 09:27:02 volumio volumio[5866]: ---------------------------- MPD announces system playlist update Jul 08 09:27:02 volumio volumio[5866]: info: Ignoring MPD Status Update Jul 08 09:27:02 volumio volumio[5866]: info: sendMpdCommand status took 2 milliseconds Jul 08 09:27:02 volumio volumio[5866]: info: sendMpdCommand add "http://streams.80s80s.de/love/mp3-192/volumio" took 2 milliseconds Jul 08 09:27:02 volumio volumio[5866]: verbose: ControllerMpd::parseState Jul 08 09:27:02 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand play Jul 08 09:27:02 volumio volumio[5866]: info: ControllerMpd::pushState Jul 08 09:27:02 volumio volumio[5866]: info: CoreCommandRouter::servicePushState Jul 08 09:27:02 volumio volumio[5866]: info: CoreStateMachine::pushState Jul 08 09:27:02 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:02 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 09:27:02 volumio volumio[5866]: info: CoreCommandRouter::volumioPushState Jul 08 09:27:02 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:02 volumio volumio[5866]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 08 09:27:02 volumio volumio[5866]: info: ------------------------------ 19ms Jul 08 09:27:02 volumio volumio[5866]: info: ------------------------------ 18ms Jul 08 09:27:02 volumio volumio[5866]: info: sendMpdCommand play took 12 milliseconds Jul 08 09:27:02 volumio volumio[5866]: info: [1751948822714] [80s80s] Pushing the next song state: Peabo Bryson & Roberta Flack - Tonight, I Celebrate My Love Jul 08 09:27:02 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:02 volumio volumio[5866]: info: CoreCommandRouter::servicePushState Jul 08 09:27:02 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:02 volumio volumio[5866]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music124/v4/9d/dd/53/9ddd5306-bb23-f192-d523-c1a79378c6cc/source/600x600bb.jpg","name":"Peabo Bryson & Roberta Flack - Tonight, I Celebrate My Love","title":"Tonight, I Celebrate My Love","artist":"80s80s Love","album":"","streaming":true,"disableUiControls":true,"duration":"201","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 08 09:27:02 volumio volumio[5866]: verbose: CURRENT POSITION 0 Jul 08 09:27:02 volumio volumio[5866]: info: CoreStateMachine::syncState stateService play Jul 08 09:27:02 volumio volumio[5866]: info: CoreStateMachine::syncState currentStatus stop Jul 08 09:27:02 volumio volumio[5866]: info: [1751948822716] [80s80s] PlayNextTrack API delay: 30 Jul 08 09:27:02 volumio volumio[5866]: info: [1751948822725] [80s80s] Setting timer to: 201000 milliseconds. Jul 08 09:27:02 volumio volumio[5866]: info: PeppyMeterBasic ---peppymeterbasic status stop Jul 08 09:27:02 volumio sudo[6233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jul 08 09:27:02 volumio sudo[6233]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 09:27:02 volumio sudo[6233]: pam_unix(sudo:session): session closed for user root Jul 08 09:27:02 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 08 09:27:02 volumio volumio[5866]: info: peppymeterbasic Daemon Stop Jul 08 09:27:03 volumio volumio[5866]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 08 09:27:03 volumio volumio[5866]: info: Updating MyVolumio device info Jul 08 09:27:03 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 09:27:03 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 09:27:03 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 08 09:27:04 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:27:04 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:04 volumio volumio[5866]: info: Listing playlists Jul 08 09:27:04 volumio volumio[5866]: info: Listing playlists Jul 08 09:27:04 volumio volumio[5866]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 08 09:27:05 volumio dbus-daemon[861]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Jul 08 09:27:05 volumio volumio-time-update[866]: Failed to set time: Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Jul 08 09:27:05 volumio sudo[6063]: pam_unix(sudo:session): session closed for user root Jul 08 09:27:05 volumio volumio-time-update[866]: volumio-time-update-util: Error setting time. Jul 08 09:27:05 volumio volumio-time-update[866]: volumio-time-update-util: Retrying in 5 seconds... Jul 08 09:27:05 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:27:05 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:05 volumio volumio[5866]: info: Listing playlists Jul 08 09:27:05 volumio volumio[5866]: info: Listing playlists Jul 08 09:27:05 volumio volumio[5866]: info: Jul 08 09:27:05 volumio volumio[5866]: ---------------------------- MPD announces state update: player Jul 08 09:27:05 volumio volumio[5866]: info: ControllerMpd::getState Jul 08 09:27:05 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand status Jul 08 09:27:05 volumio volumio[5866]: info: Jul 08 09:27:05 volumio volumio[5866]: ---------------------------- MPD announces state update: player Jul 08 09:27:05 volumio volumio[5866]: info: sendMpdCommand status took 5 milliseconds Jul 08 09:27:05 volumio volumio[5866]: info: ControllerMpd::getState Jul 08 09:27:05 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand status Jul 08 09:27:05 volumio volumio[5866]: verbose: ControllerMpd::parseState Jul 08 09:27:05 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 09:27:05 volumio volumio[5866]: info: sendMpdCommand status took 2 milliseconds Jul 08 09:27:05 volumio volumio[5866]: verbose: ControllerMpd::parseState Jul 08 09:27:05 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 09:27:05 volumio volumio[5866]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 08 09:27:05 volumio volumio[5866]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 08 09:27:05 volumio volumio[5866]: verbose: ControllerMpd::parseTrackInfo Jul 08 09:27:05 volumio volumio[5866]: verbose: ControllerMpd::parseTrackInfo Jul 08 09:27:05 volumio volumio[5866]: info: ControllerMpd::pushState Jul 08 09:27:05 volumio volumio[5866]: info: CoreCommandRouter::servicePushState Jul 08 09:27:05 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:05 volumio volumio[5866]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 08 09:27:05 volumio volumio[5866]: info: ControllerMpd::pushState Jul 08 09:27:05 volumio volumio[5866]: info: CoreCommandRouter::servicePushState Jul 08 09:27:05 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:05 volumio volumio[5866]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 08 09:27:05 volumio volumio[5866]: info: ------------------------------ 13ms Jul 08 09:27:05 volumio volumio[5866]: info: ------------------------------ 7ms Jul 08 09:27:05 volumio volumio[5866]: info: Jul 08 09:27:05 volumio volumio[5866]: ---------------------------- MPD announces system playlist update Jul 08 09:27:05 volumio volumio[5866]: info: Ignoring MPD Status Update Jul 08 09:27:05 volumio volumio[5866]: info: Jul 08 09:27:05 volumio volumio[5866]: ---------------------------- MPD announces state update: player Jul 08 09:27:05 volumio volumio[5866]: info: ControllerMpd::getState Jul 08 09:27:05 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand status Jul 08 09:27:05 volumio volumio[5866]: info: ------------------------------ 4ms Jul 08 09:27:05 volumio volumio[5866]: info: sendMpdCommand status took 2 milliseconds Jul 08 09:27:05 volumio volumio[5866]: verbose: ControllerMpd::parseState Jul 08 09:27:05 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 09:27:05 volumio volumio[5866]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 08 09:27:05 volumio volumio[5866]: verbose: ControllerMpd::parseTrackInfo Jul 08 09:27:05 volumio volumio[5866]: info: ControllerMpd::pushState Jul 08 09:27:05 volumio volumio[5866]: info: CoreCommandRouter::servicePushState Jul 08 09:27:05 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:05 volumio volumio[5866]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 08 09:27:05 volumio volumio[5866]: info: ------------------------------ 4ms Jul 08 09:27:08 volumio volumio[5866]: info: Jul 08 09:27:08 volumio volumio[5866]: ---------------------------- MPD announces system playlist update Jul 08 09:27:08 volumio volumio[5866]: info: Ignoring MPD Status Update Jul 08 09:27:08 volumio volumio[5866]: info: Jul 08 09:27:08 volumio volumio[5866]: ---------------------------- MPD announces state update: player Jul 08 09:27:08 volumio volumio[5866]: info: ControllerMpd::getState Jul 08 09:27:08 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand status Jul 08 09:27:08 volumio volumio[5866]: info: ------------------------------ 4ms Jul 08 09:27:08 volumio volumio[5866]: info: sendMpdCommand status took 2 milliseconds Jul 08 09:27:08 volumio volumio[5866]: verbose: ControllerMpd::parseState Jul 08 09:27:08 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 09:27:08 volumio volumio[5866]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 08 09:27:08 volumio volumio[5866]: verbose: ControllerMpd::parseTrackInfo Jul 08 09:27:08 volumio volumio[5866]: info: ControllerMpd::pushState Jul 08 09:27:08 volumio volumio[5866]: info: CoreCommandRouter::servicePushState Jul 08 09:27:08 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:08 volumio volumio[5866]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 08 09:27:08 volumio volumio[5866]: info: ------------------------------ 7ms Jul 08 09:27:09 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 08 09:27:09 volumio volumio[5866]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Jul 08 09:27:09 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:27:09 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:09 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 08 09:27:09 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 09:27:09 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 08 09:27:10 volumio volumio-remote-updater[870]: No test mode Jul 08 09:27:10 volumio volumio-remote-updater[870]: No alpha test mode Jul 08 09:27:10 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 08 09:27:10 volumio volumio[5866]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 08 09:27:10 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 08 09:27:10 volumio volumio-time-update[866]: volumio-time-update-util: Fetching time from Volumio... Jul 08 09:27:10 volumio volumio-time-update[866]: volumio-time-update-util: Setting system time to: 2025-07-08 09:27:16 Jul 08 09:27:10 volumio sudo[6271]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-07-08 09:27:16 Jul 08 09:27:10 volumio sudo[6271]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 09:27:10 volumio dbus-daemon[861]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.55' (uid=0 pid=6272 comm="timedatectl set-time 2025-07-08 09:27:16 ") Jul 08 09:27:10 volumio systemd[1]: systemd-timedated.service: Failed to run 'start' task: No space left on device Jul 08 09:27:10 volumio systemd[1]: systemd-timedated.service: Failed with result 'resources'. Jul 08 09:27:10 volumio systemd[1]: Failed to start Time & Date Service. Jul 08 09:27:11 volumio volumio[5866]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 09:27:11 volumio volumio[5866]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 09:27:11 volumio volumio[5866]: info: Retrieving Cloud Streaming UI Jul 08 09:27:11 volumio volumio[5866]: info: Getting Tidal Cloud Configuration Jul 08 09:27:11 volumio volumio[5866]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 09:27:11 volumio volumio[5866]: info: Getting Qobuz Cloud Configuration Jul 08 09:27:11 volumio volumio[5866]: info: Asking plugin for UI Config Jul 08 09:27:11 volumio volumio[5866]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 09:27:11 volumio volumio[5866]: info: Getting Spotify Cloud Configuration Jul 08 09:27:11 volumio volumio[5866]: info: Asking plugin for UI Config Jul 08 09:27:11 volumio volumio[5866]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 09:27:11 volumio volumio[5866]: info: Saving Spotify Acccount Jul 08 09:27:11 volumio volumio[5866]: info: Got Tidal Cloud Configuration Jul 08 09:27:11 volumio volumio[5866]: info: Got it Jul 08 09:27:11 volumio volumio[5866]: info: Got it Jul 08 09:27:11 volumio volumio[5866]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 08 09:27:11 volumio volumio[5866]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 09:27:11 volumio volumio[5866]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 08 09:27:11 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 08 09:27:11 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 08 09:27:11 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 08 09:27:11 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 08 09:27:11 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 09:27:11 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 09:27:11 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 09:27:11 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 09:27:11 volumio volumio[5866]: info: CoreCommandRouter::volumioGetBrowseSources Jul 08 09:27:11 volumio volumio[5866]: info: CoreCommandRouter::volumioGetBrowseSources Jul 08 09:27:11 volumio volumio[5866]: info: CoreCommandRouter::volumioGetBrowseSources Jul 08 09:27:11 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 08 09:27:11 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 08 09:27:12 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 09:27:12 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 09:27:12 volumio volumio[5866]: info: Discovery: Getting this device information Jul 08 09:27:12 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:27:12 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:12 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 09:27:14 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:27:14 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:14 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 09:27:14 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 09:27:14 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 08 09:27:14 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 08 09:27:14 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 08 09:27:14 volumio volumio[5866]: info: CoreCommandRouter::volumioGetBrowseSources Jul 08 09:27:14 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 08 09:27:14 volumio volumio[5866]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jul 08 09:27:14 volumio volumio[5866]: info: Completed starting MyVolumio Plugin Jul 08 09:27:14 volumio volumio[5866]: [Metrics] CommandRouter: 40s 14.27ms Jul 08 09:27:14 volumio volumio[5866]: info: CoreCommandRouter::volumiosetStartupVolume Jul 08 09:27:14 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 09:27:14 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 09:27:14 volumio volumio[5866]: info: CoreCommandRouter::Close All Modals sent Jul 08 09:27:14 volumio volumio[5866]: info: CoreCommandRouter::Close All Modals sent Jul 08 09:27:14 volumio volumio[5866]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jul 08 09:27:14 volumio volumio[5866]: aplay: main:828: audio open error: Device or resource busy Jul 08 09:27:15 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 08 09:27:15 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 08 09:27:15 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 08 09:27:15 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 08 09:27:15 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:27:15 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:19 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 08 09:27:21 volumio volumio[5866]: info: BOOT COMPLETED Jul 08 09:27:23 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 08 09:27:24 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:27:24 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:24 volumio volumio[5866]: info: Listing playlists Jul 08 09:27:24 volumio volumio[5866]: info: Listing playlists Jul 08 09:27:24 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jul 08 09:27:24 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand rescan Jul 08 09:27:24 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand status Jul 08 09:27:24 volumio volumio[5866]: info: Jul 08 09:27:24 volumio volumio[5866]: ---------------------------- MPD announces state update: update Jul 08 09:27:24 volumio volumio[5866]: info: sendMpdCommand rescan took 6 milliseconds Jul 08 09:27:24 volumio volumio[5866]: info: ControllerMpd::getState Jul 08 09:27:24 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand status Jul 08 09:27:24 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand status Jul 08 09:27:24 volumio volumio[5866]: info: Jul 08 09:27:24 volumio volumio[5866]: ---------------------------- MPD announces state update: update Jul 08 09:27:24 volumio volumio[5866]: info: ControllerMpd::getState Jul 08 09:27:24 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand status Jul 08 09:27:24 volumio volumio[5866]: info: sendMpdCommand status took 4 milliseconds Jul 08 09:27:24 volumio volumio[5866]: info: sendMpdCommand status took 2 milliseconds Jul 08 09:27:24 volumio volumio[5866]: verbose: ControllerMpd::parseState Jul 08 09:27:24 volumio volumio[5866]: info: Command Router : Notfying DB Updatefalse Jul 08 09:27:24 volumio volumio[5866]: info: CoreCommandRouter::Close All Modals sent Jul 08 09:27:24 volumio volumio[5866]: verbose: ControllerMpd::parseState Jul 08 09:27:24 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 09:27:24 volumio volumio[5866]: info: sendMpdCommand status took 64 milliseconds Jul 08 09:27:24 volumio volumio[5866]: info: sendMpdCommand status took 65 milliseconds Jul 08 09:27:24 volumio volumio[5866]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 08 09:27:24 volumio volumio[5866]: verbose: ControllerMpd::parseState Jul 08 09:27:24 volumio volumio[5866]: info: Command Router : Notfying DB Updatefalse Jul 08 09:27:24 volumio volumio[5866]: info: CoreCommandRouter::Close All Modals sent Jul 08 09:27:24 volumio volumio[5866]: verbose: ControllerMpd::parseState Jul 08 09:27:24 volumio volumio[5866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 09:27:24 volumio volumio[5866]: verbose: ControllerMpd::parseTrackInfo Jul 08 09:27:24 volumio volumio[5866]: info: ControllerMpd::pushState Jul 08 09:27:24 volumio volumio[5866]: info: CoreCommandRouter::servicePushState Jul 08 09:27:24 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:24 volumio volumio[5866]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 08 09:27:24 volumio volumio[5866]: info: ------------------------------ 98ms Jul 08 09:27:24 volumio volumio[5866]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 08 09:27:24 volumio volumio[5866]: verbose: ControllerMpd::parseTrackInfo Jul 08 09:27:24 volumio volumio[5866]: info: ControllerMpd::pushState Jul 08 09:27:24 volumio volumio[5866]: info: CoreCommandRouter::servicePushState Jul 08 09:27:24 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:24 volumio volumio[5866]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 08 09:27:24 volumio volumio[5866]: info: ------------------------------ 102ms Jul 08 09:27:25 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:27:25 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:25 volumio volumio[5866]: info: Listing playlists Jul 08 09:27:25 volumio volumio[5866]: info: Listing playlists Jul 08 09:27:27 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 08 09:27:31 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 08 09:27:34 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:27:34 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:34 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 09:27:34 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 09:27:34 volumio volumio[5866]: info: Discovery: Getting this device information Jul 08 09:27:34 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:27:34 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:34 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 09:27:35 volumio volumio[5866]: verbose: New Socket.io Connection to 192.168.0.8:3000 from 192.168.0.219 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jul 08 09:27:35 volumio volumio[5866]: verbose: New Socket.io Connection to 192.168.0.10:3000 from 192.168.0.219 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jul 08 09:27:35 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:27:35 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:35 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 08 09:27:36 volumio dbus-daemon[861]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Jul 08 09:27:36 volumio volumio-time-update[866]: Failed to set time: Connection timed out Jul 08 09:27:36 volumio sudo[6271]: pam_unix(sudo:session): session closed for user root Jul 08 09:27:36 volumio volumio-time-update[866]: volumio-time-update-util: Error setting time. Jul 08 09:27:36 volumio volumio-time-update[866]: volumio-time-update-util: Retrying in 5 seconds... Jul 08 09:27:36 volumio volumio[5866]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.219 UA: Mozilla/5.0 (Linux; Android 12; M2007J20CG Build/SKQ1.211019.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jul 08 09:27:36 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:27:36 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:36 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 08 09:27:36 volumio volumio[5866]: info: Listing playlists Jul 08 09:27:36 volumio volumio[5866]: info: CoreCommandRouter::volumioGetVisibleSources Jul 08 09:27:36 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 08 09:27:36 volumio volumio[5866]: info: CoreCommandRouter::volumioGetQueue Jul 08 09:27:36 volumio volumio[5866]: info: CoreStateMachine::getQueue Jul 08 09:27:36 volumio volumio[5866]: info: CorePlayQueue::getQueue Jul 08 09:27:36 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:27:36 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:36 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 08 09:27:36 volumio volumio[5866]: info: Listing playlists Jul 08 09:27:36 volumio volumio[5866]: info: CoreCommandRouter::volumioGetVisibleSources Jul 08 09:27:36 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 08 09:27:36 volumio volumio[5866]: info: CoreCommandRouter::volumioGetQueue Jul 08 09:27:36 volumio volumio[5866]: info: CoreStateMachine::getQueue Jul 08 09:27:36 volumio volumio[5866]: info: CorePlayQueue::getQueue Jul 08 09:27:36 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:27:36 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:36 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 08 09:27:36 volumio volumio[5866]: info: Listing playlists Jul 08 09:27:36 volumio volumio[5866]: info: CoreCommandRouter::volumioGetVisibleSources Jul 08 09:27:36 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 08 09:27:36 volumio volumio[5866]: info: CoreCommandRouter::volumioGetQueue Jul 08 09:27:36 volumio volumio[5866]: info: CoreStateMachine::getQueue Jul 08 09:27:36 volumio volumio[5866]: info: CorePlayQueue::getQueue Jul 08 09:27:37 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 08 09:27:37 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jul 08 09:27:37 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jul 08 09:27:39 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 08 09:27:41 volumio volumio-time-update[866]: volumio-time-update-util: Fetching time from Volumio... Jul 08 09:27:41 volumio volumio-time-update[866]: volumio-time-update-util: Setting system time to: 2025-07-08 09:27:47 Jul 08 09:27:41 volumio sudo[6411]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-07-08 09:27:47 Jul 08 09:27:41 volumio sudo[6411]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 09:27:41 volumio dbus-daemon[861]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.59' (uid=0 pid=6412 comm="timedatectl set-time 2025-07-08 09:27:47 ") Jul 08 09:27:41 volumio systemd[1]: systemd-timedated.service: Failed to run 'start' task: No space left on device Jul 08 09:27:41 volumio systemd[1]: systemd-timedated.service: Failed with result 'resources'. Jul 08 09:27:41 volumio systemd[1]: Failed to start Time & Date Service. Jul 08 09:27:43 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 08 09:27:44 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:27:44 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:44 volumio volumio[5866]: info: Listing playlists Jul 08 09:27:45 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:27:45 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:45 volumio volumio[5866]: info: Listing playlists Jul 08 09:27:45 volumio volumio[5866]: info: Listing playlists Jul 08 09:27:46 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Jul 08 09:27:46 volumio volumio[5866]: info: Adding a new share Jul 08 09:27:46 volumio volumio[5866]: info: Share alias is blank Jul 08 09:27:47 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 08 09:27:51 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 08 09:27:54 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:27:54 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:55 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:27:55 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:27:55 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 08 09:27:59 volumio volumio[5866]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 09:27:59 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 08 09:27:59 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jul 08 09:27:59 volumio sudo[6470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jul 08 09:27:59 volumio sudo[6470]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 09:27:59 volumio sudo[6476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 08 09:27:59 volumio sudo[6476]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 09:27:59 volumio sudo[6470]: pam_unix(sudo:session): session closed for user root Jul 08 09:27:59 volumio sudo[6476]: pam_unix(sudo:session): session closed for user root Jul 08 09:27:59 volumio sudo[6485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 08 09:27:59 volumio sudo[6485]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 09:27:59 volumio sudo[6485]: pam_unix(sudo:session): session closed for user root Jul 08 09:27:59 volumio sudo[6490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 08 09:27:59 volumio sudo[6490]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 09:27:59 volumio sudo[6490]: pam_unix(sudo:session): session closed for user root Jul 08 09:27:59 volumio sudo[6496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 08 09:27:59 volumio sudo[6496]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 09:27:59 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jul 08 09:27:59 volumio sudo[6496]: pam_unix(sudo:session): session closed for user root Jul 08 09:27:59 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jul 08 09:27:59 volumio sudo[6499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 08 09:27:59 volumio sudo[6499]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 09:27:59 volumio sudo[6499]: pam_unix(sudo:session): session closed for user root Jul 08 09:27:59 volumio sudo[6502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 08 09:27:59 volumio sudo[6502]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 09:28:00 volumio kernel: rtw_8821ce 0000:03:00.0: timed out to flush queue 2 Jul 08 09:28:01 volumio kernel: rtw_8821ce 0000:03:00.0: timed out to flush queue 2 Jul 08 09:28:04 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:28:04 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:28:04 volumio volumio[5866]: info: Listing playlists Jul 08 09:28:04 volumio volumio[5866]: info: Listing playlists Jul 08 09:28:05 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:28:05 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:28:05 volumio volumio[5866]: info: Listing playlists Jul 08 09:28:05 volumio volumio[5866]: info: Listing playlists Jul 08 09:28:05 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 09:28:05 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 09:28:05 volumio volumio[5866]: info: Discovery: Getting this device information Jul 08 09:28:05 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:28:05 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:28:05 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 09:28:06 volumio volumio-time-update[866]: Failed to set time: Connection timed out Jul 08 09:28:06 volumio dbus-daemon[861]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Jul 08 09:28:06 volumio sudo[6411]: pam_unix(sudo:session): session closed for user root Jul 08 09:28:06 volumio volumio-time-update[866]: volumio-time-update-util: Error setting time. Jul 08 09:28:06 volumio volumio-time-update[866]: volumio-time-update-util: Retrying in 5 seconds... Jul 08 09:28:07 volumio volumio[5866]: verbose: New Socket.io Connection to 192.168.0.10:3000 from 192.168.0.219 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jul 08 09:28:08 volumio volumio[5866]: verbose: New Socket.io Connection to 192.168.0.8:3000 from 192.168.0.219 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jul 08 09:28:08 volumio volumio[5866]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.219 UA: Mozilla/5.0 (Linux; Android 12; M2007J20CG Build/SKQ1.211019.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jul 08 09:28:08 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:28:08 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:28:08 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 08 09:28:08 volumio volumio[5866]: info: Listing playlists Jul 08 09:28:08 volumio volumio[5866]: info: CoreCommandRouter::volumioGetVisibleSources Jul 08 09:28:08 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 08 09:28:08 volumio volumio[5866]: info: CoreCommandRouter::volumioGetQueue Jul 08 09:28:08 volumio volumio[5866]: info: CoreStateMachine::getQueue Jul 08 09:28:08 volumio volumio[5866]: info: CorePlayQueue::getQueue Jul 08 09:28:08 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:28:08 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:28:08 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 08 09:28:08 volumio volumio[5866]: info: Listing playlists Jul 08 09:28:08 volumio volumio[5866]: info: CoreCommandRouter::volumioGetVisibleSources Jul 08 09:28:08 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 08 09:28:08 volumio volumio[5866]: info: CoreCommandRouter::volumioGetQueue Jul 08 09:28:08 volumio volumio[5866]: info: CoreStateMachine::getQueue Jul 08 09:28:08 volumio volumio[5866]: info: CorePlayQueue::getQueue Jul 08 09:28:08 volumio volumio[5866]: info: CoreCommandRouter::volumioGetState Jul 08 09:28:08 volumio volumio[5866]: info: CorePlayQueue::getTrack 0 Jul 08 09:28:08 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 08 09:28:08 volumio volumio[5866]: info: Listing playlists Jul 08 09:28:08 volumio volumio[5866]: info: CoreCommandRouter::volumioGetVisibleSources Jul 08 09:28:08 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 08 09:28:08 volumio volumio[5866]: info: CoreCommandRouter::volumioGetQueue Jul 08 09:28:08 volumio volumio[5866]: info: CoreStateMachine::getQueue Jul 08 09:28:08 volumio volumio[5866]: info: CorePlayQueue::getQueue Jul 08 09:28:09 volumio sudo[6502]: pam_unix(sudo:session): session closed for user root Jul 08 09:28:11 volumio volumio-time-update[866]: volumio-time-update-util: Fetching time from Volumio... Jul 08 09:28:11 volumio volumio-time-update[866]: volumio-time-update-util: Setting system time to: 2025-07-08 09:28:17 Jul 08 09:28:11 volumio sudo[6540]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-07-08 09:28:17 Jul 08 09:28:11 volumio sudo[6540]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 09:28:11 volumio dbus-daemon[861]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.60' (uid=0 pid=6541 comm="timedatectl set-time 2025-07-08 09:28:17 ") Jul 08 09:28:11 volumio systemd[1]: systemd-timedated.service: Failed to run 'start' task: No space left on device Jul 08 09:28:11 volumio systemd[1]: systemd-timedated.service: Failed with result 'resources'. Jul 08 09:28:11 volumio systemd[1]: Failed to start Time & Date Service. Jul 08 09:28:13 volumio volumio[5866]: info: CALLMETHOD: system_controller network saveDnsSettings [object Object] Jul 08 09:28:13 volumio volumio[5866]: info: CoreCommandRouter::executeOnPlugin: network , saveDnsSettings Jul 08 09:28:13 volumio volumio[5866]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 09:28:13 volumio volumio[5866]: ReferenceError: error is not defined Jul 08 09:28:13 volumio volumio[5866]: at /volumio/app/plugins/system_controller/network/index.js:866:58 Jul 08 09:28:13 volumio volumio[5866]: at /volumio/node_modules/graceful-fs/graceful-fs.js:144:16 Jul 08 09:28:13 volumio volumio[5866]: at fs.js:1509:7 Jul 08 09:28:13 volumio volumio[5866]: at FSReqCallback.oncomplete (fs.js:180:23) Jul 08 09:28:13 volumio volumio[5866]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 09:28:14 volumio sudo[6562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-08 09:27 Jul 08 09:28:14 volumio sudo[6562]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:41:06 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="097e6b8b481766e7a0f9527e4a34a29b"