Mar 25 04:43:02 volumio volumio[1230]: info: Clearing queue after UPNP request Mar 25 04:43:02 volumio volumio[1230]: info: CoreStateMachine::ClearQueue Mar 25 04:43:02 volumio volumio[1230]: info: CoreStateMachine::stop Mar 25 04:43:02 volumio volumio[1230]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 04:43:02 volumio volumio[1230]: info: CorePlayQueue::clearPlayQueue Mar 25 04:43:02 volumio volumio[1230]: info: CorePlayQueue::saveQueue Mar 25 04:43:02 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Mar 25 04:43:02 volumio volumio[1230]: info: CoreCommandRouter::volumioPushQueue Mar 25 04:43:02 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:02.703Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:37032 @ 0x2926cf0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 25 04:43:02 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:02.703Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:37032 @ 0x2926cf0" id= title= Mar 25 04:43:02 volumio volumio[1230]: info: Mar 25 04:43:02 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Mar 25 04:43:02 volumio volumio[1230]: info: Ignoring MPD Status Update Mar 25 04:43:02 volumio volumio[1230]: error: updateQueue error: null Mar 25 04:43:02 volumio volumio[1230]: info: ------------------------------ 1ms Mar 25 04:43:02 volumio volumio[1230]: info: Starting UPNP Playback Mar 25 04:43:02 volumio volumio[1230]: info: Preparing playback through UPNP Mar 25 04:43:02 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Mar 25 04:43:02 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Mar 25 04:43:02 volumio volumio[1230]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 04:43:03 volumio volumio[1230]: info: Mar 25 04:43:03 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Mar 25 04:43:03 volumio volumio[1230]: info: Ignoring MPD Status Update Mar 25 04:43:03 volumio volumio[1230]: info: Mar 25 04:43:03 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Mar 25 04:43:03 volumio volumio[1230]: info: Ignoring MPD Status Update Mar 25 04:43:03 volumio volumio[1230]: info: Mar 25 04:43:03 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Mar 25 04:43:03 volumio volumio[1230]: info: Ignoring MPD Status Update Mar 25 04:43:03 volumio volumio[1230]: info: ------------------------------ 2ms Mar 25 04:43:03 volumio volumio[1230]: info: ------------------------------ 1ms Mar 25 04:43:03 volumio volumio[1230]: info: ------------------------------ 2ms Mar 25 04:43:03 volumio volumio[1230]: info: Mar 25 04:43:03 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Mar 25 04:43:03 volumio volumio[1230]: info: Ignoring MPD Status Update Mar 25 04:43:03 volumio volumio[1230]: info: ------------------------------ 1ms Mar 25 04:43:03 volumio volumio[1230]: info: Mar 25 04:43:03 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Mar 25 04:43:03 volumio volumio[1230]: info: Ignoring MPD Status Update Mar 25 04:43:03 volumio volumio[1230]: info: ------------------------------ 0ms Mar 25 04:43:03 volumio volumio[1230]: info: Mar 25 04:43:03 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Mar 25 04:43:03 volumio volumio[1230]: info: Ignoring MPD Status Update Mar 25 04:43:03 volumio volumio[1230]: info: ------------------------------ 1ms Mar 25 04:43:03 volumio volumio[1230]: info: Starting UPNP Playback Mar 25 04:43:03 volumio volumio[1230]: info: Preparing playback through UPNP Mar 25 04:43:03 volumio volumio[1230]: info: Consume mode Mar 25 04:43:03 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Mar 25 04:43:03 volumio volumio[1230]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 04:43:10 volumio volumio[1230]: info: Mar 25 04:43:10 volumio volumio[1230]: ---------------------------- MPD announces state update: player Mar 25 04:43:10 volumio volumio[1230]: info: ControllerMpd::getState Mar 25 04:43:10 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand status Mar 25 04:43:10 volumio volumio[1230]: info: Mar 25 04:43:10 volumio volumio[1230]: ---------------------------- MPD announces state update: player Mar 25 04:43:10 volumio volumio[1230]: info: sendMpdCommand status took 5 milliseconds Mar 25 04:43:10 volumio volumio[1230]: info: ControllerMpd::getState Mar 25 04:43:10 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand status Mar 25 04:43:10 volumio volumio[1230]: verbose: ControllerMpd::parseState Mar 25 04:43:10 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 04:43:10 volumio volumio[1230]: info: sendMpdCommand status took 1 milliseconds Mar 25 04:43:10 volumio volumio[1230]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 25 04:43:10 volumio volumio[1230]: verbose: ControllerMpd::parseState Mar 25 04:43:10 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 04:43:10 volumio volumio[1230]: verbose: ControllerMpd::parseTrackInfo Mar 25 04:43:10 volumio volumio[1230]: info: ControllerMpd::pushState Mar 25 04:43:10 volumio volumio[1230]: info: CoreCommandRouter::servicePushState Mar 25 04:43:10 volumio volumio[1230]: verbose: In UPNP mode Mar 25 04:43:10 volumio volumio[1230]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":718,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tommy's Time","artist":"Flanagan · Coltrane · Burrell · Sulieman · Hayes","album":"The Cats","uri":"http://192.168.1.2:33511/b360af95d7e85b73c7a0d8a93387ff63/https---webdav-torbox-app-443-Flanagan-20-C2-B7-20Coltrane-20-C2-B7-20Burrell-20-C2-B7-20Sulieman-20-C2-B7-20Hayes-20-The-20Cats--20-1959-2C-202023--05--20Tommy-s-20Time-dsf","trackType":""} Mar 25 04:43:10 volumio volumio[1230]: verbose: CURRENT POSITION 0 Mar 25 04:43:10 volumio volumio[1230]: info: CoreStateMachine::syncState stateService play Mar 25 04:43:10 volumio volumio[1230]: info: CoreStateMachine::syncState currentStatus stop Mar 25 04:43:10 volumio volumio[1230]: info: ------------------------------ 8ms Mar 25 04:43:10 volumio volumio[1230]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 25 04:43:10 volumio volumio[1230]: verbose: ControllerMpd::parseTrackInfo Mar 25 04:43:10 volumio volumio[1230]: info: ControllerMpd::pushState Mar 25 04:43:10 volumio volumio[1230]: info: CoreCommandRouter::servicePushState Mar 25 04:43:10 volumio volumio[1230]: verbose: In UPNP mode Mar 25 04:43:10 volumio volumio[1230]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":718,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tommy's Time","artist":"Flanagan · Coltrane · Burrell · Sulieman · Hayes","album":"The Cats","uri":"http://192.168.1.2:33511/b360af95d7e85b73c7a0d8a93387ff63/https---webdav-torbox-app-443-Flanagan-20-C2-B7-20Coltrane-20-C2-B7-20Burrell-20-C2-B7-20Sulieman-20-C2-B7-20Hayes-20-The-20Cats--20-1959-2C-202023--05--20Tommy-s-20Time-dsf","trackType":""} Mar 25 04:43:10 volumio volumio[1230]: verbose: CURRENT POSITION 0 Mar 25 04:43:10 volumio volumio[1230]: info: CoreStateMachine::syncState stateService play Mar 25 04:43:10 volumio volumio[1230]: info: CoreStateMachine::syncState currentStatus play Mar 25 04:43:10 volumio volumio[1230]: info: Received an update from plugin. extracting info from payload Mar 25 04:43:10 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 04:43:10 volumio volumio[1230]: info: CoreStateMachine::pushState Mar 25 04:43:10 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 04:43:10 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Mar 25 04:43:10 volumio volumio[1230]: info: CoreStateMachine::pushState Mar 25 04:43:10 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Mar 25 04:43:10 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:10.336Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:37032 @ 0x2926cf0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 25 04:43:10 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:10.336Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:37032 @ 0x2926cf0" id=http://192.168.1.2:33511/b360af95d7e85b73c7a0d8a93387ff63/https---webdav-torbox-app-443-Flanagan-20-C2-B7-20Coltrane-20-C2-B7-20Burrell-20-C2-B7-20Sulieman-20-C2-B7-20Hayes-20-The-20Cats--20-1959-2C-202023--05--20Tommy-s-20Time-dsf title="Tommy's Time" Mar 25 04:43:10 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:10.337Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:37032 @ 0x2926cf0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 25 04:43:10 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:10.337Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:37032 @ 0x2926cf0" id=http://192.168.1.2:33511/b360af95d7e85b73c7a0d8a93387ff63/https---webdav-torbox-app-443-Flanagan-20-C2-B7-20Coltrane-20-C2-B7-20Burrell-20-C2-B7-20Sulieman-20-C2-B7-20Hayes-20-The-20Cats--20-1959-2C-202023--05--20Tommy-s-20Time-dsf title="Tommy's Time" Mar 25 04:43:10 volumio volumio[1230]: info: ------------------------------ 8ms Mar 25 04:43:10 volumio volumio[1230]: info: CoreCommandRouter::volumioGetQueue Mar 25 04:43:10 volumio volumio[1230]: info: CoreStateMachine::getQueue Mar 25 04:43:10 volumio volumio[1230]: info: CorePlayQueue::getQueue Mar 25 04:43:10 volumio volumio[1230]: info: CoreCommandRouter::volumioGetQueue Mar 25 04:43:10 volumio volumio[1230]: info: CoreStateMachine::getQueue Mar 25 04:43:10 volumio volumio[1230]: info: CorePlayQueue::getQueue Mar 25 04:43:23 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Mar 25 04:43:26 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:26.008Z level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.1.2:37032 error="read tcp 192.168.1.20:7331->192.168.1.2:37032: read: connection reset by peer" Mar 25 04:43:26 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:26.008Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.2:37032 Mar 25 04:43:26 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:26.008Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.2:37032 Mar 25 04:43:26 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 9 Mar 25 04:43:26 volumio kernel: xhci_hcd 0000:01:00.0: @000000040cd0adb0 00000000 00000000 0e000000 020a8001 Mar 25 04:43:26 volumio volumio[1230]: info: Mar 25 04:43:26 volumio volumio[1230]: ---------------------------- MPD announces state update: player Mar 25 04:43:26 volumio volumio[1230]: info: ControllerMpd::getState Mar 25 04:43:26 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand status Mar 25 04:43:26 volumio volumio[1230]: info: sendMpdCommand status took 1 milliseconds Mar 25 04:43:26 volumio volumio[1230]: verbose: ControllerMpd::parseState Mar 25 04:43:26 volumio volumio[1230]: info: ControllerMpd::pushState Mar 25 04:43:26 volumio volumio[1230]: info: CoreCommandRouter::servicePushState Mar 25 04:43:26 volumio volumio[1230]: info: CoreStateMachine::pushState Mar 25 04:43:26 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Mar 25 04:43:26 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Mar 25 04:43:26 volumio volumio[1230]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 25 04:43:26 volumio volumio[1230]: verbose: CURRENT POSITION 0 Mar 25 04:43:26 volumio volumio[1230]: info: CoreStateMachine::syncState stateService stop Mar 25 04:43:26 volumio volumio[1230]: info: CoreStateMachine::syncState currentStatus play Mar 25 04:43:26 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Mar 25 04:43:26 volumio volumio[1230]: info: CoreStateMachine::stPlaybackTimer Mar 25 04:43:26 volumio volumio[1230]: info: ------------------------------ 4ms Mar 25 04:43:26 volumio volumio[1230]: info: CoreCommandRouter::volumioGetQueue Mar 25 04:43:26 volumio volumio[1230]: info: CoreStateMachine::getQueue Mar 25 04:43:26 volumio volumio[1230]: info: CorePlayQueue::getQueue Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.034Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.2:44272 Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.053Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.2:44272 @ 0x2cb0000" latency=-3737h11m21.682265669s timeout=10s Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.063Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.2:44272 @ 0x2cb0000" latency=-3737h11m21.680091095s timeout=20s Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.063Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.2:44272 @ 0x2cb0000" Mar 25 04:43:27 volumio volumio[1230]: info: Received Get System Info Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 04:43:27 volumio volumio[1230]: info: Discovery: Getting this device information Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.065Z level=INFO msg="emitting device name changed event" component=server peer="192.168.1.2:44272 @ 0x2cb0000" name=Volumio Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.066Z level=INFO msg="emitting device language changed event" component=server peer="192.168.1.2:44272 @ 0x2cb0000" language=vi Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.069Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.2:44272 @ 0x2cb0000" timezone=Asia/Ho_Chi_Minh Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.070Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.2:44272 @ 0x2cb0000" available=true connected=true macAddress=d8:3a:dd:50:ca:e3 ip4Address=192.168.1.20/24 ip6Address= Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.071Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.2:44272 @ 0x2cb0000" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.071Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.2:44272 @ 0x2cb0000" setupComplete=true Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 04:43:27 volumio volumio[1230]: amixer -c 0 info | grep "bcm2835 ALSA" Mar 25 04:43:27 volumio volumio[1230]: amixer -c 1 info | grep "bcm2835 Headphones" Mar 25 04:43:27 volumio volumio[1230]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Mar 25 04:43:27 volumio volumio[1230]: amixer -c 2 info | grep "vc4-hdmi-0" Mar 25 04:43:27 volumio volumio[1230]: Card sysdefault:2 'vc4hdmi0'/'vc4-hdmi-0' Mar 25 04:43:27 volumio volumio[1230]: amixer -c 3 info | grep "vc4-hdmi-1" Mar 25 04:43:27 volumio volumio[1230]: Card sysdefault:3 'vc4hdmi1'/'vc4-hdmi-1' Mar 25 04:43:27 volumio volumio[1230]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Mar 25 04:43:27 volumio volumio[1230]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 25 04:43:27 volumio volumio[1230]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 25 04:43:27 volumio volumio[1230]: amixer -c 5 info | grep "USB HS Audio Device" Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.211Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.2:44272 @ 0x2cb0000" latency=-3737h11m21.682265669s timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=157.88776ms error="Head \"https://browsing-performer.dfs.volumio.org\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2026-03-24T21:43:27Z is before 2026-07-24T11:26:04Z" Mar 25 04:43:27 volumio volumio[1230]: Card sysdefault:5 'Device'/'ONKYO USB HS Audio Device at usb-0000:01:00.0-1.4, high speed' Mar 25 04:43:27 volumio volumio[1230]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Mar 25 04:43:27 volumio volumio[1230]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 25 04:43:27 volumio volumio[1230]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 25 04:43:27 volumio volumio[1230]: amixer -c 5 info | grep "USB HS Audio Device" Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.224Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.2:44272 @ 0x2cb0000" latency=-3737h11m21.682265669s timeout=10s endpoint=https://database.volumio.cloud duration=168.533852ms error="Head \"https://database.volumio.cloud\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2026-03-24T21:43:27Z is before 2026-07-02T14:14:43Z" Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.235Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.2:44272 @ 0x2cb0000" latency=-3737h11m21.682265669s timeout=10s endpoint=https://google.com duration=181.08563ms error="Head \"https://google.com\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2026-03-24T21:43:27Z is before 2026-08-10T08:37:35Z" Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.235Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.2:44272 @ 0x2cb0000" latency=-3737h11m21.682265669s timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=181.446296ms error="Head \"https://radio-directory.firebaseapp.com\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2026-03-24T21:43:27Z is before 2026-07-20T15:01:16Z" Mar 25 04:43:27 volumio volumio[1230]: Card sysdefault:5 'Device'/'ONKYO USB HS Audio Device at usb-0000:01:00.0-1.4, high speed' Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.254Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.2:44272 @ 0x2cb0000" latency=-3737h11m21.682265669s timeout=10s endpoint=https://www.googleapis.com duration=200.858371ms error="Head \"https://www.googleapis.com\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2026-03-24T21:43:27Z is before 2026-08-10T08:39:11Z" Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.254Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.2:44272 @ 0x2cb0000" latency=-3737h11m21.682265669s timeout=10s endpoint=https://securetoken.googleapis.com duration=199.099852ms error="Head \"https://securetoken.googleapis.com\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2026-03-24T21:43:27Z is before 2026-08-10T08:39:11Z" Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.255Z level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.2:44272 @ 0x2cb0000" selectedOutputId=5 Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.262Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.2:44272 @ 0x2cb0000" latency=-3737h11m21.682265669s timeout=10s endpoint=http://pushupdates.volumio.org duration=208.767778ms Mar 25 04:43:27 volumio volumio[1230]: info: Received Get System Info Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 04:43:27 volumio volumio[1230]: info: Discovery: Getting this device information Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.320Z level=INFO msg="emitting software info changed event" component=server peer="192.168.1.2:44272 @ 0x2cb0000" currentVersion=4.119 latestVersion=4.119 Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.320Z level=INFO msg="emitting software update progress event" component=server peer="192.168.1.2:44272 @ 0x2cb0000" status=UPDATE_STATUS_NONE progress=0 Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.321Z level=INFO msg="emitting user changed event" component=server peer="192.168.1.2:44272 @ 0x2cb0000" userId= Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.321Z level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.2:44272 @ 0x2cb0000" providers=3 Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.322Z level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.2:44272 @ 0x2cb0000" plugins=0 Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.324Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:44272 @ 0x2cb0000" state=STATUS_STOPPED positionMs= volume=100 Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.324Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:44272 @ 0x2cb0000" id=http://192.168.1.2:33511/b360af95d7e85b73c7a0d8a93387ff63/https---webdav-torbox-app-443-Flanagan-20-C2-B7-20Coltrane-20-C2-B7-20Burrell-20-C2-B7-20Sulieman-20-C2-B7-20Hayes-20-The-20Cats--20-1959-2C-202023--05--20Tommy-s-20Time-dsf title="Tommy's Time" Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.347Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.2:44272 @ 0x2cb0000" latency=-3737h11m21.682265669s timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=292.49574ms error="Head \"https://oauth-performer.dfs.volumio.org\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2026-03-24T21:43:27Z is before 2026-07-08T11:28:45Z" Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.435Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.2:44272 @ 0x2cb0000" latency=-3737h11m21.682265669s timeout=10s endpoint=https://myvolumio.firebaseio.com duration=380.529648ms error="Head \"https://myvolumio.firebaseio.com\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2026-03-24T21:43:27Z is before 2026-07-06T15:01:47Z" Mar 25 04:43:27 volumio volumio[1230]: verbose: New Socket.io Connection to 192.168.1.20 from 192.168.1.2 UA: Mozilla/5.0 (Linux; Android 16; 23127PN0CC Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::volumioGetVisibleSources Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 25 04:43:27 volumio volumio[1230]: info: Received Get System Info Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 04:43:27 volumio volumio[1230]: info: Discovery: Getting this device information Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 04:43:27 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Mar 25 04:43:27 volumio volumio[1230]: info: Listing playlists Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.848Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.2:44272 @ 0x2cb0000" latency=-3737h11m21.682265669s timeout=10s endpoint=http://cddb.volumio.org duration=793.946704ms Mar 25 04:43:27 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:27.898Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.2:44272 @ 0x2cb0000" latency=-3737h11m21.682265669s timeout=10s endpoint=http://plugins.volumio.org duration=842.09537ms Mar 25 04:43:28 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:28.798Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.2:44272 @ 0x2cb0000" latency=-3737h11m21.682265669s timeout=10s endpoint=https://functions.volumio.cloud duration=1.742951295s error="Head \"https://functions.volumio.cloud\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2026-03-24T21:43:28Z is before 2026-07-02T14:14:43Z" Mar 25 04:43:28 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:28.996Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.2:44272 @ 0x2cb0000" latency=-3737h11m21.682265669s timeout=10s endpoint=https://functions.volumio.cloud duration=1.94192611s error="Head \"https://functions.volumio.cloud\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2026-03-24T21:43:28Z is before 2026-07-02T14:14:43Z" Mar 25 04:43:33 volumio bash[1443]: setdatetime-helper: all HTTPS Date fallbacks failed Mar 25 04:43:33 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 25 04:43:33 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 25 04:43:33 volumio systemd[1]: setdatetime-helper.service: Consumed 1.137s CPU time. Mar 25 04:43:39 volumio ntpd[1042]: PROTO: 115.165.161.155 unlink local addr 192.168.1.20 -> Mar 25 04:43:41 volumio volumio[1230]: info: Clearing queue after UPNP request Mar 25 04:43:41 volumio volumio[1230]: info: CoreStateMachine::ClearQueue Mar 25 04:43:41 volumio volumio[1230]: info: CoreStateMachine::stop Mar 25 04:43:41 volumio volumio[1230]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 04:43:41 volumio volumio[1230]: info: CorePlayQueue::clearPlayQueue Mar 25 04:43:41 volumio volumio[1230]: info: CorePlayQueue::saveQueue Mar 25 04:43:41 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Mar 25 04:43:41 volumio volumio[1230]: info: CoreCommandRouter::volumioPushQueue Mar 25 04:43:41 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:41.794Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:44272 @ 0x2cb0000" state=STATUS_STOPPED positionMs=0 volume=100 Mar 25 04:43:41 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:41.794Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:44272 @ 0x2cb0000" id= title= Mar 25 04:43:41 volumio volumio[1230]: info: Mar 25 04:43:41 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Mar 25 04:43:41 volumio volumio[1230]: info: Ignoring MPD Status Update Mar 25 04:43:41 volumio volumio[1230]: error: updateQueue error: null Mar 25 04:43:41 volumio volumio[1230]: info: ------------------------------ 1ms Mar 25 04:43:41 volumio volumio[1230]: info: Starting UPNP Playback Mar 25 04:43:41 volumio volumio[1230]: info: Preparing playback through UPNP Mar 25 04:43:41 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Mar 25 04:43:41 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Mar 25 04:43:41 volumio volumio[1230]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 04:43:42 volumio ntpd[1042]: PROTO: 45.252.250.189 unlink local addr 192.168.1.20 -> Mar 25 04:43:55 volumio volumio[1230]: info: Clearing queue after UPNP request Mar 25 04:43:55 volumio volumio[1230]: info: CoreStateMachine::ClearQueue Mar 25 04:43:55 volumio volumio[1230]: info: CoreStateMachine::stop Mar 25 04:43:55 volumio volumio[1230]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 04:43:55 volumio volumio[1230]: info: CorePlayQueue::clearPlayQueue Mar 25 04:43:55 volumio volumio[1230]: info: CorePlayQueue::saveQueue Mar 25 04:43:55 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Mar 25 04:43:55 volumio volumio[1230]: info: CoreCommandRouter::volumioPushQueue Mar 25 04:43:55 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:55.637Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:44272 @ 0x2cb0000" state=STATUS_STOPPED positionMs=0 volume=100 Mar 25 04:43:55 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:55.638Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:44272 @ 0x2cb0000" id= title= Mar 25 04:43:55 volumio volumio[1230]: info: Mar 25 04:43:55 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Mar 25 04:43:55 volumio volumio[1230]: info: Ignoring MPD Status Update Mar 25 04:43:55 volumio volumio[1230]: error: updateQueue error: null Mar 25 04:43:55 volumio volumio[1230]: info: ------------------------------ 1ms Mar 25 04:43:55 volumio volumio[1230]: info: Starting UPNP Playback Mar 25 04:43:55 volumio volumio[1230]: info: Preparing playback through UPNP Mar 25 04:43:55 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Mar 25 04:43:55 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Mar 25 04:43:55 volumio volumio[1230]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 04:43:56 volumio volumio[1230]: info: Mar 25 04:43:56 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Mar 25 04:43:56 volumio volumio[1230]: info: Ignoring MPD Status Update Mar 25 04:43:56 volumio volumio[1230]: info: ------------------------------ 1ms Mar 25 04:43:56 volumio volumio[1230]: info: Mar 25 04:43:56 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Mar 25 04:43:56 volumio volumio[1230]: info: Ignoring MPD Status Update Mar 25 04:43:56 volumio volumio[1230]: info: ------------------------------ 0ms Mar 25 04:43:56 volumio volumio[1230]: info: Mar 25 04:43:56 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Mar 25 04:43:56 volumio volumio[1230]: info: Ignoring MPD Status Update Mar 25 04:43:56 volumio volumio[1230]: info: Mar 25 04:43:56 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Mar 25 04:43:56 volumio volumio[1230]: info: Ignoring MPD Status Update Mar 25 04:43:56 volumio volumio[1230]: info: ------------------------------ 1ms Mar 25 04:43:56 volumio volumio[1230]: info: ------------------------------ 0ms Mar 25 04:43:56 volumio volumio[1230]: info: Mar 25 04:43:56 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Mar 25 04:43:56 volumio volumio[1230]: info: Ignoring MPD Status Update Mar 25 04:43:56 volumio volumio[1230]: info: Mar 25 04:43:56 volumio volumio[1230]: ---------------------------- MPD announces system playlist update Mar 25 04:43:56 volumio volumio[1230]: info: Ignoring MPD Status Update Mar 25 04:43:56 volumio volumio[1230]: info: ------------------------------ 1ms Mar 25 04:43:56 volumio volumio[1230]: info: ------------------------------ 1ms Mar 25 04:43:59 volumio volumio[1230]: info: Mar 25 04:43:59 volumio volumio[1230]: ---------------------------- MPD announces state update: player Mar 25 04:43:59 volumio volumio[1230]: info: ControllerMpd::getState Mar 25 04:43:59 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand status Mar 25 04:43:59 volumio volumio[1230]: info: Mar 25 04:43:59 volumio volumio[1230]: ---------------------------- MPD announces state update: player Mar 25 04:43:59 volumio volumio[1230]: info: sendMpdCommand status took 8 milliseconds Mar 25 04:43:59 volumio volumio[1230]: info: ControllerMpd::getState Mar 25 04:43:59 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand status Mar 25 04:43:59 volumio volumio[1230]: verbose: ControllerMpd::parseState Mar 25 04:43:59 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 04:43:59 volumio volumio[1230]: info: sendMpdCommand status took 0 milliseconds Mar 25 04:43:59 volumio volumio[1230]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 25 04:43:59 volumio volumio[1230]: verbose: ControllerMpd::parseState Mar 25 04:43:59 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 04:43:59 volumio volumio[1230]: verbose: ControllerMpd::parseTrackInfo Mar 25 04:43:59 volumio volumio[1230]: info: ControllerMpd::pushState Mar 25 04:43:59 volumio volumio[1230]: info: CoreCommandRouter::servicePushState Mar 25 04:43:59 volumio volumio[1230]: verbose: In UPNP mode Mar 25 04:43:59 volumio volumio[1230]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":452,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bossa Nova U.S.A.","artist":"The Dave Brubeck Quartet","album":"At Carnegie Hall","uri":"http://192.168.1.2:33511/6c0bc55545e1a783412aed79b569b21b/https---webdav-torbox-app-443-The-20Dave-20Brubeck-20Quartet-20-At-20Carnegie-20Hall--20-1963-2C-202013--02--20Bossa-20Nova-20U-S-A--dsf","trackType":""} Mar 25 04:43:59 volumio volumio[1230]: verbose: CURRENT POSITION 0 Mar 25 04:43:59 volumio volumio[1230]: info: CoreStateMachine::syncState stateService play Mar 25 04:43:59 volumio volumio[1230]: info: CoreStateMachine::syncState currentStatus stop Mar 25 04:43:59 volumio volumio[1230]: info: ------------------------------ 10ms Mar 25 04:43:59 volumio volumio[1230]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 25 04:43:59 volumio volumio[1230]: verbose: ControllerMpd::parseTrackInfo Mar 25 04:43:59 volumio volumio[1230]: info: ControllerMpd::pushState Mar 25 04:43:59 volumio volumio[1230]: info: CoreCommandRouter::servicePushState Mar 25 04:43:59 volumio volumio[1230]: verbose: In UPNP mode Mar 25 04:43:59 volumio volumio[1230]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":452,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bossa Nova U.S.A.","artist":"The Dave Brubeck Quartet","album":"At Carnegie Hall","uri":"http://192.168.1.2:33511/6c0bc55545e1a783412aed79b569b21b/https---webdav-torbox-app-443-The-20Dave-20Brubeck-20Quartet-20-At-20Carnegie-20Hall--20-1963-2C-202013--02--20Bossa-20Nova-20U-S-A--dsf","trackType":""} Mar 25 04:43:59 volumio volumio[1230]: verbose: CURRENT POSITION 0 Mar 25 04:43:59 volumio volumio[1230]: info: CoreStateMachine::syncState stateService play Mar 25 04:43:59 volumio volumio[1230]: info: CoreStateMachine::syncState currentStatus play Mar 25 04:43:59 volumio volumio[1230]: info: Received an update from plugin. extracting info from payload Mar 25 04:43:59 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 04:43:59 volumio volumio[1230]: info: CoreStateMachine::pushState Mar 25 04:43:59 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 04:43:59 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Mar 25 04:43:59 volumio volumio[1230]: info: CoreStateMachine::pushState Mar 25 04:43:59 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Mar 25 04:43:59 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:59.237Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:44272 @ 0x2cb0000" state=STATUS_PLAYING positionMs=0 volume=100 Mar 25 04:43:59 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:59.237Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:44272 @ 0x2cb0000" id=http://192.168.1.2:33511/6c0bc55545e1a783412aed79b569b21b/https---webdav-torbox-app-443-The-20Dave-20Brubeck-20Quartet-20-At-20Carnegie-20Hall--20-1963-2C-202013--02--20Bossa-20Nova-20U-S-A--dsf title="Bossa Nova U.S.A." Mar 25 04:43:59 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:59.238Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:44272 @ 0x2cb0000" state=STATUS_PLAYING positionMs=0 volume=100 Mar 25 04:43:59 volumio volumio5-onboarding[1774]: time=2026-03-24T21:43:59.239Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:44272 @ 0x2cb0000" id=http://192.168.1.2:33511/6c0bc55545e1a783412aed79b569b21b/https---webdav-torbox-app-443-The-20Dave-20Brubeck-20Quartet-20-At-20Carnegie-20Hall--20-1963-2C-202013--02--20Bossa-20Nova-20U-S-A--dsf title="Bossa Nova U.S.A." Mar 25 04:43:59 volumio volumio[1230]: info: ------------------------------ 10ms Mar 25 04:43:59 volumio volumio[1230]: info: CoreCommandRouter::volumioGetQueue Mar 25 04:43:59 volumio volumio[1230]: info: CoreStateMachine::getQueue Mar 25 04:43:59 volumio volumio[1230]: info: CorePlayQueue::getQueue Mar 25 04:43:59 volumio volumio[1230]: info: CoreCommandRouter::volumioGetQueue Mar 25 04:43:59 volumio volumio[1230]: info: CoreStateMachine::getQueue Mar 25 04:43:59 volumio volumio[1230]: info: CorePlayQueue::getQueue Mar 25 04:44:00 volumio volumio[1230]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 04:44:00 volumio volumio[1230]: Error: certificate is not yet valid Mar 25 04:44:00 volumio volumio[1230]: at TLSSocket.onConnectSecure (node:_tls_wrap:1627:34) Mar 25 04:44:00 volumio volumio[1230]: at TLSSocket.emit (node:events:514:28) Mar 25 04:44:00 volumio volumio[1230]: at TLSSocket._finishInit (node:_tls_wrap:1038:8) Mar 25 04:44:00 volumio volumio[1230]: at ssl.onhandshakedone (node:_tls_wrap:824:12) { Mar 25 04:44:00 volumio volumio[1230]: code: 'CERT_NOT_YET_VALID' Mar 25 04:44:00 volumio volumio[1230]: } Mar 25 04:44:00 volumio volumio[1230]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 04:44:00 volumio sudo[1790]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-25 04:43' Mar 25 04:44:00 volumio sudo[1790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"