-- Logs begin at Thu 2019-02-14 19:12:00 JST, end at Sun 2025-04-13 17:08:45 JST. -- Apr 13 17:08:01 volumio ntpd[776]: receive: Unexpected origin timestamp 0xeba5eeea.51797764 does not match aorg 0000000000.00000000 from server@129.250.35.251 xmt 0xeba5ef61.54a2b2a4 Apr 13 17:08:01 volumio ntpd[776]: receive: Unexpected origin timestamp 0xeba5eeea.51845d47 does not match aorg 0000000000.00000000 from server@160.16.113.133 xmt 0xeba5ef61.54c8c4ce Apr 13 17:08:01 volumio ntpd[776]: receive: Unexpected origin timestamp 0xeba5eeea.518289c8 does not match aorg 0000000000.00000000 from server@108.160.132.224 xmt 0xeba5ef61.54b1f817 Apr 13 17:08:01 volumio ntpd[776]: receive: Unexpected origin timestamp 0xeba5eeea.518366d7 does not match aorg 0000000000.00000000 from server@45.76.221.157 xmt 0xeba5ef61.54b3ea50 Apr 13 17:08:01 volumio ntpd[776]: receive: Unexpected origin timestamp 0xeba5eeea.517d494c does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xeba5ef61.54eb8656 Apr 13 17:08:01 volumio ntpd[776]: receive: Unexpected origin timestamp 0xeba5eeea.51808218 does not match aorg 0000000000.00000000 from server@122.215.240.52 xmt 0xeba5ef61.53e78447 Apr 13 17:08:01 volumio ntpd[776]: receive: Unexpected origin timestamp 0xeba5eeea.51818d95 does not match aorg 0000000000.00000000 from server@45.77.20.103 xmt 0xeba5ef61.54c9f844 Apr 13 17:08:01 volumio ntpd[776]: receive: Unexpected origin timestamp 0xeba5eeea.518547f2 does not match aorg 0000000000.00000000 from server@18.180.64.47 xmt 0xeba5ef61.54c25004 Apr 13 17:08:01 volumio ntpd[776]: receive: Unexpected origin timestamp 0xeba5eeea.517f5b43 does not match aorg 0000000000.00000000 from server@23.81.40.154 xmt 0xeba5ef61.55614c30 Apr 13 17:08:04 volumio wpa_supplicant[871]: RRM: Ignoring radio measurement request: Not RRM network Apr 13 17:08:08 volumio volumio[955]: info: Received OAUTH Data Apr 13 17:08:08 volumio volumio[955]: info: Executing Spotify Oauth Login Apr 13 17:08:08 volumio volumio[955]: info: Saving Spotify Refresh Token Apr 13 17:08:08 volumio volumio[955]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Apr 13 17:08:08 volumio volumio[955]: SPOTIFY: BQBWtxnvghWTLR32PeR8zN4fFYYnEvgKoSSMVViW4soUH88Diyv-8PKK4Wr9rWVBITmDlHqSX2YagxZDxVHxvpeRfk_hAH4BkDdvRkk802PYv7HMOfXuhasI-6bUO87UFaW6IaYKJmnMhkDnUdnPX-9i2GU_MTomyIET6ZNGuQmLAkdUrIV1BUrfoXu-5ZYVcNbxJrWetKEZ5zHto78WqYhu0tIwX7lZkhop3yqRzS6TnGo9zJU2NdgG36al2l1dMJ_tYXKgFv5EJICh-Sddpm9LlgGzwq-lcCd-5utrTqBcNl4 Apr 13 17:08:08 volumio volumio[955]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Apr 13 17:08:08 volumio volumio[955]: info: New Spotify access token = BQBWtxnvghWTLR32PeR8zN4fFYYnEvgKoSSMVViW4soUH88Diyv-8PKK4Wr9rWVBITmDlHqSX2YagxZDxVHxvpeRfk_hAH4BkDdvRkk802PYv7HMOfXuhasI-6bUO87UFaW6IaYKJmnMhkDnUdnPX-9i2GU_MTomyIET6ZNGuQmLAkdUrIV1BUrfoXu-5ZYVcNbxJrWetKEZ5zHto78WqYhu0tIwX7lZkhop3yqRzS6TnGo9zJU2NdgG36al2l1dMJ_tYXKgFv5EJICh-Sddpm9LlgGzwq-lcCd-5utrTqBcNl4 Apr 13 17:08:08 volumio volumio[955]: info: Spotify credentials grant success - running version from March 24, 2019 Apr 13 17:08:08 volumio volumio[955]: SPOTIFY: User informations: {"country":"JP","display_name":"Hinata","email":"rixiang18@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31htwl3sr6wwoccwpehugkt6aany"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31htwl3sr6wwoccwpehugkt6aany","id":"31htwl3sr6wwoccwpehugkt6aany","images":[],"product":"premium","type":"user","uri":"spotify:user:31htwl3sr6wwoccwpehugkt6aany"} Apr 13 17:08:08 volumio volumio[955]: info: Creating Spotify config file Apr 13 17:08:08 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 17:08:08 volumio volumio[955]: info: Spotify config file written Apr 13 17:08:08 volumio sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 13 17:08:08 volumio sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:08:08 volumio systemd[1]: Stopping go-librespot Daemon... Apr 13 17:08:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Apr 13 17:08:08 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Apr 13 17:08:08 volumio systemd[1]: Stopped go-librespot Daemon. Apr 13 17:08:08 volumio volumio[955]: info: Connection to go-librespot Websocket closed Apr 13 17:08:08 volumio systemd[1]: Started go-librespot Daemon. Apr 13 17:08:08 volumio go-librespot[1308]: go-librespot daemon starting... Apr 13 17:08:08 volumio sudo[1306]: pam_unix(sudo:session): session closed for user root Apr 13 17:08:08 volumio sudo[1310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 13 17:08:08 volumio sudo[1310]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:08:08 volumio go-librespot[1308]: time="2025-04-13T17:08:08+09:00" level=info msg="running go-librespot 0.2.0" Apr 13 17:08:08 volumio go-librespot[1308]: time="2025-04-13T17:08:08+09:00" level=debug msg="app state loaded" Apr 13 17:08:08 volumio go-librespot[1308]: time="2025-04-13T17:08:08+09:00" level=debug msg="stored credentials not found" Apr 13 17:08:08 volumio sudo[1314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 13 17:08:08 volumio go-librespot[1308]: time="2025-04-13T17:08:08+09:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 13 17:08:08 volumio sudo[1314]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 17:08:08 volumio sudo[1310]: pam_unix(sudo:session): session closed for user root Apr 13 17:08:08 volumio sudo[1314]: pam_unix(sudo:session): session closed for user root Apr 13 17:08:08 volumio volumio[955]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Apr 13 17:08:08 volumio volumio[955]: SPOTIFY: BQCnqDqyfkL5Mf1SEdt71n4PplOaNxTvot8iXPuOBn4LfFxhszvMP4FuL5hDt1xuE4bPuiCXSn_sOzzGvHD5sKi6W86z_LrOD161b8XIi-3bKp4z0uOxLGbPrWdSPQSUOnBsMtDnzqpi9VSbhjWS7YuYY0UScTymiJoxt5xYqj2JLNJi4A6k2JFN0S7K6sCApwVGpjxZ-ttjUKcDGaCk3W9grgvjYHY7XJ2IlYRINDAvVlPzcQUurkuQ_DmL3_Hkf_SOg1qApeSx8yiZxO73zp46V4tC-zM62kpO0u-ePrzq4pQ Apr 13 17:08:08 volumio volumio[955]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Apr 13 17:08:08 volumio volumio[955]: info: New Spotify access token = BQCnqDqyfkL5Mf1SEdt71n4PplOaNxTvot8iXPuOBn4LfFxhszvMP4FuL5hDt1xuE4bPuiCXSn_sOzzGvHD5sKi6W86z_LrOD161b8XIi-3bKp4z0uOxLGbPrWdSPQSUOnBsMtDnzqpi9VSbhjWS7YuYY0UScTymiJoxt5xYqj2JLNJi4A6k2JFN0S7K6sCApwVGpjxZ-ttjUKcDGaCk3W9grgvjYHY7XJ2IlYRINDAvVlPzcQUurkuQ_DmL3_Hkf_SOg1qApeSx8yiZxO73zp46V4tC-zM62kpO0u-ePrzq4pQ Apr 13 17:08:08 volumio volumio[955]: info: Spotify credentials grant success - running version from March 24, 2019 Apr 13 17:08:08 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.1.148 from 192.168.1.143 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Apr 13 17:08:08 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 13 17:08:08 volumio volumio[955]: info: CoreCommandRouter::volumioGetVisibleSources Apr 13 17:08:08 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 17:08:08 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Apr 13 17:08:08 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:08 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 13 17:08:08 volumio volumio[955]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 13 17:08:08 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 13 17:08:08 volumio volumio[955]: info: Received Get System Info Apr 13 17:08:08 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 17:08:08 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 17:08:08 volumio volumio[955]: info: Discovery: Getting this device information Apr 13 17:08:08 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Apr 13 17:08:08 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:08 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 17:08:08 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Apr 13 17:08:08 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:08 volumio volumio[955]: info: Listing playlists Apr 13 17:08:08 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 13 17:08:08 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 13 17:08:08 volumio go-librespot[1308]: time="2025-04-13T17:08:08+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 13 17:08:08 volumio go-librespot[1308]: time="2025-04-13T17:08:08+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Apr 13 17:08:08 volumio go-librespot[1308]: time="2025-04-13T17:08:08+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Apr 13 17:08:08 volumio go-librespot[1308]: time="2025-04-13T17:08:08+09:00" level=info msg="zeroconf server listening on port 36279" Apr 13 17:08:09 volumio volumio[955]: SPOTIFY: User informations: {"country":"JP","display_name":"Hinata","email":"rixiang18@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31htwl3sr6wwoccwpehugkt6aany"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31htwl3sr6wwoccwpehugkt6aany","id":"31htwl3sr6wwoccwpehugkt6aany","images":[],"product":"premium","type":"user","uri":"spotify:user:31htwl3sr6wwoccwpehugkt6aany"} Apr 13 17:08:09 volumio volumio[955]: info: Spotify Successfully logged in Apr 13 17:08:09 volumio volumio[955]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 13 17:08:09 volumio volumio[955]: info: [1744531689013] CoreMusicLibrary::Adding element Spotify Apr 13 17:08:09 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 17:08:09 volumio volumio[955]: Cannot find translation for source YouTube Music Apr 13 17:08:09 volumio volumio[955]: Cannot find translation for source Randomizer Apr 13 17:08:09 volumio volumio[955]: Cannot find translation for source Spotify Apr 13 17:08:09 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 13 17:08:09 volumio go-librespot[1308]: time="2025-04-13T17:08:09+09:00" level=debug msg="obtained new client token: AADFvuh85HVx/aD/+1wPCV5w8a0F39IiWTtnX/JOnGr78CXzNfVo8SqxW4CK5iMfDnLiGRKZOm8JT6C4f27HukliLXOtZQXwmWWwYGAq+z2jgilH/3JE2L9UaXAB3aT/nmssUtcGdY0eTqVGJE2JQXoZl6JZQj4jPteo51SPYSOc0H1sWrtxl6CKtBZu/Q9fDAM0cIMsOUC6nAcVbfbp/uJ5igzEhE/rKN6+VlibwGIMzE0Lz9zpVwPOCg==" Apr 13 17:08:09 volumio go-librespot[1308]: time="2025-04-13T17:08:09+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Apr 13 17:08:09 volumio go-librespot[1308]: time="2025-04-13T17:08:09+09:00" level=debug msg="completed keyexchange" Apr 13 17:08:09 volumio go-librespot[1308]: time="2025-04-13T17:08:09+09:00" level=debug msg="completed challenge" Apr 13 17:08:09 volumio go-librespot[1308]: time="2025-04-13T17:08:09+09:00" level=info msg="authenticated AP as 31htwl3sr6wwoccwpehugkt6aany" Apr 13 17:08:09 volumio go-librespot[1308]: time="2025-04-13T17:08:09+09:00" level=info msg="authenticated Login5 as 31htwl3sr6wwoccwpehugkt6aany" Apr 13 17:08:09 volumio go-librespot[1308]: time="2025-04-13T17:08:09+09:00" level=debug msg="stored credentials for 31htwl3sr6wwoccwpehugkt6aany" Apr 13 17:08:09 volumio go-librespot[1308]: time="2025-04-13T17:08:09+09:00" level=debug msg="initializing zeroconf session, username: 31htwl3sr6wwoccwpehugkt6aany" Apr 13 17:08:09 volumio go-librespot[1308]: time="2025-04-13T17:08:09+09:00" level=debug msg="dealer connection opened" Apr 13 17:08:09 volumio go-librespot[1308]: time="2025-04-13T17:08:09+09:00" level=trace msg="starting accesspoint recv loop" Apr 13 17:08:09 volumio go-librespot[1308]: time="2025-04-13T17:08:09+09:00" level=trace msg="starting dealer recv loop" Apr 13 17:08:09 volumio go-librespot[1308]: time="2025-04-13T17:08:09+09:00" level=trace msg="received accesspoint ping" Apr 13 17:08:09 volumio go-librespot[1308]: time="2025-04-13T17:08:09+09:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 13 17:08:09 volumio go-librespot[1308]: time="2025-04-13T17:08:09+09:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 13 17:08:09 volumio go-librespot[1308]: time="2025-04-13T17:08:09+09:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Apr 13 17:08:09 volumio go-librespot[1308]: time="2025-04-13T17:08:09+09:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Apr 13 17:08:09 volumio go-librespot[1308]: time="2025-04-13T17:08:09+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Apr 13 17:08:09 volumio go-librespot[1308]: time="2025-04-13T17:08:09+09:00" level=debug msg="received connection id: MzZlMDc4OGEtNTRkMS00OTMwLWJjN2EtMDE0Yjk5MWIxOTQzK2RlYWxlcit0Y3A6Ly8wYWM5NDkzOS5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArOUNCRjVDODNDRkU4OTlERkM0NTJBMzEwRjUzMTdEQUY1QTZCOEYwQzNFOTFGMUI0MTE3QUMzRDQxMEY1QTk4Ng==" Apr 13 17:08:09 volumio go-librespot[1308]: time="2025-04-13T17:08:09+09:00" level=trace msg="received accesspoint pong ack" Apr 13 17:08:09 volumio go-librespot[1308]: time="2025-04-13T17:08:09+09:00" level=debug msg="put connect state because NEW_DEVICE" Apr 13 17:08:10 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 13 17:08:10 volumio wpa_supplicant[871]: RRM: Ignoring radio measurement request: Not RRM network Apr 13 17:08:10 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 13 17:08:10 volumio volumio[955]: info: Received Get System Info Apr 13 17:08:10 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 17:08:10 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 17:08:10 volumio volumio[955]: info: Discovery: Getting this device information Apr 13 17:08:10 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Apr 13 17:08:10 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:10 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 17:08:11 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 13 17:08:11 volumio volumio[955]: info: Received Get System Info Apr 13 17:08:11 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 17:08:11 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 17:08:11 volumio volumio[955]: info: Discovery: Getting this device information Apr 13 17:08:11 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Apr 13 17:08:11 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:11 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 17:08:11 volumio volumio[955]: info: Initializing connection to go-librespot Websocket Apr 13 17:08:11 volumio go-librespot[1308]: time="2025-04-13T17:08:11+09:00" level=debug msg="new websocket client" Apr 13 17:08:11 volumio volumio[955]: info: Connection to go-librespot Websocket established Apr 13 17:08:11 volumio volumio[955]: info: go-librespot daemon successfully initialized Apr 13 17:08:14 volumio volumio[955]: info: Getting Spotify volume Apr 13 17:08:14 volumio volumio[955]: info: Spotify volume: 100 Apr 13 17:08:14 volumio volumio[955]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Apr 13 17:08:14 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Apr 13 17:08:14 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:14 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:14 volumio volumio[955]: SPOTIFY: SPOTIFY VOLUME 100 Apr 13 17:08:14 volumio volumio[955]: SPOTIFY: VOLUMIO VOLUME 69 Apr 13 17:08:14 volumio volumio[955]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 13 17:08:14 volumio volumio[955]: info: Setting Spotify Volume from Volumio: 69 Apr 13 17:08:14 volumio volumio[955]: info: Initializing connection to go-librespot Websocket Apr 13 17:08:14 volumio go-librespot[1308]: time="2025-04-13T17:08:14+09:00" level=debug msg="new websocket client" Apr 13 17:08:14 volumio volumio[955]: info: Connection to go-librespot Websocket established Apr 13 17:08:16 volumio volumio[955]: SPOTIFY: SETTING SPOTIFY VOLUME 69 Apr 13 17:08:16 volumio volumio[955]: info: Sending Spotify command with payload to local API: /player/volume Apr 13 17:08:16 volumio go-librespot[1308]: time="2025-04-13T17:08:16+09:00" level=debug msg="update volume to 45219/65535" Apr 13 17:08:16 volumio wpa_supplicant[871]: RRM: Ignoring radio measurement request: Not RRM network Apr 13 17:08:17 volumio go-librespot[1308]: time="2025-04-13T17:08:17+09:00" level=debug msg="put connect state because VOLUME_CHANGED" Apr 13 17:08:17 volumio go-librespot[1308]: time="2025-04-13T17:08:17+09:00" level=trace msg="emitting websocket event: volume" Apr 13 17:08:17 volumio volumio[955]: SPOTIFY: received: {"type":"volume","data":{"value":69,"max":100}} Apr 13 17:08:17 volumio volumio[955]: SPOTIFY: RECEIVED SPOTIFY VOLUME 69 Apr 13 17:08:17 volumio volumio[955]: SPOTIFY: received: {"type":"volume","data":{"value":69,"max":100}} Apr 13 17:08:17 volumio volumio[955]: SPOTIFY: RECEIVED SPOTIFY VOLUME 69 Apr 13 17:08:17 volumio volumio[955]: info: Getting Spotify volume Apr 13 17:08:17 volumio volumio[955]: info: Spotify volume: 69 Apr 13 17:08:17 volumio volumio[955]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Apr 13 17:08:17 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Apr 13 17:08:17 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:17 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 13 17:08:21 volumio volumio[955]: info: CoreCommandRouter::volumioGetQueue Apr 13 17:08:21 volumio volumio[955]: info: CoreStateMachine::getQueue Apr 13 17:08:21 volumio volumio[955]: info: CorePlayQueue::getQueue Apr 13 17:08:22 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Apr 13 17:08:22 volumio volumio[955]: info: In handleBrowseUri, curUri=spotify Apr 13 17:08:22 volumio volumio[955]: info: Preload queue cleared Apr 13 17:08:22 volumio volumio[955]: info: Preload queue cleared Apr 13 17:08:22 volumio volumio[955]: info: Preload queue cleared Apr 13 17:08:22 volumio volumio[955]: info: Preload queue cleared Apr 13 17:08:22 volumio wpa_supplicant[871]: RRM: Ignoring radio measurement request: Not RRM network Apr 13 17:08:24 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Apr 13 17:08:24 volumio volumio[955]: info: In handleBrowseUri, curUri=spotify/playlists Apr 13 17:08:24 volumio volumio[955]: info: Preload queue cleared Apr 13 17:08:25 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Apr 13 17:08:25 volumio volumio[955]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:5jneHVZy0WbJFjqUqWfc8U Apr 13 17:08:26 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Apr 13 17:08:26 volumio volumio[955]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:5jneHVZy0WbJFjqUqWfc8U Apr 13 17:08:27 volumio volumio[955]: info: Preload queue cleared Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:5vNRhkKd0yEAg8suGBpjeY Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:7bjO3BMl5ps63mpPMfhPDC Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:2llI6h2T2O5KZfn1OhJQAA Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:4H89WUjBaQMIiqcnMSVX1j Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:2FRMwFXODegegRrfLAeVwx Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:2RBQ84niVRC6bBdhe7lc9F Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:5WCK18MbTKuOcmLsOXMaHd Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:4X3L6G6KDs0jBKvfTkmKmi Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:2gbVuqze7bpndIy1q9rFvv Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:6HznFPNdvbxgzm5jJyNlwp Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:38XY1ShCSiYwDaV51sFPT9 Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:3UDe37nc2yvttJXlNk6Itr Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:6kwLcF9pDovUbmGOtHo4Ml Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:38mUTswfszVFQ952r89AP8 Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:56xCsaFsih5swf7IZXPYzU Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:5ic62hKqeDhZsa9l6YQxzJ Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:0kCRrfk1WV7QMnlxqhpwV9 Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:3wM3VFL0nuEhwATHcJfPSm Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:4bdwEiJuBhWIkPKSVjyjiA Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:54ezuGQj5s0ot4J7ktWb2h Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:4oPCfEDVIKnPJ6UPkhJPCa Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:43CMcyGeImlZjy1oN0NVNG Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:74A5fPLR86U9XWYostkXwS Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:5CSVNJBsEPH3cM7S2iQgax Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:2UUwBDALzJuThY88Ro2lD2 Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:2DYupgAcdomatly7Lhw8zv Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:0WvCL9bRZZeH0BFf35jeZ3 Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:2YAzpDuHmAe5kROOlyDnmv Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:4cyk1ppFfQleEgXBvWX4Uc Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:17OQVshtovwVo2vk6tuzwn Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:6GoxKIpAcVnkR2zQyRa6EF Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:0Gp8MWnUzS5V2lvTr25c2A Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:389rEslJY9vh7TZemIA4y7 Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:2EmkTBTh964LQen4Vz0svg Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:7b4fxkOA4NDr4tDylrb10K Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:4EyKk14XxsBnMiSrsa8Ide Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:294o7PTrqj9VySUIHaJmXw Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:18p1ct9Vw2STwplkq1MlO9 Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:78W4mTLIh4qoLu92W4IQhO Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:0teqQ0PYqVDyyXJuPOyMhu Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:0tnhoHUrHfcLi9hPknUFZ4 Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:170XOP1T4MvY9TLTgPw07D Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:5BC6kr6etk2Y9J62AyI4i3 Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:2cKiHG16JBiqHdi7Llq0r7 Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:4IfrM44LofE9bSs6TDZS49 Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:7M0dIdZWN1FDcveRdoOzbZ Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:5pN2AyWwjzwRoI7gukEVNd Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:6H0PLsSYMzDOqhLgyOlzIj Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:172fMG26G2OoEzzg0wn26r Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:2Gmyw5Vg2X5YW2lM3OC7nD Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:4kPlQKwtPrnqLgrmmKFSlA Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:04TshWXkhV1qkqHzf31Hn6 Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:4PCQHOJFMV9BWq46UMVf2l Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:0H3RFIhXuuRoQyNKYdvSbn Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:3ECvJ97WjbbaTsCZ64pwgI Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:1Vqs0lXeJafYZz2sxnfB3V Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:59eluCMn0XbOWqeWQ91FTM Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:7tGDXCPLpAScEwmqab9YZd Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:0ve9Tpz8Yw0I4eXhWsy21o Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:7ncPjJBktLEOpstY5JOUMK Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:7rzoBRR4LbJZH5t7Q6qeTn Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:0FOVGBW0RpVRuDuUuN4ZVw Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:4cPwi7lcWxRQNEb4xC77fC Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:2vOAHp8PBBFYqvQUWSoITY Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:6wDntdm888mDo458RaYjGl Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:4HgsPAX3MmMgIT60hJ4W4U Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:5vsM9UppL2iTgOfenMB6Gz Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:4DmmljTCS4ViQRSLCiMnOt Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:1sIIlVrnPhrvmTrHtzM7tV Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:3YML5IHoqWbw9c9vJJvrvM Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:6DYV1GqwCTrvfPcjeFwjLt Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:00GDUNeJd97qjKp2yrx0OC Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:7sditHCsoFw3nHnjFv1BHV Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:3FniX6mJvTQWruKp5PDexD Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:33qTER0xTXBQPDMSYR1hGX Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:1nysdCpKAh6yif4JkmoiNy Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:4W3rP2KZkKEykdWvlWLgyc Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:5Snmyurx2SgwT1BvUswGwg Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:5XhxMq0dQNenKr6eDU79Ka Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:2fJbss5uUmmBqn7qFkmyWj Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:3CmVQtVx9KlzOuPhRJRShH Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:6T2b2vKSdZuvbUzK3WIGJM Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:4Ab9EruWYtWlphFen1uSxN Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:0ASw64KfzSUtChQDfzC906 Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:5v6X89ub41du3FhUI41sao Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:5V9Y0tatVvqGeZdyohOBPk Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:56Ajh1DR8TfxXe43k7fLRA Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:2ritsV4U3jq2LduJpovZ1A Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:75tQZI2X1VwNOPtOrOrdpl Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:08ZvFfHEBfTgH4MN9YT1BC Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:0cN6iBeCR7NgeBeTIKjLml Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:5OtpvLAq1uUQ6YmgxbI98H Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:7oYCBKvdjrqp5vDbhDBuac Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:3hRRYgBeunE3PTmnzATTS0 Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:3GLSPr5dyhrNBvDPpdoPDK Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:04e72JtsOIIlHUQ7CvWwvX Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:49DUifaclCSbxQa1upNXuO Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:44PT3ussI7vcQb6QYJhLF8 Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:6MJFNhbXbqXCbHP24yLltO Apr 13 17:08:27 volumio volumio[955]: info: Preloading song: spotify:track:1qjdRyMlZSpy9OW8rec9xA Apr 13 17:08:27 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 13 17:08:27 volumio volumio[955]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 13 17:08:27 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 13 17:08:27 volumio volumio[955]: info: Received Get System Version Apr 13 17:08:27 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 17:08:27 volumio volumio[955]: info: Received Get System Info Apr 13 17:08:27 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 17:08:27 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 17:08:27 volumio volumio[955]: info: Discovery: Getting this device information Apr 13 17:08:27 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Apr 13 17:08:27 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:27 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 17:08:27 volumio volumio[955]: info: Exploding uri spotify:track:5vNRhkKd0yEAg8suGBpjeY in service spop Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:5vNRhkKd0yEAg8suGBpjeY Apr 13 17:08:27 volumio volumio[955]: info: Exploding uri spotify:track:7bjO3BMl5ps63mpPMfhPDC in service spop Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:7bjO3BMl5ps63mpPMfhPDC Apr 13 17:08:27 volumio volumio[955]: info: Exploding uri spotify:track:2llI6h2T2O5KZfn1OhJQAA in service spop Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:2llI6h2T2O5KZfn1OhJQAA Apr 13 17:08:27 volumio volumio[955]: info: Exploding uri spotify:track:4H89WUjBaQMIiqcnMSVX1j in service spop Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:4H89WUjBaQMIiqcnMSVX1j Apr 13 17:08:27 volumio volumio[955]: info: Exploding uri spotify:track:2FRMwFXODegegRrfLAeVwx in service spop Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:2FRMwFXODegegRrfLAeVwx Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7bjO3BMl5ps63mpPMfhPDC","service":"spop","name":"ロストワンの号哭","artist":"Neru","album":"Kagaminext feat. 鏡音リン、鏡音レン ―10th ANNIVERSARY BEST―","type":"song","duration":214,"albumart":"https://i.scdn.co/image/ab67616d0000b273ec97087041ca8ca5e55ba67e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","service":"spop","name":"APT.","artist":"ROSÉ","album":"APT.","type":"song","duration":169,"albumart":"https://i.scdn.co/image/ab67616d0000b27336032cb4acd9df050bc2e197","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:27 volumio volumio[955]: info: Exploding uri spotify:track:2RBQ84niVRC6bBdhe7lc9F in service spop Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:2RBQ84niVRC6bBdhe7lc9F Apr 13 17:08:27 volumio volumio[955]: info: Exploding uri spotify:track:5WCK18MbTKuOcmLsOXMaHd in service spop Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:5WCK18MbTKuOcmLsOXMaHd Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4H89WUjBaQMIiqcnMSVX1j","service":"spop","name":"人マニア","artist":"Sasuke Haraguchi","album":"人マニア","type":"song","duration":127,"albumart":"https://i.scdn.co/image/ab67616d0000b2733f381b44dcb194d619a487dc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2llI6h2T2O5KZfn1OhJQAA","service":"spop","name":"ベノム","artist":"Kairikibear","album":"ベノム","type":"song","duration":203,"albumart":"https://i.scdn.co/image/ab67616d0000b273745ff8443f0db1cda32e5c97","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:27 volumio volumio[955]: info: Exploding uri spotify:track:4X3L6G6KDs0jBKvfTkmKmi in service spop Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:4X3L6G6KDs0jBKvfTkmKmi Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2FRMwFXODegegRrfLAeVwx","service":"spop","name":"エゴロック - long ver.","artist":"すりぃ","album":"エゴロック (long ver.)","type":"song","duration":169,"albumart":"https://i.scdn.co/image/ab67616d0000b2734ef98321d70e0f91f0839bd0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:27 volumio volumio[955]: info: Exploding uri spotify:track:2gbVuqze7bpndIy1q9rFvv in service spop Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:2gbVuqze7bpndIy1q9rFvv Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2RBQ84niVRC6bBdhe7lc9F","service":"spop","name":"砂の惑星 feat.初音ミク","artist":"hachi","album":"砂の惑星 feat.初音ミク","type":"song","duration":238,"albumart":"https://i.scdn.co/image/ab67616d0000b273a80eaa582c372c6115711c58","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5WCK18MbTKuOcmLsOXMaHd","service":"spop","name":"ロキ","artist":"Mikito P","album":"DAISAN WAVE","type":"song","duration":230,"albumart":"https://i.scdn.co/image/ab67616d0000b2736549e0ca9064d28052bade1a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:27 volumio volumio[955]: info: Exploding uri spotify:track:6HznFPNdvbxgzm5jJyNlwp in service spop Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:6HznFPNdvbxgzm5jJyNlwp Apr 13 17:08:27 volumio volumio[955]: info: Exploding uri spotify:track:38XY1ShCSiYwDaV51sFPT9 in service spop Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:38XY1ShCSiYwDaV51sFPT9 Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4X3L6G6KDs0jBKvfTkmKmi","service":"spop","name":"アイデンティティ","artist":"Kanaria","album":"アイデンティティ","type":"song","duration":155,"albumart":"https://i.scdn.co/image/ab67616d0000b273dc2f7a9aa2bf67d4bf044caa","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2gbVuqze7bpndIy1q9rFvv","service":"spop","name":"グッバイ宣言","artist":"Chinozo","album":"The Deluge","type":"song","duration":172,"albumart":"https://i.scdn.co/image/ab67616d0000b273a2729e8a4ee3553091d8cc04","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:27 volumio volumio[955]: info: Exploding uri spotify:track:3UDe37nc2yvttJXlNk6Itr in service spop Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:3UDe37nc2yvttJXlNk6Itr Apr 13 17:08:27 volumio volumio[955]: info: Exploding uri spotify:track:6kwLcF9pDovUbmGOtHo4Ml in service spop Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:6kwLcF9pDovUbmGOtHo4Ml Apr 13 17:08:27 volumio volumio[955]: info: Exploding uri spotify:track:38mUTswfszVFQ952r89AP8 in service spop Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:38mUTswfszVFQ952r89AP8 Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6HznFPNdvbxgzm5jJyNlwp","service":"spop","name":"オーバーライド","artist":"吉田夜世","album":"オーバーライド","type":"song","duration":138,"albumart":"https://i.scdn.co/image/ab67616d0000b27330310e1c6f71500cbbf0ad26","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:38XY1ShCSiYwDaV51sFPT9","service":"spop","name":"マーシャル・マキシマイザー","artist":"柊マグネタイト","album":"KAF+YOU KAFU COMPILATION ALBUM シンメトリー","type":"song","duration":163,"albumart":"https://i.scdn.co/image/ab67616d0000b273b19f79f9d15451ad63bb3f81","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3UDe37nc2yvttJXlNk6Itr","service":"spop","name":"テレキャスタービーボーイ - long ver.","artist":"すりぃ","album":"パンデミック","type":"song","duration":164,"albumart":"https://i.scdn.co/image/ab67616d0000b27319419da4f3cc1c93c6210ad4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:27 volumio volumio[955]: info: Exploding uri spotify:track:56xCsaFsih5swf7IZXPYzU in service spop Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:56xCsaFsih5swf7IZXPYzU Apr 13 17:08:27 volumio volumio[955]: info: Exploding uri spotify:track:5ic62hKqeDhZsa9l6YQxzJ in service spop Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:5ic62hKqeDhZsa9l6YQxzJ Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6kwLcF9pDovUbmGOtHo4Ml","service":"spop","name":"ドーナツホール","artist":"hachi","album":"ドーナツホール","type":"song","duration":205,"albumart":"https://i.scdn.co/image/ab67616d0000b2731754285e945b1b37625d6d09","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:27 volumio volumio[955]: info: Exploding uri spotify:track:0kCRrfk1WV7QMnlxqhpwV9 in service spop Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:0kCRrfk1WV7QMnlxqhpwV9 Apr 13 17:08:27 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:38mUTswfszVFQ952r89AP8","service":"spop","name":"千本桜","artist":"KuroUsa(WhiteFlame)","album":"千本桜","type":"song","duration":252,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d15ca856170b2e78e07b9c5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:28 volumio volumio[955]: info: Exploding uri spotify:track:3wM3VFL0nuEhwATHcJfPSm in service spop Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:3wM3VFL0nuEhwATHcJfPSm Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:56xCsaFsih5swf7IZXPYzU","service":"spop","name":"きゅうくらりん","artist":"いよわ","album":"きゅうくらりん","type":"song","duration":217,"albumart":"https://i.scdn.co/image/ab67616d0000b273e094c1bd11b4dde2cba4d8c7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:28 volumio volumio[955]: info: Preload queue cleared Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:4bdwEiJuBhWIkPKSVjyjiA Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:54ezuGQj5s0ot4J7ktWb2h Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:4oPCfEDVIKnPJ6UPkhJPCa Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:43CMcyGeImlZjy1oN0NVNG Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:74A5fPLR86U9XWYostkXwS Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:5CSVNJBsEPH3cM7S2iQgax Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:2UUwBDALzJuThY88Ro2lD2 Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:2DYupgAcdomatly7Lhw8zv Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:0WvCL9bRZZeH0BFf35jeZ3 Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:2YAzpDuHmAe5kROOlyDnmv Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:4cyk1ppFfQleEgXBvWX4Uc Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:17OQVshtovwVo2vk6tuzwn Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:6GoxKIpAcVnkR2zQyRa6EF Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:0Gp8MWnUzS5V2lvTr25c2A Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:389rEslJY9vh7TZemIA4y7 Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:2EmkTBTh964LQen4Vz0svg Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:7b4fxkOA4NDr4tDylrb10K Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:4EyKk14XxsBnMiSrsa8Ide Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:294o7PTrqj9VySUIHaJmXw Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:18p1ct9Vw2STwplkq1MlO9 Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:78W4mTLIh4qoLu92W4IQhO Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:0teqQ0PYqVDyyXJuPOyMhu Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:0tnhoHUrHfcLi9hPknUFZ4 Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:170XOP1T4MvY9TLTgPw07D Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:5BC6kr6etk2Y9J62AyI4i3 Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:2cKiHG16JBiqHdi7Llq0r7 Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:4IfrM44LofE9bSs6TDZS49 Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:7M0dIdZWN1FDcveRdoOzbZ Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:5pN2AyWwjzwRoI7gukEVNd Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:6H0PLsSYMzDOqhLgyOlzIj Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:172fMG26G2OoEzzg0wn26r Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:2Gmyw5Vg2X5YW2lM3OC7nD Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:4kPlQKwtPrnqLgrmmKFSlA Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:04TshWXkhV1qkqHzf31Hn6 Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:4PCQHOJFMV9BWq46UMVf2l Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:0H3RFIhXuuRoQyNKYdvSbn Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:3ECvJ97WjbbaTsCZ64pwgI Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:1Vqs0lXeJafYZz2sxnfB3V Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:59eluCMn0XbOWqeWQ91FTM Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:7tGDXCPLpAScEwmqab9YZd Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:0ve9Tpz8Yw0I4eXhWsy21o Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:7ncPjJBktLEOpstY5JOUMK Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:7rzoBRR4LbJZH5t7Q6qeTn Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:0FOVGBW0RpVRuDuUuN4ZVw Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:4cPwi7lcWxRQNEb4xC77fC Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:2vOAHp8PBBFYqvQUWSoITY Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:6wDntdm888mDo458RaYjGl Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:4HgsPAX3MmMgIT60hJ4W4U Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:5vsM9UppL2iTgOfenMB6Gz Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:4DmmljTCS4ViQRSLCiMnOt Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:1sIIlVrnPhrvmTrHtzM7tV Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:3YML5IHoqWbw9c9vJJvrvM Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:6DYV1GqwCTrvfPcjeFwjLt Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:00GDUNeJd97qjKp2yrx0OC Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:7sditHCsoFw3nHnjFv1BHV Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:3FniX6mJvTQWruKp5PDexD Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:33qTER0xTXBQPDMSYR1hGX Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:1nysdCpKAh6yif4JkmoiNy Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:4W3rP2KZkKEykdWvlWLgyc Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:5Snmyurx2SgwT1BvUswGwg Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:5XhxMq0dQNenKr6eDU79Ka Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:2fJbss5uUmmBqn7qFkmyWj Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:3CmVQtVx9KlzOuPhRJRShH Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:6T2b2vKSdZuvbUzK3WIGJM Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:4Ab9EruWYtWlphFen1uSxN Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:0ASw64KfzSUtChQDfzC906 Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:5v6X89ub41du3FhUI41sao Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:5V9Y0tatVvqGeZdyohOBPk Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:56Ajh1DR8TfxXe43k7fLRA Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:2ritsV4U3jq2LduJpovZ1A Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:75tQZI2X1VwNOPtOrOrdpl Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:08ZvFfHEBfTgH4MN9YT1BC Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:0cN6iBeCR7NgeBeTIKjLml Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:5OtpvLAq1uUQ6YmgxbI98H Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:7oYCBKvdjrqp5vDbhDBuac Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:3hRRYgBeunE3PTmnzATTS0 Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:3GLSPr5dyhrNBvDPpdoPDK Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:04e72JtsOIIlHUQ7CvWwvX Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:49DUifaclCSbxQa1upNXuO Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:44PT3ussI7vcQb6QYJhLF8 Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:6MJFNhbXbqXCbHP24yLltO Apr 13 17:08:28 volumio volumio[955]: info: Preloading song: spotify:track:1qjdRyMlZSpy9OW8rec9xA Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5ic62hKqeDhZsa9l6YQxzJ","service":"spop","name":"メズマライザー (feat. 初音ミク&重音テト)","artist":"32ki","album":"メズマライザー","type":"song","duration":156,"albumart":"https://i.scdn.co/image/ab67616d0000b27321333eb66e3cf9f3818e789f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0kCRrfk1WV7QMnlxqhpwV9","service":"spop","name":"ラビットホール","artist":"DECO*27","album":"TRANSFORM","type":"song","duration":158,"albumart":"https://i.scdn.co/image/ab67616d0000b27366c601815329070d83df52d8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:28 volumio volumio[955]: info: Exploding uri spotify:track:4bdwEiJuBhWIkPKSVjyjiA in service spop Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:4bdwEiJuBhWIkPKSVjyjiA Apr 13 17:08:28 volumio volumio[955]: info: Exploding uri spotify:track:54ezuGQj5s0ot4J7ktWb2h in service spop Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:54ezuGQj5s0ot4J7ktWb2h Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3wM3VFL0nuEhwATHcJfPSm","service":"spop","name":"春嵐","artist":"John","album":"ROSE","type":"song","duration":161,"albumart":"https://i.scdn.co/image/ab67616d0000b273d2c8cf347c916e46a35c0dd7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:28 volumio volumio[955]: info: Exploding uri spotify:track:4oPCfEDVIKnPJ6UPkhJPCa in service spop Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:4oPCfEDVIKnPJ6UPkhJPCa Apr 13 17:08:28 volumio volumio[955]: info: Exploding uri spotify:track:43CMcyGeImlZjy1oN0NVNG in service spop Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:43CMcyGeImlZjy1oN0NVNG Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4bdwEiJuBhWIkPKSVjyjiA","service":"spop","name":"ラグトレイン","artist":"稲葉曇","album":"ウェザーステーション","type":"song","duration":252,"albumart":"https://i.scdn.co/image/ab67616d0000b273a2e19e90170fd6cece34d2d9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:28 volumio volumio[955]: info: Exploding uri spotify:track:74A5fPLR86U9XWYostkXwS in service spop Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:74A5fPLR86U9XWYostkXwS Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:54ezuGQj5s0ot4J7ktWb2h","service":"spop","name":"シャルル","artist":"balloon","album":"Corridor","type":"song","duration":233,"albumart":"https://i.scdn.co/image/ab67616d0000b273fa85ef06207d05002a60d35e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:28 volumio volumio[955]: info: Exploding uri spotify:track:5CSVNJBsEPH3cM7S2iQgax in service spop Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:5CSVNJBsEPH3cM7S2iQgax Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4oPCfEDVIKnPJ6UPkhJPCa","service":"spop","name":"酔いどれ知らず","artist":"Kanaria","album":"酔いどれ知らず","type":"song","duration":134,"albumart":"https://i.scdn.co/image/ab67616d0000b2739801b6931cc8cfe8a2f2ede3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:28 volumio volumio[955]: info: Exploding uri spotify:track:2UUwBDALzJuThY88Ro2lD2 in service spop Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:2UUwBDALzJuThY88Ro2lD2 Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:43CMcyGeImlZjy1oN0NVNG","service":"spop","name":"モエチャッカファイア","artist":"issey","album":"モエチャッカファイア","type":"song","duration":155,"albumart":"https://i.scdn.co/image/ab67616d0000b2732c3840e66768368e20042fe6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:28 volumio volumio[955]: info: Exploding uri spotify:track:2DYupgAcdomatly7Lhw8zv in service spop Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:2DYupgAcdomatly7Lhw8zv Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:74A5fPLR86U9XWYostkXwS","service":"spop","name":"マトリョシカ - ORIGINAL","artist":"hachi","album":"OFFICIAL ORANGE","type":"song","duration":198,"albumart":"https://i.scdn.co/image/ab67616d0000b27334df4a6359487004c821faa0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:28 volumio volumio[955]: info: Exploding uri spotify:track:0WvCL9bRZZeH0BFf35jeZ3 in service spop Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:0WvCL9bRZZeH0BFf35jeZ3 Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5CSVNJBsEPH3cM7S2iQgax","service":"spop","name":"ヴィラン","artist":"Teniwoha","album":"ヴィラン","type":"song","duration":200,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fe37c88ebd902c1f35385b5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2UUwBDALzJuThY88Ro2lD2","service":"spop","name":"フォニイ","artist":"可不","album":"KAF+YOU KAFU COMPILATION ALBUM シンメトリー","type":"song","duration":189,"albumart":"https://i.scdn.co/image/ab67616d0000b273b19f79f9d15451ad63bb3f81","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:28 volumio volumio[955]: info: Exploding uri spotify:track:2YAzpDuHmAe5kROOlyDnmv in service spop Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:2YAzpDuHmAe5kROOlyDnmv Apr 13 17:08:28 volumio volumio[955]: info: Exploding uri spotify:track:4cyk1ppFfQleEgXBvWX4Uc in service spop Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:4cyk1ppFfQleEgXBvWX4Uc Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2DYupgAcdomatly7Lhw8zv","service":"spop","name":"フィクサー","artist":"ぬゆり","album":"plotoplan","type":"song","duration":247,"albumart":"https://i.scdn.co/image/ab67616d0000b273750c38e2a2746c2188f46e3a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:28 volumio volumio[955]: info: Exploding uri spotify:track:17OQVshtovwVo2vk6tuzwn in service spop Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:17OQVshtovwVo2vk6tuzwn Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0WvCL9bRZZeH0BFf35jeZ3","service":"spop","name":"ルマ","artist":"Kairikibear","album":"ダーリンシンドローム","type":"song","duration":187,"albumart":"https://i.scdn.co/image/ab67616d0000b273e24eeaab0da33f5dff9513d8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2YAzpDuHmAe5kROOlyDnmv","service":"spop","name":"Konton Boogie","artist":"jon-YAKITORY","album":"Konton Boogie","type":"song","duration":154,"albumart":"https://i.scdn.co/image/ab67616d0000b273ee4f46a7cd8e8e3d4b5525cf","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:28 volumio volumio[955]: info: Exploding uri spotify:track:6GoxKIpAcVnkR2zQyRa6EF in service spop Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:6GoxKIpAcVnkR2zQyRa6EF Apr 13 17:08:28 volumio volumio[955]: info: Exploding uri spotify:track:0Gp8MWnUzS5V2lvTr25c2A in service spop Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:0Gp8MWnUzS5V2lvTr25c2A Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4cyk1ppFfQleEgXBvWX4Uc","service":"spop","name":"㋰責任集合体","artist":"マサラダ","album":"㋰責任集合体","type":"song","duration":210,"albumart":"https://i.scdn.co/image/ab67616d0000b2737ee4de3062e88c4c9308f21b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:17OQVshtovwVo2vk6tuzwn","service":"spop","name":"ライアーダンサー","artist":"マサラダ","album":"ライアーダンサー","type":"song","duration":242,"albumart":"https://i.scdn.co/image/ab67616d0000b27330d7cea1e497fd66b9e525e8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:28 volumio volumio[955]: info: Exploding uri spotify:track:389rEslJY9vh7TZemIA4y7 in service spop Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:389rEslJY9vh7TZemIA4y7 Apr 13 17:08:28 volumio volumio[955]: info: Exploding uri spotify:track:2EmkTBTh964LQen4Vz0svg in service spop Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:2EmkTBTh964LQen4Vz0svg Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6GoxKIpAcVnkR2zQyRa6EF","service":"spop","name":"トウキョウ・シャンディ・ランデヴ","artist":"MAISONdes","album":"Noisy Love Songs - MAISONdes × URUSEIYATSURA Complete Collection -","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b273b652d8e535585b2fec6cf61a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:28 volumio volumio[955]: info: Exploding uri spotify:track:7b4fxkOA4NDr4tDylrb10K in service spop Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:7b4fxkOA4NDr4tDylrb10K Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0Gp8MWnUzS5V2lvTr25c2A","service":"spop","name":"ビビデバ","artist":"Hoshimachi Suisei","album":"ビビデバ","type":"song","duration":165,"albumart":"https://i.scdn.co/image/ab67616d0000b2731c4b87d1d7b61594bd5b2658","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:389rEslJY9vh7TZemIA4y7","service":"spop","name":"絶対零度","artist":"natori","album":"絶対零度","type":"song","duration":198,"albumart":"https://i.scdn.co/image/ab67616d0000b273524cec0ac2f584b666e08ff5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:28 volumio volumio[955]: info: Exploding uri spotify:track:4EyKk14XxsBnMiSrsa8Ide in service spop Apr 13 17:08:28 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:4EyKk14XxsBnMiSrsa8Ide Apr 13 17:08:29 volumio volumio[955]: info: Exploding uri spotify:track:294o7PTrqj9VySUIHaJmXw in service spop Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:294o7PTrqj9VySUIHaJmXw Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2EmkTBTh964LQen4Vz0svg","service":"spop","name":"花になって - Be a flower","artist":"Ryokuoushoku Shakai","album":"花になって - Be a flower","type":"song","duration":199,"albumart":"https://i.scdn.co/image/ab67616d0000b273429f3c0081fd674ba9365997","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:29 volumio wpa_supplicant[871]: RRM: Ignoring radio measurement request: Not RRM network Apr 13 17:08:29 volumio volumio[955]: info: Exploding uri spotify:track:18p1ct9Vw2STwplkq1MlO9 in service spop Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:18p1ct9Vw2STwplkq1MlO9 Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7b4fxkOA4NDr4tDylrb10K","service":"spop","name":"Overdose","artist":"natori","album":"劇場","type":"song","duration":194,"albumart":"https://i.scdn.co/image/ab67616d0000b2730ac0f324b55a0e56b62670ca","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4EyKk14XxsBnMiSrsa8Ide","service":"spop","name":"乙女解剖","artist":"DECO*27","album":"アンドロイドガール","type":"song","duration":223,"albumart":"https://i.scdn.co/image/ab67616d0000b273d291e26f8a49c5250fe4ee1b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:29 volumio volumio[955]: info: Exploding uri spotify:track:78W4mTLIh4qoLu92W4IQhO in service spop Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:78W4mTLIh4qoLu92W4IQhO Apr 13 17:08:29 volumio volumio[955]: info: Exploding uri spotify:track:0teqQ0PYqVDyyXJuPOyMhu in service spop Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:0teqQ0PYqVDyyXJuPOyMhu Apr 13 17:08:29 volumio volumio[955]: info: Exploding uri spotify:track:0tnhoHUrHfcLi9hPknUFZ4 in service spop Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:0tnhoHUrHfcLi9hPknUFZ4 Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:78W4mTLIh4qoLu92W4IQhO","service":"spop","name":"ライラック","artist":"Mrs. GREEN APPLE","album":"ライラック","type":"song","duration":288,"albumart":"https://i.scdn.co/image/ab67616d0000b2732dad118eff6a430b28a457bd","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:29 volumio volumio[955]: info: Exploding uri spotify:track:170XOP1T4MvY9TLTgPw07D in service spop Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:170XOP1T4MvY9TLTgPw07D Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:18p1ct9Vw2STwplkq1MlO9","service":"spop","name":"ヒバナ -Reloaded-","artist":"DECO*27","album":"アンドロイドガール","type":"song","duration":206,"albumart":"https://i.scdn.co/image/ab67616d0000b273d291e26f8a49c5250fe4ee1b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:29 volumio volumio[955]: info: Exploding uri spotify:track:5BC6kr6etk2Y9J62AyI4i3 in service spop Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:5BC6kr6etk2Y9J62AyI4i3 Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0teqQ0PYqVDyyXJuPOyMhu","service":"spop","name":"ケセラセラ","artist":"Mrs. GREEN APPLE","album":"ANTENNA","type":"song","duration":272,"albumart":"https://i.scdn.co/image/ab67616d0000b273d482c602732da71e7cdf04c3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:29 volumio volumio[955]: info: Exploding uri spotify:track:2cKiHG16JBiqHdi7Llq0r7 in service spop Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:2cKiHG16JBiqHdi7Llq0r7 Apr 13 17:08:29 volumio volumio[955]: info: Exploding uri spotify:track:4IfrM44LofE9bSs6TDZS49 in service spop Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:4IfrM44LofE9bSs6TDZS49 Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:170XOP1T4MvY9TLTgPw07D","service":"spop","name":"Magic","artist":"Mrs. GREEN APPLE","album":"ANTENNA","type":"song","duration":263,"albumart":"https://i.scdn.co/image/ab67616d0000b273d482c602732da71e7cdf04c3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0tnhoHUrHfcLi9hPknUFZ4","service":"spop","name":"僕のこと","artist":"Mrs. GREEN APPLE","album":"Attitude","type":"song","duration":321,"albumart":"https://i.scdn.co/image/ab67616d0000b27381f55cd879e9480e3ed313df","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5BC6kr6etk2Y9J62AyI4i3","service":"spop","name":"青と夏","artist":"Mrs. GREEN APPLE","album":"Attitude","type":"song","duration":270,"albumart":"https://i.scdn.co/image/ab67616d0000b27381f55cd879e9480e3ed313df","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:294o7PTrqj9VySUIHaJmXw","service":"spop","name":"デビルじゃないもん","artist":"DECO*27","album":"デビルじゃないもん","type":"song","duration":163,"albumart":"https://i.scdn.co/image/ab67616d0000b273dc25ac0392cc14597ab21e7e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:29 volumio volumio[955]: info: Exploding uri spotify:track:7M0dIdZWN1FDcveRdoOzbZ in service spop Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:7M0dIdZWN1FDcveRdoOzbZ Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2cKiHG16JBiqHdi7Llq0r7","service":"spop","name":"マリーゴールド","artist":"Aimyon","album":"瞬間的シックスセンス","type":"song","duration":306,"albumart":"https://i.scdn.co/image/ab67616d0000b273c12d785df5dbf2b15ec49504","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:29 volumio volumio[955]: info: Exploding uri spotify:track:5pN2AyWwjzwRoI7gukEVNd in service spop Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:5pN2AyWwjzwRoI7gukEVNd Apr 13 17:08:29 volumio volumio[955]: info: Exploding uri spotify:track:6H0PLsSYMzDOqhLgyOlzIj in service spop Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:6H0PLsSYMzDOqhLgyOlzIj Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4IfrM44LofE9bSs6TDZS49","service":"spop","name":"W / X / Y","artist":"Tani Yuuki","album":"Memories","type":"song","duration":278,"albumart":"https://i.scdn.co/image/ab67616d0000b273b5900aef5989a39a87bda771","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:29 volumio volumio[955]: info: Exploding uri spotify:track:172fMG26G2OoEzzg0wn26r in service spop Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:172fMG26G2OoEzzg0wn26r Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7M0dIdZWN1FDcveRdoOzbZ","service":"spop","name":"ビリミリオン","artist":"Yuuri","album":"弐","type":"song","duration":228,"albumart":"https://i.scdn.co/image/ab67616d0000b273188327ac9022d86ad2dd6483","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:29 volumio volumio[955]: info: Exploding uri spotify:track:2Gmyw5Vg2X5YW2lM3OC7nD in service spop Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:2Gmyw5Vg2X5YW2lM3OC7nD Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6H0PLsSYMzDOqhLgyOlzIj","service":"spop","name":"感電","artist":"Kenshi Yonezu","album":"STRAY SHEEP","type":"song","duration":264,"albumart":"https://i.scdn.co/image/ab67616d0000b273775e8184725e0fb89337dd9a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:29 volumio volumio[955]: info: Exploding uri spotify:track:4kPlQKwtPrnqLgrmmKFSlA in service spop Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:track:4kPlQKwtPrnqLgrmmKFSlA Apr 13 17:08:29 volumio volumio[955]: info: Preload queue cleared Apr 13 17:08:29 volumio volumio[955]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 13 17:08:29 volumio volumio[955]: info: CoreStateMachine::ClearQueue Apr 13 17:08:29 volumio volumio[955]: info: CoreStateMachine::stop Apr 13 17:08:29 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 17:08:29 volumio volumio[955]: info: CorePlayQueue::clearPlayQueue Apr 13 17:08:29 volumio volumio[955]: info: CorePlayQueue::saveQueue Apr 13 17:08:29 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Apr 13 17:08:29 volumio volumio[955]: info: CoreStateMachine::addQueueItems Apr 13 17:08:29 volumio volumio[955]: info: CorePlayQueue::addQueueItems Apr 13 17:08:29 volumio volumio[955]: info: Preload queue cleared Apr 13 17:08:29 volumio volumio[955]: info: Adding Item to queue: spotify:user:spotify:playlist:5jneHVZy0WbJFjqUqWfc8U Apr 13 17:08:29 volumio volumio[955]: info: Exploding uri spotify:user:spotify:playlist:5jneHVZy0WbJFjqUqWfc8U in service spop Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: EXPLODING URI:spotify:user:spotify:playlist:5jneHVZy0WbJFjqUqWfc8U Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:172fMG26G2OoEzzg0wn26r","service":"spop","name":"白日","artist":"King Gnu","album":"CEREMONY","type":"song","duration":276,"albumart":"https://i.scdn.co/image/ab67616d0000b273a2272c8966971b7b04066241","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2Gmyw5Vg2X5YW2lM3OC7nD","service":"spop","name":"春を告げる","artist":"yama","album":"春を告げる","type":"song","duration":196,"albumart":"https://i.scdn.co/image/ab67616d0000b273b8785210c437d5a58506827b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4kPlQKwtPrnqLgrmmKFSlA","service":"spop","name":"ドライフラワー","artist":"Yuuri","album":"壱","type":"song","duration":286,"albumart":"https://i.scdn.co/image/ab67616d0000b273d0bd51668da5629aa0cc77d8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:29 volumio volumio[955]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5pN2AyWwjzwRoI7gukEVNd","service":"spop","name":"さよーならまたいつか！- Sayonara","artist":"Kenshi Yonezu","album":"LOST CORNER","type":"song","duration":201,"albumart":"https://i.scdn.co/image/ab67616d0000b273a52ffa7b1ee67275e32d04bc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 13 17:08:30 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Apr 13 17:08:30 volumio volumio[955]: info: CorePlayQueue::saveQueue Apr 13 17:08:30 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Apr 13 17:08:30 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Apr 13 17:08:30 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Apr 13 17:08:30 volumio volumio[955]: info: CoreStateMachine::play index 0 Apr 13 17:08:30 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 17:08:30 volumio volumio[955]: info: CoreStateMachine::stop Apr 13 17:08:30 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 17:08:30 volumio volumio[955]: info: CoreStateMachine::play index undefined Apr 13 17:08:30 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 17:08:30 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:30 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Apr 13 17:08:30 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:30 volumio volumio[955]: info: [1744531710823] ControllerSpotify::clearAddPlayTrack Apr 13 17:08:30 volumio volumio[955]: info: Sending Spotify command with payload to local API: /player/play Apr 13 17:08:30 volumio go-librespot[1308]: time="2025-04-13T17:08:30+09:00" level=debug msg="resolved context of track" uri="spotify:track:5vNRhkKd0yEAg8suGBpjeY" Apr 13 17:08:30 volumio go-librespot[1308]: time="2025-04-13T17:08:30+09:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:5vNRhkKd0yEAg8suGBpjeY" Apr 13 17:08:30 volumio go-librespot[1308]: time="2025-04-13T17:08:30+09:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:5vNRhkKd0yEAg8suGBpjeY" Apr 13 17:08:30 volumio go-librespot[1308]: time="2025-04-13T17:08:30+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 13 17:08:30 volumio go-librespot[1308]: time="2025-04-13T17:08:30+09:00" level=trace msg="emitting websocket event: will_play" Apr 13 17:08:30 volumio volumio[955]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","play_origin":"go-librespot"}} Apr 13 17:08:31 volumio volumio[955]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","play_origin":"go-librespot"}} Apr 13 17:08:31 volumio go-librespot[1308]: time="2025-04-13T17:08:31+09:00" level=debug msg="selected format OGG_VORBIS_320 (d5bc117a3840617999f663da236c8f6bffbe9954)" uri="spotify:track:5vNRhkKd0yEAg8suGBpjeY" Apr 13 17:08:31 volumio go-librespot[1308]: time="2025-04-13T17:08:31+09:00" level=debug msg="requested aes key for file d5bc117a3840617999f663da236c8f6bffbe9954, gid: 5vNRhkKd0yEAg8suGBpjeY" Apr 13 17:08:31 volumio go-librespot[1308]: time="2025-04-13T17:08:31+09:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Apr 13 17:08:31 volumio go-librespot[1308]: time="2025-04-13T17:08:31+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Apr 13 17:08:31 volumio go-librespot[1308]: time="2025-04-13T17:08:31+09:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Apr 13 17:08:31 volumio go-librespot[1308]: time="2025-04-13T17:08:31+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1368" Apr 13 17:08:31 volumio go-librespot[1308]: time="2025-04-13T17:08:31+09:00" level=debug msg="fetched first chunk of 16, total size is 8240217 bytes" uri="spotify:track:5vNRhkKd0yEAg8suGBpjeY" Apr 13 17:08:31 volumio go-librespot[1308]: time="2025-04-13T17:08:31+09:00" level=debug msg="created new output device" Apr 13 17:08:31 volumio go-librespot[1308]: time="2025-04-13T17:08:31+09:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames" Apr 13 17:08:31 volumio go-librespot[1308]: time="2025-04-13T17:08:31+09:00" level=info msg="loaded track \"APT.\" (paused: false, position: 0ms, duration: 169917ms, prefetched: false)" uri="spotify:track:5vNRhkKd0yEAg8suGBpjeY" Apr 13 17:08:31 volumio go-librespot[1308]: time="2025-04-13T17:08:31+09:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Apr 13 17:08:31 volumio go-librespot[1308]: time="2025-04-13T17:08:31+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1128" Apr 13 17:08:31 volumio go-librespot[1308]: time="2025-04-13T17:08:31+09:00" level=debug msg="fetched chunk 2/15, size: 524288" uri="spotify:track:5vNRhkKd0yEAg8suGBpjeY" Apr 13 17:08:31 volumio go-librespot[1308]: time="2025-04-13T17:08:31+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 13 17:08:31 volumio go-librespot[1308]: time="2025-04-13T17:08:31+09:00" level=trace msg="scheduling prefetch in 140s" Apr 13 17:08:31 volumio go-librespot[1308]: time="2025-04-13T17:08:31+09:00" level=trace msg="emitting websocket event: metadata" Apr 13 17:08:31 volumio volumio[955]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","name":"APT.","artist_names":["ROSÉ","Bruno Mars"],"album_name":"APT.","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0236032cb4acd9df050bc2e197","position":0,"duration":169917,"release_date":"year:2024 month:10 day:18","track_number":1,"disc_number":1}} Apr 13 17:08:31 volumio volumio[955]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","name":"APT.","artist_names":["ROSÉ","Bruno Mars"],"album_name":"APT.","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0236032cb4acd9df050bc2e197","position":0,"duration":169917,"release_date":"year:2024 month:10 day:18","track_number":1,"disc_number":1}} Apr 13 17:08:31 volumio go-librespot[1308]: time="2025-04-13T17:08:31+09:00" level=debug msg="fetched chunk 1/15, size: 524288" uri="spotify:track:5vNRhkKd0yEAg8suGBpjeY" Apr 13 17:08:31 volumio go-librespot[1308]: time="2025-04-13T17:08:31+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 13 17:08:31 volumio go-librespot[1308]: time="2025-04-13T17:08:31+09:00" level=trace msg="emitting websocket event: playing" Apr 13 17:08:31 volumio volumio[955]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","play_origin":"go-librespot"}} Apr 13 17:08:31 volumio volumio[955]: SPOTIFY: PUSH STATE SPOTIFY Apr 13 17:08:31 volumio volumio[955]: SPOTIFY: {"status":"play","service":"spop","title":"APT.","artist":"ROSÉ, Bruno Mars","album":"APT.","albumart":"https://i.scdn.co/image/ab67616d00001e0236032cb4acd9df050bc2e197","uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","trackType":"spotify","seek":0,"duration":169,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:31 volumio volumio[955]: info: CoreCommandRouter::servicePushState Apr 13 17:08:31 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:31 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"APT.","artist":"ROSÉ, Bruno Mars","album":"APT.","albumart":"https://i.scdn.co/image/ab67616d00001e0236032cb4acd9df050bc2e197","uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","trackType":"spotify","seek":0,"duration":169,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:31 volumio volumio[955]: verbose: CURRENT POSITION 0 Apr 13 17:08:31 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Apr 13 17:08:31 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Apr 13 17:08:31 volumio volumio[955]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","play_origin":"go-librespot"}} Apr 13 17:08:31 volumio volumio[955]: SPOTIFY: PUSH STATE SPOTIFY Apr 13 17:08:31 volumio volumio[955]: SPOTIFY: {"status":"play","service":"spop","title":"APT.","artist":"ROSÉ, Bruno Mars","album":"APT.","albumart":"https://i.scdn.co/image/ab67616d00001e0236032cb4acd9df050bc2e197","uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","trackType":"spotify","seek":0,"duration":169,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:31 volumio volumio[955]: info: CoreCommandRouter::servicePushState Apr 13 17:08:31 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:31 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"APT.","artist":"ROSÉ, Bruno Mars","album":"APT.","albumart":"https://i.scdn.co/image/ab67616d00001e0236032cb4acd9df050bc2e197","uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","trackType":"spotify","seek":0,"duration":169,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:31 volumio volumio[955]: verbose: CURRENT POSITION 0 Apr 13 17:08:31 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Apr 13 17:08:31 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Apr 13 17:08:31 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Apr 13 17:08:31 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:31 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 17:08:31 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:31 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:31 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:31 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:31 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:31 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:31 volumio go-librespot[1308]: time="2025-04-13T17:08:31+09:00" level=debug msg="fetched chunk 3/15, size: 524288" uri="spotify:track:5vNRhkKd0yEAg8suGBpjeY" Apr 13 17:08:31 volumio volumio[955]: SPOTIFY: PUSH STATE SPOTIFY Apr 13 17:08:31 volumio volumio[955]: SPOTIFY: {"status":"play","service":"spop","title":"APT.","artist":"ROSÉ, Bruno Mars","album":"APT.","albumart":"https://i.scdn.co/image/ab67616d00001e0236032cb4acd9df050bc2e197","uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","trackType":"spotify","seek":0,"duration":169,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:31 volumio volumio[955]: info: CoreCommandRouter::servicePushState Apr 13 17:08:31 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:31 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"APT.","artist":"ROSÉ, Bruno Mars","album":"APT.","albumart":"https://i.scdn.co/image/ab67616d00001e0236032cb4acd9df050bc2e197","uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","trackType":"spotify","seek":0,"duration":169,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:31 volumio volumio[955]: verbose: CURRENT POSITION 0 Apr 13 17:08:31 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Apr 13 17:08:31 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Apr 13 17:08:31 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Apr 13 17:08:31 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:31 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:31 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:31 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:31 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:31 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:32 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:32 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:32 volumio volumio[955]: SPOTIFY: PUSH STATE SPOTIFY Apr 13 17:08:32 volumio volumio[955]: SPOTIFY: {"status":"play","service":"spop","title":"APT.","artist":"ROSÉ, Bruno Mars","album":"APT.","albumart":"https://i.scdn.co/image/ab67616d00001e0236032cb4acd9df050bc2e197","uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","trackType":"spotify","seek":0,"duration":169,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:32 volumio volumio[955]: info: CoreCommandRouter::servicePushState Apr 13 17:08:32 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:32 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"APT.","artist":"ROSÉ, Bruno Mars","album":"APT.","albumart":"https://i.scdn.co/image/ab67616d00001e0236032cb4acd9df050bc2e197","uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","trackType":"spotify","seek":0,"duration":169,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:32 volumio volumio[955]: verbose: CURRENT POSITION 0 Apr 13 17:08:32 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Apr 13 17:08:32 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Apr 13 17:08:32 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Apr 13 17:08:32 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:32 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:32 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:32 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:32 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:32 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:32 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:32 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:35 volumio wpa_supplicant[871]: RRM: Ignoring radio measurement request: Not RRM network Apr 13 17:08:35 volumio volumio[955]: info: CoreCommandRouter::volumioSeek Apr 13 17:08:35 volumio volumio[955]: info: CoreStateMachine::seek Apr 13 17:08:35 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:35 volumio volumio[955]: info: TRACKBLOCK {"service":"spop","type":"song","name":"APT.","title":"APT.","artist":"ROSÉ","album":"APT.","uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify","albumart":"https://i.scdn.co/image/ab67616d0000b27336032cb4acd9df050bc2e197","duration":169,"channels":2} Apr 13 17:08:35 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Apr 13 17:08:35 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:35 volumio volumio[955]: info: Spotify seek to: 82000 Apr 13 17:08:35 volumio volumio[955]: info: Sending Spotify command with payload to local API: /player/seek Apr 13 17:08:35 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:35 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 17:08:35 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:35 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:35 volumio go-librespot[1308]: time="2025-04-13T17:08:35+09:00" level=debug msg="seek track to 82000ms" Apr 13 17:08:35 volumio go-librespot[1308]: time="2025-04-13T17:08:35+09:00" level=debug msg="fetched chunk 7/15, size: 524288" uri="spotify:track:5vNRhkKd0yEAg8suGBpjeY" Apr 13 17:08:35 volumio go-librespot[1308]: time="2025-04-13T17:08:35+09:00" level=trace msg="seek to 82000ms (diff: 179ms, samples: 3616200, bytes: 3802331)" uri="spotify:track:5vNRhkKd0yEAg8suGBpjeY" Apr 13 17:08:36 volumio go-librespot[1308]: time="2025-04-13T17:08:36+09:00" level=debug msg="fetched chunk 9/15, size: 524288" uri="spotify:track:5vNRhkKd0yEAg8suGBpjeY" Apr 13 17:08:36 volumio go-librespot[1308]: time="2025-04-13T17:08:36+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 13 17:08:36 volumio go-librespot[1308]: time="2025-04-13T17:08:36+09:00" level=trace msg="scheduling prefetch in 58s" Apr 13 17:08:36 volumio go-librespot[1308]: time="2025-04-13T17:08:36+09:00" level=trace msg="emitting websocket event: seek" Apr 13 17:08:36 volumio volumio[955]: SPOTIFY: received: {"type":"seek","data":{"uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","position":82000,"duration":169917,"play_origin":"go-librespot"}} Apr 13 17:08:36 volumio volumio[955]: SPOTIFY: PUSH STATE SPOTIFY Apr 13 17:08:36 volumio volumio[955]: SPOTIFY: {"status":"play","service":"spop","title":"APT.","artist":"ROSÉ, Bruno Mars","album":"APT.","albumart":"https://i.scdn.co/image/ab67616d00001e0236032cb4acd9df050bc2e197","uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","trackType":"spotify","seek":82000,"duration":169,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:36 volumio volumio[955]: info: CoreCommandRouter::servicePushState Apr 13 17:08:36 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:36 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"APT.","artist":"ROSÉ, Bruno Mars","album":"APT.","albumart":"https://i.scdn.co/image/ab67616d00001e0236032cb4acd9df050bc2e197","uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","trackType":"spotify","seek":82000,"duration":169,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:36 volumio volumio[955]: verbose: CURRENT POSITION 0 Apr 13 17:08:36 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Apr 13 17:08:36 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Apr 13 17:08:36 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Apr 13 17:08:36 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:36 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:36 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:36 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:36 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:36 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:36 volumio volumio[955]: SPOTIFY: received: {"type":"seek","data":{"uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","position":82000,"duration":169917,"play_origin":"go-librespot"}} Apr 13 17:08:36 volumio volumio[955]: SPOTIFY: PUSH STATE SPOTIFY Apr 13 17:08:36 volumio volumio[955]: SPOTIFY: {"status":"play","service":"spop","title":"APT.","artist":"ROSÉ, Bruno Mars","album":"APT.","albumart":"https://i.scdn.co/image/ab67616d00001e0236032cb4acd9df050bc2e197","uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","trackType":"spotify","seek":82000,"duration":169,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:36 volumio volumio[955]: info: CoreCommandRouter::servicePushState Apr 13 17:08:36 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:36 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"APT.","artist":"ROSÉ, Bruno Mars","album":"APT.","albumart":"https://i.scdn.co/image/ab67616d00001e0236032cb4acd9df050bc2e197","uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","trackType":"spotify","seek":82000,"duration":169,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:36 volumio volumio[955]: verbose: CURRENT POSITION 0 Apr 13 17:08:36 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Apr 13 17:08:36 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Apr 13 17:08:36 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Apr 13 17:08:36 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:36 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:36 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:36 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:36 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:36 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:36 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:36 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:36 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:36 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:36 volumio go-librespot[1308]: time="2025-04-13T17:08:36+09:00" level=debug msg="fetched chunk 8/15, size: 524288" uri="spotify:track:5vNRhkKd0yEAg8suGBpjeY" Apr 13 17:08:36 volumio go-librespot[1308]: time="2025-04-13T17:08:36+09:00" level=debug msg="fetched chunk 10/15, size: 524288" uri="spotify:track:5vNRhkKd0yEAg8suGBpjeY" Apr 13 17:08:39 volumio go-librespot[1308]: time="2025-04-13T17:08:39+09:00" level=trace msg="sent dealer ping" Apr 13 17:08:39 volumio go-librespot[1308]: time="2025-04-13T17:08:39+09:00" level=trace msg="received dealer pong" Apr 13 17:08:40 volumio volumio[955]: info: CoreCommandRouter::volumioNext Apr 13 17:08:40 volumio volumio[955]: info: CoreStateMachine::next Apr 13 17:08:40 volumio volumio[955]: info: CoreStateMachine::stop Apr 13 17:08:40 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 17:08:40 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Apr 13 17:08:40 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Apr 13 17:08:40 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Apr 13 17:08:40 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:40 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:40 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 17:08:40 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:40 volumio volumio[955]: info: CoreStateMachine::serviceStop Apr 13 17:08:40 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Apr 13 17:08:40 volumio volumio[955]: info: CoreCommandRouter::serviceStop Apr 13 17:08:40 volumio volumio[955]: info: Spotify Stop Apr 13 17:08:40 volumio volumio[955]: SPOTIFY: SPOTIFY STOP Apr 13 17:08:40 volumio volumio[955]: SPOTIFY: {"status":"play","position":0,"title":"APT.","artist":"ROSÉ","album":"APT.","albumart":"https://i.scdn.co/image/ab67616d0000b27336032cb4acd9df050bc2e197","uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","trackType":"spotify","codec":"ogg","seek":82000,"duration":169,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":true,"repeat":null,"repeatSingle":false,"consume":false,"volume":69,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Apr 13 17:08:40 volumio volumio[955]: info: Sending Spotify command to local API: /player/pause Apr 13 17:08:40 volumio volumio[955]: info: CoreStateMachine::play index undefined Apr 13 17:08:40 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 17:08:40 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:40 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Apr 13 17:08:40 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:40 volumio volumio[955]: info: [1744531720733] ControllerSpotify::clearAddPlayTrack Apr 13 17:08:40 volumio volumio[955]: info: Sending Spotify command with payload to local API: /player/play Apr 13 17:08:40 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Apr 13 17:08:40 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Apr 13 17:08:40 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:40 volumio go-librespot[1308]: time="2025-04-13T17:08:40+09:00" level=debug msg="pause track at 86816ms" Apr 13 17:08:40 volumio go-librespot[1308]: time="2025-04-13T17:08:40+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 13 17:08:40 volumio go-librespot[1308]: time="2025-04-13T17:08:40+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 13 17:08:40 volumio go-librespot[1308]: time="2025-04-13T17:08:40+09:00" level=trace msg="emitting websocket event: paused" Apr 13 17:08:40 volumio volumio[955]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","play_origin":"go-librespot"}} Apr 13 17:08:40 volumio volumio[955]: SPOTIFY: PUSH STATE SPOTIFY Apr 13 17:08:40 volumio volumio[955]: 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} Apr 13 17:08:40 volumio volumio[955]: info: CoreCommandRouter::servicePushState Apr 13 17:08:40 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:40 volumio volumio[955]: 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} Apr 13 17:08:40 volumio volumio[955]: verbose: CURRENT POSITION 121 Apr 13 17:08:40 volumio volumio[955]: info: CoreStateMachine::syncState stateService pause Apr 13 17:08:40 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Apr 13 17:08:40 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:40 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:40 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 17:08:40 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:40 volumio volumio[955]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","play_origin":"go-librespot"}} Apr 13 17:08:40 volumio volumio[955]: SPOTIFY: PUSH STATE SPOTIFY Apr 13 17:08:40 volumio volumio[955]: 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} Apr 13 17:08:40 volumio volumio[955]: info: CoreCommandRouter::servicePushState Apr 13 17:08:40 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:40 volumio volumio[955]: 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} Apr 13 17:08:40 volumio volumio[955]: verbose: CURRENT POSITION 121 Apr 13 17:08:40 volumio volumio[955]: info: CoreStateMachine::syncState stateService pause Apr 13 17:08:40 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Apr 13 17:08:40 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:40 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:40 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:40 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:40 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:40 volumio go-librespot[1308]: time="2025-04-13T17:08:40+09:00" level=debug msg="resolved context of track" uri="spotify:track:5SXF0cKaxsWJYDFMbXGvoA" Apr 13 17:08:40 volumio go-librespot[1308]: time="2025-04-13T17:08:40+09:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:5SXF0cKaxsWJYDFMbXGvoA" Apr 13 17:08:40 volumio go-librespot[1308]: time="2025-04-13T17:08:40+09:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:5SXF0cKaxsWJYDFMbXGvoA" Apr 13 17:08:41 volumio go-librespot[1308]: time="2025-04-13T17:08:41+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 13 17:08:41 volumio go-librespot[1308]: time="2025-04-13T17:08:41+09:00" level=trace msg="emitting websocket event: will_play" Apr 13 17:08:41 volumio volumio[955]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","play_origin":"go-librespot"}} Apr 13 17:08:41 volumio volumio[955]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","play_origin":"go-librespot"}} Apr 13 17:08:41 volumio go-librespot[1308]: time="2025-04-13T17:08:41+09:00" level=debug msg="selected format OGG_VORBIS_320 (ec3bd987de6ff93cc3bf9f60aa75cc121cf54616)" uri="spotify:track:5SXF0cKaxsWJYDFMbXGvoA" Apr 13 17:08:41 volumio go-librespot[1308]: time="2025-04-13T17:08:41+09:00" level=debug msg="requested aes key for file ec3bd987de6ff93cc3bf9f60aa75cc121cf54616, gid: 5SXF0cKaxsWJYDFMbXGvoA" Apr 13 17:08:41 volumio wpa_supplicant[871]: RRM: Ignoring radio measurement request: Not RRM network Apr 13 17:08:41 volumio go-librespot[1308]: time="2025-04-13T17:08:41+09:00" level=debug msg="fetched first chunk of 17, total size is 8870772 bytes" uri="spotify:track:5SXF0cKaxsWJYDFMbXGvoA" Apr 13 17:08:41 volumio go-librespot[1308]: time="2025-04-13T17:08:41+09:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames" Apr 13 17:08:41 volumio go-librespot[1308]: time="2025-04-13T17:08:41+09:00" level=info msg="loaded track \"み む かゥ わ ナ イ ス ト ラ イ\" (paused: false, position: 0ms, duration: 205069ms, prefetched: false)" uri="spotify:track:5SXF0cKaxsWJYDFMbXGvoA" Apr 13 17:08:41 volumio go-librespot[1308]: time="2025-04-13T17:08:41+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 13 17:08:41 volumio go-librespot[1308]: time="2025-04-13T17:08:41+09:00" level=trace msg="scheduling prefetch in 174s" Apr 13 17:08:41 volumio go-librespot[1308]: time="2025-04-13T17:08:41+09:00" level=trace msg="emitting websocket event: metadata" Apr 13 17:08:41 volumio volumio[955]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","name":"み む かゥ わ ナ イ ス ト ラ イ","artist_names":["nunununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununu"],"album_name":"み む かゥ わ ナ イ ス ト ラ イ","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0256148bda4780e3e8d67162a8","position":0,"duration":205069,"release_date":"year:2024 month:12 day:10","track_number":1,"disc_number":1}} Apr 13 17:08:41 volumio volumio[955]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","name":"み む かゥ わ ナ イ ス ト ラ イ","artist_names":["nunununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununu"],"album_name":"み む かゥ わ ナ イ ス ト ラ イ","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0256148bda4780e3e8d67162a8","position":0,"duration":205069,"release_date":"year:2024 month:12 day:10","track_number":1,"disc_number":1}} Apr 13 17:08:42 volumio go-librespot[1308]: time="2025-04-13T17:08:42+09:00" level=debug msg="fetched chunk 2/16, size: 524288" uri="spotify:track:5SXF0cKaxsWJYDFMbXGvoA" Apr 13 17:08:42 volumio go-librespot[1308]: time="2025-04-13T17:08:42+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 13 17:08:42 volumio go-librespot[1308]: time="2025-04-13T17:08:42+09:00" level=trace msg="emitting websocket event: playing" Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","play_origin":"go-librespot"}} Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: PUSH STATE SPOTIFY Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: {"status":"play","service":"spop","title":"み む かゥ わ ナ イ ス ト ラ イ","artist":"nunununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununu","album":"み む かゥ わ ナ イ ス ト ラ イ","albumart":"https://i.scdn.co/image/ab67616d00001e0256148bda4780e3e8d67162a8","uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","trackType":"spotify","seek":0,"duration":205,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:42 volumio volumio[955]: info: CoreCommandRouter::servicePushState Apr 13 17:08:42 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:42 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"み む かゥ わ ナ イ ス ト ラ イ","artist":"nunununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununu","album":"み む かゥ わ ナ イ ス ト ラ イ","albumart":"https://i.scdn.co/image/ab67616d00001e0256148bda4780e3e8d67162a8","uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","trackType":"spotify","seek":0,"duration":205,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:42 volumio volumio[955]: verbose: CURRENT POSITION 121 Apr 13 17:08:42 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Apr 13 17:08:42 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","play_origin":"go-librespot"}} Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: PUSH STATE SPOTIFY Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: {"status":"play","service":"spop","title":"み む かゥ わ ナ イ ス ト ラ イ","artist":"nunununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununu","album":"み む かゥ わ ナ イ ス ト ラ イ","albumart":"https://i.scdn.co/image/ab67616d00001e0256148bda4780e3e8d67162a8","uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","trackType":"spotify","seek":0,"duration":205,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:42 volumio volumio[955]: info: CoreCommandRouter::servicePushState Apr 13 17:08:42 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:42 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"み む かゥ わ ナ イ ス ト ラ イ","artist":"nunununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununu","album":"み む かゥ わ ナ イ ス ト ラ イ","albumart":"https://i.scdn.co/image/ab67616d00001e0256148bda4780e3e8d67162a8","uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","trackType":"spotify","seek":0,"duration":205,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:42 volumio volumio[955]: verbose: CURRENT POSITION 121 Apr 13 17:08:42 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Apr 13 17:08:42 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Apr 13 17:08:42 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Apr 13 17:08:42 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:42 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:42 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 17:08:42 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:42 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:42 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:42 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:42 volumio go-librespot[1308]: time="2025-04-13T17:08:42+09:00" level=debug msg="fetched chunk 1/16, size: 524288" uri="spotify:track:5SXF0cKaxsWJYDFMbXGvoA" Apr 13 17:08:42 volumio go-librespot[1308]: time="2025-04-13T17:08:42+09:00" level=debug msg="fetched chunk 3/16, size: 524288" uri="spotify:track:5SXF0cKaxsWJYDFMbXGvoA" Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: PUSH STATE SPOTIFY Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: {"status":"play","service":"spop","title":"み む かゥ わ ナ イ ス ト ラ イ","artist":"nunununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununu","album":"み む かゥ わ ナ イ ス ト ラ イ","albumart":"https://i.scdn.co/image/ab67616d00001e0256148bda4780e3e8d67162a8","uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","trackType":"spotify","seek":0,"duration":205,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:42 volumio volumio[955]: info: CoreCommandRouter::servicePushState Apr 13 17:08:42 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:42 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"み む かゥ わ ナ イ ス ト ラ イ","artist":"nunununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununu","album":"み む かゥ わ ナ イ ス ト ラ イ","albumart":"https://i.scdn.co/image/ab67616d00001e0256148bda4780e3e8d67162a8","uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","trackType":"spotify","seek":0,"duration":205,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:42 volumio volumio[955]: verbose: CURRENT POSITION 121 Apr 13 17:08:42 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Apr 13 17:08:42 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Apr 13 17:08:42 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Apr 13 17:08:42 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:42 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:42 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:42 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:42 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:42 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: PUSH STATE SPOTIFY Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: {"status":"play","service":"spop","title":"み む かゥ わ ナ イ ス ト ラ イ","artist":"nunununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununu","album":"み む かゥ わ ナ イ ス ト ラ イ","albumart":"https://i.scdn.co/image/ab67616d00001e0256148bda4780e3e8d67162a8","uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","trackType":"spotify","seek":0,"duration":205,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:42 volumio volumio[955]: info: CoreCommandRouter::servicePushState Apr 13 17:08:42 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:42 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"み む かゥ わ ナ イ ス ト ラ イ","artist":"nunununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununu","album":"み む かゥ わ ナ イ ス ト ラ イ","albumart":"https://i.scdn.co/image/ab67616d00001e0256148bda4780e3e8d67162a8","uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","trackType":"spotify","seek":0,"duration":205,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:42 volumio volumio[955]: verbose: CURRENT POSITION 121 Apr 13 17:08:42 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Apr 13 17:08:42 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Apr 13 17:08:42 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Apr 13 17:08:42 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:42 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:42 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:42 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:42 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:42 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:42 volumio volumio[955]: info: CoreCommandRouter::volumioPrevious Apr 13 17:08:42 volumio volumio[955]: info: CoreStateMachine::previous Apr 13 17:08:42 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:42 volumio volumio[955]: info: Spotify seek to: 0 Apr 13 17:08:42 volumio volumio[955]: info: Sending Spotify command with payload to local API: /player/seek Apr 13 17:08:42 volumio go-librespot[1308]: time="2025-04-13T17:08:42+09:00" level=debug msg="seek track to 0ms" Apr 13 17:08:42 volumio go-librespot[1308]: time="2025-04-13T17:08:42+09:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:5SXF0cKaxsWJYDFMbXGvoA" Apr 13 17:08:42 volumio go-librespot[1308]: time="2025-04-13T17:08:42+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 13 17:08:42 volumio go-librespot[1308]: time="2025-04-13T17:08:42+09:00" level=warning msg="failed getting output device delay" error="ALSA error at snd_pcm_delay: Broken pipe" Apr 13 17:08:42 volumio go-librespot[1308]: time="2025-04-13T17:08:42+09:00" level=trace msg="scheduling prefetch in 175s" Apr 13 17:08:42 volumio go-librespot[1308]: time="2025-04-13T17:08:42+09:00" level=trace msg="emitting websocket event: seek" Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: received: {"type":"seek","data":{"uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","position":0,"duration":205069,"play_origin":"go-librespot"}} Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: PUSH STATE SPOTIFY Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: {"status":"play","service":"spop","title":"み む かゥ わ ナ イ ス ト ラ イ","artist":"nunununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununu","album":"み む かゥ わ ナ イ ス ト ラ イ","albumart":"https://i.scdn.co/image/ab67616d00001e0256148bda4780e3e8d67162a8","uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","trackType":"spotify","seek":0,"duration":205,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:42 volumio volumio[955]: info: CoreCommandRouter::servicePushState Apr 13 17:08:42 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:42 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"み む かゥ わ ナ イ ス ト ラ イ","artist":"nunununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununu","album":"み む かゥ わ ナ イ ス ト ラ イ","albumart":"https://i.scdn.co/image/ab67616d00001e0256148bda4780e3e8d67162a8","uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","trackType":"spotify","seek":0,"duration":205,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:42 volumio volumio[955]: verbose: CURRENT POSITION 121 Apr 13 17:08:42 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Apr 13 17:08:42 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Apr 13 17:08:42 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Apr 13 17:08:42 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:42 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:42 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:42 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:42 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:42 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: received: {"type":"seek","data":{"uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","position":0,"duration":205069,"play_origin":"go-librespot"}} Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: PUSH STATE SPOTIFY Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: {"status":"play","service":"spop","title":"み む かゥ わ ナ イ ス ト ラ イ","artist":"nunununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununu","album":"み む かゥ わ ナ イ ス ト ラ イ","albumart":"https://i.scdn.co/image/ab67616d00001e0256148bda4780e3e8d67162a8","uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","trackType":"spotify","seek":0,"duration":205,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:42 volumio volumio[955]: info: CoreCommandRouter::servicePushState Apr 13 17:08:42 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:42 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"み む かゥ わ ナ イ ス ト ラ イ","artist":"nunununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununu","album":"み む かゥ わ ナ イ ス ト ラ イ","albumart":"https://i.scdn.co/image/ab67616d00001e0256148bda4780e3e8d67162a8","uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","trackType":"spotify","seek":0,"duration":205,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:42 volumio volumio[955]: verbose: CURRENT POSITION 121 Apr 13 17:08:42 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Apr 13 17:08:42 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Apr 13 17:08:42 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Apr 13 17:08:42 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:42 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:42 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:42 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:42 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:42 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:42 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:43 volumio volumio[955]: info: CoreCommandRouter::volumioPrevious Apr 13 17:08:43 volumio volumio[955]: info: CoreStateMachine::previous Apr 13 17:08:43 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:43 volumio volumio[955]: info: CoreStateMachine::stop Apr 13 17:08:43 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 17:08:43 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Apr 13 17:08:43 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Apr 13 17:08:43 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Apr 13 17:08:43 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:43 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:43 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 17:08:43 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:43 volumio volumio[955]: info: CoreStateMachine::serviceStop Apr 13 17:08:43 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:43 volumio volumio[955]: info: CoreCommandRouter::serviceStop Apr 13 17:08:43 volumio volumio[955]: info: Spotify Stop Apr 13 17:08:43 volumio volumio[955]: SPOTIFY: SPOTIFY STOP Apr 13 17:08:43 volumio volumio[955]: SPOTIFY: {"status":"play","position":121,"title":"み む かゥ わ ナ イ ス ト ラ イ","artist":"nunununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununu","album":"み む かゥ わ ナ イ ス ト ラ イ","albumart":"https://i.scdn.co/image/ab67616d0000b27356148bda4780e3e8d67162a8","uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","trackType":"spotify","codec":"ogg","seek":0,"duration":205,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":true,"repeat":null,"repeatSingle":false,"consume":false,"volume":69,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Apr 13 17:08:43 volumio volumio[955]: info: Sending Spotify command to local API: /player/pause Apr 13 17:08:43 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:43 volumio go-librespot[1308]: time="2025-04-13T17:08:43+09:00" level=debug msg="pause track at 741ms" Apr 13 17:08:43 volumio go-librespot[1308]: time="2025-04-13T17:08:43+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 13 17:08:43 volumio go-librespot[1308]: time="2025-04-13T17:08:43+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 13 17:08:43 volumio go-librespot[1308]: time="2025-04-13T17:08:43+09:00" level=trace msg="emitting websocket event: paused" Apr 13 17:08:43 volumio volumio[955]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","play_origin":"go-librespot"}} Apr 13 17:08:43 volumio volumio[955]: SPOTIFY: PUSH STATE SPOTIFY Apr 13 17:08:43 volumio volumio[955]: SPOTIFY: {"status":"pause","service":"spop","title":"み む かゥ わ ナ イ ス ト ラ イ","artist":"nunununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununu","album":"み む かゥ わ ナ イ ス ト ラ イ","albumart":"https://i.scdn.co/image/ab67616d00001e0256148bda4780e3e8d67162a8","uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","trackType":"spotify","seek":1000,"duration":205,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:43 volumio volumio[955]: info: CoreCommandRouter::servicePushState Apr 13 17:08:43 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:43 volumio volumio[955]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"み む かゥ わ ナ イ ス ト ラ イ","artist":"nunununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununu","album":"み む かゥ わ ナ イ ス ト ラ イ","albumart":"https://i.scdn.co/image/ab67616d00001e0256148bda4780e3e8d67162a8","uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","trackType":"spotify","seek":1000,"duration":205,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:43 volumio volumio[955]: verbose: CURRENT POSITION 121 Apr 13 17:08:43 volumio volumio[955]: info: CoreStateMachine::syncState stateService pause Apr 13 17:08:43 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Apr 13 17:08:43 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:43 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:43 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 17:08:43 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:43 volumio volumio[955]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","play_origin":"go-librespot"}} Apr 13 17:08:43 volumio volumio[955]: SPOTIFY: PUSH STATE SPOTIFY Apr 13 17:08:43 volumio volumio[955]: SPOTIFY: {"status":"pause","service":"spop","title":"み む かゥ わ ナ イ ス ト ラ イ","artist":"nunununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununu","album":"み む かゥ わ ナ イ ス ト ラ イ","albumart":"https://i.scdn.co/image/ab67616d00001e0256148bda4780e3e8d67162a8","uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","trackType":"spotify","seek":1000,"duration":205,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:43 volumio volumio[955]: info: CoreCommandRouter::servicePushState Apr 13 17:08:43 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:43 volumio volumio[955]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"み む かゥ わ ナ イ ス ト ラ イ","artist":"nunununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununununu","album":"み む かゥ わ ナ イ ス ト ラ イ","albumart":"https://i.scdn.co/image/ab67616d00001e0256148bda4780e3e8d67162a8","uri":"spotify:track:5SXF0cKaxsWJYDFMbXGvoA","trackType":"spotify","seek":1000,"duration":205,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:43 volumio volumio[955]: verbose: CURRENT POSITION 121 Apr 13 17:08:43 volumio volumio[955]: info: CoreStateMachine::syncState stateService pause Apr 13 17:08:43 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Apr 13 17:08:43 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:43 volumio volumio[955]: info: CorePlayQueue::getTrack 121 Apr 13 17:08:43 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:43 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:43 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:43 volumio volumio[955]: info: CoreStateMachine::play index undefined Apr 13 17:08:43 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 17:08:43 volumio volumio[955]: info: CorePlayQueue::getTrack 34 Apr 13 17:08:43 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Apr 13 17:08:43 volumio volumio[955]: info: CorePlayQueue::getTrack 34 Apr 13 17:08:43 volumio volumio[955]: info: [1744531723737] ControllerSpotify::clearAddPlayTrack Apr 13 17:08:43 volumio volumio[955]: info: Sending Spotify command with payload to local API: /player/play Apr 13 17:08:43 volumio volumio[955]: info: CoreCommandRouter::volumioPrevious Apr 13 17:08:43 volumio volumio[955]: info: CoreStateMachine::previous Apr 13 17:08:43 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Apr 13 17:08:43 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Apr 13 17:08:43 volumio volumio[955]: info: CoreStateMachine::serviceClearAddPlay Apr 13 17:08:43 volumio volumio[955]: info: CoreCommandRouter::serviceClearAddPlayTracks Apr 13 17:08:43 volumio volumio[955]: error: WARNING: No clearAddPlayTracks method for service spop Apr 13 17:08:43 volumio go-librespot[1308]: time="2025-04-13T17:08:43+09:00" level=debug msg="resolved context of track" uri="spotify:track:7b4fxkOA4NDr4tDylrb10K" Apr 13 17:08:43 volumio go-librespot[1308]: time="2025-04-13T17:08:43+09:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:7b4fxkOA4NDr4tDylrb10K" Apr 13 17:08:43 volumio go-librespot[1308]: time="2025-04-13T17:08:43+09:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:7b4fxkOA4NDr4tDylrb10K" Apr 13 17:08:44 volumio go-librespot[1308]: time="2025-04-13T17:08:44+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 13 17:08:44 volumio go-librespot[1308]: time="2025-04-13T17:08:44+09:00" level=trace msg="emitting websocket event: will_play" Apr 13 17:08:44 volumio volumio[955]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7b4fxkOA4NDr4tDylrb10K","play_origin":"go-librespot"}} Apr 13 17:08:44 volumio volumio[955]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7b4fxkOA4NDr4tDylrb10K","play_origin":"go-librespot"}} Apr 13 17:08:44 volumio go-librespot[1308]: time="2025-04-13T17:08:44+09:00" level=debug msg="selected format OGG_VORBIS_320 (42192cc22583bc88a15c9c58e1a820bdb5bac31e)" uri="spotify:track:7b4fxkOA4NDr4tDylrb10K" Apr 13 17:08:44 volumio go-librespot[1308]: time="2025-04-13T17:08:44+09:00" level=debug msg="requested aes key for file 42192cc22583bc88a15c9c58e1a820bdb5bac31e, gid: 7b4fxkOA4NDr4tDylrb10K" Apr 13 17:08:44 volumio volumio[955]: info: CoreCommandRouter::volumioPrevious Apr 13 17:08:44 volumio volumio[955]: info: CoreStateMachine::previous Apr 13 17:08:44 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Apr 13 17:08:44 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Apr 13 17:08:44 volumio volumio[955]: info: CoreStateMachine::serviceClearAddPlay Apr 13 17:08:44 volumio volumio[955]: info: CoreCommandRouter::serviceClearAddPlayTracks Apr 13 17:08:44 volumio volumio[955]: error: WARNING: No clearAddPlayTracks method for service spop Apr 13 17:08:44 volumio go-librespot[1308]: time="2025-04-13T17:08:44+09:00" level=debug msg="fetched first chunk of 16, total size is 7907104 bytes" uri="spotify:track:7b4fxkOA4NDr4tDylrb10K" Apr 13 17:08:44 volumio go-librespot[1308]: time="2025-04-13T17:08:44+09:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames" Apr 13 17:08:44 volumio go-librespot[1308]: time="2025-04-13T17:08:44+09:00" level=info msg="loaded track \"Overdose\" (paused: false, position: 0ms, duration: 194106ms, prefetched: false)" uri="spotify:track:7b4fxkOA4NDr4tDylrb10K" Apr 13 17:08:44 volumio go-librespot[1308]: time="2025-04-13T17:08:44+09:00" level=debug msg="fetched chunk 2/15, size: 524288" uri="spotify:track:7b4fxkOA4NDr4tDylrb10K" Apr 13 17:08:44 volumio go-librespot[1308]: time="2025-04-13T17:08:44+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 13 17:08:44 volumio go-librespot[1308]: time="2025-04-13T17:08:44+09:00" level=trace msg="scheduling prefetch in 164s" Apr 13 17:08:44 volumio go-librespot[1308]: time="2025-04-13T17:08:44+09:00" level=trace msg="emitting websocket event: metadata" Apr 13 17:08:44 volumio volumio[955]: info: CoreCommandRouter::volumioPrevious Apr 13 17:08:44 volumio volumio[955]: info: CoreStateMachine::previous Apr 13 17:08:44 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Apr 13 17:08:44 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Apr 13 17:08:44 volumio volumio[955]: info: CoreStateMachine::serviceClearAddPlay Apr 13 17:08:44 volumio volumio[955]: info: CoreCommandRouter::serviceClearAddPlayTracks Apr 13 17:08:44 volumio volumio[955]: error: WARNING: No clearAddPlayTracks method for service spop Apr 13 17:08:44 volumio volumio[955]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7b4fxkOA4NDr4tDylrb10K","name":"Overdose","artist_names":["natori"],"album_name":"劇場","album_cover_url":"https://i.scdn.co/image/ab67616d00001e020ac0f324b55a0e56b62670ca","position":0,"duration":194106,"release_date":"year:2023 month:12 day:20","track_number":4,"disc_number":1}} Apr 13 17:08:44 volumio volumio[955]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7b4fxkOA4NDr4tDylrb10K","name":"Overdose","artist_names":["natori"],"album_name":"劇場","album_cover_url":"https://i.scdn.co/image/ab67616d00001e020ac0f324b55a0e56b62670ca","position":0,"duration":194106,"release_date":"year:2023 month:12 day:20","track_number":4,"disc_number":1}} Apr 13 17:08:44 volumio go-librespot[1308]: time="2025-04-13T17:08:44+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 13 17:08:44 volumio go-librespot[1308]: time="2025-04-13T17:08:44+09:00" level=trace msg="emitting websocket event: playing" Apr 13 17:08:44 volumio volumio[955]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:7b4fxkOA4NDr4tDylrb10K","play_origin":"go-librespot"}} Apr 13 17:08:44 volumio volumio[955]: SPOTIFY: PUSH STATE SPOTIFY Apr 13 17:08:44 volumio volumio[955]: SPOTIFY: {"status":"play","service":"spop","title":"Overdose","artist":"natori","album":"劇場","albumart":"https://i.scdn.co/image/ab67616d00001e020ac0f324b55a0e56b62670ca","uri":"spotify:track:7b4fxkOA4NDr4tDylrb10K","trackType":"spotify","seek":0,"duration":194,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:44 volumio volumio[955]: info: CoreCommandRouter::servicePushState Apr 13 17:08:44 volumio volumio[955]: info: CorePlayQueue::getTrack 126 Apr 13 17:08:44 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Overdose","artist":"natori","album":"劇場","albumart":"https://i.scdn.co/image/ab67616d00001e020ac0f324b55a0e56b62670ca","uri":"spotify:track:7b4fxkOA4NDr4tDylrb10K","trackType":"spotify","seek":0,"duration":194,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:44 volumio volumio[955]: verbose: CURRENT POSITION 126 Apr 13 17:08:44 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Apr 13 17:08:44 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Apr 13 17:08:44 volumio volumio[955]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:7b4fxkOA4NDr4tDylrb10K","play_origin":"go-librespot"}} Apr 13 17:08:44 volumio volumio[955]: SPOTIFY: PUSH STATE SPOTIFY Apr 13 17:08:44 volumio volumio[955]: SPOTIFY: {"status":"play","service":"spop","title":"Overdose","artist":"natori","album":"劇場","albumart":"https://i.scdn.co/image/ab67616d00001e020ac0f324b55a0e56b62670ca","uri":"spotify:track:7b4fxkOA4NDr4tDylrb10K","trackType":"spotify","seek":0,"duration":194,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:44 volumio volumio[955]: info: CoreCommandRouter::servicePushState Apr 13 17:08:44 volumio volumio[955]: info: CorePlayQueue::getTrack 126 Apr 13 17:08:44 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Overdose","artist":"natori","album":"劇場","albumart":"https://i.scdn.co/image/ab67616d00001e020ac0f324b55a0e56b62670ca","uri":"spotify:track:7b4fxkOA4NDr4tDylrb10K","trackType":"spotify","seek":0,"duration":194,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 13 17:08:44 volumio volumio[955]: verbose: CURRENT POSITION 126 Apr 13 17:08:44 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Apr 13 17:08:44 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Apr 13 17:08:44 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Apr 13 17:08:44 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:44 volumio volumio[955]: info: CorePlayQueue::getTrack 126 Apr 13 17:08:44 volumio go-librespot[1308]: time="2025-04-13T17:08:44+09:00" level=debug msg="fetched chunk 3/15, size: 524288" uri="spotify:track:7b4fxkOA4NDr4tDylrb10K" Apr 13 17:08:44 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 17:08:44 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:44 volumio volumio[955]: info: CoreStateMachine::pushState Apr 13 17:08:44 volumio volumio[955]: info: CorePlayQueue::getTrack 126 Apr 13 17:08:44 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Apr 13 17:08:44 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:44 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Apr 13 17:08:44 volumio go-librespot[1308]: time="2025-04-13T17:08:44+09:00" level=debug msg="fetched chunk 1/15, size: 524288" uri="spotify:track:7b4fxkOA4NDr4tDylrb10K" Apr 13 17:08:44 volumio volumio[955]: info: CoreCommandRouter::volumioPrevious Apr 13 17:08:44 volumio volumio[955]: info: CoreStateMachine::previous Apr 13 17:08:44 volumio volumio[955]: info: CorePlayQueue::getTrack 126 Apr 13 17:08:44 volumio volumio[955]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 17:08:44 volumio volumio[955]: TypeError: Cannot read property 'service' of undefined Apr 13 17:08:44 volumio volumio[955]: at CoreStateMachine.previous (/volumio/app/statemachine.js:1322:97) Apr 13 17:08:44 volumio volumio[955]: at CoreCommandRouter.volumioPrevious (/volumio/app/index.js:95:28) Apr 13 17:08:44 volumio volumio[955]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:270:33) Apr 13 17:08:44 volumio volumio[955]: at Socket.emit (events.js:315:20) Apr 13 17:08:44 volumio volumio[955]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Apr 13 17:08:44 volumio volumio[955]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 13 17:08:44 volumio volumio[955]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 17:08:45 volumio sudo[1335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-13 17:07 Apr 13 17:08:45 volumio sudo[1335]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"