-- Logs begin at Sun 2024-04-14 17:39:36 +07, end at Sun 2024-04-14 20:01:05 +07. -- Apr 14 20:00:04 volumio go-librespot[1085]: time="2024-04-14T20:00:04+07:00" level=debug msg="handling update_context player command from 02b23d1f477c7f77936df00ecb181e74e6d3a971" Apr 14 20:00:04 volumio go-librespot[1085]: time="2024-04-14T20:00:04+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 14 20:00:04 volumio go-librespot[1085]: time="2024-04-14T20:00:04+07:00" level=debug msg="sending successful reply for delaer request" Apr 14 20:00:05 volumio go-librespot[1085]: time="2024-04-14T20:00:05+07:00" level=debug msg="handling play player command from 02b23d1f477c7f77936df00ecb181e74e6d3a971" Apr 14 20:00:05 volumio go-librespot[1085]: time="2024-04-14T20:00:05+07:00" level=trace msg="fetched new page 0 with 190 items (list: 190)" Apr 14 20:00:05 volumio go-librespot[1085]: time="2024-04-14T20:00:05+07:00" level=debug msg="loading track spotify:track:1T64TCULwFXraLKRGGThw9 (paused: false, position: 230ms)" Apr 14 20:00:05 volumio go-librespot[1085]: time="2024-04-14T20:00:05+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 14 20:00:05 volumio go-librespot[1085]: time="2024-04-14T20:00:05+07:00" level=trace msg="emitting websocket event: will_play" Apr 14 20:00:05 volumio volumio[918]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1T64TCULwFXraLKRGGThw9","play_origin":"playlist"}} Apr 14 20:00:05 volumio go-librespot[1085]: time="2024-04-14T20:00:05+07:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:1T64TCULwFXraLKRGGThw9" Apr 14 20:00:05 volumio go-librespot[1085]: time="2024-04-14T20:00:05+07:00" level=debug msg="requested aes key for file 00e3520c48516dedfbc5ecda8af2d7d7f8835aed, gid: 1T64TCULwFXraLKRGGThw9" Apr 14 20:00:06 volumio go-librespot[1085]: time="2024-04-14T20:00:06+07:00" level=debug msg="fetched first chunk of 19, total size is 9729436 bytes" Apr 14 20:00:06 volumio go-librespot[1085]: time="2024-04-14T20:00:06+07:00" level=trace msg="seek to 230ms (diff: 230ms, samples: 10143, bytes: 0)" Apr 14 20:00:06 volumio go-librespot[1085]: time="2024-04-14T20:00:06+07:00" level=info msg="loaded track \"Mưa tuyết\" (uri: spotify:track:1T64TCULwFXraLKRGGThw9, paused: false, position: 230ms, duration: 250320ms)" Apr 14 20:00:06 volumio go-librespot[1085]: time="2024-04-14T20:00:06+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 14 20:00:06 volumio go-librespot[1085]: time="2024-04-14T20:00:06+07:00" level=trace msg="emitting websocket event: metadata" Apr 14 20:00:06 volumio go-librespot[1085]: time="2024-04-14T20:00:06+07:00" level=debug msg="sending successful reply for delaer request" Apr 14 20:00:06 volumio volumio[918]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1T64TCULwFXraLKRGGThw9","name":"Mưa tuyết","artist_names":["Jimmi Nguyen"],"album_name":"Tưởng đã phôi phai","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0245aec48c9f32fc60803fb7d3","position":230,"duration":250320,"release_date":"year:2000 month:1 day:1","track_number":6,"disc_number":1}} Apr 14 20:00:06 volumio go-librespot[1085]: time="2024-04-14T20:00:06+07:00" level=debug msg="fetched chunk 1/18, size: 524288" Apr 14 20:00:06 volumio go-librespot[1085]: time="2024-04-14T20:00:06+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 14 20:00:06 volumio go-librespot[1085]: time="2024-04-14T20:00:06+07:00" level=trace msg="emitting websocket event: playing" Apr 14 20:00:06 volumio volumio[918]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:1T64TCULwFXraLKRGGThw9","play_origin":"playlist"}} Apr 14 20:00:06 volumio volumio[918]: SPOTIFY: PUSH STATE SPOTIFY Apr 14 20:00:06 volumio volumio[918]: SPOTIFY: {"status":"play","service":"spop","title":"Mưa tuyết","artist":"Jimmi Nguyen","album":"Tưởng đã phôi phai","albumart":"https://i.scdn.co/image/ab67616d00001e0245aec48c9f32fc60803fb7d3","uri":"spotify:track:1T64TCULwFXraLKRGGThw9","trackType":"spotify","seek":1230,"duration":250,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":false,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 14 20:00:06 volumio volumio[918]: info: CoreCommandRouter::servicePushState Apr 14 20:00:06 volumio volumio[918]: info: CoreStateMachine::pushState Apr 14 20:00:06 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:00:06 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Apr 14 20:00:06 volumio go-librespot[1085]: time="2024-04-14T20:00:06+07:00" level=debug msg="fetched chunk 3/18, size: 524288" Apr 14 20:00:06 volumio go-librespot[1085]: time="2024-04-14T20:00:06+07:00" level=debug msg="fetched chunk 2/18, size: 524288" Apr 14 20:00:06 volumio volumio[918]: SPOTIFY: PUSH STATE SPOTIFY Apr 14 20:00:06 volumio volumio[918]: SPOTIFY: {"status":"play","service":"spop","title":"Mưa tuyết","artist":"Jimmi Nguyen","album":"Tưởng đã phôi phai","albumart":"https://i.scdn.co/image/ab67616d00001e0245aec48c9f32fc60803fb7d3","uri":"spotify:track:1T64TCULwFXraLKRGGThw9","trackType":"spotify","seek":1230,"duration":250,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":false,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 14 20:00:06 volumio volumio[918]: info: CoreCommandRouter::servicePushState Apr 14 20:00:06 volumio volumio[918]: info: CoreStateMachine::pushState Apr 14 20:00:06 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Apr 14 20:00:11 volumio go-librespot[1085]: time="2024-04-14T20:00:11+07:00" level=debug msg="handling play player command from 02b23d1f477c7f77936df00ecb181e74e6d3a971" Apr 14 20:00:11 volumio go-librespot[1085]: time="2024-04-14T20:00:11+07:00" level=trace msg="fetched new page 0 with 6 items (list: 6)" Apr 14 20:00:12 volumio go-librespot[1085]: time="2024-04-14T20:00:12+07:00" level=debug msg="loading track spotify:track:2tpl8TnVRVI8VA4lBf4aoz (paused: false, position: 194ms)" Apr 14 20:00:12 volumio go-librespot[1085]: time="2024-04-14T20:00:12+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 14 20:00:12 volumio go-librespot[1085]: time="2024-04-14T20:00:12+07:00" level=trace msg="emitting websocket event: will_play" Apr 14 20:00:12 volumio volumio[918]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2tpl8TnVRVI8VA4lBf4aoz","play_origin":"playlist-recommended"}} Apr 14 20:00:12 volumio go-librespot[1085]: time="2024-04-14T20:00:12+07:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:2tpl8TnVRVI8VA4lBf4aoz" Apr 14 20:00:12 volumio go-librespot[1085]: time="2024-04-14T20:00:12+07:00" level=debug msg="requested aes key for file 618a18951e178e713c06b97cbe1d56a77d6ea72d, gid: 2tpl8TnVRVI8VA4lBf4aoz" Apr 14 20:00:12 volumio go-librespot[1085]: time="2024-04-14T20:00:12+07:00" level=debug msg="fetched first chunk of 18, total size is 9216688 bytes" Apr 14 20:00:12 volumio go-librespot[1085]: time="2024-04-14T20:00:12+07:00" level=trace msg="seek to 194ms (diff: 194ms, samples: 8555, bytes: 0)" Apr 14 20:00:12 volumio go-librespot[1085]: time="2024-04-14T20:00:12+07:00" level=info msg="loaded track \"Những Lời Dối Gian\" (uri: spotify:track:2tpl8TnVRVI8VA4lBf4aoz, paused: false, position: 194ms, duration: 219500ms)" Apr 14 20:00:12 volumio go-librespot[1085]: time="2024-04-14T20:00:12+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 14 20:00:12 volumio go-librespot[1085]: time="2024-04-14T20:00:12+07:00" level=trace msg="emitting websocket event: metadata" Apr 14 20:00:12 volumio go-librespot[1085]: time="2024-04-14T20:00:12+07:00" level=debug msg="sending successful reply for delaer request" Apr 14 20:00:12 volumio volumio[918]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2tpl8TnVRVI8VA4lBf4aoz","name":"Những Lời Dối Gian","artist_names":["Quốc Thiên"],"album_name":"Những Bài Hát Nhạc Hoa Lời Việt Hay Nhất Của Quốc Thiên","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02518c54d9fec692478541df9a","position":194,"duration":219500,"release_date":"year:2021 month:6 day:1","track_number":8,"disc_number":1}} Apr 14 20:00:12 volumio go-librespot[1085]: time="2024-04-14T20:00:12+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 14 20:00:12 volumio go-librespot[1085]: time="2024-04-14T20:00:12+07:00" level=trace msg="emitting websocket event: playing" Apr 14 20:00:12 volumio volumio[918]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:2tpl8TnVRVI8VA4lBf4aoz","play_origin":"playlist-recommended"}} Apr 14 20:00:12 volumio volumio[918]: SPOTIFY: PUSH STATE SPOTIFY Apr 14 20:00:12 volumio volumio[918]: SPOTIFY: {"status":"play","service":"spop","title":"Những Lời Dối Gian","artist":"Quốc Thiên","album":"Những Bài Hát Nhạc Hoa Lời Việt Hay Nhất Của Quốc Thiên","albumart":"https://i.scdn.co/image/ab67616d00001e02518c54d9fec692478541df9a","uri":"spotify:track:2tpl8TnVRVI8VA4lBf4aoz","trackType":"spotify","seek":194,"duration":219,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":false,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 14 20:00:12 volumio volumio[918]: info: CoreCommandRouter::servicePushState Apr 14 20:00:12 volumio volumio[918]: info: CoreStateMachine::pushState Apr 14 20:00:12 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:00:12 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Apr 14 20:00:12 volumio go-librespot[1085]: time="2024-04-14T20:00:12+07:00" level=debug msg="fetched chunk 2/17, size: 524288" Apr 14 20:00:13 volumio go-librespot[1085]: time="2024-04-14T20:00:13+07:00" level=debug msg="fetched chunk 1/17, size: 524288" Apr 14 20:00:13 volumio volumio[918]: SPOTIFY: PUSH STATE SPOTIFY Apr 14 20:00:13 volumio volumio[918]: SPOTIFY: {"status":"play","service":"spop","title":"Những Lời Dối Gian","artist":"Quốc Thiên","album":"Những Bài Hát Nhạc Hoa Lời Việt Hay Nhất Của Quốc Thiên","albumart":"https://i.scdn.co/image/ab67616d00001e02518c54d9fec692478541df9a","uri":"spotify:track:2tpl8TnVRVI8VA4lBf4aoz","trackType":"spotify","seek":194,"duration":219,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":false,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 14 20:00:13 volumio volumio[918]: info: CoreCommandRouter::servicePushState Apr 14 20:00:13 volumio volumio[918]: info: CoreStateMachine::pushState Apr 14 20:00:13 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Apr 14 20:00:13 volumio go-librespot[1085]: time="2024-04-14T20:00:13+07:00" level=debug msg="fetched chunk 3/17, size: 524288" Apr 14 20:00:16 volumio go-librespot[1085]: time="2024-04-14T20:00:16+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 258" Apr 14 20:00:16 volumio go-librespot[1085]: time="2024-04-14T20:00:16+07:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/2sWVAXHq3rouhZzwKiEXUg" Apr 14 20:00:18 volumio go-librespot[1085]: time="2024-04-14T20:00:18+07:00" level=debug msg="handling play player command from 02b23d1f477c7f77936df00ecb181e74e6d3a971" Apr 14 20:00:18 volumio go-librespot[1085]: time="2024-04-14T20:00:18+07:00" level=trace msg="fetched new page 0 with 191 items (list: 191)" Apr 14 20:00:18 volumio go-librespot[1085]: time="2024-04-14T20:00:18+07:00" level=debug msg="loading track spotify:track:0OdRzjD1FwnzrFqkKOjg3q (paused: false, position: 117ms)" Apr 14 20:00:19 volumio go-librespot[1085]: time="2024-04-14T20:00:19+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 14 20:00:19 volumio go-librespot[1085]: time="2024-04-14T20:00:19+07:00" level=trace msg="emitting websocket event: will_play" Apr 14 20:00:19 volumio volumio[918]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0OdRzjD1FwnzrFqkKOjg3q","play_origin":"playlist"}} Apr 14 20:00:19 volumio go-librespot[1085]: time="2024-04-14T20:00:19+07:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:0OdRzjD1FwnzrFqkKOjg3q" Apr 14 20:00:19 volumio go-librespot[1085]: time="2024-04-14T20:00:19+07:00" level=debug msg="requested aes key for file 28717e06f612dc581a1de67d3baeb660622fa8b7, gid: 0OdRzjD1FwnzrFqkKOjg3q" Apr 14 20:00:19 volumio go-librespot[1085]: time="2024-04-14T20:00:19+07:00" level=debug msg="fetched first chunk of 18, total size is 8981312 bytes" Apr 14 20:00:19 volumio go-librespot[1085]: time="2024-04-14T20:00:19+07:00" level=trace msg="seek to 117ms (diff: 117ms, samples: 5159, bytes: 0)" Apr 14 20:00:19 volumio go-librespot[1085]: time="2024-04-14T20:00:19+07:00" level=info msg="loaded track \"Em Rất Nhớ Anh\" (uri: spotify:track:0OdRzjD1FwnzrFqkKOjg3q, paused: false, position: 117ms, duration: 233377ms)" Apr 14 20:00:20 volumio go-librespot[1085]: time="2024-04-14T20:00:20+07:00" level=debug msg="fetched chunk 1/17, size: 524288" Apr 14 20:00:20 volumio go-librespot[1085]: time="2024-04-14T20:00:20+07:00" level=debug msg="fetched chunk 3/17, size: 524288" Apr 14 20:00:20 volumio go-librespot[1085]: time="2024-04-14T20:00:20+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 14 20:00:20 volumio go-librespot[1085]: time="2024-04-14T20:00:20+07:00" level=trace msg="emitting websocket event: metadata" Apr 14 20:00:20 volumio go-librespot[1085]: time="2024-04-14T20:00:20+07:00" level=debug msg="sending successful reply for delaer request" Apr 14 20:00:20 volumio volumio[918]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0OdRzjD1FwnzrFqkKOjg3q","name":"Em Rất Nhớ Anh","artist_names":["Vy Oanh"],"album_name":"Du Xuân","album_cover_url":"https://i.scdn.co/image/ab67616d00001e021ca05882448eb0948b24cdd4","position":117,"duration":233377,"release_date":"year:2018 month:4 day:20","track_number":14,"disc_number":1}} Apr 14 20:00:20 volumio go-librespot[1085]: time="2024-04-14T20:00:20+07:00" level=debug msg="fetched chunk 2/17, size: 524288" Apr 14 20:00:20 volumio go-librespot[1085]: time="2024-04-14T20:00:20+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 14 20:00:20 volumio go-librespot[1085]: time="2024-04-14T20:00:20+07:00" level=trace msg="emitting websocket event: playing" Apr 14 20:00:20 volumio volumio[918]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0OdRzjD1FwnzrFqkKOjg3q","play_origin":"playlist"}} Apr 14 20:00:20 volumio volumio[918]: SPOTIFY: PUSH STATE SPOTIFY Apr 14 20:00:20 volumio volumio[918]: SPOTIFY: {"status":"play","service":"spop","title":"Em Rất Nhớ Anh","artist":"Vy Oanh","album":"Du Xuân","albumart":"https://i.scdn.co/image/ab67616d00001e021ca05882448eb0948b24cdd4","uri":"spotify:track:0OdRzjD1FwnzrFqkKOjg3q","trackType":"spotify","seek":117,"duration":233,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":false,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 14 20:00:20 volumio volumio[918]: info: CoreCommandRouter::servicePushState Apr 14 20:00:20 volumio volumio[918]: info: CoreStateMachine::pushState Apr 14 20:00:20 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:00:20 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Apr 14 20:00:20 volumio volumio[918]: SPOTIFY: PUSH STATE SPOTIFY Apr 14 20:00:20 volumio volumio[918]: SPOTIFY: {"status":"play","service":"spop","title":"Em Rất Nhớ Anh","artist":"Vy Oanh","album":"Du Xuân","albumart":"https://i.scdn.co/image/ab67616d00001e021ca05882448eb0948b24cdd4","uri":"spotify:track:0OdRzjD1FwnzrFqkKOjg3q","trackType":"spotify","seek":1117,"duration":233,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":false,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 14 20:00:20 volumio volumio[918]: info: CoreCommandRouter::servicePushState Apr 14 20:00:20 volumio volumio[918]: info: CoreStateMachine::pushState Apr 14 20:00:20 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:00:20 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Apr 14 20:00:31 volumio go-librespot[1085]: time="2024-04-14T20:00:31+07:00" level=debug msg="fetched chunk 4/17, size: 524288" Apr 14 20:00:33 volumio ntpd[705]: Soliciting pool server 2400:8904::f03c:92ff:fe80:9549 Apr 14 20:00:35 volumio go-librespot[1085]: time="2024-04-14T20:00:35+07:00" level=debug msg="handling pause player command from 02b23d1f477c7f77936df00ecb181e74e6d3a971" Apr 14 20:00:35 volumio go-librespot[1085]: time="2024-04-14T20:00:35+07:00" level=debug msg="pause track at 16009ms" Apr 14 20:00:35 volumio go-librespot[1085]: time="2024-04-14T20:00:35+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 14 20:00:35 volumio go-librespot[1085]: time="2024-04-14T20:00:35+07:00" level=debug msg="sending successful reply for delaer request" Apr 14 20:00:35 volumio go-librespot[1085]: time="2024-04-14T20:00:35+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 14 20:00:35 volumio go-librespot[1085]: time="2024-04-14T20:00:35+07:00" level=trace msg="emitting websocket event: paused" Apr 14 20:00:35 volumio volumio[918]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:0OdRzjD1FwnzrFqkKOjg3q","play_origin":"playlist"}} Apr 14 20:00:35 volumio volumio[918]: SPOTIFY: PUSH STATE SPOTIFY Apr 14 20:00:35 volumio volumio[918]: SPOTIFY: {"status":"pause","service":"spop","title":"Em Rất Nhớ Anh","artist":"Vy Oanh","album":"Du Xuân","albumart":"https://i.scdn.co/image/ab67616d00001e021ca05882448eb0948b24cdd4","uri":"spotify:track:0OdRzjD1FwnzrFqkKOjg3q","trackType":"spotify","seek":16117,"duration":233,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":false,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 14 20:00:35 volumio volumio[918]: info: CoreCommandRouter::servicePushState Apr 14 20:00:35 volumio volumio[918]: info: CoreStateMachine::pushState Apr 14 20:00:35 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:00:35 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Apr 14 20:00:37 volumio go-librespot[1085]: time="2024-04-14T20:00:37+07:00" level=debug msg="handling resume player command from 02b23d1f477c7f77936df00ecb181e74e6d3a971" Apr 14 20:00:37 volumio go-librespot[1085]: time="2024-04-14T20:00:37+07:00" level=trace msg="seek to 16009ms (diff: 134ms, samples: 705996, bytes: 617415)" Apr 14 20:00:37 volumio go-librespot[1085]: time="2024-04-14T20:00:37+07:00" level=debug msg="resume track at 15504ms" Apr 14 20:00:37 volumio go-librespot[1085]: time="2024-04-14T20:00:37+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 14 20:00:37 volumio go-librespot[1085]: time="2024-04-14T20:00:37+07:00" level=debug msg="sending successful reply for delaer request" Apr 14 20:00:37 volumio go-librespot[1085]: time="2024-04-14T20:00:37+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 14 20:00:37 volumio go-librespot[1085]: time="2024-04-14T20:00:37+07:00" level=trace msg="emitting websocket event: playing" Apr 14 20:00:37 volumio volumio[918]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0OdRzjD1FwnzrFqkKOjg3q","play_origin":"playlist"}} Apr 14 20:00:37 volumio volumio[918]: SPOTIFY: PUSH STATE SPOTIFY Apr 14 20:00:37 volumio volumio[918]: SPOTIFY: {"status":"play","service":"spop","title":"Em Rất Nhớ Anh","artist":"Vy Oanh","album":"Du Xuân","albumart":"https://i.scdn.co/image/ab67616d00001e021ca05882448eb0948b24cdd4","uri":"spotify:track:0OdRzjD1FwnzrFqkKOjg3q","trackType":"spotify","seek":16117,"duration":233,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":false,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 14 20:00:37 volumio volumio[918]: info: CoreCommandRouter::servicePushState Apr 14 20:00:37 volumio volumio[918]: info: CoreStateMachine::pushState Apr 14 20:00:37 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:00:37 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Apr 14 20:00:37 volumio volumio[918]: SPOTIFY: PUSH STATE SPOTIFY Apr 14 20:00:37 volumio volumio[918]: SPOTIFY: {"status":"play","service":"spop","title":"Em Rất Nhớ Anh","artist":"Vy Oanh","album":"Du Xuân","albumart":"https://i.scdn.co/image/ab67616d00001e021ca05882448eb0948b24cdd4","uri":"spotify:track:0OdRzjD1FwnzrFqkKOjg3q","trackType":"spotify","seek":16117,"duration":233,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":false,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 14 20:00:37 volumio volumio[918]: info: CoreCommandRouter::servicePushState Apr 14 20:00:37 volumio volumio[918]: info: CoreStateMachine::pushState Apr 14 20:00:37 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Apr 14 20:00:41 volumio go-librespot[1085]: time="2024-04-14T20:00:41+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 340" Apr 14 20:00:41 volumio go-librespot[1085]: time="2024-04-14T20:00:41+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Apr 14 20:00:41 volumio go-librespot[1085]: time="2024-04-14T20:00:41+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Apr 14 20:00:41 volumio go-librespot[1085]: time="2024-04-14T20:00:41+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2314" Apr 14 20:00:41 volumio go-librespot[1085]: time="2024-04-14T20:00:41+07:00" level=debug msg="put connect state inactive" Apr 14 20:00:41 volumio go-librespot[1085]: time="2024-04-14T20:00:41+07:00" level=trace msg="emitting websocket event: inactive" Apr 14 20:00:41 volumio volumio[918]: SPOTIFY: received: {"type":"inactive","data":null} Apr 14 20:00:41 volumio volumio[918]: error: Failed to decode event: inactive Apr 14 20:00:41 volumio go-librespot[1085]: time="2024-04-14T20:00:41+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 340" Apr 14 20:00:41 volumio go-librespot[1085]: time="2024-04-14T20:00:41+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Apr 14 20:00:51 volumio volumio[918]: info: CoreCommandRouter::volumioGetQueue Apr 14 20:00:51 volumio volumio[918]: info: CoreStateMachine::getQueue Apr 14 20:00:51 volumio volumio[918]: info: CorePlayQueue::getQueue Apr 14 20:00:52 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 20:00:52 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 20:00:52 volumio volumio[918]: info: Discovery: Getting this device information Apr 14 20:00:52 volumio volumio[918]: info: CoreCommandRouter::volumioGetState Apr 14 20:00:52 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 20:00:54 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 14 20:00:54 volumio volumio[918]: info: CURURI: music-library/USB/DKPhuong_USB/Songs/Quoc te/World Music Apr 14 20:00:54 volumio volumio[918]: info: Preload queue cleared Apr 14 20:01:05 volumio volumio[918]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 20:01:05 volumio volumio[918]: info: CoreStateMachine::ClearQueue Apr 14 20:01:05 volumio volumio[918]: info: CoreStateMachine::stop Apr 14 20:01:05 volumio volumio[918]: info: CoreStateMachine::serviceStop Apr 14 20:01:05 volumio volumio[918]: info: CoreCommandRouter::serviceStop Apr 14 20:01:05 volumio volumio[918]: info: Spotify Stop Apr 14 20:01:05 volumio volumio[918]: SPOTIFY: SPOTIFY STOP Apr 14 20:01:05 volumio volumio[918]: SPOTIFY: {"status":"play","title":"Em Rất Nhớ Anh","artist":"Vy Oanh","album":"Du Xuân","albumart":"https://i.scdn.co/image/ab67616d00001e021ca05882448eb0948b24cdd4","uri":"spotify:track:0OdRzjD1FwnzrFqkKOjg3q","trackType":"spotify","codec":"ogg","seek":16117,"duration":233,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"repeat":null,"repeatSingle":null,"consume":false,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":true,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Apr 14 20:01:05 volumio volumio[918]: info: Sending Spotify command to local API: /player/pause Apr 14 20:01:05 volumio volumio[918]: info: CorePlayQueue::clearPlayQueue Apr 14 20:01:05 volumio volumio[918]: info: CorePlayQueue::saveQueue Apr 14 20:01:05 volumio volumio[918]: info: CoreCommandRouter::volumioPushQueue Apr 14 20:01:05 volumio volumio[918]: info: CoreStateMachine::addQueueItems Apr 14 20:01:05 volumio volumio[918]: info: CorePlayQueue::addQueueItems Apr 14 20:01:05 volumio volumio[918]: info: Preload queue cleared Apr 14 20:01:05 volumio volumio[918]: info: Adding Item to queue: music-library/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - The Best of World Music - African (1993) Apr 14 20:01:05 volumio volumio[918]: info: Exploding uri music-library/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - The Best of World Music - African (1993) in service mpd Apr 14 20:01:05 volumio volumio[918]: info: ALBUMART /albumart?cacheid=608&web=John%20Chibadura/The%20Best%20of%20World%20Music%20-%20African/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20The%20Best%20of%20World%20Music%20-%20African%20(1993)%2F01.%20John%20Chibadura%20-%20Zuva%20Rekufa%20Kwangu%20-%20(The%20Best%20of%20World%20Music%20-%20African).flac&metadata=false Apr 14 20:01:05 volumio volumio[918]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - The Best of World Music - African (1993)/01. John Chibadura - Zuva Rekufa Kwangu - (The Best of World Music - African).flac Apr 14 20:01:05 volumio volumio[918]: info: ALBUMART /albumart?cacheid=608&web=Angelique%20Kidjo/The%20Best%20of%20World%20Music%20-%20African/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20The%20Best%20of%20World%20Music%20-%20African%20(1993)%2F02.%20Angelique%20Kidjo%20-%20Batonga%20-%20(The%20Best%20of%20World%20Music%20-%20African).flac&metadata=false Apr 14 20:01:05 volumio volumio[918]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - The Best of World Music - African (1993)/02. Angelique Kidjo - Batonga - (The Best of World Music - African).flac Apr 14 20:01:05 volumio volumio[918]: info: ALBUMART /albumart?cacheid=608&web=Toure%20Kunda/The%20Best%20of%20World%20Music%20-%20African/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20The%20Best%20of%20World%20Music%20-%20African%20(1993)%2F03.%20Toure%20Kunda%20%20-%20Santhiaba%20Silo%20-%20(The%20Best%20of%20World%20Music%20-%20African).flac&metadata=false Apr 14 20:01:05 volumio volumio[918]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - The Best of World Music - African (1993)/03. Toure Kunda - Santhiaba Silo - (The Best of World Music - African).flac Apr 14 20:01:05 volumio volumio[918]: info: ALBUMART /albumart?cacheid=608&web=Embowassa/The%20Best%20of%20World%20Music%20-%20African/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20The%20Best%20of%20World%20Music%20-%20African%20(1993)%2F04.%20Embowassa%20-%20Tshanga%20-%20(The%20Best%20of%20World%20Music%20-%20African).flac&metadata=false Apr 14 20:01:05 volumio volumio[918]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - The Best of World Music - African (1993)/04. Embowassa - Tshanga - (The Best of World Music - African).flac Apr 14 20:01:05 volumio volumio[918]: info: ALBUMART /albumart?cacheid=608&web=Abeti/The%20Best%20of%20World%20Music%20-%20African/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20The%20Best%20of%20World%20Music%20-%20African%20(1993)%2F05.%20Abeti%20%20-%20Je%20Suis%20Fache%20-%20(The%20Best%20of%20World%20Music%20-%20African).flac&metadata=false Apr 14 20:01:05 volumio volumio[918]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - The Best of World Music - African (1993)/05. Abeti - Je Suis Fache - (The Best of World Music - African).flac Apr 14 20:01:05 volumio volumio[918]: info: ALBUMART /albumart?cacheid=608&web=Pierre%20Akendengue/The%20Best%20of%20World%20Music%20-%20African/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20The%20Best%20of%20World%20Music%20-%20African%20(1993)%2F06.%20Pierre%20Akendengue%20%20-%20Epuguzu%20-%20(The%20Best%20of%20World%20Music%20-%20African).flac&metadata=false Apr 14 20:01:05 volumio volumio[918]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - The Best of World Music - African (1993)/06. Pierre Akendengue - Epuguzu - (The Best of World Music - African).flac Apr 14 20:01:05 volumio volumio[918]: info: ALBUMART /albumart?cacheid=608&web=Mory%20Kante/The%20Best%20of%20World%20Music%20-%20African/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20The%20Best%20of%20World%20Music%20-%20African%20(1993)%2F07.%20Mory%20Kante%20%20-%20Bankiero%20-%20(The%20Best%20of%20World%20Music%20-%20African).flac&metadata=false Apr 14 20:01:05 volumio volumio[918]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - The Best of World Music - African (1993)/07. Mory Kante - Bankiero - (The Best of World Music - African).flac Apr 14 20:01:05 volumio volumio[918]: info: ALBUMART /albumart?cacheid=608&web=Kotoja/The%20Best%20of%20World%20Music%20-%20African/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20The%20Best%20of%20World%20Music%20-%20African%20(1993)%2F08.%20Kotoja%20%20-%20Ejiro%20Oghene%20-%20(The%20Best%20of%20World%20Music%20-%20African).flac&metadata=false Apr 14 20:01:05 volumio volumio[918]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - The Best of World Music - African (1993)/08. Kotoja - Ejiro Oghene - (The Best of World Music - African).flac Apr 14 20:01:05 volumio volumio[918]: info: ALBUMART /albumart?cacheid=608&web=Johnny%20Clegg%20%26%20Juluka/The%20Best%20of%20World%20Music%20-%20African/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20The%20Best%20of%20World%20Music%20-%20African%20(1993)%2F09.%20Johnny%20Clegg%20%26%20Juluka%20%20-%20Umfazi%20Omdala%20-%20(The%20Best%20of%20World%20Music%20-%20African).flac&metadata=false Apr 14 20:01:05 volumio volumio[918]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - The Best of World Music - African (1993)/09. Johnny Clegg & Juluka - Umfazi Omdala - (The Best of World Music - African).flac Apr 14 20:01:05 volumio volumio[918]: info: ALBUMART /albumart?cacheid=608&web=Thomas%20Mapfumo/The%20Best%20of%20World%20Music%20-%20African/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20The%20Best%20of%20World%20Music%20-%20African%20(1993)%2F10.%20Thomas%20Mapfumo%20%20-%20Nyama%20Yekugocha%20-%20(The%20Best%20of%20World%20Music%20-%20African).flac&metadata=false Apr 14 20:01:05 volumio volumio[918]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - The Best of World Music - African (1993)/10. Thomas Mapfumo - Nyama Yekugocha - (The Best of World Music - African).flac Apr 14 20:01:05 volumio volumio[918]: info: ALBUMART /albumart?cacheid=608&web=Orchestre%20Makassy/The%20Best%20of%20World%20Music%20-%20African/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20The%20Best%20of%20World%20Music%20-%20African%20(1993)%2F11.%20Orchestre%20Makassy%20%20-%20Mambo%20Bado%20-%20(The%20Best%20of%20World%20Music%20-%20African).flac&metadata=false Apr 14 20:01:05 volumio volumio[918]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - The Best of World Music - African (1993)/11. Orchestre Makassy - Mambo Bado - (The Best of World Music - African).flac Apr 14 20:01:05 volumio volumio[918]: info: ALBUMART /albumart?cacheid=608&web=Sipho%20Mabuse/The%20Best%20of%20World%20Music%20-%20African/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20The%20Best%20of%20World%20Music%20-%20African%20(1993)%2F12.%20Sipho%20Mabuse%20%20-%20Jika%20Jive%20-%20(The%20Best%20of%20World%20Music%20-%20African).flac&metadata=false Apr 14 20:01:05 volumio volumio[918]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - The Best of World Music - African (1993)/12. Sipho Mabuse - Jika Jive - (The Best of World Music - African).flac Apr 14 20:01:05 volumio volumio[918]: info: ALBUMART /albumart?cacheid=608&web=O'%20Yaba/The%20Best%20of%20World%20Music%20-%20African/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20The%20Best%20of%20World%20Music%20-%20African%20(1993)%2F13.%20O'%20Yaba%20%20-%20Thube%20-%20(The%20Best%20of%20World%20Music%20-%20African).flac&metadata=false Apr 14 20:01:05 volumio volumio[918]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - The Best of World Music - African (1993)/13. O' Yaba - Thube - (The Best of World Music - African).flac Apr 14 20:01:05 volumio volumio[918]: info: ALBUMART /albumart?cacheid=608&web=John%20Chibadura/The%20Best%20of%20World%20Music%20-%20African/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20The%20Best%20of%20World%20Music%20-%20African%20(1993)%2FThe%20Best%20of%20World%20Music%20-%20African%20(1993).cuetools.flac.cue&metadata=false Apr 14 20:01:05 volumio volumio[918]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - The Best of World Music - African (1993)/The Best of World Music - African (1993).cuetools.flac.cue Apr 14 20:01:05 volumio volumio[918]: info: ALBUMART /albumart?cacheid=608&web=Angelique%20Kidjo/The%20Best%20of%20World%20Music%20-%20African/extralarge&path=%2Fmnt%2FUSB%2FDKPhuong_USB%2FSongs%2FQuoc%20te%2FWorld%20Music%2FPutumayo%20-%20The%20Best%20of%20World%20Music%20-%20African%20(1993)%2FThe%20Best%20of%20World%20Music%20-%20African%20(1993).cuetools.flac.cue&metadata=false Apr 14 20:01:05 volumio volumio[918]: info: URI /mnt/USB/DKPhuong_USB/Songs/Quoc te/World Music/Putumayo - The Best of World Music - African (1993)/The Best of World Music - African (1993).cuetools.flac.cue Apr 14 20:01:05 volumio volumio[918]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 20:01:05 volumio volumio[918]: Error: Unable to resolve or reject the same promise twice Apr 14 20:01:05 volumio volumio[918]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 14 20:01:05 volumio volumio[918]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Apr 14 20:01:05 volumio volumio[918]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 14 20:01:05 volumio volumio[918]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 14 20:01:05 volumio volumio[918]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 14 20:01:05 volumio volumio[918]: at Socket.emit (events.js:315:20) Apr 14 20:01:05 volumio volumio[918]: at addChunk (internal/streams/readable.js:309:12) Apr 14 20:01:05 volumio volumio[918]: at readableAddChunk (internal/streams/readable.js:280:11) Apr 14 20:01:05 volumio volumio[918]: at Socket.Readable.push (internal/streams/readable.js:223:10) Apr 14 20:01:05 volumio volumio[918]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 14 20:01:05 volumio volumio[918]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 20:01:05 volumio sudo[3100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-14 20:00 Apr 14 20:01:05 volumio sudo[3100]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"