-- Logs begin at Wed 2024-04-17 16:43:56 +07, end at Wed 2024-04-17 16:45:03 +07. -- Apr 17 16:44:00 volumio kernel: r8169 0000:01:00.0 eth0: RTL8168h/8111h, b4:45:06:2c:3e:bd, XID 541, IRQ 130 Apr 17 16:44:00 volumio kernel: r8169 0000:01:00.0 eth0: jumbo features [frames: 9194 bytes, tx checksumming: ko] Apr 17 16:44:00 volumio kernel: input: chtdc_ti_pwrbtn as /devices/pci0000:00/808622C1:06/i2c-4/i2c-INT33F5:00/chtdc_ti_pwrbtn/input/input2 Apr 17 16:44:00 volumio kernel: SSE version of gcm_enc/dec engaged. Apr 17 16:44:00 volumio kernel: input: Dell WMI hotkeys as /devices/platform/PNP0C14:00/wmi_bus/wmi_bus-PNP0C14:00/9DBB5994-A997-11DA-B012-B622A1EF5492/input/input3 Apr 17 16:44:00 volumio systemd-udevd[273]: Using default interface naming scheme 'v240'. Apr 17 16:44:00 volumio kernel: input: cht-bsw-rt5672 Headset as /devices/pci0000:00/808622A8:00/cht-bsw-rt5672/sound/card0/input4 Apr 17 16:44:00 volumio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Apr 17 16:44:00 volumio systemd-udevd[273]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 17 16:44:01 volumio systemd-udevd[275]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 17 16:44:01 volumio kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Apr 17 16:44:01 volumio systemd-udevd[279]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 17 16:44:01 volumio systemd-udevd[266]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 17 16:44:01 volumio systemd-udevd[274]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 17 16:44:02 volumio systemd-udevd[276]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 17 16:44:04 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Apr 17 16:44:04 volumio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Apr 17 16:44:04 volumio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Apr 17 16:44:04 volumio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Apr 17 16:44:04 volumio systemd[1]: Starting File System Check on /dev/disk/by-uuid/EA95-DE9D... Apr 17 16:44:04 volumio systemd[1]: Started File System Check Daemon to report status. Apr 17 16:44:04 volumio systemd-fsck[436]: fsck.fat 4.1 (2017-01-24) Apr 17 16:44:04 volumio systemd-fsck[436]: There are differences between boot sector and its backup. Apr 17 16:44:04 volumio systemd-fsck[436]: This is mostly harmless. Differences: (offset:original/backup) Apr 17 16:44:04 volumio systemd-fsck[436]: 3:53/6d, 4:59/6b, 5:53/66, 6:4c/73, 7:49/2e, 8:4e/66, 9:55/61, 10:58/74 Apr 17 16:44:04 volumio systemd-fsck[436]: , 90:fa/0e, 91:fc/1f, 92:31/be, 93:c9/77, 94:8e/7c, 95:d1/ac, 96:bc/22 Apr 17 16:44:04 volumio systemd-fsck[436]: , 97:76/c0, 98:7b/74, 99:52/0b, 100:06/56, 101:57/b4, 102:1e/0e, 103:56/bb Apr 17 16:44:04 volumio systemd-fsck[436]: , 104:8e/07, 105:c1/00, 106:b1/cd, 107:26/10, 108:bf/5e, 109:78/eb Apr 17 16:44:04 volumio systemd-fsck[436]: , 110:7b/f0, 111:f3/32, 112:a5/e4, 113:8e/cd, 114:d9/16, 115:bb/cd Apr 17 16:44:04 volumio systemd-fsck[436]: , 116:78/19, 117:00/eb, 118:0f/fe, 119:b4/54, 120:37/68, 121:0f/69 Apr 17 16:44:04 volumio systemd-fsck[436]: , 122:a0/73, 123:56/20, 124:20/69, 125:d2/73, 126:78/20, 127:1b/6e Apr 17 16:44:04 volumio systemd-fsck[436]: , 128:31/6f, 129:c0/74, 130:b1/20, 131:06/61, 132:89/20, 133:3f/62 Apr 17 16:44:04 volumio systemd-fsck[436]: , 134:89/6f, 135:47/6f, 136:02/74, 137:f3/61, 138:64/62, 139:a5/6c Apr 17 16:44:04 volumio systemd-fsck[436]: , 140:8a/65, 141:0e/20, 142:18/64, 143:7c/69, 144:88/73, 145:4d/6b Apr 17 16:44:04 volumio systemd-fsck[436]: , 146:f8/2e, 147:50/20, 148:50/20, 150:50/6c, 151:cd/65, 152:13/61 Apr 17 16:44:04 volumio systemd-fsck[436]: , 153:eb/73, 154:62/65, 155:8b/20, 156:55/69, 157:aa/6e, 158:8b/73 Apr 17 16:44:04 volumio systemd-fsck[436]: , 159:75/65, 160:a8/72, 161:c1/74, 162:ee/20, 163:04/61, 164:01/20 Apr 17 16:44:04 volumio systemd-fsck[436]: , 165:f2/62, 166:83/6f, 167:fa/6f, 168:4f/74, 169:76/61, 170:31/62 Apr 17 16:44:04 volumio systemd-fsck[436]: , 171:81/6c, 172:fa/65, 173:b2/20, 174:07/66, 175:73/6c, 176:2b/6f Apr 17 16:44:04 volumio systemd-fsck[436]: , 177:f6/70, 178:45/70, 179:b4/79, 180:7f/20, 181:75/61, 182:25/6e Apr 17 16:44:04 volumio systemd-fsck[436]: , 183:38/64, 184:4d/0d, 185:b8/0a, 186:74/70, 187:20/72, 188:66/65 Apr 17 16:44:04 volumio systemd-fsck[436]: , 189:3d/73, 190:21/73, 191:47/20, 192:50/61, 193:54/6e, 194:75/79 Apr 17 16:44:04 volumio systemd-fsck[436]: , 195:10/20, 196:80/6b, 197:7d/65, 198:b8/79, 199:ed/20, 200:75/74 Apr 17 16:44:04 volumio systemd-fsck[436]: , 201:0a/6f, 202:66/20, 203:ff/74, 204:75/72, 205:ec/79, 206:66/20 Apr 17 16:44:04 volumio systemd-fsck[436]: , 207:ff/61, 208:75/67, 209:e8/61, 210:eb/69, 211:0f/6e, 212:51/20 Apr 17 16:44:04 volumio systemd-fsck[436]: , 213:51/2e, 214:66/2e, 215:ff/2e, 216:75/20, 217:bc/0d, 218:eb/0a Apr 17 16:44:04 volumio systemd-fsck[436]: , 219:07/00, 220:51/00, 221:51/00, 222:66/00, 223:ff/00, 224:36/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 225:1c/00, 226:7c/00, 227:b4/00, 228:08/00, 229:e8/00, 230:e9/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 232:72/00, 233:13/00, 234:20/00, 235:e4/00, 236:75/00, 237:0f/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 238:c1/00, 239:ea/00, 240:08/00, 241:42/00, 242:89/00, 243:16/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 244:1a/00, 245:7c/00, 246:83/00, 247:e1/00, 248:3f/00, 249:89/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 250:0e/00, 251:18/00, 252:7c/00, 253:fb/00, 254:bb/00, 255:aa/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 256:55/00, 257:b4/00, 258:41/00, 259:e8/00, 260:cb/00, 262:72/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 263:10/00, 264:81/00, 265:fb/00, 266:55/00, 267:aa/00, 268:75/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 269:0a/00, 270:f6/00, 271:c1/00, 272:01/00, 273:74/00, 274:05/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 275:c6/00, 276:06/00, 277:46/00, 278:7d/00, 280:66/00, 281:b8/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 282:2d/00, 283:15/00, 286:66/00, 287:ba/00, 292:bb/00, 294:80/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 295:e8/00, 296:0e/00, 298:66/00, 299:81/00, 300:3e/00, 301:1c/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 302:80/00, 303:d5/00, 304:cf/00, 305:c7/00, 306:62/00, 307:75/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 308:74/00, 309:e9/00, 310:f8/00, 311:02/00, 312:66/00, 313:03/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 314:06/00, 315:60/00, 316:7b/00, 317:66/00, 318:13/00, 319:16/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 320:64/00, 321:7b/00, 322:b9/00, 323:10/00, 325:eb/00, 326:2b/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 327:66/00, 328:52/00, 329:66/00, 330:50/00, 331:06/00, 332:53/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 333:6a/00, 334:01/00, 335:6a/00, 336:10/00, 337:89/00, 338:e6/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 339:66/00, 340:60/00, 341:b4/00, 342:42/00, 343:e8/00, 344:77/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 346:66/00, 347:61/00, 348:8d/00, 349:64/00, 350:10/00, 351:72/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 352:01/00, 353:c3/00, 354:66/00, 355:60/00, 356:31/00, 357:c0/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 358:e8/00, 359:68/00, 361:66/00, 362:61/00, 363:e2/00, 364:da/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 365:c6/00, 366:06/00, 367:46/00, 368:7d/00, 369:2b/00, 370:66/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 371:60/00, 372:66/00, 373:0f/00, 374:b7/00, 375:36/00, 376:18/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 377:7c/00, 378:66/00, 379:0f/00, 380:b7/00, 381:3e/00, 382:1a/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 383:7c/00, 384:66/00, 385:f7/00, 386:f6/00, 387:31/00, 388:c9/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 389:87/00, 390:ca/00, 391:66/00, 392:f7/00, 393:f7/00, 394:66/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 395:3d/00, 396:ff/00, 397:03/00, 400:77/00, 401:17/00, 402:c0/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 403:e4/00, 404:06/00, 405:41/00, 406:08/00, 407:e1/00, 408:88/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 409:c5/00, 410:88/00, 411:d6/00, 412:b8/00, 413:01/00, 414:02/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 415:e8/00, 416:2f/00, 418:66/00, 419:61/00, 420:72/00, 421:01/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 422:c3/00, 423:e2/00, 424:c9/00, 425:31/00, 426:f6/00, 427:8e/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 428:d6/00, 429:bc/00, 430:68/00, 431:7b/00, 432:8e/00, 433:de/00 Apr 17 16:44:04 volumio systemd-fsck[436]: , 434:66/00, 435:8f/00, 436:06/00, 437:78/00, 439:be/00, 440:da/00 Apr 17 16:44:05 volumio systemd-fsck[436]: , 441:7d/00, 442:ac/00, 443:20/00, 444:c0/00, 445:74/00, 446:09/00 Apr 17 16:44:05 volumio systemd-fsck[436]: , 447:b4/00, 448:0e/00, 449:bb/00, 450:07/00, 452:cd/00, 453:10/00 Apr 17 16:44:05 volumio systemd-fsck[436]: , 454:eb/00, 455:f2/00, 456:31/00, 457:c0/00, 458:cd/00, 459:16/00 Apr 17 16:44:05 volumio systemd-fsck[436]: , 460:cd/00, 461:19/00, 462:f4/00, 463:eb/00, 464:fd/00, 465:8a/00 Apr 17 16:44:05 volumio systemd-fsck[436]: , 466:16/00, 467:74/00, 468:7b/00, 469:06/00, 470:cd/00, 471:13/00 Apr 17 16:44:05 volumio systemd-fsck[436]: , 472:07/00, 473:c3/00, 474:42/00, 475:6f/00, 476:6f/00, 477:74/00 Apr 17 16:44:05 volumio systemd-fsck[436]: , 478:20/00, 479:65/00, 480:72/00, 481:72/00, 482:6f/00, 483:72/00 Apr 17 16:44:05 volumio systemd-fsck[436]: , 484:0d/00, 485:0a/00, 504:fe/00, 505:02/00, 506:b2/00, 507:3e/00 Apr 17 16:44:05 volumio systemd-fsck[436]: , 508:18/00, 509:37/00 Apr 17 16:44:05 volumio systemd-fsck[436]: Not automatically fixing this. Apr 17 16:44:05 volumio systemd-fsck[436]: /dev/mmcblk1p1: 20 files, 125340/344788 clusters Apr 17 16:44:05 volumio systemd[1]: Started File System Check on /dev/disk/by-uuid/EA95-DE9D. Apr 17 16:44:05 volumio systemd[1]: Mounting /boot... Apr 17 16:44:05 volumio kernel: FAT-fs (mmcblk1p1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Apr 17 16:44:05 volumio systemd[1]: Mounted /boot. Apr 17 16:44:05 volumio systemd[1]: Reached target Local File Systems. Apr 17 16:44:05 volumio systemd[1]: Starting Preprocess NFS configuration... Apr 17 16:44:05 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Apr 17 16:44:05 volumio systemd[1]: Starting Raise network interfaces... Apr 17 16:44:05 volumio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Apr 17 16:44:05 volumio systemd[1]: Starting Create Volatile Files and Directories... Apr 17 16:44:05 volumio systemd[1]: plymouth-read-write.service: Succeeded. Apr 17 16:44:05 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Apr 17 16:44:05 volumio systemd[1]: Received SIGRTMIN+20 from PID 283 (plymouthd). Apr 17 16:44:05 volumio systemd[1]: nfs-config.service: Succeeded. Apr 17 16:44:05 volumio systemd[1]: Started Preprocess NFS configuration. Apr 17 16:44:05 volumio systemd[1]: Started Create Volatile Files and Directories. Apr 17 16:44:05 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Apr 17 16:44:05 volumio systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Apr 17 16:44:05 volumio systemd[1]: Starting RPC bind portmap service... Apr 17 16:44:05 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Apr 17 16:44:05 volumio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Apr 17 16:44:05 volumio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Apr 17 16:44:05 volumio systemd[1]: Reached target NFS client services. Apr 17 16:44:05 volumio ifup[443]: command failed: No such device (-19) Apr 17 16:44:05 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown. Apr 17 16:44:05 volumio systemd[1]: Reached target System Initialization. Apr 17 16:44:05 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Apr 17 16:44:05 volumio systemd[1]: Started Daily apt download activities. Apr 17 16:44:05 volumio systemd[1]: Listening on mpd.socket. Apr 17 16:44:05 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Apr 17 16:44:05 volumio systemd[1]: Started Daily apt upgrade and clean activities. Apr 17 16:44:05 volumio systemd[1]: Listening on triggerhappy.socket. Apr 17 16:44:05 volumio systemd[1]: Reached target Sockets. Apr 17 16:44:05 volumio systemd[1]: Started Daily man-db regeneration. Apr 17 16:44:05 volumio systemd[1]: Reached target Basic System. Apr 17 16:44:05 volumio systemd[1]: Starting Login Service... Apr 17 16:44:05 volumio systemd[1]: Started D-Bus System Message Bus. Apr 17 16:44:06 volumio systemd[1]: Started Volumio Iptables Module. Apr 17 16:44:06 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 17 16:44:06 volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Apr 17 16:44:06 volumio systemd[1]: Starting triggerhappy global hotkey daemon... Apr 17 16:44:06 volumio systemd[1]: Starting WPA supplicant... Apr 17 16:44:06 volumio systemd[1]: Starting Wireless Services... Apr 17 16:44:06 volumio systemd[1]: Started volumio-remote-updater.service. Apr 17 16:44:06 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Apr 17 16:44:06 volumio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Apr 17 16:44:06 volumio systemd[1]: Starting DHCP Client Daemon... Apr 17 16:44:06 volumio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Apr 17 16:44:06 volumio systemd[1]: Started Manage Sound Card State (restore and store). Apr 17 16:44:06 volumio systemd[1]: Starting Save/Restore Sound Card State... Apr 17 16:44:06 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Apr 17 16:44:06 volumio systemd[1]: Reached target Timers. Apr 17 16:44:06 volumio alsactl[474]: alsactl 1.1.8 daemon started Apr 17 16:44:06 volumio thd[468]: Unable to parse trigger line: Apr 17 16:44:06 volumio thd[468]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Apr 17 16:44:06 volumio thd[468]: Unable to parse trigger line: Apr 17 16:44:06 volumio ifup[443]: command failed: No such device (-19) Apr 17 16:44:06 volumio systemd[1]: Found device RTL8111/8168/8411 PCI Express Gigabit Ethernet Controller. Apr 17 16:44:06 volumio systemd[1]: Started ifup for eth0. Apr 17 16:44:06 volumio sh[493]: command failed: No such device (-19) Apr 17 16:44:07 volumio thd[468]: Found socket passed from systemd Apr 17 16:44:07 volumio systemd-logind[462]: Watching system buttons on /dev/input/event1 (Power Button) Apr 17 16:44:07 volumio systemd-logind[462]: Watching system buttons on /dev/input/event0 (Power Button) Apr 17 16:44:07 volumio systemd-logind[462]: Watching system buttons on /dev/input/event2 (chtdc_ti_pwrbtn) Apr 17 16:44:07 volumio systemd-logind[462]: New seat seat0. Apr 17 16:44:07 volumio haveged[456]: haveged: ver: 1.9.1; arch: x86; vend: GenuineIntel; build: (gcc 8.3.0 ITV); collect: 128K Apr 17 16:44:07 volumio haveged[456]: haveged: cpu: (L4 VC); data: 24K (L4 V); inst: 32K (L4 V); idx: 24/40; sz: 31410/52825 Apr 17 16:44:07 volumio haveged[456]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.9939 Apr 17 16:44:07 volumio haveged[456]: haveged: fills: 0, generated: 0 Apr 17 16:44:07 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 Apr 17 16:44:08 volumio dhcpcd[473]: no interfaces have a carrier Apr 17 16:44:08 volumio dhcpcd[473]: no interfaces have a carrier Apr 17 16:44:08 volumio dhcpcd[473]: forked to background, child pid 536 Apr 17 16:44:08 volumio dhcpcd[473]: forked to background, child pid 536 Apr 17 16:44:08 volumio kernel: Generic FE-GE Realtek PHY r8169-0-100:00: attached PHY driver [Generic FE-GE Realtek PHY] (mii_bus:phy_addr=r8169-0-100:00, irq=IGNORE) Apr 17 16:44:08 volumio avahi-daemon[472]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). Apr 17 16:44:08 volumio avahi-daemon[472]: Successfully dropped root privileges. Apr 17 16:44:08 volumio avahi-daemon[472]: avahi-daemon 0.7 starting up. Apr 17 16:44:08 volumio dhcpcd[536]: eth0: waiting for carrier Apr 17 16:44:08 volumio kernel: r8169 0000:01:00.0 eth0: Link is Down Apr 17 16:44:08 volumio sh[493]: eth0: waiting for carrier Apr 17 16:44:08 volumio sh[493]: eth0: carrier acquired Apr 17 16:44:08 volumio dhcpcd[536]: eth0: carrier acquired Apr 17 16:44:08 volumio dhcpcd[511]: eth0: waiting for carrier Apr 17 16:44:08 volumio dhcpcd[511]: eth0: carrier acquired Apr 17 16:44:08 volumio sh[493]: DUID 00:04:4c:4c:45:44:00:56:47:10:80:42:b3:c0:4f:4b:47:33 Apr 17 16:44:08 volumio sh[493]: eth0: IAID 06:2c:3e:bd Apr 17 16:44:08 volumio dhcpcd[536]: DUID 00:04:4c:4c:45:44:00:56:47:10:80:42:b3:c0:4f:4b:47:33 Apr 17 16:44:08 volumio dhcpcd[511]: DUID 00:04:4c:4c:45:44:00:56:47:10:80:42:b3:c0:4f:4b:47:33 Apr 17 16:44:08 volumio sh[493]: eth0: adding address fe80::28db:8ca4:ddc:5b98 Apr 17 16:44:08 volumio sh[493]: ipv6_addaddr1: Permission denied Apr 17 16:44:08 volumio sh[493]: eth0: carrier lost Apr 17 16:44:08 volumio dhcpcd[536]: eth0: IAID 06:2c:3e:bd Apr 17 16:44:08 volumio dhcpcd[511]: eth0: IAID 06:2c:3e:bd Apr 17 16:44:08 volumio dhcpcd[511]: eth0: adding address fe80::28db:8ca4:ddc:5b98 Apr 17 16:44:08 volumio dhcpcd[511]: ipv6_addaddr1: Permission denied Apr 17 16:44:08 volumio dhcpcd[511]: eth0: carrier lost Apr 17 16:44:08 volumio dhcpcd[536]: eth0: adding address fe80::28db:8ca4:ddc:5b98 Apr 17 16:44:08 volumio dhcpcd[536]: ipv6_addaddr1: Permission denied Apr 17 16:44:08 volumio dhcpcd[536]: eth0: carrier lost Apr 17 16:44:11 volumio systemd[1]: Started Login Service. Apr 17 16:44:11 volumio systemd[1]: Started triggerhappy global hotkey daemon. Apr 17 16:44:11 volumio systemd[1]: Started DHCP Client Daemon. Apr 17 16:44:11 volumio avahi-daemon[472]: Successfully called chroot(). Apr 17 16:44:11 volumio avahi-daemon[472]: Successfully dropped remaining capabilities. Apr 17 16:44:11 volumio avahi-daemon[472]: No service file found in /etc/avahi/services. Apr 17 16:44:11 volumio avahi-daemon[472]: Network interface enumeration completed. Apr 17 16:44:11 volumio avahi-daemon[472]: Server startup complete. Host name is volumio.local. Local service cookie is 687866976. Apr 17 16:44:11 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Apr 17 16:44:11 volumio systemd[1]: Started Raise network interfaces. Apr 17 16:44:13 volumio systemd[1]: iptables.service: Succeeded. Apr 17 16:44:13 volumio systemd[1]: Started RPC bind portmap service. Apr 17 16:44:13 volumio systemd[1]: Reached target Remote File Systems (Pre). Apr 17 16:44:13 volumio systemd[1]: Reached target Remote File Systems. Apr 17 16:44:13 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Apr 17 16:44:13 volumio systemd[1]: Reached target RPC Port Mapper. Apr 17 16:44:14 volumio loadcpufreq[595]: Loading cpufreq kernel modules...done (acpi-cpufreq). Apr 17 16:44:14 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Apr 17 16:44:14 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Apr 17 16:44:15 volumio cpufrequtils[630]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done. Apr 17 16:44:15 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Apr 17 16:44:19 volumio systemd[1]: Started Save/Restore Sound Card State. Apr 17 16:44:19 volumio systemd[1]: Reached target Sound Card. Apr 17 16:44:21 volumio systemd[1]: Started WPA supplicant. Apr 17 16:44:21 volumio systemd[1]: Reached target Network. Apr 17 16:44:21 volumio wpa_supplicant[469]: Successfully initialized wpa_supplicant Apr 17 16:44:21 volumio systemd[1]: Starting Music Player Daemon... Apr 17 16:44:21 volumio systemd[1]: Starting Permit User Sessions... Apr 17 16:44:21 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Apr 17 16:44:21 volumio systemd[1]: Starting Network Time Service... Apr 17 16:44:21 volumio systemd[1]: Reached target Network is Online. Apr 17 16:44:21 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Apr 17 16:44:21 volumio systemd[1]: Starting Samba NMB Daemon... Apr 17 16:44:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 17 16:44:21 volumio systemd[1]: Starting /etc/rc.local Compatibility... Apr 17 16:44:21 volumio systemd[1]: Started /etc/rc.local Compatibility. Apr 17 16:44:21 volumio systemd[1]: Started Permit User Sessions. Apr 17 16:44:21 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Apr 17 16:44:21 volumio systemd[1]: Starting Hold until boot process finishes up... Apr 17 16:44:21 volumio systemd[1]: Received SIGRTMIN+21 from PID 283 (plymouthd). Apr 17 16:44:21 volumio systemd[1]: plymouth-start.service: Succeeded. Apr 17 16:44:21 volumio systemd[1]: plymouth-quit.service: Succeeded. Apr 17 16:44:21 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Apr 17 16:44:21 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Apr 17 16:44:21 volumio systemd[1]: Started Hold until boot process finishes up. Apr 17 16:44:21 volumio systemd[1]: Received SIGRTMIN+21 from PID 283 (n/a). Apr 17 16:44:21 volumio systemd[1]: Started Getty on tty1. Apr 17 16:44:21 volumio systemd[1]: Reached target Login Prompts. Apr 17 16:44:22 volumio ifplugd(eth0)[666]: ifplugd 0.28 initializing. Apr 17 16:44:22 volumio ifplugd(eth0)[666]: Using interface eth0/B4:45:06:2C:3E:BD with driver (version: 5.10.188-volumio) Apr 17 16:44:22 volumio ifplugd(eth0)[666]: Using detection mode: SIOCETHTOOL Apr 17 16:44:22 volumio ifplugd(eth0)[666]: Initialization complete, link beat not detected. Apr 17 16:44:22 volumio ifplugd[642]: Network Interface Plugging Daemon...start eth0...done. Apr 17 16:44:22 volumio systemd[1]: Started LSB: Brings up/down network automatically. Apr 17 16:44:25 volumio volumio-remote-updater[471]: Error: No active session Apr 17 16:44:25 volumio volumio-remote-updater[471]: [2024-04-17 16:44:25] [info] asio async_connect error: system:111 (Connection refused) Apr 17 16:44:25 volumio volumio-remote-updater[471]: [2024-04-17 16:44:25] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 17 16:44:25 volumio volumio-remote-updater[471]: [2024-04-17 16:44:25] [error] handle_connect error: Underlying Transport Error Apr 17 16:44:26 volumio ntpd[641]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 17 16:44:26 volumio ntpd[641]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 17 16:44:26 volumio systemd[1]: Started Network Time Service. Apr 17 16:44:27 volumio ntpd[673]: proto: precision = 0.862 usec (-20) Apr 17 16:44:27 volumio ntpd[673]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 17 16:44:27 volumio ntpd[673]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 17 16:44:27 volumio ntpd[673]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 477 days ago Apr 17 16:44:27 volumio ntpd[673]: Listen and drop on 0 v6wildcard [::]:123 Apr 17 16:44:27 volumio ntpd[673]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 17 16:44:27 volumio ntpd[673]: Listen normally on 2 lo 127.0.0.1:123 Apr 17 16:44:27 volumio ntpd[673]: Listening on routing socket on fd #19 for interface updates Apr 17 16:44:27 volumio ntpd[673]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 17 16:44:27 volumio ntpd[673]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 17 16:44:28 volumio ntpd[673]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 17 16:44:29 volumio ntpd[673]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 17 16:44:30 volumio ntpd[673]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 17 16:44:30 volumio volumio-remote-updater[471]: [2024-04-17 16:44:30] [info] asio async_connect error: system:111 (Connection refused) Apr 17 16:44:30 volumio volumio-remote-updater[471]: [2024-04-17 16:44:30] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 17 16:44:30 volumio volumio-remote-updater[471]: [2024-04-17 16:44:30] [error] handle_connect error: Underlying Transport Error Apr 17 16:44:30 volumio volumio[467]: Could not open config: /tmp/upmpdcli.conf Apr 17 16:44:31 volumio ntpd[673]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 17 16:44:32 volumio kernel: i915 0000:00:02.0: vgaarb: deactivate vga console Apr 17 16:44:32 volumio kernel: i915 0000:00:02.0: vgaarb: changed VGA decodes: olddecodes=io+mem,decodes=io+mem:owns=io+mem Apr 17 16:44:32 volumio kernel: [drm] Initialized i915 1.6.0 20200917 for 0000:00:02.0 on minor 0 Apr 17 16:44:32 volumio kernel: ACPI: Video Device [GFX0] (multi-head: yes rom: no post: no) Apr 17 16:44:32 volumio kernel: input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input5 Apr 17 16:44:32 volumio kernel: i915 0000:00:02.0: [drm] HDaudio controller not detected, using LPE audio instead Apr 17 16:44:32 volumio systemd-udevd[274]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 17 16:44:32 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 17 16:44:32 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 17 16:44:32 volumio kernel: i915 0000:00:02.0: [drm] Cannot find any crtc or sizes Apr 17 16:44:32 volumio kernel: input: Intel HDMI/DP LPE Audio HDMI/DP,pcm=0 as /devices/pci0000:00/0000:00:02.0/hdmi-lpe-audio/sound/card1/input6 Apr 17 16:44:32 volumio kernel: input: Intel HDMI/DP LPE Audio HDMI/DP,pcm=1 as /devices/pci0000:00/0000:00:02.0/hdmi-lpe-audio/sound/card1/input7 Apr 17 16:44:32 volumio kernel: input: Intel HDMI/DP LPE Audio HDMI/DP,pcm=2 as /devices/pci0000:00/0000:00:02.0/hdmi-lpe-audio/sound/card1/input8 Apr 17 16:44:32 volumio systemd-udevd[274]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 17 16:44:32 volumio systemd-udevd[276]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 17 16:44:32 volumio systemd-udevd[279]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 17 16:44:32 volumio kernel: i915 0000:00:02.0: [drm] Cannot find any crtc or sizes Apr 17 16:44:32 volumio kernel: usb 1-2: 88XXau 5c:62:8b:31:c5:e2 hw_info[107] Apr 17 16:44:32 volumio kernel: usbcore: registered new interface driver rtl88XXau Apr 17 16:44:32 volumio systemd-udevd[279]: Using default interface naming scheme 'v240'. Apr 17 16:44:32 volumio systemd[1]: Starting Load/Save RF Kill Switch Status... Apr 17 16:44:32 volumio systemd-udevd[279]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 17 16:44:32 volumio systemd[1]: Started Load/Save RF Kill Switch Status. Apr 17 16:44:33 volumio nmbd[647]: [2024/04/17 16:44:33.024693, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Apr 17 16:44:33 volumio nmbd[647]: started asyncdns process 711 Apr 17 16:44:33 volumio systemd[1]: Found device 802.11ac_WLAN_Adapter. Apr 17 16:44:33 volumio systemd[1]: Started ifup for wlan0. Apr 17 16:44:33 volumio dhcpcd[536]: wlan0: waiting for carrier Apr 17 16:44:33 volumio dhcpcd[536]: wlan0: carrier acquired Apr 17 16:44:33 volumio nmbd[647]: [2024/04/17 16:44:33.224056, 0] ../lib/util/become_daemon.c:149(daemon_status) Apr 17 16:44:33 volumio nmbd[647]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Apr 17 16:44:33 volumio nmbd[647]: [2024/04/17 16:44:33.224211, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Apr 17 16:44:33 volumio nmbd[647]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Apr 17 16:44:33 volumio dhcpcd[536]: wlan0: IAID 8b:31:c5:e2 Apr 17 16:44:33 volumio dhcpcd[536]: wlan0: adding address fe80::cff5:2f84:9833:478 Apr 17 16:44:33 volumio dhcpcd[536]: ipv6_addaddr1: Permission denied Apr 17 16:44:33 volumio dhcpcd[536]: wlan0: soliciting a DHCP lease Apr 17 16:44:33 volumio sh[712]: wlan0=wlan0 Apr 17 16:44:33 volumio kernel: i915 0000:00:02.0: [drm] Cannot find any crtc or sizes Apr 17 16:44:33 volumio dhcpcd[536]: wlan0: soliciting an IPv6 router Apr 17 16:44:34 volumio wireless.js[470]: Cleaning previous... Apr 17 16:44:34 volumio sudo[770]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 17 16:44:34 volumio sudo[770]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:34 volumio sudo[770]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:34 volumio sudo[772]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 17 16:44:34 volumio sudo[772]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:34 volumio dhcpcd[536]: wlan0: carrier lost Apr 17 16:44:34 volumio sudo[772]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:34 volumio wireless.js[470]: Stopped aP Apr 17 16:44:34 volumio sudo[793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 17 16:44:34 volumio sudo[793]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:34 volumio sudo[793]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:34 volumio sudo[795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Apr 17 16:44:34 volumio sudo[795]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:34 volumio sudo[795]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:34 volumio sudo[802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 17 16:44:34 volumio sudo[802]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:34 volumio sudo[802]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:34 volumio sudo[804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Apr 17 16:44:34 volumio sudo[804]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:34 volumio mpd[667]: Apr 17 16:44 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 17 16:44:34 volumio mpd[667]: Apr 17 16:44 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 17 16:44:34 volumio mpd[667]: Apr 17 16:44 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 17 16:44:34 volumio systemd[1]: Started Music Player Daemon. Apr 17 16:44:35 volumio systemd[1]: systemd-fsckd.service: Succeeded. Apr 17 16:44:35 volumio dhcpcd[536]: wlan0: carrier acquired Apr 17 16:44:35 volumio dhcpcd[536]: wlan0: IAID 8b:31:c5:e2 Apr 17 16:44:35 volumio dhcpcd[536]: wlan0: soliciting a DHCP lease Apr 17 16:44:35 volumio dhcpcd[536]: wlan0: soliciting an IPv6 router Apr 17 16:44:37 volumio systemd[1]: systemd-rfkill.service: Succeeded. Apr 17 16:44:38 volumio sudo[804]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:38 volumio wireless.js[470]: Start wireless flow Apr 17 16:44:38 volumio wireless.js[470]: Stopped hotspot (if there).. Apr 17 16:44:38 volumio sudo[812]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 17 16:44:38 volumio sudo[812]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:38 volumio sudo[812]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:38 volumio sudo[814]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 17 16:44:38 volumio sudo[814]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:38 volumio dhcpcd[536]: wlan0: carrier lost Apr 17 16:44:38 volumio sudo[814]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:38 volumio wireless.js[470]: DHCP IP Apr 17 16:44:38 volumio wireless.js[470]: Start ap Apr 17 16:44:38 volumio wpa_supplicant[819]: Successfully initialized wpa_supplicant Apr 17 16:44:38 volumio sudo[823]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Apr 17 16:44:38 volumio sudo[823]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:38 volumio volumio-remote-updater[471]: [2024-04-17 16:44:38] [info] asio async_connect error: system:111 (Connection refused) Apr 17 16:44:38 volumio volumio-remote-updater[471]: [2024-04-17 16:44:38] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 17 16:44:38 volumio volumio-remote-updater[471]: [2024-04-17 16:44:38] [error] handle_connect error: Underlying Transport Error Apr 17 16:44:38 volumio wpa_supplicant[819]: nl80211: kernel reports: Authentication algorithm number required Apr 17 16:44:38 volumio dhcpcd[831]: sending commands to master dhcpcd process Apr 17 16:44:38 volumio sudo[823]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:38 volumio dhcpcd[536]: control command: /sbin/dhcpcd Apr 17 16:44:38 volumio sh[493]: timed out Apr 17 16:44:38 volumio dhcpcd[511]: timed out Apr 17 16:44:38 volumio sh[493]: dhcpcd exited Apr 17 16:44:38 volumio dhcpcd[511]: dhcpcd exited Apr 17 16:44:38 volumio sh[493]: ifup: failed to bring up eth0 Apr 17 16:44:38 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Apr 17 16:44:38 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Apr 17 16:44:39 volumio wireless.js[470]: trying... Apr 17 16:44:39 volumio sudo[835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 16:44:39 volumio sudo[835]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:39 volumio sudo[835]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:40 volumio wireless.js[470]: trying... Apr 17 16:44:40 volumio sudo[838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 16:44:40 volumio sudo[838]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:40 volumio sudo[838]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:41 volumio wireless.js[470]: trying... Apr 17 16:44:41 volumio sudo[841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 16:44:41 volumio sudo[841]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:41 volumio sudo[841]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:41 volumio wpa_supplicant[833]: wlan0: Trying to associate with 50:42:89:46:7a:fd (SSID='VIETTEL_150_5G' freq=5180 MHz) Apr 17 16:44:41 volumio wpa_supplicant[833]: nl80211: kernel reports: Authentication algorithm number required Apr 17 16:44:41 volumio wpa_supplicant[833]: wlan0: Associated with 50:42:89:46:7a:fd Apr 17 16:44:41 volumio wpa_supplicant[833]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 17 16:44:41 volumio wpa_supplicant[833]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=VN Apr 17 16:44:42 volumio wpa_supplicant[833]: wlan0: WPA: Key negotiation completed with 50:42:89:46:7a:fd [PTK=CCMP GTK=CCMP] Apr 17 16:44:42 volumio wpa_supplicant[833]: wlan0: CTRL-EVENT-CONNECTED - Connection to 50:42:89:46:7a:fd completed [id=0 id_str=] Apr 17 16:44:42 volumio dhcpcd[536]: wlan0: carrier acquired Apr 17 16:44:42 volumio dhcpcd[536]: wlan0: IAID 8b:31:c5:e2 Apr 17 16:44:42 volumio wireless.js[470]: trying... Apr 17 16:44:42 volumio sudo[845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 16:44:42 volumio sudo[845]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:42 volumio sudo[845]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:42 volumio dhcpcd[536]: wlan0: rebinding lease of 192.168.1.8 Apr 17 16:44:42 volumio dhcpcd[536]: wlan0: probing address 192.168.1.8/24 Apr 17 16:44:42 volumio dhcpcd[536]: wlan0: soliciting an IPv6 router Apr 17 16:44:43 volumio wireless.js[470]: trying... Apr 17 16:44:43 volumio sudo[848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 16:44:43 volumio sudo[848]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:43 volumio sudo[848]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:44 volumio wireless.js[470]: trying... Apr 17 16:44:44 volumio sudo[851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 16:44:44 volumio sudo[851]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:44 volumio sudo[851]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:45 volumio wireless.js[470]: trying... Apr 17 16:44:45 volumio sudo[854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 16:44:45 volumio sudo[854]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:45 volumio sudo[854]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:46 volumio wireless.js[470]: trying... Apr 17 16:44:46 volumio sudo[857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 16:44:46 volumio sudo[857]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:46 volumio sudo[857]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:47 volumio wireless.js[470]: trying... Apr 17 16:44:47 volumio sudo[860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 16:44:47 volumio sudo[860]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:47 volumio sudo[860]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:47 volumio dhcpcd[536]: wlan0: leased 192.168.1.8 for 3600 seconds Apr 17 16:44:47 volumio avahi-daemon[472]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.8. Apr 17 16:44:47 volumio dhcpcd[536]: wlan0: adding route to 192.168.1.0/24 Apr 17 16:44:47 volumio avahi-daemon[472]: New relevant interface wlan0.IPv4 for mDNS. Apr 17 16:44:47 volumio avahi-daemon[472]: Registering new address record for 192.168.1.8 on wlan0.IPv4. Apr 17 16:44:47 volumio dhcpcd[536]: wlan0: adding default route via 192.168.1.1 Apr 17 16:44:47 volumio ntpd[673]: ntpd exiting on signal 15 (Terminated) Apr 17 16:44:47 volumio systemd[1]: Stopping Network Time Service... Apr 17 16:44:47 volumio systemd[1]: ntp.service: Succeeded. Apr 17 16:44:47 volumio systemd[1]: Stopped Network Time Service. Apr 17 16:44:47 volumio systemd[1]: Starting Network Time Service... Apr 17 16:44:47 volumio ntpd[895]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 17 16:44:47 volumio ntpd[895]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 17 16:44:47 volumio systemd[1]: Started Network Time Service. Apr 17 16:44:47 volumio ntpd[901]: proto: precision = 0.237 usec (-22) Apr 17 16:44:47 volumio ntpd[901]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 17 16:44:47 volumio ntpd[901]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 17 16:44:47 volumio ntpd[901]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 477 days ago Apr 17 16:44:47 volumio ntpd[901]: Listen and drop on 0 v6wildcard [::]:123 Apr 17 16:44:47 volumio ntpd[901]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 17 16:44:47 volumio ntpd[901]: Listen normally on 2 lo 127.0.0.1:123 Apr 17 16:44:47 volumio ntpd[901]: Listen normally on 3 wlan0 192.168.1.8:123 Apr 17 16:44:47 volumio ntpd[901]: Listening on routing socket on fd #20 for interface updates Apr 17 16:44:47 volumio ntpd[901]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 17 16:44:47 volumio ntpd[901]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 17 16:44:48 volumio wireless.js[470]: trying... Apr 17 16:44:48 volumio volumio-remote-updater[471]: [2024-04-17 16:44:48] [info] asio async_connect error: system:111 (Connection refused) Apr 17 16:44:48 volumio volumio-remote-updater[471]: [2024-04-17 16:44:48] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 17 16:44:48 volumio volumio-remote-updater[471]: [2024-04-17 16:44:48] [error] handle_connect error: Underlying Transport Error Apr 17 16:44:48 volumio sudo[904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 16:44:48 volumio sudo[904]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:48 volumio sudo[904]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:48 volumio wireless.js[470]: Connected to: ----VIETTEL_150_5G Apr 17 16:44:48 volumio wireless.js[470]: ---- Apr 17 16:44:48 volumio sudo[907]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 17 16:44:48 volumio sudo[907]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:48 volumio sudo[907]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:48 volumio wireless.js[470]: ... joined AP, wlan0 IPv4 is 192.168.1.8, ipV6 is undefined Apr 17 16:44:48 volumio wireless.js[470]: It's done! AP Apr 17 16:44:48 volumio nmbd[647]: [2024/04/17 16:44:48.231417, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 17 16:44:48 volumio nmbd[647]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 17 16:44:48 volumio systemd[1]: Started Samba NMB Daemon. Apr 17 16:44:48 volumio systemd[1]: Starting Samba Winbind Daemon... Apr 17 16:44:48 volumio iwconfig[913]: Error for wireless request "Set Power Management" (8B2C) : Apr 17 16:44:48 volumio iwconfig[913]: SET failed on device wlan0 ; Operation not permitted. Apr 17 16:44:48 volumio systemd[1]: Started Wireless Services. Apr 17 16:44:48 volumio systemd[1]: Started Volumio Backend Module. Apr 17 16:44:48 volumio systemd[1]: Started Volumio Cpu Tweaker. Apr 17 16:44:48 volumio systemd[1]: Started Intel SST and HDA soundcard init service. Apr 17 16:44:48 volumio systemd[1]: Started Start Volumio Kiosk. Apr 17 16:44:48 volumio volumio-cpu-tweak[915]: Setting RT Priority for mpd Apr 17 16:44:48 volumio volumio-cpu-tweak[915]: Setting MPD Affinity Apr 17 16:44:48 volumio volumio-cpu-tweak[915]: pid 667's current affinity mask: f Apr 17 16:44:48 volumio volumio-cpu-tweak[915]: pid 667's new affinity mask: 3 Apr 17 16:44:48 volumio volumio-cpu-tweak[915]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Apr 17 16:44:48 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Apr 17 16:44:48 volumio startx[917]: X.Org X Server 1.20.4 Apr 17 16:44:48 volumio startx[917]: X Protocol Version 11, Revision 0 Apr 17 16:44:48 volumio startx[917]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Apr 17 16:44:48 volumio startx[917]: Current Operating System: Linux volumio 5.10.188-volumio #1 SMP Mon Aug 7 14:08:06 CEST 2023 x86_64 Apr 17 16:44:48 volumio startx[917]: Kernel command line: BOOT_IMAGE=/vmlinuz biosdevname=0 splash plymouth.ignore-serial-consoles quiet ro rootwait imgpart=UUID=a8a64f8d-4c13-47cb-9db1-3268ad11a2c4 bootpart=UUID=EA95-DE9D datapart=UUID=9885bea9-8390-401d-98cc-7dab9759bbe2 imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor use_kmsg=yes net.ifnames=0 loglevel=0 Apr 17 16:44:48 volumio startx[917]: Build Date: 18 December 2021 09:05:36AM Apr 17 16:44:48 volumio startx[917]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Apr 17 16:44:48 volumio startx[917]: Current version of pixman: 0.36.0 Apr 17 16:44:48 volumio startx[917]: Before reporting problems, check http://wiki.x.org Apr 17 16:44:48 volumio startx[917]: to make sure that you have the latest version. Apr 17 16:44:48 volumio startx[917]: Markers: (--) probed, (**) from config file, (==) default setting, Apr 17 16:44:48 volumio startx[917]: (++) from command line, (!!) notice, (II) informational, Apr 17 16:44:48 volumio startx[917]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Apr 17 16:44:48 volumio startx[917]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Apr 17 16:44:48 2024 Apr 17 16:44:48 volumio startx[917]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Apr 17 16:44:48 volumio winbindd[911]: [2024/04/17 16:44:48.561970, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 17 16:44:48 volumio winbindd[911]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 17 16:44:48 volumio systemd[1]: Started Samba Winbind Daemon. Apr 17 16:44:48 volumio winbindd[911]: [2024/04/17 16:44:48.588918, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 17 16:44:48 volumio systemd[1]: Starting Samba SMB Daemon... Apr 17 16:44:48 volumio winbindd[911]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 17 16:44:48 volumio ntpd[901]: Soliciting pool server 203.113.174.44 Apr 17 16:44:49 volumio soundcard-init.sh[916]: cht-bsw-rt5672 detected Apr 17 16:44:49 volumio soundcard-init.sh[916]: No headphones plugged in --> output to Speaker Apr 17 16:44:49 volumio soundcard-init.sh[916]: Starting acpid.service for cht-bsw-rt5672 jack detection Apr 17 16:44:49 volumio systemd[1]: Listening on ACPID Listen Socket. Apr 17 16:44:49 volumio smbd[983]: [2024/04/17 16:44:49.221256, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 17 16:44:49 volumio smbd[983]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 17 16:44:49 volumio systemd[1]: Started ACPI event daemon. Apr 17 16:44:49 volumio acpid[1036]: starting up with netlink and the input layer Apr 17 16:44:49 volumio systemd[1]: Started Samba SMB Daemon. Apr 17 16:44:49 volumio acpid[1036]: 1 rule loaded Apr 17 16:44:49 volumio acpid[1036]: waiting for events: event logging is off Apr 17 16:44:49 volumio soundcard-init.sh[916]: cht-bsw-rt5672 initialised Apr 17 16:44:49 volumio systemd[1]: Reached target Multi-User System. Apr 17 16:44:49 volumio systemd[1]: Reached target Graphical Interface. Apr 17 16:44:49 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 17 16:44:49 volumio kernel: broken atomic modeset userspace detected, disabling atomic Apr 17 16:44:49 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 17 16:44:49 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 17 16:44:49 volumio systemd[1]: Startup finished in 8.087s (firmware) + 7.232s (loader) + 27.176s (kernel) + 55.622s (userspace) = 1min 38.119s. Apr 17 16:44:49 volumio systemd[1]: soundcard-init.service: Succeeded. Apr 17 16:44:49 volumio ntpd[901]: Soliciting pool server 103.130.217.41 Apr 17 16:44:49 volumio startx[917]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Apr 17 16:44:50 volumio ntpd[901]: Soliciting pool server 2403:2c80::1:20 Apr 17 16:44:51 volumio volumio[914]: info: ------------------------------------------- Apr 17 16:44:51 volumio volumio[914]: info: ----- Volumio3 ---- Apr 17 16:44:51 volumio volumio[914]: info: ------------------------------------------- Apr 17 16:44:51 volumio volumio[914]: info: ----- System startup ---- Apr 17 16:44:51 volumio volumio[914]: info: ------------------------------------------- Apr 17 16:44:52 volumio volumio[914]: info: MYVOLUMIO Environment detected Apr 17 16:44:52 volumio volumio[914]: info: Plugin folders cleanup Apr 17 16:44:52 volumio volumio[914]: info: Scanning into folder /volumio/app/plugins/ Apr 17 16:44:52 volumio volumio[914]: info: Scanning category audio_interface Apr 17 16:44:52 volumio volumio[914]: info: Scanning category miscellanea Apr 17 16:44:52 volumio volumio[914]: info: Scanning category music_service Apr 17 16:44:52 volumio volumio[914]: info: Scanning category plugins.json Apr 17 16:44:52 volumio volumio[914]: info: Scanning category system_controller Apr 17 16:44:52 volumio volumio[914]: info: Scanning category user_interface Apr 17 16:44:52 volumio volumio[914]: info: Scanning into folder /data/plugins/ Apr 17 16:44:52 volumio volumio[914]: info: Scanning category audio_interface Apr 17 16:44:52 volumio volumio[914]: info: Scanning category music_service Apr 17 16:44:52 volumio volumio[914]: info: Plugin folders cleanup completed Apr 17 16:44:52 volumio volumio[914]: info: ------------------------------------------- Apr 17 16:44:52 volumio volumio[914]: info: ----- Core plugins startup ---- Apr 17 16:44:52 volumio volumio[914]: info: ------------------------------------------- Apr 17 16:44:52 volumio volumio[914]: info: Loading plugins from folder /volumio/app/plugins/ Apr 17 16:44:52 volumio volumio[914]: info: Adding plugin upnp to MyMusic Plugins Apr 17 16:44:52 volumio volumio[914]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 17 16:44:52 volumio volumio[914]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 17 16:44:52 volumio volumio[914]: info: Loading plugins from folder /data/plugins/ Apr 17 16:44:52 volumio volumio[914]: info: Loading plugin "system"... Apr 17 16:44:52 volumio volumio[914]: info: Loading plugin "appearance"... Apr 17 16:44:54 volumio volumio[914]: info: Loading plugin "network"... Apr 17 16:44:54 volumio volumio[914]: info: Refreshing Cached IP Addresses Apr 17 16:44:54 volumio sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 17 16:44:54 volumio sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:54 volumio sudo[1150]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:54 volumio volumio[914]: info: Loading plugin "services"... Apr 17 16:44:54 volumio sudo[1154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 17 16:44:54 volumio volumio[914]: info: Loading plugin "alsa_controller"... Apr 17 16:44:54 volumio sudo[1154]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:54 volumio sudo[1154]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:54 volumio sudo[1155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 17 16:44:54 volumio sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:54 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 17 16:44:54 volumio volumio[914]: info: Loading plugin "wizard"... Apr 17 16:44:54 volumio volumio[914]: info: Loading plugin "volumio_command_line_client"... Apr 17 16:44:54 volumio volumio[914]: info: Loading plugin "upnp"... Apr 17 16:44:54 volumio volumio[914]: info: [1713347094892] Starting Upmpd Daemon Apr 17 16:44:54 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 17 16:44:54 volumio volumio[914]: info: Loading plugin "my_music"... Apr 17 16:44:54 volumio volumio[914]: info: Loading plugin "mpd"... Apr 17 16:44:54 volumio sudo[1155]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:55 volumio volumio[914]: info: Creating MPD Configuration file Apr 17 16:44:55 volumio volumio[914]: info: Loading plugin "upnp_browser"... Apr 17 16:44:55 volumio sudo[1172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 17 16:44:55 volumio sudo[1172]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:55 volumio sudo[1172]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:55 volumio sudo[1174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 17 16:44:55 volumio sudo[1174]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:44:55 volumio systemd[1]: Stopping Music Player Daemon... Apr 17 16:44:55 volumio systemd[1]: mpd.service: Succeeded. Apr 17 16:44:55 volumio systemd[1]: Stopped Music Player Daemon. Apr 17 16:44:55 volumio systemd[1]: Starting Music Player Daemon... Apr 17 16:44:55 volumio dbus-daemon[463]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.7' (uid=0 pid=1123 comm="/usr/lib/chromium/chromium --show-component-extens") Apr 17 16:44:55 volumio systemd[1]: Starting Bluetooth service... Apr 17 16:44:55 volumio kernel: Bluetooth: Core ver 2.22 Apr 17 16:44:55 volumio kernel: NET: Registered protocol family 31 Apr 17 16:44:55 volumio kernel: Bluetooth: HCI device and connection manager initialized Apr 17 16:44:55 volumio kernel: Bluetooth: HCI socket layer initialized Apr 17 16:44:55 volumio kernel: Bluetooth: L2CAP socket layer initialized Apr 17 16:44:55 volumio kernel: Bluetooth: SCO socket layer initialized Apr 17 16:44:55 volumio bluetoothd[1180]: Bluetooth daemon 5.50 Apr 17 16:44:55 volumio bluetoothd[1180]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Apr 17 16:44:55 volumio dbus-daemon[463]: [system] Successfully activated service 'org.bluez' Apr 17 16:44:55 volumio systemd[1]: Started Bluetooth service. Apr 17 16:44:55 volumio bluetoothd[1180]: Starting SDP server Apr 17 16:44:55 volumio bluetoothd[1180]: Excluding (cli) sap Apr 17 16:44:56 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Apr 17 16:44:56 volumio kernel: Bluetooth: BNEP filters: protocol multicast Apr 17 16:44:56 volumio kernel: Bluetooth: BNEP socket layer initialized Apr 17 16:44:56 volumio dbus-daemon[463]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.8' (uid=0 pid=1180 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Apr 17 16:44:56 volumio bluetoothd[1180]: Bluetooth management interface 1.18 initialized Apr 17 16:44:56 volumio volumio[914]: info: Loading plugin "networkfs"... Apr 17 16:44:56 volumio systemd[1]: Starting Hostname Service... Apr 17 16:44:56 volumio dbus-daemon[463]: [system] Successfully activated service 'org.freedesktop.hostname1' Apr 17 16:44:56 volumio systemd[1]: Started Hostname Service. Apr 17 16:44:56 volumio volumio[914]: info: Starting Udev Watcher for removable devices Apr 17 16:44:56 volumio mpd[1184]: Apr 17 16:44 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 17 16:44:56 volumio mpd[1184]: Apr 17 16:44 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 17 16:44:56 volumio mpd[1184]: Apr 17 16:44 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 17 16:44:56 volumio systemd[1]: Started Music Player Daemon. Apr 17 16:44:56 volumio sudo[1174]: pam_unix(sudo:session): session closed for user root Apr 17 16:44:57 volumio volumio[914]: info: Ignoring mount for partition: boot Apr 17 16:44:57 volumio volumio[914]: info: Ignoring mount for partition: volumio Apr 17 16:44:57 volumio volumio[914]: info: Ignoring mount for partition: volumio_data Apr 17 16:44:57 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 17 16:44:57 volumio volumio[914]: info: Loading plugin "alarm-clock"... Apr 17 16:44:57 volumio volumio[914]: info: Loading plugin "airplay_emulation"... Apr 17 16:44:57 volumio volumio[914]: info: Starting Shairport Sync Apr 17 16:44:57 volumio volumio[914]: info: Loading plugin "last_100"... Apr 17 16:44:57 volumio volumio[914]: info: Loading plugin "webradio"... Apr 17 16:44:57 volumio volumio[914]: info: Loading plugin "i2s_dacs"... Apr 17 16:44:57 volumio volumio[914]: info: I2S DAC not set, start Auto-detection Apr 17 16:44:57 volumio volumio[914]: info: Loading plugin "volumiodiscovery"... Apr 17 16:44:57 volumio volumio[914]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 17 16:44:57 volumio volumio[914]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 17 16:44:57 volumio volumio[914]: *** WARNING *** For more information see Apr 17 16:44:57 volumio node[914]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 17 16:44:57 volumio volumio[914]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 17 16:44:57 volumio volumio[914]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 17 16:44:57 volumio volumio[914]: *** WARNING *** For more information see Apr 17 16:44:57 volumio node[914]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 17 16:44:57 volumio node[914]: *** WARNING *** For more information see Apr 17 16:44:57 volumio node[914]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 17 16:44:57 volumio node[914]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 17 16:44:57 volumio node[914]: *** WARNING *** For more information see Apr 17 16:44:57 volumio volumio[914]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 17 16:44:57 volumio volumio[914]: info: Discovery: Started advertising with name: Volumio Apr 17 16:44:57 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 17 16:44:57 volumio volumio[914]: info: Loading plugin "ytmusic"... Apr 17 16:44:58 volumio volumio-remote-updater[471]: [2024-04-17 16:44:58] [connect] Successful connection Apr 17 16:44:58 volumio volumio[914]: info: Loading plugin "outputs"... Apr 17 16:44:58 volumio volumio[914]: info: Loading plugin "albumart"... Apr 17 16:44:58 volumio volumio[914]: info: Plugin example_plugin is not enabled Apr 17 16:44:58 volumio volumio[914]: info: Loading plugin "inputs"... Apr 17 16:44:58 volumio volumio[914]: info: Loading plugin "updater_comm"... Apr 17 16:44:58 volumio volumio[914]: Forking 3 albumart workers Apr 17 16:44:58 volumio volumio[914]: info: Plugin mpdemulation is not enabled Apr 17 16:44:58 volumio volumio[914]: info: Loading plugin "rest_api"... Apr 17 16:44:58 volumio volumio[914]: info: Loading plugin "websocket"... Apr 17 16:44:58 volumio volumio[914]: info: Loading plugin "fusiondsp"... Apr 17 16:44:59 volumio volumio[914]: info: Applying required configuration parameters for plugin fusiondsp Apr 17 16:44:59 volumio volumio[914]: Starting albumart workers Apr 17 16:44:59 volumio volumio[914]: Starting albumart workers Apr 17 16:44:59 volumio volumio[914]: Starting albumart workers Apr 17 16:44:59 volumio volumio[914]: info: Loading i18n strings for locale vi Apr 17 16:44:59 volumio volumio[914]: Updating browse sources language Apr 17 16:44:59 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 16:44:59 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 16:44:59 volumio volumio[914]: info: CoreCommandRouter::initPlayerControls Apr 17 16:44:59 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:44:59 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:44:59 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:44:59 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:44:59 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:44:59 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:44:59 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:44:59 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:44:59 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 16:44:59 volumio volumio[914]: Express server listening on port 3000 Apr 17 16:44:59 volumio volumio[914]: [Metrics] WebUI: 9s 600.89ms Apr 17 16:44:59 volumio volumio[914]: info: Setting Device type: x86 Apr 17 16:44:59 volumio volumio[914]: info: CoreStateMachine::resetVolumioState Apr 17 16:44:59 volumio volumio[914]: info: CoreStateMachine::getcurrentVolume Apr 17 16:44:59 volumio volumio[914]: info: CoreCommandRouter::volumioRetrievevolume Apr 17 16:44:59 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 16:44:59 volumio volumio[914]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 17 16:44:59 volumio volumio[914]: info: Completed loading Core Plugins Apr 17 16:44:59 volumio volumio[914]: info: Preparing to generate the ALSA configuration file Apr 17 16:44:59 volumio volumio[914]: info: Cannot read play queue from file Apr 17 16:44:59 volumio volumio[914]: info: MPD Permissions set Apr 17 16:44:59 volumio volumio[914]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 17 16:44:59 volumio volumio[914]: info: Reading ALSA contributions from plugins. Apr 17 16:44:59 volumio volumio-remote-updater[471]: [2024-04-17 16:44:59] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1713347098 101 Apr 17 16:44:59 volumio volumio[914]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Apr 17 16:44:59 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 17 16:44:59 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 17 16:44:59 volumio volumio[914]: info: Discovery: Getting this device information Apr 17 16:44:59 volumio volumio[914]: info: CoreCommandRouter::volumioGetState Apr 17 16:44:59 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Apr 17 16:44:59 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 17 16:45:00 volumio volumio[914]: info: Discovery: Getting this device information Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::volumioGetState Apr 17 16:45:00 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 16:45:00 volumio volumio[914]: info: MPD running with PID1184 Apr 17 16:45:00 volumio volumio[914]: ,establishing connection Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:00 volumio volumio[914]: error: updateQueue error: null Apr 17 16:45:00 volumio volumio[914]: info: VolumeController:: Volume=100 Mute =false Apr 17 16:45:00 volumio volumio[914]: info: CoreStateMachine::pushState Apr 17 16:45:00 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::volumioPushState Apr 17 16:45:00 volumio volumio[914]: info: CoreStateMachine::updateTrackBlock Apr 17 16:45:00 volumio volumio[914]: info: CorePlayQueue::getTrackBlock Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::volumioRetrievevolume Apr 17 16:45:00 volumio volumio[914]: info: CoreStateMachine::setRepeat null single undefined Apr 17 16:45:00 volumio volumio[914]: info: CoreStateMachine::pushState Apr 17 16:45:00 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::volumioPushState Apr 17 16:45:00 volumio volumio[914]: info: CoreStateMachine::setRandom null Apr 17 16:45:00 volumio volumio[914]: info: CoreStateMachine::pushState Apr 17 16:45:00 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::volumioPushState Apr 17 16:45:00 volumio volumio[914]: verbose: New Socket.io Connection to 192.168.1.8 from 192.168.1.5 UA: unknown Total Clients: 2 Apr 17 16:45:00 volumio volumio[914]: error: updateQueue error: null Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::volumioGetState Apr 17 16:45:00 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 17 16:45:00 volumio volumio[914]: info: Discovery: adding d6e21494-34c3-4222-9b7a-9f427bb2cb58 Apr 17 16:45:00 volumio volumio[914]: info: Discovery: Found device Volumio Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::volumioGetState Apr 17 16:45:00 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Apr 17 16:45:00 volumio volumio[914]: info: VolumeController:: Volume=100 Mute =false Apr 17 16:45:00 volumio volumio[914]: info: CoreStateMachine::pushState Apr 17 16:45:00 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::volumioPushState Apr 17 16:45:00 volumio volumio[914]: info: Asound.conf file unchanged, so no further update is needed Apr 17 16:45:00 volumio volumio[914]: info: Output device has changed, restarting MPD Apr 17 16:45:00 volumio sudo[1362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 17 16:45:00 volumio sudo[1362]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:45:00 volumio volumio[914]: info: Output device has changed, restarting Shairport Sync Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:00 volumio sudo[1362]: pam_unix(sudo:session): session closed for user root Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 16:45:00 volumio sudo[1364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 17 16:45:00 volumio sudo[1364]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:45:00 volumio systemd[1]: Stopping Music Player Daemon... Apr 17 16:45:00 volumio volumio[914]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 17 16:45:00 volumio volumio[914]: info: ___________ START PLUGINS ___________ Apr 17 16:45:00 volumio systemd[1]: mpd.service: Succeeded. Apr 17 16:45:00 volumio systemd[1]: Stopped Music Player Daemon. Apr 17 16:45:00 volumio systemd[1]: Starting Music Player Daemon... Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 17 16:45:00 volumio volumio[914]: info: [1713347100418] CoreMusicLibrary::Adding element Media Servers Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 16:45:00 volumio volumio[914]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 16:45:00 volumio volumio[914]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 17 16:45:00 volumio volumio[914]: info: [1713347100523] CoreMusicLibrary::Adding element Last_100 Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 17 16:45:00 volumio volumio[914]: info: [1713347100526] CoreMusicLibrary::Adding element Webradio Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 17 16:45:00 volumio volumio[914]: info: [1713347100563] CoreMusicLibrary::Adding element YouTube Music Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 16:45:00 volumio volumio[914]: Cannot find translation for source YouTube Music Apr 17 16:45:00 volumio volumio[914]: info: Loading i18n strings for locale vi Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:00 volumio volumio[914]: info: FusionDsp - mixtype--------------------- Hardware Apr 17 16:45:00 volumio volumio[914]: info: Volumio Calling Home Apr 17 16:45:00 volumio volumio[914]: info: Preparing to generate the ALSA configuration file Apr 17 16:45:00 volumio volumio[914]: info: Completed starting Core Plugins Apr 17 16:45:00 volumio volumio[914]: info: ------------------------------------------- Apr 17 16:45:00 volumio volumio[914]: info: ----- MyVolumio plugins startup ---- Apr 17 16:45:00 volumio volumio[914]: info: ------------------------------------------- Apr 17 16:45:00 volumio volumio[914]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 17 16:45:00 volumio volumio[914]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 17 16:45:00 volumio volumio[914]: info: Reading ALSA contributions from plugins. Apr 17 16:45:00 volumio volumio[914]: info: MPD Permissions set Apr 17 16:45:00 volumio volumio[914]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 17 16:45:00 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:00 volumio volumio[914]: info: Starting Shairport Sync Apr 17 16:45:00 volumio volumio[914]: info: Starting Shairport Sync Apr 17 16:45:00 volumio sudo[1388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 17 16:45:00 volumio sudo[1388]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:45:00 volumio volumio[914]: info: Starting Shairport Sync Apr 17 16:45:00 volumio sudo[1391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 17 16:45:00 volumio sudo[1391]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:45:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 17 16:45:00 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 17 16:45:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 17 16:45:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 17 16:45:01 volumio sudo[1388]: pam_unix(sudo:session): session closed for user root Apr 17 16:45:01 volumio sudo[1391]: pam_unix(sudo:session): session closed for user root Apr 17 16:45:01 volumio sudo[1394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 17 16:45:01 volumio sudo[1394]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:45:01 volumio volumio[914]: info: Asound.conf file unchanged, so no further update is needed Apr 17 16:45:01 volumio volumio[914]: info: Output device has changed, restarting MPD Apr 17 16:45:01 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 17 16:45:01 volumio volumio[914]: info: Output device has changed, restarting Shairport Sync Apr 17 16:45:01 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:01 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 16:45:01 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 17 16:45:01 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 17 16:45:01 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 17 16:45:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 17 16:45:01 volumio sudo[1394]: pam_unix(sudo:session): session closed for user root Apr 17 16:45:01 volumio sudo[1401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 17 16:45:01 volumio sudo[1401]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:45:01 volumio sudo[1399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 17 16:45:01 volumio sudo[1399]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:45:01 volumio volumio[914]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 17 16:45:01 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 17 16:45:01 volumio systemd[1]: mpd.service: Succeeded. Apr 17 16:45:01 volumio systemd[1]: Stopped Music Player Daemon. Apr 17 16:45:01 volumio volumio[914]: info: Shairport-Sync Started Apr 17 16:45:01 volumio volumio[914]: Error adding Membership: Error: addMembership EINVAL Apr 17 16:45:01 volumio volumio[914]: info: Shairport-Sync Started Apr 17 16:45:01 volumio systemd[1]: Starting Music Player Daemon... Apr 17 16:45:01 volumio sudo[1399]: pam_unix(sudo:session): session closed for user root Apr 17 16:45:01 volumio volumio[914]: info: Volumio called home Apr 17 16:45:01 volumio volumio[914]: info: Shairport-Sync Started Apr 17 16:45:01 volumio volumio[914]: info: CoreCommandRouter::volumioGetState Apr 17 16:45:01 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Apr 17 16:45:01 volumio volumio[914]: info: MPD Permissions set Apr 17 16:45:01 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:01 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:01 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:01 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:01 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:01 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:01 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:01 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:01 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 16:45:01 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 17 16:45:01 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:01 volumio volumio[914]: info: Starting Shairport Sync Apr 17 16:45:01 volumio sudo[1418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 17 16:45:01 volumio sudo[1418]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:45:01 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 17 16:45:01 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 17 16:45:01 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 17 16:45:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 17 16:45:01 volumio sudo[1418]: pam_unix(sudo:session): session closed for user root Apr 17 16:45:01 volumio volumio[914]: info: Shairport-Sync Started Apr 17 16:45:02 volumio mpd[1420]: Apr 17 16:45 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 17 16:45:02 volumio mpd[1420]: Apr 17 16:45 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 17 16:45:02 volumio mpd[1420]: Apr 17 16:45 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 17 16:45:02 volumio systemd[1]: Started Music Player Daemon. Apr 17 16:45:02 volumio sudo[1364]: pam_unix(sudo:session): session closed for user root Apr 17 16:45:02 volumio sudo[1401]: pam_unix(sudo:session): session closed for user root Apr 17 16:45:02 volumio volumio[914]: error: MPD error: The expression evaluated to a falsy value: Apr 17 16:45:02 volumio volumio[914]: assert.ok(self.idling) Apr 17 16:45:02 volumio volumio[914]: error: The expression evaluated to a falsy value: Apr 17 16:45:02 volumio volumio[914]: assert.ok(self.idling) Apr 17 16:45:02 volumio volumio[914]: error: updateQueue error: null Apr 17 16:45:02 volumio volumio[914]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 4 Apr 17 16:45:02 volumio volumio[914]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Apr 17 16:45:02 volumio volumio[914]: info: camilladsp service started and running in background, instance 1 Apr 17 16:45:02 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 16:45:02 volumio volumio[914]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 17 16:45:02 volumio volumio[914]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 17 16:45:02 volumio volumio[914]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 17 16:45:02 volumio volumio[914]: info: FusionDsp loaded Apr 17 16:45:02 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 16:45:02 volumio sudo[1436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 17 16:45:02 volumio sudo[1436]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 16:45:02 volumio volumio[914]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 17 16:45:02 volumio volumio[914]: info: Adding Signal Path Element [object Object] Apr 17 16:45:02 volumio volumio[914]: info: Adding fusiondspeq DSP Signal Path Element Apr 17 16:45:02 volumio volumio[914]: info: FusionDsp - ---- installed callbackRead Apr 17 16:45:02 volumio volumio[914]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 17 16:45:02 volumio sudo[1436]: pam_unix(sudo:session): session closed for user root Apr 17 16:45:02 volumio volumio[914]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 17 16:45:02 volumio volumio[914]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Apr 17 16:45:02 volumio volumio[914]: at onErrorNT (internal/child_process.js:472:16) Apr 17 16:45:02 volumio volumio[914]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Apr 17 16:45:02 volumio volumio[914]: errno: -2, Apr 17 16:45:02 volumio volumio[914]: code: 'ENOENT', Apr 17 16:45:02 volumio volumio[914]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 17 16:45:02 volumio volumio[914]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 17 16:45:02 volumio volumio[914]: spawnargs: [ Apr 17 16:45:02 volumio volumio[914]: '-p', Apr 17 16:45:02 volumio volumio[914]: 9876, Apr 17 16:45:02 volumio volumio[914]: '-o', Apr 17 16:45:02 volumio volumio[914]: '/tmp/camilladsp.log', Apr 17 16:45:02 volumio volumio[914]: '-l', Apr 17 16:45:02 volumio volumio[914]: 'warn', Apr 17 16:45:02 volumio volumio[914]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 17 16:45:02 volumio volumio[914]: ] Apr 17 16:45:02 volumio volumio[914]: } Apr 17 16:45:02 volumio volumio[914]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 17 16:45:03 volumio sudo[1447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-17 16:44 Apr 17 16:45:03 volumio sudo[1447]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 04:09:51 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="2fe933ffb50e198f3245fc325fd10b17"