-- Logs begin at Mon 2024-08-26 21:12:53 CEST, end at Mon 2024-08-26 21:37:27 CEST. -- Aug 26 21:36:42 volumio go-librespot[1645]: time="2024-08-26T21:36:42+02:00" level=debug msg="handling skip_next player command from 51c819875d0010c112d05cc2fec9e46981b3aa40" Aug 26 21:36:42 volumio go-librespot[1645]: time="2024-08-26T21:36:42+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:1v5Qz44gB35Expy7nZkV3h" Aug 26 21:36:42 volumio go-librespot[1645]: time="2024-08-26T21:36:42+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 26 21:36:42 volumio go-librespot[1645]: time="2024-08-26T21:36:42+02:00" level=trace msg="emitting websocket event: will_play" Aug 26 21:36:42 volumio volumio[902]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1v5Qz44gB35Expy7nZkV3h","play_origin":"collection-songs"}} Aug 26 21:36:42 volumio go-librespot[1645]: time="2024-08-26T21:36:42+02:00" level=debug msg="selected format OGG_VORBIS_320 (387fd4fa60104cc0f9efffa6a74e1a86d35092da)" uri="spotify:track:1v5Qz44gB35Expy7nZkV3h" Aug 26 21:36:42 volumio go-librespot[1645]: time="2024-08-26T21:36:42+02:00" level=debug msg="requested aes key for file 387fd4fa60104cc0f9efffa6a74e1a86d35092da, gid: 1v5Qz44gB35Expy7nZkV3h" Aug 26 21:36:43 volumio go-librespot[1645]: time="2024-08-26T21:36:43+02:00" level=debug msg="fetched first chunk of 17, total size is 8858091 bytes" uri="spotify:track:1v5Qz44gB35Expy7nZkV3h" Aug 26 21:36:43 volumio go-librespot[1645]: time="2024-08-26T21:36:43+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:1v5Qz44gB35Expy7nZkV3h" Aug 26 21:36:43 volumio go-librespot[1645]: time="2024-08-26T21:36:43+02:00" level=info msg="loaded track \"Bass & Drum Intro\" (paused: false, position: 0ms, duration: 209800ms, prefetched: false)" uri="spotify:track:1v5Qz44gB35Expy7nZkV3h" Aug 26 21:36:43 volumio go-librespot[1645]: time="2024-08-26T21:36:43+02:00" level=debug msg="fetched chunk 1/16, size: 524288" uri="spotify:track:1v5Qz44gB35Expy7nZkV3h" Aug 26 21:36:43 volumio go-librespot[1645]: time="2024-08-26T21:36:43+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 26 21:36:43 volumio go-librespot[1645]: time="2024-08-26T21:36:43+02:00" level=debug msg="fetched chunk 2/16, size: 524288" uri="spotify:track:1v5Qz44gB35Expy7nZkV3h" Aug 26 21:36:43 volumio go-librespot[1645]: time="2024-08-26T21:36:43+02:00" level=trace msg="scheduling prefetch in 180s" Aug 26 21:36:43 volumio go-librespot[1645]: time="2024-08-26T21:36:43+02:00" level=trace msg="emitting websocket event: metadata" Aug 26 21:36:43 volumio go-librespot[1645]: time="2024-08-26T21:36:43+02:00" level=debug msg="sending successful reply for dealer request" Aug 26 21:36:43 volumio volumio[902]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1v5Qz44gB35Expy7nZkV3h","name":"Bass \u0026 Drum Intro","artist_names":["Nils Lofgren"],"album_name":"Live","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0261e7ba3225665aa962d675fb","position":0,"duration":209800,"release_date":"year:2002","track_number":9,"disc_number":2}} Aug 26 21:36:43 volumio go-librespot[1645]: time="2024-08-26T21:36:43+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 26 21:36:43 volumio go-librespot[1645]: time="2024-08-26T21:36:43+02:00" level=trace msg="emitting websocket event: playing" Aug 26 21:36:43 volumio volumio[902]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:1v5Qz44gB35Expy7nZkV3h","play_origin":"collection-songs"}} Aug 26 21:36:43 volumio volumio[902]: SPOTIFY: PUSH STATE SPOTIFY Aug 26 21:36:43 volumio volumio[902]: SPOTIFY: {"status":"play","service":"spop","title":"Bass & Drum Intro","artist":"Nils Lofgren","album":"Live","albumart":"https://i.scdn.co/image/ab67616d00001e0261e7ba3225665aa962d675fb","uri":"spotify:track:1v5Qz44gB35Expy7nZkV3h","trackType":"spotify","seek":0,"duration":209,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Aug 26 21:36:43 volumio volumio[902]: info: CoreCommandRouter::servicePushState Aug 26 21:36:43 volumio volumio[902]: info: CoreStateMachine::pushState Aug 26 21:36:43 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 21:36:43 volumio volumio[902]: info: CoreCommandRouter::volumioPushState Aug 26 21:36:43 volumio volumio[902]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Aug 26 21:36:43 volumio go-librespot[1645]: time="2024-08-26T21:36:43+02:00" level=debug msg="fetched chunk 3/16, size: 524288" uri="spotify:track:1v5Qz44gB35Expy7nZkV3h" Aug 26 21:36:43 volumio volumio[902]: SPOTIFY: PUSH STATE SPOTIFY Aug 26 21:36:43 volumio volumio[902]: SPOTIFY: {"status":"play","service":"spop","title":"Bass & Drum Intro","artist":"Nils Lofgren","album":"Live","albumart":"https://i.scdn.co/image/ab67616d00001e0261e7ba3225665aa962d675fb","uri":"spotify:track:1v5Qz44gB35Expy7nZkV3h","trackType":"spotify","seek":1000,"duration":209,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Aug 26 21:36:43 volumio volumio[902]: info: CoreCommandRouter::servicePushState Aug 26 21:36:43 volumio volumio[902]: info: CoreStateMachine::pushState Aug 26 21:36:43 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 21:36:43 volumio volumio[902]: info: CoreCommandRouter::volumioPushState Aug 26 21:36:43 volumio volumio[902]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Aug 26 21:36:51 volumio go-librespot[1645]: time="2024-08-26T21:36:51+02:00" level=debug msg="fetched chunk 4/16, size: 524288" uri="spotify:track:1v5Qz44gB35Expy7nZkV3h" Aug 26 21:36:51 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 21:36:51 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 21:36:51 volumio volumio[902]: info: Discovery: Getting this device information Aug 26 21:36:51 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Aug 26 21:36:51 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 21:36:51 volumio volumio[902]: verbose: New Socket.io Connection to 192.168.1.143:3000 from 192.168.1.20 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Aug 26 21:36:51 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Aug 26 21:36:51 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 26 21:36:51 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 26 21:36:56 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 21:36:56 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 21:36:56 volumio volumio[902]: info: Discovery: Getting this device information Aug 26 21:36:56 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Aug 26 21:36:56 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 21:36:56 volumio sudo[3219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 21:36:56 volumio sudo[3219]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 21:36:56 volumio sudo[3219]: pam_unix(sudo:session): session closed for user root Aug 26 21:36:56 volumio sudo[3222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 21:36:56 volumio sudo[3222]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 21:36:56 volumio sudo[3222]: pam_unix(sudo:session): session closed for user root Aug 26 21:36:56 volumio volumio[902]: verbose: New Socket.io Connection to 192.168.1.143 from 192.168.1.20 UA: Mozilla/5.0 (Linux; Android 10; CLT-L29 Build/HUAWEICLT-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 26 21:36:58 volumio sudo[3227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 21:36:58 volumio sudo[3227]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 21:36:58 volumio sudo[3227]: pam_unix(sudo:session): session closed for user root Aug 26 21:36:58 volumio sudo[3230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 21:36:58 volumio sudo[3230]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 21:36:58 volumio sudo[3230]: pam_unix(sudo:session): session closed for user root Aug 26 21:36:58 volumio volumio[902]: verbose: New Socket.io Connection to 192.168.1.143 from 192.168.1.20 UA: Mozilla/5.0 (Linux; Android 10; CLT-L29 Build/HUAWEICLT-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 26 21:36:59 volumio volumio[902]: verbose: New Socket.io Connection to 192.168.1.143 from 192.168.1.20 UA: Mozilla/5.0 (Linux; Android 10; CLT-L29 Build/HUAWEICLT-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 21:36:59 volumio volumio[902]: info: Received Get System Info Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 21:36:59 volumio volumio[902]: info: Discovery: Getting this device information Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Aug 26 21:36:59 volumio volumio[902]: info: Listing playlists Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 21:36:59 volumio volumio[902]: info: Received Get System Info Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 21:36:59 volumio volumio[902]: info: Discovery: Getting this device information Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 21:36:59 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Aug 26 21:36:59 volumio volumio[902]: info: Listing playlists Aug 26 21:37:00 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 26 21:37:00 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 26 21:37:00 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 21:37:00 volumio volumio[902]: info: Received Get System Info Aug 26 21:37:00 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 21:37:00 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 21:37:00 volumio volumio[902]: info: Discovery: Getting this device information Aug 26 21:37:00 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Aug 26 21:37:00 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 21:37:01 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 26 21:37:02 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 21:37:02 volumio volumio[902]: info: Received Get System Info Aug 26 21:37:02 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 21:37:02 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 21:37:02 volumio volumio[902]: info: Discovery: Getting this device information Aug 26 21:37:02 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Aug 26 21:37:02 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 21:37:02 volumio volumio[902]: info: CoreCommandRouter::volumioGetQueue Aug 26 21:37:02 volumio volumio[902]: info: CoreStateMachine::getQueue Aug 26 21:37:02 volumio volumio[902]: info: CorePlayQueue::getQueue Aug 26 21:37:03 volumio go-librespot[1645]: time="2024-08-26T21:37:03+02:00" level=debug msg="fetched chunk 5/16, size: 524288" uri="spotify:track:1v5Qz44gB35Expy7nZkV3h" Aug 26 21:37:08 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 26 21:37:09 volumio sudo[3241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 21:37:09 volumio sudo[3241]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 21:37:09 volumio sudo[3241]: pam_unix(sudo:session): session closed for user root Aug 26 21:37:09 volumio sudo[3244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 21:37:09 volumio sudo[3244]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 21:37:09 volumio sudo[3244]: pam_unix(sudo:session): session closed for user root Aug 26 21:37:10 volumio volumio[902]: verbose: New Socket.io Connection to 192.168.1.143 from 192.168.1.20 UA: Mozilla/5.0 (Linux; Android 10; CLT-L29 Build/HUAWEICLT-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Aug 26 21:37:10 volumio sudo[3249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 21:37:10 volumio sudo[3249]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 21:37:10 volumio sudo[3249]: pam_unix(sudo:session): session closed for user root Aug 26 21:37:10 volumio sudo[3252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 21:37:10 volumio sudo[3252]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 21:37:10 volumio sudo[3252]: pam_unix(sudo:session): session closed for user root Aug 26 21:37:10 volumio volumio[902]: verbose: New Socket.io Connection to 192.168.1.143 from 192.168.1.20 UA: Mozilla/5.0 (Linux; Android 10; CLT-L29 Build/HUAWEICLT-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Aug 26 21:37:10 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 26 21:37:10 volumio volumio[902]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 21:37:10 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 21:37:10 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Aug 26 21:37:10 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 26 21:37:10 volumio volumio[902]: info: CoreCommandRouter::volumioGetQueue Aug 26 21:37:10 volumio volumio[902]: info: CoreStateMachine::getQueue Aug 26 21:37:10 volumio volumio[902]: info: CorePlayQueue::getQueue Aug 26 21:37:10 volumio volumio[902]: info: Listing playlists Aug 26 21:37:10 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 21:37:10 volumio volumio[902]: info: Received Get System Info Aug 26 21:37:10 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 21:37:10 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 21:37:10 volumio volumio[902]: info: Discovery: Getting this device information Aug 26 21:37:10 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Aug 26 21:37:10 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 21:37:10 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Aug 26 21:37:10 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 26 21:37:10 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 26 21:37:10 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 26 21:37:12 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 21:37:12 volumio volumio[902]: info: Received Get System Info Aug 26 21:37:12 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 21:37:12 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 21:37:12 volumio volumio[902]: info: Discovery: Getting this device information Aug 26 21:37:12 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Aug 26 21:37:12 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 21:37:12 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 26 21:37:13 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 21:37:13 volumio volumio[902]: info: Received Get System Info Aug 26 21:37:13 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 21:37:13 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 21:37:13 volumio volumio[902]: info: Discovery: Getting this device information Aug 26 21:37:13 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Aug 26 21:37:13 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 21:37:17 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 26 21:37:17 volumio volumio[902]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 26 21:37:17 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 26 21:37:17 volumio volumio[902]: info: Received Get System Version Aug 26 21:37:17 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 21:37:17 volumio volumio[902]: info: Received Get System Info Aug 26 21:37:17 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 21:37:17 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 21:37:17 volumio volumio[902]: info: Discovery: Getting this device information Aug 26 21:37:17 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Aug 26 21:37:17 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 21:37:18 volumio go-librespot[1645]: time="2024-08-26T21:37:18+02:00" level=debug msg="fetched chunk 6/16, size: 524288" uri="spotify:track:1v5Qz44gB35Expy7nZkV3h" Aug 26 21:37:20 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 26 21:37:21 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 21:37:21 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 21:37:21 volumio volumio[902]: info: Discovery: Getting this device information Aug 26 21:37:21 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Aug 26 21:37:21 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 21:37:21 volumio volumio[902]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 21:37:21 volumio volumio[902]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 21:37:21 volumio volumio[902]: info: Retrieving Cloud Streaming UI Aug 26 21:37:21 volumio volumio[902]: info: Getting Tidal Cloud Configuration Aug 26 21:37:21 volumio volumio[902]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 21:37:21 volumio volumio[902]: info: Getting Qobuz Cloud Configuration Aug 26 21:37:21 volumio volumio[902]: info: Asking plugin for UI Config Aug 26 21:37:21 volumio volumio[902]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 21:37:21 volumio volumio[902]: info: Getting Spotify Cloud Configuration Aug 26 21:37:21 volumio volumio[902]: info: Asking plugin for UI Config Aug 26 21:37:21 volumio volumio[902]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 21:37:21 volumio volumio[902]: info: Saving Spotify Acccount Aug 26 21:37:21 volumio volumio[902]: info: Got Tidal Cloud Configuration Aug 26 21:37:21 volumio volumio[902]: info: Got it Aug 26 21:37:21 volumio volumio[902]: info: Got it Aug 26 21:37:21 volumio volumio[902]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 26 21:37:21 volumio volumio[902]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 21:37:21 volumio volumio[902]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 26 21:37:21 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 26 21:37:21 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 26 21:37:21 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 26 21:37:21 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 26 21:37:21 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 21:37:21 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 21:37:21 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 21:37:21 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 21:37:21 volumio volumio[902]: info: CoreCommandRouter::volumioGetBrowseSources Aug 26 21:37:21 volumio volumio[902]: info: CoreCommandRouter::volumioGetBrowseSources Aug 26 21:37:21 volumio volumio[902]: info: CoreCommandRouter::volumioGetBrowseSources Aug 26 21:37:22 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 21:37:22 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 26 21:37:26 volumio volumio[902]: info: Disabling MyMusic plugin upnp Aug 26 21:37:26 volumio sudo[3263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Aug 26 21:37:26 volumio sudo[3263]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 21:37:26 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Aug 26 21:37:26 volumio volumio[902]: error: Upnp client error: Error: This socket has been ended by the other party Aug 26 21:37:26 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 21:37:27 volumio volumio[902]: info: Enabling MyMusic plugin upnp Aug 26 21:37:27 volumio volumio[902]: info: Enabling plugin upnp Aug 26 21:37:27 volumio volumio[902]: info: Loading plugin "upnp"... Aug 26 21:37:27 volumio volumio[902]: info: [1724701047899] Starting Upmpd Daemon Aug 26 21:37:27 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 26 21:37:27 volumio volumio[902]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 21:37:27 volumio volumio[902]: Error: listen EADDRINUSE: address already in use :::6599 Aug 26 21:37:27 volumio volumio[902]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Aug 26 21:37:27 volumio volumio[902]: at listenInCluster (net.js:1379:12) Aug 26 21:37:27 volumio volumio[902]: at Server.listen (net.js:1465:7) Aug 26 21:37:27 volumio volumio[902]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Aug 26 21:37:27 volumio volumio[902]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Aug 26 21:37:27 volumio volumio[902]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Aug 26 21:37:27 volumio volumio[902]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 26 21:37:27 volumio volumio[902]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Aug 26 21:37:27 volumio volumio[902]: code: 'EADDRINUSE', Aug 26 21:37:27 volumio volumio[902]: errno: -98, Aug 26 21:37:27 volumio volumio[902]: syscall: 'listen', Aug 26 21:37:27 volumio volumio[902]: address: '::', Aug 26 21:37:27 volumio volumio[902]: port: 6599 Aug 26 21:37:27 volumio volumio[902]: } Aug 26 21:37:27 volumio volumio[902]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 21:37:27 volumio sudo[3274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-26 21:36 Aug 26 21:37:27 volumio sudo[3274]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"