Oct 29 16:23:20 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Oct 29 16:24:11 volumio volumio[1214]: verbose: New Socket.io Connection to 192.168.2.184:3000 from 192.168.2.125 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Oct 29 16:24:12 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 16:24:12 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 16:24:12 volumio volumio[1214]: info: Discovery: Getting this device information Oct 29 16:24:12 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Oct 29 16:24:12 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 16:24:12 volumio volumio[1214]: verbose: New Socket.io Connection to 192.168.2.184:3000 from 192.168.2.125 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Oct 29 16:24:12 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 29 16:24:12 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 29 16:24:13 volumio volumio[1214]: info: Received Get System Info Oct 29 16:24:13 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 16:24:13 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 16:24:13 volumio volumio[1214]: info: Discovery: Getting this device information Oct 29 16:24:13 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Oct 29 16:24:13 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 16:24:13 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 29 16:24:13 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Oct 29 16:24:14 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 29 16:24:14 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 29 16:24:14 volumio sudo[3921]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 29 16:24:14 volumio sudo[3921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 16:24:14 volumio sudo[3926]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 29 16:24:14 volumio sudo[3926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 16:24:14 volumio sudo[3932]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 29 16:24:14 volumio sudo[3932]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 16:24:14 volumio sudo[3921]: pam_unix(sudo:session): session closed for user root Oct 29 16:24:14 volumio sudo[3926]: pam_unix(sudo:session): session closed for user root Oct 29 16:24:14 volumio sudo[3932]: pam_unix(sudo:session): session closed for user root Oct 29 16:24:14 volumio sudo[3943]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 29 16:24:14 volumio sudo[3943]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 16:24:14 volumio sudo[3937]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 29 16:24:14 volumio sudo[3937]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 16:24:14 volumio sudo[3943]: pam_unix(sudo:session): session closed for user root Oct 29 16:24:14 volumio sudo[3945]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 16:24:14 volumio sudo[3945]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 16:24:14 volumio sudo[3937]: pam_unix(sudo:session): session closed for user root Oct 29 16:24:14 volumio sudo[3945]: pam_unix(sudo:session): session closed for user root Oct 29 16:24:14 volumio volumio[1214]: info: Received Get System Info Oct 29 16:24:14 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 16:24:14 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 16:24:14 volumio volumio[1214]: info: Discovery: Getting this device information Oct 29 16:24:14 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Oct 29 16:24:14 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 16:24:14 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Oct 29 16:24:14 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 16:24:14 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 16:24:14 volumio volumio[1214]: info: Discovery: Getting this device information Oct 29 16:24:14 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Oct 29 16:24:14 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 16:24:14 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 29 16:24:14 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 29 16:24:14 volumio volumio[1214]: verbose: New Socket.io Connection to 192.168.2.184:3000 from 192.168.2.125 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Oct 29 16:24:14 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 29 16:24:14 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 29 16:24:15 volumio sudo[3953]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 29 16:24:15 volumio sudo[3953]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 16:24:15 volumio sudo[3955]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 16:24:15 volumio sudo[3955]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 16:24:15 volumio sudo[3953]: pam_unix(sudo:session): session closed for user root Oct 29 16:24:15 volumio sudo[3955]: pam_unix(sudo:session): session closed for user root Oct 29 16:24:16 volumio volumio[1214]: verbose: New Socket.io Connection to 192.168.2.184 from 192.168.2.125 UA: Mozilla/5.0 (Linux; Android 14; T613P Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 29 16:24:16 volumio sudo[3960]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 29 16:24:16 volumio sudo[3960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 16:24:16 volumio sudo[3960]: pam_unix(sudo:session): session closed for user root Oct 29 16:24:16 volumio sudo[3962]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 16:24:16 volumio sudo[3962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 16:24:16 volumio sudo[3962]: pam_unix(sudo:session): session closed for user root Oct 29 16:24:16 volumio volumio[1214]: verbose: New Socket.io Connection to 192.168.2.184 from 192.168.2.125 UA: Mozilla/5.0 (Linux; Android 14; T613P Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 29 16:24:17 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 29 16:24:17 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 29 16:24:17 volumio volumio[1214]: info: CoreCommandRouter::volumioGetVisibleSources Oct 29 16:24:17 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 29 16:24:17 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Oct 29 16:24:17 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 29 16:24:17 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 29 16:24:17 volumio volumio[1214]: info: Received Get System Info Oct 29 16:24:17 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 16:24:17 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 16:24:17 volumio volumio[1214]: info: Discovery: Getting this device information Oct 29 16:24:17 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Oct 29 16:24:17 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 16:24:17 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Oct 29 16:24:17 volumio volumio[1214]: info: Listing playlists Oct 29 16:24:17 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 29 16:24:17 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 29 16:24:18 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 29 16:24:18 volumio volumio[1214]: info: Received Get System Info Oct 29 16:24:18 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 16:24:18 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 16:24:18 volumio volumio[1214]: info: Discovery: Getting this device information Oct 29 16:24:18 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Oct 29 16:24:18 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 16:24:19 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 29 16:24:19 volumio volumio[1214]: info: VolumeController::SetAlsaVolume+ Oct 29 16:24:20 volumio volumio[1214]: info: CoreStateMachine::pushState Oct 29 16:24:20 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 16:24:20 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Oct 29 16:24:20 volumio volumio[1214]: info: VolumeController::SetAlsaVolume+ Oct 29 16:24:20 volumio volumio[1214]: info: CoreStateMachine::pushState Oct 29 16:24:20 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 16:24:20 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Oct 29 16:24:20 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 29 16:24:20 volumio volumio[1214]: info: Received Get System Info Oct 29 16:24:20 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 16:24:20 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 16:24:20 volumio volumio[1214]: info: Discovery: Getting this device information Oct 29 16:24:20 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Oct 29 16:24:20 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 16:24:26 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 29 16:24:33 volumio volumio[1214]: info: CoreCommandRouter::volumioGetQueue Oct 29 16:24:33 volumio volumio[1214]: info: CoreStateMachine::getQueue Oct 29 16:24:33 volumio volumio[1214]: info: CorePlayQueue::getQueue Oct 29 16:24:34 volumio volumio[1214]: info: Oct 29 16:24:34 volumio volumio[1214]: ---------------------------- MPD announces system playlist update Oct 29 16:24:34 volumio volumio[1214]: info: Ignoring MPD Status Update Oct 29 16:24:34 volumio volumio[1214]: info: Oct 29 16:24:34 volumio volumio[1214]: ---------------------------- MPD announces state update: player Oct 29 16:24:34 volumio volumio[1214]: info: ControllerMpd::getState Oct 29 16:24:34 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Oct 29 16:24:34 volumio volumio[1214]: info: Oct 29 16:24:34 volumio volumio[1214]: ---------------------------- MPD announces system playlist update Oct 29 16:24:34 volumio volumio[1214]: info: Ignoring MPD Status Update Oct 29 16:24:34 volumio volumio[1214]: info: Oct 29 16:24:34 volumio volumio[1214]: ---------------------------- MPD announces state update: player Oct 29 16:24:34 volumio volumio[1214]: info: ControllerMpd::getState Oct 29 16:24:34 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Oct 29 16:24:34 volumio volumio[1214]: info: Oct 29 16:24:34 volumio volumio[1214]: ---------------------------- MPD announces system playlist update Oct 29 16:24:34 volumio volumio[1214]: info: Ignoring MPD Status Update Oct 29 16:24:34 volumio volumio[1214]: info: Oct 29 16:24:34 volumio volumio[1214]: ---------------------------- MPD announces state update: player Oct 29 16:24:34 volumio volumio[1214]: info: ControllerMpd::getState Oct 29 16:24:34 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Oct 29 16:24:34 volumio volumio[1214]: info: ------------------------------ 11ms Oct 29 16:24:34 volumio volumio[1214]: info: sendMpdCommand status took 8 milliseconds Oct 29 16:24:34 volumio volumio[1214]: info: ------------------------------ 7ms Oct 29 16:24:34 volumio volumio[1214]: info: sendMpdCommand status took 5 milliseconds Oct 29 16:24:34 volumio volumio[1214]: verbose: ControllerMpd::parseState Oct 29 16:24:34 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 16:24:34 volumio volumio[1214]: verbose: ControllerMpd::parseState Oct 29 16:24:34 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 16:24:34 volumio volumio[1214]: info: ------------------------------ 9ms Oct 29 16:24:34 volumio volumio[1214]: info: sendMpdCommand status took 6 milliseconds Oct 29 16:24:34 volumio volumio[1214]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 29 16:24:34 volumio volumio[1214]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 29 16:24:34 volumio volumio[1214]: verbose: ControllerMpd::parseState Oct 29 16:24:34 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 16:24:34 volumio volumio[1214]: verbose: ControllerMpd::parseTrackInfo Oct 29 16:24:34 volumio volumio[1214]: verbose: ControllerMpd::parseTrackInfo Oct 29 16:24:34 volumio volumio[1214]: info: ControllerMpd::pushState Oct 29 16:24:34 volumio volumio[1214]: info: CoreCommandRouter::servicePushState Oct 29 16:24:34 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Oct 29 16:24:34 volumio volumio[1214]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1304640,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michel Legrand - 'Round Midnight","artist":"Jazz Groove Mix 2 (Free FLAC)","album":null,"uri":"http://mp3-128.streamthejazzgroove.com/?referer=tunein.com","trackType":"com"} Oct 29 16:24:34 volumio volumio[1214]: verbose: CURRENT POSITION 0 Oct 29 16:24:34 volumio volumio[1214]: info: CoreStateMachine::syncState stateService play Oct 29 16:24:34 volumio volumio[1214]: info: CoreStateMachine::syncState currentStatus play Oct 29 16:24:34 volumio volumio[1214]: info: Received an update from plugin. extracting info from payload Oct 29 16:24:34 volumio volumio[1214]: info: CoreStateMachine::pushState Oct 29 16:24:34 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 16:24:34 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Oct 29 16:24:34 volumio volumio[1214]: info: CoreStateMachine::pushState Oct 29 16:24:34 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Oct 29 16:24:34 volumio volumio[1214]: info: ControllerMpd::pushState Oct 29 16:24:34 volumio volumio[1214]: info: CoreCommandRouter::servicePushState Oct 29 16:24:34 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Oct 29 16:24:34 volumio volumio[1214]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1304640,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michel Legrand - 'Round Midnight","artist":"Jazz Groove Mix 2 (Free FLAC)","album":null,"uri":"http://mp3-128.streamthejazzgroove.com/?referer=tunein.com","trackType":"com"} Oct 29 16:24:34 volumio volumio[1214]: verbose: CURRENT POSITION 0 Oct 29 16:24:34 volumio volumio[1214]: info: CoreStateMachine::syncState stateService play Oct 29 16:24:34 volumio volumio[1214]: info: CoreStateMachine::syncState currentStatus play Oct 29 16:24:34 volumio volumio[1214]: info: Received an update from plugin. extracting info from payload Oct 29 16:24:34 volumio volumio[1214]: info: CoreStateMachine::pushState Oct 29 16:24:34 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Oct 29 16:24:34 volumio volumio[1214]: info: CoreStateMachine::pushState Oct 29 16:24:34 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Oct 29 16:24:34 volumio volumio[1214]: info: ------------------------------ 86ms Oct 29 16:24:34 volumio volumio[1214]: info: ------------------------------ 82ms Oct 29 16:24:34 volumio volumio[1214]: info: sendMpdCommand playlistinfo took 71 milliseconds Oct 29 16:24:34 volumio volumio[1214]: verbose: ControllerMpd::parseTrackInfo Oct 29 16:24:34 volumio volumio[1214]: info: ControllerMpd::pushState Oct 29 16:24:34 volumio volumio[1214]: info: CoreCommandRouter::servicePushState Oct 29 16:24:34 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Oct 29 16:24:34 volumio volumio[1214]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1304640,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michel Legrand - 'Round Midnight","artist":"Jazz Groove Mix 2 (Free FLAC)","album":null,"uri":"http://mp3-128.streamthejazzgroove.com/?referer=tunein.com","trackType":"com"} Oct 29 16:24:34 volumio volumio[1214]: verbose: CURRENT POSITION 0 Oct 29 16:24:34 volumio volumio[1214]: info: CoreStateMachine::syncState stateService play Oct 29 16:24:34 volumio volumio[1214]: info: CoreStateMachine::syncState currentStatus play Oct 29 16:24:34 volumio volumio[1214]: info: Received an update from plugin. extracting info from payload Oct 29 16:24:34 volumio volumio[1214]: info: CoreStateMachine::pushState Oct 29 16:24:34 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Oct 29 16:24:34 volumio volumio[1214]: info: CoreStateMachine::pushState Oct 29 16:24:34 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Oct 29 16:24:34 volumio volumio[1214]: info: ------------------------------ 120ms Oct 29 16:24:36 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 29 16:24:36 volumio volumio[1214]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 29 16:24:36 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 29 16:24:36 volumio volumio[1214]: info: Received Get System Version Oct 29 16:24:36 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 29 16:24:36 volumio volumio[1214]: info: Received Get System Info Oct 29 16:24:36 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 16:24:36 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 16:24:36 volumio volumio[1214]: info: Discovery: Getting this device information Oct 29 16:24:36 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Oct 29 16:24:36 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 16:24:38 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 29 16:24:38 volumio volumio[1214]: info: CURURI: music-library Oct 29 16:24:38 volumio volumio[1214]: info: Preload queue cleared Oct 29 16:24:41 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 29 16:24:41 volumio volumio[1214]: info: CURURI: music-library/USB Oct 29 16:24:41 volumio volumio[1214]: info: Preload queue cleared Oct 29 16:24:42 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 29 16:24:42 volumio volumio[1214]: info: CURURI: music-library/USB/SSD_1TB Oct 29 16:24:42 volumio volumio[1214]: info: Preload queue cleared Oct 29 16:24:50 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Oct 29 16:24:50 volumio dbus-daemon[733]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.46' (uid=0 pid=4022 comm="timedatectl show --property=NTPSynchronized --valu") Oct 29 16:24:50 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Oct 29 16:24:50 volumio dbus-daemon[733]: [system] Successfully activated service 'org.freedesktop.timedate1' Oct 29 16:24:50 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Oct 29 16:24:50 volumio setdatetime-helper.sh[4021]: Time is already synchronized. Oct 29 16:24:50 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Oct 29 16:24:50 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Oct 29 16:24:52 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 29 16:24:52 volumio volumio[1214]: info: CURURI: music-library/USB/SSD_1TB/1979 In the Heat of The Night [1994 dcc gzs-1056] Oct 29 16:24:52 volumio volumio[1214]: info: Preload queue cleared Oct 29 16:24:52 volumio volumio[1214]: info: Preloading song: music-library/USB/SSD_1TB/1979 In the Heat of The Night [1994 dcc gzs-1056]/10 - So Sincere.flac Oct 29 16:24:52 volumio volumio[1214]: info: Preloading song: music-library/USB/SSD_1TB/1979 In the Heat of The Night [1994 dcc gzs-1056]/In the Heat of the Night [DCC].FLAC.CUE Oct 29 16:24:52 volumio volumio[1214]: info: Preloading song: music-library/USB/SSD_1TB/1979 In the Heat of The Night [1994 dcc gzs-1056]/Pat Benatar - In the Heat of the Night (1979) (1994 DCC).m3u Oct 29 16:24:52 volumio volumio[1214]: info: Exploding uri music-library/USB/SSD_1TB/1979 In the Heat of The Night [1994 dcc gzs-1056]/10 - So Sincere.flac in service mpd Oct 29 16:24:52 volumio volumio[1214]: info: ALBUMART /albumart?cacheid=477&web=Pat%20Benatar/In%20the%20Heat%20of%20the%20Night/extralarge&path=%2Fmnt%2FUSB%2FSSD_1TB%2F1979%20In%20the%20Heat%20of%20The%20Night%20%5B1994%20dcc%20gzs-1056%5D%2F10%20-%20So%20Sincere.flac&metadata=false Oct 29 16:24:52 volumio volumio[1214]: info: URI /mnt/USB/SSD_1TB/1979 In the Heat of The Night [1994 dcc gzs-1056]/10 - So Sincere.flac Oct 29 16:24:52 volumio volumio[1214]: info: Exploding uri music-library/USB/SSD_1TB/1979 In the Heat of The Night [1994 dcc gzs-1056]/In the Heat of the Night [DCC].FLAC.CUE in service mpd Oct 29 16:24:52 volumio volumio[1214]: info: ALBUMART /albumart?cacheid=477&web=Pat%20Benatar/In%20the%20Heat%20of%20the%20Night%20(1994%20DCC%20Gold%20GZS-1056)/extralarge&path=%2Fmnt%2FUSB%2FSSD_1TB%2F1979%20In%20the%20Heat%20of%20The%20Night%20%5B1994%20dcc%20gzs-1056%5D%2FIn%20the%20Heat%20of%20the%20Night%20%5BDCC%5D.FLAC.CUE&metadata=false Oct 29 16:24:52 volumio volumio[1214]: info: URI /mnt/USB/SSD_1TB/1979 In the Heat of The Night [1994 dcc gzs-1056]/In the Heat of the Night [DCC].FLAC.CUE Oct 29 16:24:52 volumio volumio[1214]: info: ALBUMART /albumart?cacheid=477&web=Pat%20Benatar/In%20the%20Heat%20of%20the%20Night%20(1994%20DCC%20Gold%20GZS-1056)/extralarge&path=%2Fmnt%2FUSB%2FSSD_1TB%2F1979%20In%20the%20Heat%20of%20The%20Night%20%5B1994%20dcc%20gzs-1056%5D%2FIn%20the%20Heat%20of%20the%20Night%20%5BDCC%5D.FLAC.CUE&metadata=false Oct 29 16:24:52 volumio volumio[1214]: info: URI /mnt/USB/SSD_1TB/1979 In the Heat of The Night [1994 dcc gzs-1056]/In the Heat of the Night [DCC].FLAC.CUE Oct 29 16:24:52 volumio volumio[1214]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 16:24:52 volumio volumio[1214]: Error: Unable to resolve or reject the same promise twice Oct 29 16:24:52 volumio volumio[1214]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 29 16:24:52 volumio volumio[1214]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Oct 29 16:24:52 volumio volumio[1214]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 29 16:24:52 volumio volumio[1214]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 29 16:24:52 volumio volumio[1214]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 29 16:24:52 volumio volumio[1214]: at Socket.emit (node:events:514:28) Oct 29 16:24:52 volumio volumio[1214]: at addChunk (node:internal/streams/readable:343:12) Oct 29 16:24:52 volumio volumio[1214]: at readableAddChunk (node:internal/streams/readable:312:11) Oct 29 16:24:52 volumio volumio[1214]: at Readable.push (node:internal/streams/readable:253:10) Oct 29 16:24:52 volumio volumio[1214]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Oct 29 16:24:52 volumio volumio[1214]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 16:24:53 volumio sudo[4053]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-29 16:23' Oct 29 16:24:53 volumio sudo[4053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dfc7bb04e09bf2260691765a2340814af1fa2971" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="30d63a8c53d2826bd7f262e22b4e4511a4cc9d01" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Aug 30 06:39:32 UTC 2025" VOLUMIO_VERSION="4.022" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e7b0bd0cb5661ea77402e5bd121ec64a"