-- 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"