Dec 06 14:22:00 volumio-vers-4 kernel: hwmon hwmon1: Undervoltage detected! Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:00 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces state update: player Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: ControllerMpd::getState Dec 06 14:22:00 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: FusionDsp - ---- read samplerate from file: 48000 Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: FusionDsp - ---- read samplerate from file: 48000 Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:00 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces state update: player Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: sendMpdCommand status took 29 milliseconds Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: ControllerMpd::getState Dec 06 14:22:00 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Dec 06 14:22:00 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseState Dec 06 14:22:00 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: sendMpdCommand status took 10 milliseconds Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 06 14:22:00 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseState Dec 06 14:22:00 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 14:22:00 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseTrackInfo Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: ControllerMpd::pushState Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::servicePushState Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:00 volumio-vers-4 volumio[1338]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Gwen Stefani - You Make It Feel Like Christmas (Official Music Video) ft. Blake Shelton","artist":"GwenStefaniVEVO","album":"YouTube","uri":"https://rr3---sn-obppu-hm2d.googlevideo.com/videoplayback?expire=1765048919&ei=9y00acEtivSL2g-6tbLZDg&ip=88.147.44.111&id=o-ALCcb4d4hIVQxDXlB8xZUycM-QdcZg2Q8uWYX7HdI1Xu&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=703&met=1765027319%2C&mh=7R&mm=31%2C29&mn=sn-obppu-hm2d%2Csn-hpa7zns6&ms=au%2Crdu&mv=m&mvi=3&pl=21&rms=au%2Cau&gcr=it&initcwndbps=2213750&bui=AdEuB5T9IMGQBKpdR2kbXKAsZsqcFiVgcIVrxz3--jLOTl3Fx4jikOnESX6WRN2YWGV76QU7qj-YW7WD&spc=6b0G_CRQQYoRQkWlYu6tKmwZTreL8O9kPN6yOMXUGH0LwGNa4tH0hA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Nvdvn7UMOqaxAoV_T1VSwjgQ&rqh=1&gir=yes&clen=2949803&dur=183.461&lmt=1714390373789002&mt=1765026803&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968%2C51669167%2C51673847&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4502434&n=42Am9G4YfXv6qA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAMQI9pCxhs_XidZctDgfk8Zy0LLU18kz2mlQhSXXdwXwAiBJgboOIixW2_LCd8UNo17XmWpC4df3J9AC60nh2k0UTw%3D%3D&sig=AJfQdSswRgIhAMr2pHdL3Ic8uE9Ch0fnXuLu0l83ChnRIluXyaxstOE3AiEAnZcZqsdnL3oYuSJOBc8ZfC5GGb0OOmG3GKE_tbg06Aw%3D&pot=MlV5EsKIPpT4UnksFr_sxTCtMKYiH597EQkgF7hmAbua-x0xRKVJmJMwuhpb0qpiy-yH6o7LWShZeN4_M-fQ6mUpbEMYLA8w6M9NyF2sqoBhVam9ozUI&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Dec 06 14:22:00 volumio-vers-4 volumio[1338]: verbose: CURRENT POSITION 0 Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: CoreStateMachine::syncState stateService play Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: CoreStateMachine::syncState currentStatus stop Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: ------------------------------ 46ms Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 06 14:22:00 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseTrackInfo Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: ControllerMpd::pushState Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::servicePushState Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:00 volumio-vers-4 volumio[1338]: verbose: STATE SERVICE {"status":"play","position":0,"seek":870,"duration":183,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Gwen Stefani - You Make It Feel Like Christmas (Official Music Video) ft. Blake Shelton","artist":"GwenStefaniVEVO","album":"YouTube","uri":"https://rr3---sn-obppu-hm2d.googlevideo.com/videoplayback?expire=1765048919&ei=9y00acEtivSL2g-6tbLZDg&ip=88.147.44.111&id=o-ALCcb4d4hIVQxDXlB8xZUycM-QdcZg2Q8uWYX7HdI1Xu&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=703&met=1765027319%2C&mh=7R&mm=31%2C29&mn=sn-obppu-hm2d%2Csn-hpa7zns6&ms=au%2Crdu&mv=m&mvi=3&pl=21&rms=au%2Cau&gcr=it&initcwndbps=2213750&bui=AdEuB5T9IMGQBKpdR2kbXKAsZsqcFiVgcIVrxz3--jLOTl3Fx4jikOnESX6WRN2YWGV76QU7qj-YW7WD&spc=6b0G_CRQQYoRQkWlYu6tKmwZTreL8O9kPN6yOMXUGH0LwGNa4tH0hA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Nvdvn7UMOqaxAoV_T1VSwjgQ&rqh=1&gir=yes&clen=2949803&dur=183.461&lmt=1714390373789002&mt=1765026803&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968%2C51669167%2C51673847&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4502434&n=42Am9G4YfXv6qA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAMQI9pCxhs_XidZctDgfk8Zy0LLU18kz2mlQhSXXdwXwAiBJgboOIixW2_LCd8UNo17XmWpC4df3J9AC60nh2k0UTw%3D%3D&sig=AJfQdSswRgIhAMr2pHdL3Ic8uE9Ch0fnXuLu0l83ChnRIluXyaxstOE3AiEAnZcZqsdnL3oYuSJOBc8ZfC5GGb0OOmG3GKE_tbg06Aw%3D&pot=MlV5EsKIPpT4UnksFr_sxTCtMKYiH597EQkgF7hmAbua-x0xRKVJmJMwuhpb0qpiy-yH6o7LWShZeN4_M-fQ6mUpbEMYLA8w6M9NyF2sqoBhVam9ozUI&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Dec 06 14:22:00 volumio-vers-4 volumio[1338]: verbose: CURRENT POSITION 0 Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: CoreStateMachine::syncState stateService play Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: CoreStateMachine::syncState currentStatus play Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: Received an update from plugin. extracting info from payload Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: [youtube2] (AutoplayManager) Added mpdStateListener Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: ------------------------------ 42ms Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status play Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status play Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 06 14:22:00 volumio-vers-4 sudo[16745]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 06 14:22:00 volumio-vers-4 sudo[16745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:00 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 06 14:22:00 volumio-vers-4 sudo[16747]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 06 14:22:00 volumio-vers-4 sudo[16747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:00 volumio-vers-4 sudo[16750]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 06 14:22:00 volumio-vers-4 sudo[16750]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:00 volumio-vers-4 sudo[16752]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 06 14:22:00 volumio-vers-4 sudo[16752]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:01 volumio-vers-4 sudo[16747]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:01 volumio-vers-4 sudo[16745]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:01 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Dec 06 14:22:01 volumio-vers-4 volumio[1338]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Dec 06 14:22:01 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Dec 06 14:22:01 volumio-vers-4 volumio[1338]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Dec 06 14:22:01 volumio-vers-4 systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Dec 06 14:22:01 volumio-vers-4 sudo[16750]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:01 volumio-vers-4 sudo[16752]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:01 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Started Dec 06 14:22:01 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Started Dec 06 14:22:01 volumio-vers-4 volumio[16758]: Traceback (most recent call last): Dec 06 14:22:01 volumio-vers-4 volumio[16758]: File "/data/plugins/user_interface/peppymeterbasic/BasicPeppyMeter/peppymeter.py", line 18, in Dec 06 14:22:01 volumio-vers-4 volumio[16758]: import pygame Dec 06 14:22:01 volumio-vers-4 volumio[16758]: ModuleNotFoundError: No module named 'pygame' Dec 06 14:22:01 volumio-vers-4 systemd[1]: peppymeterbasic.service: Main process exited, code=exited, status=1/FAILURE Dec 06 14:22:01 volumio-vers-4 systemd[1]: peppymeterbasic.service: Failed with result 'exit-code'. Dec 06 14:22:04 volumio-vers-4 volumio[1338]: Searching plugin music_service/youtube2 Dec 06 14:22:04 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Dec 06 14:22:05 volumio-vers-4 volumio[1338]: Searching plugin music_service/youtube2 Dec 06 14:22:05 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Dec 06 14:22:05 volumio-vers-4 volumio[1338]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Dec 06 14:22:05 volumio-vers-4 volumio[1338]: command_run: { startIndex: 0, length: 87, onTap: { innertubeCommand: [Object] } }, Dec 06 14:22:05 volumio-vers-4 volumio[1338]: input_data: { Dec 06 14:22:05 volumio-vers-4 volumio[1338]: content: 'Taylor Swift - ME! (feat. Brendon Urie of Panic! At The Disco) ft. Brendon Urie · 4:09', Dec 06 14:22:05 volumio-vers-4 volumio[1338]: commandRuns: [ [Object] ] Dec 06 14:22:05 volumio-vers-4 volumio[1338]: }, Dec 06 14:22:05 volumio-vers-4 volumio[1338]: parsed_runs: [ Dec 06 14:22:05 volumio-vers-4 volumio[1338]: { Dec 06 14:22:05 volumio-vers-4 volumio[1338]: text: 'Taylor Swift - ME! (feat. Brendon Urie of Panic! At The Disco) ft. Brendon Urie · 4:09', Dec 06 14:22:05 volumio-vers-4 volumio[1338]: startIndex: 0 Dec 06 14:22:05 volumio-vers-4 volumio[1338]: } Dec 06 14:22:05 volumio-vers-4 volumio[1338]: ] Dec 06 14:22:05 volumio-vers-4 volumio[1338]: } Dec 06 14:22:05 volumio-vers-4 volumio[1338]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Dec 06 14:22:05 volumio-vers-4 volumio[1338]: command_run: { startIndex: 0, length: 27, onTap: { innertubeCommand: [Object] } }, Dec 06 14:22:05 volumio-vers-4 volumio[1338]: input_data: { content: 'The Fate of Ophelia · 3:47', commandRuns: [ [Object] ] }, Dec 06 14:22:05 volumio-vers-4 volumio[1338]: parsed_runs: [ { text: 'The Fate of Ophelia · 3:47', startIndex: 0 } ] Dec 06 14:22:05 volumio-vers-4 volumio[1338]: } Dec 06 14:22:06 volumio-vers-4 volumio[1338]: info: All search sources collected, pushing search results Dec 06 14:22:06 volumio-vers-4 volumio[1338]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Dec 06 14:22:06 volumio-vers-4 volumio[1338]: command_run: { startIndex: 0, length: 61, onTap: { innertubeCommand: [Object] } }, Dec 06 14:22:06 volumio-vers-4 volumio[1338]: input_data: { Dec 06 14:22:06 volumio-vers-4 volumio[1338]: content: 'Men At Work - Dr. Heckyll & Mr. Jive (Official Video) · 4:20', Dec 06 14:22:06 volumio-vers-4 volumio[1338]: commandRuns: [ [Object] ] Dec 06 14:22:06 volumio-vers-4 volumio[1338]: }, Dec 06 14:22:06 volumio-vers-4 volumio[1338]: parsed_runs: [ Dec 06 14:22:06 volumio-vers-4 volumio[1338]: { Dec 06 14:22:06 volumio-vers-4 volumio[1338]: text: 'Men At Work - Dr. Heckyll & Mr. Jive (Official Video) · 4:20', Dec 06 14:22:06 volumio-vers-4 volumio[1338]: startIndex: 0 Dec 06 14:22:06 volumio-vers-4 volumio[1338]: } Dec 06 14:22:06 volumio-vers-4 volumio[1338]: ] Dec 06 14:22:06 volumio-vers-4 volumio[1338]: } Dec 06 14:22:06 volumio-vers-4 volumio[1338]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Dec 06 14:22:06 volumio-vers-4 volumio[1338]: command_run: { startIndex: 0, length: 53, onTap: { innertubeCommand: [Object] } }, Dec 06 14:22:06 volumio-vers-4 volumio[1338]: input_data: { Dec 06 14:22:06 volumio-vers-4 volumio[1338]: content: "Men At Work - It's a Mistake (Official Video) · 4:33", Dec 06 14:22:06 volumio-vers-4 volumio[1338]: commandRuns: [ [Object] ] Dec 06 14:22:06 volumio-vers-4 volumio[1338]: }, Dec 06 14:22:06 volumio-vers-4 volumio[1338]: parsed_runs: [ Dec 06 14:22:06 volumio-vers-4 volumio[1338]: { Dec 06 14:22:06 volumio-vers-4 volumio[1338]: text: "Men At Work - It's a Mistake (Official Video) · 4:33", Dec 06 14:22:06 volumio-vers-4 volumio[1338]: startIndex: 0 Dec 06 14:22:06 volumio-vers-4 volumio[1338]: } Dec 06 14:22:06 volumio-vers-4 volumio[1338]: ] Dec 06 14:22:06 volumio-vers-4 volumio[1338]: } Dec 06 14:22:06 volumio-vers-4 volumio[1338]: info: All search sources collected, pushing search results Dec 06 14:22:08 volumio-vers-4 volumio[1338]: Searching plugin music_service/youtube2 Dec 06 14:22:08 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Dec 06 14:22:08 volumio-vers-4 volumio[1338]: info: All search sources collected, pushing search results Dec 06 14:22:10 volumio-vers-4 volumio[1338]: Searching plugin music_service/youtube2 Dec 06 14:22:10 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Dec 06 14:22:11 volumio-vers-4 volumio[1338]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Dec 06 14:22:11 volumio-vers-4 volumio[1338]: command_run: { startIndex: 0, length: 93, onTap: { innertubeCommand: [Object] } }, Dec 06 14:22:11 volumio-vers-4 volumio[1338]: input_data: { Dec 06 14:22:11 volumio-vers-4 volumio[1338]: content: 'A Man Without Love LYRICS Video Engelbert Humperdinck 1968 🌙 Moon Knight Episode 1 · 3:18', Dec 06 14:22:11 volumio-vers-4 volumio[1338]: commandRuns: [ [Object] ] Dec 06 14:22:11 volumio-vers-4 volumio[1338]: }, Dec 06 14:22:11 volumio-vers-4 volumio[1338]: parsed_runs: [ Dec 06 14:22:11 volumio-vers-4 volumio[1338]: { Dec 06 14:22:11 volumio-vers-4 volumio[1338]: text: 'A Man Without Love LYRICS Video Engelbert Humperdinck 1968 🌙 Moon Knight Episode 1 · 3:18', Dec 06 14:22:11 volumio-vers-4 volumio[1338]: startIndex: 0 Dec 06 14:22:11 volumio-vers-4 volumio[1338]: } Dec 06 14:22:11 volumio-vers-4 volumio[1338]: ] Dec 06 14:22:11 volumio-vers-4 volumio[1338]: } Dec 06 14:22:11 volumio-vers-4 volumio[1338]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Dec 06 14:22:11 volumio-vers-4 volumio[1338]: command_run: { startIndex: 0, length: 39, onTap: { innertubeCommand: [Object] } }, Dec 06 14:22:11 volumio-vers-4 volumio[1338]: input_data: { Dec 06 14:22:11 volumio-vers-4 volumio[1338]: content: 'Frank Sinatra - My Way (Lyrics) · 4:37', Dec 06 14:22:11 volumio-vers-4 volumio[1338]: commandRuns: [ [Object] ] Dec 06 14:22:11 volumio-vers-4 volumio[1338]: }, Dec 06 14:22:11 volumio-vers-4 volumio[1338]: parsed_runs: [ { text: 'Frank Sinatra - My Way (Lyrics) · 4:37', startIndex: 0 } ] Dec 06 14:22:11 volumio-vers-4 volumio[1338]: } Dec 06 14:22:11 volumio-vers-4 volumio[1338]: info: All search sources collected, pushing search results Dec 06 14:22:11 volumio-vers-4 volumio[1338]: Searching plugin music_service/youtube2 Dec 06 14:22:11 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Dec 06 14:22:11 volumio-vers-4 volumio[1338]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Dec 06 14:22:11 volumio-vers-4 volumio[1338]: command_run: { startIndex: 0, length: 63, onTap: { innertubeCommand: [Object] } }, Dec 06 14:22:11 volumio-vers-4 volumio[1338]: input_data: { Dec 06 14:22:11 volumio-vers-4 volumio[1338]: content: 'Men Without Hats - The Safety Dance (Official HD Video) · 2:44', Dec 06 14:22:11 volumio-vers-4 volumio[1338]: commandRuns: [ [Object] ] Dec 06 14:22:11 volumio-vers-4 volumio[1338]: }, Dec 06 14:22:11 volumio-vers-4 volumio[1338]: parsed_runs: [ Dec 06 14:22:11 volumio-vers-4 volumio[1338]: { Dec 06 14:22:11 volumio-vers-4 volumio[1338]: text: 'Men Without Hats - The Safety Dance (Official HD Video) · 2:44', Dec 06 14:22:11 volumio-vers-4 volumio[1338]: startIndex: 0 Dec 06 14:22:11 volumio-vers-4 volumio[1338]: } Dec 06 14:22:11 volumio-vers-4 volumio[1338]: ] Dec 06 14:22:11 volumio-vers-4 volumio[1338]: } Dec 06 14:22:11 volumio-vers-4 volumio[1338]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Dec 06 14:22:11 volumio-vers-4 volumio[1338]: command_run: { startIndex: 0, length: 50, onTap: { innertubeCommand: [Object] } }, Dec 06 14:22:11 volumio-vers-4 volumio[1338]: input_data: { Dec 06 14:22:11 volumio-vers-4 volumio[1338]: content: "Simple Minds - Don't You (Forget About Me) · 4:23", Dec 06 14:22:11 volumio-vers-4 volumio[1338]: commandRuns: [ [Object] ] Dec 06 14:22:11 volumio-vers-4 volumio[1338]: }, Dec 06 14:22:11 volumio-vers-4 volumio[1338]: parsed_runs: [ Dec 06 14:22:11 volumio-vers-4 volumio[1338]: { Dec 06 14:22:11 volumio-vers-4 volumio[1338]: text: "Simple Minds - Don't You (Forget About Me) · 4:23", Dec 06 14:22:11 volumio-vers-4 volumio[1338]: startIndex: 0 Dec 06 14:22:11 volumio-vers-4 volumio[1338]: } Dec 06 14:22:11 volumio-vers-4 volumio[1338]: ] Dec 06 14:22:11 volumio-vers-4 volumio[1338]: } Dec 06 14:22:11 volumio-vers-4 volumio[1338]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Dec 06 14:22:11 volumio-vers-4 volumio[1338]: command_run: { startIndex: 0, length: 39, onTap: { innertubeCommand: [Object] } }, Dec 06 14:22:11 volumio-vers-4 volumio[1338]: input_data: { Dec 06 14:22:11 volumio-vers-4 volumio[1338]: content: 'Ban The Game - Men Without Hats · 0:50', Dec 06 14:22:11 volumio-vers-4 volumio[1338]: commandRuns: [ [Object] ] Dec 06 14:22:11 volumio-vers-4 volumio[1338]: }, Dec 06 14:22:11 volumio-vers-4 volumio[1338]: parsed_runs: [ { text: 'Ban The Game - Men Without Hats · 0:50', startIndex: 0 } ] Dec 06 14:22:11 volumio-vers-4 volumio[1338]: } Dec 06 14:22:11 volumio-vers-4 volumio[1338]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Dec 06 14:22:11 volumio-vers-4 volumio[1338]: command_run: { startIndex: 0, length: 42, onTap: { innertubeCommand: [Object] } }, Dec 06 14:22:11 volumio-vers-4 volumio[1338]: input_data: { Dec 06 14:22:11 volumio-vers-4 volumio[1338]: content: 'Living In China - Men Without Hats · 3:06', Dec 06 14:22:11 volumio-vers-4 volumio[1338]: commandRuns: [ [Object] ] Dec 06 14:22:11 volumio-vers-4 volumio[1338]: }, Dec 06 14:22:11 volumio-vers-4 volumio[1338]: parsed_runs: [ Dec 06 14:22:11 volumio-vers-4 volumio[1338]: { Dec 06 14:22:11 volumio-vers-4 volumio[1338]: text: 'Living In China - Men Without Hats · 3:06', Dec 06 14:22:11 volumio-vers-4 volumio[1338]: startIndex: 0 Dec 06 14:22:11 volumio-vers-4 volumio[1338]: } Dec 06 14:22:11 volumio-vers-4 volumio[1338]: ] Dec 06 14:22:11 volumio-vers-4 volumio[1338]: } Dec 06 14:22:12 volumio-vers-4 volumio[1338]: info: All search sources collected, pushing search results Dec 06 14:22:13 volumio-vers-4 volumio[1338]: Searching plugin music_service/youtube2 Dec 06 14:22:13 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Dec 06 14:22:14 volumio-vers-4 volumio[1338]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Dec 06 14:22:14 volumio-vers-4 volumio[1338]: command_run: { startIndex: 0, length: 63, onTap: { innertubeCommand: [Object] } }, Dec 06 14:22:14 volumio-vers-4 volumio[1338]: input_data: { Dec 06 14:22:14 volumio-vers-4 volumio[1338]: content: 'Men Without Hats - The Safety Dance (Official HD Video) · 2:44', Dec 06 14:22:14 volumio-vers-4 volumio[1338]: commandRuns: [ [Object] ] Dec 06 14:22:14 volumio-vers-4 volumio[1338]: }, Dec 06 14:22:14 volumio-vers-4 volumio[1338]: parsed_runs: [ Dec 06 14:22:14 volumio-vers-4 volumio[1338]: { Dec 06 14:22:14 volumio-vers-4 volumio[1338]: text: 'Men Without Hats - The Safety Dance (Official HD Video) · 2:44', Dec 06 14:22:14 volumio-vers-4 volumio[1338]: startIndex: 0 Dec 06 14:22:14 volumio-vers-4 volumio[1338]: } Dec 06 14:22:14 volumio-vers-4 volumio[1338]: ] Dec 06 14:22:14 volumio-vers-4 volumio[1338]: } Dec 06 14:22:14 volumio-vers-4 volumio[1338]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Dec 06 14:22:14 volumio-vers-4 volumio[1338]: command_run: { startIndex: 0, length: 50, onTap: { innertubeCommand: [Object] } }, Dec 06 14:22:14 volumio-vers-4 volumio[1338]: input_data: { Dec 06 14:22:14 volumio-vers-4 volumio[1338]: content: "Simple Minds - Don't You (Forget About Me) · 4:23", Dec 06 14:22:14 volumio-vers-4 volumio[1338]: commandRuns: [ [Object] ] Dec 06 14:22:14 volumio-vers-4 volumio[1338]: }, Dec 06 14:22:14 volumio-vers-4 volumio[1338]: parsed_runs: [ Dec 06 14:22:14 volumio-vers-4 volumio[1338]: { Dec 06 14:22:14 volumio-vers-4 volumio[1338]: text: "Simple Minds - Don't You (Forget About Me) · 4:23", Dec 06 14:22:14 volumio-vers-4 volumio[1338]: startIndex: 0 Dec 06 14:22:14 volumio-vers-4 volumio[1338]: } Dec 06 14:22:14 volumio-vers-4 volumio[1338]: ] Dec 06 14:22:14 volumio-vers-4 volumio[1338]: } Dec 06 14:22:14 volumio-vers-4 volumio[1338]: info: All search sources collected, pushing search results Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: Preload queue cleared Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreStateMachine::ClearQueue Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreStateMachine::stop Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreStateMachine::stPlaybackTimer Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreStateMachine::updateTrackBlock Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrackBlock Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreStateMachine::serviceStop Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::serviceStop Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: [youtube2] (AutoplayManager) Removed mpdStateListener Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: [youtube2] (AutoplayManager) Removed volumioStateListener Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: [youtube2] (AutoplayManager) Disabled Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: ControllerMpd::stop Dec 06 14:22:16 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand stop Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CorePlayQueue::clearPlayQueue Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CorePlayQueue::saveQueue Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushQueue Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreStateMachine::addQueueItems Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CorePlayQueue::addQueueItems Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: Preload queue cleared Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Men%20without%20Hats%20-%20The%20safety%20dance%201983%20live%22%2C%22artist%22%3A%2280s%20Rec.%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FhHh-EDptCEk%2Fhqdefault.jpg%3Fsqp%3D-oaymwE2COADEI4CSFXyq4qpAygIARUAAIhCGAFwAcABBvABAfgBjAWAAuADigIMCAAQARhyID0oNzAP%26rs%3DAOn4CLC2A4kQGtQXBcZnFM-eJeweQ5peCA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22hHh-EDptCEk%22%2C%22playlistId%22%3A%22RDhHh-EDptCEk%22%2C%22params%22%3A%22OAHAAQGqAw9NZW4gd2hpb3V0IGhlYWS6AwsI6ICyj9q38M_WAboDCwi25pe62um-99kBugMLCIuh0LvTr9rw9AG6AwsI7_OA99-WxZrfAboDCwi51qy2o46n75YBugMLCID_nu-8rur8vgG6AwsIgfik7srGkvj8AboDCwj1mpibt8Gg1-MBugMKCO-G6p2k6Nv1NroDDxINUkQxcF9CdmFIc2dHZ7oDCwiJk73624yHmZABugMKCKObhMPF97fYaboDCwixu9ftn6fs-KABugMLCK3tk7CEquqVkQG6AwoIsKSC2YHgoZpougMLCJOpr6DGh_-kvAG6AwsIprXN4b7dtfHLAboDCwiwqtPj1pKI_v0BugMLCKDl-amUlLPB2wG4BQE%253D%22%7D%7D%7D Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: Exploding uri youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Men%20without%20Hats%20-%20The%20safety%20dance%201983%20live%22%2C%22artist%22%3A%2280s%20Rec.%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FhHh-EDptCEk%2Fhqdefault.jpg%3Fsqp%3D-oaymwE2COADEI4CSFXyq4qpAygIARUAAIhCGAFwAcABBvABAfgBjAWAAuADigIMCAAQARhyID0oNzAP%26rs%3DAOn4CLC2A4kQGtQXBcZnFM-eJeweQ5peCA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22hHh-EDptCEk%22%2C%22playlistId%22%3A%22RDhHh-EDptCEk%22%2C%22params%22%3A%22OAHAAQGqAw9NZW4gd2hpb3V0IGhlYWS6AwsI6ICyj9q38M_WAboDCwi25pe62um-99kBugMLCIuh0LvTr9rw9AG6AwsI7_OA99-WxZrfAboDCwi51qy2o46n75YBugMLCID_nu-8rur8vgG6AwsIgfik7srGkvj8AboDCwj1mpibt8Gg1-MBugMKCO-G6p2k6Nv1NroDDxINUkQxcF9CdmFIc2dHZ7oDCwiJk73624yHmZABugMKCKObhMPF97fYaboDCwixu9ftn6fs-KABugMLCK3tk7CEquqVkQG6AwoIsKSC2YHgoZpougMLCJOpr6DGh_-kvAG6AwsIprXN4b7dtfHLAboDCwiwqtPj1pKI_v0BugMLCKDl-amUlLPB2wG4BQE%253D%22%7D%7D%7D in service youtube2 Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: [youtube2-browse] explodeUri: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Men%20without%20Hats%20-%20The%20safety%20dance%201983%20live%22%2C%22artist%22%3A%2280s%20Rec.%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FhHh-EDptCEk%2Fhqdefault.jpg%3Fsqp%3D-oaymwE2COADEI4CSFXyq4qpAygIARUAAIhCGAFwAcABBvABAfgBjAWAAuADigIMCAAQARhyID0oNzAP%26rs%3DAOn4CLC2A4kQGtQXBcZnFM-eJeweQ5peCA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22hHh-EDptCEk%22%2C%22playlistId%22%3A%22RDhHh-EDptCEk%22%2C%22params%22%3A%22OAHAAQGqAw9NZW4gd2hpb3V0IGhlYWS6AwsI6ICyj9q38M_WAboDCwi25pe62um-99kBugMLCIuh0LvTr9rw9AG6AwsI7_OA99-WxZrfAboDCwi51qy2o46n75YBugMLCID_nu-8rur8vgG6AwsIgfik7srGkvj8AboDCwj1mpibt8Gg1-MBugMKCO-G6p2k6Nv1NroDDxINUkQxcF9CdmFIc2dHZ7oDCwiJk73624yHmZABugMKCKObhMPF97fYaboDCwixu9ftn6fs-KABugMLCK3tk7CEquqVkQG6AwoIsKSC2YHgoZpougMLCJOpr6DGh_-kvAG6AwsIprXN4b7dtfHLAboDCwiwqtPj1pKI_v0BugMLCKDl-amUlLPB2wG4BQE%253D%22%7D%7D%7D Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushQueue Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CorePlayQueue::saveQueue Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreStateMachine::updateTrackBlock Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrackBlock Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPlay Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreStateMachine::play index 0 Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreStateMachine::stop Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreStateMachine::play index undefined Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CoreStateMachine::startPlaybackTimer Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22hHh-EDptCEk%22%2C%22playlistId%22%3A%22RDhHh-EDptCEk%22%2C%22params%22%3A%22OAHAAQGqAw9NZW4gd2hpb3V0IGhlYWS6AwsI6ICyj9q38M_WAboDCwi25pe62um-99kBugMLCIuh0LvTr9rw9AG6AwsI7_OA99-WxZrfAboDCwi51qy2o46n75YBugMLCID_nu-8rur8vgG6AwsIgfik7srGkvj8AboDCwj1mpibt8Gg1-MBugMKCO-G6p2k6Nv1NroDDxINUkQxcF9CdmFIc2dHZ7oDCwiJk73624yHmZABugMKCKObhMPF97fYaboDCwixu9ftn6fs-KABugMLCK3tk7CEquqVkQG6AwoIsKSC2YHgoZpougMLCJOpr6DGh_-kvAG6AwsIprXN4b7dtfHLAboDCwiwqtPj1pKI_v0BugMLCKDl-amUlLPB2wG4BQE%253D%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Men%20without%20Hats%20-%20The%20safety%20dance%201983%20live%22%2C%22artist%22%3A%2280s%20Rec.%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FhHh-EDptCEk%2Fhqdefault.jpg%3Fsqp%3D-oaymwE2COADEI4CSFXyq4qpAygIARUAAIhCGAFwAcABBvABAfgBjAWAAuADigIMCAAQARhyID0oNzAP%26rs%3DAOn4CLC2A4kQGtQXBcZnFM-eJeweQ5peCA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22hHh-EDptCEk%22%2C%22playlistId%22%3A%22RDhHh-EDptCEk%22%2C%22params%22%3A%22OAHAAQGqAw9NZW4gd2hpb3V0IGhlYWS6AwsI6ICyj9q38M_WAboDCwi25pe62um-99kBugMLCIuh0LvTr9rw9AG6AwsI7_OA99-WxZrfAboDCwi51qy2o46n75YBugMLCID_nu-8rur8vgG6AwsIgfik7srGkvj8AboDCwj1mpibt8Gg1-MBugMKCO-G6p2k6Nv1NroDDxINUkQxcF9CdmFIc2dHZ7oDCwiJk73624yHmZABugMKCKObhMPF97fYaboDCwixu9ftn6fs-KABugMLCK3tk7CEquqVkQG6AwoIsKSC2YHgoZpougMLCJOpr6DGh_-kvAG6AwsIprXN4b7dtfHLAboDCwiwqtPj1pKI_v0BugMLCKDl-amUlLPB2wG4BQE%253D%22%7D%7D%7D Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status stop Dec 06 14:22:16 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:17 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces state update: player Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: sendMpdCommand stop took 106 milliseconds Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: ControllerMpd::getState Dec 06 14:22:17 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Dec 06 14:22:17 volumio-vers-4 sudo[17345]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 06 14:22:17 volumio-vers-4 sudo[17345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:17 volumio-vers-4 sudo[17347]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 06 14:22:17 volumio-vers-4 sudo[17347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: sendMpdCommand status took 93 milliseconds Dec 06 14:22:17 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseState Dec 06 14:22:17 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: [ytmusic] Obtained PO token for video #hHh-EDptCEk: MlXDRK6cT7iyYsN6equd6XqdivBOC-5XWzmaQdRycJfQy6dnKLE4tNkAAEw3xttOgdw9vOLfKAQTSGRpX_Ohxi8Z1hV0OH4cov981H0B0EUDf7eObeBi Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: sendMpdCommand playlistinfo took 22 milliseconds Dec 06 14:22:17 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseTrackInfo Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: ControllerMpd::pushState Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::servicePushState Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:22:17 volumio-vers-4 sudo[17345]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: ------------------------------ 145ms Dec 06 14:22:17 volumio-vers-4 sudo[17347]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status stop Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Dec 06 14:22:17 volumio-vers-4 volumio[1338]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Stop Dec 06 14:22:17 volumio-vers-4 sudo[17356]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 06 14:22:17 volumio-vers-4 sudo[17356]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:17 volumio-vers-4 sudo[17358]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 06 14:22:17 volumio-vers-4 sudo[17358]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:17 volumio-vers-4 sudo[17356]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:17 volumio-vers-4 sudo[17358]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Stop Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Dec 06 14:22:17 volumio-vers-4 volumio[1338]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: [ytmusic] Obtained PO token for video #hHh-EDptCEk: MlXjFzX8PhT-aeMp4cvsRTaWqqPVa5_7FzK6Ek8SATucwIc0s9FJGJULIB-spqrizdcd73m_WahfQ0Q6xJPQamMS9kbvWA-w7vT0jSOIjUfGDp1kmdEJ Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: [youtube2] VideoModel.getInfo(hHh-EDptCEk): validating stream URL "https://rr2---sn-obppu-hm2d.googlevideo.com/videoplayback?expire=1765048937&ei=CS40acyiHbyDi9oPuubgsQ8&ip=88.147.44.111&id=o-AEOi27Qjh7kTRmYTWjQ2s5dawtCs9X4-KZkkFh22bWtW&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=0&met=1765027337%2C&mh=xa&mm=31%2C29&mn=sn-obppu-hm2d%2Csn-hpa7znzr&ms=au%2Crdu&mv=m&mvi=2&pl=21&rms=au%2Cau&initcwndbps=2213750&bui=AdEuB5S7JYxgoFgZi8khgTGW1VvKeLItBZgJ0c4ZIQhoExy0yGEf7FmO9mhatv5vUMHliuJ9H240GHrE&spc=6b0G_CSKVuPWZtj4QoQ2t5-3PYY1sDxjxTI3aQeHdM4DNRWU7ihgoQ&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=eCtjB78GIhX9LFgYub1IwwoQ&rqh=1&gir=yes&clen=2550884&dur=157.570&lmt=1736981271892921&mt=1765026803&fvip=2&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968%2C51669167%2C51673847&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=1432534&n=krEQjTAL6MGaXQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgBTEaJ5AhZCligxHIyayKiB7boN7YBH7-d6glWYyh4lkCIQCwt9c57JDbzMINc1nXw0Ix5HmAga26tWErctECUoQAKw%3D%3D&sig=AJfQdSswRQIhAOiFNZxpDGUFdFIvFngTtmIgYVnQx_vq1cwG-EkScBSBAiB9REwnuGg8ZXCkGY1dGmT0GiG7CygYPNNqb__qu71FuQ%3D%3D&pot=MlXjFzX8PhT-aeMp4cvsRTaWqqPVa5_7FzK6Ek8SATucwIc0s9FJGJULIB-spqrizdcd73m_WahfQ0Q6xJPQamMS9kbvWA-w7vT0jSOIjUfGDp1kmdEJ&cver=1.20250219.01.00"... Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: [youtube2] VideoModel.getInfo(hHh-EDptCEk): stream validated in 0.141s. Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: [youtube2] (AutoplayManager) Added volumioStateListener Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: [youtube2] (AutoplayManager) Enabled Dec 06 14:22:17 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand stop Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: sendMpdCommand stop took 1 milliseconds Dec 06 14:22:17 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand clear Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:17 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces system playlist update Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: Ignoring MPD Status Update Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: sendMpdCommand clear took 2 milliseconds Dec 06 14:22:17 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand addid "https://rr2---sn-obppu-hm2d.googlevideo.com/videoplayback?expire=1765048937&ei=CS40acyiHbyDi9oPuubgsQ8&ip=88.147.44.111&id=o-AEOi27Qjh7kTRmYTWjQ2s5dawtCs9X4-KZkkFh22bWtW&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=0&met=1765027337%2C&mh=xa&mm=31%2C29&mn=sn-obppu-hm2d%2Csn-hpa7znzr&ms=au%2Crdu&mv=m&mvi=2&pl=21&rms=au%2Cau&initcwndbps=2213750&bui=AdEuB5S7JYxgoFgZi8khgTGW1VvKeLItBZgJ0c4ZIQhoExy0yGEf7FmO9mhatv5vUMHliuJ9H240GHrE&spc=6b0G_CSKVuPWZtj4QoQ2t5-3PYY1sDxjxTI3aQeHdM4DNRWU7ihgoQ&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=eCtjB78GIhX9LFgYub1IwwoQ&rqh=1&gir=yes&clen=2550884&dur=157.570&lmt=1736981271892921&mt=1765026803&fvip=2&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968%2C51669167%2C51673847&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=1432534&n=krEQjTAL6MGaXQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgBTEaJ5AhZCligxHIyayKiB7boN7YBH7-d6glWYyh4lkCIQCwt9c57JDbzMINc1nXw0Ix5HmAga26tWErctECUoQAKw%3D%3D&sig=AJfQdSswRQIhAOiFNZxpDGUFdFIvFngTtmIgYVnQx_vq1cwG-EkScBSBAiB9REwnuGg8ZXCkGY1dGmT0GiG7CygYPNNqb__qu71FuQ%3D%3D&pot=MlXjFzX8PhT-aeMp4cvsRTaWqqPVa5_7FzK6Ek8SATucwIc0s9FJGJULIB-spqrizdcd73m_WahfQ0Q6xJPQamMS9kbvWA-w7vT0jSOIjUfGDp1kmdEJ&cver=1.20250219.01.00&t.YouTube" Dec 06 14:22:17 volumio-vers-4 volumio[1338]: error: updateQueue error: null Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: ------------------------------ 3ms Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:17 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces system playlist update Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: Ignoring MPD Status Update Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: sendMpdCommand addid "https://rr2---sn-obppu-hm2d.googlevideo.com/videoplayback?expire=1765048937&ei=CS40acyiHbyDi9oPuubgsQ8&ip=88.147.44.111&id=o-AEOi27Qjh7kTRmYTWjQ2s5dawtCs9X4-KZkkFh22bWtW&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=0&met=1765027337%2C&mh=xa&mm=31%2C29&mn=sn-obppu-hm2d%2Csn-hpa7znzr&ms=au%2Crdu&mv=m&mvi=2&pl=21&rms=au%2Cau&initcwndbps=2213750&bui=AdEuB5S7JYxgoFgZi8khgTGW1VvKeLItBZgJ0c4ZIQhoExy0yGEf7FmO9mhatv5vUMHliuJ9H240GHrE&spc=6b0G_CSKVuPWZtj4QoQ2t5-3PYY1sDxjxTI3aQeHdM4DNRWU7ihgoQ&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=eCtjB78GIhX9LFgYub1IwwoQ&rqh=1&gir=yes&clen=2550884&dur=157.570&lmt=1736981271892921&mt=1765026803&fvip=2&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968%2C51669167%2C51673847&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=1432534&n=krEQjTAL6MGaXQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgBTEaJ5AhZCligxHIyayKiB7boN7YBH7-d6glWYyh4lkCIQCwt9c57JDbzMINc1nXw0Ix5HmAga26tWErctECUoQAKw%3D%3D&sig=AJfQdSswRQIhAOiFNZxpDGUFdFIvFngTtmIgYVnQx_vq1cwG-EkScBSBAiB9REwnuGg8ZXCkGY1dGmT0GiG7CygYPNNqb__qu71FuQ%3D%3D&pot=MlXjFzX8PhT-aeMp4cvsRTaWqqPVa5_7FzK6Ek8SATucwIc0s9FJGJULIB-spqrizdcd73m_WahfQ0Q6xJPQamMS9kbvWA-w7vT0jSOIjUfGDp1kmdEJ&cver=1.20250219.01.00&t.YouTube" took 3 milliseconds Dec 06 14:22:17 volumio-vers-4 volumio[1338]: verbose: MPD COMMAND [object Object] Dec 06 14:22:17 volumio-vers-4 volumio[1338]: verbose: MPD COMMAND [object Object] Dec 06 14:22:17 volumio-vers-4 volumio[1338]: verbose: MPD COMMAND [object Object] Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:17 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces system playlist update Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: Ignoring MPD Status Update Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: ------------------------------ 4ms Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 06 14:22:17 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand play Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: ------------------------------ 3ms Dec 06 14:22:17 volumio-vers-4 volumio[1338]: info: sendMpdCommand play took 2 milliseconds Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: Preload queue cleared Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: CoreStateMachine::ClearQueue Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: CoreStateMachine::stop Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: CorePlayQueue::clearPlayQueue Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: CorePlayQueue::saveQueue Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushQueue Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: CoreStateMachine::addQueueItems Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: CorePlayQueue::addQueueItems Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: Preload queue cleared Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Billy%20Idol%20-%20Eyes%20Without%20A%20Face%20(Lyrics)%22%2C%22artist%22%3A%22Cassiopeia%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FrXUK48JKjrY%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLAM0NMqmjx_Q1K6VJwBOsty39ojDA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22rXUK48JKjrY%22%2C%22playlistId%22%3A%22RDrXUK48JKjrY%22%2C%22params%22%3A%22OAHAAQG4BQE%253D%22%7D%7D%7D Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: Exploding uri youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Billy%20Idol%20-%20Eyes%20Without%20A%20Face%20(Lyrics)%22%2C%22artist%22%3A%22Cassiopeia%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FrXUK48JKjrY%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLAM0NMqmjx_Q1K6VJwBOsty39ojDA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22rXUK48JKjrY%22%2C%22playlistId%22%3A%22RDrXUK48JKjrY%22%2C%22params%22%3A%22OAHAAQG4BQE%253D%22%7D%7D%7D in service youtube2 Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: [youtube2-browse] explodeUri: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Billy%20Idol%20-%20Eyes%20Without%20A%20Face%20(Lyrics)%22%2C%22artist%22%3A%22Cassiopeia%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FrXUK48JKjrY%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLAM0NMqmjx_Q1K6VJwBOsty39ojDA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22rXUK48JKjrY%22%2C%22playlistId%22%3A%22RDrXUK48JKjrY%22%2C%22params%22%3A%22OAHAAQG4BQE%253D%22%7D%7D%7D Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushQueue Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: CorePlayQueue::saveQueue Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: CoreStateMachine::updateTrackBlock Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrackBlock Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPlay Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: CoreStateMachine::play index 0 Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: CoreStateMachine::stop Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: CoreStateMachine::play index undefined Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: CoreStateMachine::startPlaybackTimer Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22rXUK48JKjrY%22%2C%22playlistId%22%3A%22RDrXUK48JKjrY%22%2C%22params%22%3A%22OAHAAQG4BQE%253D%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Billy%20Idol%20-%20Eyes%20Without%20A%20Face%20(Lyrics)%22%2C%22artist%22%3A%22Cassiopeia%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FrXUK48JKjrY%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLAM0NMqmjx_Q1K6VJwBOsty39ojDA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22rXUK48JKjrY%22%2C%22playlistId%22%3A%22RDrXUK48JKjrY%22%2C%22params%22%3A%22OAHAAQG4BQE%253D%22%7D%7D%7D Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: [ytmusic] Obtained PO token for video #rXUK48JKjrY: MlV__K4JddiS1n_Cej6niVopNkhOntQ3e40m-dTnSvfwfxvfKCQC1Pm0vPQ3U-EuoWiBBOJKEmQz_NjRX2abpg-taq10rUR8gkuraVkCP3RazMNzAuwf Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:18 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces state update: player Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: ControllerMpd::getState Dec 06 14:22:18 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 06 14:22:18 volumio-vers-4 volumio[1338]: info: camilladsp stopping service pid 17352... Dec 06 14:22:19 volumio-vers-4 volumio[1338]: grep: /proc/17352/cmdline: binary file matches Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: camilladsp service terminated, instance 1 Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: camilladsp service started and running in background, instance 1 Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:19 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces state update: player Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: sendMpdCommand status took 130 milliseconds Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: ControllerMpd::getState Dec 06 14:22:19 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Dec 06 14:22:19 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseState Dec 06 14:22:19 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: sendMpdCommand status took 2 milliseconds Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 06 14:22:19 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseState Dec 06 14:22:19 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 14:22:19 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseTrackInfo Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: ControllerMpd::pushState Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::servicePushState Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: ------------------------------ 134ms Dec 06 14:22:19 volumio-vers-4 volumio[1338]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 06 14:22:19 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseTrackInfo Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: ControllerMpd::pushState Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::servicePushState Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: ------------------------------ 15ms Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: [ytmusic] Obtained PO token for video #rXUK48JKjrY: MlUGoObggJjTjwaeMtdSyRtwTxQGdyF3OtRfpZwOv7exJmKDYM33lLjtxah_uhRu4DH4WKqj5yRypaGNF49u5k70E_E8RLE8wxIjg0R1QBVGptf2GyM8 Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: [youtube2] VideoModel.getInfo(rXUK48JKjrY): validating stream URL "https://rr1---sn-obppu-hm2l.googlevideo.com/videoplayback?expire=1765048939&ei=Cy40afqWCO-Bi9oPpaCnuAU&ip=88.147.44.111&id=o-AAw0CoW1Y9IK7k_uDNHhfbTnj3IQmb_hea5R7xyJR5An&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=86&met=1765027339%2C&mh=Pb&mm=31%2C29&mn=sn-obppu-hm2l%2Csn-hpa7zn6s&ms=au%2Crdu&mv=m&mvi=1&pl=21&rms=au%2Cau&initcwndbps=2235000&bui=AdEuB5SQkX6YKp6fnXIPRbTDHve6cDVBUKh_Llguo7N9qsFO6PUK6dAyIooQno8fCdTv-b1sVf9kVcVU&spc=6b0G_Bmw5nErRuEhGKuwuW2A9hVE4XtHEG42F2jE6fWM4EGnchKSWA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=QDmRnkNcU8RaRHfm2arDOKIQ&rqh=1&gir=yes&clen=4985897&dur=295.341&lmt=1759003220752230&mt=1765026803&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968%2C51669167%2C51673847&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5532534&n=LjwycAOO6NqQzQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAJr701UhbPOaquPWj_KZnk_S43k1qy-rLPfRIYCNI992AiA_IkA9yotsOu_9H1I0jFmsU0pF_kci5umUSdHyHE4zBw%3D%3D&sig=AJfQdSswRQIhAMHlQuRWesi-cEjjUooZSCUB2iTWYchcCnj6TdYr9wDVAiBKxknGdH_SAeuwQ9tvHS43_UPlJuu8E0QBzBarsqX7Yg%3D%3D&pot=MlUGoObggJjTjwaeMtdSyRtwTxQGdyF3OtRfpZwOv7exJmKDYM33lLjtxah_uhRu4DH4WKqj5yRypaGNF49u5k70E_E8RLE8wxIjg0R1QBVGptf2GyM8&cver=1.20250219.01.00"... Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: [youtube2] VideoModel.getInfo(rXUK48JKjrY): stream validated in 0.154s. Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: [youtube2] (AutoplayManager) Enabled Dec 06 14:22:19 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand stop Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: sendMpdCommand stop took 17 milliseconds Dec 06 14:22:19 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand clear Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:19 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces state update: player Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:19 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces system playlist update Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: Ignoring MPD Status Update Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: ControllerMpd::getState Dec 06 14:22:19 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: sendMpdCommand clear took 2 milliseconds Dec 06 14:22:19 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-obppu-hm2l.googlevideo.com/videoplayback?expire=1765048939&ei=Cy40afqWCO-Bi9oPpaCnuAU&ip=88.147.44.111&id=o-AAw0CoW1Y9IK7k_uDNHhfbTnj3IQmb_hea5R7xyJR5An&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=86&met=1765027339%2C&mh=Pb&mm=31%2C29&mn=sn-obppu-hm2l%2Csn-hpa7zn6s&ms=au%2Crdu&mv=m&mvi=1&pl=21&rms=au%2Cau&initcwndbps=2235000&bui=AdEuB5SQkX6YKp6fnXIPRbTDHve6cDVBUKh_Llguo7N9qsFO6PUK6dAyIooQno8fCdTv-b1sVf9kVcVU&spc=6b0G_Bmw5nErRuEhGKuwuW2A9hVE4XtHEG42F2jE6fWM4EGnchKSWA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=QDmRnkNcU8RaRHfm2arDOKIQ&rqh=1&gir=yes&clen=4985897&dur=295.341&lmt=1759003220752230&mt=1765026803&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968%2C51669167%2C51673847&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5532534&n=LjwycAOO6NqQzQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAJr701UhbPOaquPWj_KZnk_S43k1qy-rLPfRIYCNI992AiA_IkA9yotsOu_9H1I0jFmsU0pF_kci5umUSdHyHE4zBw%3D%3D&sig=AJfQdSswRQIhAMHlQuRWesi-cEjjUooZSCUB2iTWYchcCnj6TdYr9wDVAiBKxknGdH_SAeuwQ9tvHS43_UPlJuu8E0QBzBarsqX7Yg%3D%3D&pot=MlUGoObggJjTjwaeMtdSyRtwTxQGdyF3OtRfpZwOv7exJmKDYM33lLjtxah_uhRu4DH4WKqj5yRypaGNF49u5k70E_E8RLE8wxIjg0R1QBVGptf2GyM8&cver=1.20250219.01.00&t.YouTube" Dec 06 14:22:19 volumio-vers-4 volumio[1338]: error: updateQueue error: null Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: sendMpdCommand status took 2 milliseconds Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: ------------------------------ 4ms Dec 06 14:22:19 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseState Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: ControllerMpd::pushState Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::servicePushState Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: ------------------------------ 15ms Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:19 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces system playlist update Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: Ignoring MPD Status Update Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: sendMpdCommand addid "https://rr1---sn-obppu-hm2l.googlevideo.com/videoplayback?expire=1765048939&ei=Cy40afqWCO-Bi9oPpaCnuAU&ip=88.147.44.111&id=o-AAw0CoW1Y9IK7k_uDNHhfbTnj3IQmb_hea5R7xyJR5An&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=86&met=1765027339%2C&mh=Pb&mm=31%2C29&mn=sn-obppu-hm2l%2Csn-hpa7zn6s&ms=au%2Crdu&mv=m&mvi=1&pl=21&rms=au%2Cau&initcwndbps=2235000&bui=AdEuB5SQkX6YKp6fnXIPRbTDHve6cDVBUKh_Llguo7N9qsFO6PUK6dAyIooQno8fCdTv-b1sVf9kVcVU&spc=6b0G_Bmw5nErRuEhGKuwuW2A9hVE4XtHEG42F2jE6fWM4EGnchKSWA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=QDmRnkNcU8RaRHfm2arDOKIQ&rqh=1&gir=yes&clen=4985897&dur=295.341&lmt=1759003220752230&mt=1765026803&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968%2C51669167%2C51673847&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5532534&n=LjwycAOO6NqQzQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAJr701UhbPOaquPWj_KZnk_S43k1qy-rLPfRIYCNI992AiA_IkA9yotsOu_9H1I0jFmsU0pF_kci5umUSdHyHE4zBw%3D%3D&sig=AJfQdSswRQIhAMHlQuRWesi-cEjjUooZSCUB2iTWYchcCnj6TdYr9wDVAiBKxknGdH_SAeuwQ9tvHS43_UPlJuu8E0QBzBarsqX7Yg%3D%3D&pot=MlUGoObggJjTjwaeMtdSyRtwTxQGdyF3OtRfpZwOv7exJmKDYM33lLjtxah_uhRu4DH4WKqj5yRypaGNF49u5k70E_E8RLE8wxIjg0R1QBVGptf2GyM8&cver=1.20250219.01.00&t.YouTube" took 15 milliseconds Dec 06 14:22:19 volumio-vers-4 volumio[1338]: verbose: MPD COMMAND [object Object] Dec 06 14:22:19 volumio-vers-4 volumio[1338]: verbose: MPD COMMAND [object Object] Dec 06 14:22:19 volumio-vers-4 volumio[1338]: verbose: MPD COMMAND [object Object] Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status stop Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:19 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces system playlist update Dec 06 14:22:19 volumio-vers-4 sudo[17396]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: Ignoring MPD Status Update Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: ------------------------------ 92ms Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 06 14:22:19 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand play Dec 06 14:22:19 volumio-vers-4 sudo[17396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: ------------------------------ 37ms Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: sendMpdCommand play took 29 milliseconds Dec 06 14:22:19 volumio-vers-4 sudo[17398]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 06 14:22:19 volumio-vers-4 sudo[17398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:19 volumio-vers-4 sudo[17396]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Dec 06 14:22:19 volumio-vers-4 volumio[1338]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Dec 06 14:22:19 volumio-vers-4 sudo[17398]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:19 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Stop Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:21 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces state update: player Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: ControllerMpd::getState Dec 06 14:22:21 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: FusionDsp - ---- read samplerate from file: 48000 Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: camilladsp stopping service pid 17416... Dec 06 14:22:21 volumio-vers-4 volumio[1338]: grep: /proc/17416/cmdline: binary file matches Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: camilladsp service terminated, instance 1 Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: camilladsp service started and running in background, instance 1 Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: FusionDsp - ---- read samplerate from file: 48000 Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:21 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces state update: player Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: sendMpdCommand status took 159 milliseconds Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: ControllerMpd::getState Dec 06 14:22:21 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Dec 06 14:22:21 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseState Dec 06 14:22:21 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: sendMpdCommand status took 3 milliseconds Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 06 14:22:21 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseState Dec 06 14:22:21 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 14:22:21 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseTrackInfo Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: ControllerMpd::pushState Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::servicePushState Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:21 volumio-vers-4 volumio[1338]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":295,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Billy Idol - Eyes Without A Face (Lyrics)","artist":"Cassiopeia","album":"YouTube","uri":"https://rr1---sn-obppu-hm2l.googlevideo.com/videoplayback?expire=1765048939&ei=Cy40afqWCO-Bi9oPpaCnuAU&ip=88.147.44.111&id=o-AAw0CoW1Y9IK7k_uDNHhfbTnj3IQmb_hea5R7xyJR5An&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=86&met=1765027339%2C&mh=Pb&mm=31%2C29&mn=sn-obppu-hm2l%2Csn-hpa7zn6s&ms=au%2Crdu&mv=m&mvi=1&pl=21&rms=au%2Cau&initcwndbps=2235000&bui=AdEuB5SQkX6YKp6fnXIPRbTDHve6cDVBUKh_Llguo7N9qsFO6PUK6dAyIooQno8fCdTv-b1sVf9kVcVU&spc=6b0G_Bmw5nErRuEhGKuwuW2A9hVE4XtHEG42F2jE6fWM4EGnchKSWA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=QDmRnkNcU8RaRHfm2arDOKIQ&rqh=1&gir=yes&clen=4985897&dur=295.341&lmt=1759003220752230&mt=1765026803&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968%2C51669167%2C51673847&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5532534&n=LjwycAOO6NqQzQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAJr701UhbPOaquPWj_KZnk_S43k1qy-rLPfRIYCNI992AiA_IkA9yotsOu_9H1I0jFmsU0pF_kci5umUSdHyHE4zBw%3D%3D&sig=AJfQdSswRQIhAMHlQuRWesi-cEjjUooZSCUB2iTWYchcCnj6TdYr9wDVAiBKxknGdH_SAeuwQ9tvHS43_UPlJuu8E0QBzBarsqX7Yg%3D%3D&pot=MlUGoObggJjTjwaeMtdSyRtwTxQGdyF3OtRfpZwOv7exJmKDYM33lLjtxah_uhRu4DH4WKqj5yRypaGNF49u5k70E_E8RLE8wxIjg0R1QBVGptf2GyM8&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Dec 06 14:22:21 volumio-vers-4 volumio[1338]: verbose: CURRENT POSITION 0 Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: CoreStateMachine::syncState stateService play Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: CoreStateMachine::syncState currentStatus stop Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: ------------------------------ 168ms Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: sendMpdCommand playlistinfo took 22 milliseconds Dec 06 14:22:21 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseTrackInfo Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: ControllerMpd::pushState Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::servicePushState Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:21 volumio-vers-4 volumio[1338]: verbose: STATE SERVICE {"status":"play","position":0,"seek":870,"duration":295,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Billy Idol - Eyes Without A Face (Lyrics)","artist":"Cassiopeia","album":"YouTube","uri":"https://rr1---sn-obppu-hm2l.googlevideo.com/videoplayback?expire=1765048939&ei=Cy40afqWCO-Bi9oPpaCnuAU&ip=88.147.44.111&id=o-AAw0CoW1Y9IK7k_uDNHhfbTnj3IQmb_hea5R7xyJR5An&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=86&met=1765027339%2C&mh=Pb&mm=31%2C29&mn=sn-obppu-hm2l%2Csn-hpa7zn6s&ms=au%2Crdu&mv=m&mvi=1&pl=21&rms=au%2Cau&initcwndbps=2235000&bui=AdEuB5SQkX6YKp6fnXIPRbTDHve6cDVBUKh_Llguo7N9qsFO6PUK6dAyIooQno8fCdTv-b1sVf9kVcVU&spc=6b0G_Bmw5nErRuEhGKuwuW2A9hVE4XtHEG42F2jE6fWM4EGnchKSWA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=QDmRnkNcU8RaRHfm2arDOKIQ&rqh=1&gir=yes&clen=4985897&dur=295.341&lmt=1759003220752230&mt=1765026803&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968%2C51669167%2C51673847&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5532534&n=LjwycAOO6NqQzQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAJr701UhbPOaquPWj_KZnk_S43k1qy-rLPfRIYCNI992AiA_IkA9yotsOu_9H1I0jFmsU0pF_kci5umUSdHyHE4zBw%3D%3D&sig=AJfQdSswRQIhAMHlQuRWesi-cEjjUooZSCUB2iTWYchcCnj6TdYr9wDVAiBKxknGdH_SAeuwQ9tvHS43_UPlJuu8E0QBzBarsqX7Yg%3D%3D&pot=MlUGoObggJjTjwaeMtdSyRtwTxQGdyF3OtRfpZwOv7exJmKDYM33lLjtxah_uhRu4DH4WKqj5yRypaGNF49u5k70E_E8RLE8wxIjg0R1QBVGptf2GyM8&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Dec 06 14:22:21 volumio-vers-4 volumio[1338]: verbose: CURRENT POSITION 0 Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: CoreStateMachine::syncState stateService play Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: CoreStateMachine::syncState currentStatus play Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: Received an update from plugin. extracting info from payload Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: [youtube2] (AutoplayManager) Added mpdStateListener Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: ------------------------------ 55ms Dec 06 14:22:21 volumio-vers-4 volumio[1338]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status play Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status play Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 06 14:22:21 volumio-vers-4 sudo[17442]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 06 14:22:21 volumio-vers-4 sudo[17442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 06 14:22:21 volumio-vers-4 sudo[17444]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 06 14:22:21 volumio-vers-4 sudo[17448]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 06 14:22:21 volumio-vers-4 sudo[17448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:21 volumio-vers-4 sudo[17444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:21 volumio-vers-4 sudo[17452]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 06 14:22:21 volumio-vers-4 sudo[17452]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:21 volumio-vers-4 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 06 14:22:21 volumio-vers-4 sudo[17442]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Dec 06 14:22:21 volumio-vers-4 volumio[1338]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Dec 06 14:22:21 volumio-vers-4 sudo[17444]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Dec 06 14:22:21 volumio-vers-4 volumio[1338]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Dec 06 14:22:21 volumio-vers-4 systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Dec 06 14:22:21 volumio-vers-4 sudo[17452]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:21 volumio-vers-4 sudo[17448]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Started Dec 06 14:22:21 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Started Dec 06 14:22:21 volumio-vers-4 volumio[17477]: Traceback (most recent call last): Dec 06 14:22:21 volumio-vers-4 volumio[17477]: File "/data/plugins/user_interface/peppymeterbasic/BasicPeppyMeter/peppymeter.py", line 18, in Dec 06 14:22:21 volumio-vers-4 volumio[17477]: import pygame Dec 06 14:22:21 volumio-vers-4 volumio[17477]: ModuleNotFoundError: No module named 'pygame' Dec 06 14:22:21 volumio-vers-4 systemd[1]: peppymeterbasic.service: Main process exited, code=exited, status=1/FAILURE Dec 06 14:22:21 volumio-vers-4 systemd[1]: peppymeterbasic.service: Failed with result 'exit-code'. Dec 06 14:22:22 volumio-vers-4 systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 06 14:22:22 volumio-vers-4 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 06 14:22:22 volumio-vers-4 systemd[1]: setdatetime-helper.service: Consumed 1.155s CPU time. Dec 06 14:22:40 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 06 14:22:40 volumio-vers-4 volumio[1338]: info: Preload queue cleared Dec 06 14:22:41 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 06 14:22:41 volumio-vers-4 volumio[1338]: info: TuneIn handleBrowseUri: tunein/local Dec 06 14:22:42 volumio-vers-4 volumio[1338]: info: Preload queue cleared Dec 06 14:22:42 volumio-vers-4 volumio[1338]: info: Preload queue cleared Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: Preload queue cleared Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreStateMachine::ClearQueue Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreStateMachine::stop Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreStateMachine::stPlaybackTimer Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreStateMachine::updateTrackBlock Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrackBlock Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreStateMachine::serviceStop Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::serviceStop Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: [youtube2] (AutoplayManager) Removed mpdStateListener Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: [youtube2] (AutoplayManager) Removed volumioStateListener Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: [youtube2] (AutoplayManager) Disabled Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: ControllerMpd::stop Dec 06 14:22:52 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand stop Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CorePlayQueue::clearPlayQueue Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CorePlayQueue::saveQueue Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushQueue Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreStateMachine::addQueueItems Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CorePlayQueue::addQueueItems Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: Preload queue cleared Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s71916 Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status stop Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:52 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces state update: player Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: sendMpdCommand stop took 114 milliseconds Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: ControllerMpd::getState Dec 06 14:22:52 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: sendMpdCommand status took 4 milliseconds Dec 06 14:22:52 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseState Dec 06 14:22:52 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: sendMpdCommand playlistinfo took 30 milliseconds Dec 06 14:22:52 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseTrackInfo Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: ControllerMpd::pushState Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::servicePushState Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:52 volumio-vers-4 volumio[1338]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Billy Idol - Eyes Without A Face (Lyrics)","artist":"Cassiopeia","album":"YouTube","uri":"https://rr1---sn-obppu-hm2l.googlevideo.com/videoplayback?expire=1765048939&ei=Cy40afqWCO-Bi9oPpaCnuAU&ip=88.147.44.111&id=o-AAw0CoW1Y9IK7k_uDNHhfbTnj3IQmb_hea5R7xyJR5An&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=86&met=1765027339%2C&mh=Pb&mm=31%2C29&mn=sn-obppu-hm2l%2Csn-hpa7zn6s&ms=au%2Crdu&mv=m&mvi=1&pl=21&rms=au%2Cau&initcwndbps=2235000&bui=AdEuB5SQkX6YKp6fnXIPRbTDHve6cDVBUKh_Llguo7N9qsFO6PUK6dAyIooQno8fCdTv-b1sVf9kVcVU&spc=6b0G_Bmw5nErRuEhGKuwuW2A9hVE4XtHEG42F2jE6fWM4EGnchKSWA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=QDmRnkNcU8RaRHfm2arDOKIQ&rqh=1&gir=yes&clen=4985897&dur=295.341&lmt=1759003220752230&mt=1765026803&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968%2C51669167%2C51673847&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5532534&n=LjwycAOO6NqQzQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAJr701UhbPOaquPWj_KZnk_S43k1qy-rLPfRIYCNI992AiA_IkA9yotsOu_9H1I0jFmsU0pF_kci5umUSdHyHE4zBw%3D%3D&sig=AJfQdSswRQIhAMHlQuRWesi-cEjjUooZSCUB2iTWYchcCnj6TdYr9wDVAiBKxknGdH_SAeuwQ9tvHS43_UPlJuu8E0QBzBarsqX7Yg%3D%3D&pot=MlUGoObggJjTjwaeMtdSyRtwTxQGdyF3OtRfpZwOv7exJmKDYM33lLjtxah_uhRu4DH4WKqj5yRypaGNF49u5k70E_E8RLE8wxIjg0R1QBVGptf2GyM8&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Dec 06 14:22:52 volumio-vers-4 volumio[1338]: verbose: CURRENT POSITION 0 Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreStateMachine::syncState stateService stop Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreStateMachine::syncState currentStatus stop Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: No code Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:22:52 volumio-vers-4 sudo[18440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 06 14:22:52 volumio-vers-4 sudo[18440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: ------------------------------ 84ms Dec 06 14:22:52 volumio-vers-4 sudo[18442]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 06 14:22:52 volumio-vers-4 sudo[18442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status play Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status play Dec 06 14:22:52 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status play Dec 06 14:22:52 volumio-vers-4 sudo[18454]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 06 14:22:52 volumio-vers-4 sudo[18454]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:52 volumio-vers-4 sudo[18442]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:53 volumio-vers-4 sudo[18440]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:53 volumio-vers-4 sudo[18462]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 06 14:22:53 volumio-vers-4 sudo[18462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:53 volumio-vers-4 sudo[18454]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:53 volumio-vers-4 sudo[18465]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 06 14:22:53 volumio-vers-4 sudo[18465]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 06 14:22:53 volumio-vers-4 sudo[18469]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 06 14:22:53 volumio-vers-4 sudo[18469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 06 14:22:53 volumio-vers-4 sudo[18462]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:53 volumio-vers-4 sudo[18465]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:53 volumio-vers-4 sudo[18472]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 06 14:22:53 volumio-vers-4 sudo[18472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 06 14:22:53 volumio-vers-4 sudo[18474]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 06 14:22:53 volumio-vers-4 sudo[18474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushQueue Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: CorePlayQueue::saveQueue Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: CoreStateMachine::updateTrackBlock Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrackBlock Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPlay Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: CoreStateMachine::play index 0 Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: CoreStateMachine::stop Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: CoreStateMachine::play index undefined Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: CoreStateMachine::startPlaybackTimer Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: [1765027373281] ControllerWebradio::clearAddPlayTrack Dec 06 14:22:53 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand stop Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Dec 06 14:22:53 volumio-vers-4 volumio[1338]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Dec 06 14:22:53 volumio-vers-4 volumio[1338]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Dec 06 14:22:53 volumio-vers-4 volumio[1338]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Stop Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Dec 06 14:22:53 volumio-vers-4 volumio[1338]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: sendMpdCommand stop took 18 milliseconds Dec 06 14:22:53 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand clear Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:53 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces system playlist update Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: Ignoring MPD Status Update Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: sendMpdCommand clear took 2 milliseconds Dec 06 14:22:53 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand load "http://cdn-cms.tunein.com/service/Audio/notcompatible.enUS.mp3" Dec 06 14:22:53 volumio-vers-4 volumio[1338]: error: updateQueue error: null Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: ------------------------------ 3ms Dec 06 14:22:53 volumio-vers-4 systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Dec 06 14:22:53 volumio-vers-4 sudo[18469]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Started Dec 06 14:22:53 volumio-vers-4 sudo[18474]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:53 volumio-vers-4 sudo[18472]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Started Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Started Dec 06 14:22:53 volumio-vers-4 volumio[18480]: Traceback (most recent call last): Dec 06 14:22:53 volumio-vers-4 volumio[18480]: File "/data/plugins/user_interface/peppymeterbasic/BasicPeppyMeter/peppymeter.py", line 18, in Dec 06 14:22:53 volumio-vers-4 volumio[18480]: import pygame Dec 06 14:22:53 volumio-vers-4 volumio[18480]: ModuleNotFoundError: No module named 'pygame' Dec 06 14:22:53 volumio-vers-4 systemd[1]: peppymeterbasic.service: Main process exited, code=exited, status=1/FAILURE Dec 06 14:22:53 volumio-vers-4 systemd[1]: peppymeterbasic.service: Failed with result 'exit-code'. Dec 06 14:22:53 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand add "http://cdn-cms.tunein.com/service/Audio/notcompatible.enUS.mp3" Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:53 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces system playlist update Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: Ignoring MPD Status Update Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: sendMpdCommand add "http://cdn-cms.tunein.com/service/Audio/notcompatible.enUS.mp3" took 1 milliseconds Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 06 14:22:53 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand play Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: ------------------------------ 3ms Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: sendMpdCommand play took 2 milliseconds Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:53 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces state update: player Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: ControllerMpd::getState Dec 06 14:22:53 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: FusionDsp - ---- read samplerate, raw: 16000,S32_LE,2,32 Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: FusionDsp - ---- read samplerate from file: 16000 Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: camilladsp stopping service pid 18451... Dec 06 14:22:53 volumio-vers-4 volumio[1338]: grep: /proc/18451/cmdline: binary file matches Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: camilladsp service terminated, instance 1 Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: camilladsp service started and running in background, instance 1 Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: FusionDsp - ---- read samplerate, raw: 16000,S32_LE,2,32 Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: FusionDsp - ---- read samplerate from file: 16000 Dec 06 14:22:53 volumio-vers-4 volumio[1338]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:54 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces state update: player Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: sendMpdCommand status took 648 milliseconds Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: ControllerMpd::getState Dec 06 14:22:54 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Dec 06 14:22:54 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseState Dec 06 14:22:54 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: Preload queue cleared Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::ClearQueue Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::stop Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CorePlayQueue::clearPlayQueue Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CorePlayQueue::saveQueue Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushQueue Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::addQueueItems Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CorePlayQueue::addQueueItems Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: Preload queue cleared Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s92023 Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: sendMpdCommand status took 22 milliseconds Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: sendMpdCommand playlistinfo took 23 milliseconds Dec 06 14:22:54 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseState Dec 06 14:22:54 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 14:22:54 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseTrackInfo Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: ControllerMpd::pushState Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::servicePushState Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:54 volumio-vers-4 volumio[1338]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":12,"samplerate":"16 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"24 Kbps","isStreaming":false,"title":"notcompatible.enUS.mp3","artist":null,"album":null,"uri":"http://cdn-cms.tunein.com/service/Audio/notcompatible.enUS.mp3","trackType":"mp3"} Dec 06 14:22:54 volumio-vers-4 volumio[1338]: verbose: CURRENT POSITION 0 Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::syncState stateService play Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::syncState currentStatus stop Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: ------------------------------ 677ms Dec 06 14:22:54 volumio-vers-4 volumio[1338]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: sendMpdCommand playlistinfo took 25 milliseconds Dec 06 14:22:54 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseTrackInfo Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: ControllerMpd::pushState Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::servicePushState Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:54 volumio-vers-4 volumio[1338]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1390,"duration":12,"samplerate":"16 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"24 Kbps","isStreaming":false,"title":"notcompatible.enUS.mp3","artist":null,"album":null,"uri":"http://cdn-cms.tunein.com/service/Audio/notcompatible.enUS.mp3","trackType":"mp3"} Dec 06 14:22:54 volumio-vers-4 volumio[1338]: verbose: CURRENT POSITION 0 Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::syncState stateService play Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::syncState currentStatus play Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: Received an update from plugin. extracting info from payload Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: ------------------------------ 87ms Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status stop Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status stop Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 06 14:22:54 volumio-vers-4 sudo[18504]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 06 14:22:54 volumio-vers-4 sudo[18504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:54 volumio-vers-4 sudo[18507]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 06 14:22:54 volumio-vers-4 sudo[18507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:54 volumio-vers-4 sudo[18509]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 06 14:22:54 volumio-vers-4 sudo[18509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:54 volumio-vers-4 sudo[18513]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 06 14:22:54 volumio-vers-4 sudo[18513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:54 volumio-vers-4 sudo[18504]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushQueue Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CorePlayQueue::saveQueue Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::updateTrackBlock Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrackBlock Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPlay Dec 06 14:22:54 volumio-vers-4 sudo[18507]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::play index 0 Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::stop Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::stPlaybackTimer Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::updateTrackBlock Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrackBlock Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::serviceStop Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::serviceStop Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: [1765027374643] ControllerWebradio::stop Dec 06 14:22:54 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand stop Dec 06 14:22:54 volumio-vers-4 sudo[18513]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status stop Dec 06 14:22:54 volumio-vers-4 sudo[18509]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:54 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces state update: player Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: sendMpdCommand stop took 102 milliseconds Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: ControllerMpd::getState Dec 06 14:22:54 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::play index undefined Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::startPlaybackTimer Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:54 volumio-vers-4 sudo[18519]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 06 14:22:54 volumio-vers-4 sudo[18519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: [1765027374756] ControllerWebradio::clearAddPlayTrack Dec 06 14:22:54 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand stop Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Dec 06 14:22:54 volumio-vers-4 volumio[1338]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Dec 06 14:22:54 volumio-vers-4 volumio[1338]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: sendMpdCommand status took 30 milliseconds Dec 06 14:22:54 volumio-vers-4 sudo[18521]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 06 14:22:54 volumio-vers-4 sudo[18521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: sendMpdCommand stop took 31 milliseconds Dec 06 14:22:54 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseState Dec 06 14:22:54 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 14:22:54 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand clear Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Stop Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Stop Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:54 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces system playlist update Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: Ignoring MPD Status Update Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: sendMpdCommand playlistinfo took 17 milliseconds Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: sendMpdCommand clear took 19 milliseconds Dec 06 14:22:54 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseTrackInfo Dec 06 14:22:54 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand load "http://167.86.86.4:8501/" Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: ControllerMpd::pushState Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::servicePushState Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 06 14:22:54 volumio-vers-4 sudo[18519]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: ------------------------------ 91ms Dec 06 14:22:54 volumio-vers-4 volumio[1338]: error: updateQueue error: null Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: ------------------------------ 35ms Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status stop Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 06 14:22:54 volumio-vers-4 sudo[18521]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Dec 06 14:22:54 volumio-vers-4 volumio[1338]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Stop Dec 06 14:22:54 volumio-vers-4 sudo[18527]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 06 14:22:54 volumio-vers-4 sudo[18527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:54 volumio-vers-4 sudo[18525]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 06 14:22:54 volumio-vers-4 sudo[18525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:54 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand add "http://167.86.86.4:8501/" Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:54 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces system playlist update Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: Ignoring MPD Status Update Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: sendMpdCommand add "http://167.86.86.4:8501/" took 2 milliseconds Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 06 14:22:54 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand play Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: ------------------------------ 5ms Dec 06 14:22:54 volumio-vers-4 volumio[1338]: info: sendMpdCommand play took 3 milliseconds Dec 06 14:22:55 volumio-vers-4 sudo[18525]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Dec 06 14:22:55 volumio-vers-4 volumio[1338]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Dec 06 14:22:55 volumio-vers-4 sudo[18527]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Stop Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:55 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces state update: player Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: ControllerMpd::getState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: camilladsp stopping service pid 18530... Dec 06 14:22:55 volumio-vers-4 volumio[1338]: grep: /proc/18530/cmdline: binary file matches Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: camilladsp service terminated, instance 1 Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: camilladsp service started and running in background, instance 1 Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:55 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces state update: player Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: sendMpdCommand status took 164 milliseconds Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: ControllerMpd::getState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: sendMpdCommand status took 22 milliseconds Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: sendMpdCommand playlistinfo took 21 milliseconds Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseTrackInfo Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: ControllerMpd::pushState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::servicePushState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://167.86.86.4:8501/","trackType":"4:8501/"} Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: CURRENT POSITION 0 Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreStateMachine::syncState stateService play Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreStateMachine::syncState currentStatus stop Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: ------------------------------ 201ms Dec 06 14:22:55 volumio-vers-4 volumio[1338]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: sendMpdCommand playlistinfo took 33 milliseconds Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseTrackInfo Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: ControllerMpd::pushState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::servicePushState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://167.86.86.4:8501/","trackType":"4:8501/"} Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: CURRENT POSITION 0 Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreStateMachine::syncState stateService play Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreStateMachine::syncState currentStatus play Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: Received an update from plugin. extracting info from payload Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: ------------------------------ 99ms Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status play Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status play Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 06 14:22:55 volumio-vers-4 sudo[18554]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 06 14:22:55 volumio-vers-4 sudo[18554]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 06 14:22:55 volumio-vers-4 sudo[18556]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:55 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces system playlist update Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: Ignoring MPD Status Update Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:55 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces state update: player Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: ControllerMpd::getState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Dec 06 14:22:55 volumio-vers-4 sudo[18556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:55 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces system playlist update Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: Ignoring MPD Status Update Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: Dec 06 14:22:55 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces state update: player Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: ControllerMpd::getState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: ------------------------------ 23ms Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: sendMpdCommand status took 21 milliseconds Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: ------------------------------ 15ms Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: sendMpdCommand status took 14 milliseconds Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseTrackInfo Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseTrackInfo Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: ControllerMpd::pushState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::servicePushState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: STATE SERVICE {"status":"play","position":0,"seek":977,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"GRACE JONES - SLAVE TO THE RHYTHM","artist":"PRIMARADIO","album":null,"uri":"http://167.86.86.4:8501/","trackType":"4:8501/"} Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: CURRENT POSITION 0 Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreStateMachine::syncState stateService play Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreStateMachine::syncState currentStatus play Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: Received an update from plugin. extracting info from payload Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:22:55 volumio-vers-4 sudo[18561]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 06 14:22:55 volumio-vers-4 sudo[18561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: ControllerMpd::pushState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::servicePushState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: STATE SERVICE {"status":"play","position":0,"seek":977,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"GRACE JONES - SLAVE TO THE RHYTHM","artist":"PRIMARADIO","album":null,"uri":"http://167.86.86.4:8501/","trackType":"4:8501/"} Dec 06 14:22:55 volumio-vers-4 volumio[1338]: verbose: CURRENT POSITION 0 Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreStateMachine::syncState stateService play Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreStateMachine::syncState currentStatus play Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: Received an update from plugin. extracting info from payload Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:22:55 volumio-vers-4 sudo[18559]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 06 14:22:55 volumio-vers-4 sudo[18554]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:55 volumio-vers-4 sudo[18559]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: ------------------------------ 164ms Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: ------------------------------ 157ms Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status play Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status play Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status play Dec 06 14:22:55 volumio-vers-4 sudo[18556]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status play Dec 06 14:22:55 volumio-vers-4 sudo[18570]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 06 14:22:55 volumio-vers-4 sudo[18570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:55 volumio-vers-4 sudo[18567]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 06 14:22:55 volumio-vers-4 systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Dec 06 14:22:55 volumio-vers-4 sudo[18567]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:55 volumio-vers-4 sudo[18559]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 06 14:22:55 volumio-vers-4 sudo[18561]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:55 volumio-vers-4 sudo[18572]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 06 14:22:55 volumio-vers-4 sudo[18572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:55 volumio-vers-4 sudo[18576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 06 14:22:55 volumio-vers-4 sudo[18576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:55 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 06 14:22:56 volumio-vers-4 volumio[18578]: Traceback (most recent call last): Dec 06 14:22:56 volumio-vers-4 volumio[18578]: File "/data/plugins/user_interface/peppymeterbasic/BasicPeppyMeter/peppymeter.py", line 18, in Dec 06 14:22:56 volumio-vers-4 volumio[18578]: import pygame Dec 06 14:22:56 volumio-vers-4 volumio[18578]: ModuleNotFoundError: No module named 'pygame' Dec 06 14:22:56 volumio-vers-4 sudo[18584]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 06 14:22:56 volumio-vers-4 sudo[18584]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:56 volumio-vers-4 sudo[18567]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:56 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 06 14:22:56 volumio-vers-4 sudo[18580]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 06 14:22:56 volumio-vers-4 sudo[18580]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:56 volumio-vers-4 systemd[1]: peppymeterbasic.service: Main process exited, code=exited, status=1/FAILURE Dec 06 14:22:56 volumio-vers-4 systemd[1]: peppymeterbasic.service: Failed with result 'exit-code'. Dec 06 14:22:56 volumio-vers-4 sudo[18570]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:56 volumio-vers-4 sudo[18590]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 06 14:22:56 volumio-vers-4 sudo[18572]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:56 volumio-vers-4 sudo[18590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:56 volumio-vers-4 sudo[18587]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 06 14:22:56 volumio-vers-4 sudo[18587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:22:56 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Dec 06 14:22:56 volumio-vers-4 volumio[1338]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Dec 06 14:22:56 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Dec 06 14:22:56 volumio-vers-4 volumio[1338]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Dec 06 14:22:56 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Dec 06 14:22:56 volumio-vers-4 volumio[1338]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Dec 06 14:22:56 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Dec 06 14:22:56 volumio-vers-4 volumio[1338]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Dec 06 14:22:56 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Started Dec 06 14:22:56 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Started Dec 06 14:22:56 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Dec 06 14:22:56 volumio-vers-4 volumio[1338]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Dec 06 14:22:56 volumio-vers-4 sudo[18580]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:56 volumio-vers-4 systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Dec 06 14:22:56 volumio-vers-4 sudo[18576]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:56 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Started Dec 06 14:22:56 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Dec 06 14:22:56 volumio-vers-4 volumio[1338]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Dec 06 14:22:56 volumio-vers-4 sudo[18584]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:56 volumio-vers-4 sudo[18587]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:56 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Started Dec 06 14:22:56 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Started Dec 06 14:22:56 volumio-vers-4 sudo[18590]: pam_unix(sudo:session): session closed for user root Dec 06 14:22:56 volumio-vers-4 volumio[18598]: Traceback (most recent call last): Dec 06 14:22:56 volumio-vers-4 volumio[18598]: File "/data/plugins/user_interface/peppymeterbasic/BasicPeppyMeter/peppymeter.py", line 18, in Dec 06 14:22:56 volumio-vers-4 volumio[18598]: import pygame Dec 06 14:22:56 volumio-vers-4 volumio[18598]: ModuleNotFoundError: No module named 'pygame' Dec 06 14:22:56 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Started Dec 06 14:22:56 volumio-vers-4 systemd[1]: peppymeterbasic.service: Main process exited, code=exited, status=1/FAILURE Dec 06 14:22:56 volumio-vers-4 systemd[1]: peppymeterbasic.service: Failed with result 'exit-code'. Dec 06 14:23:08 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioGetQueue Dec 06 14:23:08 volumio-vers-4 volumio[1338]: info: CoreStateMachine::getQueue Dec 06 14:23:08 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getQueue Dec 06 14:23:10 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Dec 06 14:23:10 volumio-vers-4 volumio[1338]: info: [youtube2-browse] browseUri: youtube2 Dec 06 14:23:10 volumio-vers-4 volumio[1338]: info: Preload queue cleared Dec 06 14:23:10 volumio-vers-4 volumio[1338]: info: Preload queue cleared Dec 06 14:23:10 volumio-vers-4 volumio[1338]: info: Preload queue cleared Dec 06 14:23:15 volumio-vers-4 volumio[1338]: info: VolumeController::SetAlsaVolume49 Dec 06 14:23:15 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:23:15 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 14:23:15 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:23:15 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status play Dec 06 14:23:15 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 06 14:23:15 volumio-vers-4 sudo[18791]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 06 14:23:15 volumio-vers-4 sudo[18791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:23:15 volumio-vers-4 sudo[18793]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 06 14:23:15 volumio-vers-4 sudo[18793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:23:15 volumio-vers-4 sudo[18791]: pam_unix(sudo:session): session closed for user root Dec 06 14:23:15 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Dec 06 14:23:15 volumio-vers-4 volumio[1338]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Dec 06 14:23:15 volumio-vers-4 systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Dec 06 14:23:15 volumio-vers-4 sudo[18793]: pam_unix(sudo:session): session closed for user root Dec 06 14:23:15 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Started Dec 06 14:23:15 volumio-vers-4 volumio[18798]: Traceback (most recent call last): Dec 06 14:23:15 volumio-vers-4 volumio[18798]: File "/data/plugins/user_interface/peppymeterbasic/BasicPeppyMeter/peppymeter.py", line 18, in Dec 06 14:23:15 volumio-vers-4 volumio[18798]: import pygame Dec 06 14:23:15 volumio-vers-4 volumio[18798]: ModuleNotFoundError: No module named 'pygame' Dec 06 14:23:15 volumio-vers-4 systemd[1]: peppymeterbasic.service: Main process exited, code=exited, status=1/FAILURE Dec 06 14:23:15 volumio-vers-4 systemd[1]: peppymeterbasic.service: Failed with result 'exit-code'. Dec 06 14:23:19 volumio-vers-4 kernel: bcm2835-audio bcm2835-audio: failed to close VCHI service connection (status=-11) Dec 06 14:23:21 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 14:23:21 volumio-vers-4 volumio[1338]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 06 14:23:31 volumio-vers-4 volumio[1338]: info: CALLMETHOD: audio_interface fusiondsp disableeffect undefined Dec 06 14:23:31 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , disableeffect Dec 06 14:23:31 volumio-vers-4 volumio[1338]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 06 14:23:31 volumio-vers-4 volumio[1338]: info: FusionDsp - Effects disabled Dec 06 14:23:32 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 14:23:32 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::Close All Modals sent Dec 06 14:23:32 volumio-vers-4 volumio[1338]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 06 14:23:38 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Dec 06 14:23:38 volumio-vers-4 volumio[1338]: info: [youtube2-browse] browseUri: youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEstorefront%22%2C%22params%22%3A%22ogUCKAU%253D%22%7D%7D Dec 06 14:23:39 volumio-vers-4 volumio[1338]: info: Preload queue cleared Dec 06 14:23:39 volumio-vers-4 volumio[1338]: info: Preload queue cleared Dec 06 14:23:39 volumio-vers-4 volumio[1338]: info: Preload queue cleared Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: Preload queue cleared Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CoreStateMachine::ClearQueue Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CoreStateMachine::stop Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CoreStateMachine::stPlaybackTimer Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CoreStateMachine::updateTrackBlock Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrackBlock Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CoreStateMachine::serviceStop Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::serviceStop Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: [1765027420870] ControllerWebradio::stop Dec 06 14:23:40 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand stop Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CorePlayQueue::clearPlayQueue Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CorePlayQueue::saveQueue Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushQueue Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CoreStateMachine::addQueueItems Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CorePlayQueue::addQueueItems Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: Preload queue cleared Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Babygirl%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FYHAk4YeVasY%2Fmovieposter.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22YHAk4YeVasY%22%7D%7D%7D Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: Exploding uri youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Babygirl%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FYHAk4YeVasY%2Fmovieposter.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22YHAk4YeVasY%22%7D%7D%7D in service youtube2 Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: [youtube2-browse] explodeUri: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Babygirl%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FYHAk4YeVasY%2Fmovieposter.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22YHAk4YeVasY%22%7D%7D%7D Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushQueue Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CorePlayQueue::saveQueue Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CoreStateMachine::updateTrackBlock Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrackBlock Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPlay Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CoreStateMachine::play index 0 Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CoreStateMachine::stop Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CoreStateMachine::play index undefined Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CoreStateMachine::startPlaybackTimer Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22YHAk4YeVasY%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Babygirl%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FYHAk4YeVasY%2Fmovieposter.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22YHAk4YeVasY%22%7D%7D%7D Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status stop Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 06 14:23:40 volumio-vers-4 sudo[18914]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 06 14:23:40 volumio-vers-4 sudo[18914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:23:40 volumio-vers-4 sudo[18916]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 06 14:23:40 volumio-vers-4 sudo[18916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: Dec 06 14:23:40 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces state update: player Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: sendMpdCommand stop took 91 milliseconds Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: ControllerMpd::getState Dec 06 14:23:40 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Dec 06 14:23:40 volumio-vers-4 volumio[1338]: info: sendMpdCommand status took 5 milliseconds Dec 06 14:23:40 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseState Dec 06 14:23:40 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 14:23:41 volumio-vers-4 sudo[18914]: pam_unix(sudo:session): session closed for user root Dec 06 14:23:41 volumio-vers-4 volumio[1338]: info: sendMpdCommand playlistinfo took 79 milliseconds Dec 06 14:23:41 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseTrackInfo Dec 06 14:23:41 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::parseTrackInfo Dec 06 14:23:41 volumio-vers-4 volumio[1338]: info: ControllerMpd::pushState Dec 06 14:23:41 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::servicePushState Dec 06 14:23:41 volumio-vers-4 volumio[1338]: info: CoreStateMachine::pushState Dec 06 14:23:41 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:23:41 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 14:23:41 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushState Dec 06 14:23:41 volumio-vers-4 sudo[18916]: pam_unix(sudo:session): session closed for user root Dec 06 14:23:41 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:23:41 volumio-vers-4 volumio[1338]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd Dec 06 14:23:41 volumio-vers-4 volumio[1338]: info: ------------------------------ 157ms Dec 06 14:23:41 volumio-vers-4 volumio[1338]: info: [ytmusic] Obtained PO token for video #YHAk4YeVasY: MlXqalcK07scZ-pUgz0B6tSYo963nXJU9Tyzby3k7JR-zo5J0Sekt3cFKWLOUEdNL9kUkhtJtAe9TU1HpmU9xYEc_zuNruIfDPqfUVhatk0hN7yj82HC Dec 06 14:23:41 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Dec 06 14:23:41 volumio-vers-4 volumio[1338]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Dec 06 14:23:41 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum status stop Dec 06 14:23:41 volumio-vers-4 volumio[1338]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 06 14:23:41 volumio-vers-4 volumio[1338]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 06 14:23:41 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Stop Dec 06 14:23:41 volumio-vers-4 sudo[18948]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 06 14:23:41 volumio-vers-4 sudo[18948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:23:41 volumio-vers-4 sudo[18946]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 06 14:23:41 volumio-vers-4 sudo[18946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 14:23:41 volumio-vers-4 volumio[1338]: info: [ytmusic] Obtained PO token for video #YHAk4YeVasY: MlXoGKc4wpgtZegmcw8QyeWaoaxHr2N3xD6xHd3W_bdPzIw7IRW1lEYHKxA-YlZuHtsW4Ot7pSSMT081Vlcs5rAe_Ul9nPM8PfhsaD2hEXqJXydWYuU2 Dec 06 14:23:41 volumio-vers-4 sudo[18946]: pam_unix(sudo:session): session closed for user root Dec 06 14:23:41 volumio-vers-4 volumio[1338]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Dec 06 14:23:41 volumio-vers-4 volumio[1338]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Dec 06 14:23:41 volumio-vers-4 sudo[18948]: pam_unix(sudo:session): session closed for user root Dec 06 14:23:41 volumio-vers-4 volumio[1338]: info: peppymeterbasic Daemon Stop Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: Preload queue cleared Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: CoreStateMachine::ClearQueue Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: CoreStateMachine::stop Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: CorePlayQueue::clearPlayQueue Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: CorePlayQueue::saveQueue Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushQueue Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: CoreStateMachine::addQueueItems Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: CorePlayQueue::addQueueItems Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: Preload queue cleared Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Napoli-New%20York%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FBMuZEWV8d7M%2Fmovieposter.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22BMuZEWV8d7M%22%7D%7D%7D Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: Exploding uri youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Napoli-New%20York%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FBMuZEWV8d7M%2Fmovieposter.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22BMuZEWV8d7M%22%7D%7D%7D in service youtube2 Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: [youtube2-browse] explodeUri: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Napoli-New%20York%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FBMuZEWV8d7M%2Fmovieposter.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22BMuZEWV8d7M%22%7D%7D%7D Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPushQueue Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: CorePlayQueue::saveQueue Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: CoreStateMachine::updateTrackBlock Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrackBlock Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: CoreCommandRouter::volumioPlay Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: CoreStateMachine::play index 0 Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: CoreStateMachine::stop Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: CoreStateMachine::play index undefined Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: CoreStateMachine::startPlaybackTimer Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: CorePlayQueue::getTrack 0 Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22BMuZEWV8d7M%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Napoli-New%20York%22%2C%22artist%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi_webp%2FBMuZEWV8d7M%2Fmovieposter.webp%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22BMuZEWV8d7M%22%7D%7D%7D Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: [ytmusic] Obtained PO token for video #BMuZEWV8d7M: MlVvInEt4SJMLG8cpRozc4TTJpaRukDNpXc2JwvD3g0uhQsB9wCWLidOrCrod3XUf5KR2j1uhp7tBsgPgEIPXNFXenOridCGXLEn56uxOEm7eoCxNWox Dec 06 14:23:43 volumio-vers-4 volumio[1338]: info: [ytmusic] Obtained PO token for video #BMuZEWV8d7M: MlUnaL8EVTNXFSdWazOHYp_qbtxfk_Tcvk5-bcXqahw1vENLOSkiPzx35GAmXsHFZKvZkPNHMo_2P4BFTmu7TcpuMjlloGSXR4j6rSAyMUZyZbG2Ru43 Dec 06 14:23:44 volumio-vers-4 volumio[1338]: info: [youtube2] VideoModel.getInfo(BMuZEWV8d7M): validating stream URL "https://rr4---sn-obppu-hm2d.googlevideo.com/videoplayback?expire=1765049024&ei=Xy40aZ74PLyDi9oPs83g4Q4&ip=88.147.44.111&id=o-AIbFlfAvUD-fe8nje6WtVCFaJwXbjJFX5o7ebvqJN4OR&itag=258&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=0&met=1765027424%2C&mh=VB&mm=31%2C29&mn=sn-obppu-hm2d%2Csn-hpa7zns6&ms=au%2Crdu&mv=m&mvi=4&pl=21&rms=au%2Cau&initcwndbps=2213750&bui=AdEuB5S5bAaVl29PmcmQOmJOVlUVZG2sQdZYlqecB8qkq5SmtlIYa7iKTox6oEigDLZ-NX2QONvxKjuG&spc=6b0G_DQSF5_EXxsFmRyKc27hPLoi4xYKgmDHjcNv87owdaDGSvbInSlWYUU&vprv=1&svpuc=1&xtags=acont%3Doriginal%3Alang%3Dit&mime=audio%2Fmp4&ns=zJ3ZSAw0wEmSAdiaq8F0AZYQ&rqh=1&gir=yes&clen=5465803&dur=112.725&lmt=1740577077884203&mt=1765026803&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968%2C51669167%2C51673847&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=0000224&n=CrGSb07n6hhxfw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cxtags%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgYivWVvKCUr4YPujOAzd71bJk54Eu9eZwv2D2gtjFzowCIDgkyQ05qtzmzE_tcBNu4W-OcvHtCe-CZRRQEggPvAsQ&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAK0ZdA772FuAMY-i5FDanxFM3kkNmFn6CV4RFv8mY1tQAiEApfkFHc__m-hv0wYAV3joOj684feGuEsXX2fanx7U_aU%3D&pot=MlUnaL8EVTNXFSdWazOHYp_qbtxfk_Tcvk5-bcXqahw1vENLOSkiPzx35GAmXsHFZKvZkPNHMo_2P4BFTmu7TcpuMjlloGSXR4j6rSAyMUZyZbG2Ru43&cver=1.20250219.01.00"... Dec 06 14:23:44 volumio-vers-4 volumio[1338]: info: [youtube2] VideoModel.getInfo(BMuZEWV8d7M): stream validated in 0.121s. Dec 06 14:23:44 volumio-vers-4 volumio[1338]: info: [youtube2] (AutoplayManager) Added volumioStateListener Dec 06 14:23:44 volumio-vers-4 volumio[1338]: info: [youtube2] (AutoplayManager) Enabled Dec 06 14:23:44 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand stop Dec 06 14:23:44 volumio-vers-4 volumio[1338]: info: sendMpdCommand stop took 1 milliseconds Dec 06 14:23:44 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand clear Dec 06 14:23:44 volumio-vers-4 volumio[1338]: info: Dec 06 14:23:44 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces system playlist update Dec 06 14:23:44 volumio-vers-4 volumio[1338]: info: Ignoring MPD Status Update Dec 06 14:23:44 volumio-vers-4 volumio[1338]: info: sendMpdCommand clear took 1 milliseconds Dec 06 14:23:44 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand addid "https://rr4---sn-obppu-hm2d.googlevideo.com/videoplayback?expire=1765049024&ei=Xy40aZ74PLyDi9oPs83g4Q4&ip=88.147.44.111&id=o-AIbFlfAvUD-fe8nje6WtVCFaJwXbjJFX5o7ebvqJN4OR&itag=258&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=0&met=1765027424%2C&mh=VB&mm=31%2C29&mn=sn-obppu-hm2d%2Csn-hpa7zns6&ms=au%2Crdu&mv=m&mvi=4&pl=21&rms=au%2Cau&initcwndbps=2213750&bui=AdEuB5S5bAaVl29PmcmQOmJOVlUVZG2sQdZYlqecB8qkq5SmtlIYa7iKTox6oEigDLZ-NX2QONvxKjuG&spc=6b0G_DQSF5_EXxsFmRyKc27hPLoi4xYKgmDHjcNv87owdaDGSvbInSlWYUU&vprv=1&svpuc=1&xtags=acont%3Doriginal%3Alang%3Dit&mime=audio%2Fmp4&ns=zJ3ZSAw0wEmSAdiaq8F0AZYQ&rqh=1&gir=yes&clen=5465803&dur=112.725&lmt=1740577077884203&mt=1765026803&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968%2C51669167%2C51673847&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=0000224&n=CrGSb07n6hhxfw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cxtags%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgYivWVvKCUr4YPujOAzd71bJk54Eu9eZwv2D2gtjFzowCIDgkyQ05qtzmzE_tcBNu4W-OcvHtCe-CZRRQEggPvAsQ&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAK0ZdA772FuAMY-i5FDanxFM3kkNmFn6CV4RFv8mY1tQAiEApfkFHc__m-hv0wYAV3joOj684feGuEsXX2fanx7U_aU%3D&pot=MlUnaL8EVTNXFSdWazOHYp_qbtxfk_Tcvk5-bcXqahw1vENLOSkiPzx35GAmXsHFZKvZkPNHMo_2P4BFTmu7TcpuMjlloGSXR4j6rSAyMUZyZbG2Ru43&cver=1.20250219.01.00&t.YouTube" Dec 06 14:23:44 volumio-vers-4 volumio[1338]: error: updateQueue error: null Dec 06 14:23:44 volumio-vers-4 volumio[1338]: info: ------------------------------ 1ms Dec 06 14:23:44 volumio-vers-4 volumio[1338]: info: Dec 06 14:23:44 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces system playlist update Dec 06 14:23:44 volumio-vers-4 volumio[1338]: info: Ignoring MPD Status Update Dec 06 14:23:44 volumio-vers-4 volumio[1338]: info: sendMpdCommand addid "https://rr4---sn-obppu-hm2d.googlevideo.com/videoplayback?expire=1765049024&ei=Xy40aZ74PLyDi9oPs83g4Q4&ip=88.147.44.111&id=o-AIbFlfAvUD-fe8nje6WtVCFaJwXbjJFX5o7ebvqJN4OR&itag=258&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=0&met=1765027424%2C&mh=VB&mm=31%2C29&mn=sn-obppu-hm2d%2Csn-hpa7zns6&ms=au%2Crdu&mv=m&mvi=4&pl=21&rms=au%2Cau&initcwndbps=2213750&bui=AdEuB5S5bAaVl29PmcmQOmJOVlUVZG2sQdZYlqecB8qkq5SmtlIYa7iKTox6oEigDLZ-NX2QONvxKjuG&spc=6b0G_DQSF5_EXxsFmRyKc27hPLoi4xYKgmDHjcNv87owdaDGSvbInSlWYUU&vprv=1&svpuc=1&xtags=acont%3Doriginal%3Alang%3Dit&mime=audio%2Fmp4&ns=zJ3ZSAw0wEmSAdiaq8F0AZYQ&rqh=1&gir=yes&clen=5465803&dur=112.725&lmt=1740577077884203&mt=1765026803&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968%2C51669167%2C51673847&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=0000224&n=CrGSb07n6hhxfw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cxtags%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgYivWVvKCUr4YPujOAzd71bJk54Eu9eZwv2D2gtjFzowCIDgkyQ05qtzmzE_tcBNu4W-OcvHtCe-CZRRQEggPvAsQ&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAK0ZdA772FuAMY-i5FDanxFM3kkNmFn6CV4RFv8mY1tQAiEApfkFHc__m-hv0wYAV3joOj684feGuEsXX2fanx7U_aU%3D&pot=MlUnaL8EVTNXFSdWazOHYp_qbtxfk_Tcvk5-bcXqahw1vENLOSkiPzx35GAmXsHFZKvZkPNHMo_2P4BFTmu7TcpuMjlloGSXR4j6rSAyMUZyZbG2Ru43&cver=1.20250219.01.00&t.YouTube" took 1 milliseconds Dec 06 14:23:44 volumio-vers-4 volumio[1338]: verbose: MPD COMMAND [object Object] Dec 06 14:23:44 volumio-vers-4 volumio[1338]: verbose: MPD COMMAND [object Object] Dec 06 14:23:44 volumio-vers-4 volumio[1338]: verbose: MPD COMMAND [object Object] Dec 06 14:23:44 volumio-vers-4 volumio[1338]: info: Dec 06 14:23:44 volumio-vers-4 volumio[1338]: ---------------------------- MPD announces system playlist update Dec 06 14:23:44 volumio-vers-4 volumio[1338]: info: Ignoring MPD Status Update Dec 06 14:23:44 volumio-vers-4 volumio[1338]: info: ------------------------------ 2ms Dec 06 14:23:44 volumio-vers-4 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 06 14:23:44 volumio-vers-4 volumio[1338]: verbose: ControllerMpd::sendMpdCommand play Dec 06 14:23:44 volumio-vers-4 volumio[1338]: info: ------------------------------ 2ms Dec 06 14:23:44 volumio-vers-4 volumio[1338]: info: sendMpdCommand play took 1 milliseconds Dec 06 14:23:44 volumio-vers-4 volumio[1338]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 14:23:44 volumio-vers-4 volumio[1338]: InnertubeError: Playback tracking not available Dec 06 14:23:44 volumio-vers-4 volumio[1338]: at _VideoInfo.addToWatchHistory (/data/plugins/music_service/youtube2/node_modules/volumio-yt-support/node_modules/volumio-youtubei.js/bundle/node.cjs:30709:13) Dec 06 14:23:44 volumio-vers-4 volumio[1338]: at _VideoInfo.addToWatchHistory (/data/plugins/music_service/youtube2/node_modules/volumio-yt-support/node_modules/volumio-youtubei.js/bundle/node.cjs:32099:18) Dec 06 14:23:44 volumio-vers-4 volumio[1338]: at Object.addToHistory (/data/plugins/music_service/youtube2/dist/lib/model/VideoModel.js:66:34) Dec 06 14:23:44 volumio-vers-4 volumio[1338]: at PlayController.clearAddPlayTrack (/data/plugins/music_service/youtube2/dist/lib/controller/play/PlayController.js:141:35) Dec 06 14:23:44 volumio-vers-4 volumio[1338]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) { Dec 06 14:23:44 volumio-vers-4 volumio[1338]: date: 2025-12-06T13:23:44.451Z, Dec 06 14:23:44 volumio-vers-4 volumio[1338]: version: '1.6.0', Dec 06 14:23:44 volumio-vers-4 volumio[1338]: info: undefined Dec 06 14:23:44 volumio-vers-4 volumio[1338]: } Dec 06 14:23:44 volumio-vers-4 volumio[1338]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 14:23:45 volumio-vers-4 sudo[18976]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-06 14:22' Dec 06 14:23:45 volumio-vers-4 sudo[18976]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"