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