-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sat 2024-10-19 11:14:28 CEST. -- Oct 19 11:13:40 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 11:13:40 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 11:13:40 volumio volumio[972]: info: Discovery: Getting this device information Oct 19 11:13:40 volumio volumio[972]: info: CoreCommandRouter::volumioGetState Oct 19 11:13:40 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 11:13:40 volumio volumio[972]: verbose: New Socket.io Connection to 192.168.1.234:3000 from 192.168.1.132 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Oct 19 11:13:40 volumio volumio[972]: info: CoreCommandRouter::volumioGetState Oct 19 11:13:40 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 19 11:13:40 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 19 11:13:43 volumio sudo[20047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 19 11:13:43 volumio sudo[20051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 19 11:13:43 volumio sudo[20047]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:13:43 volumio sudo[20051]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:13:43 volumio sudo[20047]: pam_unix(sudo:session): session closed for user root Oct 19 11:13:43 volumio sudo[20051]: pam_unix(sudo:session): session closed for user root Oct 19 11:13:43 volumio volumio[972]: verbose: New Socket.io Connection to 192.168.1.234 from 192.168.1.132 UA: Mozilla/5.0 (Linux; Android 12; M2102J20SG Build/SKQ1.211006.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Oct 19 11:13:43 volumio sudo[20056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 19 11:13:43 volumio sudo[20056]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:13:43 volumio sudo[20056]: pam_unix(sudo:session): session closed for user root Oct 19 11:13:43 volumio sudo[20059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 19 11:13:43 volumio sudo[20059]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:13:43 volumio sudo[20059]: pam_unix(sudo:session): session closed for user root Oct 19 11:13:43 volumio volumio[972]: verbose: New Socket.io Connection to 192.168.1.234 from 192.168.1.132 UA: Mozilla/5.0 (Linux; Android 12; M2102J20SG Build/SKQ1.211006.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Oct 19 11:13:44 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 19 11:13:44 volumio volumio[972]: info: CoreCommandRouter::volumioGetVisibleSources Oct 19 11:13:44 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 19 11:13:44 volumio volumio[972]: info: CoreCommandRouter::volumioGetState Oct 19 11:13:44 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 19 11:13:44 volumio volumio[972]: info: CoreCommandRouter::volumioGetQueue Oct 19 11:13:44 volumio volumio[972]: info: CoreStateMachine::getQueue Oct 19 11:13:44 volumio volumio[972]: info: CorePlayQueue::getQueue Oct 19 11:13:44 volumio volumio[972]: info: Listing playlists Oct 19 11:13:44 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 19 11:13:44 volumio volumio[972]: info: Received Get System Info Oct 19 11:13:44 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 11:13:44 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 11:13:44 volumio volumio[972]: info: Discovery: Getting this device information Oct 19 11:13:44 volumio volumio[972]: info: CoreCommandRouter::volumioGetState Oct 19 11:13:44 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 11:13:44 volumio volumio[972]: info: CoreCommandRouter::volumioGetState Oct 19 11:13:44 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 19 11:13:44 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 19 11:13:44 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 19 11:13:45 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 19 11:13:45 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 19 11:13:45 volumio volumio[972]: info: Received Get System Info Oct 19 11:13:45 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 11:13:45 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 11:13:45 volumio volumio[972]: info: Discovery: Getting this device information Oct 19 11:13:45 volumio volumio[972]: info: CoreCommandRouter::volumioGetState Oct 19 11:13:45 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 11:13:46 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 19 11:13:46 volumio volumio[972]: info: Received Get System Info Oct 19 11:13:46 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 11:13:46 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 11:13:46 volumio volumio[972]: info: Discovery: Getting this device information Oct 19 11:13:46 volumio volumio[972]: info: CoreCommandRouter::volumioGetState Oct 19 11:13:46 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 11:13:53 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 19 11:13:54 volumio volumio[972]: info: CoreCommandRouter::volumioNext Oct 19 11:13:54 volumio volumio[972]: info: CoreStateMachine::next Oct 19 11:13:54 volumio volumio[972]: info: Spotify next Oct 19 11:13:54 volumio volumio[972]: info: Sending Spotify command to local API: /player/next Oct 19 11:13:54 volumio go-librespot[21673]: time="2024-10-19T11:13:54+02:00" level=debug msg="skip next track" Oct 19 11:13:54 volumio go-librespot[21673]: time="2024-10-19T11:13:54+02:00" level=debug msg="loading track spotify:track:5yHLgSm28EjyWUljD6RcKk (paused: false, position: 0ms)" Oct 19 11:13:54 volumio go-librespot[21673]: time="2024-10-19T11:13:54+02:00" level=debug msg="renewing login5 access token" Oct 19 11:13:54 volumio go-librespot[21673]: time="2024-10-19T11:13:54+02:00" level=debug msg="authenticated as marccalafell-es" Oct 19 11:13:54 volumio go-librespot[21673]: time="2024-10-19T11:13:54+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 19 11:13:54 volumio go-librespot[21673]: time="2024-10-19T11:13:54+02:00" level=trace msg="emitting websocket event: will_play" Oct 19 11:13:54 volumio volumio[972]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:5yHLgSm28EjyWUljD6RcKk","play_origin":""}} Oct 19 11:13:54 volumio go-librespot[21673]: time="2024-10-19T11:13:54+02:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:5yHLgSm28EjyWUljD6RcKk" Oct 19 11:13:54 volumio go-librespot[21673]: time="2024-10-19T11:13:54+02:00" level=debug msg="requested aes key for file 63d5d88b45840518cb067b04ba3db2ddb95585e3, gid: 5yHLgSm28EjyWUljD6RcKk" Oct 19 11:13:55 volumio go-librespot[21673]: time="2024-10-19T11:13:55+02:00" level=debug msg="fetched first chunk of 14, total size is 6833718 bytes" Oct 19 11:13:55 volumio go-librespot[21673]: time="2024-10-19T11:13:55+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" Oct 19 11:13:55 volumio go-librespot[21673]: time="2024-10-19T11:13:55+02:00" level=info msg="loaded track \"Tinieblas\" (uri: spotify:track:5yHLgSm28EjyWUljD6RcKk, paused: false, position: 0ms, duration: 158450ms)" Oct 19 11:13:55 volumio go-librespot[21673]: time="2024-10-19T11:13:55+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 19 11:13:55 volumio go-librespot[21673]: time="2024-10-19T11:13:55+02:00" level=trace msg="emitting websocket event: metadata" Oct 19 11:13:55 volumio volumio[972]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:5yHLgSm28EjyWUljD6RcKk","name":"Tinieblas","artist_names":["Bihotza","Calero LDN"],"album_name":"Corona de espinas","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0211eaf8bd17856d0b5dea5619","position":0,"duration":158450,"release_date":"year:2024 month:7 day:11","track_number":4,"disc_number":1}} Oct 19 11:13:55 volumio go-librespot[21673]: time="2024-10-19T11:13:55+02:00" level=debug msg="fetched chunk 1/13, size: 524288" Oct 19 11:13:55 volumio go-librespot[21673]: time="2024-10-19T11:13:55+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 19 11:13:55 volumio go-librespot[21673]: time="2024-10-19T11:13:55+02:00" level=trace msg="emitting websocket event: playing" Oct 19 11:13:55 volumio volumio[972]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:5yHLgSm28EjyWUljD6RcKk","play_origin":""}} Oct 19 11:13:55 volumio volumio[972]: SPOTIFY: PUSH STATE SPOTIFY Oct 19 11:13:55 volumio volumio[972]: SPOTIFY: {"status":"play","service":"spop","title":"Tinieblas","artist":"Bihotza, Calero LDN","album":"Corona de espinas","albumart":"https://i.scdn.co/image/ab67616d00001e0211eaf8bd17856d0b5dea5619","uri":"spotify:track:5yHLgSm28EjyWUljD6RcKk","trackType":"spotify","seek":0,"duration":158,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":true,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 19 11:13:55 volumio volumio[972]: info: CoreCommandRouter::servicePushState Oct 19 11:13:55 volumio volumio[972]: info: CoreStateMachine::pushState Oct 19 11:13:55 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 11:13:55 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Oct 19 11:13:55 volumio volumio[972]: info: [LastFM] Current track has sufficient metadata: title (Tinieblas) and artist (Bihotza, Calero LDN) passed on explicitly Oct 19 11:13:55 volumio volumio[972]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Oct 19 11:13:55 volumio volumio[972]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Oct 19 11:13:55 volumio go-librespot[21673]: time="2024-10-19T11:13:55+02:00" level=debug msg="fetched chunk 3/13, size: 524288" Oct 19 11:13:55 volumio go-librespot[21673]: time="2024-10-19T11:13:55+02:00" level=debug msg="fetched chunk 2/13, size: 524288" Oct 19 11:13:55 volumio volumio[972]: SPOTIFY: PUSH STATE SPOTIFY Oct 19 11:13:55 volumio volumio[972]: SPOTIFY: {"status":"play","service":"spop","title":"Tinieblas","artist":"Bihotza, Calero LDN","album":"Corona de espinas","albumart":"https://i.scdn.co/image/ab67616d00001e0211eaf8bd17856d0b5dea5619","uri":"spotify:track:5yHLgSm28EjyWUljD6RcKk","trackType":"spotify","seek":0,"duration":158,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":true,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 19 11:13:55 volumio volumio[972]: info: CoreCommandRouter::servicePushState Oct 19 11:13:55 volumio volumio[972]: info: CoreStateMachine::pushState Oct 19 11:13:55 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Oct 19 11:13:55 volumio volumio[972]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Oct 19 11:13:57 volumio sudo[20107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 19 11:13:57 volumio sudo[20107]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:13:57 volumio sudo[20107]: pam_unix(sudo:session): session closed for user root Oct 19 11:13:57 volumio sudo[20110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 19 11:13:57 volumio sudo[20110]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:13:57 volumio sudo[20110]: pam_unix(sudo:session): session closed for user root Oct 19 11:13:57 volumio volumio[972]: verbose: New Socket.io Connection to 192.168.1.234 from 192.168.1.132 UA: Mozilla/5.0 (Linux; Android 12; M2102J20SG Build/SKQ1.211006.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Oct 19 11:13:58 volumio sudo[20113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 19 11:13:58 volumio sudo[20113]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:13:58 volumio sudo[20113]: pam_unix(sudo:session): session closed for user root Oct 19 11:13:58 volumio sudo[20116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 19 11:13:58 volumio sudo[20116]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:13:58 volumio sudo[20116]: pam_unix(sudo:session): session closed for user root Oct 19 11:13:58 volumio volumio[972]: verbose: New Socket.io Connection to 192.168.1.234 from 192.168.1.132 UA: Mozilla/5.0 (Linux; Android 12; M2102J20SG Build/SKQ1.211006.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Oct 19 11:13:58 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 19 11:13:58 volumio volumio[972]: info: CoreCommandRouter::volumioGetVisibleSources Oct 19 11:13:58 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 19 11:13:58 volumio volumio[972]: info: CoreCommandRouter::volumioGetState Oct 19 11:13:58 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 19 11:13:58 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 19 11:13:58 volumio volumio[972]: info: Received Get System Info Oct 19 11:13:58 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 11:13:58 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 11:13:58 volumio volumio[972]: info: Discovery: Getting this device information Oct 19 11:13:58 volumio volumio[972]: info: CoreCommandRouter::volumioGetState Oct 19 11:13:58 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 11:13:58 volumio volumio[972]: info: CoreCommandRouter::volumioGetState Oct 19 11:13:58 volumio volumio[972]: info: Listing playlists Oct 19 11:13:58 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 19 11:13:58 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 19 11:13:58 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 19 11:13:59 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 19 11:14:00 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 19 11:14:00 volumio volumio[972]: info: Received Get System Info Oct 19 11:14:00 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 11:14:00 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 11:14:00 volumio volumio[972]: info: Discovery: Getting this device information Oct 19 11:14:00 volumio volumio[972]: info: CoreCommandRouter::volumioGetState Oct 19 11:14:00 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 11:14:00 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 19 11:14:00 volumio volumio[972]: info: Received Get System Info Oct 19 11:14:00 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 11:14:00 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 11:14:00 volumio volumio[972]: info: Discovery: Getting this device information Oct 19 11:14:00 volumio volumio[972]: info: CoreCommandRouter::volumioGetState Oct 19 11:14:00 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 11:14:06 volumio go-librespot[21673]: time="2024-10-19T11:14:06+02:00" level=debug msg="fetched chunk 4/13, size: 524288" Oct 19 11:14:08 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 19 11:14:08 volumio volumio[972]: info: CoreCommandRouter::volumioGetQueue Oct 19 11:14:08 volumio volumio[972]: info: CoreStateMachine::getQueue Oct 19 11:14:08 volumio volumio[972]: info: CorePlayQueue::getQueue Oct 19 11:14:12 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 11:14:12 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 11:14:12 volumio volumio[972]: info: Discovery: Getting this device information Oct 19 11:14:12 volumio volumio[972]: info: CoreCommandRouter::volumioGetState Oct 19 11:14:12 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 11:14:12 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 19 11:14:12 volumio volumio[972]: info: CURURI: music-library Oct 19 11:14:12 volumio volumio[972]: info: Preload queue cleared Oct 19 11:14:14 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 19 11:14:14 volumio volumio[972]: info: CURURI: music-library/USB Oct 19 11:14:14 volumio volumio[972]: info: Preload queue cleared Oct 19 11:14:15 volumio volumio[972]: info: Preload queue cleared Oct 19 11:14:15 volumio volumio[972]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 19 11:14:15 volumio volumio[972]: info: CoreStateMachine::ClearQueue Oct 19 11:14:15 volumio volumio[972]: info: CoreStateMachine::stop Oct 19 11:14:15 volumio volumio[972]: info: CoreStateMachine::serviceStop Oct 19 11:14:15 volumio volumio[972]: info: CoreCommandRouter::serviceStop Oct 19 11:14:15 volumio volumio[972]: info: Spotify Stop Oct 19 11:14:15 volumio volumio[972]: SPOTIFY: SPOTIFY STOP Oct 19 11:14:15 volumio volumio[972]: SPOTIFY: {"status":"play","title":"Tinieblas","artist":"Bihotza, Calero LDN","album":"Corona de espinas","albumart":"https://i.scdn.co/image/ab67616d00001e0211eaf8bd17856d0b5dea5619","uri":"spotify:track:5yHLgSm28EjyWUljD6RcKk","trackType":"spotify","codec":"ogg","seek":0,"duration":158,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":true,"repeat":null,"repeatSingle":null,"consume":false,"volume":93,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Oct 19 11:14:15 volumio volumio[972]: info: Sending Spotify command to local API: /player/pause Oct 19 11:14:15 volumio volumio[972]: info: CorePlayQueue::clearPlayQueue Oct 19 11:14:15 volumio volumio[972]: info: CorePlayQueue::saveQueue Oct 19 11:14:15 volumio volumio[972]: info: CoreCommandRouter::volumioPushQueue Oct 19 11:14:15 volumio volumio[972]: info: CoreStateMachine::addQueueItems Oct 19 11:14:15 volumio volumio[972]: info: CorePlayQueue::addQueueItems Oct 19 11:14:15 volumio volumio[972]: info: Preload queue cleared Oct 19 11:14:15 volumio volumio[972]: info: Adding Item to queue: music-library/USB/DADES Oct 19 11:14:15 volumio volumio[972]: info: Exploding uri music-library/USB/DADES in service mpd Oct 19 11:14:18 volumio go-librespot[21673]: time="2024-10-19T11:14:18+02:00" level=debug msg="fetched chunk 5/13, size: 524288" Oct 19 11:14:27 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 19 11:14:27 volumio volumio[972]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 19 11:14:27 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 19 11:14:27 volumio volumio[972]: info: Received Get System Version Oct 19 11:14:27 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 19 11:14:27 volumio volumio[972]: info: Received Get System Info Oct 19 11:14:27 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 11:14:27 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 11:14:27 volumio volumio[972]: info: Discovery: Getting this device information Oct 19 11:14:27 volumio volumio[972]: info: CoreCommandRouter::volumioGetState Oct 19 11:14:27 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 11:14:27 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 19 11:14:27 volumio volumio[972]: info: CURURI: music-library/USB/DADES Oct 19 11:14:27 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 19 11:14:27 volumio volumio[972]: info: CURURI: music-library/USB/DADES Oct 19 11:14:27 volumio volumio[972]: info: ALBUMART /albumart?cacheid=728&web=AC-DC/High%20Voltage/large&path=%2Fmnt%2FUSB%2FDADES%2FAC%20DC%2F1975%20-%20High%20Voltage%2F01.%20Baby%2C%20Please%20Don't%20Go.flac&metadata=false Oct 19 11:14:27 volumio volumio[972]: info: URI /mnt/USB/DADES/AC DC/1975 - High Voltage/01. Baby, Please Don't Go.flac Oct 19 11:14:27 volumio volumio[972]: info: ALBUMART /albumart?cacheid=728&web=AC-DC/High%20Voltage/large&path=%2Fmnt%2FUSB%2FDADES%2FAC%20DC%2F1975%20-%20High%20Voltage%2F02.%20She's%20Got%20Balls.flac&metadata=false Oct 19 11:14:27 volumio volumio[972]: info: URI /mnt/USB/DADES/AC DC/1975 - High Voltage/02. She's Got Balls.flac Oct 19 11:14:27 volumio volumio[972]: info: ALBUMART /albumart?cacheid=728&web=AC-DC/High%20Voltage/large&path=%2Fmnt%2FUSB%2FDADES%2FAC%20DC%2F1975%20-%20High%20Voltage%2F03.%20Little%20Lover.flac&metadata=false Oct 19 11:14:27 volumio volumio[972]: info: URI /mnt/USB/DADES/AC DC/1975 - High Voltage/03. Little Lover.flac Oct 19 11:14:27 volumio volumio[972]: info: ALBUMART /albumart?cacheid=728&web=AC-DC/High%20Voltage/large&path=%2Fmnt%2FUSB%2FDADES%2FAC%20DC%2F1975%20-%20High%20Voltage%2F04.%20Stick%20Around.flac&metadata=false Oct 19 11:14:27 volumio volumio[972]: info: URI /mnt/USB/DADES/AC DC/1975 - High Voltage/04. Stick Around.flac Oct 19 11:14:27 volumio volumio[972]: info: ALBUMART /albumart?cacheid=728&web=AC-DC/High%20Voltage/large&path=%2Fmnt%2FUSB%2FDADES%2FAC%20DC%2F1975%20-%20High%20Voltage%2F05.%20Soul%20Stripper.flac&metadata=false Oct 19 11:14:27 volumio volumio[972]: info: URI /mnt/USB/DADES/AC DC/1975 - High Voltage/05. Soul Stripper.flac Oct 19 11:14:27 volumio volumio[972]: info: ALBUMART /albumart?cacheid=728&web=AC-DC/High%20Voltage/large&path=%2Fmnt%2FUSB%2FDADES%2FAC%20DC%2F1975%20-%20High%20Voltage%2F06.%20You%20Ain't%20Got%20A%20Hold%20On%20Me.flac&metadata=false Oct 19 11:14:27 volumio volumio[972]: info: URI /mnt/USB/DADES/AC DC/1975 - High Voltage/06. You Ain't Got A Hold On Me.flac Oct 19 11:14:27 volumio volumio[972]: info: ALBUMART /albumart?cacheid=728&web=AC-DC/High%20Voltage/large&path=%2Fmnt%2FUSB%2FDADES%2FAC%20DC%2F1975%20-%20High%20Voltage%2F07.%20Love%20Song.flac&metadata=false Oct 19 11:14:27 volumio volumio[972]: info: URI /mnt/USB/DADES/AC DC/1975 - High Voltage/07. Love Song.flac Oct 19 11:14:27 volumio volumio[972]: info: ALBUMART /albumart?cacheid=728&web=AC-DC/High%20Voltage/large&path=%2Fmnt%2FUSB%2FDADES%2FAC%20DC%2F1975%20-%20High%20Voltage%2F08.%20Show%20Business.flac&metadata=false Oct 19 11:14:27 volumio volumio[972]: info: URI /mnt/USB/DADES/AC DC/1975 - High Voltage/08. Show Business.flac Oct 19 11:14:27 volumio volumio[972]: info: ALBUMART /albumart?cacheid=728&web=AC-DC/High%20Voltage%20(Albert%20CDP%20746667%202)/large&path=%2Fmnt%2FUSB%2FDADES%2FAC%20DC%2F1975%20-%20High%20Voltage%2FAC-DC%20-%20High%20Voltage.cue&metadata=false Oct 19 11:14:27 volumio volumio[972]: info: URI /mnt/USB/DADES/AC DC/1975 - High Voltage/AC-DC - High Voltage.cue Oct 19 11:14:27 volumio volumio[972]: info: ALBUMART /albumart?cacheid=728&web=AC-DC/High%20Voltage%20(Albert%20CDP%20746667%202)/large&path=%2Fmnt%2FUSB%2FDADES%2FAC%20DC%2F1975%20-%20High%20Voltage%2FAC-DC%20-%20High%20Voltage.cue&metadata=false Oct 19 11:14:27 volumio volumio[972]: info: URI /mnt/USB/DADES/AC DC/1975 - High Voltage/AC-DC - High Voltage.cue Oct 19 11:14:27 volumio volumio[972]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 19 11:14:27 volumio volumio[972]: Error: Unable to resolve or reject the same promise twice Oct 19 11:14:27 volumio volumio[972]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 19 11:14:27 volumio volumio[972]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Oct 19 11:14:27 volumio volumio[972]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 19 11:14:27 volumio volumio[972]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 19 11:14:27 volumio volumio[972]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 19 11:14:27 volumio volumio[972]: at Socket.emit (events.js:315:20) Oct 19 11:14:27 volumio volumio[972]: at addChunk (internal/streams/readable.js:309:12) Oct 19 11:14:27 volumio volumio[972]: at readableAddChunk (internal/streams/readable.js:280:11) Oct 19 11:14:27 volumio volumio[972]: at Socket.Readable.push (internal/streams/readable.js:223:10) Oct 19 11:14:27 volumio volumio[972]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Oct 19 11:14:27 volumio volumio[972]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 19 11:14:28 volumio sudo[20129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-19 11:13 Oct 19 11:14:28 volumio sudo[20129]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"