-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Thu 2024-07-04 17:59:34 BST. -- Jul 04 17:58:09 volumio go-librespot[10334]: time="2024-07-04T17:58:09+01:00" level=debug msg="obtained new client token: AADcIIdHwG3se7ww/s+beTzQbXt3G1tGahS6yF8GM/A3mFTfERXWnNGULxgJChV1ct+XWwNx634XFzLrmillw/9N/oIXlX6lZlH19Fc0kj86PoYJWidD0fdVhWkZSFLLKFvgx1vMgEoU3s2t/4scuihLD13HaGrC+rbwcr1q6bHWGpPQw8apysX7hiPOPiHLlaB4PklbFHD9ikPg7hvYs6in+WXvHJXrUxciJxE4sb4+l8A14a9vSkE5wQF4aA==" Jul 04 17:58:10 volumio go-librespot[10334]: time="2024-07-04T17:58:10+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jul 04 17:58:10 volumio go-librespot[10334]: time="2024-07-04T17:58:10+01:00" level=debug msg="completed keyexchange" Jul 04 17:58:10 volumio go-librespot[10334]: time="2024-07-04T17:58:10+01:00" level=debug msg="completed challenge" Jul 04 17:58:10 volumio go-librespot[10334]: time="2024-07-04T17:58:10+01:00" level=debug msg="authenticated as 1149492164" Jul 04 17:58:10 volumio go-librespot[10334]: time="2024-07-04T17:58:10+01:00" level=debug msg="authenticated as 1149492164" Jul 04 17:58:10 volumio go-librespot[10334]: time="2024-07-04T17:58:10+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jul 04 17:58:10 volumio go-librespot[10334]: time="2024-07-04T17:58:10+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jul 04 17:58:10 volumio go-librespot[10334]: time="2024-07-04T17:58:10+01:00" level=debug msg="dealer connection opened" Jul 04 17:58:10 volumio go-librespot[10334]: time="2024-07-04T17:58:10+01:00" level=info msg="accepted zeroconf user 1149492164 from iPhone" Jul 04 17:58:10 volumio go-librespot[10334]: time="2024-07-04T17:58:10+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 04 17:58:10 volumio go-librespot[10334]: time="2024-07-04T17:58:10+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 04 17:58:10 volumio go-librespot[10334]: time="2024-07-04T17:58:10+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 04 17:58:10 volumio go-librespot[10334]: time="2024-07-04T17:58:10+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 04 17:58:10 volumio go-librespot[10334]: time="2024-07-04T17:58:10+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" Jul 04 17:58:10 volumio go-librespot[10334]: time="2024-07-04T17:58:10+01:00" level=debug msg="received connection id: NjM4YmMyZWMtZTkyOS00MzY4LWE1ZmUtZmQzZTBiMDNhN2FkK2RlYWxlcit0Y3A6Ly9nZXcxLWRlYWxlci1hLWg4dzUuZ2V3MS5zcG90aWZ5Lm5ldDo1NzAwKzk0OTRBMzQyRTJFNTRFMzM0MUFFM0VFN0ZGQjBGOUFFMDZBNUU1RkNEODY5QTUyNUU1MjQ0Rjk4QjE4OTc4MDQ=" Jul 04 17:58:11 volumio go-librespot[10334]: time="2024-07-04T17:58:11+01:00" level=debug msg="put connect state because NEW_DEVICE" Jul 04 17:58:11 volumio go-librespot[10334]: time="2024-07-04T17:58:11+01:00" level=debug msg="handling transfer player command from acc4387a4d0b1831139daec74d784a5aa78e8db2" Jul 04 17:58:11 volumio go-librespot[10334]: time="2024-07-04T17:58:11+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Jul 04 17:58:11 volumio go-librespot[10334]: time="2024-07-04T17:58:11+01:00" level=debug msg="loading track spotify:track:0ZUo4YjG4saFnEJhdWp9Bt (paused: true, position: 149561ms)" Jul 04 17:58:11 volumio go-librespot[10334]: time="2024-07-04T17:58:11+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 04 17:58:11 volumio go-librespot[10334]: time="2024-07-04T17:58:11+01:00" level=trace msg="emitting websocket event: will_play" Jul 04 17:58:11 volumio volumio[10189]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0ZUo4YjG4saFnEJhdWp9Bt","play_origin":"com.spotify.service.search2"}} Jul 04 17:58:11 volumio go-librespot[10334]: time="2024-07-04T17:58:11+01:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:0ZUo4YjG4saFnEJhdWp9Bt" Jul 04 17:58:11 volumio go-librespot[10334]: time="2024-07-04T17:58:11+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Jul 04 17:58:11 volumio go-librespot[10334]: time="2024-07-04T17:58:11+01:00" level=debug msg="requested aes key for file 221632688e930084e61fc1a81747ed8539070a23, gid: 0ZUo4YjG4saFnEJhdWp9Bt" Jul 04 17:58:11 volumio go-librespot[10334]: time="2024-07-04T17:58:11+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1929" Jul 04 17:58:11 volumio go-librespot[10334]: time="2024-07-04T17:58:11+01:00" level=debug msg="fetched first chunk of 17, total size is 8490668 bytes" Jul 04 17:58:11 volumio go-librespot[10334]: time="2024-07-04T17:58:11+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1539" Jul 04 17:58:11 volumio go-librespot[10334]: time="2024-07-04T17:58:11+01:00" level=debug msg="fetched chunk 2/16, size: 524288" Jul 04 17:58:11 volumio go-librespot[10334]: time="2024-07-04T17:58:11+01:00" level=debug msg="fetched chunk 3/16, size: 524288" Jul 04 17:58:12 volumio go-librespot[10334]: time="2024-07-04T17:58:12+01:00" level=debug msg="fetched chunk 13/16, size: 524288" Jul 04 17:58:12 volumio go-librespot[10334]: time="2024-07-04T17:58:12+01:00" level=debug msg="fetched chunk 14/16, size: 524288" Jul 04 17:58:12 volumio go-librespot[10334]: time="2024-07-04T17:58:12+01:00" level=debug msg="fetched chunk 15/16, size: 524288" Jul 04 17:58:12 volumio go-librespot[10334]: time="2024-07-04T17:58:12+01:00" level=debug msg="fetched chunk 1/16, size: 524288" Jul 04 17:58:12 volumio go-librespot[10334]: time="2024-07-04T17:58:12+01:00" level=debug msg="fetched chunk 12/16, size: 524288" Jul 04 17:58:12 volumio go-librespot[10334]: time="2024-07-04T17:58:12+01:00" level=trace msg="seek to 149561ms (diff: 112ms, samples: 6595640, bytes: 6416246)" Jul 04 17:58:12 volumio go-librespot[10334]: time="2024-07-04T17:58:12+01:00" level=info msg="loaded track \"Before He Cheats\" (uri: spotify:track:0ZUo4YjG4saFnEJhdWp9Bt, paused: true, position: 149561ms, duration: 199946ms)" Jul 04 17:58:12 volumio go-librespot[10334]: time="2024-07-04T17:58:12+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 04 17:58:12 volumio go-librespot[10334]: time="2024-07-04T17:58:12+01:00" level=trace msg="emitting websocket event: metadata" Jul 04 17:58:12 volumio go-librespot[10334]: time="2024-07-04T17:58:12+01:00" level=trace msg="emitting websocket event: active" Jul 04 17:58:12 volumio go-librespot[10334]: time="2024-07-04T17:58:12+01:00" level=debug msg="sending successful reply for delaer request" Jul 04 17:58:12 volumio volumio[10189]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0ZUo4YjG4saFnEJhdWp9Bt","name":"Before He Cheats","artist_names":["Carrie Underwood"],"album_name":"Some Hearts","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02724bd326692d222c5906b0b0","position":149561,"duration":199946,"release_date":"year:2005 month:11 day:14","track_number":7,"disc_number":1}} Jul 04 17:58:12 volumio go-librespot[10334]: time="2024-07-04T17:58:12+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jul 04 17:58:12 volumio volumio[10189]: SPOTIFY: received: {"type":"active","data":null} Jul 04 17:58:12 volumio go-librespot[10334]: time="2024-07-04T17:58:12+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 04 17:58:12 volumio volumio[10189]: info: Aligning Spotify Volume to Volumio Volume Jul 04 17:58:12 volumio volumio[10189]: info: CoreCommandRouter::volumioGetState Jul 04 17:58:12 volumio volumio[10189]: info: CorePlayQueue::getTrack 0 Jul 04 17:58:12 volumio volumio[10189]: info: Setting Spotify Volume from Volumio: 100 Jul 04 17:58:12 volumio go-librespot[10334]: time="2024-07-04T17:58:12+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 04 17:58:12 volumio go-librespot[10334]: time="2024-07-04T17:58:12+01:00" level=trace msg="emitting websocket event: paused" Jul 04 17:58:12 volumio volumio[10189]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:0ZUo4YjG4saFnEJhdWp9Bt","play_origin":"com.spotify.service.search2"}} Jul 04 17:58:12 volumio go-librespot[10334]: time="2024-07-04T17:58:12+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 04 17:58:12 volumio volumio[10189]: info: Spotify is playing in volatile mode Jul 04 17:58:12 volumio volumio[10189]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 17:58:12 volumio volumio[10189]: SPOTIFY: UNSET VOLATILE Jul 04 17:58:12 volumio volumio[10189]: SPOTIFY: {"status":"play","position":0,"title":"Janet Jackson - Whoops Now","artist":"80s80s Michael Jackson","album":"","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music4/v4/9b/f2/12/9bf212d6-df17-e6bd-ba6b-4a699897a4d4/source/600x600bb.jpg","uri":"http://streams.80s80s.de/mj/mp3-192/volumio","trackType":"80s80s Radio","seek":0,"duration":"194","samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":true,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"80s80s Radio","volatile":false,"service":"80s80s"} Jul 04 17:58:12 volumio volumio[10189]: info: Setting Spotify stop after unset volatile call Jul 04 17:58:12 volumio volumio[10189]: SPOTIFY: PUSH STATE SPOTIFY Jul 04 17:58:12 volumio volumio[10189]: SPOTIFY: {"status":"pause","service":"spop","title":"Before He Cheats","artist":"Carrie Underwood","album":"Some Hearts","albumart":"https://i.scdn.co/image/ab67616d00001e02724bd326692d222c5906b0b0","uri":"spotify:track:0ZUo4YjG4saFnEJhdWp9Bt","trackType":"spotify","seek":149561,"duration":199,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 04 17:58:12 volumio volumio[10189]: info: CoreCommandRouter::servicePushState Jul 04 17:58:12 volumio volumio[10189]: info: CoreStateMachine::pushState Jul 04 17:58:12 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 17:58:12 volumio volumio[10189]: info: CoreCommandRouter::volumioPushState Jul 04 17:58:12 volumio volumio[10189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 17:58:12 volumio volumio[10189]: info: Spotify Stop Jul 04 17:58:12 volumio volumio[10189]: SPOTIFY: SPOTIFY STOP Jul 04 17:58:12 volumio volumio[10189]: SPOTIFY: {"status":"pause","title":"Before He Cheats","artist":"Carrie Underwood","album":"Some Hearts","albumart":"https://i.scdn.co/image/ab67616d00001e02724bd326692d222c5906b0b0","uri":"spotify:track:0ZUo4YjG4saFnEJhdWp9Bt","trackType":"spotify","codec":"ogg","seek":149561,"duration":199,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Jul 04 17:58:13 volumio volumio[10189]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Jul 04 17:58:13 volumio volumio[10189]: info: Sending Spotify command with payload to local API: /player/volume Jul 04 17:58:13 volumio go-librespot[10334]: time="2024-07-04T17:58:13+01:00" level=debug msg="update volume to 65535/65535" Jul 04 17:58:13 volumio go-librespot[10334]: time="2024-07-04T17:58:13+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 04 17:58:13 volumio go-librespot[10334]: time="2024-07-04T17:58:13+01:00" level=trace msg="emitting websocket event: volume" Jul 04 17:58:13 volumio volumio[10189]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Jul 04 17:58:13 volumio volumio[10189]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Jul 04 17:58:16 volumio go-librespot[10334]: time="2024-07-04T17:58:16+01:00" level=debug msg="handling play player command from acc4387a4d0b1831139daec74d784a5aa78e8db2" Jul 04 17:58:16 volumio go-librespot[10334]: time="2024-07-04T17:58:16+01:00" level=trace msg="fetched new page 0 with 30 items (list: 30)" Jul 04 17:58:16 volumio go-librespot[10334]: time="2024-07-04T17:58:16+01:00" level=debug msg="loading track spotify:track:2VWBdFsDJVGlRTdz4PvYwy (paused: false, position: 0ms)" Jul 04 17:58:16 volumio go-librespot[10334]: time="2024-07-04T17:58:16+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 04 17:58:16 volumio go-librespot[10334]: time="2024-07-04T17:58:16+01:00" level=trace msg="emitting websocket event: will_play" Jul 04 17:58:16 volumio volumio[10189]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2VWBdFsDJVGlRTdz4PvYwy","play_origin":"playlist/ondemand"}} Jul 04 17:58:16 volumio go-librespot[10334]: time="2024-07-04T17:58:16+01:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:2VWBdFsDJVGlRTdz4PvYwy" Jul 04 17:58:16 volumio go-librespot[10334]: time="2024-07-04T17:58:16+01:00" level=debug msg="requested aes key for file 43c28b2975cc87b4d83eac8cda8a265869dbd154, gid: 2VWBdFsDJVGlRTdz4PvYwy" Jul 04 17:58:16 volumio go-librespot[10334]: time="2024-07-04T17:58:16+01:00" level=debug msg="fetched first chunk of 20, total size is 10478116 bytes" Jul 04 17:58:16 volumio go-librespot[10334]: time="2024-07-04T17:58:16+01:00" level=trace msg="seek to 0ms (diff: -36ms, samples: 0, bytes: 2876)" Jul 04 17:58:16 volumio go-librespot[10334]: time="2024-07-04T17:58:16+01:00" level=info msg="loaded track \"Dive - Recorded At Sound Stage Nashville\" (uri: spotify:track:2VWBdFsDJVGlRTdz4PvYwy, paused: false, position: 0ms, duration: 260862ms)" Jul 04 17:58:16 volumio go-librespot[10334]: time="2024-07-04T17:58:16+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 04 17:58:16 volumio go-librespot[10334]: time="2024-07-04T17:58:16+01:00" level=trace msg="emitting websocket event: metadata" Jul 04 17:58:16 volumio go-librespot[10334]: time="2024-07-04T17:58:16+01:00" level=debug msg="sending successful reply for delaer request" Jul 04 17:58:16 volumio volumio[10189]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2VWBdFsDJVGlRTdz4PvYwy","name":"Dive - Recorded At Sound Stage Nashville","artist_names":["Luke Combs"],"album_name":"Spotify Singles","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02cbcea9a401b5cf8fdb6fbff2","position":0,"duration":260862,"release_date":"year:2018 month:3 day:7","track_number":2,"disc_number":1}} Jul 04 17:58:16 volumio go-librespot[10334]: time="2024-07-04T17:58:16+01:00" level=debug msg="fetched chunk 3/19, size: 524288" Jul 04 17:58:16 volumio go-librespot[10334]: time="2024-07-04T17:58:16+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 04 17:58:16 volumio go-librespot[10334]: time="2024-07-04T17:58:16+01:00" level=trace msg="emitting websocket event: playing" Jul 04 17:58:16 volumio volumio[10189]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:2VWBdFsDJVGlRTdz4PvYwy","play_origin":"playlist/ondemand"}} Jul 04 17:58:16 volumio volumio[10189]: SPOTIFY: PUSH STATE SPOTIFY Jul 04 17:58:16 volumio volumio[10189]: SPOTIFY: {"status":"play","service":"spop","title":"Dive - Recorded At Sound Stage Nashville","artist":"Luke Combs","album":"Spotify Singles","albumart":"https://i.scdn.co/image/ab67616d00001e02cbcea9a401b5cf8fdb6fbff2","uri":"spotify:track:2VWBdFsDJVGlRTdz4PvYwy","trackType":"spotify","seek":0,"duration":260,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jul 04 17:58:16 volumio volumio[10189]: info: CoreCommandRouter::servicePushState Jul 04 17:58:16 volumio volumio[10189]: info: CoreStateMachine::pushState Jul 04 17:58:16 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 17:58:16 volumio volumio[10189]: info: CoreCommandRouter::volumioPushState Jul 04 17:58:16 volumio volumio[10189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 17:58:16 volumio go-librespot[10334]: time="2024-07-04T17:58:16+01:00" level=debug msg="fetched chunk 1/19, size: 524288" Jul 04 17:58:16 volumio go-librespot[10334]: time="2024-07-04T17:58:16+01:00" level=debug msg="fetched chunk 2/19, size: 524288" Jul 04 17:58:16 volumio volumio[10189]: SPOTIFY: PUSH STATE SPOTIFY Jul 04 17:58:16 volumio volumio[10189]: SPOTIFY: {"status":"play","service":"spop","title":"Dive - Recorded At Sound Stage Nashville","artist":"Luke Combs","album":"Spotify Singles","albumart":"https://i.scdn.co/image/ab67616d00001e02cbcea9a401b5cf8fdb6fbff2","uri":"spotify:track:2VWBdFsDJVGlRTdz4PvYwy","trackType":"spotify","seek":0,"duration":260,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jul 04 17:58:16 volumio volumio[10189]: info: CoreCommandRouter::servicePushState Jul 04 17:58:16 volumio volumio[10189]: info: CoreStateMachine::pushState Jul 04 17:58:16 volumio volumio[10189]: info: CoreCommandRouter::volumioPushState Jul 04 17:58:16 volumio volumio[10189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 17:58:29 volumio go-librespot[10334]: time="2024-07-04T17:58:29+01:00" level=debug msg="fetched chunk 4/19, size: 524288" Jul 04 17:58:41 volumio go-librespot[10334]: time="2024-07-04T17:58:41+01:00" level=debug msg="fetched chunk 5/19, size: 524288" Jul 04 17:58:55 volumio go-librespot[10334]: time="2024-07-04T17:58:55+01:00" level=debug msg="fetched chunk 6/19, size: 524288" Jul 04 17:59:00 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 17:59:00 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 17:59:00 volumio volumio[10189]: info: Discovery: Getting this device information Jul 04 17:59:00 volumio volumio[10189]: info: CoreCommandRouter::volumioGetState Jul 04 17:59:00 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 17:59:00 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 17:59:00 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 17:59:00 volumio volumio[10189]: info: Discovery: Getting this device information Jul 04 17:59:00 volumio volumio[10189]: info: CoreCommandRouter::volumioGetState Jul 04 17:59:00 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 17:59:00 volumio volumio[10189]: verbose: New Socket.io Connection to 192.168.1.28 from 192.168.1.247 UA: unknown Total Clients: 5 Jul 04 17:59:00 volumio volumio[10189]: verbose: New Socket.io Connection to 192.168.1.30 from 192.168.1.247 UA: unknown Total Clients: 6 Jul 04 17:59:00 volumio volumio[10189]: info: CoreCommandRouter::volumioGetState Jul 04 17:59:01 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 04 17:59:01 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 04 17:59:01 volumio volumio[10189]: info: CoreCommandRouter::volumioGetState Jul 04 17:59:01 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 04 17:59:01 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 04 17:59:06 volumio go-librespot[10334]: time="2024-07-04T17:59:06+01:00" level=debug msg="fetched chunk 7/19, size: 524288" Jul 04 17:59:08 volumio sudo[16339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 04 17:59:08 volumio sudo[16339]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 17:59:08 volumio sudo[16339]: pam_unix(sudo:session): session closed for user root Jul 04 17:59:08 volumio sudo[16342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 04 17:59:08 volumio sudo[16342]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 17:59:08 volumio sudo[16342]: pam_unix(sudo:session): session closed for user root Jul 04 17:59:08 volumio volumio[10189]: verbose: New Socket.io Connection to 192.168.1.28 from 192.168.1.247 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 7 Jul 04 17:59:08 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 04 17:59:08 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 04 17:59:08 volumio volumio[10189]: info: CoreCommandRouter::volumioGetVisibleSources Jul 04 17:59:08 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 17:59:08 volumio volumio[10189]: info: CoreCommandRouter::volumioGetState Jul 04 17:59:08 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 04 17:59:08 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 04 17:59:08 volumio volumio[10189]: info: Received Get System Info Jul 04 17:59:08 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 17:59:08 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 17:59:08 volumio volumio[10189]: info: Discovery: Getting this device information Jul 04 17:59:08 volumio volumio[10189]: info: CoreCommandRouter::volumioGetState Jul 04 17:59:08 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 17:59:08 volumio volumio[10189]: info: CoreCommandRouter::volumioGetState Jul 04 17:59:08 volumio volumio[10189]: info: Listing playlists Jul 04 17:59:08 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 04 17:59:08 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 04 17:59:09 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 04 17:59:10 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 04 17:59:10 volumio volumio[10189]: info: Received Get System Info Jul 04 17:59:10 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 17:59:10 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 17:59:10 volumio volumio[10189]: info: Discovery: Getting this device information Jul 04 17:59:10 volumio volumio[10189]: info: CoreCommandRouter::volumioGetState Jul 04 17:59:10 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 17:59:12 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 04 17:59:12 volumio volumio[10189]: info: Received Get System Info Jul 04 17:59:12 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 17:59:12 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 17:59:12 volumio volumio[10189]: info: Discovery: Getting this device information Jul 04 17:59:12 volumio volumio[10189]: info: CoreCommandRouter::volumioGetState Jul 04 17:59:12 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 17:59:19 volumio volumio[10189]: info: CoreCommandRouter::volumioGetQueue Jul 04 17:59:19 volumio volumio[10189]: info: CoreStateMachine::getQueue Jul 04 17:59:19 volumio volumio[10189]: info: CorePlayQueue::getQueue Jul 04 17:59:19 volumio go-librespot[10334]: time="2024-07-04T17:59:19+01:00" level=debug msg="fetched chunk 8/19, size: 524288" Jul 04 17:59:23 volumio volumio[10189]: info: Preload queue cleared Jul 04 17:59:23 volumio volumio[10189]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::ClearQueue Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::stop Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::serviceStop Jul 04 17:59:23 volumio volumio[10189]: info: CoreCommandRouter::serviceStop Jul 04 17:59:23 volumio volumio[10189]: info: Spotify Stop Jul 04 17:59:23 volumio volumio[10189]: SPOTIFY: SPOTIFY STOP Jul 04 17:59:23 volumio volumio[10189]: SPOTIFY: {"status":"play","title":"Dive - Recorded At Sound Stage Nashville","artist":"Luke Combs","album":"Spotify Singles","albumart":"https://i.scdn.co/image/ab67616d00001e02cbcea9a401b5cf8fdb6fbff2","uri":"spotify:track:2VWBdFsDJVGlRTdz4PvYwy","trackType":"spotify","codec":"ogg","seek":0,"duration":260,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Jul 04 17:59:23 volumio volumio[10189]: info: Sending Spotify command to local API: /player/pause Jul 04 17:59:23 volumio volumio[10189]: info: CorePlayQueue::clearPlayQueue Jul 04 17:59:23 volumio volumio[10189]: info: CorePlayQueue::saveQueue Jul 04 17:59:23 volumio volumio[10189]: info: CoreCommandRouter::volumioPushQueue Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::addQueueItems Jul 04 17:59:23 volumio volumio[10189]: info: CorePlayQueue::addQueueItems Jul 04 17:59:23 volumio volumio[10189]: info: Preload queue cleared Jul 04 17:59:23 volumio volumio[10189]: info: Adding Item to queue: webeighties/7 Jul 04 17:59:23 volumio volumio[10189]: info: Exploding uri webeighties/7 in service 80s80s Jul 04 17:59:23 volumio volumio[10189]: info: [1720112363882] [80s80s] explodeUri: webeighties/7 Jul 04 17:59:23 volumio volumio[10189]: info: CoreCommandRouter::volumioPushQueue Jul 04 17:59:23 volumio volumio[10189]: info: CorePlayQueue::saveQueue Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::updateTrackBlock Jul 04 17:59:23 volumio volumio[10189]: info: CorePlayQueue::getTrackBlock Jul 04 17:59:23 volumio volumio[10189]: info: CoreCommandRouter::volumioPlay Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::play index 0 Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::stop Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::stPlaybackTimer Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::updateTrackBlock Jul 04 17:59:23 volumio volumio[10189]: info: CorePlayQueue::getTrackBlock Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::pushState Jul 04 17:59:23 volumio volumio[10189]: info: CorePlayQueue::getTrack 0 Jul 04 17:59:23 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 17:59:23 volumio volumio[10189]: info: CoreCommandRouter::volumioPushState Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::serviceStop Jul 04 17:59:23 volumio volumio[10189]: info: CorePlayQueue::getTrack 0 Jul 04 17:59:23 volumio volumio[10189]: info: CoreCommandRouter::serviceStop Jul 04 17:59:23 volumio volumio[10189]: info: ControllerMpd::stop Jul 04 17:59:23 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand stop Jul 04 17:59:23 volumio volumio[10189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 17:59:23 volumio volumio[10189]: info: Jul 04 17:59:23 volumio volumio[10189]: ---------------------------- MPD announces state update: player Jul 04 17:59:23 volumio volumio[10189]: info: sendMpdCommand stop took 17 milliseconds Jul 04 17:59:23 volumio volumio[10189]: info: ControllerMpd::getState Jul 04 17:59:23 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand status Jul 04 17:59:23 volumio volumio[10189]: info: CoreCommandRouter::servicePushState Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::pushState Jul 04 17:59:23 volumio volumio[10189]: info: CorePlayQueue::getTrack 0 Jul 04 17:59:23 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 17:59:23 volumio volumio[10189]: info: CoreCommandRouter::volumioPushState Jul 04 17:59:23 volumio volumio[10189]: info: CorePlayQueue::getTrack 0 Jul 04 17:59:23 volumio volumio[10189]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music4/v4/9b/f2/12/9bf212d6-df17-e6bd-ba6b-4a699897a4d4/source/600x600bb.jpg","name":"Janet Jackson - Whoops Now","title":"Whoops Now","artist":"80s80s Michael Jackson","album":"","streaming":true,"disableUiControls":true,"duration":"194","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 04 17:59:23 volumio volumio[10189]: verbose: CURRENT POSITION 0 Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::syncState stateService stop Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::syncState currentStatus stop Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::pushState Jul 04 17:59:23 volumio volumio[10189]: info: CorePlayQueue::getTrack 0 Jul 04 17:59:23 volumio volumio[10189]: info: CoreCommandRouter::volumioPushState Jul 04 17:59:23 volumio volumio[10189]: info: No code Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::pushState Jul 04 17:59:23 volumio volumio[10189]: info: CorePlayQueue::getTrack 0 Jul 04 17:59:23 volumio volumio[10189]: info: CoreCommandRouter::volumioPushState Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::play index undefined Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 17:59:23 volumio volumio[10189]: info: CorePlayQueue::getTrack 0 Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::startPlaybackTimer Jul 04 17:59:23 volumio volumio[10189]: info: CorePlayQueue::getTrack 0 Jul 04 17:59:23 volumio volumio[10189]: info: [1720112363933] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/mj/mp3-192/volumio Jul 04 17:59:23 volumio volumio[10189]: info: [1720112363934] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=156&count=2 Jul 04 17:59:23 volumio volumio[10189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 17:59:23 volumio volumio[10189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 17:59:23 volumio volumio[10189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 17:59:23 volumio volumio[10189]: info: sendMpdCommand status took 31 milliseconds Jul 04 17:59:23 volumio volumio[10189]: verbose: ControllerMpd::parseState Jul 04 17:59:23 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 17:59:23 volumio volumio[10189]: info: Jul 04 17:59:23 volumio volumio[10189]: ---------------------------- MPD announces state update: player Jul 04 17:59:23 volumio volumio[10189]: info: ControllerMpd::getState Jul 04 17:59:23 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand status Jul 04 17:59:23 volumio volumio[10189]: info: Jul 04 17:59:23 volumio volumio[10189]: ---------------------------- MPD announces state update: player Jul 04 17:59:23 volumio volumio[10189]: info: ControllerMpd::getState Jul 04 17:59:23 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand status Jul 04 17:59:23 volumio volumio[10189]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 04 17:59:23 volumio volumio[10189]: info: sendMpdCommand status took 2 milliseconds Jul 04 17:59:23 volumio volumio[10189]: info: sendMpdCommand status took 1 milliseconds Jul 04 17:59:23 volumio volumio[10189]: verbose: ControllerMpd::parseTrackInfo Jul 04 17:59:23 volumio volumio[10189]: verbose: ControllerMpd::parseState Jul 04 17:59:23 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 17:59:23 volumio volumio[10189]: verbose: ControllerMpd::parseState Jul 04 17:59:23 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 17:59:23 volumio volumio[10189]: info: ControllerMpd::pushState Jul 04 17:59:23 volumio volumio[10189]: info: CoreCommandRouter::servicePushState Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::pushState Jul 04 17:59:23 volumio volumio[10189]: info: CorePlayQueue::getTrack 0 Jul 04 17:59:23 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 17:59:23 volumio volumio[10189]: info: CoreCommandRouter::volumioPushState Jul 04 17:59:23 volumio volumio[10189]: info: CorePlayQueue::getTrack 0 Jul 04 17:59:23 volumio volumio[10189]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 04 17:59:23 volumio volumio[10189]: info: ------------------------------ 43ms Jul 04 17:59:23 volumio volumio[10189]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 04 17:59:23 volumio volumio[10189]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 04 17:59:23 volumio volumio[10189]: verbose: ControllerMpd::parseTrackInfo Jul 04 17:59:23 volumio volumio[10189]: verbose: ControllerMpd::parseTrackInfo Jul 04 17:59:23 volumio volumio[10189]: info: ControllerMpd::pushState Jul 04 17:59:23 volumio volumio[10189]: info: CoreCommandRouter::servicePushState Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::pushState Jul 04 17:59:23 volumio volumio[10189]: info: CorePlayQueue::getTrack 0 Jul 04 17:59:23 volumio volumio[10189]: info: CoreCommandRouter::volumioPushState Jul 04 17:59:23 volumio volumio[10189]: info: CorePlayQueue::getTrack 0 Jul 04 17:59:23 volumio volumio[10189]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 04 17:59:23 volumio volumio[10189]: info: ControllerMpd::pushState Jul 04 17:59:23 volumio volumio[10189]: info: CoreCommandRouter::servicePushState Jul 04 17:59:23 volumio volumio[10189]: info: CoreStateMachine::pushState Jul 04 17:59:23 volumio volumio[10189]: info: CorePlayQueue::getTrack 0 Jul 04 17:59:23 volumio volumio[10189]: info: CoreCommandRouter::volumioPushState Jul 04 17:59:23 volumio volumio[10189]: info: CorePlayQueue::getTrack 0 Jul 04 17:59:23 volumio volumio[10189]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 04 17:59:23 volumio volumio[10189]: info: ------------------------------ 30ms Jul 04 17:59:23 volumio volumio[10189]: info: ------------------------------ 29ms Jul 04 17:59:23 volumio volumio[10189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 17:59:23 volumio volumio[10189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 17:59:23 volumio volumio[10189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 17:59:24 volumio go-librespot[10334]: time="2024-07-04T17:59:24+01:00" level=debug msg="pause track at 67802ms" Jul 04 17:59:24 volumio go-librespot[10334]: time="2024-07-04T17:59:24+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 04 17:59:24 volumio volumio[10189]: info: [1720112364162] [80s80s] received new event containing 2 songs. Jul 04 17:59:24 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand stop Jul 04 17:59:24 volumio volumio[10189]: info: sendMpdCommand stop took 3 milliseconds Jul 04 17:59:24 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand clear Jul 04 17:59:24 volumio volumio[10189]: info: Jul 04 17:59:24 volumio volumio[10189]: ---------------------------- MPD announces system playlist update Jul 04 17:59:24 volumio volumio[10189]: info: Ignoring MPD Status Update Jul 04 17:59:24 volumio volumio[10189]: info: sendMpdCommand clear took 1 milliseconds Jul 04 17:59:24 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 04 17:59:24 volumio volumio[10189]: info: Jul 04 17:59:24 volumio volumio[10189]: ---------------------------- MPD announces system playlist update Jul 04 17:59:24 volumio volumio[10189]: info: Ignoring MPD Status Update Jul 04 17:59:24 volumio volumio[10189]: info: Jul 04 17:59:24 volumio volumio[10189]: ---------------------------- MPD announces system playlist update Jul 04 17:59:24 volumio volumio[10189]: info: Ignoring MPD Status Update Jul 04 17:59:24 volumio volumio[10189]: error: updateQueue error: null Jul 04 17:59:24 volumio volumio[10189]: error: updateQueue error: null Jul 04 17:59:24 volumio volumio[10189]: error: updateQueue error: null Jul 04 17:59:24 volumio volumio[10189]: info: ------------------------------ 3ms Jul 04 17:59:24 volumio volumio[10189]: info: sendMpdCommand consume 1 took 2 milliseconds Jul 04 17:59:24 volumio volumio[10189]: info: ------------------------------ 2ms Jul 04 17:59:24 volumio volumio[10189]: info: ------------------------------ 2ms Jul 04 17:59:24 volumio volumio[10189]: info: [1720112364170] [80s80s] adding url: http://streams.80s80s.de/mj/mp3-192/volumio Jul 04 17:59:24 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/mj/mp3-192/volumio" Jul 04 17:59:24 volumio volumio[10189]: info: Jul 04 17:59:24 volumio volumio[10189]: ---------------------------- MPD announces system playlist update Jul 04 17:59:24 volumio volumio[10189]: info: Ignoring MPD Status Update Jul 04 17:59:24 volumio volumio[10189]: info: sendMpdCommand add "http://streams.80s80s.de/mj/mp3-192/volumio" took 1 milliseconds Jul 04 17:59:24 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand play Jul 04 17:59:24 volumio volumio[10189]: info: Jul 04 17:59:24 volumio volumio[10189]: ---------------------------- MPD announces system playlist update Jul 04 17:59:24 volumio volumio[10189]: info: Ignoring MPD Status Update Jul 04 17:59:24 volumio volumio[10189]: info: Jul 04 17:59:24 volumio volumio[10189]: ---------------------------- MPD announces system playlist update Jul 04 17:59:24 volumio volumio[10189]: info: Ignoring MPD Status Update Jul 04 17:59:24 volumio volumio[10189]: info: ------------------------------ 7ms Jul 04 17:59:24 volumio volumio[10189]: info: sendMpdCommand play took 3 milliseconds Jul 04 17:59:24 volumio volumio[10189]: info: ------------------------------ 2ms Jul 04 17:59:24 volumio volumio[10189]: info: ------------------------------ 1ms Jul 04 17:59:24 volumio volumio[10189]: info: [1720112364177] [80s80s] Pushing the next song state: Michael Jackson - You Rock My World Jul 04 17:59:24 volumio volumio[10189]: info: CorePlayQueue::getTrack 0 Jul 04 17:59:24 volumio volumio[10189]: info: CoreCommandRouter::servicePushState Jul 04 17:59:24 volumio volumio[10189]: info: CorePlayQueue::getTrack 0 Jul 04 17:59:24 volumio volumio[10189]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music125/v4/9f/ac/1c/9fac1c50-8472-8c43-3448-59205cd580a7/source/600x600bb.jpg","name":"Michael Jackson - You Rock My World","title":"You Rock My World","artist":"80s80s Michael Jackson","album":"","streaming":true,"disableUiControls":true,"duration":"265","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 04 17:59:24 volumio volumio[10189]: verbose: CURRENT POSITION 0 Jul 04 17:59:24 volumio volumio[10189]: info: CoreStateMachine::syncState stateService play Jul 04 17:59:24 volumio volumio[10189]: info: CoreStateMachine::syncState currentStatus stop Jul 04 17:59:24 volumio volumio[10189]: info: [1720112364178] [80s80s] PlayNextTrack API delay: 30 Jul 04 17:59:24 volumio volumio[10189]: info: [1720112364179] [80s80s] Setting timer to: 9822 milliseconds. Jul 04 17:59:24 volumio volumio[10189]: info: CorePlayQueue::getTrack 0 Jul 04 17:59:24 volumio volumio[10189]: info: CorePlayQueue::getTrack 0 Jul 04 17:59:24 volumio volumio[10189]: info: Prefetching next song Jul 04 17:59:24 volumio go-librespot[10334]: time="2024-07-04T17:59:24+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 04 17:59:24 volumio go-librespot[10334]: time="2024-07-04T17:59:24+01:00" level=trace msg="emitting websocket event: paused" Jul 04 17:59:24 volumio volumio[10189]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:2VWBdFsDJVGlRTdz4PvYwy","play_origin":"playlist/ondemand"}} Jul 04 17:59:24 volumio volumio[10189]: info: Spotify is playing in volatile mode Jul 04 17:59:24 volumio volumio[10189]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 17:59:24 volumio volumio[10189]: SPOTIFY: UNSET VOLATILE Jul 04 17:59:24 volumio volumio[10189]: SPOTIFY: {"status":"stop","position":0,"title":"80s80s Michael Jackson","albumart":"/albumart?sourceicon=music_service/80s80s/images/80s80s-cover.png","uri":"http://streams.80s80s.de/mj/mp3-192/volumio","trackType":"80s80s Radio","seek":0,"duration":1000,"random":null,"repeat":true,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"80s80s Radio","volatile":false,"service":"80s80s"} Jul 04 17:59:24 volumio volumio[10189]: SPOTIFY: PUSH STATE SPOTIFY Jul 04 17:59:24 volumio volumio[10189]: SPOTIFY: {"status":"pause","service":"spop","title":"Dive - Recorded At Sound Stage Nashville","artist":"Luke Combs","album":"Spotify Singles","albumart":"https://i.scdn.co/image/ab67616d00001e02cbcea9a401b5cf8fdb6fbff2","uri":"spotify:track:2VWBdFsDJVGlRTdz4PvYwy","trackType":"spotify","seek":67000,"duration":260,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jul 04 17:59:24 volumio volumio[10189]: info: CoreCommandRouter::servicePushState Jul 04 17:59:24 volumio volumio[10189]: info: CoreStateMachine::pushState Jul 04 17:59:24 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 17:59:24 volumio volumio[10189]: info: CoreCommandRouter::volumioPushState Jul 04 17:59:24 volumio volumio[10189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 17:59:26 volumio volumio[10189]: info: Jul 04 17:59:26 volumio volumio[10189]: ---------------------------- MPD announces state update: player Jul 04 17:59:26 volumio volumio[10189]: info: ControllerMpd::getState Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand status Jul 04 17:59:26 volumio volumio[10189]: info: Jul 04 17:59:26 volumio volumio[10189]: ---------------------------- MPD announces state update: player Jul 04 17:59:26 volumio volumio[10189]: info: ControllerMpd::getState Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand status Jul 04 17:59:26 volumio volumio[10189]: info: Jul 04 17:59:26 volumio volumio[10189]: ---------------------------- MPD announces state update: player Jul 04 17:59:26 volumio volumio[10189]: info: ControllerMpd::getState Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand status Jul 04 17:59:26 volumio volumio[10189]: info: Jul 04 17:59:26 volumio volumio[10189]: ---------------------------- MPD announces state update: player Jul 04 17:59:26 volumio volumio[10189]: info: sendMpdCommand status took 2 milliseconds Jul 04 17:59:26 volumio volumio[10189]: info: ControllerMpd::getState Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand status Jul 04 17:59:26 volumio volumio[10189]: info: sendMpdCommand status took 3 milliseconds Jul 04 17:59:26 volumio volumio[10189]: info: sendMpdCommand status took 1 milliseconds Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::parseState Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::parseState Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::parseState Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 17:59:26 volumio volumio[10189]: info: Jul 04 17:59:26 volumio volumio[10189]: ---------------------------- MPD announces state update: player Jul 04 17:59:26 volumio volumio[10189]: info: ControllerMpd::getState Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand status Jul 04 17:59:26 volumio volumio[10189]: info: Jul 04 17:59:26 volumio volumio[10189]: ---------------------------- MPD announces state update: player Jul 04 17:59:26 volumio volumio[10189]: info: ControllerMpd::getState Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand status Jul 04 17:59:26 volumio volumio[10189]: info: sendMpdCommand status took 4 milliseconds Jul 04 17:59:26 volumio volumio[10189]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 04 17:59:26 volumio volumio[10189]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 04 17:59:26 volumio volumio[10189]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 04 17:59:26 volumio volumio[10189]: info: sendMpdCommand status took 2 milliseconds Jul 04 17:59:26 volumio volumio[10189]: info: sendMpdCommand status took 1 milliseconds Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::parseState Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::parseTrackInfo Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::parseTrackInfo Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::parseTrackInfo Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::parseState Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::parseState Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 17:59:26 volumio volumio[10189]: info: ControllerMpd::pushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::servicePushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreStateMachine::pushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::volumioPushState Jul 04 17:59:26 volumio volumio[10189]: info: ControllerMpd::pushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::servicePushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreStateMachine::pushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::volumioPushState Jul 04 17:59:26 volumio volumio[10189]: info: ControllerMpd::pushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::servicePushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreStateMachine::pushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::volumioPushState Jul 04 17:59:26 volumio volumio[10189]: info: ------------------------------ 30ms Jul 04 17:59:26 volumio volumio[10189]: info: ------------------------------ 29ms Jul 04 17:59:26 volumio volumio[10189]: info: ------------------------------ 29ms Jul 04 17:59:26 volumio volumio[10189]: info: sendMpdCommand playlistinfo took 22 milliseconds Jul 04 17:59:26 volumio volumio[10189]: info: sendMpdCommand playlistinfo took 23 milliseconds Jul 04 17:59:26 volumio volumio[10189]: info: sendMpdCommand playlistinfo took 23 milliseconds Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::parseTrackInfo Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::parseTrackInfo Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::parseTrackInfo Jul 04 17:59:26 volumio volumio[10189]: info: ControllerMpd::pushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::servicePushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreStateMachine::pushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::volumioPushState Jul 04 17:59:26 volumio volumio[10189]: info: ControllerMpd::pushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::servicePushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreStateMachine::pushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::volumioPushState Jul 04 17:59:26 volumio volumio[10189]: info: ControllerMpd::pushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::servicePushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreStateMachine::pushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::volumioPushState Jul 04 17:59:26 volumio volumio[10189]: info: ------------------------------ 53ms Jul 04 17:59:26 volumio volumio[10189]: info: ------------------------------ 50ms Jul 04 17:59:26 volumio volumio[10189]: info: ------------------------------ 51ms Jul 04 17:59:26 volumio volumio[10189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 17:59:26 volumio volumio[10189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 17:59:26 volumio volumio[10189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 17:59:26 volumio volumio[10189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 17:59:26 volumio volumio[10189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 17:59:26 volumio volumio[10189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 17:59:26 volumio volumio[10189]: info: Jul 04 17:59:26 volumio volumio[10189]: ---------------------------- MPD announces system playlist update Jul 04 17:59:26 volumio volumio[10189]: info: Ignoring MPD Status Update Jul 04 17:59:26 volumio volumio[10189]: info: Jul 04 17:59:26 volumio volumio[10189]: ---------------------------- MPD announces state update: player Jul 04 17:59:26 volumio volumio[10189]: info: ControllerMpd::getState Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand status Jul 04 17:59:26 volumio volumio[10189]: info: Jul 04 17:59:26 volumio volumio[10189]: ---------------------------- MPD announces system playlist update Jul 04 17:59:26 volumio volumio[10189]: info: Ignoring MPD Status Update Jul 04 17:59:26 volumio volumio[10189]: info: Jul 04 17:59:26 volumio volumio[10189]: ---------------------------- MPD announces state update: player Jul 04 17:59:26 volumio volumio[10189]: info: ControllerMpd::getState Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand status Jul 04 17:59:26 volumio volumio[10189]: info: Jul 04 17:59:26 volumio volumio[10189]: ---------------------------- MPD announces system playlist update Jul 04 17:59:26 volumio volumio[10189]: info: Ignoring MPD Status Update Jul 04 17:59:26 volumio volumio[10189]: info: Jul 04 17:59:26 volumio volumio[10189]: ---------------------------- MPD announces state update: player Jul 04 17:59:26 volumio volumio[10189]: info: ControllerMpd::getState Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand status Jul 04 17:59:26 volumio volumio[10189]: info: ------------------------------ 4ms Jul 04 17:59:26 volumio volumio[10189]: info: sendMpdCommand status took 3 milliseconds Jul 04 17:59:26 volumio volumio[10189]: info: ------------------------------ 4ms Jul 04 17:59:26 volumio volumio[10189]: info: sendMpdCommand status took 3 milliseconds Jul 04 17:59:26 volumio volumio[10189]: info: ------------------------------ 3ms Jul 04 17:59:26 volumio volumio[10189]: info: sendMpdCommand status took 2 milliseconds Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::parseState Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::parseState Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::parseState Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 17:59:26 volumio volumio[10189]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 04 17:59:26 volumio volumio[10189]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 04 17:59:26 volumio volumio[10189]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::parseTrackInfo Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::parseTrackInfo Jul 04 17:59:26 volumio volumio[10189]: verbose: ControllerMpd::parseTrackInfo Jul 04 17:59:26 volumio volumio[10189]: info: ControllerMpd::pushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::servicePushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreStateMachine::pushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::volumioPushState Jul 04 17:59:26 volumio volumio[10189]: info: ControllerMpd::pushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::servicePushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreStateMachine::pushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::volumioPushState Jul 04 17:59:26 volumio volumio[10189]: info: ControllerMpd::pushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::servicePushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreStateMachine::pushState Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::volumioPushState Jul 04 17:59:26 volumio volumio[10189]: info: ------------------------------ 28ms Jul 04 17:59:26 volumio volumio[10189]: info: ------------------------------ 27ms Jul 04 17:59:26 volumio volumio[10189]: info: ------------------------------ 27ms Jul 04 17:59:26 volumio volumio[10189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 17:59:26 volumio volumio[10189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 17:59:26 volumio volumio[10189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 04 17:59:26 volumio volumio[10189]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 04 17:59:26 volumio volumio[10189]: info: Received Get System Version Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 17:59:26 volumio volumio[10189]: info: Received Get System Info Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 17:59:26 volumio volumio[10189]: info: Discovery: Getting this device information Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::volumioGetState Jul 04 17:59:26 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 17:59:33 volumio volumio[10189]: info: Jul 04 17:59:33 volumio volumio[10189]: ---------------------------- MPD announces system playlist update Jul 04 17:59:33 volumio volumio[10189]: info: Ignoring MPD Status Update Jul 04 17:59:33 volumio volumio[10189]: info: Jul 04 17:59:33 volumio volumio[10189]: ---------------------------- MPD announces state update: player Jul 04 17:59:33 volumio volumio[10189]: info: ControllerMpd::getState Jul 04 17:59:33 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand status Jul 04 17:59:33 volumio volumio[10189]: info: Jul 04 17:59:33 volumio volumio[10189]: ---------------------------- MPD announces system playlist update Jul 04 17:59:33 volumio volumio[10189]: info: Ignoring MPD Status Update Jul 04 17:59:33 volumio volumio[10189]: info: Jul 04 17:59:33 volumio volumio[10189]: ---------------------------- MPD announces state update: player Jul 04 17:59:33 volumio volumio[10189]: info: ControllerMpd::getState Jul 04 17:59:33 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand status Jul 04 17:59:33 volumio volumio[10189]: info: ------------------------------ 3ms Jul 04 17:59:33 volumio volumio[10189]: info: sendMpdCommand status took 2 milliseconds Jul 04 17:59:33 volumio volumio[10189]: info: ------------------------------ 2ms Jul 04 17:59:33 volumio volumio[10189]: info: sendMpdCommand status took 2 milliseconds Jul 04 17:59:33 volumio volumio[10189]: verbose: ControllerMpd::parseState Jul 04 17:59:33 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 17:59:33 volumio volumio[10189]: verbose: ControllerMpd::parseState Jul 04 17:59:33 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 17:59:33 volumio volumio[10189]: info: Jul 04 17:59:33 volumio volumio[10189]: ---------------------------- MPD announces system playlist update Jul 04 17:59:33 volumio volumio[10189]: info: Ignoring MPD Status Update Jul 04 17:59:33 volumio volumio[10189]: info: Jul 04 17:59:33 volumio volumio[10189]: ---------------------------- MPD announces state update: player Jul 04 17:59:33 volumio volumio[10189]: info: ControllerMpd::getState Jul 04 17:59:33 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand status Jul 04 17:59:33 volumio volumio[10189]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 04 17:59:33 volumio volumio[10189]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 04 17:59:33 volumio volumio[10189]: info: ------------------------------ 1ms Jul 04 17:59:33 volumio volumio[10189]: info: sendMpdCommand status took 1 milliseconds Jul 04 17:59:33 volumio volumio[10189]: verbose: ControllerMpd::parseTrackInfo Jul 04 17:59:33 volumio volumio[10189]: verbose: ControllerMpd::parseTrackInfo Jul 04 17:59:33 volumio volumio[10189]: verbose: ControllerMpd::parseState Jul 04 17:59:33 volumio volumio[10189]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 17:59:33 volumio volumio[10189]: info: ControllerMpd::pushState Jul 04 17:59:33 volumio volumio[10189]: info: CoreCommandRouter::servicePushState Jul 04 17:59:33 volumio volumio[10189]: info: CoreStateMachine::pushState Jul 04 17:59:33 volumio volumio[10189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 17:59:33 volumio volumio[10189]: info: CoreCommandRouter::volumioPushState Jul 04 17:59:33 volumio volumio[10189]: info: ControllerMpd::pushState Jul 04 17:59:33 volumio volumio[10189]: info: CoreCommandRouter::servicePushState Jul 04 17:59:33 volumio volumio[10189]: info: CoreStateMachine::pushState Jul 04 17:59:33 volumio volumio[10189]: info: CoreCommandRouter::volumioPushState Jul 04 17:59:33 volumio volumio[10189]: info: ------------------------------ 16ms Jul 04 17:59:33 volumio volumio[10189]: info: ------------------------------ 15ms Jul 04 17:59:33 volumio volumio[10189]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 04 17:59:33 volumio volumio[10189]: verbose: ControllerMpd::parseTrackInfo Jul 04 17:59:33 volumio volumio[10189]: info: ControllerMpd::pushState Jul 04 17:59:33 volumio volumio[10189]: info: CoreCommandRouter::servicePushState Jul 04 17:59:33 volumio volumio[10189]: info: CoreStateMachine::pushState Jul 04 17:59:33 volumio volumio[10189]: info: CoreCommandRouter::volumioPushState Jul 04 17:59:33 volumio volumio[10189]: info: ------------------------------ 17ms Jul 04 17:59:33 volumio volumio[10189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 17:59:33 volumio volumio[10189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 17:59:33 volumio volumio[10189]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 17:59:34 volumio volumio[10189]: info: [1720112374001] [80s80s] Pushing the next song state Michael Jackson - You Rock My World and getting next track. Jul 04 17:59:34 volumio volumio[10189]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 17:59:34 volumio volumio[10189]: TypeError: Cannot set property 'name' of undefined Jul 04 17:59:34 volumio volumio[10189]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) Jul 04 17:59:34 volumio volumio[10189]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Jul 04 17:59:34 volumio volumio[10189]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Jul 04 17:59:34 volumio volumio[10189]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Jul 04 17:59:34 volumio volumio[10189]: at processImmediate (internal/timers.js:461:21) Jul 04 17:59:34 volumio volumio[10189]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 17:59:34 volumio sudo[16357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-04 17:58 Jul 04 17:59:34 volumio sudo[16357]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"