-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Thu 2025-05-01 14:09:20 EDT. -- May 01 14:08:03 pi-volumio pulseaudio[1699]: Too many underruns, increasing latency to 210.00 ms May 01 14:08:06 pi-volumio bluetoothd[788]: Unable to get io data for :1.49/Profile/HSPHSProfile/00001108-0000-1000-8000-00805f9b34fb: getpeername: Transport endpoint is not connected (107) May 01 14:08:06 pi-volumio dbus-daemon[637]: [system] Rejected send message, 1 matched rules; type="method_return", sender=":1.49" (uid=1000 pid=1699 comm="/usr/bin/pulseaudio --high-priority=true ") interface="(unset)" member="(unset)" error name="(unset)" requested_reply="0" destination=":1.5" (uid=0 pid=788 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") May 01 14:08:10 pi-volumio volumio[998]: info: [jellyfin-poller] Polled http://192.168.1.61:8096: offline May 01 14:08:13 pi-volumio volumiologrotate[631]: ls: cannot access '/var/log/samba/log.wb-PI': No such file or directory May 01 14:08:13 pi-volumio volumiologrotate[631]: ls: cannot access 'VOLUMIO': No such file or directory May 01 14:08:20 pi-volumio pulseaudio[1699]: Configured latency of 200.00 ms is smaller than minimum latency, using minimum instead May 01 14:08:20 pi-volumio pulseaudio[1699]: Cannot set requested source latency of 66.67 ms, adjusting to 250.00 ms May 01 14:08:22 pi-volumio kernel: input: 94:45:60:84:B1:4D as /devices/virtual/input/input4 May 01 14:08:22 pi-volumio systemd-udevd[30988]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 01 14:08:22 pi-volumio systemd-logind[626]: Watching system buttons on /dev/input/event0 (94:45:60:84:B1:4D) May 01 14:08:23 pi-volumio volumio[998]: info: VolumeController::SetAlsaVolume19 May 01 14:08:23 pi-volumio volumio[998]: BT PLUGIN MESSAGE: Volume set to 19 May 01 14:08:23 pi-volumio volumio[998]: info: CoreCommandRouter::volumioGetState May 01 14:08:23 pi-volumio volumio[998]: info: CorePlayQueue::getTrack 0 May 01 14:08:23 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:08:23 pi-volumio volumio[998]: info: CorePlayQueue::getTrack 0 May 01 14:08:23 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:08:23 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:08:23 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:08:23 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 May 01 14:08:23 pi-volumio volumio[998]: BT PLUGIN MESSAGE: Duration is 266440 May 01 14:08:25 pi-volumio volumio[998]: info: Executing endpoint metavolumio May 01 14:08:25 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 01 14:08:26 pi-volumio volumio[998]: ------------------------------------ BT MESSAGE: BT STATUS: pending May 01 14:08:26 pi-volumio bluetoothd[788]: /org/bluez/hci0/dev_94_45_60_84_B1_4D/fd4: fd(24) ready May 01 14:08:26 pi-volumio volumio[998]: ------------------------------------ BT MESSAGE: BT STATUS: active May 01 14:08:26 pi-volumio volumio[998]: BT PLUGIN MESSAGE: Playing: true May 01 14:08:26 pi-volumio volumio[998]: BT PLUGIN MESSAGE: VOLATILE: undefined May 01 14:08:26 pi-volumio volumio[998]: BT PLUGIN MESSAGE: STATE:undefined May 01 14:08:26 pi-volumio volumio[998]: info: CoreCommandRouter::volumioStop May 01 14:08:26 pi-volumio volumio[998]: info: CoreStateMachine::stop May 01 14:08:26 pi-volumio volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 14:08:26 pi-volumio volumio[998]: BT PLUGIN MESSAGE: BLUETOOTH: Starting Playback Routine May 01 14:08:26 pi-volumio volumio[998]: info: Enabling Bluetooth Audio Output May 01 14:08:26 pi-volumio volumio[998]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 01 14:08:26 pi-volumio volumio[998]: info: FusionDsp - ---- read samplerate from file: 44100 May 01 14:08:26 pi-volumio volumio[998]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 01 14:08:26 pi-volumio volumio[998]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 01 14:08:26 pi-volumio volumio[998]: info: FusionDsp - ---- read samplerate from file: 44100 May 01 14:08:26 pi-volumio volumio[998]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 01 14:08:26 pi-volumio volumio[998]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 01 14:08:26 pi-volumio volumio[998]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 01 14:08:26 pi-volumio pulseaudio[1699]: Configured latency of 200.00 ms is smaller than minimum latency, using minimum instead May 01 14:08:26 pi-volumio pulseaudio[1699]: Cannot set requested sink latency of 343.25 ms, adjusting to 600.00 ms May 01 14:08:26 pi-volumio volumio[998]: ------------------------------------ BT MESSAGE: BT STATUS: playing May 01 14:08:26 pi-volumio volumio[998]: BT PLUGIN MESSAGE: Playing: true May 01 14:08:26 pi-volumio volumio[998]: BT PLUGIN MESSAGE: VOLATILE: bluetooth May 01 14:08:26 pi-volumio volumio[998]: BT PLUGIN MESSAGE: STATE:undefined May 01 14:08:26 pi-volumio volumio[998]: info: Bluetooth Audio successfully Enabled May 01 14:08:26 pi-volumio volumio[998]: info: CoreCommandRouter::servicePushState May 01 14:08:26 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:08:26 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:08:26 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:08:26 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:08:26 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:08:26 pi-volumio volumio[998]: info: [LastFM] Current track has sufficient metadata: title (Sweet Love) and artist (Anita Baker) passed on explicitly May 01 14:08:26 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 May 01 14:08:26 pi-volumio volumio[998]: info: CoreCommandRouter::servicePushState May 01 14:08:26 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:08:26 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:08:26 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:08:26 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:08:26 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:08:26 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 May 01 14:08:26 pi-volumio volumio[998]: info: MRS: Found cast device: Lenovo-Smart-Display-5ff377ad744801de5ffe8a761428ad5d May 01 14:08:26 pi-volumio volumio[998]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Sweet Love","mbid":"c876d89e-468a-4b21-9d58-ad6079556207","url":"https://www.last.fm/music/Anita+Baker/_/Sweet+Love","duration":"246000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"260085","playcount":"1128610","artist":{"name":"Anita Baker","mbid":"02349767-d5ac-4528-892d-17b6d40107a1","url":"https://www.last.fm/music/Anita+Baker"},"album":{"@":{"position":"3"},"artist":"Anita Baker","title":"Sweet Love: The Very Best of Anita Baker","mbid":"97ba4683-2aed-4cf0-a6d8-702c91b738e6","url":"https://www.last.fm/music/Anita+Baker/Sweet+Love:+The+Very+Best+of+Anita+Baker","image":[{"#":"https://lastfm.freetls.fastly.net/i/u/34s/24d6c46b7b52dedc73f93d814f56aaa0.png","@":{"size":"small"}},{"#":"https://lastfm.freetls.fastly.net/i/u/64s/24d6c46b7b52dedc73f93d814f56aaa0.png","@":{"size":"medium"}},{"#":"https://lastfm.freetls.fastly.net/i/u/174s/24d6c46b7b52dedc73f93d814f56aaa0.png","@":{"size":"large"}},{"#":"https://lastfm.freetls.fastly.net/i/u/300x300/24d6c46b7b52dedc73f93d814f56aaa0.png","@":{"size":"extralarge"}}]},"userplaycount":"1","userloved":"0","toptags":{"tag":[{"name":"soul","url":"https://www.last.fm/tag/soul"},{"name":"female vocalists","url":"https://www.last.fm/tag/female+vocalists"},{"name":"love songs","url":"https://www.last.fm/tag/love+songs"},{"name":"80s","url":"https://www.last.fm/tag/80s"},{"name":"Anita Baker","url":"https://www.last.fm/tag/Anita+Baker"}]}}} May 01 14:08:28 pi-volumio volumio[998]: info: [jellyfin-poller] Polled http://192.168.1.34:8096: offline May 01 14:08:28 pi-volumio volumio[998]: info: Executing endpoint metavolumio May 01 14:08:28 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 01 14:08:28 pi-volumio volumio[998]: info: Executing endpoint metavolumio May 01 14:08:28 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 01 14:08:28 pi-volumio volumio[998]: info: Executing endpoint metavolumio May 01 14:08:28 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 01 14:08:30 pi-volumio volumio[998]: info: VolumeController::SetAlsaVolume23 May 01 14:08:30 pi-volumio volumio[998]: BT PLUGIN MESSAGE: Volume set to 23 May 01 14:08:30 pi-volumio volumio[998]: info: CoreCommandRouter::volumioGetState May 01 14:08:30 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:08:30 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:08:30 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:08:30 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:08:30 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:08:30 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 23 May 01 14:08:30 pi-volumio volumio[998]: SPOTIFY: SPOTIFY VOLUME 19 May 01 14:08:30 pi-volumio volumio[998]: SPOTIFY: VOLUMIO VOLUME 23 May 01 14:08:30 pi-volumio volumio[998]: SPOTIFY: DELTA VOLUME ENOUGH: true May 01 14:08:30 pi-volumio volumio[998]: info: Setting Spotify Volume from Volumio: 23 May 01 14:08:30 pi-volumio volumio[998]: info: VolumeController::SetAlsaVolume28 May 01 14:08:30 pi-volumio volumio[998]: BT PLUGIN MESSAGE: Volume set to 28 May 01 14:08:30 pi-volumio volumio[998]: info: CoreCommandRouter::volumioGetState May 01 14:08:30 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:08:30 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:08:30 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:08:30 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:08:30 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:08:30 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 May 01 14:08:30 pi-volumio volumio[998]: SPOTIFY: SPOTIFY VOLUME 23 May 01 14:08:30 pi-volumio volumio[998]: SPOTIFY: VOLUMIO VOLUME 28 May 01 14:08:30 pi-volumio volumio[998]: SPOTIFY: DELTA VOLUME ENOUGH: true May 01 14:08:30 pi-volumio volumio[998]: info: Setting Spotify Volume from Volumio: 28 May 01 14:08:31 pi-volumio volumio[998]: SPOTIFY: SETTING SPOTIFY VOLUME 28 May 01 14:08:31 pi-volumio volumio[998]: info: Sending Spotify command with payload to local API: /player/volume May 01 14:08:36 pi-volumio pulseaudio[1699]: Too many underruns, increasing latency to 941.50 ms May 01 14:08:43 pi-volumio volumio[998]: info: CoreCommandRouter::servicePushState May 01 14:08:43 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:08:43 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:08:43 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:08:43 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:08:43 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:08:43 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 May 01 14:08:43 pi-volumio volumio[998]: info: [jellyfin-poller] Polled http://192.168.1.61:8096: offline May 01 14:08:43 pi-volumio volumio[998]: info: CoreCommandRouter::servicePushState May 01 14:08:43 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:08:43 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:08:43 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:08:43 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:08:43 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:08:43 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 May 01 14:08:43 pi-volumio volumio[998]: info: CoreCommandRouter::servicePushState May 01 14:08:43 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:08:43 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:08:43 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:08:43 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:08:43 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:08:43 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 May 01 14:08:43 pi-volumio volumio[998]: BT PLUGIN MESSAGE: Duration is 266000 May 01 14:08:43 pi-volumio volumio[998]: info: CoreCommandRouter::servicePushState May 01 14:08:43 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:08:43 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:08:43 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:08:43 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:08:43 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:08:43 pi-volumio volumio[998]: info: [LastFM] Current track has sufficient metadata: title (Dance With My Father) and artist (Luther Vandross) passed on explicitly May 01 14:08:43 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 May 01 14:08:43 pi-volumio volumio[998]: info: CoreCommandRouter::servicePushState May 01 14:08:43 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:08:43 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:08:43 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:08:43 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:08:43 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:08:43 pi-volumio volumio[998]: info: [LastFM] updating timer, previous duration is obsolete; adding -199300500 milliseconds. May 01 14:08:43 pi-volumio volumio[998]: Currently remaining: 199500000 May 01 14:08:43 pi-volumio volumio[998]: -------------------------------------------------// Adding -199300500 milliseconds to a total of 199500. May 01 14:08:43 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 May 01 14:08:43 pi-volumio volumio[998]: info: CoreCommandRouter::servicePushState May 01 14:08:43 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:08:43 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:08:43 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:08:43 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:08:43 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:08:43 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 May 01 14:08:43 pi-volumio volumio[998]: info: CoreCommandRouter::servicePushState May 01 14:08:43 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:08:43 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:08:43 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:08:43 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:08:43 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:08:43 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 May 01 14:08:44 pi-volumio volumio[998]: info: CoreCommandRouter::servicePushState May 01 14:08:44 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:08:44 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:08:44 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:08:44 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:08:44 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:08:44 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 May 01 14:08:44 pi-volumio volumio[998]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Dance With My Father","mbid":"8e3c3714-06e6-4f75-a720-fa38f93103e4","url":"https://www.last.fm/music/Luther+Vandross/_/Dance+With+My+Father","duration":"265000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"96945","playcount":"368220","artist":{"name":"Luther Vandross","mbid":"816d9b5d-eaf9-4a97-b5f7-6e82cd01aafe","url":"https://www.last.fm/music/Luther+Vandross"},"album":{"@":{"position":"7"},"artist":"Luther Vandross","title":"Dance With My Father","mbid":"9b00c9af-dc71-451f-af30-ef7273b88f5b","url":"https://www.last.fm/music/Luther+Vandross/Dance+With+My+Father","image":[{"#":"https://lastfm.freetls.fastly.net/i/u/34s/d1a6cdf28592481ebcb04e5dc9203bb1.png","@":{"size":"small"}},{"#":"https://lastfm.freetls.fastly.net/i/u/64s/d1a6cdf28592481ebcb04e5dc9203bb1.png","@":{"size":"medium"}},{"#":"https://lastfm.freetls.fastly.net/i/u/174s/d1a6cdf28592481ebcb04e5dc9203bb1.png","@":{"size":"large"}},{"#":"https://lastfm.freetls.fastly.net/i/u/300x300/d1a6cdf28592481ebcb04e5dc9203bb1.png","@":{"size":"extralarge"}}]},"userplaycount":"0","userloved":"0","toptags":{"tag":[{"name":"soul","url":"https://www.last.fm/tag/soul"},{"name":"luther vandross","url":"https://www.last.fm/tag/luther+vandross"},{"name":"rnb","url":"https://www.last.fm/tag/rnb"},{"name":"rhythm and blues","url":"https://www.last.fm/tag/rhythm+and+blues"},{"name":"father","url":"https://www.last.fm/tag/father"}]},"wiki":{"published":"05 Jul 2009, 00:15","summary":"\"Dance with My Father\" is a song by Luther Vandross and the title track to his thirteenth studio album. Vandross wrote the song together with singer/song-writer Richard Marx. Based on his personal experiences, the lyrics illustrate Vandross's childhood memories of his father, who used to dance with him and his mother. \"Dance with My Father\" earned Vandross the Song of the Year and Best Male R&B Vocal Performance accolades at the 2004 Grammy Awards, . Read more on Last.fm.","content":"\"Dance with My Father\" is a song by Luther Vandross and the title track to his thirteenth studio album. Vandross wrote the song together with singer/song-writer Richard Marx. Based on his personal experiences, the lyrics illustrate Vandross's childhood memories of his father, who used to dance with him and his mother. \"Dance with My Father\" earned Vandross the Song of the Year and Best Male R&B Vocal Performance accolades at the 2004 Grammy Awards, . Read more on Last.fm. User-contributed text is available under the Creative Commons By-SA License; additional terms may apply."}}} May 01 14:08:45 pi-volumio volumio[998]: info: Executing endpoint metavolumio May 01 14:08:45 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 01 14:08:45 pi-volumio volumio[998]: info: Executing endpoint metavolumio May 01 14:08:45 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 01 14:08:46 pi-volumio volumio[998]: info: Executing endpoint metavolumio May 01 14:08:46 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 01 14:08:58 pi-volumio volumio[998]: info: [jellyfin-poller] Polled http://192.168.1.34:8096: offline May 01 14:08:58 pi-volumio nmbd[757]: [2025/05/01 14:08:58.462239, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 01 14:08:58 pi-volumio nmbd[757]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.119 for name WORKGROUP<1d>. May 01 14:08:58 pi-volumio nmbd[757]: This response was from IP 192.168.1.33, reporting an IP address of 192.168.1.33. May 01 14:09:11 pi-volumio volumio[998]: BT PLUGIN MESSAGE: Duration is 305960 May 01 14:09:11 pi-volumio volumio[998]: info: CoreCommandRouter::servicePushState May 01 14:09:11 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:09:11 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:09:11 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:09:11 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:09:11 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:09:11 pi-volumio volumio[998]: info: [LastFM] Current track has sufficient metadata: title (Have Yourself a Merry Little Christmas) and artist (Luther Vandross) passed on explicitly May 01 14:09:11 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 May 01 14:09:11 pi-volumio volumio[998]: info: CoreCommandRouter::servicePushState May 01 14:09:11 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:09:11 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:09:11 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:09:11 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:09:11 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:09:11 pi-volumio volumio[998]: info: [LastFM] updating timer, previous duration is obsolete; adding -229241250 milliseconds. May 01 14:09:11 pi-volumio volumio[998]: Currently remaining: 229470000 May 01 14:09:11 pi-volumio volumio[998]: -------------------------------------------------// Adding -229241250 milliseconds to a total of 228750. May 01 14:09:11 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 May 01 14:09:12 pi-volumio volumio[998]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Have Yourself a Merry Little Christmas","mbid":"dab526d9-a2eb-47ed-9af3-a2f5f13f59bd","url":"https://www.last.fm/music/Luther+Vandross/_/Have+Yourself+a+Merry+Little+Christmas","duration":"304000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"85780","playcount":"188745","artist":{"name":"Luther Vandross","mbid":"816d9b5d-eaf9-4a97-b5f7-6e82cd01aafe","url":"https://www.last.fm/music/Luther+Vandross"},"album":{"@":{"position":"6"},"artist":"Luther Vandross","title":"This Is Christmas","mbid":"45b7a342-3291-41d1-b1eb-e7831fff16be","url":"https://www.last.fm/music/Luther+Vandross/This+Is+Christmas","image":[{"#":"https://lastfm.freetls.fastly.net/i/u/34s/cb64065b2cabb0097230be6e4d2d9c7a.png","@":{"size":"small"}},{"#":"https://lastfm.freetls.fastly.net/i/u/64s/cb64065b2cabb0097230be6e4d2d9c7a.png","@":{"size":"medium"}},{"#":"https://lastfm.freetls.fastly.net/i/u/174s/cb64065b2cabb0097230be6e4d2d9c7a.png","@":{"size":"large"}},{"#":"https://lastfm.freetls.fastly.net/i/u/300x300/cb64065b2cabb0097230be6e4d2d9c7a.png","@":{"size":"extralarge"}}]},"userplaycount":"0","userloved":"0","toptags":{"tag":[{"name":"christmas","url":"https://www.last.fm/tag/christmas"},{"name":"christmas soul","url":"https://www.last.fm/tag/christmas+soul"},{"name":"xmas","url":"https://www.last.fm/tag/xmas"},{"name":"soul","url":"https://www.last.fm/tag/soul"},{"name":"Have Yourself A Merry Little Christmas","url":"https://www.last.fm/tag/Have+Yourself+A+Merry+Little+Christmas"}]}}} May 01 14:09:13 pi-volumio volumio[998]: info: Executing endpoint metavolumio May 01 14:09:13 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 01 14:09:13 pi-volumio volumio[998]: info: Executing endpoint metavolumio May 01 14:09:13 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 01 14:09:13 pi-volumio volumio[998]: info: Executing endpoint metavolumio May 01 14:09:13 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 01 14:09:13 pi-volumio volumiologrotate[631]: ls: cannot access '/var/log/samba/log.wb-PI': No such file or directory May 01 14:09:13 pi-volumio volumiologrotate[631]: ls: cannot access 'VOLUMIO': No such file or directory May 01 14:09:16 pi-volumio volumio[998]: info: [jellyfin-poller] Polled http://192.168.1.61:8096: offline May 01 14:09:16 pi-volumio volumio[998]: info: CoreCommandRouter::servicePushState May 01 14:09:16 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:09:16 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:09:16 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:09:16 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:09:16 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:09:17 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 May 01 14:09:17 pi-volumio volumio[998]: BT PLUGIN MESSAGE: Duration is 305960 May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::servicePushState May 01 14:09:17 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:09:17 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:09:17 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:09:17 pi-volumio volumio[998]: info: [LastFM] updating timer, previous duration is obsolete; adding 229241250 milliseconds. May 01 14:09:17 pi-volumio volumio[998]: Currently remaining: 228750 May 01 14:09:17 pi-volumio volumio[998]: -------------------------------------------------// Adding 229241250 milliseconds to a total of 229470000. May 01 14:09:17 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::servicePushState May 01 14:09:17 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:09:17 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:09:17 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:09:17 pi-volumio volumio[998]: info: [LastFM] updating timer, previous duration is obsolete; adding -229241250 milliseconds. May 01 14:09:17 pi-volumio volumio[998]: Currently remaining: 229470000 May 01 14:09:17 pi-volumio volumio[998]: -------------------------------------------------// Adding -229241250 milliseconds to a total of 228750. May 01 14:09:17 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::servicePushState May 01 14:09:17 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:09:17 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:09:17 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:09:17 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 May 01 14:09:17 pi-volumio volumio[998]: BT PLUGIN MESSAGE: Duration is 420613 May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::servicePushState May 01 14:09:17 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:09:17 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:09:17 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:09:17 pi-volumio volumio[998]: info: [LastFM] Current track has sufficient metadata: title (Good To Know That If I Ever Need Attention All I Have To Do Is Die) and artist (Brand New) passed on explicitly May 01 14:09:17 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::servicePushState May 01 14:09:17 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:09:17 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:09:17 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:09:17 pi-volumio volumio[998]: info: [LastFM] updating timer, previous duration is obsolete; adding -315144750 milliseconds. May 01 14:09:17 pi-volumio volumio[998]: Currently remaining: 315459750 May 01 14:09:17 pi-volumio volumio[998]: -------------------------------------------------// Adding -315144750 milliseconds to a total of 315000. May 01 14:09:17 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 May 01 14:09:17 pi-volumio volumio[998]: BT PLUGIN MESSAGE: Duration is 420613 May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::servicePushState May 01 14:09:17 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:09:17 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:09:17 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:09:17 pi-volumio volumio[998]: info: [LastFM] updating timer, previous duration is obsolete; adding 315144750 milliseconds. May 01 14:09:17 pi-volumio volumio[998]: Currently remaining: 315000 May 01 14:09:17 pi-volumio volumio[998]: -------------------------------------------------// Adding 315144750 milliseconds to a total of 315459750. May 01 14:09:17 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::servicePushState May 01 14:09:17 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:09:17 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:09:17 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:09:17 pi-volumio volumio[998]: info: [LastFM] updating timer, previous duration is obsolete; adding -315144750 milliseconds. May 01 14:09:17 pi-volumio volumio[998]: Currently remaining: 315459750 May 01 14:09:17 pi-volumio volumio[998]: -------------------------------------------------// Adding -315144750 milliseconds to a total of 315000. May 01 14:09:17 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::servicePushState May 01 14:09:17 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:09:17 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:09:17 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:09:17 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:09:17 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 May 01 14:09:18 pi-volumio volumio[998]: BT PLUGIN MESSAGE: Duration is 420613 May 01 14:09:18 pi-volumio volumio[998]: info: CoreCommandRouter::servicePushState May 01 14:09:18 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:09:18 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:09:18 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:09:18 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:09:18 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:09:18 pi-volumio volumio[998]: info: [LastFM] updating timer, previous duration is obsolete; adding 315144750 milliseconds. May 01 14:09:18 pi-volumio volumio[998]: Currently remaining: 315000 May 01 14:09:18 pi-volumio volumio[998]: -------------------------------------------------// Adding 315144750 milliseconds to a total of 315459750. May 01 14:09:18 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 May 01 14:09:18 pi-volumio volumio[998]: info: CoreCommandRouter::servicePushState May 01 14:09:18 pi-volumio volumio[998]: info: CoreStateMachine::pushState May 01 14:09:18 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:09:18 pi-volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 01 14:09:18 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output update for this device May 01 14:09:18 pi-volumio volumio[998]: info: MRS: Pushing multiroomSync output May 01 14:09:18 pi-volumio volumio[998]: info: [LastFM] updating timer, previous duration is obsolete; adding -315144750 milliseconds. May 01 14:09:18 pi-volumio volumio[998]: Currently remaining: 315459750 May 01 14:09:18 pi-volumio volumio[998]: -------------------------------------------------// Adding -315144750 milliseconds to a total of 315000. May 01 14:09:18 pi-volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 May 01 14:09:19 pi-volumio volumio[998]: info: MRS: Found cast device: Chromecast-Audio-415e0e61ec1f2e9439988e6974e94fba May 01 14:09:19 pi-volumio volumio[998]: info: Executing endpoint metavolumio May 01 14:09:19 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 01 14:09:19 pi-volumio volumio[998]: info: Executing endpoint metavolumio May 01 14:09:19 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 01 14:09:19 pi-volumio volumio[998]: info: Executing endpoint metavolumio May 01 14:09:19 pi-volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 01 14:09:20 pi-volumio volumio[998]: error: Failed request for metavolumio API May 01 14:09:20 pi-volumio volumio[998]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 14:09:20 pi-volumio volumio[998]: TypeError: Cannot read property '@' of null May 01 14:09:20 pi-volumio volumio[998]: at Object.callback (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:342:14) May 01 14:09:20 pi-volumio volumio[998]: at /data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:60:13 May 01 14:09:20 pi-volumio volumio[998]: at Parser. (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:304:18) May 01 14:09:20 pi-volumio volumio[998]: at Parser.emit (events.js:315:20) May 01 14:09:20 pi-volumio volumio[998]: at Parser.exports.Parser.Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:314:16) May 01 14:09:20 pi-volumio volumio[998]: at Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:5:59) May 01 14:09:20 pi-volumio volumio[998]: at IncomingMessage. (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:58:14) May 01 14:09:20 pi-volumio volumio[998]: at IncomingMessage.emit (events.js:327:22) May 01 14:09:20 pi-volumio volumio[998]: at endReadableNT (internal/streams/readable.js:1327:12) May 01 14:09:20 pi-volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 01 14:09:20 pi-volumio volumio[998]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 14:09:20 pi-volumio sudo[31154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-01 14:08 May 01 14:09:20 pi-volumio sudo[31154]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"