-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Tue 2025-05-13 21:29:35 IST. -- May 13 21:28:17 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 21:28:17 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 21:28:17 volumio volumio[1196]: info: Discovery: Getting this device information May 13 21:28:17 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 13 21:28:17 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 21:28:17 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 21:28:17 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 21:28:17 volumio volumio[1196]: info: Discovery: Getting this device information May 13 21:28:17 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 13 21:28:17 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 21:28:18 volumio volumio[1196]: verbose: New Socket.io Connection to 192.168.0.48:3000 from 192.168.0.42 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 May 13 21:28:30 volumio volumio[1196]: verbose: New Socket.io Connection to 192.168.0.48 from 192.168.0.42 UA: Mozilla/5.0 (Linux; Android 9; SM-A520F Build/PQ1A.190105.004; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.87 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 13 21:28:30 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 13 21:28:30 volumio volumio[1196]: info: CoreCommandRouter::volumioGetVisibleSources May 13 21:28:30 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 21:28:30 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 13 21:28:30 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 13 21:28:30 volumio volumio[1196]: info: CoreCommandRouter::volumioGetQueue May 13 21:28:30 volumio volumio[1196]: info: CoreStateMachine::getQueue May 13 21:28:30 volumio volumio[1196]: info: CorePlayQueue::getQueue May 13 21:28:30 volumio volumio[1196]: info: Listing playlists May 13 21:28:30 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 13 21:28:30 volumio volumio[1196]: info: Received Get System Info May 13 21:28:30 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 21:28:30 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 21:28:30 volumio volumio[1196]: info: Discovery: Getting this device information May 13 21:28:30 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 13 21:28:30 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 21:28:30 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 13 21:28:31 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 13 21:28:35 volumio wpa_supplicant[922]: wlan0: CTRL-EVENT-DISCONNECTED bssid=68:02:b8:d8:cb:e6 reason=4 May 13 21:28:35 volumio wpa_supplicant[922]: wlan0: Trying to associate with SSID 'VM3969302_5G' May 13 21:28:35 volumio wpa_supplicant[922]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 13 21:28:35 volumio wpa_supplicant[922]: wlan0: Associated with 68:02:b8:d8:cb:e6 May 13 21:28:35 volumio wpa_supplicant[922]: wlan0: CTRL-EVENT-CONNECTED - Connection to 68:02:b8:d8:cb:e6 completed [id=0 id_str=] May 13 21:28:35 volumio wpa_supplicant[922]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 13 21:28:35 volumio systemd-udevd[3814]: Process '/sbin/crda' failed with exit code 255. May 13 21:28:38 volumio wpa_supplicant[922]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 13 21:28:48 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 21:28:48 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 21:28:48 volumio volumio[1196]: info: Discovery: Getting this device information May 13 21:28:48 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 13 21:28:48 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 21:28:49 volumio volumio[1196]: verbose: New Socket.io Connection to 192.168.0.48:3000 from 192.168.0.42 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 May 13 21:28:53 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 21:28:53 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 21:28:53 volumio volumio[1196]: info: Discovery: Getting this device information May 13 21:28:53 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 13 21:28:53 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 21:28:59 volumio volumio[1196]: info: May 13 21:28:59 volumio volumio[1196]: ---------------------------- MPD announces state update: player May 13 21:28:59 volumio volumio[1196]: info: ControllerMpd::getState May 13 21:28:59 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status May 13 21:28:59 volumio volumio[1196]: info: sendMpdCommand status took 1 milliseconds May 13 21:28:59 volumio volumio[1196]: verbose: ControllerMpd::parseState May 13 21:28:59 volumio volumio[1196]: info: ControllerMpd::pushState May 13 21:28:59 volumio volumio[1196]: info: CoreCommandRouter::servicePushState May 13 21:28:59 volumio volumio[1196]: info: CoreStateMachine::pushState May 13 21:28:59 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 21:28:59 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 13 21:28:59 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 May 13 21:28:59 volumio volumio[1196]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 13 21:28:59 volumio volumio[1196]: verbose: CURRENT POSITION 0 May 13 21:28:59 volumio volumio[1196]: info: CoreStateMachine::syncState stateService stop May 13 21:28:59 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play May 13 21:28:59 volumio volumio[1196]: info: CoreStateMachine::play index undefined May 13 21:28:59 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 21:28:59 volumio volumio[1196]: info: CoreStateMachine::pushState May 13 21:28:59 volumio volumio[1196]: info: CorePlayQueue::getTrack 1 May 13 21:28:59 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 21:28:59 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 13 21:28:59 volumio volumio[1196]: info: CorePlayQueue::getTrack 1 May 13 21:28:59 volumio volumio[1196]: info: CoreStateMachine::startPlaybackTimer May 13 21:28:59 volumio volumio[1196]: info: CorePlayQueue::getTrack 1 May 13 21:28:59 volumio volumio[1196]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=1918171622 May 13 21:28:59 volumio volumio[1196]: info: ------------------------------ 31ms May 13 21:28:59 volumio volumio[1196]: info: CoreStateMachine::pushState May 13 21:28:59 volumio volumio[1196]: info: CorePlayQueue::getTrack 1 May 13 21:28:59 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 21:28:59 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 13 21:28:59 volumio volumio[1196]: info: touch_display: Setting screensaver timeout to 60 seconds. May 13 21:28:59 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand stop May 13 21:28:59 volumio volumio[1196]: info: sendMpdCommand stop took 1 milliseconds May 13 21:28:59 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand clear May 13 21:28:59 volumio volumio[1196]: info: May 13 21:28:59 volumio volumio[1196]: ---------------------------- MPD announces system playlist update May 13 21:28:59 volumio volumio[1196]: info: Ignoring MPD Status Update May 13 21:28:59 volumio volumio[1196]: info: sendMpdCommand clear took 1 milliseconds May 13 21:28:59 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand addid "https://cf-preview-media.sndcdn.com/preview/0/30/t2rzkHU3zWDE.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvdDJyemtIVTN6V0RFLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ3MTY4NDM5fX19XX0_&Signature=QcKTddY5JEnHttElXUxTZpmPqX6C-n9tehDatXfLnRm50d7drGCKC0h08-fwHSUctGfQNXxnlklJxd4HA7oaGhKsf4LDZXSc8x-wOe8508Qk0dpKW3gDx3-HQT2bzE9oIjWpZ2O6dACIonbh7Wuv37j6XfowGh93aJEWS5QvOMAjY0LL2yS7ORMOTsBAktAdNLKXfF8xK0vpc0By4E02g6rnISfldT2xsFzOy64BH0uaLKiJlehZGYuQev8ZBD17-xfLcC6c-5zP2FvwOhJMvU-YwL8DfKYaxGzagFApp7av8VqM8QHmwoI3Kj9YXnb1xzFo8i~x7PE~IOb9adb-Ig__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" May 13 21:28:59 volumio volumio[1196]: error: updateQueue error: null May 13 21:28:59 volumio volumio[1196]: info: May 13 21:28:59 volumio volumio[1196]: ---------------------------- MPD announces system playlist update May 13 21:28:59 volumio volumio[1196]: info: Ignoring MPD Status Update May 13 21:28:59 volumio volumio[1196]: info: ------------------------------ 6ms May 13 21:28:59 volumio volumio[1196]: info: sendMpdCommand addid "https://cf-preview-media.sndcdn.com/preview/0/30/t2rzkHU3zWDE.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvdDJyemtIVTN6V0RFLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ3MTY4NDM5fX19XX0_&Signature=QcKTddY5JEnHttElXUxTZpmPqX6C-n9tehDatXfLnRm50d7drGCKC0h08-fwHSUctGfQNXxnlklJxd4HA7oaGhKsf4LDZXSc8x-wOe8508Qk0dpKW3gDx3-HQT2bzE9oIjWpZ2O6dACIonbh7Wuv37j6XfowGh93aJEWS5QvOMAjY0LL2yS7ORMOTsBAktAdNLKXfF8xK0vpc0By4E02g6rnISfldT2xsFzOy64BH0uaLKiJlehZGYuQev8ZBD17-xfLcC6c-5zP2FvwOhJMvU-YwL8DfKYaxGzagFApp7av8VqM8QHmwoI3Kj9YXnb1xzFo8i~x7PE~IOb9adb-Ig__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" took 4 milliseconds May 13 21:28:59 volumio volumio[1196]: verbose: MPD COMMAND [object Object] May 13 21:28:59 volumio volumio[1196]: verbose: MPD COMMAND [object Object] May 13 21:28:59 volumio volumio[1196]: verbose: MPD COMMAND [object Object] May 13 21:28:59 volumio volumio[1196]: info: May 13 21:28:59 volumio volumio[1196]: ---------------------------- MPD announces system playlist update May 13 21:28:59 volumio volumio[1196]: info: Ignoring MPD Status Update May 13 21:28:59 volumio volumio[1196]: info: ------------------------------ 4ms May 13 21:28:59 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService mpd May 13 21:28:59 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand play May 13 21:28:59 volumio volumio[1196]: info: ------------------------------ 5ms May 13 21:28:59 volumio volumio[1196]: info: sendMpdCommand play took 8 milliseconds May 13 21:29:01 volumio volumio[1196]: info: May 13 21:29:01 volumio volumio[1196]: ---------------------------- MPD announces state update: player May 13 21:29:01 volumio volumio[1196]: info: ControllerMpd::getState May 13 21:29:01 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status May 13 21:29:01 volumio volumio[1196]: info: May 13 21:29:01 volumio volumio[1196]: ---------------------------- MPD announces state update: player May 13 21:29:01 volumio volumio[1196]: info: sendMpdCommand status took 3 milliseconds May 13 21:29:01 volumio volumio[1196]: info: ControllerMpd::getState May 13 21:29:01 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status May 13 21:29:01 volumio volumio[1196]: verbose: ControllerMpd::parseState May 13 21:29:01 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 21:29:01 volumio volumio[1196]: info: sendMpdCommand status took 2 milliseconds May 13 21:29:01 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 2 milliseconds May 13 21:29:01 volumio volumio[1196]: verbose: ControllerMpd::parseState May 13 21:29:01 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 21:29:01 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo May 13 21:29:01 volumio volumio[1196]: info: ControllerMpd::pushState May 13 21:29:01 volumio volumio[1196]: info: CoreCommandRouter::servicePushState May 13 21:29:01 volumio volumio[1196]: info: CorePlayQueue::getTrack 1 May 13 21:29:01 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nightcall","artist":"(Preview) Kavinsky","album":"Nightcall","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/t2rzkHU3zWDE.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvdDJyemtIVTN6V0RFLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ3MTY4NDM5fX19XX0_&Signature=QcKTddY5JEnHttElXUxTZpmPqX6C-n9tehDatXfLnRm50d7drGCKC0h08-fwHSUctGfQNXxnlklJxd4HA7oaGhKsf4LDZXSc8x-wOe8508Qk0dpKW3gDx3-HQT2bzE9oIjWpZ2O6dACIonbh7Wuv37j6XfowGh93aJEWS5QvOMAjY0LL2yS7ORMOTsBAktAdNLKXfF8xK0vpc0By4E02g6rnISfldT2xsFzOy64BH0uaLKiJlehZGYuQev8ZBD17-xfLcC6c-5zP2FvwOhJMvU-YwL8DfKYaxGzagFApp7av8VqM8QHmwoI3Kj9YXnb1xzFo8i~x7PE~IOb9adb-Ig__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} May 13 21:29:01 volumio volumio[1196]: verbose: CURRENT POSITION 1 May 13 21:29:01 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play May 13 21:29:01 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus stop May 13 21:29:01 volumio volumio[1196]: info: ------------------------------ 11ms May 13 21:29:01 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 2 milliseconds May 13 21:29:01 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo May 13 21:29:01 volumio volumio[1196]: info: ControllerMpd::pushState May 13 21:29:01 volumio volumio[1196]: info: CoreCommandRouter::servicePushState May 13 21:29:01 volumio volumio[1196]: info: CorePlayQueue::getTrack 1 May 13 21:29:01 volumio volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nightcall","artist":"(Preview) Kavinsky","album":"Nightcall","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/t2rzkHU3zWDE.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvdDJyemtIVTN6V0RFLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ3MTY4NDM5fX19XX0_&Signature=QcKTddY5JEnHttElXUxTZpmPqX6C-n9tehDatXfLnRm50d7drGCKC0h08-fwHSUctGfQNXxnlklJxd4HA7oaGhKsf4LDZXSc8x-wOe8508Qk0dpKW3gDx3-HQT2bzE9oIjWpZ2O6dACIonbh7Wuv37j6XfowGh93aJEWS5QvOMAjY0LL2yS7ORMOTsBAktAdNLKXfF8xK0vpc0By4E02g6rnISfldT2xsFzOy64BH0uaLKiJlehZGYuQev8ZBD17-xfLcC6c-5zP2FvwOhJMvU-YwL8DfKYaxGzagFApp7av8VqM8QHmwoI3Kj9YXnb1xzFo8i~x7PE~IOb9adb-Ig__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} May 13 21:29:01 volumio volumio[1196]: verbose: CURRENT POSITION 1 May 13 21:29:01 volumio volumio[1196]: info: CoreStateMachine::syncState stateService play May 13 21:29:01 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus play May 13 21:29:01 volumio volumio[1196]: info: Received an update from plugin. extracting info from payload May 13 21:29:01 volumio volumio[1196]: info: CoreStateMachine::pushState May 13 21:29:01 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 21:29:01 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 13 21:29:01 volumio volumio[1196]: info: CoreStateMachine::pushState May 13 21:29:01 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 13 21:29:01 volumio volumio[1196]: info: ------------------------------ 29ms May 13 21:29:01 volumio volumio[1196]: info: touch_display: Setting screensaver timeout to 0 seconds. May 13 21:29:02 volumio volumio[1196]: verbose: New Socket.io Connection to 192.168.0.48 from 192.168.0.42 UA: Mozilla/5.0 (Linux; Android 9; SM-A520F Build/PQ1A.190105.004; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.87 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 13 21:29:02 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 13 21:29:02 volumio volumio[1196]: info: CoreCommandRouter::volumioGetVisibleSources May 13 21:29:02 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 21:29:02 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 13 21:29:02 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 13 21:29:02 volumio volumio[1196]: info: CoreCommandRouter::volumioGetQueue May 13 21:29:02 volumio volumio[1196]: info: CoreStateMachine::getQueue May 13 21:29:02 volumio volumio[1196]: info: CorePlayQueue::getQueue May 13 21:29:02 volumio volumio[1196]: info: Listing playlists May 13 21:29:02 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 13 21:29:02 volumio volumio[1196]: info: Received Get System Info May 13 21:29:02 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 21:29:02 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 21:29:02 volumio volumio[1196]: info: Discovery: Getting this device information May 13 21:29:02 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 13 21:29:02 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 21:29:02 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 13 21:29:02 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 13 21:29:05 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 21:29:05 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 21:29:05 volumio volumio[1196]: info: Discovery: Getting this device information May 13 21:29:05 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 13 21:29:05 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 21:29:05 volumio volumio[1196]: info: CoreCommandRouter::volumioPause May 13 21:29:05 volumio volumio[1196]: info: CoreStateMachine::pause May 13 21:29:05 volumio volumio[1196]: info: CoreStateMachine::stPlaybackTimer May 13 21:29:05 volumio volumio[1196]: info: CoreStateMachine::servicePause May 13 21:29:05 volumio volumio[1196]: info: CorePlayQueue::getTrack 1 May 13 21:29:05 volumio volumio[1196]: info: CoreCommandRouter::servicePause May 13 21:29:05 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService mpd May 13 21:29:05 volumio volumio[1196]: info: ControllerMpd::pause May 13 21:29:05 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand pause May 13 21:29:05 volumio volumio[1196]: info: May 13 21:29:05 volumio volumio[1196]: ---------------------------- MPD announces state update: player May 13 21:29:05 volumio volumio[1196]: info: sendMpdCommand pause took 1 milliseconds May 13 21:29:05 volumio volumio[1196]: info: ControllerMpd::getState May 13 21:29:05 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand status May 13 21:29:05 volumio volumio[1196]: info: sendMpdCommand status took 1 milliseconds May 13 21:29:05 volumio volumio[1196]: verbose: ControllerMpd::parseState May 13 21:29:05 volumio volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 21:29:05 volumio volumio[1196]: info: sendMpdCommand playlistinfo took 0 milliseconds May 13 21:29:05 volumio volumio[1196]: verbose: ControllerMpd::parseTrackInfo May 13 21:29:05 volumio volumio[1196]: info: ControllerMpd::pushState May 13 21:29:05 volumio volumio[1196]: info: CoreCommandRouter::servicePushState May 13 21:29:05 volumio volumio[1196]: info: CorePlayQueue::getTrack 1 May 13 21:29:05 volumio volumio[1196]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4994,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nightcall","artist":"(Preview) Kavinsky","album":"Nightcall","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/t2rzkHU3zWDE.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvdDJyemtIVTN6V0RFLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ3MTY4NDM5fX19XX0_&Signature=QcKTddY5JEnHttElXUxTZpmPqX6C-n9tehDatXfLnRm50d7drGCKC0h08-fwHSUctGfQNXxnlklJxd4HA7oaGhKsf4LDZXSc8x-wOe8508Qk0dpKW3gDx3-HQT2bzE9oIjWpZ2O6dACIonbh7Wuv37j6XfowGh93aJEWS5QvOMAjY0LL2yS7ORMOTsBAktAdNLKXfF8xK0vpc0By4E02g6rnISfldT2xsFzOy64BH0uaLKiJlehZGYuQev8ZBD17-xfLcC6c-5zP2FvwOhJMvU-YwL8DfKYaxGzagFApp7av8VqM8QHmwoI3Kj9YXnb1xzFo8i~x7PE~IOb9adb-Ig__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} May 13 21:29:05 volumio volumio[1196]: verbose: CURRENT POSITION 1 May 13 21:29:05 volumio volumio[1196]: info: CoreStateMachine::syncState stateService pause May 13 21:29:05 volumio volumio[1196]: info: CoreStateMachine::syncState currentStatus pause May 13 21:29:05 volumio volumio[1196]: info: CoreStateMachine::pushState May 13 21:29:05 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 21:29:05 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 13 21:29:05 volumio volumio[1196]: info: CoreStateMachine::stPlaybackTimer May 13 21:29:05 volumio volumio[1196]: info: ------------------------------ 18ms May 13 21:29:05 volumio volumio[1196]: info: touch_display: Setting screensaver timeout to 60 seconds. May 13 21:29:07 volumio volumio[1196]: info: peppy_screensaver: Start PeppyMeter May 13 21:29:21 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 13 21:29:21 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 13 21:29:25 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 21:29:25 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 21:29:25 volumio volumio[1196]: info: Discovery: Getting this device information May 13 21:29:25 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 13 21:29:25 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 21:29:25 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 21:29:25 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 21:29:25 volumio volumio[1196]: info: Discovery: Getting this device information May 13 21:29:25 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState May 13 21:29:25 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 21:29:27 volumio volumio[1196]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSoundCloud May 13 21:29:27 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 21:29:27 volumio volumio[1196]: info: Disabling plugin soundcloud May 13 21:29:27 volumio volumio[1196]: info: Done. May 13 21:29:35 volumio ntpd[761]: 193.111.32.123 local addr 192.168.0.49 -> May 13 21:29:35 volumio volumio[1196]: info: CoreStateMachine::stop May 13 21:29:35 volumio volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 21:29:35 volumio volumio[1196]: info: CoreStateMachine::updateTrackBlock May 13 21:29:35 volumio volumio[1196]: info: CorePlayQueue::getTrackBlock May 13 21:29:35 volumio volumio[1196]: info: CoreStateMachine::stPlaybackTimer May 13 21:29:35 volumio volumio[1196]: info: CoreStateMachine::pushState May 13 21:29:35 volumio volumio[1196]: info: CorePlayQueue::getTrack 1 May 13 21:29:35 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 21:29:35 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState May 13 21:29:35 volumio volumio[1196]: info: CoreStateMachine::serviceStop May 13 21:29:35 volumio volumio[1196]: info: CorePlayQueue::getTrack 1 May 13 21:29:35 volumio volumio[1196]: info: CoreCommandRouter::serviceStop May 13 21:29:35 volumio volumio[1196]: error: WARNING: No stop method for service soundcloud May 13 21:29:35 volumio volumio[1196]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 21:29:35 volumio volumio[1196]: TypeError: Cannot read property 'then' of undefined May 13 21:29:35 volumio volumio[1196]: at peppyScreensaver.onStop (/data/plugins/user_interface/peppy_screensaver/index.js:243:43) May 13 21:29:35 volumio volumio[1196]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 13 21:29:35 volumio volumio[1196]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) May 13 21:29:35 volumio volumio[1196]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) May 13 21:29:35 volumio volumio[1196]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) May 13 21:29:35 volumio volumio[1196]: at Socket.emit (events.js:315:20) May 13 21:29:35 volumio volumio[1196]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 13 21:29:35 volumio volumio[1196]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 13 21:29:35 volumio volumio[1196]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 21:29:35 volumio sudo[4056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-13 21:28 May 13 21:29:35 volumio sudo[4056]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 09 May 2025 12:45:48 AM CEST" VOLUMIO_VERSION="3.808" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fd57d03cbffb8029ebc849ab499a86a4"