Jun 09 13:01:07 gospel-reggae systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 09 13:01:07 gospel-reggae dbus-daemon[474]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.21' (uid=0 pid=1364 comm="timedatectl show --property=NTPSynchronized --valu") Jun 09 13:01:07 gospel-reggae systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 09 13:01:07 gospel-reggae dbus-daemon[474]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 09 13:01:07 gospel-reggae systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 09 13:01:07 gospel-reggae setdatetime-helper.sh[1363]: Time is not synchronized. Attempting to sync... Jun 09 13:01:08 gospel-reggae sudo[1372]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Mon, 09 Jun 2025 17:01:08 GMT#015' Jun 09 13:01:08 gospel-reggae sudo[1372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 09 13:01:08 gospel-reggae setdatetime-helper.sh[1373]: Mon Jun 9 13:01:08 EDT 2025 Jun 09 13:01:08 gospel-reggae systemd-journald[307]: Time jumped backwards, rotating. Jun 09 13:01:08 gospel-reggae sudo[1372]: pam_unix(sudo:session): session closed for user root Jun 09 13:01:08 gospel-reggae setdatetime-helper.sh[1363]: Time synchronized successfully. Jun 09 13:01:08 gospel-reggae systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 09 13:01:08 gospel-reggae systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 09 13:01:24 gospel-reggae upmpdcli[1390]: writing RSA key Jun 09 13:01:37 gospel-reggae systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 09 13:01:38 gospel-reggae volumio[865]: info: MYVOLUMIO: Adding device Jun 09 13:01:38 gospel-reggae volumio[865]: info: MYVOLUMIO: Evaluating Server Jun 09 13:01:38 gospel-reggae volumio[865]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 09 13:01:38 gospel-reggae volumio[865]: info: Completed starting MyVolumio Plugin Jun 09 13:01:38 gospel-reggae volumio[865]: [Metrics] CommandRouter: 141s 782.11ms Jun 09 13:01:38 gospel-reggae volumio[865]: info: CoreCommandRouter::volumiosetStartupVolume Jun 09 13:01:38 gospel-reggae volumio[865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 13:01:38 gospel-reggae volumio[865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 13:01:38 gospel-reggae volumio[865]: info: CoreCommandRouter::Close All Modals sent Jun 09 13:01:38 gospel-reggae volumio[865]: info: CoreCommandRouter::Close All Modals sent Jun 09 13:01:38 gospel-reggae volumio[865]: info: CoreCommandRouter::volumioPlay Jun 09 13:01:38 gospel-reggae volumio[865]: info: CoreStateMachine::play index 1 Jun 09 13:01:38 gospel-reggae volumio[865]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 13:01:38 gospel-reggae volumio[865]: info: CoreCommandRouter::volumioPlay Jun 09 13:01:38 gospel-reggae volumio[865]: info: CoreStateMachine::play index 2 Jun 09 13:01:38 gospel-reggae volumio[865]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 13:01:38 gospel-reggae volumio[865]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 09 13:01:38 gospel-reggae volumio[865]: info: CoreStateMachine::stop Jun 09 13:01:38 gospel-reggae volumio[865]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 13:01:38 gospel-reggae volumio[865]: info: CoreStateMachine::stop Jun 09 13:01:38 gospel-reggae volumio[865]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 13:01:38 gospel-reggae volumio[865]: info: CoreStateMachine::play index undefined Jun 09 13:01:38 gospel-reggae volumio[865]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 13:01:38 gospel-reggae volumio[865]: info: CoreStateMachine::play index undefined Jun 09 13:01:38 gospel-reggae volumio[865]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 13:01:38 gospel-reggae volumio[865]: info: CorePlayQueue::getTrack 2 Jun 09 13:01:38 gospel-reggae volumio[865]: info: CoreStateMachine::startPlaybackTimer Jun 09 13:01:38 gospel-reggae volumio[865]: info: CorePlayQueue::getTrack 2 Jun 09 13:01:38 gospel-reggae volumio[865]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22-18rr4X1MWI%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22BEST%20GOSPEL%20REGGAE%20%5B%20video%20mix%20%5D%20CHRISTIANITY%20WORSHIP%20REGGAE%20REMIX%20JUNE%202025%20BY%20ZJ%20DERO.%22%2C%22artist%22%3A%22Zj%20Dero%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F-18rr4X1MWI%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBjbggyMdZrpyInhVX1Jx8gsLRycQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22-18rr4X1MWI%22%7D%7D%7D Jun 09 13:01:38 gospel-reggae volumio[865]: info: CorePlayQueue::getTrack 2 Jun 09 13:01:38 gospel-reggae volumio[865]: info: CoreStateMachine::startPlaybackTimer Jun 09 13:01:38 gospel-reggae volumio[865]: info: CorePlayQueue::getTrack 2 Jun 09 13:01:38 gospel-reggae volumio[865]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22-18rr4X1MWI%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22BEST%20GOSPEL%20REGGAE%20%5B%20video%20mix%20%5D%20CHRISTIANITY%20WORSHIP%20REGGAE%20REMIX%20JUNE%202025%20BY%20ZJ%20DERO.%22%2C%22artist%22%3A%22Zj%20Dero%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F-18rr4X1MWI%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBjbggyMdZrpyInhVX1Jx8gsLRycQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22-18rr4X1MWI%22%7D%7D%7D Jun 09 13:01:40 gospel-reggae volumio[865]: info: Preload queue cleared Jun 09 13:01:40 gospel-reggae volumio[865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 09 13:01:40 gospel-reggae volumio[865]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 09 13:01:40 gospel-reggae volumio[865]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 09 13:01:40 gospel-reggae volumio[865]: info: Setting Geolocation for MyVolumio to us1 Jun 09 13:01:40 gospel-reggae volumio[865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 13:01:40 gospel-reggae volumio[865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 13:01:40 gospel-reggae volumio[865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 13:01:41 gospel-reggae volumio[865]: info: [youtube2] InnertubeLoader: obtaining po_token by datasyncIdToken... Jun 09 13:01:44 gospel-reggae volumio[865]: info: Updating MyVolumio device info Jun 09 13:01:44 gospel-reggae volumio[865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 13:01:44 gospel-reggae volumio[865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 13:01:44 gospel-reggae volumio[865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 13:01:44 gospel-reggae volumio[865]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 09 13:01:45 gospel-reggae volumio[865]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 09 13:01:45 gospel-reggae volumio[865]: info: CoreCommandRouter::volumioGetState Jun 09 13:01:45 gospel-reggae volumio[865]: info: CorePlayQueue::getTrack 2 Jun 09 13:01:45 gospel-reggae volumio[865]: info: BOOT COMPLETED Jun 09 13:01:45 gospel-reggae volumio[865]: info: Preload queue cleared Jun 09 13:01:46 gospel-reggae volumio[865]: info: Listing playlists Jun 09 13:01:46 gospel-reggae volumio[865]: info: Listing playlists Jun 09 13:01:46 gospel-reggae volumio[865]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 09 13:01:52 gospel-reggae volumio[865]: info: Preload queue cleared Jun 09 13:01:52 gospel-reggae volumio[865]: info: CoreCommandRouter::volumioAddQueueItems Jun 09 13:01:52 gospel-reggae volumio[865]: info: CoreStateMachine::addQueueItems Jun 09 13:01:52 gospel-reggae volumio[865]: info: CorePlayQueue::addQueueItems Jun 09 13:01:52 gospel-reggae volumio[865]: info: Preload queue cleared Jun 09 13:01:52 gospel-reggae volumio[865]: info: Adding Item to queue: https://stream.radio.co/s09849366f/listen Jun 09 13:01:52 gospel-reggae volumio[865]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 09 13:01:52 gospel-reggae volumio[865]: info: CoreCommandRouter::volumioPushQueue Jun 09 13:01:52 gospel-reggae volumio[865]: info: CorePlayQueue::saveQueue Jun 09 13:01:52 gospel-reggae volumio[865]: info: CoreStateMachine::updateTrackBlock Jun 09 13:01:52 gospel-reggae volumio[865]: info: CorePlayQueue::getTrackBlock Jun 09 13:01:52 gospel-reggae volumio[865]: info: CoreCommandRouter::volumioPlay Jun 09 13:01:52 gospel-reggae volumio[865]: info: CoreStateMachine::play index 4 Jun 09 13:01:52 gospel-reggae volumio[865]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 13:01:52 gospel-reggae volumio[865]: info: CoreStateMachine::stop Jun 09 13:01:52 gospel-reggae volumio[865]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 13:01:52 gospel-reggae volumio[865]: info: CoreStateMachine::play index undefined Jun 09 13:01:52 gospel-reggae volumio[865]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 13:01:52 gospel-reggae volumio[865]: info: CorePlayQueue::getTrack 4 Jun 09 13:01:52 gospel-reggae volumio[865]: info: CoreStateMachine::startPlaybackTimer Jun 09 13:01:52 gospel-reggae volumio[865]: info: CorePlayQueue::getTrack 4 Jun 09 13:01:52 gospel-reggae volumio[865]: info: [1749488512136] ControllerWebradio::clearAddPlayTrack Jun 09 13:01:52 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand stop Jun 09 13:01:52 gospel-reggae volumio[865]: info: sendMpdCommand stop took 231 milliseconds Jun 09 13:01:52 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand clear Jun 09 13:01:52 gospel-reggae volumio[865]: info: Jun 09 13:01:52 gospel-reggae volumio[865]: ---------------------------- MPD announces system playlist update Jun 09 13:01:52 gospel-reggae volumio[865]: info: Ignoring MPD Status Update Jun 09 13:01:52 gospel-reggae volumio[865]: info: sendMpdCommand clear took 23 milliseconds Jun 09 13:01:52 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand load "https://stream.radio.co/s09849366f/listen" Jun 09 13:01:52 gospel-reggae volumio[865]: info: Jun 09 13:01:52 gospel-reggae volumio[865]: ---------------------------- MPD announces system playlist update Jun 09 13:01:52 gospel-reggae volumio[865]: info: Ignoring MPD Status Update Jun 09 13:01:52 gospel-reggae volumio[865]: info: Jun 09 13:01:52 gospel-reggae volumio[865]: ---------------------------- MPD announces system playlist update Jun 09 13:01:52 gospel-reggae volumio[865]: info: Ignoring MPD Status Update Jun 09 13:01:52 gospel-reggae volumio[865]: error: updateQueue error: null Jun 09 13:01:52 gospel-reggae volumio[865]: info: ------------------------------ 92ms Jun 09 13:01:52 gospel-reggae volumio[865]: info: CoreCommandRouter::volumioGetState Jun 09 13:01:52 gospel-reggae volumio[865]: info: CorePlayQueue::getTrack 4 Jun 09 13:01:52 gospel-reggae volumio[865]: info: [youtube2] InnertubeLoader: obtained po_token (expires in 43200 seconds) Jun 09 13:01:52 gospel-reggae volumio[865]: info: [youtube2] InnertubeLoader: re-create Innertube instance with po_token Jun 09 13:01:52 gospel-reggae volumio[865]: info: [youtube2] InnertubeLoader: creating Innertube instance with po_token... Jun 09 13:01:54 gospel-reggae volumio[865]: error: updateQueue error: null Jun 09 13:01:54 gospel-reggae volumio[865]: error: updateQueue error: null Jun 09 13:01:54 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radio.co/s09849366f/listen" Jun 09 13:01:54 gospel-reggae volumio[865]: info: ------------------------------ 2471ms Jun 09 13:01:54 gospel-reggae volumio[865]: info: ------------------------------ 2452ms Jun 09 13:01:54 gospel-reggae volumio[865]: info: Jun 09 13:01:54 gospel-reggae volumio[865]: ---------------------------- MPD announces system playlist update Jun 09 13:01:54 gospel-reggae volumio[865]: info: Ignoring MPD Status Update Jun 09 13:01:54 gospel-reggae volumio[865]: info: sendMpdCommand add "https://stream.radio.co/s09849366f/listen" took 32 milliseconds Jun 09 13:01:54 gospel-reggae volumio[865]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 09 13:01:54 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand play Jun 09 13:01:54 gospel-reggae volumio[865]: info: Jun 09 13:01:54 gospel-reggae volumio[865]: ---------------------------- MPD announces system playlist update Jun 09 13:01:54 gospel-reggae volumio[865]: info: Ignoring MPD Status Update Jun 09 13:01:54 gospel-reggae volumio[865]: info: Jun 09 13:01:54 gospel-reggae volumio[865]: ---------------------------- MPD announces system playlist update Jun 09 13:01:54 gospel-reggae volumio[865]: info: Ignoring MPD Status Update Jun 09 13:01:55 gospel-reggae volumio[865]: info: ------------------------------ 97ms Jun 09 13:01:55 gospel-reggae volumio[865]: info: sendMpdCommand play took 68 milliseconds Jun 09 13:01:55 gospel-reggae volumio[865]: info: ------------------------------ 62ms Jun 09 13:01:55 gospel-reggae volumio[865]: info: ------------------------------ 46ms Jun 09 13:02:16 gospel-reggae systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 09 13:02:17 gospel-reggae dbus-daemon[474]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.23' (uid=0 pid=1502 comm="timedatectl show --property=NTPSynchronized --valu") Jun 09 13:02:17 gospel-reggae systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 09 13:02:17 gospel-reggae dbus-daemon[474]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 09 13:02:17 gospel-reggae systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 09 13:02:17 gospel-reggae setdatetime-helper.sh[1501]: Time is not synchronized. Attempting to sync... Jun 09 13:02:18 gospel-reggae sudo[1524]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Mon, 09 Jun 2025 17:02:18 GMT#015' Jun 09 13:02:18 gospel-reggae sudo[1524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 09 13:02:18 gospel-reggae setdatetime-helper.sh[1525]: Mon Jun 9 13:02:18 EDT 2025 Jun 09 13:02:18 gospel-reggae setdatetime-helper.sh[1501]: Time synchronized successfully. Jun 09 13:02:18 gospel-reggae systemd-journald[307]: Time jumped backwards, rotating. Jun 09 13:02:18 gospel-reggae sudo[1524]: pam_unix(sudo:session): session closed for user root Jun 09 13:02:18 gospel-reggae systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 09 13:02:18 gospel-reggae systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 09 13:02:41 gospel-reggae volumio[865]: info: [youtube2] InnertubeLoader: going to refresh po_token in 43100 seconds Jun 09 13:02:42 gospel-reggae volumio[865]: info: Jun 09 13:02:42 gospel-reggae volumio[865]: ---------------------------- MPD announces state update: player Jun 09 13:02:42 gospel-reggae volumio[865]: info: ControllerMpd::getState Jun 09 13:02:42 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand status Jun 09 13:02:42 gospel-reggae volumio[865]: info: Jun 09 13:02:42 gospel-reggae volumio[865]: ---------------------------- MPD announces state update: player Jun 09 13:02:42 gospel-reggae volumio[865]: info: ControllerMpd::getState Jun 09 13:02:42 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand status Jun 09 13:02:42 gospel-reggae volumio[865]: info: Jun 09 13:02:42 gospel-reggae volumio[865]: ---------------------------- MPD announces state update: player Jun 09 13:02:42 gospel-reggae volumio[865]: info: Jun 09 13:02:42 gospel-reggae volumio[865]: ---------------------------- MPD announces system playlist update Jun 09 13:02:42 gospel-reggae volumio[865]: info: Ignoring MPD Status Update Jun 09 13:02:42 gospel-reggae volumio[865]: info: Jun 09 13:02:42 gospel-reggae volumio[865]: ---------------------------- MPD announces state update: player Jun 09 13:02:42 gospel-reggae volumio[865]: info: ControllerMpd::getState Jun 09 13:02:42 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand status Jun 09 13:02:42 gospel-reggae volumio[865]: info: sendMpdCommand status took 50 milliseconds Jun 09 13:02:42 gospel-reggae volumio[865]: info: ControllerMpd::getState Jun 09 13:02:42 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand status Jun 09 13:02:42 gospel-reggae volumio[865]: info: sendMpdCommand status took 33 milliseconds Jun 09 13:02:42 gospel-reggae volumio[865]: verbose: ControllerMpd::parseState Jun 09 13:02:42 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 13:02:42 gospel-reggae volumio[865]: verbose: ControllerMpd::parseState Jun 09 13:02:42 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 13:02:42 gospel-reggae volumio[865]: info: Preload queue cleared Jun 09 13:02:42 gospel-reggae volumio[865]: info: CoreCommandRouter::volumioAddQueueItems Jun 09 13:02:42 gospel-reggae volumio[865]: info: CoreStateMachine::addQueueItems Jun 09 13:02:42 gospel-reggae volumio[865]: info: CorePlayQueue::addQueueItems Jun 09 13:02:42 gospel-reggae volumio[865]: info: Preload queue cleared Jun 09 13:02:42 gospel-reggae volumio[865]: info: Adding Item to queue: https://stream.radio.co/s09849366f/listen Jun 09 13:02:42 gospel-reggae volumio[865]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 09 13:02:42 gospel-reggae volumio[865]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 09 13:02:42 gospel-reggae volumio[865]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 09 13:02:42 gospel-reggae volumio[865]: info: CoreCommandRouter::volumioPushQueue Jun 09 13:02:42 gospel-reggae volumio[865]: info: CorePlayQueue::saveQueue Jun 09 13:02:42 gospel-reggae volumio[865]: info: CoreStateMachine::updateTrackBlock Jun 09 13:02:42 gospel-reggae volumio[865]: info: CorePlayQueue::getTrackBlock Jun 09 13:02:42 gospel-reggae volumio[865]: info: CoreCommandRouter::volumioPlay Jun 09 13:02:42 gospel-reggae volumio[865]: info: CoreStateMachine::play index 4 Jun 09 13:02:42 gospel-reggae volumio[865]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 13:02:42 gospel-reggae volumio[865]: info: CoreStateMachine::stop Jun 09 13:02:42 gospel-reggae volumio[865]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 13:02:42 gospel-reggae volumio[865]: info: CoreStateMachine::play index undefined Jun 09 13:02:42 gospel-reggae volumio[865]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 13:02:42 gospel-reggae volumio[865]: info: CorePlayQueue::getTrack 4 Jun 09 13:02:42 gospel-reggae volumio[865]: info: CoreStateMachine::startPlaybackTimer Jun 09 13:02:42 gospel-reggae volumio[865]: info: CorePlayQueue::getTrack 4 Jun 09 13:02:42 gospel-reggae volumio[865]: info: [1749488562465] ControllerWebradio::clearAddPlayTrack Jun 09 13:02:42 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand stop Jun 09 13:02:42 gospel-reggae volumio[865]: info: Jun 09 13:02:42 gospel-reggae volumio[865]: ---------------------------- MPD announces system playlist update Jun 09 13:02:42 gospel-reggae volumio[865]: info: Ignoring MPD Status Update Jun 09 13:02:42 gospel-reggae volumio[865]: info: Jun 09 13:02:42 gospel-reggae volumio[865]: ---------------------------- MPD announces state update: player Jun 09 13:02:42 gospel-reggae volumio[865]: info: ControllerMpd::getState Jun 09 13:02:42 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand status Jun 09 13:02:42 gospel-reggae volumio[865]: info: Jun 09 13:02:42 gospel-reggae volumio[865]: ---------------------------- MPD announces system playlist update Jun 09 13:02:42 gospel-reggae volumio[865]: info: Ignoring MPD Status Update Jun 09 13:02:42 gospel-reggae volumio[865]: info: Jun 09 13:02:42 gospel-reggae volumio[865]: ---------------------------- MPD announces state update: player Jun 09 13:02:42 gospel-reggae volumio[865]: info: ControllerMpd::getState Jun 09 13:02:42 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand status Jun 09 13:02:42 gospel-reggae volumio[865]: info: Jun 09 13:02:42 gospel-reggae volumio[865]: ---------------------------- MPD announces state update: player Jun 09 13:02:42 gospel-reggae volumio[865]: info: sendMpdCommand status took 577 milliseconds Jun 09 13:02:42 gospel-reggae volumio[865]: info: ------------------------------ 582ms Jun 09 13:02:42 gospel-reggae volumio[865]: info: sendMpdCommand status took 561 milliseconds Jun 09 13:02:42 gospel-reggae volumio[865]: info: sendMpdCommand playlistinfo took 561 milliseconds Jun 09 13:02:42 gospel-reggae volumio[865]: info: sendMpdCommand playlistinfo took 554 milliseconds Jun 09 13:02:42 gospel-reggae volumio[865]: info: sendMpdCommand stop took 362 milliseconds Jun 09 13:02:42 gospel-reggae volumio[865]: info: ControllerMpd::getState Jun 09 13:02:42 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand status Jun 09 13:02:42 gospel-reggae volumio[865]: info: ------------------------------ 91ms Jun 09 13:02:42 gospel-reggae volumio[865]: info: sendMpdCommand status took 78 milliseconds Jun 09 13:02:42 gospel-reggae volumio[865]: info: ------------------------------ 69ms Jun 09 13:02:42 gospel-reggae volumio[865]: info: sendMpdCommand status took 77 milliseconds Jun 09 13:02:42 gospel-reggae volumio[865]: verbose: ControllerMpd::parseState Jun 09 13:02:42 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 13:02:42 gospel-reggae volumio[865]: verbose: ControllerMpd::parseState Jun 09 13:02:42 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 13:02:42 gospel-reggae volumio[865]: verbose: ControllerMpd::parseTrackInfo Jun 09 13:02:42 gospel-reggae volumio[865]: verbose: ControllerMpd::parseTrackInfo Jun 09 13:02:42 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand clear Jun 09 13:02:42 gospel-reggae volumio[865]: verbose: ControllerMpd::parseState Jun 09 13:02:42 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 13:02:42 gospel-reggae volumio[865]: verbose: ControllerMpd::parseState Jun 09 13:02:42 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 13:02:42 gospel-reggae volumio[865]: info: ControllerMpd::pushState Jun 09 13:02:42 gospel-reggae volumio[865]: info: CoreCommandRouter::servicePushState Jun 09 13:02:42 gospel-reggae volumio[865]: info: CorePlayQueue::getTrack 4 Jun 09 13:02:42 gospel-reggae volumio[865]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 09 13:02:42 gospel-reggae volumio[865]: info: ControllerMpd::pushState Jun 09 13:02:42 gospel-reggae volumio[865]: info: CoreCommandRouter::servicePushState Jun 09 13:02:42 gospel-reggae volumio[865]: info: CorePlayQueue::getTrack 4 Jun 09 13:02:42 gospel-reggae volumio[865]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 09 13:02:42 gospel-reggae volumio[865]: info: ------------------------------ 765ms Jun 09 13:02:42 gospel-reggae volumio[865]: info: ------------------------------ 744ms Jun 09 13:02:42 gospel-reggae volumio[865]: Upnp client error: Error: This socket has been ended by the other party Jun 09 13:02:43 gospel-reggae volumio[865]: info: Jun 09 13:02:43 gospel-reggae volumio[865]: ---------------------------- MPD announces state update: player Jun 09 13:02:43 gospel-reggae volumio[865]: info: ControllerMpd::getState Jun 09 13:02:43 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand status Jun 09 13:02:43 gospel-reggae volumio[865]: info: Jun 09 13:02:43 gospel-reggae volumio[865]: ---------------------------- MPD announces state update: player Jun 09 13:02:43 gospel-reggae volumio[865]: info: ControllerMpd::getState Jun 09 13:02:43 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand status Jun 09 13:02:43 gospel-reggae volumio[865]: info: Jun 09 13:02:43 gospel-reggae volumio[865]: ---------------------------- MPD announces system playlist update Jun 09 13:02:43 gospel-reggae volumio[865]: info: Ignoring MPD Status Update Jun 09 13:02:43 gospel-reggae volumio[865]: info: sendMpdCommand status took 276 milliseconds Jun 09 13:02:43 gospel-reggae volumio[865]: info: sendMpdCommand playlistinfo took 271 milliseconds Jun 09 13:02:43 gospel-reggae volumio[865]: info: sendMpdCommand playlistinfo took 270 milliseconds Jun 09 13:02:43 gospel-reggae volumio[865]: info: sendMpdCommand clear took 240 milliseconds Jun 09 13:02:43 gospel-reggae volumio[865]: info: sendMpdCommand playlistinfo took 244 milliseconds Jun 09 13:02:43 gospel-reggae volumio[865]: info: sendMpdCommand playlistinfo took 243 milliseconds Jun 09 13:02:43 gospel-reggae volumio[865]: info: sendMpdCommand status took 41 milliseconds Jun 09 13:02:43 gospel-reggae volumio[865]: info: sendMpdCommand status took 28 milliseconds Jun 09 13:02:43 gospel-reggae volumio[865]: verbose: ControllerMpd::parseState Jun 09 13:02:43 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 13:02:43 gospel-reggae volumio[865]: verbose: ControllerMpd::parseTrackInfo Jun 09 13:02:43 gospel-reggae volumio[865]: verbose: ControllerMpd::parseTrackInfo Jun 09 13:02:43 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand load "https://stream.radio.co/s09849366f/listen" Jun 09 13:02:43 gospel-reggae volumio[865]: verbose: ControllerMpd::parseTrackInfo Jun 09 13:02:43 gospel-reggae volumio[865]: verbose: ControllerMpd::parseTrackInfo Jun 09 13:02:43 gospel-reggae volumio[865]: verbose: ControllerMpd::parseState Jun 09 13:02:43 gospel-reggae volumio[865]: verbose: ControllerMpd::parseState Jun 09 13:02:43 gospel-reggae volumio[865]: info: ControllerMpd::pushState Jun 09 13:02:43 gospel-reggae volumio[865]: info: CoreCommandRouter::servicePushState Jun 09 13:02:43 gospel-reggae volumio[865]: info: CorePlayQueue::getTrack 4 Jun 09 13:02:43 gospel-reggae volumio[865]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 09 13:02:43 gospel-reggae volumio[865]: info: ControllerMpd::pushState Jun 09 13:02:43 gospel-reggae volumio[865]: info: CoreCommandRouter::servicePushState Jun 09 13:02:43 gospel-reggae volumio[865]: info: CorePlayQueue::getTrack 4 Jun 09 13:02:43 gospel-reggae volumio[865]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 09 13:02:43 gospel-reggae volumio[865]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 09 13:02:43 gospel-reggae volumio[865]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 09 13:02:43 gospel-reggae volumio[865]: info: ControllerMpd::pushState Jun 09 13:02:43 gospel-reggae volumio[865]: info: CoreCommandRouter::servicePushState Jun 09 13:02:43 gospel-reggae volumio[865]: info: CoreStateMachine::pushState Jun 09 13:02:43 gospel-reggae volumio[865]: info: CorePlayQueue::getTrack 4 Jun 09 13:02:43 gospel-reggae volumio[865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 13:02:43 gospel-reggae volumio[865]: info: CoreCommandRouter::volumioPushState Jun 09 13:02:43 gospel-reggae volumio[865]: info: CorePlayQueue::getTrack 4 Jun 09 13:02:43 gospel-reggae volumio[865]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 09 13:02:43 gospel-reggae volumio[865]: info: ControllerMpd::pushState Jun 09 13:02:43 gospel-reggae volumio[865]: info: CoreCommandRouter::servicePushState Jun 09 13:02:43 gospel-reggae volumio[865]: info: CoreStateMachine::pushState Jun 09 13:02:43 gospel-reggae volumio[865]: info: CorePlayQueue::getTrack 4 Jun 09 13:02:43 gospel-reggae volumio[865]: info: CoreCommandRouter::volumioPushState Jun 09 13:02:43 gospel-reggae volumio[865]: info: CorePlayQueue::getTrack 4 Jun 09 13:02:43 gospel-reggae volumio[865]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 09 13:02:43 gospel-reggae volumio[865]: info: ------------------------------ 1139ms Jun 09 13:02:43 gospel-reggae volumio[865]: info: ------------------------------ 1150ms Jun 09 13:02:43 gospel-reggae volumio[865]: info: ------------------------------ 620ms Jun 09 13:02:43 gospel-reggae volumio[865]: info: ------------------------------ 602ms Jun 09 13:02:43 gospel-reggae volumio[865]: info: ------------------------------ 570ms Jun 09 13:02:43 gospel-reggae volumio[865]: info: ------------------------------ 565ms Jun 09 13:02:44 gospel-reggae volumio[865]: info: CoreCommandRouter::volumioGetState Jun 09 13:02:44 gospel-reggae volumio[865]: info: CorePlayQueue::getTrack 4 Jun 09 13:02:44 gospel-reggae volumio[865]: info: Jun 09 13:02:44 gospel-reggae volumio[865]: ---------------------------- MPD announces system playlist update Jun 09 13:02:44 gospel-reggae volumio[865]: info: Ignoring MPD Status Update Jun 09 13:02:44 gospel-reggae volumio[865]: info: Jun 09 13:02:44 gospel-reggae volumio[865]: ---------------------------- MPD announces system playlist update Jun 09 13:02:44 gospel-reggae volumio[865]: info: Ignoring MPD Status Update Jun 09 13:02:44 gospel-reggae volumio[865]: error: updateQueue error: null Jun 09 13:02:44 gospel-reggae volumio[865]: info: ------------------------------ 1275ms Jun 09 13:02:44 gospel-reggae volumio[865]: info: sendMpdCommand playlistinfo took 1242 milliseconds Jun 09 13:02:44 gospel-reggae volumio[865]: verbose: ControllerMpd::parseTrackInfo Jun 09 13:02:44 gospel-reggae volumio[865]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 09 13:02:44 gospel-reggae volumio[865]: info: ------------------------------ 1611ms Jun 09 13:02:44 gospel-reggae volumio[865]: error: updateQueue error: null Jun 09 13:02:44 gospel-reggae volumio[865]: error: updateQueue error: null Jun 09 13:02:44 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radio.co/s09849366f/listen" Jun 09 13:02:44 gospel-reggae volumio[865]: info: ------------------------------ 569ms Jun 09 13:02:44 gospel-reggae volumio[865]: info: ------------------------------ 568ms Jun 09 13:02:44 gospel-reggae volumio[865]: info: Listing playlists Jun 09 13:02:44 gospel-reggae volumio[865]: info: Listing playlists Jun 09 13:02:45 gospel-reggae volumio[865]: info: Jun 09 13:02:45 gospel-reggae volumio[865]: ---------------------------- MPD announces system playlist update Jun 09 13:02:45 gospel-reggae volumio[865]: info: Ignoring MPD Status Update Jun 09 13:02:45 gospel-reggae volumio[865]: info: sendMpdCommand add "https://stream.radio.co/s09849366f/listen" took 202 milliseconds Jun 09 13:02:45 gospel-reggae volumio[865]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 09 13:02:45 gospel-reggae volumio[865]: verbose: ControllerMpd::sendMpdCommand play Jun 09 13:02:45 gospel-reggae volumio[865]: info: Jun 09 13:02:45 gospel-reggae volumio[865]: ---------------------------- MPD announces system playlist update Jun 09 13:02:45 gospel-reggae volumio[865]: info: Ignoring MPD Status Update Jun 09 13:02:45 gospel-reggae volumio[865]: info: Jun 09 13:02:45 gospel-reggae volumio[865]: ---------------------------- MPD announces system playlist update Jun 09 13:02:45 gospel-reggae volumio[865]: info: Ignoring MPD Status Update Jun 09 13:02:45 gospel-reggae volumio[865]: info: READ Jun 09 13:02:45 gospel-reggae volumio[865]: Use XMLElement.setAttribute instead Jun 09 13:02:45 gospel-reggae volumio[865]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 13:02:45 gospel-reggae volumio[865]: TypeError: children[i].attr(...).value is not a function Jun 09 13:02:45 gospel-reggae volumio[865]: at /volumio/app/plugins/music_service/webradio/index.js:286:47 Jun 09 13:02:45 gospel-reggae volumio[865]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 09 13:02:45 gospel-reggae volumio[865]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 13:02:47 gospel-reggae systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 09 13:02:52 gospel-reggae sudo[1587]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-09 13:01' Jun 09 13:02:52 gospel-reggae sudo[1587]: 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"