-- Logs begin at Wed 2024-11-27 22:57:52 UTC, end at Fri 2024-11-29 00:48:43 UTC. -- Nov 29 00:47:01 volumio hostapd[1112]: wlan0: STA 12:3c:bb:85:2c:b4 IEEE 802.11: disassociated Nov 29 00:47:01 volumio hostapd[1112]: wlan0: STA 12:3c:bb:85:2c:b4 IEEE 802.11: disassociated Nov 29 00:47:07 volumio hostapd[1112]: wlan0: STA 12:3c:bb:85:2c:b4 IEEE 802.11: associated Nov 29 00:47:07 volumio hostapd[1112]: wlan0: STA 12:3c:bb:85:2c:b4 RADIUS: starting accounting session 415B5422E8F0234A Nov 29 00:47:08 volumio dnsmasq-dhcp[1115]: DHCPDISCOVER(wlan0) 12:3c:bb:85:2c:b4 Nov 29 00:47:08 volumio dnsmasq-dhcp[1115]: DHCPOFFER(wlan0) 192.168.211.179 12:3c:bb:85:2c:b4 Nov 29 00:47:08 volumio dnsmasq-dhcp[1115]: DHCPREQUEST(wlan0) 192.168.211.179 12:3c:bb:85:2c:b4 Nov 29 00:47:08 volumio dnsmasq-dhcp[1115]: DHCPACK(wlan0) 192.168.211.179 12:3c:bb:85:2c:b4 Redmi-13C Nov 29 00:47:12 volumio volumio[1120]: info: CorePlayQueue::getTrack 7 Nov 29 00:47:12 volumio volumio[1120]: info: CorePlayQueue::getTrack 39 Nov 29 00:47:12 volumio volumio[1120]: info: Prefetching next song Nov 29 00:47:12 volumio volumio[1120]: info: [1732841232028] ControllerSpotify::prefetch Nov 29 00:47:12 volumio volumio[1120]: info: Sending Spotify command with payload to local API: /player/add_to_queue Nov 29 00:47:12 volumio go-librespot[1278]: time="2024-11-29T00:47:12Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 29 00:47:12 volumio go-librespot[1278]: time="2024-11-29T00:47:12Z" level=debug msg="prefetching next track" uri="spotify:track:7fGODUqa0gtkb72oBVCgCD" Nov 29 00:47:12 volumio go-librespot[1278]: time="2024-11-29T00:47:12Z" level=debug msg="selected format OGG_VORBIS_320 (ce84b5baaa9a9ee5e7d0e986d5ae1f771b0c63ab)" uri="spotify:track:7fGODUqa0gtkb72oBVCgCD" Nov 29 00:47:12 volumio go-librespot[1278]: time="2024-11-29T00:47:12Z" level=debug msg="requested aes key for file ce84b5baaa9a9ee5e7d0e986d5ae1f771b0c63ab, gid: 7fGODUqa0gtkb72oBVCgCD" Nov 29 00:47:12 volumio go-librespot[1278]: time="2024-11-29T00:47:12Z" level=debug msg="fetched first chunk of 19, total size is 9474740 bytes" uri="spotify:track:7fGODUqa0gtkb72oBVCgCD" Nov 29 00:47:12 volumio go-librespot[1278]: time="2024-11-29T00:47:12Z" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:7fGODUqa0gtkb72oBVCgCD" Nov 29 00:47:12 volumio go-librespot[1278]: time="2024-11-29T00:47:12Z" level=info msg="prefetched track \"Ella Me Levanto\" (duration: 209493ms)" uri="spotify:track:7fGODUqa0gtkb72oBVCgCD" Nov 29 00:47:12 volumio go-librespot[1278]: time="2024-11-29T00:47:12Z" level=debug msg="fetched chunk 2/18, size: 524288" uri="spotify:track:7fGODUqa0gtkb72oBVCgCD" Nov 29 00:47:12 volumio go-librespot[1278]: time="2024-11-29T00:47:12Z" level=debug msg="fetched chunk 1/18, size: 524288" uri="spotify:track:7fGODUqa0gtkb72oBVCgCD" Nov 29 00:47:12 volumio go-librespot[1278]: time="2024-11-29T00:47:12Z" level=debug msg="fetched chunk 3/18, size: 524288" uri="spotify:track:7fGODUqa0gtkb72oBVCgCD" Nov 29 00:47:15 volumio go-librespot[1278]: time="2024-11-29T00:47:15Z" level=trace msg="emitting websocket event: not_playing" Nov 29 00:47:15 volumio go-librespot[1278]: time="2024-11-29T00:47:15Z" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:7fGODUqa0gtkb72oBVCgCD" Nov 29 00:47:15 volumio volumio[1120]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:5bi0gh89wRuH2OgjdAKFsb","play_origin":"go-librespot"}} Nov 29 00:47:15 volumio volumio[1120]: error: Failed to decode event: not_playing Nov 29 00:47:15 volumio go-librespot[1278]: time="2024-11-29T00:47:15Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 29 00:47:15 volumio go-librespot[1278]: time="2024-11-29T00:47:15Z" level=trace msg="emitting websocket event: will_play" Nov 29 00:47:15 volumio volumio[1120]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7fGODUqa0gtkb72oBVCgCD","play_origin":"go-librespot"}} Nov 29 00:47:15 volumio go-librespot[1278]: time="2024-11-29T00:47:15Z" level=info msg="loaded track \"Ella Me Levanto\" (paused: false, position: 0ms, duration: 209493ms, prefetched: true)" uri="spotify:track:7fGODUqa0gtkb72oBVCgCD" Nov 29 00:47:15 volumio go-librespot[1278]: time="2024-11-29T00:47:15Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 29 00:47:15 volumio go-librespot[1278]: time="2024-11-29T00:47:15Z" level=trace msg="scheduling prefetch in 179s" Nov 29 00:47:15 volumio go-librespot[1278]: time="2024-11-29T00:47:15Z" level=trace msg="emitting websocket event: metadata" Nov 29 00:47:15 volumio volumio[1120]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7fGODUqa0gtkb72oBVCgCD","name":"Ella Me Levanto","artist_names":["Daddy Yankee"],"album_name":"El Cartel: The Big Boss","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0223a0d80da059e0b729f42714","position":0,"duration":209493,"release_date":"year:2007 month:6 day:5","track_number":6,"disc_number":1}} Nov 29 00:47:15 volumio go-librespot[1278]: time="2024-11-29T00:47:15Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 29 00:47:15 volumio go-librespot[1278]: time="2024-11-29T00:47:15Z" level=trace msg="emitting websocket event: playing" Nov 29 00:47:15 volumio volumio[1120]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:7fGODUqa0gtkb72oBVCgCD","play_origin":"go-librespot"}} Nov 29 00:47:15 volumio volumio[1120]: SPOTIFY: PUSH STATE SPOTIFY Nov 29 00:47:15 volumio volumio[1120]: SPOTIFY: {"status":"play","service":"spop","title":"Ella Me Levanto","artist":"Daddy Yankee","album":"El Cartel: The Big Boss","albumart":"https://i.scdn.co/image/ab67616d00001e0223a0d80da059e0b729f42714","uri":"spotify:track:7fGODUqa0gtkb72oBVCgCD","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} Nov 29 00:47:15 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Nov 29 00:47:15 volumio volumio[1120]: info: CoreStateMachine::startPlaybackTimer Nov 29 00:47:15 volumio volumio[1120]: info: CorePlayQueue::getTrack 39 Nov 29 00:47:15 volumio volumio[1120]: info: CorePlayQueue::getTrack 39 Nov 29 00:47:15 volumio volumio[1120]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Ella Me Levanto","artist":"Daddy Yankee","album":"El Cartel: The Big Boss","albumart":"https://i.scdn.co/image/ab67616d00001e0223a0d80da059e0b729f42714","uri":"spotify:track:7fGODUqa0gtkb72oBVCgCD","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} Nov 29 00:47:15 volumio volumio[1120]: verbose: CURRENT POSITION 39 Nov 29 00:47:15 volumio volumio[1120]: info: CoreStateMachine::syncState stateService play Nov 29 00:47:15 volumio volumio[1120]: info: CoreStateMachine::syncState currentStatus play Nov 29 00:47:15 volumio volumio[1120]: info: Received an update from plugin. extracting info from payload Nov 29 00:47:15 volumio volumio[1120]: info: CoreStateMachine::pushState Nov 29 00:47:15 volumio volumio[1120]: info: CorePlayQueue::getTrack 39 Nov 29 00:47:15 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 00:47:15 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Nov 29 00:47:15 volumio volumio[1120]: info: CoreStateMachine::pushState Nov 29 00:47:15 volumio volumio[1120]: info: CorePlayQueue::getTrack 39 Nov 29 00:47:15 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Nov 29 00:47:15 volumio volumio[1120]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Nov 29 00:47:15 volumio volumio[1120]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Nov 29 00:47:16 volumio volumio[1120]: SPOTIFY: PUSH STATE SPOTIFY Nov 29 00:47:16 volumio volumio[1120]: SPOTIFY: {"status":"play","service":"spop","title":"Ella Me Levanto","artist":"Daddy Yankee","album":"El Cartel: The Big Boss","albumart":"https://i.scdn.co/image/ab67616d00001e0223a0d80da059e0b729f42714","uri":"spotify:track:7fGODUqa0gtkb72oBVCgCD","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} Nov 29 00:47:16 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Nov 29 00:47:16 volumio volumio[1120]: info: CorePlayQueue::getTrack 39 Nov 29 00:47:16 volumio volumio[1120]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Ella Me Levanto","artist":"Daddy Yankee","album":"El Cartel: The Big Boss","albumart":"https://i.scdn.co/image/ab67616d00001e0223a0d80da059e0b729f42714","uri":"spotify:track:7fGODUqa0gtkb72oBVCgCD","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} Nov 29 00:47:16 volumio volumio[1120]: verbose: CURRENT POSITION 39 Nov 29 00:47:16 volumio volumio[1120]: info: CoreStateMachine::syncState stateService play Nov 29 00:47:16 volumio volumio[1120]: info: CoreStateMachine::syncState currentStatus play Nov 29 00:47:16 volumio volumio[1120]: info: Received an update from plugin. extracting info from payload Nov 29 00:47:16 volumio volumio[1120]: info: CoreStateMachine::pushState Nov 29 00:47:16 volumio volumio[1120]: info: CorePlayQueue::getTrack 39 Nov 29 00:47:16 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 00:47:16 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Nov 29 00:47:16 volumio volumio[1120]: info: CoreStateMachine::pushState Nov 29 00:47:16 volumio volumio[1120]: info: CorePlayQueue::getTrack 39 Nov 29 00:47:16 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Nov 29 00:47:16 volumio volumio[1120]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Nov 29 00:47:16 volumio volumio[1120]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Nov 29 00:47:22 volumio sudo[31600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 29 00:47:22 volumio sudo[31600]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 00:47:22 volumio sudo[31600]: pam_unix(sudo:session): session closed for user root Nov 29 00:47:22 volumio sudo[31603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 29 00:47:22 volumio sudo[31603]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 00:47:22 volumio sudo[31603]: pam_unix(sudo:session): session closed for user root Nov 29 00:47:22 volumio volumio[1120]: verbose: New Socket.io Connection to 192.168.1.40 from 192.168.211.179 UA: Mozilla/5.0 (Linux; Android 13; 23108RN04Y Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.107 Mobile Safari/537.36 Total Clients: 4 Nov 29 00:47:23 volumio sudo[31606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 29 00:47:23 volumio sudo[31606]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 00:47:23 volumio sudo[31606]: pam_unix(sudo:session): session closed for user root Nov 29 00:47:23 volumio sudo[31608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 29 00:47:23 volumio sudo[31608]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 00:47:23 volumio sudo[31608]: pam_unix(sudo:session): session closed for user root Nov 29 00:47:23 volumio volumio[1120]: verbose: New Socket.io Connection to 192.168.1.40 from 192.168.211.179 UA: Mozilla/5.0 (Linux; Android 13; 23108RN04Y Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.107 Mobile Safari/537.36 Total Clients: 5 Nov 29 00:47:23 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Nov 29 00:47:23 volumio volumio[1120]: info: CorePlayQueue::getTrack 39 Nov 29 00:47:23 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 29 00:47:23 volumio volumio[1120]: info: Listing playlists Nov 29 00:47:23 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 29 00:47:23 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 29 00:47:23 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 29 00:47:23 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 29 00:47:26 volumio go-librespot[1278]: time="2024-11-29T00:47:26Z" level=debug msg="fetched chunk 4/18, size: 524288" uri="spotify:track:7fGODUqa0gtkb72oBVCgCD" Nov 29 00:47:29 volumio volumio[1120]: info: CoreCommandRouter::volumioPause Nov 29 00:47:29 volumio volumio[1120]: info: CoreStateMachine::pause Nov 29 00:47:29 volumio volumio[1120]: info: CoreStateMachine::stPlaybackTimer Nov 29 00:47:29 volumio volumio[1120]: info: CoreStateMachine::servicePause Nov 29 00:47:29 volumio volumio[1120]: info: CorePlayQueue::getTrack 39 Nov 29 00:47:29 volumio volumio[1120]: info: CoreCommandRouter::servicePause Nov 29 00:47:29 volumio volumio[1120]: info: Spotify Received pause Nov 29 00:47:29 volumio volumio[1120]: SPOTIFY: SPOTIFY PAUSE Nov 29 00:47:29 volumio volumio[1120]: SPOTIFY: {"status":"play","position":39,"title":"Ella Me Levanto","artist":"Daddy Yankee","album":"El Cartel: The Big Boss","albumart":"https://i.scdn.co/image/ab67616d0000b27323a0d80da059e0b729f42714","uri":"spotify:track:7fGODUqa0gtkb72oBVCgCD","trackType":"spotify","seek":1000,"duration":209,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":true,"repeat":true,"repeatSingle":false,"consume":false,"volume":40,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Nov 29 00:47:29 volumio volumio[1120]: info: Sending Spotify command to local API: /player/pause Nov 29 00:47:29 volumio go-librespot[1278]: time="2024-11-29T00:47:29Z" level=debug msg="pause track at 13576ms" Nov 29 00:47:29 volumio go-librespot[1278]: time="2024-11-29T00:47:29Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 29 00:47:29 volumio go-librespot[1278]: time="2024-11-29T00:47:29Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 29 00:47:29 volumio go-librespot[1278]: time="2024-11-29T00:47:29Z" level=trace msg="emitting websocket event: paused" Nov 29 00:47:29 volumio volumio[1120]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7fGODUqa0gtkb72oBVCgCD","play_origin":"go-librespot"}} Nov 29 00:47:29 volumio volumio[1120]: SPOTIFY: PUSH STATE SPOTIFY Nov 29 00:47:29 volumio volumio[1120]: SPOTIFY: {"status":"pause","service":"spop","title":"Ella Me Levanto","artist":"Daddy Yankee","album":"El Cartel: The Big Boss","albumart":"https://i.scdn.co/image/ab67616d00001e0223a0d80da059e0b729f42714","uri":"spotify:track:7fGODUqa0gtkb72oBVCgCD","trackType":"spotify","seek":14000,"duration":209,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 29 00:47:29 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Nov 29 00:47:29 volumio volumio[1120]: info: CorePlayQueue::getTrack 39 Nov 29 00:47:29 volumio volumio[1120]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Ella Me Levanto","artist":"Daddy Yankee","album":"El Cartel: The Big Boss","albumart":"https://i.scdn.co/image/ab67616d00001e0223a0d80da059e0b729f42714","uri":"spotify:track:7fGODUqa0gtkb72oBVCgCD","trackType":"spotify","seek":14000,"duration":209,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 29 00:47:29 volumio volumio[1120]: verbose: CURRENT POSITION 39 Nov 29 00:47:29 volumio volumio[1120]: info: CoreStateMachine::syncState stateService pause Nov 29 00:47:29 volumio volumio[1120]: info: CoreStateMachine::syncState currentStatus pause Nov 29 00:47:29 volumio volumio[1120]: info: CoreStateMachine::pushState Nov 29 00:47:29 volumio volumio[1120]: info: CorePlayQueue::getTrack 39 Nov 29 00:47:29 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 00:47:29 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Nov 29 00:47:29 volumio volumio[1120]: info: CoreStateMachine::stPlaybackTimer Nov 29 00:47:29 volumio volumio[1120]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Nov 29 00:47:41 volumio sudo[31618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 29 00:47:41 volumio sudo[31618]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 00:47:41 volumio sudo[31618]: pam_unix(sudo:session): session closed for user root Nov 29 00:47:41 volumio sudo[31620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 29 00:47:41 volumio sudo[31620]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 00:47:41 volumio sudo[31620]: pam_unix(sudo:session): session closed for user root Nov 29 00:47:41 volumio volumio[1120]: verbose: New Socket.io Connection to 192.168.1.40 from 192.168.211.179 UA: Mozilla/5.0 (Linux; Android 13; 23108RN04Y Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.107 Mobile Safari/537.36 Total Clients: 5 Nov 29 00:47:41 volumio volumio[1120]: info: CoreCommandRouter::volumioGetState Nov 29 00:47:41 volumio volumio[1120]: info: CorePlayQueue::getTrack 39 Nov 29 00:47:41 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 29 00:47:41 volumio volumio[1120]: info: Listing playlists Nov 29 00:47:41 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 29 00:47:41 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 29 00:47:41 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 29 00:47:41 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 29 00:47:51 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: spop , getTrack Nov 29 00:47:51 volumio volumio[1120]: info: New access token = BQA7P0PjftFoQMwp7zb9DxO7zGMXAUon6kJSXpkjPST-eQS91R2UH3YjRvZVIDkr2lc0W0dQ79xSxdxjzrivj9Pg1aq17ro0vNi8CUbkR5w1YsoGnx2Q-v0uTb92-oSy3C-S5CjdhaOu8Ogr-lOf1CewXbp2e2gCTgMFkF8DpteyIv7utLLGkwzW9jP-b2vVZRrRxBZTKangD4wu8jNj3_l3_i3hyKLqYCPK27Boc6tkHw6VhYjr_r7fQck3IU-edhB3dntgKOhYzMSQbzo Nov 29 00:47:51 volumio volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7fGODUqa0gtkb72oBVCgCD","service":"spop","name":"Ella Me Levanto","artist":"Daddy Yankee","album":"El Cartel: The Big Boss","type":"song","duration":209,"albumart":"https://i.scdn.co/image/ab67616d0000b27323a0d80da059e0b729f42714","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 29 00:47:51 volumio volumio[1120]: info: Saving local item KATE Nov 29 00:47:51 volumio volumio[1120]: info: Pushing Favourites {"uri":"spotify:track:7fGODUqa0gtkb72oBVCgCD","favourite":false} Nov 29 00:47:51 volumio volumio[1120]: info: Listing playlists Nov 29 00:47:55 volumio volumio[1120]: info: CoreCommandRouter::volumioPlay Nov 29 00:47:55 volumio volumio[1120]: verbose: UNSET VOLATILE Nov 29 00:47:55 volumio volumio[1120]: info: CoreStateMachine::play index undefined Nov 29 00:47:55 volumio volumio[1120]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 00:47:55 volumio volumio[1120]: info: CorePlayQueue::getTrack 39 Nov 29 00:47:55 volumio volumio[1120]: info: CoreStateMachine::startPlaybackTimer Nov 29 00:47:55 volumio volumio[1120]: info: CorePlayQueue::getTrack 39 Nov 29 00:47:55 volumio volumio[1120]: info: Spotify Resume Nov 29 00:47:55 volumio volumio[1120]: info: Sending Spotify command to local API: /player/resume Nov 29 00:47:55 volumio go-librespot[1278]: time="2024-11-29T00:47:55Z" level=trace msg="seek to 13576ms (diff: 76ms, samples: 598701, bytes: 629838)" uri="spotify:track:7fGODUqa0gtkb72oBVCgCD" Nov 29 00:47:55 volumio go-librespot[1278]: time="2024-11-29T00:47:55Z" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 10068 us, period size = 444 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 49 frames" Nov 29 00:47:55 volumio go-librespot[1278]: time="2024-11-29T00:47:55Z" level=debug msg="resume track at 13500ms" Nov 29 00:47:55 volumio go-librespot[1278]: time="2024-11-29T00:47:55Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 29 00:47:55 volumio go-librespot[1278]: time="2024-11-29T00:47:55Z" level=trace msg="scheduling prefetch in 166s" Nov 29 00:47:55 volumio go-librespot[1278]: time="2024-11-29T00:47:55Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 29 00:47:55 volumio go-librespot[1278]: time="2024-11-29T00:47:55Z" level=trace msg="emitting websocket event: playing" Nov 29 00:47:55 volumio volumio[1120]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:7fGODUqa0gtkb72oBVCgCD","play_origin":"go-librespot"}} Nov 29 00:47:55 volumio volumio[1120]: SPOTIFY: PUSH STATE SPOTIFY Nov 29 00:47:55 volumio volumio[1120]: SPOTIFY: {"status":"play","service":"spop","title":"Ella Me Levanto","artist":"Daddy Yankee","album":"El Cartel: The Big Boss","albumart":"https://i.scdn.co/image/ab67616d00001e0223a0d80da059e0b729f42714","uri":"spotify:track:7fGODUqa0gtkb72oBVCgCD","trackType":"spotify","seek":14000,"duration":209,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 29 00:47:55 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Nov 29 00:47:55 volumio volumio[1120]: info: CorePlayQueue::getTrack 39 Nov 29 00:47:55 volumio volumio[1120]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Ella Me Levanto","artist":"Daddy Yankee","album":"El Cartel: The Big Boss","albumart":"https://i.scdn.co/image/ab67616d00001e0223a0d80da059e0b729f42714","uri":"spotify:track:7fGODUqa0gtkb72oBVCgCD","trackType":"spotify","seek":14000,"duration":209,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 29 00:47:55 volumio volumio[1120]: verbose: CURRENT POSITION 39 Nov 29 00:47:55 volumio volumio[1120]: info: CoreStateMachine::syncState stateService play Nov 29 00:47:55 volumio volumio[1120]: info: CoreStateMachine::syncState currentStatus pause Nov 29 00:47:55 volumio volumio[1120]: info: CoreStateMachine::pushState Nov 29 00:47:55 volumio volumio[1120]: info: CorePlayQueue::getTrack 39 Nov 29 00:47:55 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 00:47:55 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Nov 29 00:47:55 volumio volumio[1120]: info: CoreStateMachine::pushState Nov 29 00:47:55 volumio volumio[1120]: info: CorePlayQueue::getTrack 39 Nov 29 00:47:55 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Nov 29 00:47:55 volumio volumio[1120]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Nov 29 00:47:55 volumio volumio[1120]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Nov 29 00:47:55 volumio volumio[1120]: SPOTIFY: PUSH STATE SPOTIFY Nov 29 00:47:55 volumio volumio[1120]: SPOTIFY: {"status":"play","service":"spop","title":"Ella Me Levanto","artist":"Daddy Yankee","album":"El Cartel: The Big Boss","albumart":"https://i.scdn.co/image/ab67616d00001e0223a0d80da059e0b729f42714","uri":"spotify:track:7fGODUqa0gtkb72oBVCgCD","trackType":"spotify","seek":14000,"duration":209,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 29 00:47:55 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Nov 29 00:47:55 volumio volumio[1120]: info: CorePlayQueue::getTrack 39 Nov 29 00:47:55 volumio volumio[1120]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Ella Me Levanto","artist":"Daddy Yankee","album":"El Cartel: The Big Boss","albumart":"https://i.scdn.co/image/ab67616d00001e0223a0d80da059e0b729f42714","uri":"spotify:track:7fGODUqa0gtkb72oBVCgCD","trackType":"spotify","seek":14000,"duration":209,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 29 00:47:55 volumio volumio[1120]: verbose: CURRENT POSITION 39 Nov 29 00:47:55 volumio volumio[1120]: info: CoreStateMachine::syncState stateService play Nov 29 00:47:55 volumio volumio[1120]: info: CoreStateMachine::syncState currentStatus play Nov 29 00:47:55 volumio volumio[1120]: info: Received an update from plugin. extracting info from payload Nov 29 00:47:55 volumio volumio[1120]: info: CoreStateMachine::pushState Nov 29 00:47:55 volumio volumio[1120]: info: CorePlayQueue::getTrack 39 Nov 29 00:47:55 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 00:47:55 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Nov 29 00:47:55 volumio volumio[1120]: info: CoreStateMachine::pushState Nov 29 00:47:55 volumio volumio[1120]: info: CorePlayQueue::getTrack 39 Nov 29 00:47:55 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Nov 29 00:47:55 volumio volumio[1120]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Nov 29 00:47:55 volumio volumio[1120]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Nov 29 00:48:00 volumio volumio[1120]: info: CoreCommandRouter::volumioGetQueue Nov 29 00:48:00 volumio volumio[1120]: info: CoreStateMachine::getQueue Nov 29 00:48:00 volumio volumio[1120]: info: CorePlayQueue::getQueue Nov 29 00:48:04 volumio go-librespot[1278]: time="2024-11-29T00:48:04Z" level=debug msg="fetched chunk 5/18, size: 524288" uri="spotify:track:7fGODUqa0gtkb72oBVCgCD" Nov 29 00:48:08 volumio volumio[1120]: info: CoreCommandRouter::volumioPlay Nov 29 00:48:08 volumio volumio[1120]: verbose: UNSET VOLATILE Nov 29 00:48:08 volumio volumio[1120]: info: CoreStateMachine::play index 46 Nov 29 00:48:08 volumio volumio[1120]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 00:48:08 volumio volumio[1120]: info: CoreStateMachine::stop Nov 29 00:48:08 volumio volumio[1120]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 00:48:08 volumio volumio[1120]: verbose: UNSET VOLATILE Nov 29 00:48:08 volumio volumio[1120]: info: CoreStateMachine::stPlaybackTimer Nov 29 00:48:08 volumio volumio[1120]: info: CoreStateMachine::updateTrackBlock Nov 29 00:48:08 volumio volumio[1120]: info: CorePlayQueue::getTrackBlock Nov 29 00:48:08 volumio volumio[1120]: info: CoreStateMachine::pushState Nov 29 00:48:08 volumio volumio[1120]: info: CorePlayQueue::getTrack 39 Nov 29 00:48:08 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 00:48:08 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Nov 29 00:48:08 volumio volumio[1120]: info: CoreStateMachine::serviceStop Nov 29 00:48:08 volumio volumio[1120]: info: CorePlayQueue::getTrack 39 Nov 29 00:48:08 volumio volumio[1120]: info: CoreCommandRouter::serviceStop Nov 29 00:48:08 volumio volumio[1120]: info: Spotify Stop Nov 29 00:48:08 volumio volumio[1120]: SPOTIFY: SPOTIFY STOP Nov 29 00:48:08 volumio volumio[1120]: SPOTIFY: {"status":"play","position":39,"title":"Ella Me Levanto","artist":"Daddy Yankee","album":"El Cartel: The Big Boss","albumart":"https://i.scdn.co/image/ab67616d0000b27323a0d80da059e0b729f42714","uri":"spotify:track:7fGODUqa0gtkb72oBVCgCD","trackType":"spotify","seek":14000,"duration":209,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":true,"repeat":true,"repeatSingle":false,"consume":false,"volume":40,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Nov 29 00:48:08 volumio volumio[1120]: info: Sending Spotify command to local API: /player/pause Nov 29 00:48:08 volumio volumio[1120]: info: CoreStateMachine::play index undefined Nov 29 00:48:08 volumio volumio[1120]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 00:48:08 volumio volumio[1120]: info: CorePlayQueue::getTrack 46 Nov 29 00:48:08 volumio volumio[1120]: info: CoreStateMachine::startPlaybackTimer Nov 29 00:48:08 volumio volumio[1120]: info: CorePlayQueue::getTrack 46 Nov 29 00:48:08 volumio volumio[1120]: info: [1732841288513] ControllerSpotify::clearAddPlayTrack Nov 29 00:48:08 volumio volumio[1120]: info: Sending Spotify command with payload to local API: /player/play Nov 29 00:48:08 volumio volumio[1120]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Nov 29 00:48:08 volumio go-librespot[1278]: time="2024-11-29T00:48:08Z" level=debug msg="pause track at 26891ms" Nov 29 00:48:08 volumio go-librespot[1278]: time="2024-11-29T00:48:08Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 29 00:48:08 volumio go-librespot[1278]: time="2024-11-29T00:48:08Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 29 00:48:08 volumio go-librespot[1278]: time="2024-11-29T00:48:08Z" level=trace msg="emitting websocket event: paused" Nov 29 00:48:08 volumio volumio[1120]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7fGODUqa0gtkb72oBVCgCD","play_origin":"go-librespot"}} Nov 29 00:48:08 volumio volumio[1120]: SPOTIFY: PUSH STATE SPOTIFY Nov 29 00:48:08 volumio volumio[1120]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 29 00:48:08 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Nov 29 00:48:08 volumio volumio[1120]: info: CorePlayQueue::getTrack 46 Nov 29 00:48:08 volumio volumio[1120]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 29 00:48:08 volumio volumio[1120]: verbose: CURRENT POSITION 46 Nov 29 00:48:08 volumio volumio[1120]: info: CoreStateMachine::syncState stateService pause Nov 29 00:48:08 volumio volumio[1120]: info: CoreStateMachine::syncState currentStatus stop Nov 29 00:48:08 volumio volumio[1120]: info: CoreStateMachine::pushState Nov 29 00:48:08 volumio volumio[1120]: info: CorePlayQueue::getTrack 46 Nov 29 00:48:08 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 00:48:08 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Nov 29 00:48:08 volumio volumio[1120]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Nov 29 00:48:08 volumio go-librespot[1278]: time="2024-11-29T00:48:08Z" level=debug msg="resolved context of track" uri="spotify:track:62k8iliO7KTiYp7LWGPa5p" Nov 29 00:48:08 volumio go-librespot[1278]: time="2024-11-29T00:48:08Z" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:62k8iliO7KTiYp7LWGPa5p" Nov 29 00:48:08 volumio go-librespot[1278]: time="2024-11-29T00:48:08Z" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:62k8iliO7KTiYp7LWGPa5p" Nov 29 00:48:08 volumio go-librespot[1278]: time="2024-11-29T00:48:08Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 29 00:48:08 volumio go-librespot[1278]: time="2024-11-29T00:48:08Z" level=trace msg="emitting websocket event: will_play" Nov 29 00:48:08 volumio volumio[1120]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:62k8iliO7KTiYp7LWGPa5p","play_origin":"go-librespot"}} Nov 29 00:48:08 volumio go-librespot[1278]: time="2024-11-29T00:48:08Z" level=debug msg="selected format OGG_VORBIS_320 (d7d5e5bfaff32598e79f77ea3b2b4cd9c64c2017)" uri="spotify:track:62k8iliO7KTiYp7LWGPa5p" Nov 29 00:48:08 volumio go-librespot[1278]: time="2024-11-29T00:48:08Z" level=debug msg="requested aes key for file d7d5e5bfaff32598e79f77ea3b2b4cd9c64c2017, gid: 62k8iliO7KTiYp7LWGPa5p" Nov 29 00:48:09 volumio go-librespot[1278]: time="2024-11-29T00:48:09Z" level=debug msg="fetched first chunk of 19, total size is 9526095 bytes" uri="spotify:track:62k8iliO7KTiYp7LWGPa5p" Nov 29 00:48:09 volumio go-librespot[1278]: time="2024-11-29T00:48:09Z" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:62k8iliO7KTiYp7LWGPa5p" Nov 29 00:48:09 volumio go-librespot[1278]: time="2024-11-29T00:48:09Z" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 10068 us, period size = 444 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 49 frames" Nov 29 00:48:09 volumio go-librespot[1278]: time="2024-11-29T00:48:09Z" level=info msg="loaded track \"X'CLUSIVO - REMIX\" (paused: false, position: 0ms, duration: 208133ms, prefetched: false)" uri="spotify:track:62k8iliO7KTiYp7LWGPa5p" Nov 29 00:48:09 volumio go-librespot[1278]: time="2024-11-29T00:48:09Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 29 00:48:09 volumio go-librespot[1278]: time="2024-11-29T00:48:09Z" level=trace msg="scheduling prefetch in 178s" Nov 29 00:48:09 volumio go-librespot[1278]: time="2024-11-29T00:48:09Z" level=trace msg="emitting websocket event: metadata" Nov 29 00:48:09 volumio volumio[1120]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:62k8iliO7KTiYp7LWGPa5p","name":"X'CLUSIVO - REMIX","artist_names":["Gonzy","SAIKO","Arcángel"],"album_name":"X'CLUSIVO (REMIX)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e025327757614a832374e491778","position":0,"duration":208133,"release_date":"year:2024 month:2 day:29","track_number":1,"disc_number":1}} Nov 29 00:48:09 volumio go-librespot[1278]: time="2024-11-29T00:48:09Z" level=debug msg="fetched chunk 1/18, size: 524288" uri="spotify:track:62k8iliO7KTiYp7LWGPa5p" Nov 29 00:48:09 volumio go-librespot[1278]: time="2024-11-29T00:48:09Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 29 00:48:09 volumio go-librespot[1278]: time="2024-11-29T00:48:09Z" level=trace msg="emitting websocket event: playing" Nov 29 00:48:09 volumio volumio[1120]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:62k8iliO7KTiYp7LWGPa5p","play_origin":"go-librespot"}} Nov 29 00:48:09 volumio volumio[1120]: SPOTIFY: PUSH STATE SPOTIFY Nov 29 00:48:09 volumio volumio[1120]: SPOTIFY: {"status":"play","service":"spop","title":"X'CLUSIVO - REMIX","artist":"Gonzy, SAIKO, Arcángel","album":"X'CLUSIVO (REMIX)","albumart":"https://i.scdn.co/image/ab67616d00001e025327757614a832374e491778","uri":"spotify:track:62k8iliO7KTiYp7LWGPa5p","trackType":"spotify","seek":0,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 29 00:48:09 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Nov 29 00:48:09 volumio volumio[1120]: info: CorePlayQueue::getTrack 46 Nov 29 00:48:09 volumio volumio[1120]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"X'CLUSIVO - REMIX","artist":"Gonzy, SAIKO, Arcángel","album":"X'CLUSIVO (REMIX)","albumart":"https://i.scdn.co/image/ab67616d00001e025327757614a832374e491778","uri":"spotify:track:62k8iliO7KTiYp7LWGPa5p","trackType":"spotify","seek":0,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 29 00:48:09 volumio volumio[1120]: verbose: CURRENT POSITION 46 Nov 29 00:48:09 volumio volumio[1120]: info: CoreStateMachine::syncState stateService play Nov 29 00:48:09 volumio volumio[1120]: info: CoreStateMachine::syncState currentStatus stop Nov 29 00:48:09 volumio volumio[1120]: info: CoreStateMachine::pushState Nov 29 00:48:09 volumio volumio[1120]: info: CorePlayQueue::getTrack 46 Nov 29 00:48:09 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 00:48:09 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Nov 29 00:48:09 volumio volumio[1120]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Nov 29 00:48:09 volumio go-librespot[1278]: time="2024-11-29T00:48:09Z" level=debug msg="fetched chunk 2/18, size: 524288" uri="spotify:track:62k8iliO7KTiYp7LWGPa5p" Nov 29 00:48:09 volumio go-librespot[1278]: time="2024-11-29T00:48:09Z" level=debug msg="fetched chunk 3/18, size: 524288" uri="spotify:track:62k8iliO7KTiYp7LWGPa5p" Nov 29 00:48:09 volumio volumio[1120]: SPOTIFY: PUSH STATE SPOTIFY Nov 29 00:48:09 volumio volumio[1120]: SPOTIFY: {"status":"play","service":"spop","title":"X'CLUSIVO - REMIX","artist":"Gonzy, SAIKO, Arcángel","album":"X'CLUSIVO (REMIX)","albumart":"https://i.scdn.co/image/ab67616d00001e025327757614a832374e491778","uri":"spotify:track:62k8iliO7KTiYp7LWGPa5p","trackType":"spotify","seek":0,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 29 00:48:09 volumio volumio[1120]: info: CoreCommandRouter::servicePushState Nov 29 00:48:09 volumio volumio[1120]: info: CorePlayQueue::getTrack 46 Nov 29 00:48:09 volumio volumio[1120]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"X'CLUSIVO - REMIX","artist":"Gonzy, SAIKO, Arcángel","album":"X'CLUSIVO (REMIX)","albumart":"https://i.scdn.co/image/ab67616d00001e025327757614a832374e491778","uri":"spotify:track:62k8iliO7KTiYp7LWGPa5p","trackType":"spotify","seek":0,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 29 00:48:09 volumio volumio[1120]: verbose: CURRENT POSITION 46 Nov 29 00:48:09 volumio volumio[1120]: info: CoreStateMachine::syncState stateService play Nov 29 00:48:09 volumio volumio[1120]: info: CoreStateMachine::syncState currentStatus play Nov 29 00:48:09 volumio volumio[1120]: info: Received an update from plugin. extracting info from payload Nov 29 00:48:09 volumio volumio[1120]: info: CoreStateMachine::pushState Nov 29 00:48:09 volumio volumio[1120]: info: CorePlayQueue::getTrack 46 Nov 29 00:48:09 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Nov 29 00:48:09 volumio volumio[1120]: info: CoreStateMachine::pushState Nov 29 00:48:09 volumio volumio[1120]: info: CorePlayQueue::getTrack 46 Nov 29 00:48:09 volumio volumio[1120]: info: CoreCommandRouter::volumioPushState Nov 29 00:48:09 volumio volumio[1120]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Nov 29 00:48:09 volumio volumio[1120]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Nov 29 00:48:19 volumio go-librespot[1278]: time="2024-11-29T00:48:19Z" level=debug msg="fetched chunk 4/18, size: 524288" uri="spotify:track:62k8iliO7KTiYp7LWGPa5p" Nov 29 00:48:29 volumio go-librespot[1278]: time="2024-11-29T00:48:29Z" level=debug msg="fetched chunk 5/18, size: 524288" uri="spotify:track:62k8iliO7KTiYp7LWGPa5p" Nov 29 00:48:35 volumio volumio[1120]: info: CoreCommandRouter::volumioGetVisibleSources Nov 29 00:48:35 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 00:48:40 volumio go-librespot[1278]: time="2024-11-29T00:48:40Z" level=debug msg="fetched chunk 6/18, size: 524288" uri="spotify:track:62k8iliO7KTiYp7LWGPa5p" Nov 29 00:48:41 volumio volumio[1120]: Searching all installed plugins Nov 29 00:48:41 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 00:48:41 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: , search Nov 29 00:48:41 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , search Nov 29 00:48:41 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Nov 29 00:48:41 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Nov 29 00:48:41 volumio volumio[1120]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Nov 29 00:48:41 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Nov 29 00:48:41 volumio volumio[1120]: info: CoreCommandRouter::executeOnPlugin: spop , search Nov 29 00:48:42 volumio volumio[1120]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 00:48:42 volumio volumio[1120]: Error: read ECONNRESET Nov 29 00:48:42 volumio volumio[1120]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Nov 29 00:48:42 volumio volumio[1120]: errno: -104, Nov 29 00:48:42 volumio volumio[1120]: code: 'ECONNRESET', Nov 29 00:48:42 volumio volumio[1120]: syscall: 'read' Nov 29 00:48:42 volumio volumio[1120]: } Nov 29 00:48:42 volumio volumio[1120]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 00:48:43 volumio sudo[31649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-29 00:47 Nov 29 00:48:43 volumio sudo[31649]: 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="1f061e035f7cb2799b32d3520aa8e55cd07df6f9" VOLUMIO_FE_VERSION="9971a09df9c3edfdedf2ecf438999be8aba9db68" VOLUMIO_FE3_VERSION="3974def988c7b2392ddc85f9b8cb4692d5e8fda9" VOLUMIO_BE_VERSION="b3c53a818b9b3d0e5c13c9d346c4d613c4ca426f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 11 Jul 2022 04:03:33 PM CEST" VOLUMIO_VERSION="3.324" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5b2f59e6e90e57f326ca25c7ca839d52"