-- Logs begin at Thu 2019-02-14 14:11:59 +04, end at Sun 2024-03-31 11:10:03 +04. -- Mar 31 11:09:03 volumio volumio[858]: info: CALLMETHOD: audio_interface fusiondsp installtools Mar 31 11:09:03 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , installtools Mar 31 11:09:06 volumio ntpd[666]: Soliciting pool server 2603:1040:900:6::1c Mar 31 11:09:11 volumio volumio[858]: --2024-03-31 11:09:03-- https://github.com/balbuze/volumio-plugins/raw/alsa_modular/plugins/audio_interface/FusionDsp/tools/tools.tar.xz Mar 31 11:09:11 volumio volumio[858]: Resolving github.com (github.com)... 20.233.83.145 Mar 31 11:09:11 volumio volumio[858]: Connecting to github.com (github.com)|20.233.83.145|:443... connected. Mar 31 11:09:11 volumio volumio[858]: HTTP request sent, awaiting response... 302 Found Mar 31 11:09:11 volumio volumio[858]: Location: https://raw.githubusercontent.com/balbuze/volumio-plugins/alsa_modular/plugins/audio_interface/FusionDsp/tools/tools.tar.xz [following] Mar 31 11:09:11 volumio volumio[858]: --2024-03-31 11:09:03-- https://raw.githubusercontent.com/balbuze/volumio-plugins/alsa_modular/plugins/audio_interface/FusionDsp/tools/tools.tar.xz Mar 31 11:09:11 volumio volumio[858]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.109.133, 185.199.110.133, 185.199.108.133, ... Mar 31 11:09:11 volumio volumio[858]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.109.133|:443... connected. Mar 31 11:09:11 volumio volumio[858]: HTTP request sent, awaiting response... 200 OK Mar 31 11:09:11 volumio volumio[858]: Length: 23035700 (22M) [application/octet-stream] Mar 31 11:09:11 volumio volumio[858]: Saving to: ‘/tmp/tools.tar.xz’ Mar 31 11:09:11 volumio volumio[858]: 0K .......... .......... .......... .......... .......... 0% 1.87M 12s Mar 31 11:09:11 volumio volumio[858]: 50K .......... .......... .......... .......... .......... 0% 2.45M 10s Mar 31 11:09:11 volumio volumio[858]: 100K .......... .......... .......... .......... .......... 0% 4.51M 8s Mar 31 11:09:11 volumio volumio[858]: 150K .......... .......... .......... .......... .......... 0% 3.50M 8s Mar 31 11:09:11 volumio volumio[858]: 200K .......... .......... .......... .......... .......... 1% 1.34M 10s Mar 31 11:09:11 volumio volumio[858]: 250K .......... .......... .......... .......... .......... 1% 2.73M 9s Mar 31 11:09:11 volumio volumio[858]: 300K .......... .......... .......... .......... .......... 1% 4.03M 9s Mar 31 11:09:11 volumio volumio[858]: 350K .......... .......... .......... .......... .......... 1% 2.22M 9s Mar 31 11:09:11 volumio volumio[858]: 400K .......... .......... .......... .......... .......... 2% 6.00M 8s Mar 31 11:09:11 volumio volumio[858]: 450K .......... .......... .......... .......... .......... 2% 1.70M 9s Mar 31 11:09:11 volumio volumio[858]: 500K .......... .......... .......... .......... .......... 2% 3.27M 8s Mar 31 11:09:11 volumio volumio[858]: 550K .......... .......... .......... .......... .......... 2% 3.47M 8s Mar 31 11:09:11 volumio volumio[858]: 600K .......... .......... .......... .......... .......... 2% 3.12M 8s Mar 31 11:09:11 volumio volumio[858]: 650K .......... .......... .......... .......... .......... 3% 3.58M 8s Mar 31 11:09:11 volumio volumio[858]: 700K .......... .......... .......... .......... .......... 3% 5.38M 8s Mar 31 11:09:11 volumio volumio[858]: 750K .......... .......... .......... .......... .......... 3% 2.00M 8s Mar 31 11:09:11 volumio volumio[858]: 800K .......... .......... .......... .......... .......... 3% 4.32M 8s Mar 31 11:09:11 volumio volumio[858]: 850K .......... .......... .......... .......... .......... 4% 2.97M 8s Mar 31 11:09:11 volumio volumio[858]: 900K .......... .......... .......... .......... .......... 4% 4.13M 7s Mar 31 11:09:11 volumio volumio[858]: 950K .......... .......... .......... .......... .......... 4% 3.04M 7s Mar 31 11:09:11 volumio volumio[858]: 1000K .......... .......... .......... .......... .......... 4% 3.93M 7s Mar 31 11:09:11 volumio volumio[858]: 1050K .......... .......... .......... .......... .......... 4% 4.09M 7s Mar 31 11:09:11 volumio volumio[858]: 1100K .......... .......... .......... .......... .......... 5% 5.37M 7s Mar 31 11:09:11 volumio volumio[858]: 1150K .......... .......... .......... .......... .......... 5% 2.08M 7s Mar 31 11:09:11 volumio volumio[858]: 1200K .......... .......... .......... .......... .......... 5% 2.53M 7s Mar 31 11:09:11 volumio volumio[858]: 1250K .......... .......... .......... .......... .......... 5% 3.99M 7s Mar 31 11:09:11 volumio volumio[858]: 1300K .......... .......... .......... .......... .......... 6% 3.65M 7s Mar 31 11:09:11 volumio volumio[858]: 1350K .......... .......... .......... .......... .......... 6% 2.59M 7s Mar 31 11:09:11 volumio volumio[858]: 1400K .......... .......... .......... .......... .......... 6% 4.02M 7s Mar 31 11:09:11 volumio volumio[858]: 1450K .......... .......... .......... .......... .......... 6% 3.05M 7s Mar 31 11:09:11 volumio volumio[858]: 1500K .......... .......... .......... .......... .......... 6% 3.13M 7s Mar 31 11:09:11 volumio volumio[858]: 1550K .......... .......... .......... .......... .......... 7% 2.96M 7s Mar 31 11:09:11 volumio volumio[858]: 1600K .......... .......... .......... .......... .......... 7% 3.30M 7s Mar 31 11:09:11 volumio volumio[858]: 1650K .......... .......... .......... .......... .......... 7% 4.38M 7s Mar 31 11:09:11 volumio volumio[858]: 1700K .......... .......... .......... .......... .......... 7% 4.00M 7s Mar 31 11:09:11 volumio volumio[858]: 1750K .......... .......... .......... .......... .......... 8% 4.16M 7s Mar 31 11:09:11 volumio volumio[858]: 1800K .......... .......... .......... .......... .......... 8% 3.79M 7s Mar 31 11:09:11 volumio volumio[858]: 1850K .......... .......... .......... .......... .......... 8% 2.50M 7s Mar 31 11:09:11 volumio volumio[858]: 1900K .......... .......... .......... .......... .......... 8% 3.24M 7s Mar 31 11:09:11 volumio volumio[858]: 1950K .......... .......... .......... .......... .......... 8% 3.12M 7s Mar 31 11:09:11 volumio volumio[858]: 2000K .......... .......... .......... .......... .......... 9% 3.76M 6s Mar 31 11:09:11 volumio volumio[858]: 2050K .......... .......... .......... .......... .......... 9% 4.34M 6s Mar 31 11:09:11 volumio volumio[858]: 2100K .......... .......... .......... .......... .......... 9% 3.86M 6s Mar 31 11:09:11 volumio volumio[858]: 2150K .......... .......... .......... .......... .......... 9% 3.64M 6s Mar 31 11:09:11 volumio volumio[858]: 2200K .......... .......... .......... .......... .......... 10% 2.30M 6s Mar 31 11:09:11 volumio volumio[858]: 2250K .......... .......... .......... .......... .......... 10% 2.11M 6s Mar 31 11:09:11 volumio volumio[858]: 2300K .......... .......... .......... .......... .......... 10% 3.57M 6s Mar 31 11:09:11 volumio volumio[858]: 2350K .......... .......... .......... .......... .......... 10% 3.01M 6s Mar 31 11:09:11 volumio volumio[858]: 2400K .......... .......... .......... .......... .......... 10% 5.36M 6s Mar 31 11:09:11 volumio volumio[858]: 2450K .......... .......... .......... .......... .......... 11% 3.17M 6s Mar 31 11:09:11 volumio volumio[858]: 2500K .......... .......... .......... .......... .......... 11% 2.17M 6s Mar 31 11:09:11 volumio volumio[858]: 2550K .......... .......... .......... .......... .......... 11% 3.47M 6s Mar 31 11:09:11 volumio volumio[858]: 2600K .......... .......... .......... .......... .......... 11% 4.02M 6s Mar 31 11:09:11 volumio volumio[858]: 2650K .......... .......... .......... .......... .......... 12% 4.05M 6s Mar 31 11:09:11 volumio volumio[858]: 2700K .......... .......... .......... .......... .......... 12% 4.43M 6s Mar 31 11:09:11 volumio volumio[858]: 2750K .......... .......... .......... .......... .......... 12% 3.04M 6s Mar 31 11:09:11 volumio volumio[858]: 2800K .......... .......... .......... .......... .......... 12% 4.10M 6s Mar 31 11:09:11 volumio volumio[858]: 2850K .......... .......... .......... .......... .......... 12% 3.37M 6s Mar 31 11:09:11 volumio volumio[858]: 2900K .......... .......... .......... .......... .......... 13% 2.39M 6s Mar 31 11:09:11 volumio volumio[858]: 2950K .......... .......... .......... .......... .......... 13% 3.95M 6s Mar 31 11:09:11 volumio volumio[858]: 3000K .......... .......... .......... .......... .......... 13% 4.46M 6s Mar 31 11:09:11 volumio volumio[858]: 3050K .......... .......... .......... .......... .......... 13% 4.39M 6s Mar 31 11:09:11 volumio volumio[858]: 3100K .......... .......... .......... .......... .......... 14% 5.38M 6s Mar 31 11:09:11 volumio volumio[858]: 3150K .......... .......... .......... .......... .......... 14% 2.42M 6s Mar 31 11:09:11 volumio volumio[858]: 3200K .......... .......... .......... .......... .......... 14% 2.91M 6s Mar 31 11:09:11 volumio volumio[858]: 3250K .......... .......... .......... .......... .......... 14% 3.79M 6s Mar 31 11:09:11 volumio volumio[858]: 3300K .......... .......... .......... .......... .......... 14% 3.08M 6s Mar 31 11:09:11 volumio volumio[858]: 3350K .......... .......... .......... .......... .......... 15% 2.66M 6s Mar 31 11:09:11 volumio volumio[858]: 3400K .......... .......... .......... .......... .......... 15% 3.97M 6s Mar 31 11:09:11 volumio volumio[858]: 3450K .......... .......... .......... .......... .......... 15% 4.14M 6s Mar 31 11:09:11 volumio volumio[858]: 3500K .......... .......... .......... .......... .......... 15% 5.51M 6s Mar 31 11:09:11 volumio volumio[858]: 3550K .......... .......... .......... .......... .......... 16% 3.20M 6s Mar 31 11:09:11 volumio volumio[858]: 3600K .......... .......... .......... .......... .......... 16% 2.35M 6s Mar 31 11:09:11 volumio volumio[858]: 3650K .......... .......... .......... .......... .......... 16% 2.86M 6s Mar 31 11:09:11 volumio volumio[858]: 3700K .......... .......... .......... .......... .......... 16% 5.85M 6s Mar 31 11:09:11 volumio volumio[858]: 3750K .......... .......... .......... .......... .......... 16% 4.23M 6s Mar 31 11:09:11 volumio volumio[858]: 3800K .......... .......... .......... .......... .......... 17% 3.64M 6s Mar 31 11:09:11 volumio volumio[858]: 3850K .......... .......... .......... .......... .......... 17% 3.96M 6s Mar 31 11:09:11 volumio volumio[858]: 3900K .......... .......... .......... .......... .......... 17% 4.23M 6s Mar 31 11:09:11 volumio volumio[858]: 3950K .......... .......... .......... .......... .......... 17% 1.73M 6s Mar 31 11:09:11 volumio volumio[858]: 4000K .......... .......... .......... .......... .......... 18% 2.45M 6s Mar 31 11:09:11 volumio volumio[858]: 4050K .......... .......... .......... .......... .......... 18% 3.97M 6s Mar 31 11:09:11 volumio volumio[858]: 4100K .......... .......... .......... .......... .......... 18% 4.18M 6s Mar 31 11:09:11 volumio volumio[858]: 4150K .......... .......... .......... .......... .......... 18% 4.77M 6s Mar 31 11:09:11 volumio volumio[858]: 4200K .......... .......... .......... .......... .......... 18% 2.59M 6s Mar 31 11:09:11 volumio volumio[858]: 4250K .......... .......... .......... .......... .......... 19% 2.51M 6s Mar 31 11:09:11 volumio volumio[858]: 4300K .......... .......... .......... .......... .......... 19% 4.07M 6s Mar 31 11:09:11 volumio volumio[858]: 4350K .......... .......... .......... .......... .......... 19% 2.65M 6s Mar 31 11:09:11 volumio volumio[858]: 4400K .......... .......... .......... .......... .......... 19% 3.92M 5s Mar 31 11:09:11 volumio volumio[858]: 4450K .......... .......... .......... .......... .......... 20% 4.82M 5s Mar 31 11:09:11 volumio volumio[858]: 4500K .......... .......... .......... .......... .......... 20% 5.40M 5s Mar 31 11:09:11 volumio volumio[858]: 4550K .......... .......... .......... .......... .......... 20% 4.06M 5s Mar 31 11:09:11 volumio volumio[858]: 4600K .......... .......... .......... .......... .......... 20% 5.35M 5s Mar 31 11:09:11 volumio volumio[858]: 4650K .......... .......... .......... .......... .......... 20% 4.07M 5s Mar 31 11:09:11 volumio volumio[858]: 4700K .......... .......... .......... .......... .......... 21% 1.72M 5s Mar 31 11:09:11 volumio volumio[858]: 4750K .......... .......... .......... .......... .......... 21% 2.13M 5s Mar 31 11:09:11 volumio volumio[858]: 4800K .......... .......... .......... .......... .......... 21% 4.13M 5s Mar 31 11:09:11 volumio volumio[858]: 4850K .......... .......... .......... .......... .......... 21% 5.38M 5s Mar 31 11:09:11 volumio volumio[858]: 4900K .......... .......... .......... .......... .......... 22% 4.06M 5s Mar 31 11:09:11 volumio volumio[858]: 4950K .......... .......... .......... .......... .......... 22% 5.38M 5s Mar 31 11:09:11 volumio volumio[858]: 5000K .......... .......... .......... .......... .......... 22% 2.10M 5s Mar 31 11:09:11 volumio volumio[858]: 5050K .......... .......... .......... .......... .......... 22% 2.27M 5s Mar 31 11:09:11 volumio volumio[858]: 5100K .......... .......... .......... .......... .......... 22% 3.12M 5s Mar 31 11:09:11 volumio volumio[858]: 5150K .......... .......... .......... .......... .......... 23% 1018K 5s Mar 31 11:09:11 volumio volumio[858]: 5200K .......... .......... .......... .......... .......... 23% 852K 5s Mar 31 11:09:11 volumio volumio[858]: 5250K .......... .......... .......... .......... .......... 23% 25.1M 5s Mar 31 11:09:11 volumio volumio[858]: 5300K .......... .......... .......... .......... .......... 23% 24.8M 5s Mar 31 11:09:11 volumio volumio[858]: 5350K .......... .......... .......... .......... .......... 24% 3.06M 5s Mar 31 11:09:11 volumio volumio[858]: 5400K .......... .......... .......... .......... .......... 24% 25.3M 5s Mar 31 11:09:11 volumio volumio[858]: 5450K .......... .......... .......... .......... .......... 24% 25.2M 5s Mar 31 11:09:11 volumio volumio[858]: 5500K .......... .......... .......... .......... .......... 24% 25.0M 5s Mar 31 11:09:11 volumio volumio[858]: 5550K .......... .......... .......... .......... .......... 24% 6.72M 5s Mar 31 11:09:11 volumio volumio[858]: 5600K .......... .......... .......... .......... .......... 25% 4.78M 5s Mar 31 11:09:11 volumio volumio[858]: 5650K .......... .......... .......... .......... .......... 25% 1.74M 5s Mar 31 11:09:11 volumio volumio[858]: 5700K .......... .......... .......... .......... .......... 25% 2.47M 5s Mar 31 11:09:11 volumio volumio[858]: 5750K .......... .......... .......... .......... .......... 25% 3.03M 5s Mar 31 11:09:11 volumio volumio[858]: 5800K .......... .......... .......... .......... .......... 26% 3.91M 5s Mar 31 11:09:11 volumio volumio[858]: 5850K .......... .......... .......... .......... .......... 26% 3.68M 5s Mar 31 11:09:11 volumio volumio[858]: 5900K .......... .......... .......... .......... .......... 26% 1.78M 5s Mar 31 11:09:11 volumio volumio[858]: 5950K .......... .......... .......... .......... .......... 26% 1.61M 5s Mar 31 11:09:11 volumio volumio[858]: 6000K .......... .......... .......... .......... .......... 26% 2.02M 5s Mar 31 11:09:11 volumio volumio[858]: 6050K .......... .......... .......... .......... .......... 27% 4.09M 5s Mar 31 11:09:11 volumio volumio[858]: 6100K .......... .......... .......... .......... .......... 27% 2.86M 5s Mar 31 11:09:11 volumio volumio[858]: 6150K .......... .......... .......... .......... .......... 27% 3.41M 5s Mar 31 11:09:11 volumio volumio[858]: 6200K .......... .......... .......... .......... .......... 27% 2.22M 5s Mar 31 11:09:11 volumio volumio[858]: 6250K .......... .......... .......... .......... .......... 28% 3.70M 5s Mar 31 11:09:11 volumio volumio[858]: 6300K .......... .......... .......... .......... .......... 28% 5.65M 5s Mar 31 11:09:11 volumio volumio[858]: 6350K .......... .......... .......... .......... .......... 28% 2.04M 5s Mar 31 11:09:11 volumio volumio[858]: 6400K .......... .......... .......... .......... .......... 28% 3.66M 5s Mar 31 11:09:11 volumio volumio[858]: 6450K .......... .......... .......... .......... .......... 28% 3.48M 5s Mar 31 11:09:11 volumio volumio[858]: 6500K .......... .......... .......... .......... .......... 29% 1.70M 5s Mar 31 11:09:11 volumio volumio[858]: 6550K .......... .......... .......... .......... .......... 29% 2.61M 5s Mar 31 11:09:11 volumio volumio[858]: 6600K .......... .......... .......... .......... .......... 29% 4.12M 5s Mar 31 11:09:11 volumio volumio[858]: 6650K .......... .......... .......... .......... .......... 29% 4.15M 5s Mar 31 11:09:11 volumio volumio[858]: 6700K .......... .......... .......... .......... .......... 30% 5.53M 5s Mar 31 11:09:11 volumio volumio[858]: 6750K .......... .......... .......... .......... .......... 30% 2.82M 5s Mar 31 11:09:11 volumio volumio[858]: 6800K .......... .......... .......... .......... .......... 30% 1.40M 5s Mar 31 11:09:11 volumio volumio[858]: 6850K .......... .......... .......... .......... .......... 30% 2.82M 5s Mar 31 11:09:11 volumio volumio[858]: 6900K .......... .......... .......... .......... .......... 30% 3.96M 5s Mar 31 11:09:11 volumio volumio[858]: 6950K .......... .......... .......... .......... .......... 31% 5.64M 5s Mar 31 11:09:11 volumio volumio[858]: 7000K .......... .......... .......... .......... .......... 31% 4.04M 5s Mar 31 11:09:11 volumio volumio[858]: 7050K .......... .......... .......... .......... .......... 31% 5.44M 5s Mar 31 11:09:11 volumio volumio[858]: 7100K .......... .......... .......... .......... .......... 31% 3.83M 5s Mar 31 11:09:11 volumio volumio[858]: 7150K .......... .......... .......... .......... .......... 32% 1.40M 5s Mar 31 11:09:11 volumio volumio[858]: 7200K .......... .......... .......... .......... .......... 32% 3.91M 5s Mar 31 11:09:11 volumio volumio[858]: 7250K .......... .......... .......... .......... .......... 32% 3.11M 5s Mar 31 11:09:11 volumio volumio[858]: 7300K .......... .......... .......... .......... .......... 32% 3.38M 5s Mar 31 11:09:11 volumio volumio[858]: 7350K .......... .......... .......... .......... .......... 32% 4.09M 5s Mar 31 11:09:11 volumio volumio[858]: 7400K .......... .......... .......... .......... .......... 33% 3.81M 5s Mar 31 11:09:11 volumio volumio[858]: 7450K .......... .......... .......... .......... .......... 33% 1.92M 5s Mar 31 11:09:11 volumio volumio[858]: 7500K .......... .......... .......... .......... .......... 33% 4.01M 5s Mar 31 11:09:11 volumio volumio[858]: 7550K .......... .......... .......... .......... .......... 33% 3.29M 5s Mar 31 11:09:11 volumio volumio[858]: 7600K .......... .......... .......... .......... .......... 34% 5.35M 5s Mar 31 11:09:11 volumio volumio[858]: 7650K .......... .......... .......... .......... .......... 34% 4.14M 5s Mar 31 11:09:11 volumio volumio[858]: 7700K .......... .......... .......... .......... .......... 34% 4.14M 5s Mar 31 11:09:11 volumio volumio[858]: 7750K .......... .......... .......... .......... .......... 34% 5.09M 5s Mar 31 11:09:11 volumio volumio[858]: 7800K .......... .......... .......... .......... .......... 34% 1.51M 5s Mar 31 11:09:11 volumio volumio[858]: 7850K .......... .......... .......... .......... .......... 35% 4.75M 5s Mar 31 11:09:11 volumio volumio[858]: 7900K .......... .......... .......... .......... .......... 35% 3.89M 5s Mar 31 11:09:11 volumio volumio[858]: 7950K .......... .......... .......... .......... .......... 35% 3.42M 5s Mar 31 11:09:11 volumio volumio[858]: 8000K .......... .......... .......... .......... .......... 35% 5.43M 4s Mar 31 11:09:11 volumio volumio[858]: 8050K .......... .......... .......... .......... .......... 36% 4.18M 4s Mar 31 11:09:11 volumio volumio[858]: 8100K .......... .......... .......... .......... .......... 36% 5.30M 4s Mar 31 11:09:11 volumio volumio[858]: 8150K .......... .......... .......... .......... .......... 36% 2.43M 4s Mar 31 11:09:11 volumio volumio[858]: 8200K .......... .......... .......... .......... .......... 36% 5.13M 4s Mar 31 11:09:11 volumio volumio[858]: 8250K .......... .......... .......... .......... .......... 36% 2.91M 4s Mar 31 11:09:11 volumio volumio[858]: 8300K .......... .......... .......... .......... .......... 37% 5.34M 4s Mar 31 11:09:11 volumio volumio[858]: 8350K .......... .......... .......... .......... .......... 37% 3.30M 4s Mar 31 11:09:11 volumio volumio[858]: 8400K .......... .......... .......... .......... .......... 37% 3.98M 4s Mar 31 11:09:11 volumio volumio[858]: 8450K .......... .......... .......... .......... .......... 37% 4.39M 4s Mar 31 11:09:11 volumio volumio[858]: 8500K .......... .......... .......... .......... .......... 38% 4.32M 4s Mar 31 11:09:11 volumio volumio[858]: 8550K .......... .......... .......... .......... .......... 38% 2.49M 4s Mar 31 11:09:11 volumio volumio[858]: 8600K .......... .......... .......... .......... .......... 38% 3.57M 4s Mar 31 11:09:11 volumio volumio[858]: 8650K .......... .......... .......... .......... .......... 38% 3.72M 4s Mar 31 11:09:11 volumio volumio[858]: 8700K .......... .......... .......... .......... .......... 38% 5.46M 4s Mar 31 11:09:11 volumio volumio[858]: 8750K .......... .......... .......... .......... .......... 39% 3.26M 4s Mar 31 11:09:11 volumio volumio[858]: 8800K .......... .......... .......... .......... .......... 39% 4.07M 4s Mar 31 11:09:11 volumio volumio[858]: 8850K .......... .......... .......... .......... .......... 39% 5.45M 4s Mar 31 11:09:11 volumio volumio[858]: 8900K .......... .......... .......... .......... .......... 39% 4.46M 4s Mar 31 11:09:11 volumio volumio[858]: 8950K .......... .......... .......... .......... .......... 40% 1.77M 4s Mar 31 11:09:11 volumio volumio[858]: 9000K .......... .......... .......... .......... .......... 40% 2.92M 4s Mar 31 11:09:11 volumio volumio[858]: 9050K .......... .......... .......... .......... .......... 40% 5.33M 4s Mar 31 11:09:11 volumio volumio[858]: 9100K .......... .......... .......... .......... .......... 40% 5.30M 4s Mar 31 11:09:11 volumio volumio[858]: 9150K .......... .......... .......... .......... .......... 40% 3.96M 4s Mar 31 11:09:11 volumio volumio[858]: 9200K .......... .......... .......... .......... .......... 41% 5.72M 4s Mar 31 11:09:11 volumio volumio[858]: 9250K .......... .......... .......... .......... .......... 41% 5.47M 4s Mar 31 11:09:11 volumio volumio[858]: 9300K .......... .......... .......... .......... .......... 41% 5.36M 4s Mar 31 11:09:11 volumio volumio[858]: 9350K .......... .......... .......... .......... .......... 41% 3.26M 4s Mar 31 11:09:11 volumio volumio[858]: 9400K .......... .......... .......... .......... .......... 42% 4.02M 4s Mar 31 11:09:11 volumio volumio[858]: 9450K .......... .......... .......... .......... .......... 42% 2.79M 4s Mar 31 11:09:11 volumio volumio[858]: 9500K .......... .......... .......... .......... .......... 42% 3.85M 4s Mar 31 11:09:11 volumio volumio[858]: 9550K .......... .......... .......... .......... .......... 42% 4.14M 4s Mar 31 11:09:11 volumio volumio[858]: 9600K .......... .......... .......... .......... .......... 42% 5.37M 4s Mar 31 11:09:11 volumio volumio[858]: 9650K .......... .......... .......... .......... .......... 43% 5.60M 4s Mar 31 11:09:11 volumio volumio[858]: 9700K .......... .......... .......... .......... .......... 43% 5.12M 4s Mar 31 11:09:11 volumio volumio[858]: 9750K .......... .......... .......... .......... .......... 43% 5.00M 4s Mar 31 11:09:11 volumio volumio[858]: 9800K .......... .......... .......... .......... .......... 43% 2.52M 4s Mar 31 11:09:11 volumio volumio[858]: 9850K .......... .......... .......... .......... .......... 44% 5.48M 4s Mar 31 11:09:11 volumio volumio[858]: 9900K .......... .......... .......... .......... .......... 44% 4.01M 4s Mar 31 11:09:11 volumio volumio[858]: 9950K .......... .......... .......... .......... .......... 44% 4.19M 4s Mar 31 11:09:11 volumio volumio[858]: 10000K .......... .......... .......... .......... .......... 44% 4.83M 4s Mar 31 11:09:11 volumio volumio[858]: 10050K .......... .......... .......... .......... .......... 44% 5.53M 4s Mar 31 11:09:11 volumio volumio[858]: 10100K .......... .......... .......... .......... .......... 45% 2.45M 4s Mar 31 11:09:11 volumio volumio[858]: 10150K .......... .......... .......... .......... .......... 45% 1.76M 4s Mar 31 11:09:11 volumio volumio[858]: 10200K .......... .......... .......... .......... .......... 45% 1.72M 4s Mar 31 11:09:11 volumio volumio[858]: 10250K .......... .......... .......... .......... .......... 45% 2.13M 4s Mar 31 11:09:11 volumio volumio[858]: 10300K .......... .......... .......... .......... .......... 46% 1.25M 4s Mar 31 11:09:11 volumio volumio[858]: 10350K .......... .......... .......... .......... .......... 46% 3.05M 4s Mar 31 11:09:11 volumio volumio[858]: 10400K .......... .......... .......... .......... .......... 46% 2.46M 4s Mar 31 11:09:11 volumio volumio[858]: 10450K .......... .......... .......... .......... .......... 46% 5.60M 4s Mar 31 11:09:11 volumio volumio[858]: 10500K .......... .......... .......... .......... .......... 46% 5.31M 4s Mar 31 11:09:11 volumio volumio[858]: 10550K .......... .......... .......... .......... .......... 47% 5.33M 4s Mar 31 11:09:11 volumio volumio[858]: 10600K .......... .......... .......... .......... .......... 47% 5.41M 4s Mar 31 11:09:11 volumio volumio[858]: 10650K .......... .......... .......... .......... .......... 47% 5.38M 4s Mar 31 11:09:11 volumio volumio[858]: 10700K .......... .......... .......... .......... .......... 47% 5.22M 4s Mar 31 11:09:11 volumio volumio[858]: 10750K .......... .......... .......... .......... .......... 48% 4.20M 4s Mar 31 11:09:11 volumio volumio[858]: 10800K .......... .......... .......... .......... .......... 48% 5.33M 3s Mar 31 11:09:11 volumio volumio[858]: 10850K .......... .......... .......... .......... .......... 48% 5.46M 3s Mar 31 11:09:11 volumio volumio[858]: 10900K .......... .......... .......... .......... .......... 48% 3.87M 3s Mar 31 11:09:11 volumio volumio[858]: 10950K .......... .......... .......... .......... .......... 48% 3.59M 3s Mar 31 11:09:11 volumio volumio[858]: 11000K .......... .......... .......... .......... .......... 49% 5.06M 3s Mar 31 11:09:11 volumio volumio[858]: 11050K .......... .......... .......... .......... .......... 49% 3.92M 3s Mar 31 11:09:11 volumio volumio[858]: 11100K .......... .......... .......... .......... .......... 49% 5.46M 3s Mar 31 11:09:11 volumio volumio[858]: 11150K .......... .......... .......... .......... .......... 49% 3.89M 3s Mar 31 11:09:11 volumio volumio[858]: 11200K .......... .......... .......... .......... .......... 50% 5.72M 3s Mar 31 11:09:11 volumio volumio[858]: 11250K .......... .......... .......... .......... .......... 50% 2.14M 3s Mar 31 11:09:11 volumio volumio[858]: 11300K .......... .......... .......... .......... .......... 50% 2.90M 3s Mar 31 11:09:11 volumio volumio[858]: 11350K .......... .......... .......... .......... .......... 50% 5.24M 3s Mar 31 11:09:11 volumio volumio[858]: 11400K .......... .......... .......... .......... .......... 50% 7.91M 3s Mar 31 11:09:11 volumio volumio[858]: 11450K .......... .......... .......... .......... .......... 51% 4.06M 3s Mar 31 11:09:11 volumio volumio[858]: 11500K .......... .......... .......... .......... .......... 51% 5.49M 3s Mar 31 11:09:11 volumio volumio[858]: 11550K .......... .......... .......... .......... .......... 51% 4.20M 3s Mar 31 11:09:11 volumio volumio[858]: 11600K .......... .......... .......... .......... .......... 51% 5.19M 3s Mar 31 11:09:11 volumio volumio[858]: 11650K .......... .......... .......... .......... .......... 52% 5.48M 3s Mar 31 11:09:11 volumio volumio[858]: 11700K .......... .......... .......... .......... .......... 52% 2.11M 3s Mar 31 11:09:11 volumio volumio[858]: 11750K .......... .......... .......... .......... .......... 52% 3.16M 3s Mar 31 11:09:11 volumio volumio[858]: 11800K .......... .......... .......... .......... .......... 52% 8.24M 3s Mar 31 11:09:11 volumio volumio[858]: 11850K .......... .......... .......... .......... .......... 52% 5.65M 3s Mar 31 11:09:11 volumio volumio[858]: 11900K .......... .......... .......... .......... .......... 53% 5.25M 3s Mar 31 11:09:11 volumio volumio[858]: 11950K .......... .......... .......... .......... .......... 53% 4.09M 3s Mar 31 11:09:11 volumio volumio[858]: 12000K .......... .......... .......... .......... .......... 53% 4.09M 3s Mar 31 11:09:11 volumio volumio[858]: 12050K .......... .......... .......... .......... .......... 53% 5.62M 3s Mar 31 11:09:11 volumio volumio[858]: 12100K .......... .......... .......... .......... .......... 54% 4.76M 3s Mar 31 11:09:11 volumio volumio[858]: 12150K .......... .......... .......... .......... .......... 54% 3.25M 3s Mar 31 11:09:11 volumio volumio[858]: 12200K .......... .......... .......... .......... .......... 54% 3.21M 3s Mar 31 11:09:11 volumio volumio[858]: 12250K .......... .......... .......... .......... .......... 54% 8.15M 3s Mar 31 11:09:11 volumio volumio[858]: 12300K .......... .......... .......... .......... .......... 54% 5.37M 3s Mar 31 11:09:11 volumio volumio[858]: 12350K .......... .......... .......... .......... .......... 55% 4.19M 3s Mar 31 11:09:11 volumio volumio[858]: 12400K .......... .......... .......... .......... .......... 55% 5.28M 3s Mar 31 11:09:11 volumio volumio[858]: 12450K .......... .......... .......... .......... .......... 55% 3.65M 3s Mar 31 11:09:11 volumio volumio[858]: 12500K .......... .......... .......... .......... .......... 55% 4.37M 3s Mar 31 11:09:11 volumio volumio[858]: 12550K .......... .......... .......... .......... .......... 56% 3.97M 3s Mar 31 11:09:11 volumio volumio[858]: 12600K .......... .......... .......... .......... .......... 56% 3.63M 3s Mar 31 11:09:11 volumio volumio[858]: 12650K .......... .......... .......... .......... .......... 56% 2.35M 3s Mar 31 11:09:11 volumio volumio[858]: 12700K .......... .......... .......... .......... .......... 56% 3.75M 3s Mar 31 11:09:11 volumio volumio[858]: 12750K .......... .......... .......... .......... .......... 56% 2.21M 3s Mar 31 11:09:11 volumio volumio[858]: 12800K .......... .......... .......... .......... .......... 57% 2.58M 3s Mar 31 11:09:11 volumio volumio[858]: 12850K .......... .......... .......... .......... .......... 57% 5.36M 3s Mar 31 11:09:11 volumio volumio[858]: 12900K .......... .......... .......... .......... .......... 57% 3.32M 3s Mar 31 11:09:11 volumio volumio[858]: 12950K .......... .......... .......... .......... .......... 57% 4.28M 3s Mar 31 11:09:11 volumio volumio[858]: 13000K .......... .......... .......... .......... .......... 58% 4.08M 3s Mar 31 11:09:11 volumio volumio[858]: 13050K .......... .......... .......... .......... .......... 58% 5.23M 3s Mar 31 11:09:11 volumio volumio[858]: 13100K .......... .......... .......... .......... .......... 58% 5.66M 3s Mar 31 11:09:11 volumio volumio[858]: 13150K .......... .......... .......... .......... .......... 58% 3.32M 3s Mar 31 11:09:11 volumio volumio[858]: 13200K .......... .......... .......... .......... .......... 58% 3.87M 3s Mar 31 11:09:11 volumio volumio[858]: 13250K .......... .......... .......... .......... .......... 59% 5.65M 3s Mar 31 11:09:11 volumio volumio[858]: 13300K .......... .......... .......... .......... .......... 59% 4.03M 3s Mar 31 11:09:11 volumio volumio[858]: 13350K .......... .......... .......... .......... .......... 59% 1.77M 3s Mar 31 11:09:11 volumio volumio[858]: 13400K .......... .......... .......... .......... .......... 59% 4.05M 3s Mar 31 11:09:11 volumio volumio[858]: 13450K .......... .......... .......... .......... .......... 60% 5.47M 3s Mar 31 11:09:11 volumio volumio[858]: 13500K .......... .......... .......... .......... .......... 60% 5.37M 3s Mar 31 11:09:11 volumio volumio[858]: 13550K .......... .......... .......... .......... .......... 60% 4.06M 3s Mar 31 11:09:11 volumio volumio[858]: 13600K .......... .......... .......... .......... .......... 60% 5.46M 3s Mar 31 11:09:11 volumio volumio[858]: 13650K .......... .......... .......... .......... .......... 60% 5.31M 3s Mar 31 11:09:11 volumio volumio[858]: 13700K .......... .......... .......... .......... .......... 61% 5.40M 3s Mar 31 11:09:11 volumio volumio[858]: 13750K .......... .......... .......... .......... .......... 61% 5.88M 2s Mar 31 11:09:11 volumio volumio[858]: 13800K .......... .......... .......... .......... .......... 61% 2.65M 2s Mar 31 11:09:11 volumio volumio[858]: 13850K .......... .......... .......... .......... .......... 61% 3.98M 2s Mar 31 11:09:11 volumio volumio[858]: 13900K .......... .......... .......... .......... .......... 62% 5.37M 2s Mar 31 11:09:11 volumio volumio[858]: 13950K .......... .......... .......... .......... .......... 62% 4.21M 2s Mar 31 11:09:11 volumio volumio[858]: 14000K .......... .......... .......... .......... .......... 62% 5.26M 2s Mar 31 11:09:11 volumio volumio[858]: 14050K .......... .......... .......... .......... .......... 62% 5.47M 2s Mar 31 11:09:11 volumio volumio[858]: 14100K .......... .......... .......... .......... .......... 62% 5.24M 2s Mar 31 11:09:11 volumio volumio[858]: 14150K .......... .......... .......... .......... .......... 63% 6.21M 2s Mar 31 11:09:11 volumio volumio[858]: 14200K .......... .......... .......... .......... .......... 63% 5.38M 2s Mar 31 11:09:11 volumio volumio[858]: 14250K .......... .......... .......... .......... .......... 63% 3.02M 2s Mar 31 11:09:11 volumio volumio[858]: 14300K .......... .......... .......... .......... .......... 63% 5.27M 2s Mar 31 11:09:11 volumio volumio[858]: 14350K .......... .......... .......... .......... .......... 64% 2.76M 2s Mar 31 11:09:11 volumio volumio[858]: 14400K .......... .......... .......... .......... .......... 64% 7.44M 2s Mar 31 11:09:11 volumio volumio[858]: 14450K .......... .......... .......... .......... .......... 64% 4.26M 2s Mar 31 11:09:11 volumio volumio[858]: 14500K .......... .......... .......... .......... .......... 64% 5.47M 2s Mar 31 11:09:11 volumio volumio[858]: 14550K .......... .......... .......... .......... .......... 64% 5.32M 2s Mar 31 11:09:11 volumio volumio[858]: 14600K .......... .......... .......... .......... .......... 65% 4.44M 2s Mar 31 11:09:11 volumio volumio[858]: 14650K .......... .......... .......... .......... .......... 65% 5.49M 2s Mar 31 11:09:11 volumio volumio[858]: 14700K .......... .......... .......... .......... .......... 65% 5.26M 2s Mar 31 11:09:11 volumio volumio[858]: 14750K .......... .......... .......... .......... .......... 65% 2.38M 2s Mar 31 11:09:11 volumio volumio[858]: 14800K .......... .......... .......... .......... .......... 66% 3.97M 2s Mar 31 11:09:11 volumio volumio[858]: 14850K .......... .......... .......... .......... .......... 66% 5.62M 2s Mar 31 11:09:11 volumio volumio[858]: 14900K .......... .......... .......... .......... .......... 66% 5.18M 2s Mar 31 11:09:11 volumio volumio[858]: 14950K .......... .......... .......... .......... .......... 66% 7.12M 2s Mar 31 11:09:11 volumio volumio[858]: 15000K .......... .......... .......... .......... .......... 66% 5.30M 2s Mar 31 11:09:11 volumio volumio[858]: 15050K .......... .......... .......... .......... .......... 67% 4.86M 2s Mar 31 11:09:11 volumio volumio[858]: 15100K .......... .......... .......... .......... .......... 67% 5.40M 2s Mar 31 11:09:11 volumio volumio[858]: 15150K .......... .......... .......... .......... .......... 67% 4.10M 2s Mar 31 11:09:11 volumio volumio[858]: 15200K .......... .......... .......... .......... .......... 67% 1.43M 2s Mar 31 11:09:11 volumio volumio[858]: 15250K .......... .......... .......... .......... .......... 68% 5.41M 2s Mar 31 11:09:11 volumio volumio[858]: 15300K .......... .......... .......... .......... .......... 68% 6.49M 2s Mar 31 11:09:11 volumio volumio[858]: 15350K .......... .......... .......... .......... .......... 68% 4.60M 2s Mar 31 11:09:11 volumio volumio[858]: 15400K .......... .......... .......... .......... .......... 68% 7.36M 2s Mar 31 11:09:11 volumio volumio[858]: 15450K .......... .......... .......... .......... .......... 68% 4.31M 2s Mar 31 11:09:11 volumio volumio[858]: 15500K .......... .......... .......... .......... .......... 69% 5.62M 2s Mar 31 11:09:11 volumio volumio[858]: 15550K .......... .......... .......... .......... .......... 69% 3.99M 2s Mar 31 11:09:11 volumio volumio[858]: 15600K .......... .......... .......... .......... .......... 69% 3.50M 2s Mar 31 11:09:11 volumio volumio[858]: 15650K .......... .......... .......... .......... .......... 69% 4.32M 2s Mar 31 11:09:11 volumio volumio[858]: 15700K .......... .......... .......... .......... .......... 70% 4.40M 2s Mar 31 11:09:11 volumio volumio[858]: 15750K .......... .......... .......... .......... .......... 70% 5.35M 2s Mar 31 11:09:11 volumio volumio[858]: 15800K .......... .......... .......... .......... .......... 70% 5.48M 2s Mar 31 11:09:11 volumio volumio[858]: 15850K .......... .......... .......... .......... .......... 70% 4.14M 2s Mar 31 11:09:11 volumio volumio[858]: 15900K .......... .......... .......... .......... .......... 70% 4.75M 2s Mar 31 11:09:11 volumio volumio[858]: 15950K .......... .......... .......... .......... .......... 71% 4.05M 2s Mar 31 11:09:11 volumio volumio[858]: 16000K .......... .......... .......... .......... .......... 71% 5.48M 2s Mar 31 11:09:11 volumio volumio[858]: 16050K .......... .......... .......... .......... .......... 71% 5.38M 2s Mar 31 11:09:11 volumio volumio[858]: 16100K .......... .......... .......... .......... .......... 71% 5.49M 2s Mar 31 11:09:11 volumio volumio[858]: 16150K .......... .......... .......... .......... .......... 72% 5.34M 2s Mar 31 11:09:11 volumio volumio[858]: 16200K .......... .......... .......... .......... .......... 72% 3.88M 2s Mar 31 11:09:11 volumio volumio[858]: 16250K .......... .......... .......... .......... .......... 72% 3.86M 2s Mar 31 11:09:11 volumio volumio[858]: 16300K .......... .......... .......... .......... .......... 72% 5.54M 2s Mar 31 11:09:11 volumio volumio[858]: 16350K .......... .......... .......... .......... .......... 72% 3.51M 2s Mar 31 11:09:11 volumio volumio[858]: 16400K .......... .......... .......... .......... .......... 73% 5.34M 2s Mar 31 11:09:11 volumio volumio[858]: 16450K .......... .......... .......... .......... .......... 73% 4.88M 2s Mar 31 11:09:11 volumio volumio[858]: 16500K .......... .......... .......... .......... .......... 73% 5.40M 2s Mar 31 11:09:11 volumio volumio[858]: 16550K .......... .......... .......... .......... .......... 73% 5.42M 2s Mar 31 11:09:11 volumio volumio[858]: 16600K .......... .......... .......... .......... .......... 74% 2.95M 2s Mar 31 11:09:11 volumio volumio[858]: 16650K .......... .......... .......... .......... .......... 74% 2.80M 2s Mar 31 11:09:11 volumio volumio[858]: 16700K .......... .......... .......... .......... .......... 74% 6.68M 2s Mar 31 11:09:11 volumio volumio[858]: 16750K .......... .......... .......... .......... .......... 74% 4.10M 2s Mar 31 11:09:11 volumio volumio[858]: 16800K .......... .......... .......... .......... .......... 74% 5.58M 2s Mar 31 11:09:11 volumio volumio[858]: 16850K .......... .......... .......... .......... .......... 75% 5.15M 2s Mar 31 11:09:11 volumio volumio[858]: 16900K .......... .......... .......... .......... .......... 75% 4.05M 2s Mar 31 11:09:11 volumio volumio[858]: 16950K .......... .......... .......... .......... .......... 75% 8.04M 2s Mar 31 11:09:11 volumio volumio[858]: 17000K .......... .......... .......... .......... .......... 75% 5.44M 1s Mar 31 11:09:11 volumio volumio[858]: 17050K .......... .......... .......... .......... .......... 76% 1.81M 1s Mar 31 11:09:11 volumio volumio[858]: 17100K .......... .......... .......... .......... .......... 76% 4.06M 1s Mar 31 11:09:11 volumio volumio[858]: 17150K .......... .......... .......... .......... .......... 76% 4.13M 1s Mar 31 11:09:11 volumio volumio[858]: 17200K .......... .......... .......... .......... .......... 76% 5.24M 1s Mar 31 11:09:11 volumio volumio[858]: 17250K .......... .......... .......... .......... .......... 76% 5.60M 1s Mar 31 11:09:11 volumio volumio[858]: 17300K .......... .......... .......... .......... .......... 77% 4.03M 1s Mar 31 11:09:11 volumio volumio[858]: 17350K .......... .......... .......... .......... .......... 77% 7.91M 1s Mar 31 11:09:11 volumio volumio[858]: 17400K .......... .......... .......... .......... .......... 77% 5.07M 1s Mar 31 11:09:11 volumio volumio[858]: 17450K .......... .......... .......... .......... .......... 77% 5.88M 1s Mar 31 11:09:11 volumio volumio[858]: 17500K .......... .......... .......... .......... .......... 78% 4.08M 1s Mar 31 11:09:11 volumio volumio[858]: 17550K .......... .......... .......... .......... .......... 78% 4.14M 1s Mar 31 11:09:11 volumio volumio[858]: 17600K .......... .......... .......... .......... .......... 78% 2.80M 1s Mar 31 11:09:11 volumio volumio[858]: 17650K .......... .......... .......... .......... .......... 78% 5.51M 1s Mar 31 11:09:11 volumio volumio[858]: 17700K .......... .......... .......... .......... .......... 78% 5.20M 1s Mar 31 11:09:11 volumio volumio[858]: 17750K .......... .......... .......... .......... .......... 79% 4.54M 1s Mar 31 11:09:11 volumio volumio[858]: 17800K .......... .......... .......... .......... .......... 79% 5.50M 1s Mar 31 11:09:11 volumio volumio[858]: 17850K .......... .......... .......... .......... .......... 79% 8.02M 1s Mar 31 11:09:11 volumio volumio[858]: 17900K .......... .......... .......... .......... .......... 79% 5.02M 1s Mar 31 11:09:11 volumio volumio[858]: 17950K .......... .......... .......... .......... .......... 80% 1.77M 1s Mar 31 11:09:11 volumio volumio[858]: 18000K .......... .......... .......... .......... .......... 80% 5.55M 1s Mar 31 11:09:11 volumio volumio[858]: 18050K .......... .......... .......... .......... .......... 80% 4.34M 1s Mar 31 11:09:11 volumio volumio[858]: 18100K .......... .......... .......... .......... .......... 80% 5.56M 1s Mar 31 11:09:11 volumio volumio[858]: 18150K .......... .......... .......... .......... .......... 80% 4.14M 1s Mar 31 11:09:11 volumio volumio[858]: 18200K .......... .......... .......... .......... .......... 81% 5.23M 1s Mar 31 11:09:11 volumio volumio[858]: 18250K .......... .......... .......... .......... .......... 81% 5.40M 1s Mar 31 11:09:11 volumio volumio[858]: 18300K .......... .......... .......... .......... .......... 81% 5.30M 1s Mar 31 11:09:11 volumio volumio[858]: 18350K .......... .......... .......... .......... .......... 81% 4.11M 1s Mar 31 11:09:11 volumio volumio[858]: 18400K .......... .......... .......... .......... .......... 82% 2.12M 1s Mar 31 11:09:11 volumio volumio[858]: 18450K .......... .......... .......... .......... .......... 82% 6.00M 1s Mar 31 11:09:11 volumio volumio[858]: 18500K .......... .......... .......... .......... .......... 82% 4.35M 1s Mar 31 11:09:11 volumio volumio[858]: 18550K .......... .......... .......... .......... .......... 82% 5.46M 1s Mar 31 11:09:11 volumio volumio[858]: 18600K .......... .......... .......... .......... .......... 82% 5.50M 1s Mar 31 11:09:11 volumio volumio[858]: 18650K .......... .......... .......... .......... .......... 83% 5.67M 1s Mar 31 11:09:11 volumio volumio[858]: 18700K .......... .......... .......... .......... .......... 83% 5.09M 1s Mar 31 11:09:11 volumio volumio[858]: 18750K .......... .......... .......... .......... .......... 83% 3.91M 1s Mar 31 11:09:11 volumio volumio[858]: 18800K .......... .......... .......... .......... .......... 83% 4.28M 1s Mar 31 11:09:11 volumio volumio[858]: 18850K .......... .......... .......... .......... .......... 84% 2.85M 1s Mar 31 11:09:11 volumio volumio[858]: 18900K .......... .......... .......... .......... .......... 84% 5.73M 1s Mar 31 11:09:11 volumio volumio[858]: 18950K .......... .......... .......... .......... .......... 84% 4.16M 1s Mar 31 11:09:11 volumio volumio[858]: 19000K .......... .......... .......... .......... .......... 84% 3.76M 1s Mar 31 11:09:11 volumio volumio[858]: 19050K .......... .......... .......... .......... .......... 84% 10.9M 1s Mar 31 11:09:11 volumio volumio[858]: 19100K .......... .......... .......... .......... .......... 85% 5.21M 1s Mar 31 11:09:11 volumio volumio[858]: 19150K .......... .......... .......... .......... .......... 85% 3.79M 1s Mar 31 11:09:11 volumio volumio[858]: 19200K .......... .......... .......... .......... .......... 85% 5.37M 1s Mar 31 11:09:11 volumio volumio[858]: 19250K .......... .......... .......... .......... .......... 85% 5.35M 1s Mar 31 11:09:11 volumio volumio[858]: 19300K .......... .......... .......... .......... .......... 86% 5.42M 1s Mar 31 11:09:11 volumio volumio[858]: 19350K .......... .......... .......... .......... .......... 86% 2.54M 1s Mar 31 11:09:11 volumio volumio[858]: 19400K .......... .......... .......... .......... .......... 86% 5.83M 1s Mar 31 11:09:11 volumio volumio[858]: 19450K .......... .......... .......... .......... .......... 86% 4.17M 1s Mar 31 11:09:11 volumio volumio[858]: 19500K .......... .......... .......... .......... .......... 86% 5.69M 1s Mar 31 11:09:11 volumio volumio[858]: 19550K .......... .......... .......... .......... .......... 87% 4.11M 1s Mar 31 11:09:11 volumio volumio[858]: 19600K .......... .......... .......... .......... .......... 87% 5.24M 1s Mar 31 11:09:11 volumio volumio[858]: 19650K .......... .......... .......... .......... .......... 87% 4.79M 1s Mar 31 11:09:11 volumio volumio[858]: 19700K .......... .......... .......... .......... .......... 87% 5.54M 1s Mar 31 11:09:11 volumio volumio[858]: 19750K .......... .......... .......... .......... .......... 88% 7.91M 1s Mar 31 11:09:11 volumio volumio[858]: 19800K .......... .......... .......... .......... .......... 88% 2.58M 1s Mar 31 11:09:11 volumio volumio[858]: 19850K .......... .......... .......... .......... .......... 88% 4.38M 1s Mar 31 11:09:11 volumio volumio[858]: 19900K .......... .......... .......... .......... .......... 88% 3.53M 1s Mar 31 11:09:11 volumio volumio[858]: 19950K .......... .......... .......... .......... .......... 88% 4.04M 1s Mar 31 11:09:11 volumio volumio[858]: 20000K .......... .......... .......... .......... .......... 89% 5.49M 1s Mar 31 11:09:11 volumio volumio[858]: 20050K .......... .......... .......... .......... .......... 89% 4.88M 1s Mar 31 11:09:11 volumio volumio[858]: 20100K .......... .......... .......... .......... .......... 89% 5.28M 1s Mar 31 11:09:11 volumio volumio[858]: 20150K .......... .......... .......... .......... .......... 89% 5.17M 1s Mar 31 11:09:11 volumio volumio[858]: 20200K .......... .......... .......... .......... .......... 90% 5.72M 1s Mar 31 11:09:11 volumio volumio[858]: 20250K .......... .......... .......... .......... .......... 90% 5.40M 1s Mar 31 11:09:11 volumio volumio[858]: 20300K .......... .......... .......... .......... .......... 90% 3.05M 1s Mar 31 11:09:11 volumio volumio[858]: 20350K .......... .......... .......... .......... .......... 90% 2.19M 1s Mar 31 11:09:11 volumio volumio[858]: 20400K .......... .......... .......... .......... .......... 90% 8.35M 1s Mar 31 11:09:11 volumio volumio[858]: 20450K .......... .......... .......... .......... .......... 91% 4.95M 1s Mar 31 11:09:11 volumio volumio[858]: 20500K .......... .......... .......... .......... .......... 91% 5.97M 1s Mar 31 11:09:11 volumio volumio[858]: 20550K .......... .......... .......... .......... .......... 91% 5.31M 1s Mar 31 11:09:11 volumio volumio[858]: 20600K .......... .......... .......... .......... .......... 91% 5.00M 0s Mar 31 11:09:11 volumio volumio[858]: 20650K .......... .......... .......... .......... .......... 92% 5.42M 0s Mar 31 11:09:11 volumio volumio[858]: 20700K .......... .......... .......... .......... .......... 92% 3.92M 0s Mar 31 11:09:11 volumio volumio[858]: 20750K .......... .......... .......... .......... .......... 92% 2.16M 0s Mar 31 11:09:11 volumio volumio[858]: 20800K .......... .......... .......... .......... .......... 92% 3.38M 0s Mar 31 11:09:11 volumio volumio[858]: 20850K .......... .......... .......... .......... .......... 92% 8.57M 0s Mar 31 11:09:11 volumio volumio[858]: 20900K .......... .......... .......... .......... .......... 93% 5.46M 0s Mar 31 11:09:11 volumio volumio[858]: 20950K .......... .......... .......... .......... .......... 93% 2.78M 0s Mar 31 11:09:11 volumio volumio[858]: 21000K .......... .......... .......... .......... .......... 93% 4.18M 0s Mar 31 11:09:11 volumio volumio[858]: 21050K .......... .......... .......... .......... .......... 93% 5.42M 0s Mar 31 11:09:11 volumio volumio[858]: 21100K .......... .......... .......... .......... .......... 94% 5.36M 0s Mar 31 11:09:11 volumio volumio[858]: 21150K .......... .......... .......... .......... .......... 94% 1.42M 0s Mar 31 11:09:11 volumio volumio[858]: 21200K .......... .......... .......... .......... .......... 94% 3.89M 0s Mar 31 11:09:11 volumio volumio[858]: 21250K .......... .......... .......... .......... .......... 94% 5.33M 0s Mar 31 11:09:11 volumio volumio[858]: 21300K .......... .......... .......... .......... .......... 94% 5.52M 0s Mar 31 11:09:11 volumio volumio[858]: 21350K .......... .......... .......... .......... .......... 95% 5.31M 0s Mar 31 11:09:11 volumio volumio[858]: 21400K .......... .......... .......... .......... .......... 95% 5.49M 0s Mar 31 11:09:11 volumio volumio[858]: 21450K .......... .......... .......... .......... .......... 95% 4.05M 0s Mar 31 11:09:11 volumio volumio[858]: 21500K .......... .......... .......... .......... .......... 95% 3.87M 0s Mar 31 11:09:11 volumio volumio[858]: 21550K .......... .......... .......... .......... .......... 96% 3.92M 0s Mar 31 11:09:11 volumio volumio[858]: 21600K .......... .......... .......... .......... .......... 96% 2.95M 0s Mar 31 11:09:11 volumio volumio[858]: 21650K .......... .......... .......... .......... .......... 96% 3.92M 0s Mar 31 11:09:11 volumio volumio[858]: 21700K .......... .......... .......... .......... .......... 96% 3.94M 0s Mar 31 11:09:11 volumio volumio[858]: 21750K .......... .......... .......... .......... .......... 96% 2.92M 0s Mar 31 11:09:11 volumio volumio[858]: 21800K .......... .......... .......... .......... .......... 97% 4.17M 0s Mar 31 11:09:11 volumio volumio[858]: 21850K .......... .......... .......... .......... .......... 97% 3.08M 0s Mar 31 11:09:11 volumio volumio[858]: 21900K .......... .......... .......... .......... .......... 97% 2.35M 0s Mar 31 11:09:11 volumio volumio[858]: 21950K .......... .......... .......... .......... .......... 97% 2.71M 0s Mar 31 11:09:11 volumio volumio[858]: 22000K .......... .......... .......... .......... .......... 98% 5.44M 0s Mar 31 11:09:11 volumio volumio[858]: 22050K .......... .......... .......... .......... .......... 98% 5.49M 0s Mar 31 11:09:11 volumio volumio[858]: 22100K .......... .......... .......... .......... .......... 98% 5.33M 0s Mar 31 11:09:11 volumio volumio[858]: 22150K .......... .......... .......... .......... .......... 98% 4.83M 0s Mar 31 11:09:11 volumio volumio[858]: 22200K .......... .......... .......... .......... .......... 98% 4.40M 0s Mar 31 11:09:11 volumio volumio[858]: 22250K .......... .......... .......... .......... .......... 99% 5.50M 0s Mar 31 11:09:11 volumio volumio[858]: 22300K .......... .......... .......... .......... .......... 99% 5.44M 0s Mar 31 11:09:11 volumio volumio[858]: 22350K .......... .......... .......... .......... .......... 99% 5.39M 0s Mar 31 11:09:11 volumio volumio[858]: 22400K .......... .......... .......... .......... .......... 99% 4.05M 0s Mar 31 11:09:11 volumio volumio[858]: 22450K .......... .......... .......... .......... ..... 100% 3.92M=5.9s Mar 31 11:09:11 volumio volumio[858]: 2024-03-31 11:09:11 (3.70 MB/s) - ‘/tmp/tools.tar.xz’ saved [23035700/23035700] Mar 31 11:09:16 volumio volumio[858]: Upnp client error: Error: This socket has been ended by the other party Mar 31 11:09:16 volumio volumio[858]: Upnp client error: Error: This socket has been ended by the other party Mar 31 11:09:16 volumio volumio[858]: Upnp client error: Error: This socket has been ended by the other party Mar 31 11:09:16 volumio volumio[858]: Upnp client error: Error: This socket has been ended by the other party Mar 31 11:09:16 volumio volumio[858]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 11:09:16 volumio volumio[858]: info: CoreCommandRouter::Close All Modals sent Mar 31 11:09:17 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 31 11:09:17 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand update Mar 31 11:09:17 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 31 11:09:17 volumio volumio[858]: info: Mar 31 11:09:17 volumio volumio[858]: ---------------------------- MPD announces state update: update Mar 31 11:09:17 volumio volumio[858]: info: sendMpdCommand update took 9 milliseconds Mar 31 11:09:17 volumio volumio[858]: info: ControllerMpd::getState Mar 31 11:09:17 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 31 11:09:17 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 31 11:09:17 volumio volumio[858]: info: Mar 31 11:09:17 volumio volumio[858]: ---------------------------- MPD announces state update: update Mar 31 11:09:17 volumio volumio[858]: info: ControllerMpd::getState Mar 31 11:09:17 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 31 11:09:17 volumio volumio[858]: info: sendMpdCommand status took 10 milliseconds Mar 31 11:09:17 volumio volumio[858]: info: sendMpdCommand status took 6 milliseconds Mar 31 11:09:17 volumio volumio[858]: info: sendMpdCommand status took 6 milliseconds Mar 31 11:09:17 volumio volumio[858]: info: sendMpdCommand status took 4 milliseconds Mar 31 11:09:17 volumio volumio[858]: verbose: ControllerMpd::parseState Mar 31 11:09:20 volumio volumio[858]: info: Command Router : Notfying DB Updatetrue Mar 31 11:09:20 volumio volumio[858]: verbose: ControllerMpd::parseState Mar 31 11:09:20 volumio volumio[858]: verbose: ControllerMpd::parseState Mar 31 11:09:20 volumio volumio[858]: info: Command Router : Notfying DB Updatetrue Mar 31 11:09:20 volumio volumio[858]: info: CoreCommandRouter::Close All Modals sent Mar 31 11:09:20 volumio volumio[858]: verbose: ControllerMpd::parseState Mar 31 11:09:20 volumio volumio[858]: info: ControllerMpd::pushState Mar 31 11:09:20 volumio volumio[858]: info: CoreCommandRouter::servicePushState Mar 31 11:09:20 volumio volumio[858]: info: CoreStateMachine::pushState Mar 31 11:09:20 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:20 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 11:09:20 volumio volumio[858]: info: CoreCommandRouter::volumioPushState Mar 31 11:09:20 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:20 volumio volumio[858]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 31 11:09:20 volumio volumio[858]: verbose: CURRENT POSITION 82 Mar 31 11:09:20 volumio volumio[858]: info: CoreStateMachine::syncState stateService stop Mar 31 11:09:20 volumio volumio[858]: info: CoreStateMachine::syncState currentStatus pause Mar 31 11:09:20 volumio volumio[858]: info: CoreStateMachine::pushState Mar 31 11:09:20 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:20 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 11:09:20 volumio volumio[858]: info: CoreCommandRouter::volumioPushState Mar 31 11:09:20 volumio volumio[858]: info: ControllerMpd::pushState Mar 31 11:09:20 volumio volumio[858]: info: CoreCommandRouter::servicePushState Mar 31 11:09:20 volumio volumio[858]: info: CoreStateMachine::pushState Mar 31 11:09:20 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:20 volumio volumio[858]: info: CoreCommandRouter::volumioPushState Mar 31 11:09:20 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:20 volumio volumio[858]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 31 11:09:20 volumio volumio[858]: verbose: CURRENT POSITION 82 Mar 31 11:09:20 volumio volumio[858]: info: CoreStateMachine::syncState stateService stop Mar 31 11:09:20 volumio volumio[858]: info: CoreStateMachine::syncState currentStatus pause Mar 31 11:09:20 volumio volumio[858]: info: CoreStateMachine::pushState Mar 31 11:09:20 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:20 volumio volumio[858]: info: CoreCommandRouter::volumioPushState Mar 31 11:09:20 volumio volumio[858]: info: ------------------------------ 3414ms Mar 31 11:09:20 volumio volumio[858]: info: ------------------------------ 3410ms Mar 31 11:09:20 volumio volumio[858]: info: MPD Database updated - AlbumList cache refreshed Mar 31 11:09:20 volumio volumio[858]: info: Mar 31 11:09:20 volumio volumio[858]: ---------------------------- MPD announces state update: database Mar 31 11:09:20 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 31 11:09:20 volumio volumio[858]: info: Mar 31 11:09:20 volumio volumio[858]: ---------------------------- MPD announces state update: update Mar 31 11:09:20 volumio volumio[858]: info: ControllerMpd::getState Mar 31 11:09:20 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 31 11:09:20 volumio volumio[858]: info: ControllerMpd::getState Mar 31 11:09:20 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 31 11:09:20 volumio volumio[858]: info: MPD Database updated - AlbumList cache refreshed Mar 31 11:09:20 volumio volumio[858]: info: Mar 31 11:09:20 volumio volumio[858]: ---------------------------- MPD announces state update: database Mar 31 11:09:20 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 31 11:09:20 volumio volumio[858]: info: Mar 31 11:09:20 volumio volumio[858]: ---------------------------- MPD announces state update: update Mar 31 11:09:20 volumio volumio[858]: info: ControllerMpd::getState Mar 31 11:09:20 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 31 11:09:20 volumio volumio[858]: info: ControllerMpd::getState Mar 31 11:09:20 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 31 11:09:20 volumio volumio[858]: info: sendMpdCommand status took 39 milliseconds Mar 31 11:09:20 volumio volumio[858]: info: sendMpdCommand status took 36 milliseconds Mar 31 11:09:20 volumio volumio[858]: info: sendMpdCommand status took 35 milliseconds Mar 31 11:09:20 volumio volumio[858]: info: sendMpdCommand status took 19 milliseconds Mar 31 11:09:20 volumio volumio[858]: info: sendMpdCommand status took 18 milliseconds Mar 31 11:09:20 volumio volumio[858]: info: sendMpdCommand status took 18 milliseconds Mar 31 11:09:20 volumio volumio[858]: verbose: ControllerMpd::parseState Mar 31 11:09:21 volumio volumio[858]: info: Command Router : Notfying DB Updatefalse Mar 31 11:09:21 volumio volumio[858]: info: CoreCommandRouter::Close All Modals sent Mar 31 11:09:21 volumio volumio[858]: verbose: ControllerMpd::parseState Mar 31 11:09:21 volumio volumio[858]: verbose: ControllerMpd::parseState Mar 31 11:09:21 volumio volumio[858]: verbose: ControllerMpd::parseState Mar 31 11:09:21 volumio volumio[858]: info: Command Router : Notfying DB Updatefalse Mar 31 11:09:21 volumio volumio[858]: info: CoreCommandRouter::Close All Modals sent Mar 31 11:09:21 volumio volumio[858]: verbose: ControllerMpd::parseState Mar 31 11:09:21 volumio volumio[858]: verbose: ControllerMpd::parseState Mar 31 11:09:21 volumio volumio[858]: info: ControllerMpd::pushState Mar 31 11:09:21 volumio volumio[858]: info: CoreCommandRouter::servicePushState Mar 31 11:09:21 volumio volumio[858]: info: CoreStateMachine::pushState Mar 31 11:09:21 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:21 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 11:09:21 volumio volumio[858]: info: CoreCommandRouter::volumioPushState Mar 31 11:09:21 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:21 volumio volumio[858]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 31 11:09:21 volumio volumio[858]: verbose: CURRENT POSITION 82 Mar 31 11:09:21 volumio volumio[858]: info: CoreStateMachine::syncState stateService stop Mar 31 11:09:21 volumio volumio[858]: info: CoreStateMachine::syncState currentStatus pause Mar 31 11:09:21 volumio volumio[858]: info: CoreStateMachine::pushState Mar 31 11:09:21 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:21 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 11:09:21 volumio volumio[858]: info: CoreCommandRouter::volumioPushState Mar 31 11:09:21 volumio volumio[858]: info: ControllerMpd::pushState Mar 31 11:09:21 volumio volumio[858]: info: CoreCommandRouter::servicePushState Mar 31 11:09:21 volumio volumio[858]: info: CoreStateMachine::pushState Mar 31 11:09:21 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:21 volumio volumio[858]: info: CoreCommandRouter::volumioPushState Mar 31 11:09:21 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:21 volumio volumio[858]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 31 11:09:21 volumio volumio[858]: verbose: CURRENT POSITION 82 Mar 31 11:09:21 volumio volumio[858]: info: CoreStateMachine::syncState stateService stop Mar 31 11:09:21 volumio volumio[858]: info: CoreStateMachine::syncState currentStatus pause Mar 31 11:09:21 volumio volumio[858]: info: CoreStateMachine::pushState Mar 31 11:09:21 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:21 volumio volumio[858]: info: CoreCommandRouter::volumioPushState Mar 31 11:09:21 volumio volumio[858]: info: ControllerMpd::pushState Mar 31 11:09:21 volumio volumio[858]: info: CoreCommandRouter::servicePushState Mar 31 11:09:21 volumio volumio[858]: info: CoreStateMachine::pushState Mar 31 11:09:21 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:21 volumio volumio[858]: info: CoreCommandRouter::volumioPushState Mar 31 11:09:21 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:21 volumio volumio[858]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 31 11:09:21 volumio volumio[858]: verbose: CURRENT POSITION 82 Mar 31 11:09:21 volumio volumio[858]: info: CoreStateMachine::syncState stateService stop Mar 31 11:09:21 volumio volumio[858]: info: CoreStateMachine::syncState currentStatus pause Mar 31 11:09:21 volumio volumio[858]: info: CoreStateMachine::pushState Mar 31 11:09:21 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:21 volumio volumio[858]: info: CoreCommandRouter::volumioPushState Mar 31 11:09:21 volumio volumio[858]: info: ControllerMpd::pushState Mar 31 11:09:21 volumio volumio[858]: info: CoreCommandRouter::servicePushState Mar 31 11:09:21 volumio volumio[858]: info: CoreStateMachine::pushState Mar 31 11:09:21 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:21 volumio volumio[858]: info: CoreCommandRouter::volumioPushState Mar 31 11:09:21 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:21 volumio volumio[858]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 31 11:09:21 volumio volumio[858]: verbose: CURRENT POSITION 82 Mar 31 11:09:21 volumio volumio[858]: info: CoreStateMachine::syncState stateService stop Mar 31 11:09:21 volumio volumio[858]: info: CoreStateMachine::syncState currentStatus pause Mar 31 11:09:21 volumio volumio[858]: info: CoreStateMachine::pushState Mar 31 11:09:21 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:21 volumio volumio[858]: info: CoreCommandRouter::volumioPushState Mar 31 11:09:21 volumio volumio[858]: info: ------------------------------ 165ms Mar 31 11:09:21 volumio volumio[858]: info: ------------------------------ 165ms Mar 31 11:09:21 volumio volumio[858]: info: ------------------------------ 146ms Mar 31 11:09:21 volumio volumio[858]: info: ------------------------------ 147ms Mar 31 11:09:21 volumio volumio[858]: Upnp client error: Error: This socket has been ended by the other party Mar 31 11:09:31 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 31 11:09:31 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 31 11:09:35 volumio volumio[858]: info: CoreCommandRouter::volumioGetState Mar 31 11:09:35 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:36 volumio volumio[858]: info: CoreCommandRouter::volumioPlay Mar 31 11:09:36 volumio volumio[858]: info: CoreStateMachine::play index undefined Mar 31 11:09:36 volumio volumio[858]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 31 11:09:36 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:36 volumio volumio[858]: info: CoreStateMachine::startPlaybackTimer Mar 31 11:09:36 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:36 volumio volumio[858]: info: ControllerMpd::resume Mar 31 11:09:36 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand play Mar 31 11:09:36 volumio volumio[858]: info: sendMpdCommand play took 1 milliseconds Mar 31 11:09:36 volumio volumio[858]: info: Mar 31 11:09:36 volumio volumio[858]: ---------------------------- MPD announces state update: player Mar 31 11:09:36 volumio volumio[858]: info: ControllerMpd::getState Mar 31 11:09:36 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 31 11:09:36 volumio volumio[858]: info: Mar 31 11:09:36 volumio volumio[858]: ---------------------------- MPD announces state update: player Mar 31 11:09:36 volumio volumio[858]: info: ControllerMpd::getState Mar 31 11:09:36 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 31 11:09:36 volumio volumio[858]: info: FusionDsp - ---- read samplerate, raw: Mar 31 11:09:36 volumio volumio[858]: error: FusionDsp - invalid sample rate Mar 31 11:09:36 volumio volumio[858]: info: FusionDsp - ---- read samplerate, raw: Mar 31 11:09:36 volumio volumio[858]: error: FusionDsp - invalid sample rate Mar 31 11:09:36 volumio volumio[858]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 31 11:09:36 volumio volumio[858]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 31 11:09:36 volumio volumio[858]: info: camilladsp stopping service pid 4118... Mar 31 11:09:36 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=1) Mar 31 11:09:36 volumio volumio[858]: info: camilladsp service terminated, instance 1 Mar 31 11:09:36 volumio volumio[858]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 31 11:09:36 volumio volumio[858]: info: camilladsp service started and running in background, instance 1 Mar 31 11:09:36 volumio volumio[858]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 31 11:09:36 volumio volumio[858]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 31 11:09:36 volumio volumio[858]: info: camilladsp stopping service pid 4185... Mar 31 11:09:36 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=1) Mar 31 11:09:36 volumio volumio[858]: info: camilladsp service terminated, instance 2 Mar 31 11:09:36 volumio volumio[858]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 31 11:09:36 volumio volumio[858]: info: camilladsp service started and running in background, instance 2 Mar 31 11:09:36 volumio volumio[858]: info: Mar 31 11:09:36 volumio volumio[858]: ---------------------------- MPD announces state update: player Mar 31 11:09:36 volumio volumio[858]: info: sendMpdCommand status took 89 milliseconds Mar 31 11:09:36 volumio volumio[858]: info: sendMpdCommand status took 88 milliseconds Mar 31 11:09:36 volumio volumio[858]: info: ControllerMpd::getState Mar 31 11:09:36 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 31 11:09:36 volumio volumio[858]: verbose: ControllerMpd::parseState Mar 31 11:09:36 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 11:09:36 volumio volumio[858]: verbose: ControllerMpd::parseState Mar 31 11:09:36 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 11:09:36 volumio volumio[858]: info: Mar 31 11:09:36 volumio volumio[858]: ---------------------------- MPD announces state update: player Mar 31 11:09:36 volumio volumio[858]: info: ControllerMpd::getState Mar 31 11:09:36 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand status Mar 31 11:09:36 volumio volumio[858]: info: camilladsp spawned new process with pid 4711, instance 2, run: true Mar 31 11:09:36 volumio volumio[858]: info: sendMpdCommand status took 33 milliseconds Mar 31 11:09:36 volumio volumio[858]: info: sendMpdCommand playlistinfo took 34 milliseconds Mar 31 11:09:36 volumio volumio[858]: info: sendMpdCommand playlistinfo took 34 milliseconds Mar 31 11:09:36 volumio volumio[858]: info: sendMpdCommand status took 32 milliseconds Mar 31 11:09:36 volumio volumio[858]: verbose: ControllerMpd::parseState Mar 31 11:09:36 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 11:09:36 volumio volumio[858]: verbose: ControllerMpd::parseTrackInfo Mar 31 11:09:36 volumio volumio[858]: verbose: ControllerMpd::parseTrackInfo Mar 31 11:09:36 volumio volumio[858]: verbose: ControllerMpd::parseState Mar 31 11:09:36 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 11:09:36 volumio volumio[858]: info: ControllerMpd::pushState Mar 31 11:09:36 volumio volumio[858]: info: CoreCommandRouter::servicePushState Mar 31 11:09:36 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:36 volumio volumio[858]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"55 Mazhaneer Thullikal","artist":null,"album":null,"uri":"USB/Songs/Malayalam Melodies/55 Mazhaneer Thullikal.mp3","trackType":"mp3"} Mar 31 11:09:36 volumio volumio[858]: verbose: CURRENT POSITION 82 Mar 31 11:09:36 volumio volumio[858]: info: CoreStateMachine::syncState stateService play Mar 31 11:09:36 volumio volumio[858]: info: CoreStateMachine::syncState currentStatus pause Mar 31 11:09:36 volumio volumio[858]: info: CoreStateMachine::pushState Mar 31 11:09:36 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:36 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 11:09:36 volumio volumio[858]: info: CoreCommandRouter::volumioPushState Mar 31 11:09:36 volumio volumio[858]: info: ControllerMpd::pushState Mar 31 11:09:36 volumio volumio[858]: info: CoreCommandRouter::servicePushState Mar 31 11:09:36 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:36 volumio volumio[858]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"55 Mazhaneer Thullikal","artist":null,"album":null,"uri":"USB/Songs/Malayalam Melodies/55 Mazhaneer Thullikal.mp3","trackType":"mp3"} Mar 31 11:09:36 volumio volumio[858]: verbose: CURRENT POSITION 82 Mar 31 11:09:36 volumio volumio[858]: info: CoreStateMachine::syncState stateService play Mar 31 11:09:36 volumio volumio[858]: info: CoreStateMachine::syncState currentStatus play Mar 31 11:09:36 volumio volumio[858]: info: Received an update from plugin. extracting info from payload Mar 31 11:09:36 volumio volumio[858]: info: CoreStateMachine::pushState Mar 31 11:09:36 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:36 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 11:09:36 volumio volumio[858]: info: CoreCommandRouter::volumioPushState Mar 31 11:09:36 volumio volumio[858]: info: CoreStateMachine::pushState Mar 31 11:09:36 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:36 volumio volumio[858]: info: CoreCommandRouter::volumioPushState Mar 31 11:09:36 volumio volumio[858]: info: ------------------------------ 174ms Mar 31 11:09:36 volumio volumio[858]: info: ------------------------------ 172ms Mar 31 11:09:36 volumio volumio[858]: info: camilladsp spawned new process with pid 4718, instance 1, run: true Mar 31 11:09:36 volumio volumio[858]: info: sendMpdCommand playlistinfo took 74 milliseconds Mar 31 11:09:36 volumio volumio[858]: info: sendMpdCommand playlistinfo took 72 milliseconds Mar 31 11:09:36 volumio volumio[858]: verbose: ControllerMpd::parseTrackInfo Mar 31 11:09:36 volumio volumio[858]: verbose: ControllerMpd::parseTrackInfo Mar 31 11:09:36 volumio volumio[858]: info: ControllerMpd::pushState Mar 31 11:09:36 volumio volumio[858]: info: CoreCommandRouter::servicePushState Mar 31 11:09:36 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:36 volumio volumio[858]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1862,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"55 Mazhaneer Thullikal","artist":null,"album":null,"uri":"USB/Songs/Malayalam Melodies/55 Mazhaneer Thullikal.mp3","trackType":"mp3"} Mar 31 11:09:36 volumio volumio[858]: verbose: CURRENT POSITION 82 Mar 31 11:09:36 volumio volumio[858]: info: CoreStateMachine::syncState stateService play Mar 31 11:09:36 volumio volumio[858]: info: CoreStateMachine::syncState currentStatus play Mar 31 11:09:36 volumio volumio[858]: info: Received an update from plugin. extracting info from payload Mar 31 11:09:36 volumio volumio[858]: info: CoreStateMachine::pushState Mar 31 11:09:36 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:36 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 11:09:36 volumio volumio[858]: info: CoreCommandRouter::volumioPushState Mar 31 11:09:36 volumio volumio[858]: info: CoreStateMachine::pushState Mar 31 11:09:36 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:36 volumio volumio[858]: info: CoreCommandRouter::volumioPushState Mar 31 11:09:36 volumio volumio[858]: info: ControllerMpd::pushState Mar 31 11:09:36 volumio volumio[858]: info: CoreCommandRouter::servicePushState Mar 31 11:09:36 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:36 volumio volumio[858]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1862,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"55 Mazhaneer Thullikal","artist":null,"album":null,"uri":"USB/Songs/Malayalam Melodies/55 Mazhaneer Thullikal.mp3","trackType":"mp3"} Mar 31 11:09:36 volumio volumio[858]: verbose: CURRENT POSITION 82 Mar 31 11:09:36 volumio volumio[858]: info: CoreStateMachine::syncState stateService play Mar 31 11:09:36 volumio volumio[858]: info: CoreStateMachine::syncState currentStatus play Mar 31 11:09:36 volumio volumio[858]: info: Received an update from plugin. extracting info from payload Mar 31 11:09:36 volumio volumio[858]: info: CoreStateMachine::pushState Mar 31 11:09:36 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:36 volumio volumio[858]: info: CoreCommandRouter::volumioPushState Mar 31 11:09:36 volumio volumio[858]: info: CoreStateMachine::pushState Mar 31 11:09:36 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:36 volumio volumio[858]: info: CoreCommandRouter::volumioPushState Mar 31 11:09:36 volumio volumio[858]: info: ------------------------------ 154ms Mar 31 11:09:36 volumio volumio[858]: info: ------------------------------ 150ms Mar 31 11:09:42 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 31 11:09:42 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 31 11:09:46 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 31 11:09:52 volumio volumio[858]: info: FusionDsp - Stopping FusionDsp service Mar 31 11:09:52 volumio volumio[858]: info: camilladsp stopping service pid 4711... Mar 31 11:09:52 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=1) Mar 31 11:09:52 volumio volumio[858]: info: camilladsp service terminated, instance 2 Mar 31 11:09:52 volumio volumio[858]: info: Disabling plugin fusiondsp Mar 31 11:09:52 volumio volumio[858]: info: Preparing to generate the ALSA configuration file Mar 31 11:09:52 volumio sudo[4761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Mar 31 11:09:52 volumio sudo[4761]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 11:09:52 volumio systemd[1]: Stopping FusionDsp Daemon... Mar 31 11:09:52 volumio volumio[4113]: Backend configuration: Mar 31 11:09:52 volumio volumio[4113]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Mar 31 11:09:52 volumio volumio[4113]: active_config_txt: null Mar 31 11:09:52 volumio volumio[4113]: camilla_host: 127.0.0.1 Mar 31 11:09:52 volumio volumio[4113]: camilla_port: 9876 Mar 31 11:09:52 volumio volumio[4113]: coeff_dir: /data/INTERNAL/FusionDsp/filters Mar 31 11:09:52 volumio volumio[4113]: config_dir: /data/configuration/audio_interface/fusiondsp Mar 31 11:09:52 volumio volumio[4113]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Mar 31 11:09:52 volumio volumio[4113]: log_file: /tmp/camilladsp.log Mar 31 11:09:52 volumio volumio[4113]: on_get_active_config: null Mar 31 11:09:52 volumio volumio[4113]: on_set_active_config: null Mar 31 11:09:52 volumio volumio[4113]: port: 5011 Mar 31 11:09:52 volumio volumio[4113]: supported_capture_types: null Mar 31 11:09:52 volumio volumio[4113]: supported_playback_types: null Mar 31 11:09:52 volumio volumio[4113]: update_config_symlink: false Mar 31 11:09:52 volumio volumio[4113]: update_config_txt: false Mar 31 11:09:52 volumio volumio[4113]: ======== Running on http://0.0.0.0:5011 ======== Mar 31 11:09:52 volumio volumio[4113]: (Press CTRL+C to quit) Mar 31 11:09:52 volumio volumio[858]: info: Asound.conf file written Mar 31 11:09:52 volumio sudo[4764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 31 11:09:52 volumio sudo[4764]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 11:09:52 volumio sudo[4764]: pam_unix(sudo:session): session closed for user root Mar 31 11:09:52 volumio systemd[1]: fusiondsp.service: Succeeded. Mar 31 11:09:52 volumio systemd[1]: Stopped FusionDsp Daemon. Mar 31 11:09:52 volumio volumio[858]: info: Output device has changed, restarting MPD Mar 31 11:09:52 volumio sudo[4761]: pam_unix(sudo:session): session closed for user root Mar 31 11:09:52 volumio sudo[4769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 31 11:09:52 volumio sudo[4769]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 11:09:52 volumio sudo[4769]: pam_unix(sudo:session): session closed for user root Mar 31 11:09:52 volumio volumio[858]: info: Output device has changed, restarting Shairport Sync Mar 31 11:09:52 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:09:52 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 11:09:52 volumio sudo[4772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 31 11:09:52 volumio sudo[4772]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 11:09:52 volumio systemd[1]: Stopping Music Player Daemon... Mar 31 11:09:52 volumio volumio[858]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 31 11:09:52 volumio systemd[1]: mpd.service: Succeeded. Mar 31 11:09:52 volumio systemd[1]: Stopped Music Player Daemon. Mar 31 11:09:52 volumio volumio[858]: info: Done. Mar 31 11:09:52 volumio volumio[858]: info: MPD Permissions set Mar 31 11:09:52 volumio volumio[858]: info: CoreCommandRouter::volumioGetState Mar 31 11:09:52 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:09:52 volumio volumio[858]: info: FusionDsp - Reporting Fusion DSP Disabled Mar 31 11:09:52 volumio volumio[858]: info: Removing fusiondspeq DSP Signal Path Element Mar 31 11:09:52 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:09:52 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:09:52 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:09:52 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:09:52 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:09:52 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:09:52 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:09:52 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:09:52 volumio systemd[1]: Starting Music Player Daemon... Mar 31 11:09:52 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 11:09:52 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:09:52 volumio volumio[858]: info: Starting Shairport Sync Mar 31 11:09:52 volumio sudo[4784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 31 11:09:52 volumio sudo[4784]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 11:09:52 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 31 11:09:52 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 31 11:09:52 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 31 11:09:52 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 31 11:09:52 volumio sudo[4784]: pam_unix(sudo:session): session closed for user root Mar 31 11:09:52 volumio volumio[858]: error: Upnp client error: Error: This socket has been ended by the other party Mar 31 11:09:52 volumio volumio[858]: info: Shairport-Sync Started Mar 31 11:09:53 volumio mpd[4777]: Mar 31 11:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 31 11:09:53 volumio systemd[1]: Started Music Player Daemon. Mar 31 11:09:53 volumio sudo[4772]: pam_unix(sudo:session): session closed for user root Mar 31 11:09:53 volumio volumio[858]: error: updateQueue error: null Mar 31 11:09:53 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=1) Mar 31 11:09:53 volumio volumio[858]: info: camilladsp spawned new process with pid 4791, instance 1, run: true Mar 31 11:09:53 volumio volumio[858]: info: camilladsp spawned new process with pid 4798, instance 1, run: true Mar 31 11:09:53 volumio volumio[858]: info: camilladsp spawned new process with pid 4805, instance 1, run: true Mar 31 11:09:53 volumio volumio[858]: info: camilladsp spawned new process with pid 4812, instance 1, run: true Mar 31 11:09:53 volumio volumio[858]: info: camilladsp spawned new process with pid 4819, instance 1, run: true Mar 31 11:09:53 volumio volumio[858]: info: camilladsp spawned new process with pid 4826, instance 1, run: true Mar 31 11:09:53 volumio volumio[858]: info: camilladsp spawned new process with pid 4833, instance 1, run: true Mar 31 11:09:53 volumio volumio[858]: info: camilladsp spawned new process with pid 4840, instance 1, run: true Mar 31 11:09:53 volumio volumio[858]: info: camilladsp spawned new process with pid 4847, instance 1, run: true Mar 31 11:09:53 volumio volumio[858]: info: camilladsp spawned new process with pid 4854, instance 1, run: true Mar 31 11:09:53 volumio volumio[858]: info: camilladsp spawned new process with pid 4861, instance 1, run: true Mar 31 11:09:53 volumio volumio[858]: info: camilladsp spawned new process with pid 4868, instance 1, run: true Mar 31 11:09:53 volumio volumio[858]: info: camilladsp spawned new process with pid 4875, instance 1, run: true Mar 31 11:09:53 volumio volumio[858]: info: camilladsp spawned new process with pid 4882, instance 1, run: true Mar 31 11:09:53 volumio volumio[858]: info: camilladsp spawned new process with pid 4889, instance 1, run: true Mar 31 11:09:54 volumio volumio[858]: info: camilladsp spawned new process with pid 4896, instance 1, run: true Mar 31 11:09:54 volumio volumio[858]: info: camilladsp spawned new process with pid 4903, instance 1, run: true Mar 31 11:09:54 volumio volumio[858]: info: camilladsp spawned new process with pid 4910, instance 1, run: true Mar 31 11:09:54 volumio volumio[858]: info: camilladsp spawned new process with pid 4917, instance 1, run: true Mar 31 11:09:54 volumio volumio[858]: info: camilladsp spawned new process with pid 4924, instance 1, run: true Mar 31 11:09:54 volumio volumio[858]: info: camilladsp spawned new process with pid 4931, instance 1, run: true Mar 31 11:09:54 volumio volumio[858]: info: camilladsp spawned new process with pid 4938, instance 1, run: true Mar 31 11:09:54 volumio volumio[858]: info: camilladsp spawned new process with pid 4945, instance 1, run: true Mar 31 11:09:54 volumio volumio[858]: info: camilladsp spawned new process with pid 4952, instance 1, run: true Mar 31 11:09:54 volumio volumio[858]: info: camilladsp spawned new process with pid 4959, instance 1, run: true Mar 31 11:09:54 volumio volumio[858]: info: camilladsp spawned new process with pid 4966, instance 1, run: true Mar 31 11:09:54 volumio volumio[858]: info: camilladsp spawned new process with pid 4973, instance 1, run: true Mar 31 11:09:54 volumio volumio[858]: info: camilladsp spawned new process with pid 4980, instance 1, run: true Mar 31 11:09:54 volumio volumio[858]: info: camilladsp spawned new process with pid 4987, instance 1, run: true Mar 31 11:09:54 volumio volumio[858]: info: camilladsp spawned new process with pid 4994, instance 1, run: true Mar 31 11:09:54 volumio volumio[858]: info: camilladsp spawned new process with pid 5001, instance 1, run: true Mar 31 11:09:54 volumio volumio[858]: info: camilladsp spawned new process with pid 5010, instance 1, run: true Mar 31 11:09:54 volumio volumio[858]: info: camilladsp spawned new process with pid 5017, instance 1, run: true Mar 31 11:09:54 volumio volumio[858]: info: camilladsp spawned new process with pid 5024, instance 1, run: true Mar 31 11:09:54 volumio volumio[858]: info: camilladsp spawned new process with pid 5031, instance 1, run: true Mar 31 11:09:54 volumio volumio[858]: info: camilladsp spawned new process with pid 5038, instance 1, run: true Mar 31 11:09:54 volumio volumio[858]: info: camilladsp spawned new process with pid 5045, instance 1, run: true Mar 31 11:09:55 volumio volumio[858]: info: camilladsp spawned new process with pid 5052, instance 1, run: true Mar 31 11:09:55 volumio volumio[858]: info: camilladsp spawned new process with pid 5059, instance 1, run: true Mar 31 11:09:55 volumio volumio[858]: info: camilladsp spawned new process with pid 5066, instance 1, run: true Mar 31 11:09:55 volumio volumio[858]: info: camilladsp spawned new process with pid 5073, instance 1, run: true Mar 31 11:09:55 volumio volumio[858]: info: camilladsp spawned new process with pid 5080, instance 1, run: true Mar 31 11:09:55 volumio volumio[858]: info: camilladsp spawned new process with pid 5087, instance 1, run: true Mar 31 11:09:55 volumio volumio[858]: info: camilladsp spawned new process with pid 5094, instance 1, run: true Mar 31 11:09:55 volumio volumio[858]: info: camilladsp spawned new process with pid 5101, instance 1, run: true Mar 31 11:09:55 volumio volumio[858]: info: camilladsp spawned new process with pid 5108, instance 1, run: true Mar 31 11:09:55 volumio volumio[858]: info: camilladsp spawned new process with pid 5115, instance 1, run: true Mar 31 11:09:55 volumio volumio[858]: info: camilladsp spawned new process with pid 5122, instance 1, run: true Mar 31 11:09:55 volumio volumio[858]: info: camilladsp spawned new process with pid 5129, instance 1, run: true Mar 31 11:09:55 volumio volumio[858]: info: camilladsp spawned new process with pid 5136, instance 1, run: true Mar 31 11:09:55 volumio volumio[858]: info: camilladsp spawned new process with pid 5143, instance 1, run: true Mar 31 11:09:55 volumio volumio[858]: info: camilladsp spawned new process with pid 5150, instance 1, run: true Mar 31 11:09:55 volumio volumio[858]: info: camilladsp spawned new process with pid 5157, instance 1, run: true Mar 31 11:09:55 volumio volumio[858]: info: camilladsp spawned new process with pid 5164, instance 1, run: true Mar 31 11:09:55 volumio volumio[858]: info: camilladsp spawned new process with pid 5173, instance 1, run: true Mar 31 11:09:55 volumio volumio[858]: info: camilladsp spawned new process with pid 5180, instance 1, run: true Mar 31 11:09:55 volumio volumio[858]: info: camilladsp spawned new process with pid 5187, instance 1, run: true Mar 31 11:09:55 volumio volumio[858]: info: camilladsp spawned new process with pid 5194, instance 1, run: true Mar 31 11:09:55 volumio volumio[858]: info: camilladsp spawned new process with pid 5201, instance 1, run: true Mar 31 11:09:56 volumio volumio[858]: info: camilladsp spawned new process with pid 5208, instance 1, run: true Mar 31 11:09:56 volumio volumio[858]: info: camilladsp spawned new process with pid 5215, instance 1, run: true Mar 31 11:09:56 volumio volumio[858]: info: camilladsp spawned new process with pid 5222, instance 1, run: true Mar 31 11:09:56 volumio volumio[858]: info: camilladsp spawned new process with pid 5229, instance 1, run: true Mar 31 11:09:56 volumio volumio[858]: info: camilladsp spawned new process with pid 5236, instance 1, run: true Mar 31 11:09:56 volumio volumio[858]: info: camilladsp spawned new process with pid 5243, instance 1, run: true Mar 31 11:09:56 volumio volumio[858]: info: camilladsp spawned new process with pid 5250, instance 1, run: true Mar 31 11:09:56 volumio volumio[858]: info: camilladsp spawned new process with pid 5257, instance 1, run: true Mar 31 11:09:56 volumio volumio[858]: info: camilladsp spawned new process with pid 5264, instance 1, run: true Mar 31 11:09:56 volumio volumio[858]: info: camilladsp spawned new process with pid 5271, instance 1, run: true Mar 31 11:09:56 volumio volumio[858]: info: camilladsp spawned new process with pid 5278, instance 1, run: true Mar 31 11:09:56 volumio volumio[858]: info: camilladsp spawned new process with pid 5285, instance 1, run: true Mar 31 11:09:56 volumio volumio[858]: info: camilladsp spawned new process with pid 5292, instance 1, run: true Mar 31 11:09:56 volumio volumio[858]: info: camilladsp spawned new process with pid 5299, instance 1, run: true Mar 31 11:09:56 volumio volumio[858]: info: camilladsp spawned new process with pid 5306, instance 1, run: true Mar 31 11:09:56 volumio volumio[858]: info: camilladsp spawned new process with pid 5313, instance 1, run: true Mar 31 11:09:56 volumio volumio[858]: info: camilladsp spawned new process with pid 5320, instance 1, run: true Mar 31 11:09:56 volumio volumio[858]: info: camilladsp spawned new process with pid 5329, instance 1, run: true Mar 31 11:09:56 volumio volumio[858]: info: camilladsp spawned new process with pid 5336, instance 1, run: true Mar 31 11:09:56 volumio volumio[858]: info: camilladsp spawned new process with pid 5343, instance 1, run: true Mar 31 11:09:56 volumio volumio[858]: info: camilladsp spawned new process with pid 5350, instance 1, run: true Mar 31 11:09:56 volumio volumio[858]: info: camilladsp spawned new process with pid 5357, instance 1, run: true Mar 31 11:09:56 volumio volumio[858]: info: camilladsp spawned new process with pid 5364, instance 1, run: true Mar 31 11:09:57 volumio volumio[858]: info: camilladsp spawned new process with pid 5371, instance 1, run: true Mar 31 11:09:57 volumio volumio[858]: info: camilladsp spawned new process with pid 5378, instance 1, run: true Mar 31 11:09:57 volumio volumio[858]: info: camilladsp spawned new process with pid 5385, instance 1, run: true Mar 31 11:09:57 volumio volumio[858]: info: camilladsp spawned new process with pid 5392, instance 1, run: true Mar 31 11:09:57 volumio volumio[858]: info: camilladsp spawned new process with pid 5399, instance 1, run: true Mar 31 11:09:57 volumio volumio[858]: info: camilladsp spawned new process with pid 5406, instance 1, run: true Mar 31 11:09:57 volumio volumio[858]: info: camilladsp spawned new process with pid 5413, instance 1, run: true Mar 31 11:09:57 volumio volumio[858]: info: camilladsp spawned new process with pid 5420, instance 1, run: true Mar 31 11:09:57 volumio volumio[858]: info: camilladsp spawned new process with pid 5427, instance 1, run: true Mar 31 11:09:57 volumio volumio[858]: info: camilladsp spawned new process with pid 5434, instance 1, run: true Mar 31 11:09:57 volumio volumio[858]: info: camilladsp spawned new process with pid 5441, instance 1, run: true Mar 31 11:09:57 volumio volumio[858]: info: camilladsp spawned new process with pid 5448, instance 1, run: true Mar 31 11:09:57 volumio volumio[858]: info: camilladsp spawned new process with pid 5455, instance 1, run: true Mar 31 11:09:57 volumio volumio[858]: info: camilladsp spawned new process with pid 5462, instance 1, run: true Mar 31 11:09:57 volumio volumio[858]: info: camilladsp spawned new process with pid 5469, instance 1, run: true Mar 31 11:09:57 volumio volumio[858]: info: camilladsp spawned new process with pid 5476, instance 1, run: true Mar 31 11:09:57 volumio volumio[858]: info: camilladsp spawned new process with pid 5483, instance 1, run: true Mar 31 11:09:57 volumio volumio[858]: info: camilladsp spawned new process with pid 5492, instance 1, run: true Mar 31 11:09:57 volumio volumio[858]: info: camilladsp spawned new process with pid 5499, instance 1, run: true Mar 31 11:09:57 volumio volumio[858]: info: camilladsp spawned new process with pid 5506, instance 1, run: true Mar 31 11:09:57 volumio volumio[858]: info: camilladsp spawned new process with pid 5513, instance 1, run: true Mar 31 11:09:57 volumio volumio[858]: info: camilladsp spawned new process with pid 5520, instance 1, run: true Mar 31 11:09:58 volumio volumio[858]: info: camilladsp spawned new process with pid 5527, instance 1, run: true Mar 31 11:09:58 volumio volumio[858]: info: camilladsp spawned new process with pid 5534, instance 1, run: true Mar 31 11:09:58 volumio volumio[858]: info: camilladsp spawned new process with pid 5541, instance 1, run: true Mar 31 11:09:58 volumio volumio[858]: info: camilladsp spawned new process with pid 5548, instance 1, run: true Mar 31 11:09:58 volumio volumio[858]: info: camilladsp spawned new process with pid 5555, instance 1, run: true Mar 31 11:09:58 volumio volumio[858]: info: camilladsp spawned new process with pid 5562, instance 1, run: true Mar 31 11:09:58 volumio volumio[858]: info: camilladsp spawned new process with pid 5569, instance 1, run: true Mar 31 11:09:58 volumio volumio[858]: info: camilladsp spawned new process with pid 5576, instance 1, run: true Mar 31 11:09:58 volumio volumio[858]: info: camilladsp spawned new process with pid 5583, instance 1, run: true Mar 31 11:09:58 volumio volumio[858]: info: camilladsp spawned new process with pid 5590, instance 1, run: true Mar 31 11:09:58 volumio volumio[858]: info: camilladsp spawned new process with pid 5597, instance 1, run: true Mar 31 11:09:58 volumio volumio[858]: info: camilladsp spawned new process with pid 5604, instance 1, run: true Mar 31 11:09:58 volumio volumio[858]: info: camilladsp spawned new process with pid 5611, instance 1, run: true Mar 31 11:09:58 volumio volumio[858]: info: camilladsp spawned new process with pid 5618, instance 1, run: true Mar 31 11:09:58 volumio volumio[858]: info: camilladsp spawned new process with pid 5625, instance 1, run: true Mar 31 11:09:58 volumio volumio[858]: info: camilladsp spawned new process with pid 5632, instance 1, run: true Mar 31 11:09:58 volumio volumio[858]: info: camilladsp spawned new process with pid 5639, instance 1, run: true Mar 31 11:09:58 volumio volumio[858]: info: camilladsp spawned new process with pid 5646, instance 1, run: true Mar 31 11:09:58 volumio volumio[858]: info: camilladsp spawned new process with pid 5655, instance 1, run: true Mar 31 11:09:58 volumio volumio[858]: info: camilladsp spawned new process with pid 5662, instance 1, run: true Mar 31 11:09:58 volumio volumio[858]: info: camilladsp spawned new process with pid 5669, instance 1, run: true Mar 31 11:09:58 volumio volumio[858]: info: camilladsp spawned new process with pid 5676, instance 1, run: true Mar 31 11:09:58 volumio volumio[858]: info: camilladsp spawned new process with pid 5683, instance 1, run: true Mar 31 11:09:59 volumio volumio[858]: info: camilladsp spawned new process with pid 5690, instance 1, run: true Mar 31 11:09:59 volumio volumio[858]: info: camilladsp spawned new process with pid 5697, instance 1, run: true Mar 31 11:09:59 volumio volumio[858]: info: camilladsp spawned new process with pid 5704, instance 1, run: true Mar 31 11:09:59 volumio volumio[858]: info: camilladsp spawned new process with pid 5711, instance 1, run: true Mar 31 11:09:59 volumio volumio[858]: info: camilladsp spawned new process with pid 5718, instance 1, run: true Mar 31 11:09:59 volumio volumio[858]: info: camilladsp spawned new process with pid 5725, instance 1, run: true Mar 31 11:09:59 volumio volumio[858]: info: camilladsp spawned new process with pid 5732, instance 1, run: true Mar 31 11:09:59 volumio volumio[858]: info: camilladsp spawned new process with pid 5739, instance 1, run: true Mar 31 11:09:59 volumio volumio[858]: info: camilladsp spawned new process with pid 5746, instance 1, run: true Mar 31 11:09:59 volumio volumio[858]: info: camilladsp spawned new process with pid 5753, instance 1, run: true Mar 31 11:09:59 volumio volumio[858]: info: camilladsp spawned new process with pid 5760, instance 1, run: true Mar 31 11:09:59 volumio volumio[858]: info: camilladsp spawned new process with pid 5767, instance 1, run: true Mar 31 11:09:59 volumio volumio[858]: info: camilladsp spawned new process with pid 5774, instance 1, run: true Mar 31 11:09:59 volumio volumio[858]: info: camilladsp spawned new process with pid 5781, instance 1, run: true Mar 31 11:09:59 volumio volumio[858]: info: camilladsp spawned new process with pid 5788, instance 1, run: true Mar 31 11:09:59 volumio volumio[858]: info: camilladsp spawned new process with pid 5795, instance 1, run: true Mar 31 11:09:59 volumio volumio[858]: info: camilladsp spawned new process with pid 5802, instance 1, run: true Mar 31 11:09:59 volumio volumio[858]: info: camilladsp spawned new process with pid 5811, instance 1, run: true Mar 31 11:09:59 volumio volumio[858]: info: camilladsp spawned new process with pid 5818, instance 1, run: true Mar 31 11:09:59 volumio volumio[858]: info: camilladsp spawned new process with pid 5825, instance 1, run: true Mar 31 11:09:59 volumio volumio[858]: info: camilladsp spawned new process with pid 5832, instance 1, run: true Mar 31 11:09:59 volumio volumio[858]: info: camilladsp spawned new process with pid 5839, instance 1, run: true Mar 31 11:10:00 volumio volumio[858]: info: camilladsp spawned new process with pid 5846, instance 1, run: true Mar 31 11:10:00 volumio volumio[858]: info: camilladsp spawned new process with pid 5853, instance 1, run: true Mar 31 11:10:00 volumio volumio[858]: info: camilladsp spawned new process with pid 5860, instance 1, run: true Mar 31 11:10:00 volumio volumio[858]: info: camilladsp spawned new process with pid 5867, instance 1, run: true Mar 31 11:10:00 volumio volumio[858]: info: camilladsp spawned new process with pid 5874, instance 1, run: true Mar 31 11:10:00 volumio volumio[858]: info: camilladsp spawned new process with pid 5881, instance 1, run: true Mar 31 11:10:00 volumio volumio[858]: info: camilladsp spawned new process with pid 5888, instance 1, run: true Mar 31 11:10:00 volumio volumio[858]: info: camilladsp spawned new process with pid 5895, instance 1, run: true Mar 31 11:10:00 volumio volumio[858]: info: camilladsp spawned new process with pid 5902, instance 1, run: true Mar 31 11:10:00 volumio volumio[858]: info: camilladsp spawned new process with pid 5909, instance 1, run: true Mar 31 11:10:00 volumio volumio[858]: info: camilladsp spawned new process with pid 5916, instance 1, run: true Mar 31 11:10:00 volumio volumio[858]: info: camilladsp spawned new process with pid 5923, instance 1, run: true Mar 31 11:10:00 volumio volumio[858]: info: camilladsp spawned new process with pid 5930, instance 1, run: true Mar 31 11:10:00 volumio volumio[858]: info: camilladsp spawned new process with pid 5937, instance 1, run: true Mar 31 11:10:00 volumio volumio[858]: info: camilladsp spawned new process with pid 5944, instance 1, run: true Mar 31 11:10:00 volumio volumio[858]: info: camilladsp spawned new process with pid 5951, instance 1, run: true Mar 31 11:10:00 volumio volumio[858]: info: camilladsp spawned new process with pid 5958, instance 1, run: true Mar 31 11:10:00 volumio volumio[858]: info: camilladsp spawned new process with pid 5965, instance 1, run: true Mar 31 11:10:00 volumio volumio[858]: info: camilladsp spawned new process with pid 5974, instance 1, run: true Mar 31 11:10:00 volumio volumio[858]: info: camilladsp spawned new process with pid 5981, instance 1, run: true Mar 31 11:10:00 volumio volumio[858]: info: camilladsp spawned new process with pid 5988, instance 1, run: true Mar 31 11:10:00 volumio volumio[858]: info: camilladsp spawned new process with pid 5995, instance 1, run: true Mar 31 11:10:01 volumio volumio[858]: info: camilladsp spawned new process with pid 6002, instance 1, run: true Mar 31 11:10:01 volumio volumio[858]: info: camilladsp spawned new process with pid 6009, instance 1, run: true Mar 31 11:10:01 volumio volumio[858]: info: camilladsp spawned new process with pid 6016, instance 1, run: true Mar 31 11:10:01 volumio volumio[858]: info: camilladsp spawned new process with pid 6023, instance 1, run: true Mar 31 11:10:01 volumio volumio[858]: info: camilladsp spawned new process with pid 6030, instance 1, run: true Mar 31 11:10:01 volumio volumio[858]: info: camilladsp spawned new process with pid 6037, instance 1, run: true Mar 31 11:10:01 volumio volumio[858]: info: camilladsp spawned new process with pid 6044, instance 1, run: true Mar 31 11:10:01 volumio volumio[858]: info: camilladsp spawned new process with pid 6052, instance 1, run: true Mar 31 11:10:01 volumio volumio[858]: info: camilladsp spawned new process with pid 6059, instance 1, run: true Mar 31 11:10:01 volumio volumio[858]: info: camilladsp spawned new process with pid 6066, instance 1, run: true Mar 31 11:10:01 volumio volumio[858]: info: camilladsp spawned new process with pid 6073, instance 1, run: true Mar 31 11:10:01 volumio volumio[858]: info: camilladsp spawned new process with pid 6080, instance 1, run: true Mar 31 11:10:01 volumio volumio[858]: info: camilladsp spawned new process with pid 6087, instance 1, run: true Mar 31 11:10:01 volumio volumio[858]: info: camilladsp spawned new process with pid 6094, instance 1, run: true Mar 31 11:10:01 volumio volumio[858]: info: camilladsp spawned new process with pid 6101, instance 1, run: true Mar 31 11:10:01 volumio volumio[858]: info: camilladsp spawned new process with pid 6108, instance 1, run: true Mar 31 11:10:01 volumio volumio[858]: info: camilladsp spawned new process with pid 6115, instance 1, run: true Mar 31 11:10:01 volumio volumio[858]: info: camilladsp spawned new process with pid 6122, instance 1, run: true Mar 31 11:10:01 volumio volumio[858]: info: camilladsp spawned new process with pid 6129, instance 1, run: true Mar 31 11:10:01 volumio volumio[858]: info: camilladsp spawned new process with pid 6138, instance 1, run: true Mar 31 11:10:01 volumio volumio[858]: info: camilladsp spawned new process with pid 6145, instance 1, run: true Mar 31 11:10:01 volumio volumio[858]: info: camilladsp spawned new process with pid 6152, instance 1, run: true Mar 31 11:10:02 volumio volumio[858]: info: camilladsp spawned new process with pid 6159, instance 1, run: true Mar 31 11:10:02 volumio volumio[858]: info: camilladsp spawned new process with pid 6166, instance 1, run: true Mar 31 11:10:02 volumio volumio[858]: info: camilladsp spawned new process with pid 6173, instance 1, run: true Mar 31 11:10:02 volumio volumio[858]: info: camilladsp spawned new process with pid 6180, instance 1, run: true Mar 31 11:10:02 volumio volumio[858]: info: camilladsp spawned new process with pid 6187, instance 1, run: true Mar 31 11:10:02 volumio volumio[858]: info: camilladsp spawned new process with pid 6194, instance 1, run: true Mar 31 11:10:02 volumio volumio[858]: info: camilladsp spawned new process with pid 6201, instance 1, run: true Mar 31 11:10:02 volumio volumio[858]: info: camilladsp spawned new process with pid 6208, instance 1, run: true Mar 31 11:10:02 volumio volumio[858]: info: camilladsp spawned new process with pid 6215, instance 1, run: true Mar 31 11:10:02 volumio volumio[858]: info: camilladsp spawned new process with pid 6222, instance 1, run: true Mar 31 11:10:02 volumio volumio[858]: info: Starting Uninstall of plugin audio_interface - fusiondsp Mar 31 11:10:02 volumio volumio[858]: info: Uninstalling plugin fusiondsp Mar 31 11:10:02 volumio volumio[858]: info: Disabling plugin fusiondsp Mar 31 11:10:02 volumio volumio[858]: info: Preparing to generate the ALSA configuration file Mar 31 11:10:02 volumio volumio[858]: info: Asound.conf file unchanged, so no further update is needed Mar 31 11:10:02 volumio volumio[858]: info: Output device has changed, restarting MPD Mar 31 11:10:02 volumio sudo[6230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 31 11:10:02 volumio sudo[6230]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 11:10:02 volumio sudo[6230]: pam_unix(sudo:session): session closed for user root Mar 31 11:10:02 volumio volumio[858]: info: Output device has changed, restarting Shairport Sync Mar 31 11:10:02 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:10:02 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 11:10:02 volumio sudo[6233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 31 11:10:02 volumio sudo[6233]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 11:10:02 volumio systemd[1]: Stopping Music Player Daemon... Mar 31 11:10:02 volumio volumio[858]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 31 11:10:02 volumio systemd[1]: mpd.service: Succeeded. Mar 31 11:10:02 volumio systemd[1]: Stopped Music Player Daemon. Mar 31 11:10:02 volumio volumio[858]: info: Checking if uninstall.sh is present Mar 31 11:10:02 volumio volumio[858]: info: camilladsp spawned new process with pid 6239, instance 1, run: true Mar 31 11:10:02 volumio volumio[858]: info: Executing uninstall.sh Mar 31 11:10:02 volumio volumio[858]: info: MPD Permissions set Mar 31 11:10:02 volumio volumio[858]: info: CoreCommandRouter::volumioGetState Mar 31 11:10:02 volumio volumio[858]: info: CorePlayQueue::getTrack 82 Mar 31 11:10:02 volumio systemd[1]: Starting Music Player Daemon... Mar 31 11:10:02 volumio volumio[858]: info: camilladsp spawned new process with pid 6250, instance 1, run: true Mar 31 11:10:02 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:10:02 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:10:02 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:10:02 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:10:02 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:10:02 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:10:02 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:10:02 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:10:02 volumio sudo[6248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/uninstall.sh Mar 31 11:10:02 volumio sudo[6248]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 11:10:02 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 11:10:02 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:10:02 volumio volumio[858]: info: Starting Shairport Sync Mar 31 11:10:02 volumio sudo[6264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 31 11:10:02 volumio sudo[6264]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 11:10:02 volumio volumio[858]: info: camilladsp spawned new process with pid 6265, instance 1, run: true Mar 31 11:10:02 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 31 11:10:02 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 31 11:10:02 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 31 11:10:02 volumio volumio[858]: info: camilladsp spawned new process with pid 6276, instance 1, run: true Mar 31 11:10:02 volumio sudo[6274]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /etc/systemd/system/multi-user.target.wants/fusiondsp.service Mar 31 11:10:02 volumio sudo[6274]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 11:10:02 volumio volumio[858]: info: Uninstall script completed Mar 31 11:10:02 volumio sudo[6274]: pam_unix(sudo:session): session closed for user root Mar 31 11:10:02 volumio sudo[6248]: pam_unix(sudo:session): session closed for user root Mar 31 11:10:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 31 11:10:02 volumio volumio[858]: info: Removing plugin fusiondsp from configuration Mar 31 11:10:02 volumio sudo[6264]: pam_unix(sudo:session): session closed for user root Mar 31 11:10:03 volumio volumio[858]: info: Successfully removed fusiondsp configuration files Mar 31 11:10:03 volumio volumio[858]: info: Plugin folders cleanup Mar 31 11:10:03 volumio volumio[858]: info: Scanning into folder /volumio/app/plugins/ Mar 31 11:10:03 volumio volumio[858]: info: Scanning category audio_interface Mar 31 11:10:03 volumio volumio[858]: info: Scanning category miscellanea Mar 31 11:10:03 volumio volumio[858]: info: Scanning category music_service Mar 31 11:10:03 volumio volumio[858]: info: Scanning category plugins.json Mar 31 11:10:03 volumio volumio[858]: info: Scanning category system_controller Mar 31 11:10:03 volumio volumio[858]: info: Scanning category user_interface Mar 31 11:10:03 volumio volumio[858]: info: Scanning into folder /data/plugins/ Mar 31 11:10:03 volumio volumio[858]: info: Scanning category audio_interface Mar 31 11:10:03 volumio volumio[858]: info: Cleaning folder for fusiondsp Mar 31 11:10:03 volumio mpd[6238]: Mar 31 11:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 31 11:10:03 volumio systemd[1]: Started Music Player Daemon. Mar 31 11:10:03 volumio sudo[6233]: pam_unix(sudo:session): session closed for user root Mar 31 11:10:03 volumio volumio[858]: info: Scanning category music_service Mar 31 11:10:03 volumio volumio[858]: info: Scanning category system_controller Mar 31 11:10:03 volumio volumio[858]: info: Scanning category user_interface Mar 31 11:10:03 volumio volumio[858]: info: Plugin folders cleanup completed Mar 31 11:10:03 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 31 11:10:03 volumio volumio[858]: info: [{"prettyName":"YouTube2","name":"youtube2","category":"music_service","version":"1.1.5","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Cast Receiver","name":"ytcr","category":"music_service","version":"1.0.6","icon":"fa-youtube","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"AutoStart","name":"autostart","category":"system_controller","version":"3.0.4","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Now Playing","name":"now_playing","category":"user_interface","version":"0.5.5","icon":"fa-play-circle","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.4.0","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":false,"active":false}] Mar 31 11:10:03 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 31 11:10:03 volumio volumio[858]: error: Upnp client error: Error: This socket has been ended by the other party Mar 31 11:10:03 volumio volumio[858]: info: Shairport-Sync Started Mar 31 11:10:03 volumio volumio[858]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Mar 31 11:10:03 volumio volumio[858]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 11:10:03 volumio volumio[858]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Mar 31 11:10:03 volumio volumio[858]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Mar 31 11:10:03 volumio volumio[858]: at onErrorNT (internal/child_process.js:465:16) Mar 31 11:10:03 volumio volumio[858]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Mar 31 11:10:03 volumio volumio[858]: errno: -2, Mar 31 11:10:03 volumio volumio[858]: code: 'ENOENT', Mar 31 11:10:03 volumio volumio[858]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Mar 31 11:10:03 volumio volumio[858]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Mar 31 11:10:03 volumio volumio[858]: spawnargs: [ Mar 31 11:10:03 volumio volumio[858]: '-p', Mar 31 11:10:03 volumio volumio[858]: 9876, Mar 31 11:10:03 volumio volumio[858]: '-o', Mar 31 11:10:03 volumio volumio[858]: '/tmp/camilladsp.log', Mar 31 11:10:03 volumio volumio[858]: '-l', Mar 31 11:10:03 volumio volumio[858]: 'warn', Mar 31 11:10:03 volumio volumio[858]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Mar 31 11:10:03 volumio volumio[858]: ] Mar 31 11:10:03 volumio volumio[858]: } Mar 31 11:10:03 volumio volumio[858]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 11:10:03 volumio sudo[6303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-31 11:09 Mar 31 11:10:03 volumio sudo[6303]: 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"