Aug 28 22:57:58 gospel-reggae volumio[1005]: verbose: New Socket.io Connection to 192.168.72.21 from 192.168.59.142 UA: Mozilla/5.0 (Linux; Android 17; Pixel 7 Build/CP2A.260705.006; 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: 8 Aug 28 22:57:58 gospel-reggae volumio[1005]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 28 22:57:58 gospel-reggae volumio[1005]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 28 22:57:58 gospel-reggae volumio[1005]: info: CoreCommandRouter::volumioGetVisibleSources Aug 28 22:57:58 gospel-reggae volumio[1005]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 22:57:58 gospel-reggae volumio[1005]: info: CoreCommandRouter::volumioGetState Aug 28 22:57:58 gospel-reggae volumio[1005]: info: CorePlayQueue::getTrack 145 Aug 28 22:57:58 gospel-reggae volumio[1005]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 28 22:57:58 gospel-reggae volumio[1005]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 28 22:57:58 gospel-reggae volumio[1005]: info: Received Get System Info Aug 28 22:57:58 gospel-reggae volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 22:57:58 gospel-reggae volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 22:57:58 gospel-reggae volumio[1005]: info: Discovery: Getting this device information Aug 28 22:57:58 gospel-reggae volumio[1005]: info: CoreCommandRouter::volumioGetState Aug 28 22:57:58 gospel-reggae volumio[1005]: info: CorePlayQueue::getTrack 145 Aug 28 22:57:58 gospel-reggae volumio[1005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 22:57:58 gospel-reggae volumio[1005]: info: CoreCommandRouter::volumioGetState Aug 28 22:57:58 gospel-reggae volumio[1005]: info: CorePlayQueue::getTrack 145 Aug 28 22:57:58 gospel-reggae volumio[1005]: info: Listing playlists Aug 28 22:58:15 gospel-reggae volumio[1005]: info: CorePlayQueue::getTrack 145 Aug 28 22:58:15 gospel-reggae volumio[1005]: info: CorePlayQueue::getTrack 146 Aug 28 22:58:15 gospel-reggae volumio[1005]: info: Prefetching next song Aug 28 22:58:15 gospel-reggae volumio[1005]: info: [1787972295700] ControllerVolusonic::prefetch Aug 28 22:58:15 gospel-reggae volumio[1005]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.72.3:4747/rest/stream.view?id=tr-1785&format=raw&u=dagonic&p=enc:744c582a5e215a355c2b4b713c2b4e536642367b2553296d&v=1.10.2&f=json&c=volusonic" Aug 28 22:58:15 gospel-reggae volumio[1005]: info: sendMpdCommand add "http://192.168.72.3:4747/rest/stream.view?id=tr-1785&format=raw&u=dagonic&p=enc:744c582a5e215a355c2b4b713c2b4e536642367b2553296d&v=1.10.2&f=json&c=volusonic" took 5 milliseconds Aug 28 22:58:15 gospel-reggae volumio[1005]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 28 22:58:15 gospel-reggae volumio[1005]: info: Aug 28 22:58:15 gospel-reggae volumio[1005]: ---------------------------- MPD announces system playlist update Aug 28 22:58:15 gospel-reggae volumio[1005]: info: Ignoring MPD Status Update Aug 28 22:58:15 gospel-reggae volumio[1005]: info: sendMpdCommand consume 1 took 2 milliseconds Aug 28 22:58:15 gospel-reggae volumio[1005]: info: Aug 28 22:58:15 gospel-reggae volumio[1005]: ---------------------------- MPD announces system playlist update Aug 28 22:58:15 gospel-reggae volumio[1005]: info: Ignoring MPD Status Update Aug 28 22:58:15 gospel-reggae volumio[1005]: info: Aug 28 22:58:15 gospel-reggae volumio[1005]: ---------------------------- MPD announces system playlist update Aug 28 22:58:15 gospel-reggae volumio[1005]: info: Ignoring MPD Status Update Aug 28 22:58:15 gospel-reggae volumio[1005]: info: ------------------------------ 8ms Aug 28 22:58:15 gospel-reggae volumio[1005]: info: ------------------------------ 5ms Aug 28 22:58:15 gospel-reggae volumio[1005]: info: ------------------------------ 4ms Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CoreStateMachine::startPlaybackTimer Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CorePlayQueue::getTrack 146 Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CoreStateMachine::pushState Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CorePlayQueue::getTrack 146 Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CoreCommandRouter::volumioPushState Aug 28 22:58:20 gospel-reggae volumio5-onboarding[1279]: time=2026-08-28T22:58:20.605-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.59.142:46438 @ 0x28001e0" state=STATUS_PLAYING positionMs=251 volume=65 Aug 28 22:58:20 gospel-reggae volumio5-onboarding[1279]: time=2026-08-28T22:58:20.611-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.59.142:46438 @ 0x28001e0" id="http://192.168.72.3:4747/rest/stream.view?id=tr-1785&format=raw&u=dagonic&p=enc:744c582a5e215a355c2b4b713c2b4e536642367b2553296d&v=1.10.2&f=json&c=volusonic" title=Anointing Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CoreCommandRouter::volumioGetQueue Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CoreStateMachine::getQueue Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CorePlayQueue::getQueue Aug 28 22:58:20 gospel-reggae volumio[1005]: info: Aug 28 22:58:20 gospel-reggae volumio[1005]: ---------------------------- MPD announces system playlist update Aug 28 22:58:20 gospel-reggae volumio[1005]: info: Ignoring MPD Status Update Aug 28 22:58:20 gospel-reggae volumio[1005]: info: Aug 28 22:58:20 gospel-reggae volumio[1005]: ---------------------------- MPD announces state update: player Aug 28 22:58:20 gospel-reggae volumio[1005]: info: ControllerMpd::getState Aug 28 22:58:20 gospel-reggae volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Aug 28 22:58:20 gospel-reggae volumio[1005]: info: Aug 28 22:58:20 gospel-reggae volumio[1005]: ---------------------------- MPD announces system playlist update Aug 28 22:58:20 gospel-reggae volumio[1005]: info: Ignoring MPD Status Update Aug 28 22:58:20 gospel-reggae volumio[1005]: info: Aug 28 22:58:20 gospel-reggae volumio[1005]: ---------------------------- MPD announces state update: player Aug 28 22:58:20 gospel-reggae volumio[1005]: info: ControllerMpd::getState Aug 28 22:58:20 gospel-reggae volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Aug 28 22:58:20 gospel-reggae volumio[1005]: info: Aug 28 22:58:20 gospel-reggae volumio[1005]: ---------------------------- MPD announces system playlist update Aug 28 22:58:20 gospel-reggae volumio[1005]: info: Ignoring MPD Status Update Aug 28 22:58:20 gospel-reggae volumio[1005]: info: [1787972300932] ControllerVolusonic: MPD player state update Aug 28 22:58:20 gospel-reggae volumio[1005]: info: ControllerMpd::getState Aug 28 22:58:20 gospel-reggae volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Aug 28 22:58:20 gospel-reggae volumio[1005]: info: Aug 28 22:58:20 gospel-reggae volumio[1005]: ---------------------------- MPD announces state update: player Aug 28 22:58:20 gospel-reggae volumio[1005]: info: ControllerMpd::getState Aug 28 22:58:20 gospel-reggae volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Aug 28 22:58:20 gospel-reggae volumio[1005]: info: ------------------------------ 10ms Aug 28 22:58:20 gospel-reggae volumio[1005]: info: sendMpdCommand status took 8 milliseconds Aug 28 22:58:20 gospel-reggae volumio[1005]: info: ------------------------------ 8ms Aug 28 22:58:20 gospel-reggae volumio[1005]: info: sendMpdCommand status took 7 milliseconds Aug 28 22:58:20 gospel-reggae volumio[1005]: verbose: ControllerMpd::parseState Aug 28 22:58:20 gospel-reggae volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 22:58:20 gospel-reggae volumio[1005]: verbose: ControllerMpd::parseState Aug 28 22:58:20 gospel-reggae volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 22:58:20 gospel-reggae volumio[1005]: info: sendMpdCommand status took 7 milliseconds Aug 28 22:58:20 gospel-reggae volumio[1005]: info: ------------------------------ 9ms Aug 28 22:58:20 gospel-reggae volumio[1005]: info: sendMpdCommand status took 6 milliseconds Aug 28 22:58:20 gospel-reggae volumio[1005]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 28 22:58:20 gospel-reggae volumio[1005]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 28 22:58:20 gospel-reggae volumio[1005]: verbose: ControllerMpd::parseState Aug 28 22:58:20 gospel-reggae volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 22:58:20 gospel-reggae volumio[1005]: verbose: ControllerMpd::parseState Aug 28 22:58:20 gospel-reggae volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 22:58:20 gospel-reggae volumio[1005]: verbose: ControllerMpd::parseTrackInfo Aug 28 22:58:20 gospel-reggae volumio[1005]: verbose: ControllerMpd::parseTrackInfo Aug 28 22:58:20 gospel-reggae volumio[1005]: info: ControllerMpd::pushState Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CoreCommandRouter::servicePushState Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CorePlayQueue::getTrack 146 Aug 28 22:58:20 gospel-reggae volumio[1005]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 28 22:58:20 gospel-reggae volumio[1005]: info: ControllerMpd::pushState Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CoreCommandRouter::servicePushState Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CorePlayQueue::getTrack 146 Aug 28 22:58:20 gospel-reggae volumio[1005]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 28 22:58:20 gospel-reggae volumio[1005]: info: ------------------------------ 21ms Aug 28 22:58:20 gospel-reggae volumio[1005]: info: ------------------------------ 20ms Aug 28 22:58:20 gospel-reggae volumio[1005]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 28 22:58:20 gospel-reggae volumio[1005]: verbose: ControllerMpd::parseTrackInfo Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CorePlayQueue::getTrack 146 Aug 28 22:58:20 gospel-reggae volumio[1005]: info: [1787972300951] ControllerVolusonic::pushState Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CoreCommandRouter::servicePushState Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CorePlayQueue::getTrack 146 Aug 28 22:58:20 gospel-reggae volumio[1005]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34,"duration":167,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream.view?id=tr-1785&format=raw&u=dagonic&p=enc:744c582a5e215a355c2b4b713c2b4e536642367b2553296d&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.72.3:4747/rest/stream.view?id=tr-1785&format=raw&u=dagonic&p=enc:744c582a5e215a355c2b4b713c2b4e536642367b2553296d&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 28 22:58:20 gospel-reggae volumio[1005]: verbose: CURRENT POSITION 146 Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CoreStateMachine::syncState stateService play Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CoreStateMachine::syncState currentStatus play Aug 28 22:58:20 gospel-reggae volumio[1005]: info: Received an update from plugin. extracting info from payload Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CoreStateMachine::pushState Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CorePlayQueue::getTrack 146 Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CoreCommandRouter::volumioPushState Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CoreStateMachine::pushState Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CorePlayQueue::getTrack 146 Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CoreCommandRouter::volumioPushState Aug 28 22:58:20 gospel-reggae volumio5-onboarding[1279]: time=2026-08-28T22:58:20.968-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.59.142:46438 @ 0x28001e0" state=STATUS_PLAYING positionMs=34 volume=65 Aug 28 22:58:20 gospel-reggae volumio5-onboarding[1279]: time=2026-08-28T22:58:20.969-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.59.142:46438 @ 0x28001e0" id="http://192.168.72.3:4747/rest/stream.view?id=tr-1785&format=raw&u=dagonic&p=enc:744c582a5e215a355c2b4b713c2b4e536642367b2553296d&v=1.10.2&f=json&c=volusonic" title=Anointing Aug 28 22:58:20 gospel-reggae volumio5-onboarding[1279]: time=2026-08-28T22:58:20.971-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.59.142:46438 @ 0x28001e0" state=STATUS_PLAYING positionMs=34 volume=65 Aug 28 22:58:20 gospel-reggae volumio5-onboarding[1279]: time=2026-08-28T22:58:20.973-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.59.142:46438 @ 0x28001e0" id="http://192.168.72.3:4747/rest/stream.view?id=tr-1785&format=raw&u=dagonic&p=enc:744c582a5e215a355c2b4b713c2b4e536642367b2553296d&v=1.10.2&f=json&c=volusonic" title=Anointing Aug 28 22:58:20 gospel-reggae volumio[1005]: info: sendMpdCommand playlistinfo took 43 milliseconds Aug 28 22:58:20 gospel-reggae volumio[1005]: verbose: ControllerMpd::parseTrackInfo Aug 28 22:58:20 gospel-reggae volumio[1005]: info: ControllerMpd::pushState Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CoreCommandRouter::servicePushState Aug 28 22:58:20 gospel-reggae volumio[1005]: info: CorePlayQueue::getTrack 146 Aug 28 22:58:20 gospel-reggae volumio[1005]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 28 22:58:20 gospel-reggae volumio[1005]: info: ------------------------------ 55ms Aug 28 22:58:53 gospel-reggae volumio[1005]: info: [youtube2] Refresh session PO token Aug 28 22:58:53 gospel-reggae volumio[1005]: info: [youtube2] Obtained session PO token using visitorData (expires in 43104 seconds) Aug 28 22:58:53 gospel-reggae volumio[1005]: info: [youtube2] Going to refresh session PO token in 43004 seconds Aug 28 22:58:53 gospel-reggae volumio[1005]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 22:58:53 gospel-reggae volumio[1005]: TypeError: Chaining cycle detected for promise # Aug 28 22:58:53 gospel-reggae volumio[1005]: at InnertubeWrapper._InnertubeWrapper_doGetSessionPoToken (/data/plugins/music_service/youtube2/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:191:12) Aug 28 22:58:53 gospel-reggae volumio[1005]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Aug 28 22:58:53 gospel-reggae volumio[1005]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 22:58:55 gospel-reggae sudo[20433]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-28 22:57' Aug 28 22:58:55 gospel-reggae sudo[20433]: 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"