Jul 16 09:11:28 volumiohifiplus systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 16 09:11:28 volumiohifiplus dbus-daemon[869]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.25' (uid=0 pid=1818 comm="timedatectl show --property=NTPSynchronized --valu") Jul 16 09:11:28 volumiohifiplus systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 16 09:11:28 volumiohifiplus dbus-daemon[869]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 16 09:11:28 volumiohifiplus systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 16 09:11:28 volumiohifiplus setdatetime-helper.sh[1817]: Time is not synchronized. Attempting to sync... Jul 16 09:11:28 volumiohifiplus sudo[1826]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Wed, 16 Jul 2025 01:11:28 GMT#015' Jul 16 09:11:28 volumiohifiplus sudo[1826]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 16 09:11:28 volumiohifiplus setdatetime-helper.sh[1827]: Wed Jul 16 09:11:28 AWST 2025 Jul 16 09:11:28 volumiohifiplus sudo[1826]: pam_unix(sudo:session): session closed for user root Jul 16 09:11:28 volumiohifiplus systemd-journald[412]: Time jumped backwards, rotating. Jul 16 09:11:28 volumiohifiplus systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 16 09:11:28 volumiohifiplus setdatetime-helper.sh[1817]: Time synchronized successfully. Jul 16 09:11:28 volumiohifiplus systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 16 09:11:57 volumiohifiplus systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 16 09:12:02 volumiohifiplus sudo[1875]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 16 09:12:02 volumiohifiplus sudo[1875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 16 09:12:02 volumiohifiplus sudo[1877]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 16 09:12:02 volumiohifiplus sudo[1875]: pam_unix(sudo:session): session closed for user root Jul 16 09:12:02 volumiohifiplus sudo[1877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 16 09:12:02 volumiohifiplus sudo[1877]: pam_unix(sudo:session): session closed for user root Jul 16 09:12:03 volumiohifiplus sudo[1881]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 16 09:12:03 volumiohifiplus sudo[1881]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 16 09:12:03 volumiohifiplus sudo[1883]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 16 09:12:03 volumiohifiplus sudo[1881]: pam_unix(sudo:session): session closed for user root Jul 16 09:12:03 volumiohifiplus sudo[1883]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 16 09:12:03 volumiohifiplus sudo[1883]: pam_unix(sudo:session): session closed for user root Jul 16 09:12:03 volumiohifiplus volumio[1325]: verbose: New Socket.io Connection to 192.168.0.10 from 192.168.0.150 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 4 Jul 16 09:12:03 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 16 09:12:03 volumiohifiplus volumio[1325]: info: CoreCommandRouter::volumioGetVisibleSources Jul 16 09:12:03 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 16 09:12:03 volumiohifiplus volumio[1325]: info: CoreCommandRouter::volumioGetState Jul 16 09:12:03 volumiohifiplus volumio[1325]: info: CorePlayQueue::getTrack 0 Jul 16 09:12:03 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 16 09:12:03 volumiohifiplus volumio[1325]: info: CoreCommandRouter::volumioGetQueue Jul 16 09:12:03 volumiohifiplus volumio[1325]: info: CoreStateMachine::getQueue Jul 16 09:12:03 volumiohifiplus volumio[1325]: info: CorePlayQueue::getQueue Jul 16 09:12:03 volumiohifiplus volumio[1325]: info: Listing playlists Jul 16 09:12:03 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 16 09:12:03 volumiohifiplus volumio[1325]: info: Received Get System Info Jul 16 09:12:03 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 16 09:12:03 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 16 09:12:03 volumiohifiplus volumio[1325]: info: Discovery: Getting this device information Jul 16 09:12:03 volumiohifiplus volumio[1325]: info: CoreCommandRouter::volumioGetState Jul 16 09:12:03 volumiohifiplus volumio[1325]: info: CorePlayQueue::getTrack 0 Jul 16 09:12:03 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 16 09:12:04 volumiohifiplus volumio[1325]: info: CoreCommandRouter::volumioGetState Jul 16 09:12:04 volumiohifiplus volumio[1325]: info: CorePlayQueue::getTrack 0 Jul 16 09:12:04 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 16 09:12:04 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 16 09:12:04 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 16 09:12:05 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 16 09:12:05 volumiohifiplus volumio[1325]: info: Received Get System Info Jul 16 09:12:05 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 16 09:12:05 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 16 09:12:05 volumiohifiplus volumio[1325]: info: Discovery: Getting this device information Jul 16 09:12:05 volumiohifiplus volumio[1325]: info: CoreCommandRouter::volumioGetState Jul 16 09:12:05 volumiohifiplus volumio[1325]: info: CorePlayQueue::getTrack 0 Jul 16 09:12:05 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 16 09:12:05 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 16 09:12:05 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 16 09:12:07 volumiohifiplus volumio[1325]: info: CoreCommandRouter::volumioGetState Jul 16 09:12:07 volumiohifiplus volumio[1325]: info: CorePlayQueue::getTrack 0 Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: CoreCommandRouter::volumioPlay Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: CoreStateMachine::play index undefined Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: CorePlayQueue::getTrack 0 Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: CoreStateMachine::startPlaybackTimer Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: CorePlayQueue::getTrack 0 Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: [1752628328002] ControllerWebradio::clearAddPlayTrack Jul 16 09:12:08 volumiohifiplus volumio[1325]: verbose: ControllerMpd::sendMpdCommand stop Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: sendMpdCommand stop took 0 milliseconds Jul 16 09:12:08 volumiohifiplus volumio[1325]: verbose: ControllerMpd::sendMpdCommand clear Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: sendMpdCommand clear took 1 milliseconds Jul 16 09:12:08 volumiohifiplus volumio[1325]: verbose: ControllerMpd::sendMpdCommand add "https://mediaserviceslive.akamaized.net/hls/live/2038319/abcjazz/index.m3u8" Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: Jul 16 09:12:08 volumiohifiplus volumio[1325]: ---------------------------- MPD announces system playlist update Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: Ignoring MPD Status Update Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: Jul 16 09:12:08 volumiohifiplus volumio[1325]: ---------------------------- MPD announces system playlist update Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: Ignoring MPD Status Update Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: Jul 16 09:12:08 volumiohifiplus volumio[1325]: ---------------------------- MPD announces system playlist update Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: Ignoring MPD Status Update Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: Jul 16 09:12:08 volumiohifiplus volumio[1325]: ---------------------------- MPD announces system playlist update Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: Ignoring MPD Status Update Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: sendMpdCommand add "https://mediaserviceslive.akamaized.net/hls/live/2038319/abcjazz/index.m3u8" took 2 milliseconds Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: ------------------------------ 2ms Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: ------------------------------ 1ms Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 16 09:12:08 volumiohifiplus volumio[1325]: verbose: ControllerMpd::sendMpdCommand play Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: Jul 16 09:12:08 volumiohifiplus volumio[1325]: ---------------------------- MPD announces system playlist update Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: Ignoring MPD Status Update Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: Jul 16 09:12:08 volumiohifiplus volumio[1325]: ---------------------------- MPD announces system playlist update Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: Ignoring MPD Status Update Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: ------------------------------ 3ms Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: ------------------------------ 3ms Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: sendMpdCommand play took 2 milliseconds Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: ------------------------------ 1ms Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: ------------------------------ 1ms Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: Received Get System Info Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: Discovery: Getting this device information Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: CoreCommandRouter::volumioGetState Jul 16 09:12:08 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: Received Get System Info Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: Discovery: Getting this device information Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreCommandRouter::volumioGetState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: Jul 16 09:12:09 volumiohifiplus volumio[1325]: ---------------------------- MPD announces state update: player Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: ControllerMpd::getState Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::sendMpdCommand status Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: Jul 16 09:12:09 volumiohifiplus volumio[1325]: ---------------------------- MPD announces state update: player Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: ControllerMpd::getState Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::sendMpdCommand status Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: Jul 16 09:12:09 volumiohifiplus volumio[1325]: ---------------------------- MPD announces state update: player Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: ControllerMpd::getState Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::sendMpdCommand status Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: sendMpdCommand status took 18 milliseconds Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::parseState Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: Jul 16 09:12:09 volumiohifiplus volumio[1325]: ---------------------------- MPD announces state update: player Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: ControllerMpd::getState Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::sendMpdCommand status Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: Jul 16 09:12:09 volumiohifiplus volumio[1325]: ---------------------------- MPD announces state update: player Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: ControllerMpd::getState Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::sendMpdCommand status Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: sendMpdCommand status took 20 milliseconds Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: sendMpdCommand status took 20 milliseconds Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: sendMpdCommand status took 1 milliseconds Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::parseState Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::parseState Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::parseTrackInfo Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::parseState Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: ControllerMpd::pushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreCommandRouter::servicePushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CorePlayQueue::getTrack 0 Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"index.m3u8","artist":null,"album":null,"uri":"https://mediaserviceslive.akamaized.net/hls/live/2038319/abcjazz/index.m3u8","trackType":"m3u8"} Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: CURRENT POSITION 0 Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreStateMachine::syncState stateService play Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreStateMachine::syncState currentStatus stop Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: ------------------------------ 25ms Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: Jul 16 09:12:09 volumiohifiplus volumio[1325]: ---------------------------- MPD announces state update: player Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: ControllerMpd::getState Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::sendMpdCommand status Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: sendMpdCommand status took 5 milliseconds Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: sendMpdCommand status took 1 milliseconds Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::parseState Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::parseTrackInfo Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::parseTrackInfo Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::parseTrackInfo Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::parseState Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: ControllerMpd::pushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreCommandRouter::servicePushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CorePlayQueue::getTrack 0 Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: STATE SERVICE {"status":"play","position":0,"seek":275,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"index.m3u8","artist":null,"album":null,"uri":"https://mediaserviceslive.akamaized.net/hls/live/2038319/abcjazz/index.m3u8","trackType":"m3u8"} Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: CURRENT POSITION 0 Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreStateMachine::syncState stateService play Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreStateMachine::syncState currentStatus play Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: Received an update from plugin. extracting info from payload Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreStateMachine::pushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CorePlayQueue::getTrack 0 Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreCommandRouter::volumioPushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreStateMachine::pushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreCommandRouter::volumioPushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: ControllerMpd::pushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreCommandRouter::servicePushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CorePlayQueue::getTrack 0 Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: STATE SERVICE {"status":"play","position":0,"seek":812,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"index.m3u8","artist":null,"album":null,"uri":"https://mediaserviceslive.akamaized.net/hls/live/2038319/abcjazz/index.m3u8","trackType":"m3u8"} Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: CURRENT POSITION 0 Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreStateMachine::syncState stateService play Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreStateMachine::syncState currentStatus play Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: Received an update from plugin. extracting info from payload Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreStateMachine::pushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CorePlayQueue::getTrack 0 Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreCommandRouter::volumioPushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreStateMachine::pushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreCommandRouter::volumioPushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: ControllerMpd::pushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreCommandRouter::servicePushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CorePlayQueue::getTrack 0 Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"index.m3u8","artist":null,"album":null,"uri":"https://mediaserviceslive.akamaized.net/hls/live/2038319/abcjazz/index.m3u8","trackType":"m3u8"} Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: CURRENT POSITION 0 Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreStateMachine::syncState stateService play Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreStateMachine::syncState currentStatus play Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: Received an update from plugin. extracting info from payload Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreStateMachine::pushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CorePlayQueue::getTrack 0 Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreCommandRouter::volumioPushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreStateMachine::pushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreCommandRouter::volumioPushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: ------------------------------ 44ms Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: ------------------------------ 43ms Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: ------------------------------ 25ms Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: sendMpdCommand playlistinfo took 19 milliseconds Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: sendMpdCommand playlistinfo took 19 milliseconds Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::parseTrackInfo Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: ControllerMpd::parseTrackInfo Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: ControllerMpd::pushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreCommandRouter::servicePushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CorePlayQueue::getTrack 0 Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"index.m3u8","artist":null,"album":null,"uri":"https://mediaserviceslive.akamaized.net/hls/live/2038319/abcjazz/index.m3u8","trackType":"m3u8"} Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: CURRENT POSITION 0 Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreStateMachine::syncState stateService play Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreStateMachine::syncState currentStatus play Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: Received an update from plugin. extracting info from payload Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreStateMachine::pushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CorePlayQueue::getTrack 0 Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreCommandRouter::volumioPushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreStateMachine::pushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreCommandRouter::volumioPushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: ControllerMpd::pushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreCommandRouter::servicePushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CorePlayQueue::getTrack 0 Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"index.m3u8","artist":null,"album":null,"uri":"https://mediaserviceslive.akamaized.net/hls/live/2038319/abcjazz/index.m3u8","trackType":"m3u8"} Jul 16 09:12:09 volumiohifiplus volumio[1325]: verbose: CURRENT POSITION 0 Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreStateMachine::syncState stateService play Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreStateMachine::syncState currentStatus play Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: Received an update from plugin. extracting info from payload Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreStateMachine::pushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CorePlayQueue::getTrack 0 Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreCommandRouter::volumioPushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreStateMachine::pushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: CoreCommandRouter::volumioPushState Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: ------------------------------ 35ms Jul 16 09:12:09 volumiohifiplus volumio[1325]: info: ------------------------------ 31ms Jul 16 09:12:10 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 16 09:12:10 volumiohifiplus volumio[1325]: info: Received Get System Info Jul 16 09:12:10 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 16 09:12:10 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 16 09:12:10 volumiohifiplus volumio[1325]: info: Discovery: Getting this device information Jul 16 09:12:10 volumiohifiplus volumio[1325]: info: CoreCommandRouter::volumioGetState Jul 16 09:12:10 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 16 09:12:11 volumiohifiplus volumio[1325]: info: Volumio called home Jul 16 09:12:13 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 16 09:12:15 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 16 09:12:15 volumiohifiplus volumio[1325]: info: Preload queue cleared Jul 16 09:12:18 volumiohifiplus volumio[1325]: Searching plugin music_service/webradio Jul 16 09:12:18 volumiohifiplus volumio[1325]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jul 16 09:12:19 volumiohifiplus volumio[1325]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 16 09:12:19 volumiohifiplus volumio[1325]: TypeError: Cannot read properties of undefined (reading 'length') Jul 16 09:12:19 volumiohifiplus volumio[1325]: at /volumio/node_modules/libxmljs/dist/lib/parse.js:186:126 Jul 16 09:12:19 volumiohifiplus volumio[1325]: at Object.parseXml (/volumio/node_modules/libxmljs/dist/lib/parse.js:185:49) Jul 16 09:12:19 volumiohifiplus volumio[1325]: at /volumio/app/plugins/music_service/webradio/index.js:949:31 Jul 16 09:12:19 volumiohifiplus volumio[1325]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jul 16 09:12:19 volumiohifiplus volumio[1325]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 16 09:12:19 volumiohifiplus sudo[1958]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-16 09:11' Jul 16 09:12:19 volumiohifiplus sudo[1958]: 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="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"