-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2025-04-23 13:25:07 CEST. -- Apr 23 13:24:05 stromer wpa_supplicant[874]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 13:24:11 stromer wpa_supplicant[874]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 13:24:12 stromer volumio[972]: info: CALLMETHOD: user_interface peppyspectrum dlspectrum [object Object] Apr 23 13:24:12 stromer volumio[972]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , dlspectrum Apr 23 13:24:14 stromer volumio[972]: --2025-04-23 13:24:12-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/Zipped-folders/1280x400+30-Gelo5.zip Apr 23 13:24:14 stromer volumio[972]: Resolving github.com (github.com)... 140.82.121.3 Apr 23 13:24:14 stromer volumio[972]: Connecting to github.com (github.com)|140.82.121.3|:443... connected. Apr 23 13:24:14 stromer volumio[972]: HTTP request sent, awaiting response... 302 Found Apr 23 13:24:14 stromer volumio[972]: Location: https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/Zipped-folders/1280x400%2B30-Gelo5.zip [following] Apr 23 13:24:14 stromer volumio[972]: --2025-04-23 13:24:13-- https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/Zipped-folders/1280x400%2B30-Gelo5.zip Apr 23 13:24:14 stromer volumio[972]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.108.133, 185.199.110.133, 185.199.109.133, ... Apr 23 13:24:14 stromer volumio[972]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.108.133|:443... connected. Apr 23 13:24:14 stromer volumio[972]: HTTP request sent, awaiting response... 200 OK Apr 23 13:24:14 stromer volumio[972]: Length: 7220248 (6.9M) [application/zip] Apr 23 13:24:14 stromer volumio[972]: Saving to: ‘/tmp/1280x400+30-Gelo5.zip’ Apr 23 13:24:14 stromer volumio[972]: 0K .......... .......... .......... .......... .......... 0% 4.66M 1s Apr 23 13:24:14 stromer volumio[972]: 50K .......... .......... .......... .......... .......... 1% 6.64M 1s Apr 23 13:24:14 stromer volumio[972]: 100K .......... .......... .......... .......... .......... 2% 9.99M 1s Apr 23 13:24:14 stromer volumio[972]: 150K .......... .......... .......... .......... .......... 2% 9.65M 1s Apr 23 13:24:14 stromer volumio[972]: 200K .......... .......... .......... .......... .......... 3% 8.48M 1s Apr 23 13:24:14 stromer volumio[972]: 250K .......... .......... .......... .......... .......... 4% 8.46M 1s Apr 23 13:24:14 stromer volumio[972]: 300K .......... .......... .......... .......... .......... 4% 7.60M 1s Apr 23 13:24:14 stromer volumio[972]: 350K .......... .......... .......... .......... .......... 5% 8.79M 1s Apr 23 13:24:14 stromer volumio[972]: 400K .......... .......... .......... .......... .......... 6% 7.70M 1s Apr 23 13:24:14 stromer volumio[972]: 450K .......... .......... .......... .......... .......... 7% 10.0M 1s Apr 23 13:24:14 stromer volumio[972]: 500K .......... .......... .......... .......... .......... 7% 9.83M 1s Apr 23 13:24:14 stromer volumio[972]: 550K .......... .......... .......... .......... .......... 8% 9.98M 1s Apr 23 13:24:14 stromer volumio[972]: 600K .......... .......... .......... .......... .......... 9% 9.97M 1s Apr 23 13:24:14 stromer volumio[972]: 650K .......... .......... .......... .......... .......... 9% 10.1M 1s Apr 23 13:24:14 stromer volumio[972]: 700K .......... .......... .......... .......... .......... 10% 9.69M 1s Apr 23 13:24:14 stromer volumio[972]: 750K .......... .......... .......... .......... .......... 11% 8.51M 1s Apr 23 13:24:14 stromer volumio[972]: 800K .......... .......... .......... .......... .......... 12% 6.81M 1s Apr 23 13:24:14 stromer volumio[972]: 850K .......... .......... .......... .......... .......... 12% 6.42M 1s Apr 23 13:24:14 stromer volumio[972]: 900K .......... .......... .......... .......... .......... 13% 7.73M 1s Apr 23 13:24:14 stromer volumio[972]: 950K .......... .......... .......... .......... .......... 14% 9.18M 1s Apr 23 13:24:14 stromer volumio[972]: 1000K .......... .......... .......... .......... .......... 14% 7.88M 1s Apr 23 13:24:14 stromer volumio[972]: 1050K .......... .......... .......... .......... .......... 15% 8.65M 1s Apr 23 13:24:14 stromer volumio[972]: 1100K .......... .......... .......... .......... .......... 16% 8.52M 1s Apr 23 13:24:14 stromer volumio[972]: 1150K .......... .......... .......... .......... .......... 17% 7.83M 1s Apr 23 13:24:14 stromer volumio[972]: 1200K .......... .......... .......... .......... .......... 17% 6.39M 1s Apr 23 13:24:14 stromer volumio[972]: 1250K .......... .......... .......... .......... .......... 18% 8.90M 1s Apr 23 13:24:14 stromer volumio[972]: 1300K .......... .......... .......... .......... .......... 19% 8.99M 1s Apr 23 13:24:14 stromer volumio[972]: 1350K .......... .......... .......... .......... .......... 19% 6.44M 1s Apr 23 13:24:14 stromer volumio[972]: 1400K .......... .......... .......... .......... .......... 20% 6.77M 1s Apr 23 13:24:14 stromer volumio[972]: 1450K .......... .......... .......... .......... .......... 21% 10.2M 1s Apr 23 13:24:14 stromer volumio[972]: 1500K .......... .......... .......... .......... .......... 21% 4.01M 1s Apr 23 13:24:14 stromer volumio[972]: 1550K .......... .......... .......... .......... .......... 22% 10.1M 1s Apr 23 13:24:14 stromer volumio[972]: 1600K .......... .......... .......... .......... .......... 23% 7.52M 1s Apr 23 13:24:14 stromer volumio[972]: 1650K .......... .......... .......... .......... .......... 24% 10.4M 1s Apr 23 13:24:14 stromer volumio[972]: 1700K .......... .......... .......... .......... .......... 24% 9.48M 1s Apr 23 13:24:14 stromer volumio[972]: 1750K .......... .......... .......... .......... .......... 25% 8.95M 1s Apr 23 13:24:14 stromer volumio[972]: 1800K .......... .......... .......... .......... .......... 26% 7.39M 1s Apr 23 13:24:14 stromer volumio[972]: 1850K .......... .......... .......... .......... .......... 26% 9.02M 1s Apr 23 13:24:14 stromer volumio[972]: 1900K .......... .......... .......... .......... .......... 27% 10.2M 1s Apr 23 13:24:14 stromer volumio[972]: 1950K .......... .......... .......... .......... .......... 28% 7.47M 1s Apr 23 13:24:14 stromer volumio[972]: 2000K .......... .......... .......... .......... .......... 29% 9.86M 1s Apr 23 13:24:14 stromer volumio[972]: 2050K .......... .......... .......... .......... .......... 29% 10.0M 1s Apr 23 13:24:14 stromer volumio[972]: 2100K .......... .......... .......... .......... .......... 30% 10.1M 1s Apr 23 13:24:14 stromer volumio[972]: 2150K .......... .......... .......... .......... .......... 31% 10.2M 1s Apr 23 13:24:14 stromer volumio[972]: 2200K .......... .......... .......... .......... .......... 31% 10.2M 1s Apr 23 13:24:14 stromer volumio[972]: 2250K .......... .......... .......... .......... .......... 32% 8.99M 1s Apr 23 13:24:14 stromer volumio[972]: 2300K .......... .......... .......... .......... .......... 33% 8.50M 1s Apr 23 13:24:14 stromer volumio[972]: 2350K .......... .......... .......... .......... .......... 34% 6.50M 1s Apr 23 13:24:14 stromer volumio[972]: 2400K .......... .......... .......... .......... .......... 34% 8.85M 1s Apr 23 13:24:14 stromer volumio[972]: 2450K .......... .......... .......... .......... .......... 35% 9.98M 1s Apr 23 13:24:14 stromer volumio[972]: 2500K .......... .......... .......... .......... .......... 36% 9.98M 1s Apr 23 13:24:14 stromer volumio[972]: 2550K .......... .......... .......... .......... .......... 36% 9.65M 1s Apr 23 13:24:14 stromer volumio[972]: 2600K .......... .......... .......... .......... .......... 37% 10.1M 1s Apr 23 13:24:14 stromer volumio[972]: 2650K .......... .......... .......... .......... .......... 38% 10.1M 1s Apr 23 13:24:14 stromer volumio[972]: 2700K .......... .......... .......... .......... .......... 39% 10.2M 1s Apr 23 13:24:14 stromer volumio[972]: 2750K .......... .......... .......... .......... .......... 39% 7.62M 0s Apr 23 13:24:14 stromer volumio[972]: 2800K .......... .......... .......... .......... .......... 40% 8.95M 0s Apr 23 13:24:14 stromer volumio[972]: 2850K .......... .......... .......... .......... .......... 41% 9.01M 0s Apr 23 13:24:14 stromer volumio[972]: 2900K .......... .......... .......... .......... .......... 41% 7.91M 0s Apr 23 13:24:14 stromer volumio[972]: 2950K .......... .......... .......... .......... .......... 42% 8.19M 0s Apr 23 13:24:14 stromer volumio[972]: 3000K .......... .......... .......... .......... .......... 43% 10.0M 0s Apr 23 13:24:14 stromer volumio[972]: 3050K .......... .......... .......... .......... .......... 43% 10.3M 0s Apr 23 13:24:14 stromer volumio[972]: 3100K .......... .......... .......... .......... .......... 44% 9.63M 0s Apr 23 13:24:14 stromer volumio[972]: 3150K .......... .......... .......... .......... .......... 45% 7.53M 0s Apr 23 13:24:14 stromer volumio[972]: 3200K .......... .......... .......... .......... .......... 46% 10.1M 0s Apr 23 13:24:14 stromer volumio[972]: 3250K .......... .......... .......... .......... .......... 46% 9.80M 0s Apr 23 13:24:14 stromer volumio[972]: 3300K .......... .......... .......... .......... .......... 47% 9.80M 0s Apr 23 13:24:14 stromer volumio[972]: 3350K .......... .......... .......... .......... .......... 48% 9.02M 0s Apr 23 13:24:14 stromer volumio[972]: 3400K .......... .......... .......... .......... .......... 48% 8.58M 0s Apr 23 13:24:14 stromer volumio[972]: 3450K .......... .......... .......... .......... .......... 49% 8.26M 0s Apr 23 13:24:14 stromer volumio[972]: 3500K .......... .......... .......... .......... .......... 50% 8.38M 0s Apr 23 13:24:14 stromer volumio[972]: 3550K .......... .......... .......... .......... .......... 51% 7.66M 0s Apr 23 13:24:14 stromer volumio[972]: 3600K .......... .......... .......... .......... .......... 51% 9.96M 0s Apr 23 13:24:14 stromer volumio[972]: 3650K .......... .......... .......... .......... .......... 52% 10.0M 0s Apr 23 13:24:14 stromer volumio[972]: 3700K .......... .......... .......... .......... .......... 53% 10.1M 0s Apr 23 13:24:14 stromer volumio[972]: 3750K .......... .......... .......... .......... .......... 53% 9.88M 0s Apr 23 13:24:14 stromer volumio[972]: 3800K .......... .......... .......... .......... .......... 54% 10.2M 0s Apr 23 13:24:14 stromer volumio[972]: 3850K .......... .......... .......... .......... .......... 55% 9.77M 0s Apr 23 13:24:14 stromer volumio[972]: 3900K .......... .......... .......... .......... .......... 56% 9.08M 0s Apr 23 13:24:14 stromer volumio[972]: 3950K .......... .......... .......... .......... .......... 56% 6.56M 0s Apr 23 13:24:14 stromer volumio[972]: 4000K .......... .......... .......... .......... .......... 57% 7.59M 0s Apr 23 13:24:14 stromer volumio[972]: 4050K .......... .......... .......... .......... .......... 58% 8.41M 0s Apr 23 13:24:14 stromer volumio[972]: 4100K .......... .......... .......... .......... .......... 58% 9.93M 0s Apr 23 13:24:14 stromer volumio[972]: 4150K .......... .......... .......... .......... .......... 59% 10.1M 0s Apr 23 13:24:14 stromer volumio[972]: 4200K .......... .......... .......... .......... .......... 60% 9.74M 0s Apr 23 13:24:14 stromer volumio[972]: 4250K .......... .......... .......... .......... .......... 60% 10.1M 0s Apr 23 13:24:14 stromer volumio[972]: 4300K .......... .......... .......... .......... .......... 61% 9.93M 0s Apr 23 13:24:14 stromer volumio[972]: 4350K .......... .......... .......... .......... .......... 62% 7.61M 0s Apr 23 13:24:14 stromer volumio[972]: 4400K .......... .......... .......... .......... .......... 63% 9.85M 0s Apr 23 13:24:14 stromer volumio[972]: 4450K .......... .......... .......... .......... .......... 63% 9.38M 0s Apr 23 13:24:14 stromer volumio[972]: 4500K .......... .......... .......... .......... .......... 64% 8.18M 0s Apr 23 13:24:14 stromer volumio[972]: 4550K .......... .......... .......... .......... .......... 65% 9.19M 0s Apr 23 13:24:14 stromer volumio[972]: 4600K .......... .......... .......... .......... .......... 65% 6.26M 0s Apr 23 13:24:14 stromer volumio[972]: 4650K .......... .......... .......... .......... .......... 66% 10.0M 0s Apr 23 13:24:14 stromer volumio[972]: 4700K .......... .......... .......... .......... .......... 67% 10.1M 0s Apr 23 13:24:14 stromer volumio[972]: 4750K .......... .......... .......... .......... .......... 68% 9.70M 0s Apr 23 13:24:14 stromer volumio[972]: 4800K .......... .......... .......... .......... .......... 68% 9.98M 0s Apr 23 13:24:14 stromer volumio[972]: 4850K .......... .......... .......... .......... .......... 69% 10.1M 0s Apr 23 13:24:14 stromer volumio[972]: 4900K .......... .......... .......... .......... .......... 70% 10.2M 0s Apr 23 13:24:14 stromer volumio[972]: 4950K .......... .......... .......... .......... .......... 70% 9.31M 0s Apr 23 13:24:14 stromer volumio[972]: 5000K .......... .......... .......... .......... .......... 71% 9.34M 0s Apr 23 13:24:14 stromer volumio[972]: 5050K .......... .......... .......... .......... .......... 72% 6.55M 0s Apr 23 13:24:14 stromer volumio[972]: 5100K .......... .......... .......... .......... .......... 73% 8.22M 0s Apr 23 13:24:14 stromer volumio[972]: 5150K .......... .......... .......... .......... .......... 73% 8.76M 0s Apr 23 13:24:14 stromer volumio[972]: 5200K .......... .......... .......... .......... .......... 74% 9.97M 0s Apr 23 13:24:14 stromer volumio[972]: 5250K .......... .......... .......... .......... .......... 75% 10.0M 0s Apr 23 13:24:14 stromer volumio[972]: 5300K .......... .......... .......... .......... .......... 75% 9.83M 0s Apr 23 13:24:14 stromer volumio[972]: 5350K .......... .......... .......... .......... .......... 76% 7.75M 0s Apr 23 13:24:14 stromer volumio[972]: 5400K .......... .......... .......... .......... .......... 77% 10.1M 0s Apr 23 13:24:14 stromer volumio[972]: 5450K .......... .......... .......... .......... .......... 78% 10.1M 0s Apr 23 13:24:14 stromer volumio[972]: 5500K .......... .......... .......... .......... .......... 78% 9.69M 0s Apr 23 13:24:14 stromer volumio[972]: 5550K .......... .......... .......... .......... .......... 79% 9.60M 0s Apr 23 13:24:14 stromer volumio[972]: 5600K .......... .......... .......... .......... .......... 80% 8.60M 0s Apr 23 13:24:14 stromer volumio[972]: 5650K .......... .......... .......... .......... .......... 80% 8.72M 0s Apr 23 13:24:14 stromer volumio[972]: 5700K .......... .......... .......... .......... .......... 81% 7.68M 0s Apr 23 13:24:14 stromer volumio[972]: 5750K .......... .......... .......... .......... .......... 82% 7.38M 0s Apr 23 13:24:14 stromer volumio[972]: 5800K .......... .......... .......... .......... .......... 82% 10.2M 0s Apr 23 13:24:14 stromer volumio[972]: 5850K .......... .......... .......... .......... .......... 83% 9.81M 0s Apr 23 13:24:14 stromer volumio[972]: 5900K .......... .......... .......... .......... .......... 84% 10.5M 0s Apr 23 13:24:14 stromer volumio[972]: 5950K .......... .......... .......... .......... .......... 85% 9.93M 0s Apr 23 13:24:14 stromer volumio[972]: 6000K .......... .......... .......... .......... .......... 85% 10.1M 0s Apr 23 13:24:14 stromer volumio[972]: 6050K .......... .......... .......... .......... .......... 86% 9.81M 0s Apr 23 13:24:14 stromer volumio[972]: 6100K .......... .......... .......... .......... .......... 87% 7.59M 0s Apr 23 13:24:14 stromer volumio[972]: 6150K .......... .......... .......... .......... .......... 87% 8.81M 0s Apr 23 13:24:14 stromer volumio[972]: 6200K .......... .......... .......... .......... .......... 88% 9.23M 0s Apr 23 13:24:14 stromer volumio[972]: 6250K .......... .......... .......... .......... .......... 89% 8.48M 0s Apr 23 13:24:14 stromer volumio[972]: 6300K .......... .......... .......... .......... .......... 90% 10.0M 0s Apr 23 13:24:14 stromer volumio[972]: 6350K .......... .......... .......... .......... .......... 90% 10.4M 0s Apr 23 13:24:14 stromer volumio[972]: 6400K .......... .......... .......... .......... .......... 91% 10.4M 0s Apr 23 13:24:14 stromer volumio[972]: 6450K .......... .......... .......... .......... .......... 92% 10.4M 0s Apr 23 13:24:14 stromer volumio[972]: 6500K .......... .......... .......... .......... .......... 92% 9.64M 0s Apr 23 13:24:14 stromer volumio[972]: 6550K .......... .......... .......... .......... .......... 93% 9.10M 0s Apr 23 13:24:14 stromer volumio[972]: 6600K .......... .......... .......... .......... .......... 94% 12.2M 0s Apr 23 13:24:14 stromer volumio[972]: 6650K .......... .......... .......... .......... .......... 95% 13.1M 0s Apr 23 13:24:14 stromer volumio[972]: 6700K .......... .......... .......... .......... .......... 95% 10.5M 0s Apr 23 13:24:14 stromer volumio[972]: 6750K .......... .......... .......... .......... .......... 96% 10.2M 0s Apr 23 13:24:14 stromer volumio[972]: 6800K .......... .......... .......... .......... .......... 97% 8.02M 0s Apr 23 13:24:14 stromer volumio[972]: 6850K .......... .......... .......... .......... .......... 97% 11.0M 0s Apr 23 13:24:14 stromer volumio[972]: 6900K .......... .......... .......... .......... .......... 98% 12.0M 0s Apr 23 13:24:14 stromer volumio[972]: 6950K .......... .......... .......... .......... .......... 99% 12.1M 0s Apr 23 13:24:14 stromer volumio[972]: 7000K .......... .......... .......... .......... .......... 99% 13.7M 0s Apr 23 13:24:14 stromer volumio[972]: 7050K . 100% 1952G=0.8s Apr 23 13:24:14 stromer volumio[972]: 2025-04-23 13:24:14 (8.88 MB/s) - ‘/tmp/1280x400+30-Gelo5.zip’ saved [7220248/7220248] Apr 23 13:24:14 stromer sudo[3163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /data/INTERNAL/PeppySpectrum/Templates/ Apr 23 13:24:14 stromer sudo[3163]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 13:24:14 stromer sudo[3163]: pam_unix(sudo:session): session closed for user root Apr 23 13:24:14 stromer volumio[972]: info: PeppySpectrum ---message miniunzip -o /tmp/1280x400+30-Gelo5.zip -d /data/INTERNAL/PeppySpectrum/Templates/ Apr 23 13:24:14 stromer volumio[972]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 23 13:24:14 stromer volumio[972]: info: CoreCommandRouter::Close All Modals sent Apr 23 13:24:14 stromer volumio[972]: info: PeppySpectrum ---list is Random,G5_01Pipe,G5_02Pipe,G5_03Nixie,G5_04Pipe Wood,G5_05Pipe Gold,G5_06Nixie Apr 23 13:24:14 stromer volumio[972]: info: PeppySpectrum ---1280x400+30-30bars-balbuze Apr 23 13:24:14 stromer volumio[972]: info: PeppySpectrum ---1280x400+30-custom Apr 23 13:24:14 stromer volumio[972]: info: PeppySpectrum ---1280x400+30-Gelo5 Apr 23 13:24:14 stromer volumio[972]: info: PeppySpectrum ---1280x400+99-99bars-balbuze Apr 23 13:24:14 stromer volumio[972]: info: PeppySpectrum ---1480x320+99-99bars-balbuze Apr 23 13:24:14 stromer volumio[972]: info: PeppySpectrum ---1920x1080+13-Gelo5 Apr 23 13:24:14 stromer volumio[972]: info: PeppySpectrum ---1920x1080+30-Gelo5 Apr 23 13:24:14 stromer volumio[972]: info: PeppySpectrum ---1920x1080+55-Gelo5 Apr 23 13:24:14 stromer volumio[972]: info: PeppySpectrum ---1920x720+99-99bars-balbuze Apr 23 13:24:14 stromer volumio[972]: info: PeppySpectrum ---800x100+99-99bars Apr 23 13:24:14 stromer volumio[972]: info: PeppySpectrum ---800x46+99-99bars-balbuze Apr 23 13:24:14 stromer volumio[972]: info: PeppySpectrum ---800x480+20-marshall Apr 23 13:24:14 stromer volumio[972]: info: PeppySpectrum ---800x480+230-230-bars-freq Apr 23 13:24:14 stromer volumio[972]: info: PeppySpectrum ---800x480+30-30bars-balbuze Apr 23 13:24:14 stromer volumio[972]: info: PeppySpectrum ---800x480+30-Gelo5 Apr 23 13:24:14 stromer volumio[972]: info: PeppySpectrum ---800x480+30-marshall Apr 23 13:24:14 stromer volumio[972]: info: PeppySpectrum ---800x480+55-marshall Apr 23 13:24:14 stromer volumio[972]: info: PeppySpectrum ---800x480+99-99bars-balbuze Apr 23 13:24:16 stromer wpa_supplicant[874]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 13:24:19 stromer volumio[972]: info: CALLMETHOD: user_interface peppyspectrum savepeppy [object Object] Apr 23 13:24:19 stromer volumio[972]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy Apr 23 13:24:19 stromer volumio[972]: aaaaaaaaaaa 1280,400+30-Gelo5 Apr 23 13:24:19 stromer volumio[972]: info: PeppySpectrum ---1280400+30-Gelo5 Apr 23 13:24:19 stromer volumio[972]: info: PeppySpectrum ---128040030 Apr 23 13:24:19 stromer volumio[972]: error: Failed callmethod call: TypeError: Cannot read property 'then' of undefined Apr 23 13:24:19 stromer volumio[972]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 23 13:24:19 stromer volumio[972]: info: CoreCommandRouter::Close All Modals sent Apr 23 13:24:19 stromer volumio[972]: info: PeppySpectrum ---list is Random,G5_01Pipe,G5_02Pipe,G5_03Nixie,G5_04Pipe Wood,G5_05Pipe Gold,G5_06Nixie,G5_07Naim,G5_08Kenwood,G5_09Marshal,G5_10Gold 2,G5_11Dorrought Apr 23 13:24:19 stromer volumio[972]: info: PeppySpectrum ---1280x400+30-30bars-balbuze Apr 23 13:24:19 stromer volumio[972]: info: PeppySpectrum ---1280x400+30-custom Apr 23 13:24:19 stromer volumio[972]: info: PeppySpectrum ---1280x400+30-Gelo5 Apr 23 13:24:19 stromer volumio[972]: info: PeppySpectrum ---1280x400+99-99bars-balbuze Apr 23 13:24:19 stromer volumio[972]: info: PeppySpectrum ---1480x320+99-99bars-balbuze Apr 23 13:24:19 stromer volumio[972]: info: PeppySpectrum ---1920x1080+13-Gelo5 Apr 23 13:24:19 stromer volumio[972]: info: PeppySpectrum ---1920x1080+30-Gelo5 Apr 23 13:24:19 stromer volumio[972]: info: PeppySpectrum ---1920x1080+55-Gelo5 Apr 23 13:24:19 stromer volumio[972]: info: PeppySpectrum ---1920x720+99-99bars-balbuze Apr 23 13:24:19 stromer volumio[972]: info: PeppySpectrum ---800x100+99-99bars Apr 23 13:24:19 stromer volumio[972]: info: PeppySpectrum ---800x46+99-99bars-balbuze Apr 23 13:24:19 stromer volumio[972]: info: PeppySpectrum ---800x480+20-marshall Apr 23 13:24:19 stromer volumio[972]: info: PeppySpectrum ---800x480+230-230-bars-freq Apr 23 13:24:19 stromer volumio[972]: info: PeppySpectrum ---800x480+30-30bars-balbuze Apr 23 13:24:19 stromer volumio[972]: info: PeppySpectrum ---800x480+30-Gelo5 Apr 23 13:24:19 stromer volumio[972]: info: PeppySpectrum ---800x480+30-marshall Apr 23 13:24:19 stromer volumio[972]: info: PeppySpectrum ---800x480+55-marshall Apr 23 13:24:19 stromer volumio[972]: info: PeppySpectrum ---800x480+99-99bars-balbuze Apr 23 13:24:24 stromer go-librespot[1321]: time="2025-04-23T13:24:24+02:00" level=trace msg="sent dealer ping" Apr 23 13:24:24 stromer go-librespot[1321]: time="2025-04-23T13:24:24+02:00" level=trace msg="received dealer pong" Apr 23 13:24:25 stromer wpa_supplicant[874]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 13:24:29 stromer volumio[972]: info: CALLMETHOD: user_interface peppyspectrum savepeppy1 [object Object] Apr 23 13:24:29 stromer volumio[972]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy1 Apr 23 13:24:29 stromer volumio[972]: error: Failed callmethod call: TypeError: Cannot read property 'then' of undefined Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum -----------------------spectrumG5_01Pipe Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum -----------------------$basefolder/data/INTERNAL/PeppySpectrum/Templates Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum -----------------------screensize1280x400+30-Gelo5 Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum -----------------------screenwidth1280 Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum -----------------------screenheight400 Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum -----------------------spectrumsize30 Apr 23 13:24:29 stromer volumio[972]: error: PeppySpectrum ---Error writing config null Apr 23 13:24:29 stromer sudo[3215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart peppyspectrum.service Apr 23 13:24:29 stromer sudo[3215]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 13:24:29 stromer systemd[1]: Stopping peppyspectrum Daemon... Apr 23 13:24:29 stromer systemd[1]: peppyspectrum.service: Main process exited, code=killed, status=15/TERM Apr 23 13:24:29 stromer volumio[972]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 23 13:24:29 stromer volumio[972]: info: CoreCommandRouter::Close All Modals sent Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum ---list is Random,G5_01Pipe,G5_02Pipe,G5_03Nixie,G5_04Pipe Wood,G5_05Pipe Gold,G5_06Nixie,G5_07Naim,G5_08Kenwood,G5_09Marshal,G5_10Gold 2,G5_11Dorrought Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum ---1280x400+30-30bars-balbuze Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum ---1280x400+30-custom Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum ---1280x400+30-Gelo5 Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum ---1280x400+99-99bars-balbuze Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum ---1480x320+99-99bars-balbuze Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum ---1920x1080+13-Gelo5 Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum ---1920x1080+30-Gelo5 Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum ---1920x1080+55-Gelo5 Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum ---1920x720+99-99bars-balbuze Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum ---800x100+99-99bars Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum ---800x46+99-99bars-balbuze Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum ---800x480+20-marshall Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum ---800x480+230-230-bars-freq Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum ---800x480+30-30bars-balbuze Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum ---800x480+30-Gelo5 Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum ---800x480+30-marshall Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum ---800x480+55-marshall Apr 23 13:24:29 stromer volumio[972]: info: PeppySpectrum ---800x480+99-99bars-balbuze Apr 23 13:24:29 stromer systemd[1]: peppyspectrum.service: Succeeded. Apr 23 13:24:29 stromer systemd[1]: Stopped peppyspectrum Daemon. Apr 23 13:24:30 stromer systemd[1]: Started peppyspectrum Daemon. Apr 23 13:24:30 stromer sudo[3215]: pam_unix(sudo:session): session closed for user root Apr 23 13:24:30 stromer volumio[972]: info: peppyspectrum Daemon Started Apr 23 13:24:30 stromer wpa_supplicant[874]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 13:24:39 stromer wpa_supplicant[874]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 13:24:43 stromer volumio[972]: info: CALLMETHOD: user_interface peppyspectrum savepeppy [object Object] Apr 23 13:24:43 stromer volumio[972]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy Apr 23 13:24:43 stromer volumio[972]: aaaaaaaaaaa 1280,400 Apr 23 13:24:43 stromer volumio[972]: info: PeppySpectrum ---1280400 Apr 23 13:24:43 stromer volumio[972]: error: Failed callmethod call: TypeError: Cannot read property 'then' of undefined Apr 23 13:24:43 stromer volumio[972]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 23 13:24:43 stromer volumio[972]: info: CoreCommandRouter::Close All Modals sent Apr 23 13:24:43 stromer volumio[972]: info: PeppySpectrum ---list is Random,s.1,s.2,s.3,s.4,s.5,s.6 Apr 23 13:24:43 stromer volumio[972]: info: PeppySpectrum ---1280x400+30-30bars-balbuze Apr 23 13:24:43 stromer volumio[972]: info: PeppySpectrum ---1280x400+30-custom Apr 23 13:24:43 stromer volumio[972]: info: PeppySpectrum ---1280x400+30-Gelo5 Apr 23 13:24:43 stromer volumio[972]: info: PeppySpectrum ---1280x400+99-99bars-balbuze Apr 23 13:24:43 stromer volumio[972]: info: PeppySpectrum ---1480x320+99-99bars-balbuze Apr 23 13:24:43 stromer volumio[972]: info: PeppySpectrum ---1920x1080+13-Gelo5 Apr 23 13:24:43 stromer volumio[972]: info: PeppySpectrum ---1920x1080+30-Gelo5 Apr 23 13:24:43 stromer volumio[972]: info: PeppySpectrum ---1920x1080+55-Gelo5 Apr 23 13:24:43 stromer volumio[972]: info: PeppySpectrum ---1920x720+99-99bars-balbuze Apr 23 13:24:43 stromer volumio[972]: info: PeppySpectrum ---800x100+99-99bars Apr 23 13:24:43 stromer volumio[972]: info: PeppySpectrum ---800x46+99-99bars-balbuze Apr 23 13:24:43 stromer volumio[972]: info: PeppySpectrum ---800x480+20-marshall Apr 23 13:24:43 stromer volumio[972]: info: PeppySpectrum ---800x480+230-230-bars-freq Apr 23 13:24:43 stromer volumio[972]: info: PeppySpectrum ---800x480+30-30bars-balbuze Apr 23 13:24:43 stromer volumio[972]: info: PeppySpectrum ---800x480+30-Gelo5 Apr 23 13:24:43 stromer volumio[972]: info: PeppySpectrum ---800x480+30-marshall Apr 23 13:24:43 stromer volumio[972]: info: PeppySpectrum ---800x480+55-marshall Apr 23 13:24:43 stromer volumio[972]: info: PeppySpectrum ---800x480+99-99bars-balbuze Apr 23 13:24:47 stromer volumio[972]: info: CALLMETHOD: user_interface peppyspectrum savepeppy1 [object Object] Apr 23 13:24:47 stromer volumio[972]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy1 Apr 23 13:24:47 stromer volumio[972]: error: Failed callmethod call: TypeError: Cannot read property 'then' of undefined Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum -----------------------spectrums.3 Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum -----------------------$basefolder Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum -----------------------screensize1280x400 Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum -----------------------screenwidth Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum -----------------------screenheight Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum -----------------------spectrumsize30 Apr 23 13:24:47 stromer volumio[972]: error: PeppySpectrum ---Error writing config null Apr 23 13:24:47 stromer sudo[3289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart peppyspectrum.service Apr 23 13:24:47 stromer sudo[3289]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 13:24:47 stromer systemd[1]: Stopping peppyspectrum Daemon... Apr 23 13:24:47 stromer systemd[1]: peppyspectrum.service: Main process exited, code=killed, status=15/TERM Apr 23 13:24:47 stromer volumio[972]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 23 13:24:47 stromer volumio[972]: info: CoreCommandRouter::Close All Modals sent Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum ---list is Random,s.1,s.2,s.3,s.4,s.5,s.6 Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum ---1280x400+30-30bars-balbuze Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum ---1280x400+30-custom Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum ---1280x400+30-Gelo5 Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum ---1280x400+99-99bars-balbuze Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum ---1480x320+99-99bars-balbuze Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum ---1920x1080+13-Gelo5 Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum ---1920x1080+30-Gelo5 Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum ---1920x1080+55-Gelo5 Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum ---1920x720+99-99bars-balbuze Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum ---800x100+99-99bars Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum ---800x46+99-99bars-balbuze Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum ---800x480+20-marshall Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum ---800x480+230-230-bars-freq Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum ---800x480+30-30bars-balbuze Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum ---800x480+30-Gelo5 Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum ---800x480+30-marshall Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum ---800x480+55-marshall Apr 23 13:24:47 stromer volumio[972]: info: PeppySpectrum ---800x480+99-99bars-balbuze Apr 23 13:24:47 stromer systemd[1]: peppyspectrum.service: Succeeded. Apr 23 13:24:47 stromer systemd[1]: Stopped peppyspectrum Daemon. Apr 23 13:24:47 stromer systemd[1]: Started peppyspectrum Daemon. Apr 23 13:24:47 stromer sudo[3289]: pam_unix(sudo:session): session closed for user root Apr 23 13:24:47 stromer volumio[972]: info: peppyspectrum Daemon Started Apr 23 13:24:48 stromer volumio[972]: info: [1745407488363] [80s80s] Pushing the next song state ABC - King Without a Crown and getting next track. Apr 23 13:24:48 stromer volumio[972]: info: CorePlayQueue::getTrack 0 Apr 23 13:24:48 stromer volumio[972]: info: CoreCommandRouter::servicePushState Apr 23 13:24:48 stromer volumio[972]: info: CorePlayQueue::getTrack 0 Apr 23 13:24:48 stromer volumio[972]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music115/v4/0b/41/ac/0b41ac3f-4cd9-848d-2763-8cb4f027df04/source/600x600bb.jpg","name":"ABC - King Without a Crown","title":"King Without a Crown","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"273","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Apr 23 13:24:48 stromer volumio[972]: verbose: CURRENT POSITION 0 Apr 23 13:24:48 stromer volumio[972]: info: CoreStateMachine::syncState stateService play Apr 23 13:24:48 stromer volumio[972]: info: CoreStateMachine::syncState currentStatus play Apr 23 13:24:48 stromer volumio[972]: info: Received an update from plugin. extracting info from payload Apr 23 13:24:48 stromer volumio[972]: info: CoreStateMachine::pushState Apr 23 13:24:48 stromer volumio[972]: info: CorePlayQueue::getTrack 0 Apr 23 13:24:48 stromer volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 23 13:24:48 stromer volumio[972]: info: CoreCommandRouter::volumioPushState Apr 23 13:24:48 stromer volumio[972]: info: CoreStateMachine::pushState Apr 23 13:24:48 stromer volumio[972]: info: CorePlayQueue::getTrack 0 Apr 23 13:24:48 stromer volumio[972]: info: CoreCommandRouter::volumioPushState Apr 23 13:24:48 stromer volumio[972]: info: [1745407488404] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=62&count=1 Apr 23 13:24:48 stromer volumio[972]: info: PeppySpectrum ---peppyspectrum status play Apr 23 13:24:48 stromer volumio[972]: info: PeppySpectrum ---peppyspectrum status play Apr 23 13:24:48 stromer sudo[3301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Apr 23 13:24:48 stromer sudo[3301]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 13:24:48 stromer volumio[972]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Apr 23 13:24:48 stromer volumio[972]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Apr 23 13:24:48 stromer volumio[972]: info: CorePlayQueue::getTrack 0 Apr 23 13:24:48 stromer volumio[972]: info: CorePlayQueue::getTrack 1 Apr 23 13:24:48 stromer sudo[3304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Apr 23 13:24:48 stromer sudo[3304]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 13:24:48 stromer sudo[3301]: pam_unix(sudo:session): session closed for user root Apr 23 13:24:48 stromer volumio[972]: info: peppyspectrum Daemon Started Apr 23 13:24:48 stromer volumio[972]: info: touch_display: Setting screensaver timeout to 0 seconds. Apr 23 13:24:48 stromer sudo[3304]: pam_unix(sudo:session): session closed for user root Apr 23 13:24:48 stromer volumio[972]: info: peppyspectrum Daemon Started Apr 23 13:24:48 stromer volumio[972]: info: [1745407488754] [80s80s] received new event containing 1 songs. Apr 23 13:24:48 stromer volumio[972]: info: [1745407488756] [80s80s] PlayNextTrack API delay: 30 Apr 23 13:24:48 stromer volumio[972]: info: [1745407488793] [80s80s] Setting timer to: 18244 milliseconds. Apr 23 13:24:49 stromer volumio[3291]: libpng warning: iCCP: known incorrect sRGB profile Apr 23 13:24:49 stromer volumio[3291]: libpng warning: iCCP: known incorrect sRGB profile Apr 23 13:24:49 stromer volumio[3291]: libpng warning: iCCP: known incorrect sRGB profile Apr 23 13:24:51 stromer wpa_supplicant[874]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 13:24:54 stromer go-librespot[1321]: time="2025-04-23T13:24:54+02:00" level=trace msg="sent dealer ping" Apr 23 13:24:54 stromer go-librespot[1321]: time="2025-04-23T13:24:54+02:00" level=trace msg="received dealer pong" Apr 23 13:24:54 stromer go-librespot[1321]: time="2025-04-23T13:24:54+02:00" level=debug msg="handling pause player command from 9ebc2cb4c063143d46874c2a64dc857bc1b08e2a" Apr 23 13:24:54 stromer go-librespot[1321]: time="2025-04-23T13:24:54+02:00" level=debug msg="pause track at 0ms" Apr 23 13:24:54 stromer go-librespot[1321]: time="2025-04-23T13:24:54+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 23 13:24:54 stromer go-librespot[1321]: time="2025-04-23T13:24:54+02:00" level=debug msg="sending successful reply for dealer request" Apr 23 13:24:54 stromer go-librespot[1321]: time="2025-04-23T13:24:54+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 23 13:24:54 stromer go-librespot[1321]: time="2025-04-23T13:24:54+02:00" level=trace msg="emitting websocket event: paused" Apr 23 13:24:54 stromer volumio[972]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:0VCBoaKWRtAUAOHdBdqEQX","play_origin":"playlist"}} Apr 23 13:24:54 stromer volumio[972]: info: Spotify is playing in volatile mode Apr 23 13:24:54 stromer volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 13:24:54 stromer volumio[972]: SPOTIFY: UNSET VOLATILE Apr 23 13:24:54 stromer volumio[972]: SPOTIFY: {"status":"play","position":0,"title":"ABC - King Without a Crown","artist":"80s80s Real 80s Radio","album":"","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music115/v4/0b/41/ac/0b41ac3f-4cd9-848d-2763-8cb4f027df04/source/600x600bb.jpg","uri":"http://streams.80s80s.de/web/mp3-192/volumio","trackType":"80s80s Radio","seek":0,"duration":"273","samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":true,"stream":"80s80s Radio","volatile":false,"service":"80s80s"} Apr 23 13:24:55 stromer volumio[972]: info: Setting Spotify stop after unset volatile call Apr 23 13:24:55 stromer volumio[972]: SPOTIFY: PUSH STATE SPOTIFY Apr 23 13:24:55 stromer volumio[972]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 23 13:24:55 stromer volumio[972]: info: CoreCommandRouter::servicePushState Apr 23 13:24:55 stromer volumio[972]: info: CoreStateMachine::pushState Apr 23 13:24:55 stromer volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 23 13:24:55 stromer volumio[972]: info: CoreCommandRouter::volumioPushState Apr 23 13:24:55 stromer volumio[972]: info: PeppySpectrum ---peppyspectrum status pause Apr 23 13:24:55 stromer volumio[972]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Apr 23 13:24:55 stromer sudo[3342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 23 13:24:55 stromer sudo[3342]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 13:24:55 stromer volumio[972]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 23 13:24:55 stromer systemd[1]: Stopping peppyspectrum Daemon... Apr 23 13:24:55 stromer systemd[1]: peppyspectrum.service: Main process exited, code=killed, status=15/TERM Apr 23 13:24:55 stromer systemd[1]: peppyspectrum.service: Succeeded. Apr 23 13:24:55 stromer systemd[1]: Stopped peppyspectrum Daemon. Apr 23 13:24:55 stromer sudo[3342]: pam_unix(sudo:session): session closed for user root Apr 23 13:24:55 stromer volumio[972]: info: peppyspectrum Daemon Stop Apr 23 13:24:55 stromer volumio[972]: info: Spotify Stop Apr 23 13:24:55 stromer volumio[972]: SPOTIFY: SPOTIFY STOP Apr 23 13:24:55 stromer volumio[972]: SPOTIFY: {"status":"pause","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","codec":"ogg","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":100,"dbVolume":null,"mute":true,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Apr 23 13:24:56 stromer go-librespot[1321]: time="2025-04-23T13:24:56+02:00" level=debug msg="handling resume player command from 9ebc2cb4c063143d46874c2a64dc857bc1b08e2a" Apr 23 13:24:56 stromer go-librespot[1321]: time="2025-04-23T13:24:56+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:0VCBoaKWRtAUAOHdBdqEQX" Apr 23 13:24:56 stromer go-librespot[1321]: time="2025-04-23T13:24:56+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Apr 23 13:24:56 stromer go-librespot[1321]: time="2025-04-23T13:24:56+02:00" level=debug msg="resume track at -200ms" Apr 23 13:24:56 stromer go-librespot[1321]: go-librespot: pcm_meter.c:1216: snd_pcm_scope_s16_get_channel_buffer: Assertion `s16->buf_areas' failed. Apr 23 13:24:56 stromer go-librespot[1321]: Aborted Apr 23 13:24:56 stromer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Apr 23 13:24:56 stromer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 23 13:24:56 stromer volumio[972]: info: Connection to go-librespot Websocket closed Apr 23 13:24:59 stromer volumio[972]: info: Initializing connection to go-librespot Websocket Apr 23 13:24:59 stromer volumio[972]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 23 13:24:59 stromer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 23 13:24:59 stromer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Apr 23 13:24:59 stromer systemd[1]: Stopped go-librespot Daemon. Apr 23 13:24:59 stromer systemd[1]: Started go-librespot Daemon. Apr 23 13:24:59 stromer go-librespot[3359]: go-librespot daemon starting... Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=info msg="running go-librespot 0.2.0" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=debug msg="app state loaded" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=info msg="zeroconf server listening on port 33493" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=debug msg="obtained new client token: AABbqDTjMtjhMh5G0QxZ92LqT6MwGSnQjQSUziqUJN+W4JR31riITIJTWTmhOcDXelrYsWIVLDXH0CP6XsAnEDIR622yW58qCj56n+DKZu4OFP0+RQpozzpSTAjUiDd5Twwct7aYIaOcMBLqf5m8+kG60Muvw+WM2HZmuU6pKmV+Bem7Ma9DDkkSOy3SROOh+7EzC2R/7QE017QdOCWCqp4A9Qdtx13/PD28CwfVz7pVXPNIUFnhU/KWzw==" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=debug msg="completed keyexchange" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=debug msg="completed challenge" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=info msg="authenticated AP as 1137572429" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=info msg="authenticated Login5 as 1137572429" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=debug msg="initializing zeroconf session, username: 1137572429" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=debug msg="dealer connection opened" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=trace msg="starting accesspoint recv loop" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=trace msg="starting dealer recv loop" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=trace msg="received accesspoint ping" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=debug msg="received connection id: YzIyZGNkOTItOWZkNy00MjA2LWE0YjctMGFjMWQzOTA2MGIzK2RlYWxlcit0Y3A6Ly8wYWNhNThkZC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArMEU5QzkxNUE4NzA4NzJDODc0ODQwOTk1RUM5NjExMDhERDY3Qzc2MTYyMzUwMjM5QTA3RUExQTcwMUIwM0ZCRA==" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=trace msg="received accesspoint pong ack" Apr 23 13:25:00 stromer go-librespot[3359]: time="2025-04-23T13:25:00+02:00" level=debug msg="put connect state because NEW_DEVICE" Apr 23 13:25:02 stromer go-librespot[3359]: time="2025-04-23T13:25:02+02:00" level=debug msg="handling transfer player command from 9ebc2cb4c063143d46874c2a64dc857bc1b08e2a" Apr 23 13:25:02 stromer go-librespot[3359]: time="2025-04-23T13:25:02+02:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1DZ06evO4xzt1q" Apr 23 13:25:02 stromer go-librespot[3359]: time="2025-04-23T13:25:02+02:00" level=trace msg="fetched new page 0 with 41 items (list: 41)" uri="spotify:playlist:37i9dQZF1DZ06evO4xzt1q" Apr 23 13:25:02 stromer go-librespot[3359]: time="2025-04-23T13:25:02+02:00" level=debug msg="loading track (paused: false, position: 5253ms)" uri="spotify:track:0VCBoaKWRtAUAOHdBdqEQX" Apr 23 13:25:02 stromer go-librespot[3359]: time="2025-04-23T13:25:02+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 23 13:25:02 stromer go-librespot[3359]: time="2025-04-23T13:25:02+02:00" level=trace msg="emitting websocket event: will_play" Apr 23 13:25:02 stromer go-librespot[3359]: time="2025-04-23T13:25:02+02:00" level=debug msg="selected format OGG_VORBIS_320 (6820347f940dd16f33546c81976c44216016fc86)" uri="spotify:track:0VCBoaKWRtAUAOHdBdqEQX" Apr 23 13:25:02 stromer go-librespot[3359]: time="2025-04-23T13:25:02+02:00" level=debug msg="requested aes key for file 6820347f940dd16f33546c81976c44216016fc86, gid: 0VCBoaKWRtAUAOHdBdqEQX" Apr 23 13:25:02 stromer go-librespot[3359]: time="2025-04-23T13:25:02+02:00" level=debug msg="fetched first chunk of 28, total size is 14608836 bytes" uri="spotify:track:0VCBoaKWRtAUAOHdBdqEQX" Apr 23 13:25:02 stromer go-librespot[3359]: time="2025-04-23T13:25:02+02:00" level=trace msg="seek to 5253ms (diff: 36ms, samples: 231657, bytes: 174179)" uri="spotify:track:0VCBoaKWRtAUAOHdBdqEQX" Apr 23 13:25:02 stromer go-librespot[3359]: time="2025-04-23T13:25:02+02:00" level=debug msg="created new output device" Apr 23 13:25:02 stromer go-librespot[3359]: time="2025-04-23T13:25:02+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Apr 23 13:25:02 stromer go-librespot[3359]: time="2025-04-23T13:25:02+02:00" level=info msg="loaded track \"Desire - 1997 Remaster\" (paused: false, position: 5253ms, duration: 417066ms, prefetched: false)" uri="spotify:track:0VCBoaKWRtAUAOHdBdqEQX" Apr 23 13:25:02 stromer go-librespot[3359]: go-librespot: pcm_meter.c:1216: snd_pcm_scope_s16_get_channel_buffer: Assertion `s16->buf_areas' failed. Apr 23 13:25:02 stromer go-librespot[3359]: Aborted Apr 23 13:25:02 stromer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Apr 23 13:25:02 stromer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 23 13:25:02 stromer volumio[972]: info: Initializing connection to go-librespot Websocket Apr 23 13:25:02 stromer volumio[972]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 23 13:25:05 stromer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 23 13:25:05 stromer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Apr 23 13:25:05 stromer systemd[1]: Stopped go-librespot Daemon. Apr 23 13:25:05 stromer systemd[1]: Started go-librespot Daemon. Apr 23 13:25:05 stromer go-librespot[3395]: go-librespot daemon starting... Apr 23 13:25:05 stromer volumio[972]: info: Initializing connection to go-librespot Websocket Apr 23 13:25:05 stromer volumio[972]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 23 13:25:05 stromer go-librespot[3395]: time="2025-04-23T13:25:05+02:00" level=info msg="running go-librespot 0.2.0" Apr 23 13:25:05 stromer go-librespot[3395]: time="2025-04-23T13:25:05+02:00" level=debug msg="app state loaded" Apr 23 13:25:05 stromer go-librespot[3395]: time="2025-04-23T13:25:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 23 13:25:05 stromer go-librespot[3395]: time="2025-04-23T13:25:05+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 23 13:25:05 stromer go-librespot[3395]: time="2025-04-23T13:25:05+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 23 13:25:05 stromer go-librespot[3395]: time="2025-04-23T13:25:05+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 23 13:25:05 stromer go-librespot[3395]: time="2025-04-23T13:25:05+02:00" level=info msg="zeroconf server listening on port 35447" Apr 23 13:25:05 stromer go-librespot[3395]: time="2025-04-23T13:25:05+02:00" level=debug msg="obtained new client token: AABBh/jFDCjiC1aTx9NInDImuZOjtfa0oaXaA9sXSF082J5BvGRKO3BUpUyRaeOsqKgg8XUnMPkeF6QGfQlVSUbsgV7dDimMfvkbasaaIwBghyhlDeYwZVhKweqgBwq+ITfDOKfnYO6dgtp/8YWgUH6pCF7IS7R0C9Ld7jYWj+Jn1bXXDmOnZJcO92+1E+W1Q9SEhloeGtg+bmU4v1+qO06SP/YN6u+Gtd6CV0a7tSJnOWBXVRHzSCVh5g==" Apr 23 13:25:06 stromer go-librespot[3395]: time="2025-04-23T13:25:06+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Apr 23 13:25:06 stromer go-librespot[3395]: time="2025-04-23T13:25:06+02:00" level=debug msg="completed keyexchange" Apr 23 13:25:06 stromer go-librespot[3395]: time="2025-04-23T13:25:06+02:00" level=debug msg="completed challenge" Apr 23 13:25:06 stromer go-librespot[3395]: time="2025-04-23T13:25:06+02:00" level=info msg="authenticated AP as 1137572429" Apr 23 13:25:06 stromer go-librespot[3395]: time="2025-04-23T13:25:06+02:00" level=info msg="authenticated Login5 as 1137572429" Apr 23 13:25:06 stromer go-librespot[3395]: time="2025-04-23T13:25:06+02:00" level=debug msg="initializing zeroconf session, username: 1137572429" Apr 23 13:25:06 stromer go-librespot[3395]: time="2025-04-23T13:25:06+02:00" level=debug msg="dealer connection opened" Apr 23 13:25:06 stromer go-librespot[3395]: time="2025-04-23T13:25:06+02:00" level=trace msg="starting accesspoint recv loop" Apr 23 13:25:06 stromer go-librespot[3395]: time="2025-04-23T13:25:06+02:00" level=trace msg="starting dealer recv loop" Apr 23 13:25:06 stromer go-librespot[3395]: time="2025-04-23T13:25:06+02:00" level=trace msg="received accesspoint ping" Apr 23 13:25:06 stromer go-librespot[3395]: time="2025-04-23T13:25:06+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 23 13:25:06 stromer go-librespot[3395]: time="2025-04-23T13:25:06+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 23 13:25:06 stromer go-librespot[3395]: time="2025-04-23T13:25:06+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Apr 23 13:25:06 stromer go-librespot[3395]: time="2025-04-23T13:25:06+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Apr 23 13:25:06 stromer go-librespot[3395]: time="2025-04-23T13:25:06+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Apr 23 13:25:06 stromer go-librespot[3395]: time="2025-04-23T13:25:06+02:00" level=debug msg="received connection id: NzY2ZDlhMWYtMWE0My00ODk3LThmZTctNDliZjA1MzIxOWQ5K2RlYWxlcit0Y3A6Ly8wYWNhNTg0NC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArNTIwMDkwRDlGOUIwMTRDOEU3RkFGRTEwNTg1ODEzMUNGMDcwNTdFMDQwMDhGOThDOURFMTUwQzM5RDY4MEZGNw==" Apr 23 13:25:06 stromer go-librespot[3395]: time="2025-04-23T13:25:06+02:00" level=trace msg="received accesspoint pong ack" Apr 23 13:25:06 stromer go-librespot[3395]: time="2025-04-23T13:25:06+02:00" level=debug msg="put connect state because NEW_DEVICE" Apr 23 13:25:06 stromer go-librespot[3395]: time="2025-04-23T13:25:06+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Apr 23 13:25:06 stromer go-librespot[3395]: time="2025-04-23T13:25:06+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 338" Apr 23 13:25:07 stromer volumio[972]: info: [1745407507039] [80s80s] Pushing the next song state Bronski Beat - Hit That Perfect Beat and getting next track. Apr 23 13:25:07 stromer volumio[972]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 23 13:25:07 stromer volumio[972]: TypeError: Cannot set property 'name' of undefined Apr 23 13:25:07 stromer volumio[972]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) Apr 23 13:25:07 stromer volumio[972]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Apr 23 13:25:07 stromer volumio[972]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Apr 23 13:25:07 stromer volumio[972]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Apr 23 13:25:07 stromer volumio[972]: at processImmediate (internal/timers.js:461:21) Apr 23 13:25:07 stromer volumio[972]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 23 13:25:07 stromer sudo[3455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-23 13:24 Apr 23 13:25:07 stromer sudo[3455]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"