-- Logs begin at Thu 2024-04-11 19:43:36 CEST, end at Thu 2024-04-11 19:51:01 CEST. -- Apr 11 19:50:02 volumio volumio[1913]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Apr 11 19:50:07 volumio volumio[1913]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Apr 11 19:50:09 volumio kernel: hwmon hwmon1: Undervoltage detected! Apr 11 19:50:10 volumio volumio[1913]: info: CALLMETHOD: audio_interface fusiondsp installtools Apr 11 19:50:10 volumio volumio[1913]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , installtools Apr 11 19:50:13 volumio kernel: hwmon hwmon1: Voltage normalised Apr 11 19:50:14 volumio volumio[1913]: --2024-04-11 19:50:10-- https://github.com/balbuze/volumio-plugins/raw/alsa_modular/plugins/audio_interface/FusionDsp/tools/tools.tar.xz Apr 11 19:50:14 volumio volumio[1913]: Resolving github.com (github.com)... 140.82.121.4 Apr 11 19:50:14 volumio volumio[1913]: Connecting to github.com (github.com)|140.82.121.4|:443... connected. Apr 11 19:50:14 volumio volumio[1913]: HTTP request sent, awaiting response... 302 Found Apr 11 19:50:14 volumio volumio[1913]: Location: https://raw.githubusercontent.com/balbuze/volumio-plugins/alsa_modular/plugins/audio_interface/FusionDsp/tools/tools.tar.xz [following] Apr 11 19:50:14 volumio volumio[1913]: --2024-04-11 19:50:10-- https://raw.githubusercontent.com/balbuze/volumio-plugins/alsa_modular/plugins/audio_interface/FusionDsp/tools/tools.tar.xz Apr 11 19:50:14 volumio volumio[1913]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.111.133, 185.199.108.133, 185.199.110.133, ... Apr 11 19:50:14 volumio volumio[1913]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.111.133|:443... connected. Apr 11 19:50:14 volumio volumio[1913]: HTTP request sent, awaiting response... 200 OK Apr 11 19:50:14 volumio volumio[1913]: Length: 23035700 (22M) [application/octet-stream] Apr 11 19:50:14 volumio volumio[1913]: Saving to: ‘/tmp/tools.tar.xz’ Apr 11 19:50:14 volumio volumio[1913]: 0K .......... .......... .......... .......... .......... 0% 1.54M 14s Apr 11 19:50:14 volumio volumio[1913]: 50K .......... .......... .......... .......... .......... 0% 2.12M 12s Apr 11 19:50:14 volumio volumio[1913]: 100K .......... .......... .......... .......... .......... 0% 9.55M 9s Apr 11 19:50:14 volumio volumio[1913]: 150K .......... .......... .......... .......... .......... 0% 7.14M 7s Apr 11 19:50:14 volumio volumio[1913]: 200K .......... .......... .......... .......... .......... 1% 3.92M 7s Apr 11 19:50:14 volumio volumio[1913]: 250K .......... .......... .......... .......... .......... 1% 11.1M 6s Apr 11 19:50:14 volumio volumio[1913]: 300K .......... .......... .......... .......... .......... 1% 12.0M 6s Apr 11 19:50:14 volumio volumio[1913]: 350K .......... .......... .......... .......... .......... 1% 10.3M 5s Apr 11 19:50:14 volumio volumio[1913]: 400K .......... .......... .......... .......... .......... 2% 16.2M 5s Apr 11 19:50:14 volumio volumio[1913]: 450K .......... .......... .......... .......... .......... 2% 6.26M 5s Apr 11 19:50:14 volumio volumio[1913]: 500K .......... .......... .......... .......... .......... 2% 16.2M 4s Apr 11 19:50:14 volumio volumio[1913]: 550K .......... .......... .......... .......... .......... 2% 13.4M 4s Apr 11 19:50:14 volumio volumio[1913]: 600K .......... .......... .......... .......... .......... 2% 13.0M 4s Apr 11 19:50:14 volumio volumio[1913]: 650K .......... .......... .......... .......... .......... 3% 12.1M 4s Apr 11 19:50:14 volumio volumio[1913]: 700K .......... .......... .......... .......... .......... 3% 16.0M 4s Apr 11 19:50:14 volumio volumio[1913]: 750K .......... .......... .......... .......... .......... 3% 10.7M 3s Apr 11 19:50:14 volumio volumio[1913]: 800K .......... .......... .......... .......... .......... 3% 13.7M 3s Apr 11 19:50:14 volumio volumio[1913]: 850K .......... .......... .......... .......... .......... 4% 13.0M 3s Apr 11 19:50:14 volumio volumio[1913]: 900K .......... .......... .......... .......... .......... 4% 16.0M 3s Apr 11 19:50:14 volumio volumio[1913]: 950K .......... .......... .......... .......... .......... 4% 13.4M 3s Apr 11 19:50:14 volumio volumio[1913]: 1000K .......... .......... .......... .......... .......... 4% 12.6M 3s Apr 11 19:50:14 volumio volumio[1913]: 1050K .......... .......... .......... .......... .......... 4% 15.9M 3s Apr 11 19:50:14 volumio volumio[1913]: 1100K .......... .......... .......... .......... .......... 5% 15.8M 3s Apr 11 19:50:14 volumio volumio[1913]: 1150K .......... .......... .......... .......... .......... 5% 10.1M 3s Apr 11 19:50:14 volumio volumio[1913]: 1200K .......... .......... .......... .......... .......... 5% 13.2M 3s Apr 11 19:50:14 volumio volumio[1913]: 1250K .......... .......... .......... .......... .......... 5% 16.3M 3s Apr 11 19:50:14 volumio volumio[1913]: 1300K .......... .......... .......... .......... .......... 6% 16.3M 3s Apr 11 19:50:14 volumio volumio[1913]: 1350K .......... .......... .......... .......... .......... 6% 11.7M 3s Apr 11 19:50:14 volumio volumio[1913]: 1400K .......... .......... .......... .......... .......... 6% 17.3M 2s Apr 11 19:50:14 volumio volumio[1913]: 1450K .......... .......... .......... .......... .......... 6% 9.48M 2s Apr 11 19:50:14 volumio volumio[1913]: 1500K .......... .......... .......... .......... .......... 6% 2.40M 3s Apr 11 19:50:14 volumio volumio[1913]: 1550K .......... .......... .......... .......... .......... 7% 10.9M 3s Apr 11 19:50:14 volumio volumio[1913]: 1600K .......... .......... .......... .......... .......... 7% 15.6M 3s Apr 11 19:50:14 volumio volumio[1913]: 1650K .......... .......... .......... .......... .......... 7% 14.1M 3s Apr 11 19:50:14 volumio volumio[1913]: 1700K .......... .......... .......... .......... .......... 7% 12.6M 3s Apr 11 19:50:14 volumio volumio[1913]: 1750K .......... .......... .......... .......... .......... 8% 15.4M 2s Apr 11 19:50:14 volumio volumio[1913]: 1800K .......... .......... .......... .......... .......... 8% 13.9M 2s Apr 11 19:50:14 volumio volumio[1913]: 1850K .......... .......... .......... .......... .......... 8% 13.3M 2s Apr 11 19:50:14 volumio volumio[1913]: 1900K .......... .......... .......... .......... .......... 8% 16.5M 2s Apr 11 19:50:14 volumio volumio[1913]: 1950K .......... .......... .......... .......... .......... 8% 11.2M 2s Apr 11 19:50:14 volumio volumio[1913]: 2000K .......... .......... .......... .......... .......... 9% 14.0M 2s Apr 11 19:50:14 volumio volumio[1913]: 2050K .......... .......... .......... .......... .......... 9% 13.1M 2s Apr 11 19:50:14 volumio volumio[1913]: 2100K .......... .......... .......... .......... .......... 9% 16.1M 2s Apr 11 19:50:14 volumio volumio[1913]: 2150K .......... .......... .......... .......... .......... 9% 16.3M 2s Apr 11 19:50:14 volumio volumio[1913]: 2200K .......... .......... .......... .......... .......... 10% 11.5M 2s Apr 11 19:50:14 volumio volumio[1913]: 2250K .......... .......... .......... .......... .......... 10% 16.4M 2s Apr 11 19:50:14 volumio volumio[1913]: 2300K .......... .......... .......... .......... .......... 10% 16.1M 2s Apr 11 19:50:14 volumio volumio[1913]: 2350K .......... .......... .......... .......... .......... 10% 10.1M 2s Apr 11 19:50:14 volumio volumio[1913]: 2400K .......... .......... .......... .......... .......... 10% 13.2M 2s Apr 11 19:50:14 volumio volumio[1913]: 2450K .......... .......... .......... .......... .......... 11% 16.4M 2s Apr 11 19:50:14 volumio volumio[1913]: 2500K .......... .......... .......... .......... .......... 11% 16.1M 2s Apr 11 19:50:14 volumio volumio[1913]: 2550K .......... .......... .......... .......... .......... 11% 11.4M 2s Apr 11 19:50:14 volumio volumio[1913]: 2600K .......... .......... .......... .......... .......... 11% 16.4M 2s Apr 11 19:50:14 volumio volumio[1913]: 2650K .......... .......... .......... .......... .......... 12% 16.5M 2s Apr 11 19:50:14 volumio volumio[1913]: 2700K .......... .......... .......... .......... .......... 12% 13.7M 2s Apr 11 19:50:14 volumio volumio[1913]: 2750K .......... .......... .......... .......... .......... 12% 9.65M 2s Apr 11 19:50:14 volumio volumio[1913]: 2800K .......... .......... .......... .......... .......... 12% 16.3M 2s Apr 11 19:50:14 volumio volumio[1913]: 2850K .......... .......... .......... .......... .......... 12% 12.5M 2s Apr 11 19:50:14 volumio volumio[1913]: 2900K .......... .......... .......... .......... .......... 13% 16.1M 2s Apr 11 19:50:14 volumio volumio[1913]: 2950K .......... .......... .......... .......... .......... 13% 17.1M 2s Apr 11 19:50:14 volumio volumio[1913]: 3000K .......... .......... .......... .......... .......... 13% 2.64M 2s Apr 11 19:50:14 volumio volumio[1913]: 3050K .......... .......... .......... .......... .......... 13% 15.8M 2s Apr 11 19:50:14 volumio volumio[1913]: 3100K .......... .......... .......... .......... .......... 14% 12.9M 2s Apr 11 19:50:14 volumio volumio[1913]: 3150K .......... .......... .......... .......... .......... 14% 9.95M 2s Apr 11 19:50:14 volumio volumio[1913]: 3200K .......... .......... .......... .......... .......... 14% 10.2M 2s Apr 11 19:50:14 volumio volumio[1913]: 3250K .......... .......... .......... .......... .......... 14% 15.2M 2s Apr 11 19:50:14 volumio volumio[1913]: 3300K .......... .......... .......... .......... .......... 14% 14.3M 2s Apr 11 19:50:14 volumio volumio[1913]: 3350K .......... .......... .......... .......... .......... 15% 12.3M 2s Apr 11 19:50:14 volumio volumio[1913]: 3400K .......... .......... .......... .......... .......... 15% 12.6M 2s Apr 11 19:50:14 volumio volumio[1913]: 3450K .......... .......... .......... .......... .......... 15% 15.6M 2s Apr 11 19:50:14 volumio volumio[1913]: 3500K .......... .......... .......... .......... .......... 15% 13.6M 2s Apr 11 19:50:14 volumio volumio[1913]: 3550K .......... .......... .......... .......... .......... 16% 10.6M 2s Apr 11 19:50:14 volumio volumio[1913]: 3600K .......... .......... .......... .......... .......... 16% 12.6M 2s Apr 11 19:50:14 volumio volumio[1913]: 3650K .......... .......... .......... .......... .......... 16% 15.7M 2s Apr 11 19:50:14 volumio volumio[1913]: 3700K .......... .......... .......... .......... .......... 16% 13.6M 2s Apr 11 19:50:14 volumio volumio[1913]: 3750K .......... .......... .......... .......... .......... 16% 11.9M 2s Apr 11 19:50:14 volumio volumio[1913]: 3800K .......... .......... .......... .......... .......... 17% 15.9M 2s Apr 11 19:50:14 volumio volumio[1913]: 3850K .......... .......... .......... .......... .......... 17% 13.5M 2s Apr 11 19:50:14 volumio volumio[1913]: 3900K .......... .......... .......... .......... .......... 17% 12.2M 2s Apr 11 19:50:14 volumio volumio[1913]: 3950K .......... .......... .......... .......... .......... 17% 10.8M 2s Apr 11 19:50:14 volumio volumio[1913]: 4000K .......... .......... .......... .......... .......... 18% 15.9M 2s Apr 11 19:50:14 volumio volumio[1913]: 4050K .......... .......... .......... .......... .......... 18% 13.5M 2s Apr 11 19:50:14 volumio volumio[1913]: 4100K .......... .......... .......... .......... .......... 18% 12.2M 2s Apr 11 19:50:14 volumio volumio[1913]: 4150K .......... .......... .......... .......... .......... 18% 15.6M 2s Apr 11 19:50:14 volumio volumio[1913]: 4200K .......... .......... .......... .......... .......... 18% 13.5M 2s Apr 11 19:50:14 volumio volumio[1913]: 4250K .......... .......... .......... .......... .......... 19% 12.3M 2s Apr 11 19:50:14 volumio volumio[1913]: 4300K .......... .......... .......... .......... .......... 19% 15.7M 2s Apr 11 19:50:14 volumio volumio[1913]: 4350K .......... .......... .......... .......... .......... 19% 10.7M 2s Apr 11 19:50:14 volumio volumio[1913]: 4400K .......... .......... .......... .......... .......... 19% 12.4M 2s Apr 11 19:50:14 volumio volumio[1913]: 4450K .......... .......... .......... .......... .......... 20% 15.7M 2s Apr 11 19:50:14 volumio volumio[1913]: 4500K .......... .......... .......... .......... .......... 20% 12.7M 2s Apr 11 19:50:14 volumio volumio[1913]: 4550K .......... .......... .......... .......... .......... 20% 13.1M 2s Apr 11 19:50:14 volumio volumio[1913]: 4600K .......... .......... .......... .......... .......... 20% 19.9M 2s Apr 11 19:50:14 volumio volumio[1913]: 4650K .......... .......... .......... .......... .......... 20% 3.02M 2s Apr 11 19:50:14 volumio volumio[1913]: 4700K .......... .......... .......... .......... .......... 21% 4.90M 2s Apr 11 19:50:14 volumio volumio[1913]: 4750K .......... .......... .......... .......... .......... 21% 10.4M 2s Apr 11 19:50:14 volumio volumio[1913]: 4800K .......... .......... .......... .......... .......... 21% 15.0M 2s Apr 11 19:50:14 volumio volumio[1913]: 4850K .......... .......... .......... .......... .......... 21% 12.8M 2s Apr 11 19:50:14 volumio volumio[1913]: 4900K .......... .......... .......... .......... .......... 22% 11.8M 2s Apr 11 19:50:14 volumio volumio[1913]: 4950K .......... .......... .......... .......... .......... 22% 15.6M 2s Apr 11 19:50:14 volumio volumio[1913]: 5000K .......... .......... .......... .......... .......... 22% 12.8M 2s Apr 11 19:50:14 volumio volumio[1913]: 5050K .......... .......... .......... .......... .......... 22% 12.6M 2s Apr 11 19:50:14 volumio volumio[1913]: 5100K .......... .......... .......... .......... .......... 22% 15.7M 2s Apr 11 19:50:14 volumio volumio[1913]: 5150K .......... .......... .......... .......... .......... 23% 11.5M 2s Apr 11 19:50:14 volumio volumio[1913]: 5200K .......... .......... .......... .......... .......... 23% 11.7M 2s Apr 11 19:50:14 volumio volumio[1913]: 5250K .......... .......... .......... .......... .......... 23% 15.1M 2s Apr 11 19:50:14 volumio volumio[1913]: 5300K .......... .......... .......... .......... .......... 23% 13.7M 2s Apr 11 19:50:14 volumio volumio[1913]: 5350K .......... .......... .......... .......... .......... 24% 13.5M 2s Apr 11 19:50:14 volumio volumio[1913]: 5400K .......... .......... .......... .......... .......... 24% 11.3M 2s Apr 11 19:50:14 volumio volumio[1913]: 5450K .......... .......... .......... .......... .......... 24% 15.8M 2s Apr 11 19:50:14 volumio volumio[1913]: 5500K .......... .......... .......... .......... .......... 24% 13.4M 2s Apr 11 19:50:14 volumio volumio[1913]: 5550K .......... .......... .......... .......... .......... 24% 10.7M 2s Apr 11 19:50:14 volumio volumio[1913]: 5600K .......... .......... .......... .......... .......... 25% 13.6M 2s Apr 11 19:50:14 volumio volumio[1913]: 5650K .......... .......... .......... .......... .......... 25% 11.9M 2s Apr 11 19:50:14 volumio volumio[1913]: 5700K .......... .......... .......... .......... .......... 25% 15.8M 2s Apr 11 19:50:14 volumio volumio[1913]: 5750K .......... .......... .......... .......... .......... 25% 13.5M 2s Apr 11 19:50:14 volumio volumio[1913]: 5800K .......... .......... .......... .......... .......... 26% 13.6M 2s Apr 11 19:50:14 volumio volumio[1913]: 5850K .......... .......... .......... .......... .......... 26% 11.9M 2s Apr 11 19:50:14 volumio volumio[1913]: 5900K .......... .......... .......... .......... .......... 26% 15.7M 2s Apr 11 19:50:14 volumio volumio[1913]: 5950K .......... .......... .......... .......... .......... 26% 10.8M 2s Apr 11 19:50:14 volumio volumio[1913]: 6000K .......... .......... .......... .......... .......... 26% 11.7M 2s Apr 11 19:50:14 volumio volumio[1913]: 6050K .......... .......... .......... .......... .......... 27% 14.4M 2s Apr 11 19:50:14 volumio volumio[1913]: 6100K .......... .......... .......... .......... .......... 27% 14.3M 2s Apr 11 19:50:14 volumio volumio[1913]: 6150K .......... .......... .......... .......... .......... 27% 13.4M 1s Apr 11 19:50:14 volumio volumio[1913]: 6200K .......... .......... .......... .......... .......... 27% 13.3M 1s Apr 11 19:50:14 volumio volumio[1913]: 6250K .......... .......... .......... .......... .......... 28% 15.2M 1s Apr 11 19:50:14 volumio volumio[1913]: 6300K .......... .......... .......... .......... .......... 28% 12.6M 1s Apr 11 19:50:14 volumio volumio[1913]: 6350K .......... .......... .......... .......... .......... 28% 10.5M 1s Apr 11 19:50:14 volumio volumio[1913]: 6400K .......... .......... .......... .......... .......... 28% 13.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 6450K .......... .......... .......... .......... .......... 28% 12.4M 1s Apr 11 19:50:14 volumio volumio[1913]: 6500K .......... .......... .......... .......... .......... 29% 15.9M 1s Apr 11 19:50:14 volumio volumio[1913]: 6550K .......... .......... .......... .......... .......... 29% 13.3M 1s Apr 11 19:50:14 volumio volumio[1913]: 6600K .......... .......... .......... .......... .......... 29% 12.4M 1s Apr 11 19:50:14 volumio volumio[1913]: 6650K .......... .......... .......... .......... .......... 29% 15.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 6700K .......... .......... .......... .......... .......... 30% 13.6M 1s Apr 11 19:50:14 volumio volumio[1913]: 6750K .......... .......... .......... .......... .......... 30% 10.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 6800K .......... .......... .......... .......... .......... 30% 12.4M 1s Apr 11 19:50:14 volumio volumio[1913]: 6850K .......... .......... .......... .......... .......... 30% 16.0M 1s Apr 11 19:50:14 volumio volumio[1913]: 6900K .......... .......... .......... .......... .......... 30% 13.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 6950K .......... .......... .......... .......... .......... 31% 14.4M 1s Apr 11 19:50:14 volumio volumio[1913]: 7000K .......... .......... .......... .......... .......... 31% 12.5M 1s Apr 11 19:50:14 volumio volumio[1913]: 7050K .......... .......... .......... .......... .......... 31% 3.31M 1s Apr 11 19:50:14 volumio volumio[1913]: 7100K .......... .......... .......... .......... .......... 31% 4.34M 1s Apr 11 19:50:14 volumio volumio[1913]: 7150K .......... .......... .......... .......... .......... 32% 8.24M 1s Apr 11 19:50:14 volumio volumio[1913]: 7200K .......... .......... .......... .......... .......... 32% 10.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 7250K .......... .......... .......... .......... .......... 32% 13.6M 1s Apr 11 19:50:14 volumio volumio[1913]: 7300K .......... .......... .......... .......... .......... 32% 12.1M 1s Apr 11 19:50:14 volumio volumio[1913]: 7350K .......... .......... .......... .......... .......... 32% 14.4M 1s Apr 11 19:50:14 volumio volumio[1913]: 7400K .......... .......... .......... .......... .......... 33% 12.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 7450K .......... .......... .......... .......... .......... 33% 12.3M 1s Apr 11 19:50:14 volumio volumio[1913]: 7500K .......... .......... .......... .......... .......... 33% 15.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 7550K .......... .......... .......... .......... .......... 33% 10.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 7600K .......... .......... .......... .......... .......... 34% 13.3M 1s Apr 11 19:50:14 volumio volumio[1913]: 7650K .......... .......... .......... .......... .......... 34% 12.4M 1s Apr 11 19:50:14 volumio volumio[1913]: 7700K .......... .......... .......... .......... .......... 34% 15.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 7750K .......... .......... .......... .......... .......... 34% 13.5M 1s Apr 11 19:50:14 volumio volumio[1913]: 7800K .......... .......... .......... .......... .......... 34% 12.3M 1s Apr 11 19:50:14 volumio volumio[1913]: 7850K .......... .......... .......... .......... .......... 35% 15.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 7900K .......... .......... .......... .......... .......... 35% 13.6M 1s Apr 11 19:50:14 volumio volumio[1913]: 7950K .......... .......... .......... .......... .......... 35% 10.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 8000K .......... .......... .......... .......... .......... 35% 14.5M 1s Apr 11 19:50:14 volumio volumio[1913]: 8050K .......... .......... .......... .......... .......... 36% 12.9M 1s Apr 11 19:50:14 volumio volumio[1913]: 8100K .......... .......... .......... .......... .......... 36% 11.6M 1s Apr 11 19:50:14 volumio volumio[1913]: 8150K .......... .......... .......... .......... .......... 36% 14.1M 1s Apr 11 19:50:14 volumio volumio[1913]: 8200K .......... .......... .......... .......... .......... 36% 13.1M 1s Apr 11 19:50:14 volumio volumio[1913]: 8250K .......... .......... .......... .......... .......... 36% 12.2M 1s Apr 11 19:50:14 volumio volumio[1913]: 8300K .......... .......... .......... .......... .......... 37% 17.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 8350K .......... .......... .......... .......... .......... 37% 11.4M 1s Apr 11 19:50:14 volumio volumio[1913]: 8400K .......... .......... .......... .......... .......... 37% 13.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 8450K .......... .......... .......... .......... .......... 37% 13.2M 1s Apr 11 19:50:14 volumio volumio[1913]: 8500K .......... .......... .......... .......... .......... 38% 16.3M 1s Apr 11 19:50:14 volumio volumio[1913]: 8550K .......... .......... .......... .......... .......... 38% 16.1M 1s Apr 11 19:50:14 volumio volumio[1913]: 8600K .......... .......... .......... .......... .......... 38% 11.3M 1s Apr 11 19:50:14 volumio volumio[1913]: 8650K .......... .......... .......... .......... .......... 38% 16.3M 1s Apr 11 19:50:14 volumio volumio[1913]: 8700K .......... .......... .......... .......... .......... 38% 15.1M 1s Apr 11 19:50:14 volumio volumio[1913]: 8750K .......... .......... .......... .......... .......... 39% 10.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 8800K .......... .......... .......... .......... .......... 39% 12.6M 1s Apr 11 19:50:14 volumio volumio[1913]: 8850K .......... .......... .......... .......... .......... 39% 15.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 8900K .......... .......... .......... .......... .......... 39% 14.0M 1s Apr 11 19:50:14 volumio volumio[1913]: 8950K .......... .......... .......... .......... .......... 40% 12.9M 1s Apr 11 19:50:14 volumio volumio[1913]: 9000K .......... .......... .......... .......... .......... 40% 13.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 9050K .......... .......... .......... .......... .......... 40% 15.4M 1s Apr 11 19:50:14 volumio volumio[1913]: 9100K .......... .......... .......... .......... .......... 40% 6.95M 1s Apr 11 19:50:14 volumio volumio[1913]: 9150K .......... .......... .......... .......... .......... 40% 10.5M 1s Apr 11 19:50:14 volumio volumio[1913]: 9200K .......... .......... .......... .......... .......... 41% 12.2M 1s Apr 11 19:50:14 volumio volumio[1913]: 9250K .......... .......... .......... .......... .......... 41% 16.4M 1s Apr 11 19:50:14 volumio volumio[1913]: 9300K .......... .......... .......... .......... .......... 41% 11.6M 1s Apr 11 19:50:14 volumio volumio[1913]: 9350K .......... .......... .......... .......... .......... 41% 11.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 9400K .......... .......... .......... .......... .......... 42% 12.0M 1s Apr 11 19:50:14 volumio volumio[1913]: 9450K .......... .......... .......... .......... .......... 42% 14.4M 1s Apr 11 19:50:14 volumio volumio[1913]: 9500K .......... .......... .......... .......... .......... 42% 15.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 9550K .......... .......... .......... .......... .......... 42% 9.27M 1s Apr 11 19:50:14 volumio volumio[1913]: 9600K .......... .......... .......... .......... .......... 42% 11.9M 1s Apr 11 19:50:14 volumio volumio[1913]: 9650K .......... .......... .......... .......... .......... 43% 15.1M 1s Apr 11 19:50:14 volumio volumio[1913]: 9700K .......... .......... .......... .......... .......... 43% 14.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 9750K .......... .......... .......... .......... .......... 43% 10.5M 1s Apr 11 19:50:14 volumio volumio[1913]: 9800K .......... .......... .......... .......... .......... 43% 16.0M 1s Apr 11 19:50:14 volumio volumio[1913]: 9850K .......... .......... .......... .......... .......... 44% 15.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 9900K .......... .......... .......... .......... .......... 44% 14.0M 1s Apr 11 19:50:14 volumio volumio[1913]: 9950K .......... .......... .......... .......... .......... 44% 9.65M 1s Apr 11 19:50:14 volumio volumio[1913]: 10000K .......... .......... .......... .......... .......... 44% 16.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 10050K .......... .......... .......... .......... .......... 44% 7.03M 1s Apr 11 19:50:14 volumio volumio[1913]: 10100K .......... .......... .......... .......... .......... 45% 14.9M 1s Apr 11 19:50:14 volumio volumio[1913]: 10150K .......... .......... .......... .......... .......... 45% 12.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 10200K .......... .......... .......... .......... .......... 45% 12.0M 1s Apr 11 19:50:14 volumio volumio[1913]: 10250K .......... .......... .......... .......... .......... 45% 15.9M 1s Apr 11 19:50:14 volumio volumio[1913]: 10300K .......... .......... .......... .......... .......... 46% 15.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 10350K .......... .......... .......... .......... .......... 46% 9.50M 1s Apr 11 19:50:14 volumio volumio[1913]: 10400K .......... .......... .......... .......... .......... 46% 14.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 10450K .......... .......... .......... .......... .......... 46% 11.0M 1s Apr 11 19:50:14 volumio volumio[1913]: 10500K .......... .......... .......... .......... .......... 46% 15.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 10550K .......... .......... .......... .......... .......... 47% 15.6M 1s Apr 11 19:50:14 volumio volumio[1913]: 10600K .......... .......... .......... .......... .......... 47% 10.9M 1s Apr 11 19:50:14 volumio volumio[1913]: 10650K .......... .......... .......... .......... .......... 47% 15.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 10700K .......... .......... .......... .......... .......... 47% 15.9M 1s Apr 11 19:50:14 volumio volumio[1913]: 10750K .......... .......... .......... .......... .......... 48% 10.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 10800K .......... .......... .......... .......... .......... 48% 10.9M 1s Apr 11 19:50:14 volumio volumio[1913]: 10850K .......... .......... .......... .......... .......... 48% 15.6M 1s Apr 11 19:50:14 volumio volumio[1913]: 10900K .......... .......... .......... .......... .......... 48% 15.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 10950K .......... .......... .......... .......... .......... 48% 13.4M 1s Apr 11 19:50:14 volumio volumio[1913]: 11000K .......... .......... .......... .......... .......... 49% 12.5M 1s Apr 11 19:50:14 volumio volumio[1913]: 11050K .......... .......... .......... .......... .......... 49% 15.6M 1s Apr 11 19:50:14 volumio volumio[1913]: 11100K .......... .......... .......... .......... .......... 49% 12.5M 1s Apr 11 19:50:14 volumio volumio[1913]: 11150K .......... .......... .......... .......... .......... 49% 10.1M 1s Apr 11 19:50:14 volumio volumio[1913]: 11200K .......... .......... .......... .......... .......... 50% 16.4M 1s Apr 11 19:50:14 volumio volumio[1913]: 11250K .......... .......... .......... .......... .......... 50% 11.1M 1s Apr 11 19:50:14 volumio volumio[1913]: 11300K .......... .......... .......... .......... .......... 50% 18.0M 1s Apr 11 19:50:14 volumio volumio[1913]: 11350K .......... .......... .......... .......... .......... 50% 10.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 11400K .......... .......... .......... .......... .......... 50% 15.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 11450K .......... .......... .......... .......... .......... 51% 15.6M 1s Apr 11 19:50:14 volumio volumio[1913]: 11500K .......... .......... .......... .......... .......... 51% 13.6M 1s Apr 11 19:50:14 volumio volumio[1913]: 11550K .......... .......... .......... .......... .......... 51% 9.02M 1s Apr 11 19:50:14 volumio volumio[1913]: 11600K .......... .......... .......... .......... .......... 51% 15.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 11650K .......... .......... .......... .......... .......... 52% 15.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 11700K .......... .......... .......... .......... .......... 52% 13.5M 1s Apr 11 19:50:14 volumio volumio[1913]: 11750K .......... .......... .......... .......... .......... 52% 12.0M 1s Apr 11 19:50:14 volumio volumio[1913]: 11800K .......... .......... .......... .......... .......... 52% 15.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 11850K .......... .......... .......... .......... .......... 52% 13.6M 1s Apr 11 19:50:14 volumio volumio[1913]: 11900K .......... .......... .......... .......... .......... 53% 12.9M 1s Apr 11 19:50:14 volumio volumio[1913]: 11950K .......... .......... .......... .......... .......... 53% 3.23M 1s Apr 11 19:50:14 volumio volumio[1913]: 12000K .......... .......... .......... .......... .......... 53% 3.52M 1s Apr 11 19:50:14 volumio volumio[1913]: 12050K .......... .......... .......... .......... .......... 53% 11.0M 1s Apr 11 19:50:14 volumio volumio[1913]: 12100K .......... .......... .......... .......... .......... 54% 13.3M 1s Apr 11 19:50:14 volumio volumio[1913]: 12150K .......... .......... .......... .......... .......... 54% 13.3M 1s Apr 11 19:50:14 volumio volumio[1913]: 12200K .......... .......... .......... .......... .......... 54% 12.0M 1s Apr 11 19:50:14 volumio volumio[1913]: 12250K .......... .......... .......... .......... .......... 54% 14.6M 1s Apr 11 19:50:14 volumio volumio[1913]: 12300K .......... .......... .......... .......... .......... 54% 12.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 12350K .......... .......... .......... .......... .......... 55% 10.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 12400K .......... .......... .......... .......... .......... 55% 12.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 12450K .......... .......... .......... .......... .......... 55% 15.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 12500K .......... .......... .......... .......... .......... 55% 13.6M 1s Apr 11 19:50:14 volumio volumio[1913]: 12550K .......... .......... .......... .......... .......... 56% 12.1M 1s Apr 11 19:50:14 volumio volumio[1913]: 12600K .......... .......... .......... .......... .......... 56% 18.2M 1s Apr 11 19:50:14 volumio volumio[1913]: 12650K .......... .......... .......... .......... .......... 56% 11.3M 1s Apr 11 19:50:14 volumio volumio[1913]: 12700K .......... .......... .......... .......... .......... 56% 15.1M 1s Apr 11 19:50:14 volumio volumio[1913]: 12750K .......... .......... .......... .......... .......... 56% 10.5M 1s Apr 11 19:50:14 volumio volumio[1913]: 12800K .......... .......... .......... .......... .......... 57% 12.4M 1s Apr 11 19:50:14 volumio volumio[1913]: 12850K .......... .......... .......... .......... .......... 57% 15.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 12900K .......... .......... .......... .......... .......... 57% 13.5M 1s Apr 11 19:50:14 volumio volumio[1913]: 12950K .......... .......... .......... .......... .......... 57% 12.1M 1s Apr 11 19:50:14 volumio volumio[1913]: 13000K .......... .......... .......... .......... .......... 58% 15.9M 1s Apr 11 19:50:14 volumio volumio[1913]: 13050K .......... .......... .......... .......... .......... 58% 13.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 13100K .......... .......... .......... .......... .......... 58% 14.2M 1s Apr 11 19:50:14 volumio volumio[1913]: 13150K .......... .......... .......... .......... .......... 58% 10.2M 1s Apr 11 19:50:14 volumio volumio[1913]: 13200K .......... .......... .......... .......... .......... 58% 13.4M 1s Apr 11 19:50:14 volumio volumio[1913]: 13250K .......... .......... .......... .......... .......... 59% 12.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 13300K .......... .......... .......... .......... .......... 59% 15.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 13350K .......... .......... .......... .......... .......... 59% 13.4M 1s Apr 11 19:50:14 volumio volumio[1913]: 13400K .......... .......... .......... .......... .......... 59% 12.3M 1s Apr 11 19:50:14 volumio volumio[1913]: 13450K .......... .......... .......... .......... .......... 60% 19.1M 1s Apr 11 19:50:14 volumio volumio[1913]: 13500K .......... .......... .......... .......... .......... 60% 11.0M 1s Apr 11 19:50:14 volumio volumio[1913]: 13550K .......... .......... .......... .......... .......... 60% 9.86M 1s Apr 11 19:50:14 volumio volumio[1913]: 13600K .......... .......... .......... .......... .......... 60% 14.5M 1s Apr 11 19:50:14 volumio volumio[1913]: 13650K .......... .......... .......... .......... .......... 60% 16.0M 1s Apr 11 19:50:14 volumio volumio[1913]: 13700K .......... .......... .......... .......... .......... 61% 10.6M 1s Apr 11 19:50:14 volumio volumio[1913]: 13750K .......... .......... .......... .......... .......... 61% 15.4M 1s Apr 11 19:50:14 volumio volumio[1913]: 13800K .......... .......... .......... .......... .......... 61% 15.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 13850K .......... .......... .......... .......... .......... 61% 11.1M 1s Apr 11 19:50:14 volumio volumio[1913]: 13900K .......... .......... .......... .......... .......... 62% 15.6M 1s Apr 11 19:50:14 volumio volumio[1913]: 13950K .......... .......... .......... .......... .......... 62% 10.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 14000K .......... .......... .......... .......... .......... 62% 16.3M 1s Apr 11 19:50:14 volumio volumio[1913]: 14050K .......... .......... .......... .......... .......... 62% 12.9M 1s Apr 11 19:50:14 volumio volumio[1913]: 14100K .......... .......... .......... .......... .......... 62% 15.2M 1s Apr 11 19:50:14 volumio volumio[1913]: 14150K .......... .......... .......... .......... .......... 63% 11.4M 1s Apr 11 19:50:14 volumio volumio[1913]: 14200K .......... .......... .......... .......... .......... 63% 16.3M 1s Apr 11 19:50:14 volumio volumio[1913]: 14250K .......... .......... .......... .......... .......... 63% 16.4M 1s Apr 11 19:50:14 volumio volumio[1913]: 14300K .......... .......... .......... .......... .......... 63% 11.3M 1s Apr 11 19:50:14 volumio volumio[1913]: 14350K .......... .......... .......... .......... .......... 64% 11.2M 1s Apr 11 19:50:14 volumio volumio[1913]: 14400K .......... .......... .......... .......... .......... 64% 5.65M 1s Apr 11 19:50:14 volumio volumio[1913]: 14450K .......... .......... .......... .......... .......... 64% 16.6M 1s Apr 11 19:50:14 volumio volumio[1913]: 14500K .......... .......... .......... .......... .......... 64% 3.29M 1s Apr 11 19:50:14 volumio volumio[1913]: 14550K .......... .......... .......... .......... .......... 64% 11.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 14600K .......... .......... .......... .......... .......... 65% 11.6M 1s Apr 11 19:50:14 volumio volumio[1913]: 14650K .......... .......... .......... .......... .......... 65% 15.9M 1s Apr 11 19:50:14 volumio volumio[1913]: 14700K .......... .......... .......... .......... .......... 65% 12.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 14750K .......... .......... .......... .......... .......... 65% 11.0M 1s Apr 11 19:50:14 volumio volumio[1913]: 14800K .......... .......... .......... .......... .......... 66% 12.6M 1s Apr 11 19:50:14 volumio volumio[1913]: 14850K .......... .......... .......... .......... .......... 66% 16.0M 1s Apr 11 19:50:14 volumio volumio[1913]: 14900K .......... .......... .......... .......... .......... 66% 14.1M 1s Apr 11 19:50:14 volumio volumio[1913]: 14950K .......... .......... .......... .......... .......... 66% 12.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 15000K .......... .......... .......... .......... .......... 66% 16.4M 1s Apr 11 19:50:14 volumio volumio[1913]: 15050K .......... .......... .......... .......... .......... 67% 13.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 15100K .......... .......... .......... .......... .......... 67% 13.2M 1s Apr 11 19:50:14 volumio volumio[1913]: 15150K .......... .......... .......... .......... .......... 67% 11.3M 1s Apr 11 19:50:14 volumio volumio[1913]: 15200K .......... .......... .......... .......... .......... 67% 16.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 15250K .......... .......... .......... .......... .......... 68% 13.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 15300K .......... .......... .......... .......... .......... 68% 13.2M 1s Apr 11 19:50:14 volumio volumio[1913]: 15350K .......... .......... .......... .......... .......... 68% 16.2M 1s Apr 11 19:50:14 volumio volumio[1913]: 15400K .......... .......... .......... .......... .......... 68% 14.1M 1s Apr 11 19:50:14 volumio volumio[1913]: 15450K .......... .......... .......... .......... .......... 68% 12.9M 1s Apr 11 19:50:14 volumio volumio[1913]: 15500K .......... .......... .......... .......... .......... 69% 16.3M 1s Apr 11 19:50:14 volumio volumio[1913]: 15550K .......... .......... .......... .......... .......... 69% 11.4M 1s Apr 11 19:50:14 volumio volumio[1913]: 15600K .......... .......... .......... .......... .......... 69% 13.6M 1s Apr 11 19:50:14 volumio volumio[1913]: 15650K .......... .......... .......... .......... .......... 69% 13.1M 1s Apr 11 19:50:14 volumio volumio[1913]: 15700K .......... .......... .......... .......... .......... 70% 16.3M 1s Apr 11 19:50:14 volumio volumio[1913]: 15750K .......... .......... .......... .......... .......... 70% 13.9M 1s Apr 11 19:50:14 volumio volumio[1913]: 15800K .......... .......... .......... .......... .......... 70% 12.4M 1s Apr 11 19:50:14 volumio volumio[1913]: 15850K .......... .......... .......... .......... .......... 70% 15.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 15900K .......... .......... .......... .......... .......... 70% 16.0M 1s Apr 11 19:50:14 volumio volumio[1913]: 15950K .......... .......... .......... .......... .......... 71% 9.73M 1s Apr 11 19:50:14 volumio volumio[1913]: 16000K .......... .......... .......... .......... .......... 71% 12.6M 1s Apr 11 19:50:14 volumio volumio[1913]: 16050K .......... .......... .......... .......... .......... 71% 15.5M 1s Apr 11 19:50:14 volumio volumio[1913]: 16100K .......... .......... .......... .......... .......... 71% 12.4M 1s Apr 11 19:50:14 volumio volumio[1913]: 16150K .......... .......... .......... .......... .......... 72% 15.5M 1s Apr 11 19:50:14 volumio volumio[1913]: 16200K .......... .......... .......... .......... .......... 72% 14.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 16250K .......... .......... .......... .......... .......... 72% 11.0M 1s Apr 11 19:50:14 volumio volumio[1913]: 16300K .......... .......... .......... .......... .......... 72% 15.9M 1s Apr 11 19:50:14 volumio volumio[1913]: 16350K .......... .......... .......... .......... .......... 72% 10.7M 1s Apr 11 19:50:14 volumio volumio[1913]: 16400K .......... .......... .......... .......... .......... 73% 16.0M 1s Apr 11 19:50:14 volumio volumio[1913]: 16450K .......... .......... .......... .......... .......... 73% 11.0M 1s Apr 11 19:50:14 volumio volumio[1913]: 16500K .......... .......... .......... .......... .......... 73% 15.8M 1s Apr 11 19:50:14 volumio volumio[1913]: 16550K .......... .......... .......... .......... .......... 73% 15.2M 0s Apr 11 19:50:14 volumio volumio[1913]: 16600K .......... .......... .......... .......... .......... 74% 11.0M 0s Apr 11 19:50:14 volumio volumio[1913]: 16650K .......... .......... .......... .......... .......... 74% 15.9M 0s Apr 11 19:50:14 volumio volumio[1913]: 16700K .......... .......... .......... .......... .......... 74% 15.9M 0s Apr 11 19:50:14 volumio volumio[1913]: 16750K .......... .......... .......... .......... .......... 74% 10.8M 0s Apr 11 19:50:14 volumio volumio[1913]: 16800K .......... .......... .......... .......... .......... 74% 11.6M 0s Apr 11 19:50:14 volumio volumio[1913]: 16850K .......... .......... .......... .......... .......... 75% 16.5M 0s Apr 11 19:50:14 volumio volumio[1913]: 16900K .......... .......... .......... .......... .......... 75% 2.99M 0s Apr 11 19:50:14 volumio volumio[1913]: 16950K .......... .......... .......... .......... .......... 75% 4.07M 0s Apr 11 19:50:14 volumio volumio[1913]: 17000K .......... .......... .......... .......... .......... 75% 11.9M 0s Apr 11 19:50:14 volumio volumio[1913]: 17050K .......... .......... .......... .......... .......... 76% 15.1M 0s Apr 11 19:50:14 volumio volumio[1913]: 17100K .......... .......... .......... .......... .......... 76% 13.4M 0s Apr 11 19:50:14 volumio volumio[1913]: 17150K .......... .......... .......... .......... .......... 76% 10.5M 0s Apr 11 19:50:14 volumio volumio[1913]: 17200K .......... .......... .......... .......... .......... 76% 11.9M 0s Apr 11 19:50:14 volumio volumio[1913]: 17250K .......... .......... .......... .......... .......... 76% 14.9M 0s Apr 11 19:50:14 volumio volumio[1913]: 17300K .......... .......... .......... .......... .......... 77% 13.5M 0s Apr 11 19:50:14 volumio volumio[1913]: 17350K .......... .......... .......... .......... .......... 77% 11.6M 0s Apr 11 19:50:14 volumio volumio[1913]: 17400K .......... .......... .......... .......... .......... 77% 15.9M 0s Apr 11 19:50:14 volumio volumio[1913]: 17450K .......... .......... .......... .......... .......... 77% 14.9M 0s Apr 11 19:50:14 volumio volumio[1913]: 17500K .......... .......... .......... .......... .......... 78% 13.6M 0s Apr 11 19:50:14 volumio volumio[1913]: 17550K .......... .......... .......... .......... .......... 78% 10.9M 0s Apr 11 19:50:14 volumio volumio[1913]: 17600K .......... .......... .......... .......... .......... 78% 10.5M 0s Apr 11 19:50:14 volumio volumio[1913]: 17650K .......... .......... .......... .......... .......... 78% 16.1M 0s Apr 11 19:50:14 volumio volumio[1913]: 17700K .......... .......... .......... .......... .......... 78% 16.2M 0s Apr 11 19:50:14 volumio volumio[1913]: 17750K .......... .......... .......... .......... .......... 79% 13.6M 0s Apr 11 19:50:14 volumio volumio[1913]: 17800K .......... .......... .......... .......... .......... 79% 13.7M 0s Apr 11 19:50:14 volumio volumio[1913]: 17850K .......... .......... .......... .......... .......... 79% 12.0M 0s Apr 11 19:50:14 volumio volumio[1913]: 17900K .......... .......... .......... .......... .......... 79% 15.5M 0s Apr 11 19:50:14 volumio volumio[1913]: 17950K .......... .......... .......... .......... .......... 80% 10.2M 0s Apr 11 19:50:14 volumio volumio[1913]: 18000K .......... .......... .......... .......... .......... 80% 10.2M 0s Apr 11 19:50:14 volumio volumio[1913]: 18050K .......... .......... .......... .......... .......... 80% 15.7M 0s Apr 11 19:50:14 volumio volumio[1913]: 18100K .......... .......... .......... .......... .......... 80% 15.6M 0s Apr 11 19:50:14 volumio volumio[1913]: 18150K .......... .......... .......... .......... .......... 80% 12.5M 0s Apr 11 19:50:14 volumio volumio[1913]: 18200K .......... .......... .......... .......... .......... 81% 12.4M 0s Apr 11 19:50:14 volumio volumio[1913]: 18250K .......... .......... .......... .......... .......... 81% 14.6M 0s Apr 11 19:50:14 volumio volumio[1913]: 18300K .......... .......... .......... .......... .......... 81% 15.9M 0s Apr 11 19:50:14 volumio volumio[1913]: 18350K .......... .......... .......... .......... .......... 81% 9.28M 0s Apr 11 19:50:14 volumio volumio[1913]: 18400K .......... .......... .......... .......... .......... 82% 12.5M 0s Apr 11 19:50:14 volumio volumio[1913]: 18450K .......... .......... .......... .......... .......... 82% 15.6M 0s Apr 11 19:50:14 volumio volumio[1913]: 18500K .......... .......... .......... .......... .......... 82% 13.5M 0s Apr 11 19:50:14 volumio volumio[1913]: 18550K .......... .......... .......... .......... .......... 82% 12.3M 0s Apr 11 19:50:14 volumio volumio[1913]: 18600K .......... .......... .......... .......... .......... 82% 15.7M 0s Apr 11 19:50:14 volumio volumio[1913]: 18650K .......... .......... .......... .......... .......... 83% 15.8M 0s Apr 11 19:50:14 volumio volumio[1913]: 18700K .......... .......... .......... .......... .......... 83% 10.9M 0s Apr 11 19:50:14 volumio volumio[1913]: 18750K .......... .......... .......... .......... .......... 83% 11.0M 0s Apr 11 19:50:14 volumio volumio[1913]: 18800K .......... .......... .......... .......... .......... 83% 15.6M 0s Apr 11 19:50:14 volumio volumio[1913]: 18850K .......... .......... .......... .......... .......... 84% 16.0M 0s Apr 11 19:50:14 volumio volumio[1913]: 18900K .......... .......... .......... .......... .......... 84% 10.8M 0s Apr 11 19:50:14 volumio volumio[1913]: 18950K .......... .......... .......... .......... .......... 84% 15.4M 0s Apr 11 19:50:14 volumio volumio[1913]: 19000K .......... .......... .......... .......... .......... 84% 15.8M 0s Apr 11 19:50:14 volumio volumio[1913]: 19050K .......... .......... .......... .......... .......... 84% 10.9M 0s Apr 11 19:50:14 volumio volumio[1913]: 19100K .......... .......... .......... .......... .......... 85% 15.7M 0s Apr 11 19:50:14 volumio volumio[1913]: 19150K .......... .......... .......... .......... .......... 85% 10.8M 0s Apr 11 19:50:14 volumio volumio[1913]: 19200K .......... .......... .......... .......... .......... 85% 15.8M 0s Apr 11 19:50:14 volumio volumio[1913]: 19250K .......... .......... .......... .......... .......... 85% 10.7M 0s Apr 11 19:50:14 volumio volumio[1913]: 19300K .......... .......... .......... .......... .......... 86% 15.7M 0s Apr 11 19:50:14 volumio volumio[1913]: 19350K .......... .......... .......... .......... .......... 86% 12.1M 0s Apr 11 19:50:14 volumio volumio[1913]: 19400K .......... .......... .......... .......... .......... 86% 16.6M 0s Apr 11 19:50:14 volumio volumio[1913]: 19450K .......... .......... .......... .......... .......... 86% 11.9M 0s Apr 11 19:50:14 volumio volumio[1913]: 19500K .......... .......... .......... .......... .......... 86% 13.6M 0s Apr 11 19:50:14 volumio volumio[1913]: 19550K .......... .......... .......... .......... .......... 87% 10.9M 0s Apr 11 19:50:14 volumio volumio[1913]: 19600K .......... .......... .......... .......... .......... 87% 12.6M 0s Apr 11 19:50:14 volumio volumio[1913]: 19650K .......... .......... .......... .......... .......... 87% 15.4M 0s Apr 11 19:50:14 volumio volumio[1913]: 19700K .......... .......... .......... .......... .......... 87% 13.6M 0s Apr 11 19:50:14 volumio volumio[1913]: 19750K .......... .......... .......... .......... .......... 88% 12.4M 0s Apr 11 19:50:14 volumio volumio[1913]: 19800K .......... .......... .......... .......... .......... 88% 15.7M 0s Apr 11 19:50:14 volumio volumio[1913]: 19850K .......... .......... .......... .......... .......... 88% 13.3M 0s Apr 11 19:50:14 volumio volumio[1913]: 19900K .......... .......... .......... .......... .......... 88% 12.9M 0s Apr 11 19:50:14 volumio volumio[1913]: 19950K .......... .......... .......... .......... .......... 88% 11.4M 0s Apr 11 19:50:14 volumio volumio[1913]: 20000K .......... .......... .......... .......... .......... 89% 1.89M 0s Apr 11 19:50:14 volumio volumio[1913]: 20050K .......... .......... .......... .......... .......... 89% 11.3M 0s Apr 11 19:50:14 volumio volumio[1913]: 20100K .......... .......... .......... .......... .......... 89% 12.3M 0s Apr 11 19:50:14 volumio volumio[1913]: 20150K .......... .......... .......... .......... .......... 89% 11.8M 0s Apr 11 19:50:14 volumio volumio[1913]: 20200K .......... .......... .......... .......... .......... 90% 15.0M 0s Apr 11 19:50:14 volumio volumio[1913]: 20250K .......... .......... .......... .......... .......... 90% 16.1M 0s Apr 11 19:50:14 volumio volumio[1913]: 20300K .......... .......... .......... .......... .......... 90% 10.4M 0s Apr 11 19:50:14 volumio volumio[1913]: 20350K .......... .......... .......... .......... .......... 90% 10.5M 0s Apr 11 19:50:14 volumio volumio[1913]: 20400K .......... .......... .......... .......... .......... 90% 15.4M 0s Apr 11 19:50:14 volumio volumio[1913]: 20450K .......... .......... .......... .......... .......... 91% 15.8M 0s Apr 11 19:50:14 volumio volumio[1913]: 20500K .......... .......... .......... .......... .......... 91% 11.1M 0s Apr 11 19:50:14 volumio volumio[1913]: 20550K .......... .......... .......... .......... .......... 91% 15.5M 0s Apr 11 19:50:14 volumio volumio[1913]: 20600K .......... .......... .......... .......... .......... 91% 15.5M 0s Apr 11 19:50:14 volumio volumio[1913]: 20650K .......... .......... .......... .......... .......... 92% 11.1M 0s Apr 11 19:50:14 volumio volumio[1913]: 20700K .......... .......... .......... .......... .......... 92% 15.9M 0s Apr 11 19:50:14 volumio volumio[1913]: 20750K .......... .......... .......... .......... .......... 92% 10.7M 0s Apr 11 19:50:14 volumio volumio[1913]: 20800K .......... .......... .......... .......... .......... 92% 16.0M 0s Apr 11 19:50:14 volumio volumio[1913]: 20850K .......... .......... .......... .......... .......... 92% 11.0M 0s Apr 11 19:50:14 volumio volumio[1913]: 20900K .......... .......... .......... .......... .......... 93% 15.8M 0s Apr 11 19:50:14 volumio volumio[1913]: 20950K .......... .......... .......... .......... .......... 93% 15.2M 0s Apr 11 19:50:14 volumio volumio[1913]: 21000K .......... .......... .......... .......... .......... 93% 13.5M 0s Apr 11 19:50:14 volumio volumio[1913]: 21050K .......... .......... .......... .......... .......... 93% 12.5M 0s Apr 11 19:50:14 volumio volumio[1913]: 21100K .......... .......... .......... .......... .......... 94% 15.8M 0s Apr 11 19:50:14 volumio volumio[1913]: 21150K .......... .......... .......... .......... .......... 94% 10.9M 0s Apr 11 19:50:14 volumio volumio[1913]: 21200K .......... .......... .......... .......... .......... 94% 10.9M 0s Apr 11 19:50:14 volumio volumio[1913]: 21250K .......... .......... .......... .......... .......... 94% 18.6M 0s Apr 11 19:50:14 volumio volumio[1913]: 21300K .......... .......... .......... .......... .......... 94% 11.2M 0s Apr 11 19:50:14 volumio volumio[1913]: 21350K .......... .......... .......... .......... .......... 95% 14.4M 0s Apr 11 19:50:14 volumio volumio[1913]: 21400K .......... .......... .......... .......... .......... 95% 15.7M 0s Apr 11 19:50:14 volumio volumio[1913]: 21450K .......... .......... .......... .......... .......... 95% 13.6M 0s Apr 11 19:50:14 volumio volumio[1913]: 21500K .......... .......... .......... .......... .......... 95% 12.5M 0s Apr 11 19:50:14 volumio volumio[1913]: 21550K .......... .......... .......... .......... .......... 96% 10.8M 0s Apr 11 19:50:14 volumio volumio[1913]: 21600K .......... .......... .......... .......... .......... 96% 15.9M 0s Apr 11 19:50:14 volumio volumio[1913]: 21650K .......... .......... .......... .......... .......... 96% 12.5M 0s Apr 11 19:50:14 volumio volumio[1913]: 21700K .......... .......... .......... .......... .......... 96% 15.9M 0s Apr 11 19:50:14 volumio volumio[1913]: 21750K .......... .......... .......... .......... .......... 96% 10.3M 0s Apr 11 19:50:14 volumio volumio[1913]: 21800K .......... .......... .......... .......... .......... 97% 15.7M 0s Apr 11 19:50:14 volumio volumio[1913]: 21850K .......... .......... .......... .......... .......... 97% 17.9M 0s Apr 11 19:50:14 volumio volumio[1913]: 21900K .......... .......... .......... .......... .......... 97% 11.2M 0s Apr 11 19:50:14 volumio volumio[1913]: 21950K .......... .......... .......... .......... .......... 97% 10.7M 0s Apr 11 19:50:14 volumio volumio[1913]: 22000K .......... .......... .......... .......... .......... 98% 15.8M 0s Apr 11 19:50:14 volumio volumio[1913]: 22050K .......... .......... .......... .......... .......... 98% 11.0M 0s Apr 11 19:50:14 volumio volumio[1913]: 22100K .......... .......... .......... .......... .......... 98% 15.5M 0s Apr 11 19:50:14 volumio volumio[1913]: 22150K .......... .......... .......... .......... .......... 98% 15.6M 0s Apr 11 19:50:14 volumio volumio[1913]: 22200K .......... .......... .......... .......... .......... 98% 13.6M 0s Apr 11 19:50:14 volumio volumio[1913]: 22250K .......... .......... .......... .......... .......... 99% 12.5M 0s Apr 11 19:50:14 volumio volumio[1913]: 22300K .......... .......... .......... .......... .......... 99% 15.6M 0s Apr 11 19:50:14 volumio volumio[1913]: 22350K .......... .......... .......... .......... .......... 99% 11.2M 0s Apr 11 19:50:14 volumio volumio[1913]: 22400K .......... .......... .......... .......... .......... 99% 13.7M 0s Apr 11 19:50:14 volumio volumio[1913]: 22450K .......... .......... .......... .......... ..... 100% 18.4M=1.9s Apr 11 19:50:14 volumio volumio[1913]: 2024-04-11 19:50:14 (11.7 MB/s) - ‘/tmp/tools.tar.xz’ saved [23035700/23035700] Apr 11 19:50:18 volumio volumio[1913]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 19:50:18 volumio volumio[1913]: info: CoreCommandRouter::Close All Modals sent Apr 11 19:50:19 volumio volumio[1913]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion Apr 11 19:50:19 volumio volumio[1913]: info: Preparing to generate the ALSA configuration file Apr 11 19:50:19 volumio volumio[1913]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 11 19:50:19 volumio volumio[1913]: info: Reading ALSA contributions from plugins. Apr 11 19:50:19 volumio volumio[1913]: info: Asound.conf file unchanged, so no further update is needed Apr 11 19:50:19 volumio volumio[1913]: info: Output device has changed, restarting MPD Apr 11 19:50:19 volumio sudo[2070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 19:50:19 volumio sudo[2070]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:19 volumio volumio[1913]: info: Output device has changed, restarting Shairport Sync Apr 11 19:50:19 volumio volumio[1913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:19 volumio volumio[1913]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 19:50:19 volumio sudo[2070]: pam_unix(sudo:session): session closed for user root Apr 11 19:50:19 volumio sudo[2073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 19:50:19 volumio sudo[2073]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:19 volumio systemd[1]: Stopping Music Player Daemon... Apr 11 19:50:19 volumio volumio[1913]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 19:50:19 volumio volumio[1913]: info: ___________ START PLUGINS ___________ Apr 11 19:50:19 volumio volumio[1913]: info: ControllerMpd::onStart: Initializing MPD Apr 11 19:50:19 volumio volumio[1913]: info: Creating MPD Configuration file Apr 11 19:50:19 volumio systemd[1]: mpd.service: Succeeded. Apr 11 19:50:19 volumio systemd[1]: Stopped Music Player Daemon. Apr 11 19:50:19 volumio sudo[2080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 19:50:19 volumio sudo[2080]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:20 volumio volumio[1913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 19:50:20 volumio volumio[1913]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 19:50:20 volumio volumio[1913]: info: [1712857819982] CoreMusicLibrary::Adding element Server multimediali Apr 11 19:50:20 volumio volumio[1913]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 19:50:20 volumio volumio[1913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:20 volumio volumio[1913]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 19:50:19 volumio sudo[2080]: pam_unix(sudo:session): session closed for user root Apr 11 19:50:20 volumio systemd[1]: Starting Music Player Daemon... Apr 11 19:50:19 volumio sudo[2083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 19:50:19 volumio sudo[2083]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:20 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 11 19:50:20 volumio systemd[1]: mpd.service: Succeeded. Apr 11 19:50:20 volumio systemd[1]: Stopped Music Player Daemon. Apr 11 19:50:20 volumio systemd[1]: Starting Music Player Daemon... Apr 11 19:50:20 volumio volumio[1913]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 19:50:20 volumio volumio[1913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:20 volumio volumio[1913]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 19:50:20 volumio volumio[1913]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 19:50:20 volumio volumio[1913]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 19:50:20 volumio volumio[1913]: info: [1712857820092] CoreMusicLibrary::Adding element Last_100 Apr 11 19:50:20 volumio volumio[1913]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 19:50:20 volumio volumio[1913]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 19:50:20 volumio volumio[1913]: info: [1712857820094] CoreMusicLibrary::Adding element Webradio Apr 11 19:50:20 volumio volumio[1913]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 19:50:20 volumio volumio[1913]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 19:50:20 volumio volumio[1913]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 19:50:20 volumio volumio[1913]: info: [1712857820106] CoreMusicLibrary::Adding element SoundCloud Apr 11 19:50:20 volumio volumio[1913]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 19:50:20 volumio volumio[1913]: Cannot find translation for source SoundCloud Apr 11 19:50:20 volumio volumio[1913]: info: Checking for old spotify connect plugin installed Apr 11 19:50:20 volumio volumio[1913]: info: Loading i18n strings for locale it Apr 11 19:50:20 volumio volumio[1913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 11 19:50:20 volumio volumio[1913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:20 volumio volumio[1913]: info: FusionDsp - mixtype--------------------- None Apr 11 19:50:20 volumio volumio[1913]: info: Volumio Calling Home Apr 11 19:50:20 volumio volumio[1913]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 19:50:20 volumio volumio[1913]: [SpotifyConnect] Creating VLS config file Apr 11 19:50:20 volumio volumio[1913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:20 volumio volumio[1913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:20 volumio volumio[1913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:20 volumio volumio[1913]: [SpotifyConnect] Starting metadata listener Apr 11 19:50:20 volumio volumio[1913]: info: Preparing to generate the ALSA configuration file Apr 11 19:50:20 volumio sudo[2096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Apr 11 19:50:20 volumio sudo[2096]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:20 volumio sudo[2096]: pam_unix(sudo:session): session closed for user root Apr 11 19:50:20 volumio volumio[1913]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 19:50:20 volumio volumio[1913]: TypeError: Cannot read property 'emit' of undefined Apr 11 19:50:20 volumio volumio[1913]: at Timeout._onTimeout (/data/plugins/audio_interface/fusiondsp/index.js:4932:21) Apr 11 19:50:20 volumio volumio[1913]: at listOnTimeout (internal/timers.js:554:17) Apr 11 19:50:20 volumio volumio[1913]: at processTimers (internal/timers.js:497:7) Apr 11 19:50:20 volumio volumio[1913]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 19:50:20 volumio mpd[2086]: Apr 11 19:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 19:50:21 volumio sudo[2108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-11 19:49 Apr 11 19:50:21 volumio sudo[2108]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:21 volumio sudo[2108]: pam_unix(sudo:session): session closed for user root Apr 11 19:50:21 volumio systemd[1]: Started Music Player Daemon. Apr 11 19:50:21 volumio sudo[2083]: pam_unix(sudo:session): session closed for user root Apr 11 19:50:21 volumio sudo[2073]: pam_unix(sudo:session): session closed for user root Apr 11 19:50:21 volumio volumio-remote-updater[509]: [2024-04-11 19:50:21] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 11 19:50:21 volumio volumio-remote-updater[509]: [2024-04-11 19:50:21] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 11 19:50:21 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 11 19:50:21 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 11 19:50:21 volumio systemd[1]: Started dynamicswap service. Apr 11 19:50:21 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 11 19:50:21 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 11 19:50:21 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Apr 11 19:50:21 volumio systemd[1]: Stopped Volumio Backend Module. Apr 11 19:50:21 volumio kernel: hwmon hwmon1: Undervoltage detected! Apr 11 19:50:21 volumio systemd[1]: Started Volumio Backend Module. Apr 11 19:50:22 volumio systemd[1]: Started dynamicswap service. Apr 11 19:50:22 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 11 19:50:23 volumio volumio[2123]: info: ------------------------------------------- Apr 11 19:50:23 volumio volumio[2123]: info: ----- Volumio3 ---- Apr 11 19:50:23 volumio volumio[2123]: info: ------------------------------------------- Apr 11 19:50:23 volumio volumio[2123]: info: ----- System startup ---- Apr 11 19:50:23 volumio volumio[2123]: info: ------------------------------------------- Apr 11 19:50:24 volumio volumio[2123]: info: MYVOLUMIO Environment detected Apr 11 19:50:24 volumio volumio[2123]: info: Plugin folders cleanup Apr 11 19:50:24 volumio volumio[2123]: info: Scanning into folder /volumio/app/plugins/ Apr 11 19:50:24 volumio volumio[2123]: info: Scanning category audio_interface Apr 11 19:50:24 volumio volumio[2123]: info: Scanning category miscellanea Apr 11 19:50:24 volumio volumio[2123]: info: Scanning category music_service Apr 11 19:50:24 volumio volumio[2123]: info: Scanning category plugins.json Apr 11 19:50:24 volumio volumio[2123]: info: Scanning category system_controller Apr 11 19:50:24 volumio volumio[2123]: info: Scanning category user_interface Apr 11 19:50:24 volumio volumio[2123]: info: Scanning into folder /data/plugins/ Apr 11 19:50:24 volumio volumio[2123]: info: Scanning category audio_interface Apr 11 19:50:24 volumio volumio[2123]: info: Scanning category music_service Apr 11 19:50:24 volumio volumio[2123]: info: Plugin folders cleanup completed Apr 11 19:50:24 volumio volumio[2123]: info: ------------------------------------------- Apr 11 19:50:24 volumio volumio[2123]: info: ----- Core plugins startup ---- Apr 11 19:50:24 volumio volumio[2123]: info: ------------------------------------------- Apr 11 19:50:24 volumio volumio[2123]: info: Loading plugins from folder /volumio/app/plugins/ Apr 11 19:50:24 volumio volumio[2123]: info: Adding plugin upnp to MyMusic Plugins Apr 11 19:50:24 volumio volumio[2123]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 11 19:50:24 volumio volumio[2123]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 11 19:50:24 volumio volumio[2123]: info: Loading plugins from folder /data/plugins/ Apr 11 19:50:24 volumio volumio[2123]: info: Loading plugin "system"... Apr 11 19:50:24 volumio volumio[2123]: info: Loading plugin "appearance"... Apr 11 19:50:25 volumio volumio[2123]: info: Loading plugin "network"... Apr 11 19:50:25 volumio volumio[2123]: info: Refreshing Cached IP Addresses Apr 11 19:50:25 volumio sudo[2151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 19:50:25 volumio sudo[2151]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:25 volumio sudo[2151]: pam_unix(sudo:session): session closed for user root Apr 11 19:50:25 volumio sudo[2153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 19:50:25 volumio sudo[2153]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:25 volumio volumio[2123]: info: Loading plugin "services"... Apr 11 19:50:25 volumio volumio[2123]: info: Loading plugin "alsa_controller"... Apr 11 19:50:25 volumio sudo[2153]: pam_unix(sudo:session): session closed for user root Apr 11 19:50:25 volumio sudo[2157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 11 19:50:25 volumio sudo[2157]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:25 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 19:50:25 volumio volumio[2123]: info: Loading plugin "wizard"... Apr 11 19:50:25 volumio volumio[2123]: info: Loading plugin "networkfs"... Apr 11 19:50:25 volumio volumio[2123]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Apr 11 19:50:25 volumio volumio[2123]: info: Starting Udev Watcher for removable devices Apr 11 19:50:25 volumio volumio[2123]: info: Ignoring mount for partition: boot Apr 11 19:50:25 volumio volumio[2123]: info: Ignoring mount for partition: volumio Apr 11 19:50:25 volumio volumio[2123]: info: Ignoring mount for partition: volumio_data Apr 11 19:50:25 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 19:50:26 volumio volumio[2123]: info: Loading plugin "volumio_command_line_client"... Apr 11 19:50:26 volumio volumio[2123]: info: Loading plugin "upnp"... Apr 11 19:50:26 volumio volumio[2123]: info: [1712857826034] Starting Upmpd Daemon Apr 11 19:50:26 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 19:50:26 volumio volumio[2123]: info: Loading plugin "my_music"... Apr 11 19:50:26 volumio volumio[2123]: info: Loading plugin "mpd"... Apr 11 19:50:26 volumio volumio[2123]: info: Loading plugin "upnp_browser"... Apr 11 19:50:26 volumio volumio-remote-updater[509]: [2024-04-11 19:50:26] [connect] Successful connection Apr 11 19:50:26 volumio volumio[2123]: info: Loading plugin "alarm-clock"... Apr 11 19:50:27 volumio volumio[2123]: info: Loading plugin "airplay_emulation"... Apr 11 19:50:27 volumio volumio[2123]: info: Starting Shairport Sync Apr 11 19:50:27 volumio volumio[2123]: info: Loading plugin "last_100"... Apr 11 19:50:27 volumio volumio[2123]: info: Loading plugin "webradio"... Apr 11 19:50:27 volumio volumio[2123]: info: Loading plugin "i2s_dacs"... Apr 11 19:50:27 volumio volumio[2123]: info: Loading plugin "volumiodiscovery"... Apr 11 19:50:27 volumio volumio[2123]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 11 19:50:27 volumio node[2123]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 11 19:50:27 volumio node[2123]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 19:50:27 volumio volumio[2123]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 19:50:27 volumio volumio[2123]: *** WARNING *** For more information see Apr 11 19:50:27 volumio volumio[2123]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 11 19:50:27 volumio volumio[2123]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 19:50:27 volumio volumio[2123]: *** WARNING *** For more information see Apr 11 19:50:27 volumio node[2123]: *** WARNING *** For more information see Apr 11 19:50:27 volumio node[2123]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 11 19:50:27 volumio node[2123]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 19:50:27 volumio node[2123]: *** WARNING *** For more information see Apr 11 19:50:27 volumio volumio[2123]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 11 19:50:27 volumio volumio[2123]: info: Discovery: Started advertising with name: Volumio Apr 11 19:50:27 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 19:50:27 volumio volumio[2123]: info: Loading plugin "soundcloud"... Apr 11 19:50:27 volumio volumio[2123]: info: Loading plugin "spop"... Apr 11 19:50:28 volumio volumio[2123]: info: Loading plugin "outputs"... Apr 11 19:50:28 volumio volumio[2123]: info: Loading plugin "albumart"... Apr 11 19:50:28 volumio volumio[2123]: info: Plugin example_plugin is not enabled Apr 11 19:50:28 volumio volumio[2123]: info: Loading plugin "inputs"... Apr 11 19:50:28 volumio volumio[2123]: info: Loading plugin "updater_comm"... Apr 11 19:50:28 volumio volumio[2123]: info: Plugin mpdemulation is not enabled Apr 11 19:50:28 volumio volumio[2123]: info: Loading plugin "rest_api"... Apr 11 19:50:28 volumio volumio[2123]: info: Loading plugin "websocket"... Apr 11 19:50:28 volumio volumio[2123]: info: Loading plugin "fusiondsp"... Apr 11 19:50:28 volumio volumio[2123]: Forking 3 albumart workers Apr 11 19:50:28 volumio volumio[2123]: info: Applying required configuration parameters for plugin fusiondsp Apr 11 19:50:28 volumio volumio[2123]: info: Loading i18n strings for locale it Apr 11 19:50:28 volumio volumio[2123]: Updating browse sources language Apr 11 19:50:28 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 19:50:28 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 19:50:28 volumio volumio[2123]: info: CoreCommandRouter::initPlayerControls Apr 11 19:50:28 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:28 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:28 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:28 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:28 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:28 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:28 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:28 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:28 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 19:50:28 volumio volumio[2123]: Express server listening on port 3000 Apr 11 19:50:28 volumio volumio[2123]: [Metrics] WebUI: 5s 66.63ms Apr 11 19:50:28 volumio volumio[2123]: info: CoreStateMachine::resetVolumioState Apr 11 19:50:28 volumio volumio[2123]: info: CoreStateMachine::getcurrentVolume Apr 11 19:50:28 volumio volumio[2123]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 19:50:28 volumio volumio[2123]: info: CoreStateMachine::pushState Apr 11 19:50:28 volumio volumio[2123]: info: CorePlayQueue::getTrack 0 Apr 11 19:50:28 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 19:50:28 volumio volumio[2123]: info: CoreCommandRouter::volumioPushState Apr 11 19:50:28 volumio volumio[2123]: verbose: New Socket.io Connection to 192.168.1.55 from 192.168.1.64 UA: okhttp/4.9.2 Total Clients: 1 Apr 11 19:50:28 volumio volumio[2123]: verbose: New Socket.io Connection to 192.168.1.55 from 192.168.1.50 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.4.1 Safari/605.1.15 Total Clients: 2 Apr 11 19:50:28 volumio volumio-remote-updater[509]: [2024-04-11 19:50:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712857826 101 Apr 11 19:50:28 volumio volumio[2123]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Apr 11 19:50:28 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 19:50:28 volumio volumio[2123]: info: Reloading queue from file Apr 11 19:50:28 volumio volumio[2123]: info: CoreStateMachine::setRepeat true single undefined Apr 11 19:50:28 volumio volumio[2123]: info: CoreStateMachine::pushState Apr 11 19:50:28 volumio volumio[2123]: info: CorePlayQueue::getTrack 0 Apr 11 19:50:28 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 19:50:28 volumio volumio[2123]: info: CoreCommandRouter::volumioPushState Apr 11 19:50:28 volumio volumio[2123]: info: CoreStateMachine::setRandom null Apr 11 19:50:28 volumio volumio[2123]: info: CoreStateMachine::pushState Apr 11 19:50:28 volumio volumio[2123]: info: CorePlayQueue::getTrack 0 Apr 11 19:50:28 volumio volumio[2123]: info: CoreCommandRouter::volumioPushState Apr 11 19:50:28 volumio volumio[2123]: Starting albumart workers Apr 11 19:50:29 volumio volumio[2123]: info: Setting Device type: Raspberry PI Apr 11 19:50:29 volumio volumio[2123]: Starting albumart workers Apr 11 19:50:29 volumio volumio[2123]: Starting albumart workers Apr 11 19:50:29 volumio volumio[2123]: info: Discovery: adding e5cb8c21-15d2-4622-8e33-f260b2035a1d Apr 11 19:50:29 volumio volumio[2123]: info: Discovery: Found device Volumio Apr 11 19:50:29 volumio volumio[2123]: info: CoreCommandRouter::volumioGetState Apr 11 19:50:29 volumio volumio[2123]: info: CorePlayQueue::getTrack 0 Apr 11 19:50:29 volumio sudo[2157]: pam_unix(sudo:session): session closed for user root Apr 11 19:50:29 volumio volumio[2123]: verbose: New Socket.io Connection to 192.168.1.55 from 192.168.1.64 UA: okhttp/4.9.2 Total Clients: 4 Apr 11 19:50:29 volumio volumio[2123]: info: CoreCommandRouter::volumioGetState Apr 11 19:50:29 volumio volumio[2123]: info: CorePlayQueue::getTrack 0 Apr 11 19:50:29 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 11 19:50:29 volumio volumio[2123]: info: Listing playlists Apr 11 19:50:29 volumio volumio[2123]: info: CoreCommandRouter::volumioGetVisibleSources Apr 11 19:50:29 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 19:50:29 volumio volumio[2123]: info: CoreCommandRouter::volumioGetQueue Apr 11 19:50:29 volumio volumio[2123]: info: CoreStateMachine::getQueue Apr 11 19:50:29 volumio volumio[2123]: info: CorePlayQueue::getQueue Apr 11 19:50:29 volumio volumio[2123]: verbose: New Socket.io Connection to 192.168.1.55 from 192.168.1.50 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.4.1 Safari/605.1.15 Total Clients: 5 Apr 11 19:50:29 volumio volumio[2123]: info: CoreCommandRouter::volumioGetState Apr 11 19:50:29 volumio volumio[2123]: info: CorePlayQueue::getTrack 0 Apr 11 19:50:29 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 11 19:50:29 volumio volumio[2123]: info: Listing playlists Apr 11 19:50:29 volumio volumio[2123]: info: CoreCommandRouter::volumioGetVisibleSources Apr 11 19:50:29 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 19:50:29 volumio volumio[2123]: info: CoreCommandRouter::volumioGetQueue Apr 11 19:50:29 volumio volumio[2123]: info: CoreStateMachine::getQueue Apr 11 19:50:29 volumio volumio[2123]: info: CorePlayQueue::getQueue Apr 11 19:50:30 volumio volumio[2123]: info: Cannot mount NAS NAS at system boot, trial number 2 ,retrying in 5 seconds Apr 11 19:50:34 volumio kernel: hwmon hwmon1: Voltage normalised Apr 11 19:50:35 volumio volumio[2123]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds Apr 11 19:50:36 volumio sudo[2225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 19:50:36 volumio sudo[2225]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:36 volumio sudo[2225]: pam_unix(sudo:session): session closed for user root Apr 11 19:50:36 volumio sudo[2228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 19:50:36 volumio sudo[2228]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:36 volumio sudo[2228]: pam_unix(sudo:session): session closed for user root Apr 11 19:50:36 volumio sudo[2231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 11 19:50:36 volumio sudo[2231]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:36 volumio sudo[2231]: pam_unix(sudo:session): session closed for user root Apr 11 19:50:36 volumio volumio[2123]: info: Upmpdcli Daemon Started Apr 11 19:50:40 volumio volumio[2123]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Apr 11 19:50:43 volumio volumio[2123]: info: CALLMETHOD: audio_interface fusiondsp playToolsFile [object Object] Apr 11 19:50:43 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , playToolsFile Apr 11 19:50:43 volumio volumio[2123]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 11 19:50:43 volumio volumio[2123]: info: CoreStateMachine::ClearQueue Apr 11 19:50:43 volumio volumio[2123]: info: CoreStateMachine::stop Apr 11 19:50:43 volumio volumio[2123]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 19:50:43 volumio volumio[2123]: info: CorePlayQueue::clearPlayQueue Apr 11 19:50:43 volumio volumio[2123]: info: CorePlayQueue::saveQueue Apr 11 19:50:43 volumio volumio[2123]: info: CoreCommandRouter::volumioPushQueue Apr 11 19:50:43 volumio volumio[2123]: info: CoreStateMachine::addQueueItems Apr 11 19:50:43 volumio volumio[2123]: info: CorePlayQueue::addQueueItems Apr 11 19:50:43 volumio volumio[2123]: info: Preload queue cleared Apr 11 19:50:43 volumio volumio[2123]: info: Adding Item to queue: INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav Apr 11 19:50:43 volumio volumio[2123]: info: Exploding uri INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav in service mpd Apr 11 19:50:43 volumio volumio[2123]: error: Failed callmethod call: TypeError: Cannot read property 'then' of undefined Apr 11 19:50:45 volumio volumio[2123]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Apr 11 19:50:46 volumio kernel: hwmon hwmon1: Undervoltage detected! Apr 11 19:50:51 volumio volumio[1866]: [Vollibrespot] : couldn't parse packet from 192.168.1.50:5353: query type 65 is invalid Apr 11 19:50:52 volumio volumio[1866]: [Vollibrespot] : couldn't parse packet from 192.168.1.50:5353: query type 65 is invalid Apr 11 19:50:53 volumio kernel: hwmon hwmon1: Voltage normalised Apr 11 19:50:54 volumio volumio[2123]: info: CoreCommandRouter::volumioPlay Apr 11 19:50:54 volumio volumio[2123]: info: CoreStateMachine::play index undefined Apr 11 19:50:54 volumio volumio[2123]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 19:50:54 volumio volumio[2123]: info: CorePlayQueue::getTrack 0 Apr 11 19:50:58 volumio volumio[2123]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion Apr 11 19:50:58 volumio volumio[2123]: info: Preparing to generate the ALSA configuration file Apr 11 19:50:58 volumio volumio[2123]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 11 19:50:58 volumio volumio[2123]: info: Reading ALSA contributions from plugins. Apr 11 19:50:58 volumio volumio[2123]: info: Asound.conf file unchanged, so no further update is needed Apr 11 19:50:58 volumio volumio[2123]: info: Output device has changed, restarting MPD Apr 11 19:50:58 volumio sudo[2275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 19:50:58 volumio sudo[2275]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:58 volumio volumio[2123]: info: Output device has changed, restarting Shairport Sync Apr 11 19:50:58 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:58 volumio sudo[2275]: pam_unix(sudo:session): session closed for user root Apr 11 19:50:58 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 19:50:58 volumio sudo[2278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 19:50:58 volumio sudo[2278]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:58 volumio systemd[1]: Stopping Music Player Daemon... Apr 11 19:50:58 volumio volumio[2123]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 19:50:58 volumio volumio[2123]: info: ___________ START PLUGINS ___________ Apr 11 19:50:58 volumio volumio[2123]: info: ControllerMpd::onStart: Initializing MPD Apr 11 19:50:58 volumio volumio[2123]: info: Creating MPD Configuration file Apr 11 19:50:58 volumio systemd[1]: mpd.service: Succeeded. Apr 11 19:50:58 volumio systemd[1]: Stopped Music Player Daemon. Apr 11 19:50:58 volumio sudo[2285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 19:50:58 volumio sudo[2285]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:58 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 19:50:58 volumio volumio[2123]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 19:50:58 volumio volumio[2123]: info: [1712857858628] CoreMusicLibrary::Adding element Server multimediali Apr 11 19:50:58 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 19:50:58 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:58 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 19:50:58 volumio sudo[2285]: pam_unix(sudo:session): session closed for user root Apr 11 19:50:58 volumio systemd[1]: Starting Music Player Daemon... Apr 11 19:50:58 volumio sudo[2288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 19:50:58 volumio sudo[2288]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:58 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 11 19:50:58 volumio systemd[1]: mpd.service: Succeeded. Apr 11 19:50:58 volumio systemd[1]: Stopped Music Player Daemon. Apr 11 19:50:58 volumio systemd[1]: Starting Music Player Daemon... Apr 11 19:50:58 volumio volumio[2123]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 19:50:58 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:58 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 19:50:58 volumio volumio[2123]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 19:50:58 volumio volumio[2123]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 19:50:58 volumio volumio[2123]: info: [1712857858733] CoreMusicLibrary::Adding element Last_100 Apr 11 19:50:58 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 19:50:58 volumio volumio[2123]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 19:50:58 volumio volumio[2123]: info: [1712857858737] CoreMusicLibrary::Adding element Webradio Apr 11 19:50:58 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 19:50:58 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 19:50:58 volumio volumio[2123]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 19:50:58 volumio volumio[2123]: info: [1712857858750] CoreMusicLibrary::Adding element SoundCloud Apr 11 19:50:58 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 19:50:58 volumio volumio[2123]: Cannot find translation for source SoundCloud Apr 11 19:50:58 volumio volumio[2123]: info: Checking for old spotify connect plugin installed Apr 11 19:50:58 volumio volumio[2123]: info: Loading i18n strings for locale it Apr 11 19:50:58 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 11 19:50:58 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:58 volumio volumio[2123]: info: FusionDsp - mixtype--------------------- None Apr 11 19:50:58 volumio volumio[2123]: info: Volumio Calling Home Apr 11 19:50:58 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 19:50:58 volumio volumio[2123]: [SpotifyConnect] Creating VLS config file Apr 11 19:50:58 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:58 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:58 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:58 volumio volumio[2123]: [SpotifyConnect] Starting metadata listener Apr 11 19:50:58 volumio volumio[2123]: info: Preparing to generate the ALSA configuration file Apr 11 19:50:58 volumio sudo[2301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Apr 11 19:50:58 volumio sudo[2301]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:58 volumio sudo[2301]: pam_unix(sudo:session): session closed for user root Apr 11 19:50:58 volumio volumio[2123]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 11 19:50:58 volumio volumio[2123]: info: Reading ALSA contributions from plugins. Apr 11 19:50:58 volumio volumio[2123]: [SpotifyConnect] Vollibrespot Daemon service started! Apr 11 19:50:58 volumio volumio[2123]: [Metrics] SpotifyConnect: 0s 123.06ms Apr 11 19:50:58 volumio volumio[2123]: info: MPD Permissions set Apr 11 19:50:58 volumio volumio[2123]: info: MPD Permissions set Apr 11 19:50:58 volumio volumio[2123]: info: Volumio called home Apr 11 19:50:59 volumio volumio[2123]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Apr 11 19:50:59 volumio volumio[2123]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: Starting Shairport Sync Apr 11 19:50:59 volumio volumio[2123]: info: Starting Shairport Sync Apr 11 19:50:59 volumio sudo[2312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 19:50:59 volumio sudo[2312]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:59 volumio volumio[2123]: info: Starting Shairport Sync Apr 11 19:50:59 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 11 19:50:59 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 11 19:50:59 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 11 19:50:59 volumio sudo[2315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 19:50:59 volumio sudo[2315]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:59 volumio sudo[2317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 19:50:59 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 11 19:50:59 volumio sudo[2317]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:59 volumio sudo[2312]: pam_unix(sudo:session): session closed for user root Apr 11 19:50:59 volumio volumio[2123]: info: Asound.conf file unchanged, so no further update is needed Apr 11 19:50:59 volumio volumio[2123]: info: Output device has changed, restarting MPD Apr 11 19:50:59 volumio mpd[2293]: Apr 11 19:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 19:50:59 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 11 19:50:59 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 11 19:50:59 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 11 19:50:59 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 11 19:50:59 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 11 19:50:59 volumio sudo[2315]: pam_unix(sudo:session): session closed for user root Apr 11 19:50:59 volumio sudo[2317]: pam_unix(sudo:session): session closed for user root Apr 11 19:50:59 volumio sudo[2322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 19:50:59 volumio sudo[2322]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:59 volumio sudo[2322]: pam_unix(sudo:session): session closed for user root Apr 11 19:50:59 volumio volumio[2123]: info: Output device has changed, restarting Shairport Sync Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 19:50:59 volumio sudo[2326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 19:50:59 volumio sudo[2326]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:59 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 11 19:50:59 volumio systemd[1]: mpd.service: Succeeded. Apr 11 19:50:59 volumio systemd[1]: Stopped Music Player Daemon. Apr 11 19:50:59 volumio volumio[2123]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 19:50:59 volumio volumio[2123]: [SpotifyConnect] Creating VLS config file Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: [SpotifyConnect] Restarting Vollibrespot Daemon Apr 11 19:50:59 volumio systemd[1]: Starting Music Player Daemon... Apr 11 19:50:59 volumio volumio[2123]: info: Shairport-Sync Started Apr 11 19:50:59 volumio volumio[2123]: Error adding Membership: Error: addMembership EINVAL Apr 11 19:50:59 volumio sudo[2335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Apr 11 19:50:59 volumio sudo[2335]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:59 volumio volumio[2123]: info: MPD Permissions set Apr 11 19:50:59 volumio volumio[2123]: info: Shairport-Sync Started Apr 11 19:50:59 volumio volumio[2123]: info: Shairport-Sync Started Apr 11 19:50:59 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Apr 11 19:50:59 volumio systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Apr 11 19:50:59 volumio systemd[1]: volspotconnect.service: Succeeded. Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::volumioGetState Apr 11 19:50:59 volumio volumio[2123]: info: CorePlayQueue::getTrack 0 Apr 11 19:50:59 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Apr 11 19:50:59 volumio systemd[1]: Started Volspotconnect2 Daemon. Apr 11 19:50:59 volumio sudo[2335]: pam_unix(sudo:session): session closed for user root Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2337]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Apr 11 19:50:59 volumio volumio[2337]: Reading Config from "/tmp/volspotify.toml" Apr 11 19:50:59 volumio volumio[2337]: [Vollibrespot] : Using Alsa backend with device: volumio Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:50:59 volumio volumio[2123]: [SpotifyConnect] Vollibrespot Daemon service restarted! Apr 11 19:50:59 volumio volumio[2123]: info: Starting Shairport Sync Apr 11 19:50:59 volumio volumio[2337]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Apr 11 19:50:59 volumio sudo[2344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 19:50:59 volumio sudo[2344]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:50:59 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 11 19:50:59 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 11 19:50:59 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 11 19:50:59 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 11 19:50:59 volumio sudo[2344]: pam_unix(sudo:session): session closed for user root Apr 11 19:50:59 volumio volumio[2123]: info: Shairport-Sync Started Apr 11 19:51:00 volumio volumio[2337]: [Vollibrespot] : couldn't parse packet from 192.168.1.50:5353: query type 65 is invalid Apr 11 19:51:00 volumio mpd[2333]: Apr 11 19:51 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 19:51:00 volumio volumio[2123]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 11 19:51:00 volumio volumio[2123]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Apr 11 19:51:00 volumio volumio[2123]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 11 19:51:00 volumio volumio[2123]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Apr 11 19:51:00 volumio volumio[2123]: info: camilladsp service started and running in background, instance 1 Apr 11 19:51:00 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 19:51:00 volumio volumio[2123]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 11 19:51:00 volumio volumio[2123]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 11 19:51:00 volumio volumio[2123]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 11 19:51:00 volumio volumio[2123]: info: FusionDsp loaded Apr 11 19:51:00 volumio volumio[2123]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 19:51:00 volumio sudo[2359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 11 19:51:00 volumio sudo[2359]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 19:51:00 volumio volumio[2123]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 11 19:51:00 volumio volumio[2123]: info: Adding Signal Path Element [object Object] Apr 11 19:51:00 volumio volumio[2123]: info: Adding fusiondspeq DSP Signal Path Element Apr 11 19:51:00 volumio volumio[2123]: info: FusionDsp - ---- installed callbackRead Apr 11 19:51:00 volumio volumio[2123]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 19:51:01 volumio sudo[2359]: pam_unix(sudo:session): session closed for user root Apr 11 19:51:01 volumio volumio[2123]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 11 19:51:01 volumio volumio[2123]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Apr 11 19:51:01 volumio volumio[2123]: at onErrorNT (internal/child_process.js:465:16) Apr 11 19:51:01 volumio volumio[2123]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 11 19:51:01 volumio volumio[2123]: at runNextTicks (internal/process/task_queues.js:62:3) Apr 11 19:51:01 volumio volumio[2123]: at listOnTimeout (internal/timers.js:523:9) Apr 11 19:51:01 volumio volumio[2123]: at processTimers (internal/timers.js:497:7) { Apr 11 19:51:01 volumio volumio[2123]: errno: -2, Apr 11 19:51:01 volumio volumio[2123]: code: 'ENOENT', Apr 11 19:51:01 volumio volumio[2123]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 11 19:51:01 volumio volumio[2123]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 11 19:51:01 volumio volumio[2123]: spawnargs: [ Apr 11 19:51:01 volumio volumio[2123]: '-p', Apr 11 19:51:01 volumio volumio[2123]: 9876, Apr 11 19:51:01 volumio volumio[2123]: '-o', Apr 11 19:51:01 volumio volumio[2123]: '/tmp/camilladsp.log', Apr 11 19:51:01 volumio volumio[2123]: '-l', Apr 11 19:51:01 volumio volumio[2123]: 'warn', Apr 11 19:51:01 volumio volumio[2123]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 11 19:51:01 volumio volumio[2123]: ] Apr 11 19:51:01 volumio volumio[2123]: } Apr 11 19:51:01 volumio volumio[2123]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 19:51:01 volumio systemd[1]: Started Music Player Daemon. Apr 11 19:51:01 volumio sudo[2278]: pam_unix(sudo:session): session closed for user root Apr 11 19:51:01 volumio sudo[2326]: pam_unix(sudo:session): session closed for user root Apr 11 19:51:01 volumio sudo[2288]: pam_unix(sudo:session): session closed for user root Apr 11 19:51:01 volumio kernel: hwmon hwmon1: Undervoltage detected! Apr 11 19:51:01 volumio volumio[2337]: [Vollibrespot] : couldn't parse packet from 192.168.1.50:5353: query type 65 is invalid Apr 11 19:51:01 volumio sudo[2374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-11 19:50 Apr 11 19:51:01 volumio sudo[2374]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"