-- Logs begin at Thu 2019-02-14 10:12:00 UTC, end at Thu 2024-11-28 22:14:27 UTC. -- Nov 28 22:14:04 volumio volumio[991]: ------------------------------------ BT MESSAGE: BT STATUS: stopped Nov 28 22:14:04 volumio volumio[991]: BT PLUGIN MESSAGE: Playing: false Nov 28 22:14:04 volumio volumio[991]: BT PLUGIN MESSAGE: VOLATILE: undefined Nov 28 22:14:04 volumio volumio[991]: BT PLUGIN MESSAGE: STATE:undefined Nov 28 22:14:11 volumio volumio[991]: BT PLUGIN MESSAGE: Duration is 6223021 Nov 28 22:14:11 volumio volumio[991]: BT PLUGIN MESSAGE: Duration is 946361 Nov 28 22:14:11 volumio volumio[991]: ------------------------------------ BT MESSAGE: BT STATUS: playing Nov 28 22:14:11 volumio volumio[991]: BT PLUGIN MESSAGE: Playing: true Nov 28 22:14:11 volumio volumio[991]: BT PLUGIN MESSAGE: VOLATILE: undefined Nov 28 22:14:11 volumio volumio[991]: BT PLUGIN MESSAGE: STATE:undefined Nov 28 22:14:11 volumio volumio[991]: info: CoreCommandRouter::volumioStop Nov 28 22:14:11 volumio volumio[991]: info: CoreStateMachine::stop Nov 28 22:14:11 volumio volumio[991]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 22:14:11 volumio volumio[991]: BT PLUGIN MESSAGE: BLUETOOTH: Starting Playback Routine Nov 28 22:14:11 volumio volumio[991]: info: Enabling Bluetooth Audio Output Nov 28 22:14:11 volumio pulseaudio[1762]: Configured latency of 200.00 ms is smaller than minimum latency, using minimum instead Nov 28 22:14:11 volumio pulseaudio[1762]: Cannot set requested sink latency of 343.25 ms, adjusting to 600.00 ms Nov 28 22:14:11 volumio volumio[991]: info: Bluetooth Audio successfully Enabled Nov 28 22:14:11 volumio volumio[991]: info: CoreCommandRouter::servicePushState Nov 28 22:14:11 volumio volumio[991]: info: CoreStateMachine::pushState Nov 28 22:14:11 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 22:14:11 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Nov 28 22:14:11 volumio volumio[991]: info: MRS: Pushing multiroomSync output update for this device Nov 28 22:14:11 volumio volumio[991]: info: MRS: Pushing multiroomSync output Nov 28 22:14:11 volumio volumio[991]: info: [LastFM] Current track has sufficient metadata: title (Scanning Film with the Cheapest Digital Camera (and a giveaway!)) and artist (Kyle McDougall) passed on explicitly Nov 28 22:14:11 volumio volumio[991]: BT PLUGIN MESSAGE: Duration is 946361 Nov 28 22:14:11 volumio volumio[991]: info: CoreCommandRouter::servicePushState Nov 28 22:14:11 volumio volumio[991]: info: CoreStateMachine::pushState Nov 28 22:14:11 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 22:14:11 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Nov 28 22:14:11 volumio volumio[991]: info: MRS: Pushing multiroomSync output update for this device Nov 28 22:14:11 volumio volumio[991]: info: MRS: Pushing multiroomSync output Nov 28 22:14:11 volumio volumio[991]: info: [LastFM] Current track has sufficient metadata: title (Der Notino Black Friday geht weiter | notino.de) and artist (Notino.de) passed on explicitly Nov 28 22:14:11 volumio volumio[991]: BT PLUGIN MESSAGE: Duration is 15021 Nov 28 22:14:11 volumio volumio[991]: info: CoreCommandRouter::servicePushState Nov 28 22:14:11 volumio volumio[991]: info: CoreStateMachine::pushState Nov 28 22:14:11 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 22:14:11 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Nov 28 22:14:11 volumio volumio[991]: info: MRS: Pushing multiroomSync output update for this device Nov 28 22:14:11 volumio volumio[991]: info: MRS: Pushing multiroomSync output Nov 28 22:14:11 volumio volumio[991]: info: [LastFM] updating timer, previous duration is obsolete; adding -698505000 milliseconds. Nov 28 22:14:11 volumio volumio[991]: Currently remaining: 709770750 Nov 28 22:14:11 volumio volumio[991]: -------------------------------------------------// Adding -698505000 milliseconds to a total of 11265750. Nov 28 22:14:11 volumio volumio[991]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Scanning Film with the Cheapest Digital Camera (and a giveaway!)","url":"https://www.last.fm/music/Kyle+McDougall/_/Scanning+Film+with+the+Cheapest+Digital+Camera+(and+a+giveaway%21)","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"2","playcount":"2","artist":{"name":"Kyle McDougall","url":"https://www.last.fm/music/Kyle+McDougall"},"userplaycount":"0","userloved":"0","toptags":""}} Nov 28 22:14:11 volumio volumio[991]: error: [LastFM] track info request failed with error: Track not found Nov 28 22:14:12 volumio volumio[991]: ------------------------------------ BT MESSAGE: BT STATUS: paused Nov 28 22:14:12 volumio volumio[991]: BT PLUGIN MESSAGE: Playing: false Nov 28 22:14:12 volumio volumio[991]: BT PLUGIN MESSAGE: VOLATILE: bluetooth Nov 28 22:14:12 volumio volumio[991]: BT PLUGIN MESSAGE: STATE:undefined Nov 28 22:14:12 volumio volumio[991]: info: CoreCommandRouter::servicePushState Nov 28 22:14:12 volumio volumio[991]: info: CoreStateMachine::pushState Nov 28 22:14:12 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 22:14:12 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Nov 28 22:14:12 volumio volumio[991]: info: MRS: Pushing multiroomSync output update for this device Nov 28 22:14:12 volumio volumio[991]: info: MRS: Pushing multiroomSync output Nov 28 22:14:12 volumio volumio[991]: verbose: UNSET VOLATILE: Service: bluetooth Nov 28 22:14:12 volumio volumio[991]: info: Bluetooth Detach Command Nov 28 22:14:12 volumio volumio[991]: info: CoreStateMachine::resetVolumioState Nov 28 22:14:12 volumio volumio[991]: info: CoreStateMachine::getcurrentVolume Nov 28 22:14:12 volumio volumio[991]: info: CoreCommandRouter::volumioRetrievevolume Nov 28 22:14:12 volumio volumio[991]: info: CoreCommandRouter::volumioStop Nov 28 22:14:12 volumio volumio[991]: info: CoreStateMachine::stop Nov 28 22:14:12 volumio volumio[991]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 22:14:12 volumio volumio[991]: info: CoreCommandRouter::servicePushState Nov 28 22:14:12 volumio volumio[991]: info: CorePlayQueue::getTrack 0 Nov 28 22:14:12 volumio volumio[991]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received bluetooth Nov 28 22:14:12 volumio volumio[991]: BT PLUGIN MESSAGE: Duration is 15021 Nov 28 22:14:12 volumio volumio[991]: info: CoreCommandRouter::servicePushState Nov 28 22:14:12 volumio volumio[991]: info: CorePlayQueue::getTrack 0 Nov 28 22:14:12 volumio volumio[991]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received bluetooth Nov 28 22:14:12 volumio volumio[991]: BT PLUGIN MESSAGE: Duration is 946361 Nov 28 22:14:12 volumio volumio[991]: info: CoreCommandRouter::servicePushState Nov 28 22:14:12 volumio volumio[991]: info: CorePlayQueue::getTrack 0 Nov 28 22:14:12 volumio volumio[991]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received bluetooth Nov 28 22:14:12 volumio pulseaudio[1762]: Configured latency of 200.00 ms is smaller than minimum latency, using minimum instead Nov 28 22:14:12 volumio volumio[991]: info: Bluetooth Audio successfully Disabled Nov 28 22:14:12 volumio volumio[991]: info: Bluetooth Successfully Detached Nov 28 22:14:12 volumio volumio[991]: info: VolumeController:: Volume=66 Mute =false Nov 28 22:14:12 volumio volumio[991]: info: CoreStateMachine::pushState Nov 28 22:14:12 volumio volumio[991]: info: CorePlayQueue::getTrack 0 Nov 28 22:14:12 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 22:14:12 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Nov 28 22:14:12 volumio volumio[991]: info: MRS: Pushing multiroomSync output update for this device Nov 28 22:14:12 volumio volumio[991]: info: MRS: Pushing multiroomSync output Nov 28 22:14:12 volumio volumio[991]: info: CoreStateMachine::updateTrackBlock Nov 28 22:14:12 volumio volumio[991]: info: CorePlayQueue::getTrackBlock Nov 28 22:14:12 volumio volumio[991]: info: CoreCommandRouter::volumioRetrievevolume Nov 28 22:14:12 volumio volumio[991]: info: VolumeController:: Volume=66 Mute =false Nov 28 22:14:12 volumio volumio[991]: info: CoreStateMachine::pushState Nov 28 22:14:12 volumio volumio[991]: info: CorePlayQueue::getTrack 0 Nov 28 22:14:12 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Nov 28 22:14:12 volumio volumio[991]: info: MRS: Pushing multiroomSync output update for this device Nov 28 22:14:12 volumio volumio[991]: info: MRS: Pushing multiroomSync output Nov 28 22:14:12 volumio volumio[991]: ------------------------------------ BT MESSAGE: BT STATUS: playing Nov 28 22:14:12 volumio volumio[991]: BT PLUGIN MESSAGE: Playing: true Nov 28 22:14:12 volumio volumio[991]: BT PLUGIN MESSAGE: VOLATILE: undefined Nov 28 22:14:12 volumio volumio[991]: BT PLUGIN MESSAGE: STATE:undefined Nov 28 22:14:12 volumio volumio[991]: info: CoreCommandRouter::volumioStop Nov 28 22:14:12 volumio volumio[991]: info: CoreStateMachine::stop Nov 28 22:14:12 volumio volumio[991]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 22:14:12 volumio volumio[991]: BT PLUGIN MESSAGE: BLUETOOTH: Starting Playback Routine Nov 28 22:14:12 volumio volumio[991]: info: Enabling Bluetooth Audio Output Nov 28 22:14:12 volumio volumio[991]: BT PLUGIN MESSAGE: Duration is 946361 Nov 28 22:14:12 volumio volumio[991]: BT PLUGIN MESSAGE: Duration is 20101 Nov 28 22:14:12 volumio pulseaudio[1762]: Configured latency of 200.00 ms is smaller than minimum latency, using minimum instead Nov 28 22:14:12 volumio pulseaudio[1762]: Cannot set requested sink latency of 343.25 ms, adjusting to 600.00 ms Nov 28 22:14:12 volumio volumio[991]: info: Bluetooth Audio successfully Enabled Nov 28 22:14:13 volumio volumio[991]: ------------------------------------ BT MESSAGE: BT STATUS: paused Nov 28 22:14:13 volumio volumio[991]: BT PLUGIN MESSAGE: Playing: false Nov 28 22:14:13 volumio volumio[991]: BT PLUGIN MESSAGE: VOLATILE: bluetooth Nov 28 22:14:13 volumio volumio[991]: BT PLUGIN MESSAGE: STATE:undefined Nov 28 22:14:13 volumio volumio[991]: info: CoreCommandRouter::servicePushState Nov 28 22:14:13 volumio volumio[991]: info: CoreStateMachine::pushState Nov 28 22:14:13 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 22:14:13 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Nov 28 22:14:13 volumio volumio[991]: info: MRS: Pushing multiroomSync output update for this device Nov 28 22:14:13 volumio volumio[991]: info: MRS: Pushing multiroomSync output Nov 28 22:14:13 volumio volumio[991]: verbose: UNSET VOLATILE: Service: bluetooth Nov 28 22:14:13 volumio volumio[991]: info: Bluetooth Detach Command Nov 28 22:14:13 volumio volumio[991]: info: CoreStateMachine::resetVolumioState Nov 28 22:14:13 volumio volumio[991]: info: CoreStateMachine::getcurrentVolume Nov 28 22:14:13 volumio volumio[991]: info: CoreCommandRouter::volumioRetrievevolume Nov 28 22:14:13 volumio volumio[991]: info: CoreCommandRouter::volumioStop Nov 28 22:14:13 volumio volumio[991]: info: CoreStateMachine::stop Nov 28 22:14:13 volumio volumio[991]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 22:14:13 volumio volumio[991]: info: CoreCommandRouter::servicePushState Nov 28 22:14:13 volumio volumio[991]: info: CorePlayQueue::getTrack 0 Nov 28 22:14:13 volumio volumio[991]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received bluetooth Nov 28 22:14:13 volumio pulseaudio[1762]: Configured latency of 200.00 ms is smaller than minimum latency, using minimum instead Nov 28 22:14:13 volumio volumio[991]: info: Bluetooth Audio successfully Disabled Nov 28 22:14:13 volumio volumio[991]: info: Bluetooth Successfully Detached Nov 28 22:14:13 volumio volumio[991]: BT PLUGIN MESSAGE: Duration is 20101 Nov 28 22:14:13 volumio volumio[991]: BT PLUGIN MESSAGE: Duration is 946361 Nov 28 22:14:13 volumio volumio[991]: info: VolumeController:: Volume=66 Mute =false Nov 28 22:14:13 volumio volumio[991]: info: CoreStateMachine::pushState Nov 28 22:14:13 volumio volumio[991]: info: CorePlayQueue::getTrack 0 Nov 28 22:14:13 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 22:14:13 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Nov 28 22:14:13 volumio volumio[991]: info: MRS: Pushing multiroomSync output update for this device Nov 28 22:14:13 volumio volumio[991]: info: MRS: Pushing multiroomSync output Nov 28 22:14:13 volumio volumio[991]: info: CoreStateMachine::updateTrackBlock Nov 28 22:14:13 volumio volumio[991]: info: CorePlayQueue::getTrackBlock Nov 28 22:14:13 volumio volumio[991]: info: CoreCommandRouter::volumioRetrievevolume Nov 28 22:14:13 volumio volumio[991]: info: VolumeController:: Volume=66 Mute =false Nov 28 22:14:13 volumio volumio[991]: info: CoreStateMachine::pushState Nov 28 22:14:13 volumio volumio[991]: info: CorePlayQueue::getTrack 0 Nov 28 22:14:13 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Nov 28 22:14:13 volumio volumio[991]: info: MRS: Pushing multiroomSync output update for this device Nov 28 22:14:13 volumio volumio[991]: info: MRS: Pushing multiroomSync output Nov 28 22:14:14 volumio volumio[991]: ------------------------------------ BT MESSAGE: BT STATUS: playing Nov 28 22:14:14 volumio volumio[991]: BT PLUGIN MESSAGE: Playing: true Nov 28 22:14:14 volumio volumio[991]: BT PLUGIN MESSAGE: VOLATILE: undefined Nov 28 22:14:14 volumio volumio[991]: BT PLUGIN MESSAGE: STATE:undefined Nov 28 22:14:14 volumio volumio[991]: info: CoreCommandRouter::volumioStop Nov 28 22:14:14 volumio volumio[991]: info: CoreStateMachine::stop Nov 28 22:14:14 volumio volumio[991]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 22:14:14 volumio volumio[991]: BT PLUGIN MESSAGE: BLUETOOTH: Starting Playback Routine Nov 28 22:14:14 volumio volumio[991]: info: Enabling Bluetooth Audio Output Nov 28 22:14:14 volumio volumio[991]: BT PLUGIN MESSAGE: Duration is 946361 Nov 28 22:14:14 volumio volumio[991]: BT PLUGIN MESSAGE: Duration is 6223021 Nov 28 22:14:14 volumio pulseaudio[1762]: Configured latency of 200.00 ms is smaller than minimum latency, using minimum instead Nov 28 22:14:14 volumio pulseaudio[1762]: Cannot set requested sink latency of 343.25 ms, adjusting to 600.00 ms Nov 28 22:14:14 volumio volumio[991]: info: Bluetooth Audio successfully Enabled Nov 28 22:14:15 volumio volumio[991]: ------------------------------------ BT MESSAGE: BT STATUS: paused Nov 28 22:14:15 volumio volumio[991]: BT PLUGIN MESSAGE: Playing: false Nov 28 22:14:15 volumio volumio[991]: BT PLUGIN MESSAGE: VOLATILE: bluetooth Nov 28 22:14:15 volumio volumio[991]: BT PLUGIN MESSAGE: STATE:undefined Nov 28 22:14:15 volumio volumio[991]: info: CoreCommandRouter::servicePushState Nov 28 22:14:15 volumio volumio[991]: info: CoreStateMachine::pushState Nov 28 22:14:15 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 22:14:15 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Nov 28 22:14:15 volumio volumio[991]: info: MRS: Pushing multiroomSync output update for this device Nov 28 22:14:15 volumio volumio[991]: info: MRS: Pushing multiroomSync output Nov 28 22:14:15 volumio volumio[991]: verbose: UNSET VOLATILE: Service: bluetooth Nov 28 22:14:15 volumio volumio[991]: info: Bluetooth Detach Command Nov 28 22:14:15 volumio volumio[991]: info: CoreStateMachine::resetVolumioState Nov 28 22:14:15 volumio volumio[991]: info: CoreStateMachine::getcurrentVolume Nov 28 22:14:15 volumio volumio[991]: info: CoreCommandRouter::volumioRetrievevolume Nov 28 22:14:15 volumio volumio[991]: info: CoreCommandRouter::volumioStop Nov 28 22:14:15 volumio volumio[991]: info: CoreStateMachine::stop Nov 28 22:14:15 volumio volumio[991]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 22:14:15 volumio volumio[991]: info: CoreCommandRouter::servicePushState Nov 28 22:14:15 volumio volumio[991]: info: CorePlayQueue::getTrack 0 Nov 28 22:14:15 volumio volumio[991]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received bluetooth Nov 28 22:14:15 volumio volumio[991]: BT PLUGIN MESSAGE: Duration is 6223021 Nov 28 22:14:15 volumio volumio[991]: info: CoreCommandRouter::servicePushState Nov 28 22:14:15 volumio volumio[991]: info: CorePlayQueue::getTrack 0 Nov 28 22:14:15 volumio volumio[991]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received bluetooth Nov 28 22:14:15 volumio pulseaudio[1762]: Configured latency of 200.00 ms is smaller than minimum latency, using minimum instead Nov 28 22:14:15 volumio volumio[991]: info: Bluetooth Audio successfully Disabled Nov 28 22:14:15 volumio volumio[991]: info: Bluetooth Successfully Detached Nov 28 22:14:15 volumio volumio[991]: BT PLUGIN MESSAGE: Duration is 946361 Nov 28 22:14:15 volumio volumio[991]: info: VolumeController:: Volume=66 Mute =false Nov 28 22:14:15 volumio volumio[991]: info: CoreStateMachine::pushState Nov 28 22:14:15 volumio volumio[991]: info: CorePlayQueue::getTrack 0 Nov 28 22:14:15 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 22:14:15 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Nov 28 22:14:15 volumio volumio[991]: info: MRS: Pushing multiroomSync output update for this device Nov 28 22:14:15 volumio volumio[991]: info: MRS: Pushing multiroomSync output Nov 28 22:14:15 volumio volumio[991]: info: CoreStateMachine::updateTrackBlock Nov 28 22:14:15 volumio volumio[991]: info: CorePlayQueue::getTrackBlock Nov 28 22:14:15 volumio volumio[991]: info: CoreCommandRouter::volumioRetrievevolume Nov 28 22:14:16 volumio volumio[991]: ------------------------------------ BT MESSAGE: BT STATUS: playing Nov 28 22:14:16 volumio volumio[991]: BT PLUGIN MESSAGE: Playing: true Nov 28 22:14:16 volumio volumio[991]: BT PLUGIN MESSAGE: VOLATILE: undefined Nov 28 22:14:16 volumio volumio[991]: BT PLUGIN MESSAGE: STATE:undefined Nov 28 22:14:16 volumio volumio[991]: info: CoreCommandRouter::volumioStop Nov 28 22:14:16 volumio volumio[991]: info: CoreStateMachine::stop Nov 28 22:14:16 volumio volumio[991]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 22:14:16 volumio volumio[991]: BT PLUGIN MESSAGE: BLUETOOTH: Starting Playback Routine Nov 28 22:14:16 volumio volumio[991]: info: Enabling Bluetooth Audio Output Nov 28 22:14:16 volumio volumio[991]: info: VolumeController:: Volume=66 Mute =false Nov 28 22:14:16 volumio volumio[991]: info: CoreStateMachine::pushState Nov 28 22:14:16 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 22:14:16 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Nov 28 22:14:16 volumio volumio[991]: info: MRS: Pushing multiroomSync output update for this device Nov 28 22:14:16 volumio volumio[991]: info: MRS: Pushing multiroomSync output Nov 28 22:14:16 volumio volumio[991]: info: [LastFM] Current track has sufficient metadata: title (Scanning Film with the Cheapest Digital Camera (and a giveaway!)) and artist (Kyle McDougall) passed on explicitly Nov 28 22:14:16 volumio pulseaudio[1762]: Configured latency of 200.00 ms is smaller than minimum latency, using minimum instead Nov 28 22:14:16 volumio pulseaudio[1762]: Cannot set requested sink latency of 343.25 ms, adjusting to 600.00 ms Nov 28 22:14:16 volumio volumio[991]: info: Bluetooth Audio successfully Enabled Nov 28 22:14:16 volumio volumio[991]: BT PLUGIN MESSAGE: Duration is 946361 Nov 28 22:14:16 volumio volumio[991]: info: CoreCommandRouter::servicePushState Nov 28 22:14:16 volumio volumio[991]: info: CoreStateMachine::pushState Nov 28 22:14:16 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 22:14:16 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Nov 28 22:14:16 volumio volumio[991]: info: MRS: Pushing multiroomSync output update for this device Nov 28 22:14:16 volumio volumio[991]: info: MRS: Pushing multiroomSync output Nov 28 22:14:16 volumio volumio[991]: BT PLUGIN MESSAGE: Duration is 15021 Nov 28 22:14:16 volumio volumio[991]: info: CoreCommandRouter::servicePushState Nov 28 22:14:16 volumio volumio[991]: info: CoreStateMachine::pushState Nov 28 22:14:16 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 22:14:16 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Nov 28 22:14:16 volumio volumio[991]: info: MRS: Pushing multiroomSync output update for this device Nov 28 22:14:16 volumio volumio[991]: info: MRS: Pushing multiroomSync output Nov 28 22:14:16 volumio volumio[991]: info: [LastFM] Current track has sufficient metadata: title (4.0 | Cyber Sale | DE | YouTube In-Feed | CyberColdMoss:15) and artist (WHOOP) passed on explicitly Nov 28 22:14:16 volumio volumio[991]: info: [LastFM] updating timer, previous duration is obsolete; adding -698505000 milliseconds. Nov 28 22:14:16 volumio volumio[991]: Currently remaining: 709770750 Nov 28 22:14:16 volumio volumio[991]: -------------------------------------------------// Adding -698505000 milliseconds to a total of 11265750. Nov 28 22:14:16 volumio volumio[991]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Scanning Film with the Cheapest Digital Camera (and a giveaway!)","url":"https://www.last.fm/music/Kyle+McDougall/_/Scanning+Film+with+the+Cheapest+Digital+Camera+(and+a+giveaway%21)","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"2","playcount":"2","artist":{"name":"Kyle McDougall","url":"https://www.last.fm/music/Kyle+McDougall"},"userplaycount":"0","userloved":"0","toptags":""}} Nov 28 22:14:16 volumio volumio[991]: error: [LastFM] track info request failed with error: Track not found Nov 28 22:14:17 volumio volumio[991]: ------------------------------------ BT MESSAGE: BT STATUS: paused Nov 28 22:14:17 volumio volumio[991]: BT PLUGIN MESSAGE: Playing: false Nov 28 22:14:17 volumio volumio[991]: BT PLUGIN MESSAGE: VOLATILE: bluetooth Nov 28 22:14:17 volumio volumio[991]: BT PLUGIN MESSAGE: STATE:undefined Nov 28 22:14:17 volumio volumio[991]: info: CoreCommandRouter::servicePushState Nov 28 22:14:17 volumio volumio[991]: info: CoreStateMachine::pushState Nov 28 22:14:17 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 22:14:17 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Nov 28 22:14:17 volumio volumio[991]: info: MRS: Pushing multiroomSync output update for this device Nov 28 22:14:17 volumio volumio[991]: info: MRS: Pushing multiroomSync output Nov 28 22:14:17 volumio volumio[991]: verbose: UNSET VOLATILE: Service: bluetooth Nov 28 22:14:17 volumio volumio[991]: info: Bluetooth Detach Command Nov 28 22:14:17 volumio volumio[991]: info: CoreStateMachine::resetVolumioState Nov 28 22:14:17 volumio volumio[991]: info: CoreStateMachine::getcurrentVolume Nov 28 22:14:17 volumio volumio[991]: info: CoreCommandRouter::volumioRetrievevolume Nov 28 22:14:17 volumio volumio[991]: info: CoreCommandRouter::volumioStop Nov 28 22:14:17 volumio volumio[991]: info: CoreStateMachine::stop Nov 28 22:14:17 volumio volumio[991]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 22:14:17 volumio volumio[991]: info: CoreCommandRouter::servicePushState Nov 28 22:14:17 volumio volumio[991]: info: CorePlayQueue::getTrack 0 Nov 28 22:14:17 volumio volumio[991]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received bluetooth Nov 28 22:14:17 volumio pulseaudio[1762]: Configured latency of 200.00 ms is smaller than minimum latency, using minimum instead Nov 28 22:14:17 volumio volumio[991]: info: Bluetooth Audio successfully Disabled Nov 28 22:14:17 volumio volumio[991]: info: Bluetooth Successfully Detached Nov 28 22:14:17 volumio volumio[991]: info: VolumeController:: Volume=66 Mute =false Nov 28 22:14:17 volumio volumio[991]: info: CoreStateMachine::pushState Nov 28 22:14:17 volumio volumio[991]: info: CorePlayQueue::getTrack 0 Nov 28 22:14:17 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 22:14:17 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Nov 28 22:14:17 volumio volumio[991]: info: MRS: Pushing multiroomSync output update for this device Nov 28 22:14:17 volumio volumio[991]: info: MRS: Pushing multiroomSync output Nov 28 22:14:17 volumio volumio[991]: info: CoreStateMachine::updateTrackBlock Nov 28 22:14:17 volumio volumio[991]: info: CorePlayQueue::getTrackBlock Nov 28 22:14:17 volumio volumio[991]: info: CoreCommandRouter::volumioRetrievevolume Nov 28 22:14:17 volumio volumio[991]: BT PLUGIN MESSAGE: Duration is 15021 Nov 28 22:14:17 volumio volumio[991]: BT PLUGIN MESSAGE: Duration is 946361 Nov 28 22:14:17 volumio volumio[991]: info: VolumeController:: Volume=66 Mute =false Nov 28 22:14:17 volumio volumio[991]: info: CoreStateMachine::pushState Nov 28 22:14:17 volumio volumio[991]: info: CorePlayQueue::getTrack 0 Nov 28 22:14:17 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Nov 28 22:14:17 volumio volumio[991]: info: MRS: Pushing multiroomSync output update for this device Nov 28 22:14:17 volumio volumio[991]: info: MRS: Pushing multiroomSync output Nov 28 22:14:24 volumio volumio[991]: ------------------------------------ BT MESSAGE: BT STATUS: playing Nov 28 22:14:24 volumio volumio[991]: BT PLUGIN MESSAGE: Playing: true Nov 28 22:14:24 volumio volumio[991]: BT PLUGIN MESSAGE: VOLATILE: undefined Nov 28 22:14:24 volumio volumio[991]: BT PLUGIN MESSAGE: STATE:undefined Nov 28 22:14:24 volumio volumio[991]: info: CoreCommandRouter::volumioStop Nov 28 22:14:24 volumio volumio[991]: info: CoreStateMachine::stop Nov 28 22:14:24 volumio volumio[991]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 22:14:24 volumio volumio[991]: BT PLUGIN MESSAGE: BLUETOOTH: Starting Playback Routine Nov 28 22:14:24 volumio volumio[991]: info: Enabling Bluetooth Audio Output Nov 28 22:14:24 volumio volumio[991]: BT PLUGIN MESSAGE: Duration is 946361 Nov 28 22:14:24 volumio volumio[991]: BT PLUGIN MESSAGE: Duration is 6223021 Nov 28 22:14:24 volumio pulseaudio[1762]: Configured latency of 200.00 ms is smaller than minimum latency, using minimum instead Nov 28 22:14:24 volumio pulseaudio[1762]: Cannot set requested sink latency of 343.25 ms, adjusting to 600.00 ms Nov 28 22:14:24 volumio volumio[991]: info: Bluetooth Audio successfully Enabled Nov 28 22:14:24 volumio volumio[991]: info: CoreCommandRouter::servicePushState Nov 28 22:14:24 volumio volumio[991]: info: CoreStateMachine::pushState Nov 28 22:14:24 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 22:14:24 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Nov 28 22:14:24 volumio volumio[991]: info: MRS: Pushing multiroomSync output update for this device Nov 28 22:14:24 volumio volumio[991]: info: MRS: Pushing multiroomSync output Nov 28 22:14:24 volumio volumio[991]: info: [LastFM] Current track has sufficient metadata: title (XXL Lego Wochenende mit Held der Steine!) and artist (Domo) passed on explicitly Nov 28 22:14:27 volumio volumio[991]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 22:14:27 volumio volumio[991]: TypeError: Cannot read property '@' of null Nov 28 22:14:27 volumio volumio[991]: at Object.callback (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:342:14) Nov 28 22:14:27 volumio volumio[991]: at /data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:60:13 Nov 28 22:14:27 volumio volumio[991]: at Parser. (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:304:18) Nov 28 22:14:27 volumio volumio[991]: at Parser.emit (events.js:315:20) Nov 28 22:14:27 volumio volumio[991]: at Parser.exports.Parser.Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:314:16) Nov 28 22:14:27 volumio volumio[991]: at Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:5:59) Nov 28 22:14:27 volumio volumio[991]: at IncomingMessage. (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:58:14) Nov 28 22:14:27 volumio volumio[991]: at IncomingMessage.emit (events.js:327:22) Nov 28 22:14:27 volumio volumio[991]: at endReadableNT (internal/streams/readable.js:1327:12) Nov 28 22:14:27 volumio volumio[991]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 28 22:14:27 volumio volumio[991]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 22:14:27 volumio sudo[20356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-28 22:13 Nov 28 22:14:27 volumio sudo[20356]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"