-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2024-04-06 00:36:31 CEST. --
Apr 06 00:35:01 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 06 00:35:01 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 06 00:35:01 volumio volumio[22859]: info: Discovery: Getting this device information
Apr 06 00:35:01 volumio volumio[22859]: info: CoreCommandRouter::volumioGetState
Apr 06 00:35:01 volumio volumio[22859]: info: CorePlayQueue::getTrack 0
Apr 06 00:35:01 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 06 00:35:01 volumio volumio[22859]: verbose: New Socket.io Connection to 192.168.1.239 from 192.168.1.164 UA: okhttp/4.9.2 Total Clients: 11
Apr 06 00:35:01 volumio volumio[22859]: info: CoreCommandRouter::volumioGetState
Apr 06 00:35:01 volumio volumio[22859]: info: CorePlayQueue::getTrack 0
Apr 06 00:35:01 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Apr 06 00:35:01 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Apr 06 00:35:10 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 06 00:35:10 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 06 00:35:10 volumio volumio[22859]: info: Discovery: Getting this device information
Apr 06 00:35:10 volumio volumio[22859]: info: CoreCommandRouter::volumioGetState
Apr 06 00:35:10 volumio volumio[22859]: info: CorePlayQueue::getTrack 0
Apr 06 00:35:10 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 06 00:35:14 volumio sudo[23328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 06 00:35:14 volumio sudo[23328]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 06 00:35:14 volumio sudo[23328]: pam_unix(sudo:session): session closed for user root
Apr 06 00:35:14 volumio sudo[23331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 06 00:35:14 volumio sudo[23331]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 06 00:35:14 volumio sudo[23331]: pam_unix(sudo:session): session closed for user root
Apr 06 00:35:14 volumio volumio[22859]: verbose: New Socket.io Connection to 192.168.1.239 from 192.168.1.164 UA: okhttp/4.9.2 Total Clients: 12
Apr 06 00:35:14 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Apr 06 00:35:15 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 06 00:35:15 volumio volumio[22859]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 06 00:35:15 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 06 00:35:15 volumio volumio[22859]: info: CoreCommandRouter::volumioGetState
Apr 06 00:35:15 volumio volumio[22859]: info: CorePlayQueue::getTrack 0
Apr 06 00:35:15 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Apr 06 00:35:15 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 06 00:35:15 volumio volumio[22859]: info: Received Get System Info
Apr 06 00:35:15 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 06 00:35:15 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 06 00:35:15 volumio volumio[22859]: info: Discovery: Getting this device information
Apr 06 00:35:15 volumio volumio[22859]: info: CoreCommandRouter::volumioGetState
Apr 06 00:35:15 volumio volumio[22859]: info: CorePlayQueue::getTrack 0
Apr 06 00:35:15 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 06 00:35:15 volumio volumio[22859]: info: CoreCommandRouter::volumioGetState
Apr 06 00:35:15 volumio volumio[22859]: info: CorePlayQueue::getTrack 0
Apr 06 00:35:15 volumio volumio[22859]: info: Listing playlists
Apr 06 00:35:15 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Apr 06 00:35:15 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 06 00:35:15 volumio volumio[22859]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 06 00:35:15 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Apr 06 00:35:16 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 06 00:35:16 volumio volumio[22859]: info: Received Get System Info
Apr 06 00:35:16 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 06 00:35:16 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 06 00:35:16 volumio volumio[22859]: info: Discovery: Getting this device information
Apr 06 00:35:16 volumio volumio[22859]: info: CoreCommandRouter::volumioGetState
Apr 06 00:35:16 volumio volumio[22859]: info: CorePlayQueue::getTrack 0
Apr 06 00:35:16 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 06 00:35:16 volumio volumio[22859]: info: CoreCommandRouter::volumioGetQueue
Apr 06 00:35:16 volumio volumio[22859]: info: CoreStateMachine::getQueue
Apr 06 00:35:16 volumio volumio[22859]: info: CorePlayQueue::getQueue
Apr 06 00:35:17 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 06 00:35:17 volumio volumio[22859]: info: Received Get System Info
Apr 06 00:35:17 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 06 00:35:17 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 06 00:35:17 volumio volumio[22859]: info: Discovery: Getting this device information
Apr 06 00:35:17 volumio volumio[22859]: info: CoreCommandRouter::volumioGetState
Apr 06 00:35:17 volumio volumio[22859]: info: CorePlayQueue::getTrack 0
Apr 06 00:35:17 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 06 00:35:20 volumio volumio[22859]: info: Preload queue cleared
Apr 06 00:35:20 volumio volumio[22859]: info: Preloading song: spotify:track:2heR3rKUhBmOncJmkvOtzX
Apr 06 00:35:20 volumio volumio[22859]: info: Preloading song: spotify:track:2aibwv5hGXSgw7Yru8IYTO
Apr 06 00:35:20 volumio volumio[22859]: info: Preloading song: spotify:track:2hjuuq7BjZvgoScDYbCe4T
Apr 06 00:35:20 volumio volumio[22859]: info: Preloading song: spotify:track:5KTZgG84bKFGm53lhLtTqc
Apr 06 00:35:20 volumio volumio[22859]: info: Preloading song: spotify:track:5KTZgG84bKFGm53lhLtTqc
Apr 06 00:35:20 volumio volumio[22859]: info: Preloading song: spotify:track:7kcApwUclbIw9B6MuVmqPc
Apr 06 00:35:20 volumio volumio[22859]: info: Preloading song: spotify:track:3Nk5OfYkSF1Xi1nIUVustg
Apr 06 00:35:20 volumio volumio[22859]: info: Preloading song: spotify:track:0wEGo9LsSrSUz9HOtraWAO
Apr 06 00:35:20 volumio volumio[22859]: info: Preloading song: spotify:track:1G391cbiT3v3Cywg8T7DM1
Apr 06 00:35:20 volumio volumio[22859]: info: Preloading song: spotify:track:43UejonUIN69O8NNAFRu5X
Apr 06 00:35:20 volumio volumio[22859]: info: Preloading song: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22%C3%87a%20s'%C3%A9claircit%20devant%22%2C%22artist%22%3A%22Mathieu%20Des%20Longchamps%22%2C%22album%22%3A%22Vivo%20en%20Panam%C3%A1%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2Fx1uE9b5D4ihtI925o8Ib7rM-eIdJIVZxvi6YuLuOXnywsTz-KVnYeNx5BCNq1DXSvOlEs9iyKshrWE4%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%222y8cR2PAe9c%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%222y8cR2PAe9c%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D
Apr 06 00:35:20 volumio volumio[22859]: info: Preloading song: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Paradis%20Perdus%22%2C%22artist%22%3A%22Christine%20and%20the%20Queens%22%2C%22album%22%3A%22Chaleur%20Humaine%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2Fi_knyfjXw6ErZtI9Tggt_9--H-gRV8VMDQF1v-TBwPvjzmk7REHVpSrujTu2V9BaTwpeMgsKjCz6GQE%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22M-WVF9hcC-M%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22M-WVF9hcC-M%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D
Apr 06 00:35:20 volumio volumio[22859]: info: Exploding uri spotify:track:2heR3rKUhBmOncJmkvOtzX in service spop
Apr 06 00:35:20 volumio volumio[22859]: SPOTIFY: EXPLODING URI:spotify:track:2heR3rKUhBmOncJmkvOtzX
Apr 06 00:35:20 volumio volumio[22859]: info: Exploding uri spotify:track:2aibwv5hGXSgw7Yru8IYTO in service spop
Apr 06 00:35:20 volumio volumio[22859]: SPOTIFY: EXPLODING URI:spotify:track:2aibwv5hGXSgw7Yru8IYTO
Apr 06 00:35:20 volumio volumio[22859]: info: Exploding uri spotify:track:2hjuuq7BjZvgoScDYbCe4T in service spop
Apr 06 00:35:20 volumio volumio[22859]: SPOTIFY: EXPLODING URI:spotify:track:2hjuuq7BjZvgoScDYbCe4T
Apr 06 00:35:20 volumio volumio[22859]: info: Exploding uri spotify:track:5KTZgG84bKFGm53lhLtTqc in service spop
Apr 06 00:35:20 volumio volumio[22859]: SPOTIFY: EXPLODING URI:spotify:track:5KTZgG84bKFGm53lhLtTqc
Apr 06 00:35:21 volumio volumio[22859]: info: Exploding uri spotify:track:5KTZgG84bKFGm53lhLtTqc in service spop
Apr 06 00:35:21 volumio volumio[22859]: SPOTIFY: EXPLODING URI:spotify:track:5KTZgG84bKFGm53lhLtTqc
Apr 06 00:35:21 volumio volumio[22859]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2heR3rKUhBmOncJmkvOtzX","service":"spop","name":"Rise Up","artist":"Charles Monneraud","album":"Rise Up","type":"song","duration":210,"albumart":"https://i.scdn.co/image/ab67616d0000b2732cec0ce8821ade0bd394e1b5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}]
Apr 06 00:35:21 volumio volumio[22859]: info: Exploding uri spotify:track:7kcApwUclbIw9B6MuVmqPc in service spop
Apr 06 00:35:21 volumio volumio[22859]: SPOTIFY: EXPLODING URI:spotify:track:7kcApwUclbIw9B6MuVmqPc
Apr 06 00:35:21 volumio volumio[22859]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2aibwv5hGXSgw7Yru8IYTO","service":"spop","name":"Snow (Hey Oh)","artist":"Red Hot Chili Peppers","album":"Stadium Arcadium","type":"song","duration":334,"albumart":"https://i.scdn.co/image/ab67616d0000b27309fd83d32aee93dceba78517","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}]
Apr 06 00:35:21 volumio volumio[22859]: info: Exploding uri spotify:track:3Nk5OfYkSF1Xi1nIUVustg in service spop
Apr 06 00:35:21 volumio volumio[22859]: SPOTIFY: EXPLODING URI:spotify:track:3Nk5OfYkSF1Xi1nIUVustg
Apr 06 00:35:21 volumio volumio[22859]: info: Exploding uri spotify:track:0wEGo9LsSrSUz9HOtraWAO in service spop
Apr 06 00:35:21 volumio volumio[22859]: SPOTIFY: EXPLODING URI:spotify:track:0wEGo9LsSrSUz9HOtraWAO
Apr 06 00:35:21 volumio volumio[22859]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5KTZgG84bKFGm53lhLtTqc","service":"spop","name":"Mwaki","artist":"Zerb","album":"SURRENDER","type":"song","duration":208,"albumart":"https://i.scdn.co/image/ab67616d0000b2733e56ef77fe414ce4af6334a4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}]
Apr 06 00:35:21 volumio volumio[22859]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2hjuuq7BjZvgoScDYbCe4T","service":"spop","name":"Would You Stand by me (feat. Claire Denamur) - French Edit","artist":"Diva Faune","album":"Would You Stand by me (feat. Claire Denamur) [French Edit]","type":"song","duration":196,"albumart":"https://i.scdn.co/image/ab67616d0000b27376aa6ae73be107cd6599082b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}]
Apr 06 00:35:21 volumio volumio[22859]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5KTZgG84bKFGm53lhLtTqc","service":"spop","name":"Mwaki","artist":"Zerb","album":"SURRENDER","type":"song","duration":208,"albumart":"https://i.scdn.co/image/ab67616d0000b2733e56ef77fe414ce4af6334a4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}]
Apr 06 00:35:21 volumio volumio[22859]: info: Exploding uri spotify:track:1G391cbiT3v3Cywg8T7DM1 in service spop
Apr 06 00:35:21 volumio volumio[22859]: SPOTIFY: EXPLODING URI:spotify:track:1G391cbiT3v3Cywg8T7DM1
Apr 06 00:35:21 volumio volumio[22859]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7kcApwUclbIw9B6MuVmqPc","service":"spop","name":"Overcompensate (edit)","artist":"Twenty One Pilots","album":"Overcompensate","type":"song","duration":190,"albumart":"https://i.scdn.co/image/ab67616d0000b2738627dc62d9612fbc1e3bc5c8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}]
Apr 06 00:35:21 volumio volumio[22859]: info: Exploding uri spotify:track:43UejonUIN69O8NNAFRu5X in service spop
Apr 06 00:35:21 volumio volumio[22859]: SPOTIFY: EXPLODING URI:spotify:track:43UejonUIN69O8NNAFRu5X
Apr 06 00:35:21 volumio volumio[22859]: info: Exploding uri ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22%C3%87a%20s'%C3%A9claircit%20devant%22%2C%22artist%22%3A%22Mathieu%20Des%20Longchamps%22%2C%22album%22%3A%22Vivo%20en%20Panam%C3%A1%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2Fx1uE9b5D4ihtI925o8Ib7rM-eIdJIVZxvi6YuLuOXnywsTz-KVnYeNx5BCNq1DXSvOlEs9iyKshrWE4%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%222y8cR2PAe9c%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%222y8cR2PAe9c%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D in service ytmusic
Apr 06 00:35:21 volumio volumio[22859]: info: [ytmusic-browse] explodeUri: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22%C3%87a%20s'%C3%A9claircit%20devant%22%2C%22artist%22%3A%22Mathieu%20Des%20Longchamps%22%2C%22album%22%3A%22Vivo%20en%20Panam%C3%A1%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2Fx1uE9b5D4ihtI925o8Ib7rM-eIdJIVZxvi6YuLuOXnywsTz-KVnYeNx5BCNq1DXSvOlEs9iyKshrWE4%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%222y8cR2PAe9c%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%222y8cR2PAe9c%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D
Apr 06 00:35:21 volumio volumio[22859]: info: Exploding uri ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Paradis%20Perdus%22%2C%22artist%22%3A%22Christine%20and%20the%20Queens%22%2C%22album%22%3A%22Chaleur%20Humaine%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2Fi_knyfjXw6ErZtI9Tggt_9--H-gRV8VMDQF1v-TBwPvjzmk7REHVpSrujTu2V9BaTwpeMgsKjCz6GQE%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22M-WVF9hcC-M%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22M-WVF9hcC-M%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D in service ytmusic
Apr 06 00:35:21 volumio volumio[22859]: info: [ytmusic-browse] explodeUri: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Paradis%20Perdus%22%2C%22artist%22%3A%22Christine%20and%20the%20Queens%22%2C%22album%22%3A%22Chaleur%20Humaine%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2Fi_knyfjXw6ErZtI9Tggt_9--H-gRV8VMDQF1v-TBwPvjzmk7REHVpSrujTu2V9BaTwpeMgsKjCz6GQE%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22M-WVF9hcC-M%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22M-WVF9hcC-M%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D
Apr 06 00:35:21 volumio volumio[22859]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3Nk5OfYkSF1Xi1nIUVustg","service":"spop","name":"Say It Again","artist":"Jestofunk","album":"100% Hits - Années 90","type":"song","duration":223,"albumart":"https://i.scdn.co/image/ab67616d0000b273b86774ffa4841ae8b51c67d0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}]
Apr 06 00:35:21 volumio volumio[22859]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1G391cbiT3v3Cywg8T7DM1","service":"spop","name":"Scar Tissue","artist":"Red Hot Chili Peppers","album":"Californication (Deluxe Edition)","type":"song","duration":215,"albumart":"https://i.scdn.co/image/ab67616d0000b27394d08ab63e57b0cae74e8595","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}]
Apr 06 00:35:21 volumio volumio[22859]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:43UejonUIN69O8NNAFRu5X","service":"spop","name":"Fulenn","artist":"Alvan","album":"Magma","type":"song","duration":166,"albumart":"https://i.scdn.co/image/ab67616d0000b273e12fda0bda5720f68bc312e6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}]
Apr 06 00:35:21 volumio volumio[22859]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0wEGo9LsSrSUz9HOtraWAO","service":"spop","name":"Overcompensate","artist":"Twenty One Pilots","album":"Overcompensate","type":"song","duration":236,"albumart":"https://i.scdn.co/image/ab67616d0000b2738627dc62d9612fbc1e3bc5c8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}]
Apr 06 00:35:23 volumio volumio[22859]: info: Preload queue cleared
Apr 06 00:35:23 volumio volumio[22859]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 06 00:35:23 volumio volumio[22859]: info: CoreStateMachine::ClearQueue
Apr 06 00:35:23 volumio volumio[22859]: info: CoreStateMachine::stop
Apr 06 00:35:23 volumio volumio[22859]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 06 00:35:23 volumio volumio[22859]: info: CorePlayQueue::clearPlayQueue
Apr 06 00:35:23 volumio volumio[22859]: info: CorePlayQueue::saveQueue
Apr 06 00:35:23 volumio volumio[22859]: info: CoreCommandRouter::volumioPushQueue
Apr 06 00:35:23 volumio volumio[22859]: info: CoreStateMachine::addQueueItems
Apr 06 00:35:23 volumio volumio[22859]: info: CorePlayQueue::addQueueItems
Apr 06 00:35:23 volumio volumio[22859]: info: Preload queue cleared
Apr 06 00:35:23 volumio volumio[22859]: info: Adding Item to queue: spotify:track:2heR3rKUhBmOncJmkvOtzX
Apr 06 00:35:23 volumio volumio[22859]: info: Using cached record of: spotify:track:2heR3rKUhBmOncJmkvOtzX
Apr 06 00:35:23 volumio volumio[22859]: info: CoreCommandRouter::volumioPushQueue
Apr 06 00:35:23 volumio volumio[22859]: info: CorePlayQueue::saveQueue
Apr 06 00:35:23 volumio volumio[22859]: info: CoreStateMachine::updateTrackBlock
Apr 06 00:35:23 volumio volumio[22859]: info: CorePlayQueue::getTrackBlock
Apr 06 00:35:23 volumio volumio[22859]: info: CoreCommandRouter::volumioPlay
Apr 06 00:35:23 volumio volumio[22859]: info: CoreStateMachine::play index 0
Apr 06 00:35:23 volumio volumio[22859]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 06 00:35:23 volumio volumio[22859]: info: CoreStateMachine::stop
Apr 06 00:35:23 volumio volumio[22859]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 06 00:35:23 volumio volumio[22859]: info: CoreStateMachine::play index undefined
Apr 06 00:35:23 volumio volumio[22859]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 06 00:35:23 volumio volumio[22859]: info: CorePlayQueue::getTrack 0
Apr 06 00:35:23 volumio volumio[22859]: info: CoreStateMachine::startPlaybackTimer
Apr 06 00:35:23 volumio volumio[22859]: info: CorePlayQueue::getTrack 0
Apr 06 00:35:23 volumio volumio[22859]: info: [1712356523886] ControllerSpotify::clearAddPlayTrack
Apr 06 00:35:23 volumio volumio[22859]: info: Sending Spotify command with payload to local API: /player/play
Apr 06 00:35:24 volumio go-librespot[23113]: time="2024-04-06T00:35:24+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)"
Apr 06 00:35:24 volumio go-librespot[23113]: time="2024-04-06T00:35:24+02:00" level=debug msg="loading track spotify:track:2heR3rKUhBmOncJmkvOtzX (paused: false, position: 0ms)"
Apr 06 00:35:24 volumio go-librespot[23113]: time="2024-04-06T00:35:24+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Apr 06 00:35:24 volumio go-librespot[23113]: time="2024-04-06T00:35:24+02:00" level=trace msg="emitting websocket event: will_play"
Apr 06 00:35:24 volumio volumio[22859]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2heR3rKUhBmOncJmkvOtzX","play_origin":"go-librespot"}}
Apr 06 00:35:24 volumio go-librespot[23113]: time="2024-04-06T00:35:24+02:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:2heR3rKUhBmOncJmkvOtzX"
Apr 06 00:35:24 volumio go-librespot[23113]: time="2024-04-06T00:35:24+02:00" level=debug msg="requested aes key for file a2a4c0467837217ffad1fd765fd3f8d9a2644c18, gid: 2heR3rKUhBmOncJmkvOtzX"
Apr 06 00:35:24 volumio go-librespot[23113]: time="2024-04-06T00:35:24+02:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: failed retrieving audio key: failed retrieving aes key with code 1"
Apr 06 00:35:24 volumio volumio[22859]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error
Apr 06 00:35:33 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 06 00:35:33 volumio volumio[22859]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Apr 06 00:35:33 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Apr 06 00:35:33 volumio volumio[22859]: info: Received Get System Version
Apr 06 00:35:33 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 06 00:35:33 volumio volumio[22859]: info: Received Get System Info
Apr 06 00:35:33 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 06 00:35:33 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 06 00:35:33 volumio volumio[22859]: info: Discovery: Getting this device information
Apr 06 00:35:33 volumio volumio[22859]: info: CoreCommandRouter::volumioGetState
Apr 06 00:35:33 volumio volumio[22859]: info: CorePlayQueue::getTrack 0
Apr 06 00:35:33 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 06 00:35:49 volumio volumio[22859]: info: Preload queue cleared
Apr 06 00:35:49 volumio volumio[22859]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 06 00:35:49 volumio volumio[22859]: info: CoreStateMachine::ClearQueue
Apr 06 00:35:49 volumio volumio[22859]: info: CoreStateMachine::stop
Apr 06 00:35:49 volumio volumio[22859]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 06 00:35:49 volumio volumio[22859]: info: CorePlayQueue::clearPlayQueue
Apr 06 00:35:49 volumio volumio[22859]: info: CorePlayQueue::saveQueue
Apr 06 00:35:49 volumio volumio[22859]: info: CoreCommandRouter::volumioPushQueue
Apr 06 00:35:49 volumio volumio[22859]: info: CoreStateMachine::addQueueItems
Apr 06 00:35:49 volumio volumio[22859]: info: CorePlayQueue::addQueueItems
Apr 06 00:35:49 volumio volumio[22859]: info: Preload queue cleared
Apr 06 00:35:49 volumio volumio[22859]: info: Adding Item to queue: spotify:track:2heR3rKUhBmOncJmkvOtzX
Apr 06 00:35:49 volumio volumio[22859]: info: Using cached record of: spotify:track:2heR3rKUhBmOncJmkvOtzX
Apr 06 00:35:49 volumio volumio[22859]: info: CoreCommandRouter::volumioPushQueue
Apr 06 00:35:49 volumio volumio[22859]: info: CorePlayQueue::saveQueue
Apr 06 00:35:49 volumio volumio[22859]: info: CoreStateMachine::updateTrackBlock
Apr 06 00:35:49 volumio volumio[22859]: info: CorePlayQueue::getTrackBlock
Apr 06 00:35:49 volumio volumio[22859]: info: CoreCommandRouter::volumioPlay
Apr 06 00:35:49 volumio volumio[22859]: info: CoreStateMachine::play index 0
Apr 06 00:35:49 volumio volumio[22859]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 06 00:35:49 volumio volumio[22859]: info: CoreStateMachine::stop
Apr 06 00:35:49 volumio volumio[22859]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 06 00:35:49 volumio volumio[22859]: info: CoreStateMachine::play index undefined
Apr 06 00:35:49 volumio volumio[22859]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 06 00:35:49 volumio volumio[22859]: info: CorePlayQueue::getTrack 0
Apr 06 00:35:49 volumio volumio[22859]: info: CoreStateMachine::startPlaybackTimer
Apr 06 00:35:49 volumio volumio[22859]: info: CorePlayQueue::getTrack 0
Apr 06 00:35:49 volumio volumio[22859]: info: [1712356549635] ControllerSpotify::clearAddPlayTrack
Apr 06 00:35:49 volumio volumio[22859]: info: Sending Spotify command with payload to local API: /player/play
Apr 06 00:35:49 volumio go-librespot[23113]: time="2024-04-06T00:35:49+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)"
Apr 06 00:35:49 volumio go-librespot[23113]: time="2024-04-06T00:35:49+02:00" level=debug msg="loading track spotify:track:2heR3rKUhBmOncJmkvOtzX (paused: false, position: 1ms)"
Apr 06 00:35:49 volumio go-librespot[23113]: time="2024-04-06T00:35:49+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Apr 06 00:35:49 volumio go-librespot[23113]: time="2024-04-06T00:35:49+02:00" level=trace msg="emitting websocket event: will_play"
Apr 06 00:35:49 volumio volumio[22859]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2heR3rKUhBmOncJmkvOtzX","play_origin":"go-librespot"}}
Apr 06 00:35:49 volumio go-librespot[23113]: time="2024-04-06T00:35:49+02:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:2heR3rKUhBmOncJmkvOtzX"
Apr 06 00:35:49 volumio go-librespot[23113]: time="2024-04-06T00:35:49+02:00" level=debug msg="requested aes key for file a2a4c0467837217ffad1fd765fd3f8d9a2644c18, gid: 2heR3rKUhBmOncJmkvOtzX"
Apr 06 00:35:49 volumio go-librespot[23113]: time="2024-04-06T00:35:49+02:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: failed retrieving audio key: failed retrieving aes key with code 1"
Apr 06 00:35:49 volumio volumio[22859]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error
Apr 06 00:36:23 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 06 00:36:23 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 06 00:36:23 volumio volumio[22859]: info: Discovery: Getting this device information
Apr 06 00:36:23 volumio volumio[22859]: info: CoreCommandRouter::volumioGetState
Apr 06 00:36:23 volumio volumio[22859]: info: CorePlayQueue::getTrack 0
Apr 06 00:36:23 volumio volumio[22859]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 06 00:36:30 volumio volumio[22859]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________
Apr 06 00:36:30 volumio volumio[22859]: info: PLUGIN onReboot : networkfs
Apr 06 00:36:30 volumio volumio[22859]: info: PLUGIN onReboot : audiophonicsonoff
Apr 06 00:36:30 volumio volumio[22859]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 06 00:36:30 volumio volumio[22859]: TypeError: Cannot read property 'writeSync' of undefined
Apr 06 00:36:30 volumio volumio[22859]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25)
Apr 06 00:36:30 volumio volumio[22859]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30)
Apr 06 00:36:30 volumio volumio[22859]: at HashMap. (/volumio/app/pluginmanager.js:668:31)
Apr 06 00:36:30 volumio volumio[22859]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10)
Apr 06 00:36:30 volumio volumio[22859]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7)
Apr 06 00:36:30 volumio volumio[22859]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20)
Apr 06 00:36:30 volumio volumio[22859]: at CoreCommandRouter.reboot (/volumio/app/index.js:1325:22)
Apr 06 00:36:30 volumio volumio[22859]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:836:33)
Apr 06 00:36:30 volumio volumio[22859]: at Socket.emit (events.js:315:20)
Apr 06 00:36:30 volumio volumio[22859]: at /volumio/node_modules/socket.io/lib/socket.js:503:12
Apr 06 00:36:30 volumio volumio[22859]: at processTicksAndRejections (internal/process/task_queues.js:75:11)
Apr 06 00:36:30 volumio volumio[22859]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 06 00:36:31 volumio sudo[23395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-06 00:35
Apr 06 00:36:31 volumio sudo[23395]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET"
VOLUMIO_VERSION="3.631"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"