-- Logs begin at Wed 2025-01-15 16:10:52 -02, end at Wed 2025-01-15 16:12:36 -02. -- Jan 15 16:11:00 volumio-acer ntpd[853]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 15 16:11:00 volumio-acer systemd[1]: man-db.service: Succeeded. Jan 15 16:11:00 volumio-acer systemd[1]: Started Daily man-db regeneration. Jan 15 16:11:01 volumio-acer ntpd[853]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 15 16:11:02 volumio-acer ntpd[853]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 15 16:11:02 volumio-acer kernel: new mount options do not match the existing superblock, will be ignored Jan 15 16:11:02 volumio-acer nmbd[837]: [2025/01/15 16:11:02.983474, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 15 16:11:02 volumio-acer nmbd[837]: started asyncdns process 942 Jan 15 16:11:02 volumio-acer nmbd[837]: [2025/01/15 16:11:02.985636, 0] ../lib/util/become_daemon.c:149(daemon_status) Jan 15 16:11:02 volumio-acer nmbd[837]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jan 15 16:11:02 volumio-acer nmbd[837]: [2025/01/15 16:11:02.985690, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jan 15 16:11:02 volumio-acer nmbd[837]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jan 15 16:11:03 volumio-acer ntpd[853]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 15 16:11:03 volumio-acer volumio[636]: cset: --> shielding system active with Jan 15 16:11:03 volumio-acer volumio[636]: cset: "system" cpuset of CPUSPEC(1) with 43 tasks running Jan 15 16:11:03 volumio-acer volumio[636]: cset: "user" cpuset of CPUSPEC(0) with 1 task running Jan 15 16:11:03 volumio-acer systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jan 15 16:11:03 volumio-acer systemd[1]: apt-daily.service: Succeeded. Jan 15 16:11:03 volumio-acer systemd[1]: Started Daily apt download activities. Jan 15 16:11:03 volumio-acer systemd[1]: Starting Daily apt upgrade and clean activities... Jan 15 16:11:03 volumio-acer wireless.js[626]: WIRELESS: No wireless interface, exiting Jan 15 16:11:03 volumio-acer iw[991]: command failed: No such device (-19) Jan 15 16:11:03 volumio-acer iwconfig[995]: Error for wireless request "Set Power Management" (8B2C) : Jan 15 16:11:03 volumio-acer iwconfig[995]: SET failed on device wlan0 ; No such device. Jan 15 16:11:03 volumio-acer systemd[1]: wireless.service: Succeeded. Jan 15 16:11:03 volumio-acer systemd[1]: Started Wireless Services. Jan 15 16:11:03 volumio-acer systemd[1]: Started Volumio Backend Module. Jan 15 16:11:03 volumio-acer volumio-remote-updater[640]: [2025-01-15 16:11:03] [info] asio async_connect error: system:111 (Connection refused) Jan 15 16:11:03 volumio-acer volumio-remote-updater[640]: [2025-01-15 16:11:03] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 15 16:11:03 volumio-acer volumio-remote-updater[640]: [2025-01-15 16:11:03] [error] handle_connect error: Underlying Transport Error Jan 15 16:11:03 volumio-acer systemd[1]: Started Volumio Cpu Tweaker. Jan 15 16:11:03 volumio-acer systemd[1]: Started Intel SST and HDA soundcard init service. Jan 15 16:11:03 volumio-acer systemd[1]: Started Start Volumio Kiosk. Jan 15 16:11:03 volumio-acer volumio-cpu-tweak[999]: Setting RT Priority for mpd Jan 15 16:11:03 volumio-acer volumio-time-update[617]: volumio-time-update-util: Fetching time from Volumio... Jan 15 16:11:03 volumio-acer volumio-time-update[617]: volumio-time-update-util: Date not found in response Jan 15 16:11:03 volumio-acer volumio-time-update[617]: volumio-time-update-util: Retrying in 5 seconds... Jan 15 16:11:03 volumio-acer volumio-cpu-tweak[999]: pid 35's current scheduling policy: SCHED_OTHER Jan 15 16:11:03 volumio-acer volumio-cpu-tweak[999]: pid 35's current scheduling priority: 0 Jan 15 16:11:03 volumio-acer volumio-cpu-tweak[999]: Not enough cores to set MPD affinity Jan 15 16:11:03 volumio-acer volumio-cpu-tweak[999]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jan 15 16:11:04 volumio-acer systemd[1]: volumio_cpu_tweak.service: Succeeded. Jan 15 16:11:04 volumio-acer startx[1001]: X.Org X Server 1.20.4 Jan 15 16:11:04 volumio-acer startx[1001]: X Protocol Version 11, Revision 0 Jan 15 16:11:04 volumio-acer startx[1001]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Jan 15 16:11:04 volumio-acer startx[1001]: Current Operating System: Linux volumio-acer 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64 Jan 15 16:11:04 volumio-acer startx[1001]: Kernel command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=3344ebaa-9648-414f-a439-37ba617b1ff4 bootpart=UUID=A585-FBCE datapart=UUID=ec277072-1003-493e-8699-4a71d0806f3c hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no initrd=volumio.initrd Jan 15 16:11:04 volumio-acer startx[1001]: Build Date: 18 December 2021 09:05:36AM Jan 15 16:11:04 volumio-acer startx[1001]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Jan 15 16:11:04 volumio-acer startx[1001]: Current version of pixman: 0.36.0 Jan 15 16:11:04 volumio-acer startx[1001]: Before reporting problems, check http://wiki.x.org Jan 15 16:11:04 volumio-acer startx[1001]: to make sure that you have the latest version. Jan 15 16:11:04 volumio-acer startx[1001]: Markers: (--) probed, (**) from config file, (==) default setting, Jan 15 16:11:04 volumio-acer startx[1001]: (++) from command line, (!!) notice, (II) informational, Jan 15 16:11:04 volumio-acer startx[1001]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jan 15 16:11:04 volumio-acer startx[1001]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Jan 15 16:11:04 2025 Jan 15 16:11:04 volumio-acer startx[1001]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jan 15 16:11:05 volumio-acer mpd[880]: Jan 15 16:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 15 16:11:05 volumio-acer systemd[1]: Started Music Player Daemon. Jan 15 16:11:05 volumio-acer mpd_monitor.sh[638]: MPD restarted due to no mpc output. Jan 15 16:11:05 volumio-acer systemd[1]: soundcard-init.service: Succeeded. Jan 15 16:11:05 volumio-acer systemd[1]: apt-daily-upgrade.service: Succeeded. Jan 15 16:11:05 volumio-acer systemd[1]: Started Daily apt upgrade and clean activities. Jan 15 16:11:05 volumio-acer startx[1001]: (II) [KMS] Kernel modesetting enabled. Jan 15 16:11:07 volumio-acer volumio[998]: info: ------------------------------------------- Jan 15 16:11:07 volumio-acer volumio[998]: info: ----- Volumio3 ---- Jan 15 16:11:07 volumio-acer volumio[998]: info: ------------------------------------------- Jan 15 16:11:07 volumio-acer volumio[998]: info: ----- System startup ---- Jan 15 16:11:07 volumio-acer volumio[998]: info: ------------------------------------------- Jan 15 16:11:08 volumio-acer volumio[998]: info: MYVOLUMIO Environment detected Jan 15 16:11:08 volumio-acer volumio-time-update[617]: volumio-time-update-util: Fetching time from Volumio... Jan 15 16:11:08 volumio-acer volumio-time-update[617]: volumio-time-update-util: Date not found in response Jan 15 16:11:08 volumio-acer volumio-time-update[617]: volumio-time-update-util: Retrying in 5 seconds... Jan 15 16:11:09 volumio-acer kernel: r8169 0000:02:00.0 eth0: Link is Up - 100Mbps/Full - flow control rx/tx Jan 15 16:11:09 volumio-acer dhcpcd[750]: eth0: carrier acquired Jan 15 16:11:09 volumio-acer dhcpcd[750]: eth0: IAID e2:3a:f1:58 Jan 15 16:11:09 volumio-acer dhcpcd[750]: eth0: soliciting a DHCP lease Jan 15 16:11:09 volumio-acer dhcpcd[750]: eth0: offered 192.168.1.8 from 192.168.1.1 Jan 15 16:11:09 volumio-acer dhcpcd[750]: eth0: probing address 192.168.1.8/24 Jan 15 16:11:09 volumio-acer ifplugd(eth0)[868]: Link beat detected. Jan 15 16:11:09 volumio-acer dhcpcd[750]: eth0: soliciting an IPv6 router Jan 15 16:11:09 volumio-acer ifplugd(eth0)[868]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jan 15 16:11:09 volumio-acer ifplugd(eth0)[868]: client: ifup: interface eth0 already configured Jan 15 16:11:09 volumio-acer ifplugd(eth0)[868]: Program executed successfully. Jan 15 16:11:09 volumio-acer volumio[998]: info: Plugin folders cleanup Jan 15 16:11:09 volumio-acer volumio[998]: info: Scanning into folder /volumio/app/plugins/ Jan 15 16:11:09 volumio-acer volumio[998]: info: Scanning category audio_interface Jan 15 16:11:09 volumio-acer volumio[998]: info: Scanning category miscellanea Jan 15 16:11:09 volumio-acer volumio[998]: info: Scanning category music_service Jan 15 16:11:09 volumio-acer volumio[998]: info: Scanning category plugins.json Jan 15 16:11:09 volumio-acer volumio[998]: info: Scanning category system_controller Jan 15 16:11:09 volumio-acer volumio[998]: info: Scanning category user_interface Jan 15 16:11:09 volumio-acer volumio[998]: info: Scanning into folder /data/plugins/ Jan 15 16:11:09 volumio-acer volumio[998]: info: Scanning category audio_interface Jan 15 16:11:09 volumio-acer volumio[998]: info: Scanning category music_service Jan 15 16:11:09 volumio-acer volumio[998]: info: Scanning category system_hardware Jan 15 16:11:09 volumio-acer volumio[998]: info: Scanning category user_interface Jan 15 16:11:09 volumio-acer volumio[998]: info: Plugin folders cleanup completed Jan 15 16:11:09 volumio-acer volumio[998]: info: ------------------------------------------- Jan 15 16:11:09 volumio-acer volumio[998]: info: ----- Core plugins startup ---- Jan 15 16:11:09 volumio-acer volumio[998]: info: ------------------------------------------- Jan 15 16:11:09 volumio-acer volumio[998]: info: Loading plugins from folder /volumio/app/plugins/ Jan 15 16:11:09 volumio-acer volumio[998]: info: Adding plugin upnp to MyMusic Plugins Jan 15 16:11:09 volumio-acer volumio[998]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 15 16:11:09 volumio-acer volumio[998]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 15 16:11:09 volumio-acer volumio[998]: info: Loading plugins from folder /data/plugins/ Jan 15 16:11:09 volumio-acer volumio[998]: info: Loading plugin "system"... Jan 15 16:11:10 volumio-acer volumio[998]: info: Loading plugin "appearance"... Jan 15 16:11:11 volumio-acer volumio-remote-updater[640]: [2025-01-15 16:11:11] [connect] Successful connection Jan 15 16:11:12 volumio-acer volumio[998]: info: Loading plugin "network"... Jan 15 16:11:12 volumio-acer volumio[998]: info: Refreshing Cached IP Addresses Jan 15 16:11:12 volumio-acer sudo[1263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 15 16:11:12 volumio-acer sudo[1263]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:12 volumio-acer sudo[1261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 15 16:11:12 volumio-acer volumio[998]: info: Loading plugin "services"... Jan 15 16:11:12 volumio-acer sudo[1261]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:12 volumio-acer sudo[1261]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:12 volumio-acer volumio[998]: info: Loading plugin "alsa_controller"... Jan 15 16:11:12 volumio-acer sudo[1263]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:12 volumio-acer sudo[1272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 15 16:11:12 volumio-acer sudo[1272]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:12 volumio-acer sudo[1272]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:12 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 15 16:11:12 volumio-acer volumio[998]: info: Loading plugin "wizard"... Jan 15 16:11:12 volumio-acer volumio[998]: info: Loading plugin "networkfs"... Jan 15 16:11:12 volumio-acer volumio[998]: info: Starting Udev Watcher for removable devices Jan 15 16:11:12 volumio-acer volumio[998]: info: Ignoring mount for partition: boot Jan 15 16:11:12 volumio-acer volumio[998]: info: Ignoring mount for partition: volumio Jan 15 16:11:12 volumio-acer volumio[998]: info: Ignoring mount for partition: volumio_data Jan 15 16:11:12 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 15 16:11:12 volumio-acer volumio[998]: info: Loading plugin "volumio_command_line_client"... Jan 15 16:11:12 volumio-acer sudo[1293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Sergey Belov,password=2199,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.5/E:\музыка /mnt/NAS/Sergey Jan 15 16:11:12 volumio-acer sudo[1293]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:12 volumio-acer volumio[998]: info: Loading plugin "upnp"... Jan 15 16:11:12 volumio-acer volumio[998]: info: [1736964672589] Starting Upmpd Daemon Jan 15 16:11:12 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 15 16:11:12 volumio-acer volumio[998]: info: Loading plugin "my_music"... Jan 15 16:11:12 volumio-acer kernel: FS-Cache: Loaded Jan 15 16:11:12 volumio-acer kernel: Key type dns_resolver registered Jan 15 16:11:12 volumio-acer volumio[998]: info: Loading plugin "mpd"... Jan 15 16:11:12 volumio-acer kernel: Key type cifs.spnego registered Jan 15 16:11:12 volumio-acer kernel: Key type cifs.idmap registered Jan 15 16:11:12 volumio-acer kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jan 15 16:11:12 volumio-acer kernel: CIFS: Attempting to mount //192.168.1.5/E:/музыка Jan 15 16:11:12 volumio-acer kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 15 16:11:12 volumio-acer kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jan 15 16:11:12 volumio-acer sudo[1293]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:13 volumio-acer volumio[998]: info: Loading plugin "upnp_browser"... Jan 15 16:11:13 volumio-acer volumio[998]: info: Loading plugin "alarm-clock"... Jan 15 16:11:13 volumio-acer volumio-time-update[617]: volumio-time-update-util: Fetching time from Volumio... Jan 15 16:11:13 volumio-acer volumio[998]: info: Loading plugin "airplay_emulation"... Jan 15 16:11:13 volumio-acer volumio[998]: info: Starting Shairport Sync Jan 15 16:11:13 volumio-acer volumio-time-update[617]: volumio-time-update-util: Date not found in response Jan 15 16:11:13 volumio-acer volumio-time-update[617]: volumio-time-update-util: Retrying in 5 seconds... Jan 15 16:11:13 volumio-acer volumio[998]: info: Loading plugin "last_100"... Jan 15 16:11:13 volumio-acer volumio[998]: info: Loading plugin "webradio"... Jan 15 16:11:14 volumio-acer volumio[998]: info: Loading plugin "i2s_dacs"... Jan 15 16:11:14 volumio-acer volumio[998]: info: I2S DAC not set, start Auto-detection Jan 15 16:11:14 volumio-acer volumio[998]: info: Loading plugin "volumiodiscovery"... Jan 15 16:11:14 volumio-acer volumio[998]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 15 16:11:14 volumio-acer node[998]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 15 16:11:14 volumio-acer volumio[998]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 15 16:11:14 volumio-acer node[998]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 15 16:11:14 volumio-acer volumio[998]: *** WARNING *** For more information see Jan 15 16:11:14 volumio-acer node[998]: *** WARNING *** For more information see Jan 15 16:11:14 volumio-acer volumio[998]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 15 16:11:14 volumio-acer node[998]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 15 16:11:14 volumio-acer volumio[998]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 15 16:11:14 volumio-acer node[998]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 15 16:11:14 volumio-acer volumio[998]: *** WARNING *** For more information see Jan 15 16:11:14 volumio-acer node[998]: *** WARNING *** For more information see Jan 15 16:11:14 volumio-acer volumio[998]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 15 16:11:14 volumio-acer volumio[998]: info: Discovery: Started advertising with name: Volumio Acer Jan 15 16:11:14 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 15 16:11:14 volumio-acer volumio[998]: info: Loading plugin "youtube2"... Jan 15 16:11:14 volumio-acer dhcpcd[750]: eth0: leased 192.168.1.8 for 28800 seconds Jan 15 16:11:14 volumio-acer dhcpcd[750]: eth0: adding route to 192.168.1.0/24 Jan 15 16:11:14 volumio-acer dhcpcd[750]: eth0: adding default route via 192.168.1.1 Jan 15 16:11:14 volumio-acer avahi-daemon[618]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.8. Jan 15 16:11:14 volumio-acer avahi-daemon[618]: New relevant interface eth0.IPv4 for mDNS. Jan 15 16:11:14 volumio-acer avahi-daemon[618]: Registering new address record for 192.168.1.8 on eth0.IPv4. Jan 15 16:11:15 volumio-acer systemd[1]: Stopping Network Time Service... Jan 15 16:11:15 volumio-acer ntpd[853]: ntpd exiting on signal 15 (Terminated) Jan 15 16:11:15 volumio-acer systemd[1]: ntp.service: Succeeded. Jan 15 16:11:15 volumio-acer systemd[1]: Stopped Network Time Service. Jan 15 16:11:15 volumio-acer systemd[1]: Starting Network Time Service... Jan 15 16:11:15 volumio-acer ntpd[1358]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 15 16:11:15 volumio-acer ntpd[1358]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jan 15 16:11:15 volumio-acer systemd[1]: Started Network Time Service. Jan 15 16:11:15 volumio-acer ntpd[1364]: proto: precision = 0.083 usec (-23) Jan 15 16:11:15 volumio-acer ntpd[1364]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 15 16:11:15 volumio-acer ntpd[1364]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 15 16:11:15 volumio-acer ntpd[1364]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 750 days ago Jan 15 16:11:15 volumio-acer ntpd[1364]: Listen and drop on 0 v6wildcard [::]:123 Jan 15 16:11:15 volumio-acer ntpd[1364]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 15 16:11:15 volumio-acer ntpd[1364]: Listen normally on 2 lo 127.0.0.1:123 Jan 15 16:11:15 volumio-acer ntpd[1364]: Listen normally on 3 eth0 192.168.1.8:123 Jan 15 16:11:15 volumio-acer ntpd[1364]: Listening on routing socket on fd #20 for interface updates Jan 15 16:11:15 volumio-acer ntpd[1364]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 15 16:11:15 volumio-acer ntpd[1364]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 15 16:11:15 volumio-acer dbus-daemon[628]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.8' (uid=0 pid=1223 comm="/usr/lib/chromium/chromium --show-component-extens") Jan 15 16:11:15 volumio-acer systemd[1]: Starting Bluetooth service... Jan 15 16:11:16 volumio-acer ntpd[1364]: Soliciting pool server 94.158.46.150 Jan 15 16:11:16 volumio-acer kernel: cryptd: max_cpu_qlen set to 1000 Jan 15 16:11:16 volumio-acer kernel: Bluetooth: Core ver 2.22 Jan 15 16:11:16 volumio-acer kernel: NET: Registered PF_BLUETOOTH protocol family Jan 15 16:11:16 volumio-acer kernel: Bluetooth: HCI device and connection manager initialized Jan 15 16:11:16 volumio-acer kernel: Bluetooth: HCI socket layer initialized Jan 15 16:11:16 volumio-acer kernel: Bluetooth: L2CAP socket layer initialized Jan 15 16:11:16 volumio-acer kernel: Bluetooth: SCO socket layer initialized Jan 15 16:11:16 volumio-acer bluetoothd[1372]: Bluetooth daemon 5.50 Jan 15 16:11:16 volumio-acer bluetoothd[1372]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jan 15 16:11:16 volumio-acer dbus-daemon[628]: [system] Successfully activated service 'org.bluez' Jan 15 16:11:16 volumio-acer systemd[1]: Started Bluetooth service. Jan 15 16:11:16 volumio-acer bluetoothd[1372]: Starting SDP server Jan 15 16:11:16 volumio-acer bluetoothd[1372]: Excluding (cli) sap Jan 15 16:11:16 volumio-acer kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 15 16:11:16 volumio-acer kernel: Bluetooth: BNEP filters: protocol multicast Jan 15 16:11:16 volumio-acer kernel: Bluetooth: BNEP socket layer initialized Jan 15 16:11:16 volumio-acer bluetoothd[1372]: Bluetooth management interface 1.22 initialized Jan 15 16:11:16 volumio-acer dbus-daemon[628]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.9' (uid=0 pid=1372 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jan 15 16:11:16 volumio-acer systemd[1]: Starting Hostname Service... Jan 15 16:11:17 volumio-acer dbus-daemon[628]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 15 16:11:17 volumio-acer systemd[1]: Started Hostname Service. Jan 15 16:11:17 volumio-acer ntpd[1364]: Soliciting pool server 193.34.155.4 Jan 15 16:11:18 volumio-acer systemd[1]: Started Samba NMB Daemon. Jan 15 16:11:18 volumio-acer nmbd[837]: [2025/01/15 16:11:18.012167, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 15 16:11:18 volumio-acer systemd[1]: Starting Samba Winbind Daemon... Jan 15 16:11:18 volumio-acer nmbd[837]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 15 16:11:18 volumio-acer ntpd[1364]: Soliciting pool server 195.137.202.129 Jan 15 16:11:18 volumio-acer ntpd[1364]: Soliciting pool server 134.249.228.3 Jan 15 16:11:18 volumio-acer volumio-time-update[617]: volumio-time-update-util: Fetching time from Volumio... Jan 15 16:11:19 volumio-acer ntpd[1364]: Soliciting pool server 37.53.71.213 Jan 15 16:11:19 volumio-acer ntpd[1364]: Soliciting pool server 79.142.192.4 Jan 15 16:11:19 volumio-acer volumio-time-update[617]: volumio-time-update-util: Setting system time to: 2025-01-15 16:11:14 Jan 15 16:11:19 volumio-acer sudo[1482]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-01-15 16:11:14 Jan 15 16:11:19 volumio-acer sudo[1482]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:19 volumio-acer ntpd[1364]: Soliciting pool server 91.236.251.24 Jan 15 16:11:19 volumio-acer dbus-daemon[628]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.12' (uid=0 pid=1483 comm="timedatectl set-time 2025-01-15 16:11:14 ") Jan 15 16:11:19 volumio-acer winbindd[1452]: [2025/01/15 16:11:19.378424, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 15 16:11:19 volumio-acer winbindd[1452]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 15 16:11:19 volumio-acer systemd[1]: Starting Time & Date Service... Jan 15 16:11:19 volumio-acer systemd[1]: Started Samba Winbind Daemon. Jan 15 16:11:19 volumio-acer winbindd[1452]: [2025/01/15 16:11:19.493814, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 15 16:11:19 volumio-acer winbindd[1452]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 15 16:11:19 volumio-acer dbus-daemon[628]: [system] Successfully activated service 'org.freedesktop.timedate1' Jan 15 16:11:19 volumio-acer systemd[1]: Starting Samba SMB Daemon... Jan 15 16:11:19 volumio-acer systemd[1]: Started Time & Date Service. Jan 15 16:11:14 volumio-acer systemd-timedated[1485]: Changed local time to Wed Jan 15 16:11:14 2025 Jan 15 16:11:14 volumio-acer sudo[1482]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:14 volumio-acer volumio-time-update[617]: volumio-time-update-util: System time updated successfully. Jan 15 16:11:14 volumio-acer systemd[1]: Started Volumio Time Update Utility. Jan 15 16:11:14 volumio-acer ntpd[1364]: Soliciting pool server 91.236.251.234 Jan 15 16:11:14 volumio-acer ntpd[1364]: Soliciting pool server 46.173.175.211 Jan 15 16:11:14 volumio-acer ntpd[1364]: Soliciting pool server 91.236.251.5 Jan 15 16:11:15 volumio-acer ntpd[1364]: Soliciting pool server 176.120.61.236 Jan 15 16:11:15 volumio-acer ntpd[1364]: Soliciting pool server 91.231.182.17 Jan 15 16:11:16 volumio-acer ntpd[1364]: Soliciting pool server 31.28.161.68 Jan 15 16:11:16 volumio-acer ntpd[1364]: Soliciting pool server 2a02:c6c1:c:207::13 Jan 15 16:11:16 volumio-acer systemd[1]: Started Samba SMB Daemon. Jan 15 16:11:16 volumio-acer smbd[1489]: [2025/01/15 16:11:16.632759, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 15 16:11:16 volumio-acer systemd[1]: Reached target Multi-User System. Jan 15 16:11:16 volumio-acer smbd[1489]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 15 16:11:16 volumio-acer systemd[1]: Reached target Graphical Interface. Jan 15 16:11:16 volumio-acer systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 15 16:11:16 volumio-acer systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 15 16:11:16 volumio-acer systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 15 16:11:16 volumio-acer systemd[1]: Startup finished in 17.507s (kernel) + 31.021s (userspace) = 48.528s. Jan 15 16:11:18 volumio-acer volumio[998]: info: Loading plugin "ytmusic"... Jan 15 16:11:19 volumio-acer ntpd[1364]: Soliciting pool server 162.159.200.1 Jan 15 16:11:19 volumio-acer ntpd[1364]: Soliciting pool server 193.106.144.7 Jan 15 16:11:20 volumio-acer ntpd[1364]: Soliciting pool server 109.110.82.19 Jan 15 16:11:20 volumio-acer ntpd[1364]: Soliciting pool server 162.159.200.123 Jan 15 16:11:20 volumio-acer systemd[1]: systemd-fsckd.service: Succeeded. Jan 15 16:11:21 volumio-acer ntpd[1364]: receive: Unexpected origin timestamp 0xeb3278c8.928ac1d7 does not match aorg 0000000000.00000000 from server@37.53.71.213 xmt 0xeb3278c9.bfce1d9c Jan 15 16:11:21 volumio-acer ntpd[1364]: receive: Unexpected origin timestamp 0xeb3278c8.928a14bb does not match aorg 0000000000.00000000 from server@46.173.175.211 xmt 0xeb3278c9.c0b6f9fc Jan 15 16:11:22 volumio-acer ntpd[1364]: Soliciting pool server 213.231.5.55 Jan 15 16:11:23 volumio-acer volumio[998]: info: Plugin now_playing is not enabled Jan 15 16:11:23 volumio-acer volumio[998]: info: Loading plugin "outputs"... Jan 15 16:11:23 volumio-acer volumio[998]: info: Loading plugin "albumart"... Jan 15 16:11:23 volumio-acer volumio[998]: info: Plugin example_plugin is not enabled Jan 15 16:11:23 volumio-acer volumio[998]: info: Loading plugin "inputs"... Jan 15 16:11:23 volumio-acer volumio[998]: info: Loading plugin "updater_comm"... Jan 15 16:11:23 volumio-acer volumio[998]: Forking 1 albumart workers Jan 15 16:11:23 volumio-acer volumio[998]: info: Plugin mpdemulation is not enabled Jan 15 16:11:23 volumio-acer volumio[998]: info: Loading plugin "rest_api"... Jan 15 16:11:23 volumio-acer volumio[998]: info: Loading plugin "websocket"... Jan 15 16:11:23 volumio-acer volumio[998]: info: Starting Socket.io Server version 2.3.0 Jan 15 16:11:23 volumio-acer volumio[998]: info: Loading plugin "fusiondsp"... Jan 15 16:11:23 volumio-acer volumio[998]: Starting albumart workers Jan 15 16:11:23 volumio-acer ntpd[1364]: Soliciting pool server 62.205.159.155 Jan 15 16:11:23 volumio-acer volumio[998]: info: Applying required configuration parameters for plugin fusiondsp Jan 15 16:11:24 volumio-acer volumio[998]: info: Loading plugin "music_services_shield"... Jan 15 16:11:24 volumio-acer volumio[998]: info: Applying required configuration parameters for plugin music_services_shield Jan 15 16:11:24 volumio-acer volumio[998]: info: Loading plugin "onkyo_control"... Jan 15 16:11:24 volumio-acer volumio[998]: info: Applying required configuration parameters for plugin onkyo_control Jan 15 16:11:25 volumio-acer volumio[998]: info: Plugin Systeminfo is not enabled Jan 15 16:11:25 volumio-acer volumio[998]: info: Loading plugin "lastfm"... Jan 15 16:11:25 volumio-acer volumio[998]: info: Plugin peppymeterbasic is not enabled Jan 15 16:11:25 volumio-acer volumio[998]: info: Loading i18n strings for locale ru Jan 15 16:11:25 volumio-acer volumio[998]: Updating browse sources language Jan 15 16:11:25 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 15 16:11:25 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 15 16:11:25 volumio-acer volumio[998]: info: CoreCommandRouter::initPlayerControls Jan 15 16:11:25 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:25 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:25 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:25 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:25 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:25 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:25 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:25 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:25 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 15 16:11:25 volumio-acer volumio[998]: Express server listening on port 3000 Jan 15 16:11:25 volumio-acer volumio[998]: [Metrics] WebUI: 23s 825.50ms Jan 15 16:11:25 volumio-acer volumio[998]: info: Setting Device type: x86 Jan 15 16:11:25 volumio-acer volumio[998]: info: CoreStateMachine::resetVolumioState Jan 15 16:11:25 volumio-acer volumio[998]: info: CoreStateMachine::getcurrentVolume Jan 15 16:11:25 volumio-acer volumio[998]: info: CoreCommandRouter::volumioRetrievevolume Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 16:11:26 volumio-acer sudo[1553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 15 16:11:26 volumio-acer sudo[1553]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:26 volumio-acer sudo[1553]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:26 volumio-acer sudo[1556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 15 16:11:26 volumio-acer volumio[998]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 15 16:11:26 volumio-acer volumio[998]: info: Completed loading Core Plugins Jan 15 16:11:26 volumio-acer volumio[998]: info: Preparing to generate the ALSA configuration file Jan 15 16:11:26 volumio-acer sudo[1556]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:26 volumio-acer sudo[1556]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:26 volumio-acer volumio[998]: info: Volumio Network Manager: Network status updated: 0 Jan 15 16:11:26 volumio-acer volumio[998]: info: Cannot mount NAS Sergey at system boot, trial number 1 ,retrying in 5 seconds Jan 15 16:11:26 volumio-acer volumio[998]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jan 15 16:11:26 volumio-acer volumio[998]: wlan0 Interface doesn't support scanning. Jan 15 16:11:26 volumio-acer volumio[998]: info: Cannot use regular scanning, forcing with ap-force Jan 15 16:11:26 volumio-acer sudo[1562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jan 15 16:11:26 volumio-acer sudo[1562]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:26 volumio-acer sudo[1562]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:26 volumio-acer volumio-remote-updater[640]: [2025-01-15 16:11:26] [connect] Successful connection Jan 15 16:11:26 volumio-acer volumio[998]: command failed: No such device (-19) Jan 15 16:11:26 volumio-acer volumio[998]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jan 15 16:11:26 volumio-acer volumio[998]: command failed: No such device (-19) Jan 15 16:11:26 volumio-acer volumio[998]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jan 15 16:11:26 volumio-acer volumio[998]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 15 16:11:26 volumio-acer volumio[998]: info: Reading ALSA contributions from plugins. Jan 15 16:11:26 volumio-acer volumio[998]: info: VolumeController:: Volume=87 Mute =false Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreStateMachine::pushState Jan 15 16:11:26 volumio-acer volumio[998]: info: CorePlayQueue::getTrack 0 Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreCommandRouter::volumioPushState Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreStateMachine::updateTrackBlock Jan 15 16:11:26 volumio-acer volumio[998]: info: CorePlayQueue::getTrackBlock Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreCommandRouter::volumioRetrievevolume Jan 15 16:11:26 volumio-acer volumio[998]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jan 15 16:11:26 volumio-acer volumio-remote-updater[640]: [2025-01-15 16:11:26] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1736964686 101 Jan 15 16:11:26 volumio-acer volumio[998]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Jan 15 16:11:26 volumio-acer volumio[998]: info: Reloading queue from file Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreStateMachine::setRepeat null single undefined Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreStateMachine::pushState Jan 15 16:11:26 volumio-acer volumio[998]: info: CorePlayQueue::getTrack 0 Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreCommandRouter::volumioPushState Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreStateMachine::setRandom null Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreStateMachine::pushState Jan 15 16:11:26 volumio-acer volumio[998]: info: CorePlayQueue::getTrack 0 Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreCommandRouter::volumioPushState Jan 15 16:11:26 volumio-acer volumio[998]: info: VolumeController:: Volume=87 Mute =false Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreStateMachine::pushState Jan 15 16:11:26 volumio-acer volumio[998]: info: CorePlayQueue::getTrack 0 Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreCommandRouter::volumioPushState Jan 15 16:11:26 volumio-acer sudo[1574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 15 16:11:26 volumio-acer sudo[1574]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:26 volumio-acer volumio[998]: info: Discovery: adding 1be179d7-6e6f-452c-9d78-497267e1866a Jan 15 16:11:26 volumio-acer volumio[998]: info: Discovery: Found device Volumio Acer Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreCommandRouter::volumioGetState Jan 15 16:11:26 volumio-acer volumio[998]: info: CorePlayQueue::getTrack 0 Jan 15 16:11:26 volumio-acer systemd[1]: Started UPnP Renderer front-end to MPD. Jan 15 16:11:26 volumio-acer sudo[1574]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:26 volumio-acer volumio[998]: info: Asound.conf file unchanged, so no further update is needed Jan 15 16:11:26 volumio-acer volumio[998]: info: Output device has changed, restarting MPD Jan 15 16:11:26 volumio-acer sudo[1578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 15 16:11:26 volumio-acer sudo[1578]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:26 volumio-acer sudo[1578]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:26 volumio-acer volumio[998]: info: Output device has changed, restarting Shairport Sync Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 16:11:26 volumio-acer sudo[1581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 15 16:11:26 volumio-acer sudo[1581]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:26 volumio-acer systemd[1]: musicservicesshield.service: Succeeded. Jan 15 16:11:26 volumio-acer systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jan 15 16:11:26 volumio-acer systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jan 15 16:11:26 volumio-acer systemd[1]: Stopping Music Player Daemon... Jan 15 16:11:26 volumio-acer volumio[998]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 15 16:11:26 volumio-acer volumio[998]: info: ___________ START PLUGINS ___________ Jan 15 16:11:26 volumio-acer volumio[998]: info: ControllerMpd::onStart: Initializing MPD Jan 15 16:11:26 volumio-acer volumio[998]: info: Creating MPD Configuration file Jan 15 16:11:26 volumio-acer systemd[1]: mpd.service: Succeeded. Jan 15 16:11:26 volumio-acer systemd[1]: Stopped Music Player Daemon. Jan 15 16:11:26 volumio-acer sudo[1587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 15 16:11:26 volumio-acer systemd[1]: Starting Music Player Daemon... Jan 15 16:11:26 volumio-acer sudo[1587]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:26 volumio-acer sudo[1587]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 15 16:11:26 volumio-acer volumio[998]: info: [1736964686746] CoreMusicLibrary::Adding element Медиа-серверы Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 15 16:11:26 volumio-acer sudo[1591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 15 16:11:26 volumio-acer sudo[1591]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:26 volumio-acer sudo[1588]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 15 16:11:26 volumio-acer sudo[1588]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 16:11:26 volumio-acer sudo[1588]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:26 volumio-acer systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 15 16:11:26 volumio-acer systemd[1]: mpd.service: Succeeded. Jan 15 16:11:26 volumio-acer systemd[1]: Stopped Music Player Daemon. Jan 15 16:11:26 volumio-acer systemd[1]: Starting Music Player Daemon... Jan 15 16:11:26 volumio-acer volumio[998]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 16:11:26 volumio-acer sudo[1596]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 15 16:11:26 volumio-acer sudo[1596]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:26 volumio-acer sudo[1596]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:26 volumio-acer volumio[998]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 15 16:11:26 volumio-acer volumio[998]: info: [1736964686888] CoreMusicLibrary::Adding element Last_100 Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 15 16:11:26 volumio-acer volumio[998]: info: [1736964686889] CoreMusicLibrary::Adding element Webradio Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 15 16:11:26 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 15 16:11:26 volumio-acer volumio[998]: info: Initializing BBC Radios Jan 15 16:11:27 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 15 16:11:27 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 16:11:27 volumio-acer volumio[998]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 15 16:11:27 volumio-acer volumio[998]: info: [1736964687055] CoreMusicLibrary::Adding element YouTube2 Jan 15 16:11:27 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 15 16:11:27 volumio-acer volumio[998]: Cannot find translation for source YouTube2 Jan 15 16:11:27 volumio-acer volumio[998]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 15 16:11:27 volumio-acer volumio[998]: info: [1736964687070] CoreMusicLibrary::Adding element YouTube Music Jan 15 16:11:27 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 15 16:11:27 volumio-acer volumio[998]: Cannot find translation for source YouTube2 Jan 15 16:11:27 volumio-acer volumio[998]: Cannot find translation for source YouTube Music Jan 15 16:11:27 volumio-acer volumio[998]: info: Loading i18n strings for locale ru Jan 15 16:11:27 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 15 16:11:27 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:27 volumio-acer volumio[998]: info: FusionDsp - mixtype--------------------- Hardware Jan 15 16:11:27 volumio-acer volumio[998]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 15 16:11:27 volumio-acer volumio[998]: info: [1736964687383] CoreMusicLibrary::Adding element LastFM Jan 15 16:11:27 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 15 16:11:27 volumio-acer volumio[998]: Cannot find translation for source YouTube2 Jan 15 16:11:27 volumio-acer volumio[998]: Cannot find translation for source YouTube Music Jan 15 16:11:27 volumio-acer volumio[998]: Cannot find translation for source LastFM Jan 15 16:11:27 volumio-acer volumio[998]: info: [LastFM] scrobbler initiated! Jan 15 16:11:27 volumio-acer volumio[998]: info: [LastFM] extended logging: false Jan 15 16:11:27 volumio-acer volumio[998]: info: [LastFM] try scrobble stream/radio plays: true Jan 15 16:11:27 volumio-acer volumio[998]: info: [LastFM] Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Jan 15 16:11:27 volumio-acer volumio[998]: info: [LastFM] Left init routine Jan 15 16:11:27 volumio-acer volumio[998]: info: [LastFM] Socket already connected: true Jan 15 16:11:27 volumio-acer volumio[998]: info: Volumio Calling Home Jan 15 16:11:27 volumio-acer sudo[1640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Jan 15 16:11:27 volumio-acer sudo[1640]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:27 volumio-acer volumio[998]: info: Preparing to generate the ALSA configuration file Jan 15 16:11:27 volumio-acer volumio[998]: info: [LastFM] finished init with error: Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Jan 15 16:11:27 volumio-acer mpd[1603]: Jan 15 16:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 15 16:11:27 volumio-acer systemd[1]: Started Music Player Daemon. Jan 15 16:11:27 volumio-acer sudo[1581]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:27 volumio-acer sudo[1591]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:27 volumio-acer systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jan 15 16:11:27 volumio-acer volumio[998]: info: Upmpdcli Daemon Started Jan 15 16:11:27 volumio-acer volumio[998]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 15 16:11:27 volumio-acer volumio[998]: info: Reading ALSA contributions from plugins. Jan 15 16:11:27 volumio-acer volumio[998]: info: MPD Permissions set Jan 15 16:11:27 volumio-acer volumio[998]: info: MPD Permissions set Jan 15 16:11:28 volumio-acer volumio[998]: info: Volumio called home Jan 15 16:11:28 volumio-acer volumio[1655]: cset: --> shielding system active with Jan 15 16:11:28 volumio-acer volumio[1655]: cset: "system" cpuset of CPUSPEC(1) with 173 tasks running Jan 15 16:11:28 volumio-acer volumio[1655]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Jan 15 16:11:28 volumio-acer volumio[998]: error: MPD error: The expression evaluated to a falsy value: Jan 15 16:11:28 volumio-acer volumio[998]: assert.ok(self.idling) Jan 15 16:11:28 volumio-acer systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jan 15 16:11:28 volumio-acer volumio[998]: error: The expression evaluated to a falsy value: Jan 15 16:11:28 volumio-acer volumio[998]: assert.ok(self.idling) Jan 15 16:11:28 volumio-acer volumio[998]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 15 16:11:28 volumio-acer volumio[998]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 15 16:11:28 volumio-acer volumio[998]: info: MPD running with PID1603 Jan 15 16:11:28 volumio-acer volumio[998]: ,establishing connection Jan 15 16:11:28 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:28 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:28 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 16:11:28 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 15 16:11:28 volumio-acer sudo[1640]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:28 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:28 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:28 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:28 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:28 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:28 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:28 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:28 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:28 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:28 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 16:11:28 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 15 16:11:28 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:28 volumio-acer volumio[998]: error: updateQueue error: null Jan 15 16:11:28 volumio-acer volumio[998]: info: Volumio Network Manager: Network status updated: 1 Jan 15 16:11:28 volumio-acer volumio[998]: error: updateQueue error: null Jan 15 16:11:28 volumio-acer volumio[998]: info: Starting Shairport Sync Jan 15 16:11:28 volumio-acer volumio[998]: info: Starting Shairport Sync Jan 15 16:11:28 volumio-acer sudo[1710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 15 16:11:28 volumio-acer volumio[998]: info: Starting Shairport Sync Jan 15 16:11:28 volumio-acer sudo[1710]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:28 volumio-acer sudo[1712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 15 16:11:28 volumio-acer sudo[1712]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:28 volumio-acer sudo[1708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Jan 15 16:11:28 volumio-acer sudo[1708]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:28 volumio-acer systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 15 16:11:28 volumio-acer systemd[1]: shairport-sync.service: Succeeded. Jan 15 16:11:28 volumio-acer systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 15 16:11:28 volumio-acer systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 15 16:11:28 volumio-acer sudo[1710]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:28 volumio-acer systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 15 16:11:28 volumio-acer systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 15 16:11:28 volumio-acer systemd[1]: shairport-sync.service: Succeeded. Jan 15 16:11:28 volumio-acer systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 15 16:11:28 volumio-acer volumio[998]: info: CoreCommandRouter::volumioGetState Jan 15 16:11:28 volumio-acer volumio[998]: info: CorePlayQueue::getTrack 0 Jan 15 16:11:28 volumio-acer sudo[1718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 15 16:11:28 volumio-acer systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 15 16:11:28 volumio-acer sudo[1712]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:28 volumio-acer sudo[1718]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:28 volumio-acer systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 15 16:11:28 volumio-acer systemd[1]: shairport-sync.service: Succeeded. Jan 15 16:11:28 volumio-acer systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 15 16:11:28 volumio-acer volumio[998]: info: Shairport-Sync Started Jan 15 16:11:28 volumio-acer systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 15 16:11:28 volumio-acer sudo[1718]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:28 volumio-acer volumio[998]: Error adding Membership: Error: addMembership EINVAL Jan 15 16:11:28 volumio-acer volumio[998]: info: Asound.conf file unchanged, so no further update is needed Jan 15 16:11:28 volumio-acer volumio[998]: info: Output device has changed, restarting MPD Jan 15 16:11:28 volumio-acer volumio[1576]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 15 16:11:29 volumio-acer systemd[1]: Reloading. Jan 15 16:11:29 volumio-acer sudo[1742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 15 16:11:29 volumio-acer sudo[1742]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:29 volumio-acer sudo[1742]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:29 volumio-acer volumio[998]: info: Output device has changed, restarting Shairport Sync Jan 15 16:11:29 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:29 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 16:11:29 volumio-acer sudo[1745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 15 16:11:29 volumio-acer sudo[1745]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:29 volumio-acer volumio[998]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 15 16:11:29 volumio-acer volumio[998]: info: Shairport-Sync Started Jan 15 16:11:29 volumio-acer volumio[998]: info: Shairport-Sync Started Jan 15 16:11:29 volumio-acer volumio[998]: info: camilladsp spawned new process with pid 1770, instance 1, run: true Jan 15 16:11:29 volumio-acer volumio[998]: info: camilladsp service started and running in background, instance 1 Jan 15 16:11:29 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:29 volumio-acer volumio[998]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 96000 192000 <-AAAAAAAAAAAAA Jan 15 16:11:29 volumio-acer systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 15 16:11:29 volumio-acer volumio[998]: info: FusionDsp loaded Jan 15 16:11:29 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 15 16:11:29 volumio-acer sudo[1776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jan 15 16:11:29 volumio-acer sudo[1776]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:29 volumio-acer volumio[998]: info: FusionDsp - Reporting Fusion DSP Enabled Jan 15 16:11:29 volumio-acer volumio[998]: info: Adding Signal Path Element [object Object] Jan 15 16:11:29 volumio-acer volumio[998]: info: Adding fusiondspeq DSP Signal Path Element Jan 15 16:11:29 volumio-acer volumio[998]: info: FusionDsp - ---- installed callbackRead Jan 15 16:11:29 volumio-acer volumio[998]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 15 16:11:29 volumio-acer volumio[998]: info: MPD Permissions set Jan 15 16:11:29 volumio-acer systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jan 15 16:11:29 volumio-acer volumio[998]: error: FusionDsp - WebSocket error: [object Object] Jan 15 16:11:30 volumio-acer volumio[998]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 15 16:11:30 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:30 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:30 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:30 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:30 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:30 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:30 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:30 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:30 volumio-acer sudo[1708]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:30 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 16:11:30 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 15 16:11:30 volumio-acer systemd[1]: Started FusionDsp Daemon. Jan 15 16:11:30 volumio-acer systemd[1]: musicservicesshield.service: Succeeded. Jan 15 16:11:30 volumio-acer systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jan 15 16:11:30 volumio-acer systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jan 15 16:11:30 volumio-acer systemd[1]: Stopping Music Player Daemon... Jan 15 16:11:30 volumio-acer systemd[1]: mpd.service: Succeeded. Jan 15 16:11:30 volumio-acer systemd[1]: Stopped Music Player Daemon. Jan 15 16:11:30 volumio-acer sudo[1776]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:30 volumio-acer systemd[1]: Starting Music Player Daemon... Jan 15 16:11:30 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:11:30 volumio-acer sudo[1790]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 15 16:11:30 volumio-acer sudo[1790]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:30 volumio-acer volumio[998]: info: succeeded Jan 15 16:11:30 volumio-acer sudo[1790]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:30 volumio-acer volumio[998]: info: Starting Shairport Sync Jan 15 16:11:30 volumio-acer sudo[1795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 15 16:11:30 volumio-acer sudo[1795]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:30 volumio-acer systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 15 16:11:30 volumio-acer systemd[1]: shairport-sync.service: Succeeded. Jan 15 16:11:30 volumio-acer systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 15 16:11:30 volumio-acer systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 15 16:11:30 volumio-acer sudo[1795]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:30 volumio-acer volumio[998]: info: Shairport-Sync Started Jan 15 16:11:30 volumio-acer volumio[1576]: ................................................++++ Jan 15 16:11:30 volumio-acer volumio[1576]: ......++++ Jan 15 16:11:30 volumio-acer volumio[1576]: e is 65537 (0x010001) Jan 15 16:11:30 volumio-acer volumio[1576]: writing RSA key Jan 15 16:11:31 volumio-acer sudo[1818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Sergey Belov,password=2199,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.5/E:\музыка /mnt/NAS/Sergey Jan 15 16:11:31 volumio-acer sudo[1818]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:31 volumio-acer kernel: CIFS: Attempting to mount //192.168.1.5/E:/музыка Jan 15 16:11:31 volumio-acer sudo[1818]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:31 volumio-acer kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 15 16:11:31 volumio-acer kernel: CIFS: VFS: cifs_mount failed w/return code = -111 Jan 15 16:11:31 volumio-acer volumio[998]: info: Cannot mount NAS Sergey at system boot, trial number 2 ,retrying in 5 seconds Jan 15 16:11:31 volumio-acer mpd[1793]: Jan 15 16:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 15 16:11:31 volumio-acer systemd[1]: Started Music Player Daemon. Jan 15 16:11:31 volumio-acer sudo[1745]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:31 volumio-acer systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jan 15 16:11:31 volumio-acer volumio[998]: error: updateQueue error: null Jan 15 16:11:32 volumio-acer volumio[1826]: cset: --> shielding system active with Jan 15 16:11:32 volumio-acer volumio[1826]: cset: "system" cpuset of CPUSPEC(1) with 200 tasks running Jan 15 16:11:32 volumio-acer volumio[1826]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Jan 15 16:11:32 volumio-acer systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jan 15 16:11:32 volumio-acer volumio[998]: info: ONKYO-CONTROL: *********** ONKYO PLUGIN STARTED ******** Jan 15 16:11:32 volumio-acer volumio[998]: info: Completed starting Core Plugins Jan 15 16:11:32 volumio-acer volumio[998]: info: ------------------------------------------- Jan 15 16:11:32 volumio-acer volumio[998]: info: ----- MyVolumio plugins startup ---- Jan 15 16:11:32 volumio-acer volumio[998]: info: ------------------------------------------- Jan 15 16:11:32 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 15 16:11:33 volumio-acer volumio[998]: 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 Engine version: 3 Transport: polling Total Clients: 6 Jan 15 16:11:33 volumio-acer volumio[998]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 15 16:11:34 volumio-acer volumio[998]: 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 Engine version: 3 Transport: polling Total Clients: 7 Jan 15 16:11:35 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 15 16:11:35 volumio-acer volumio[998]: info: CoreCommandRouter::volumioGetVisibleSources Jan 15 16:11:35 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 15 16:11:35 volumio-acer volumio[998]: info: CoreCommandRouter::volumioGetState Jan 15 16:11:35 volumio-acer volumio[998]: info: CorePlayQueue::getTrack 0 Jan 15 16:11:35 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 15 16:11:35 volumio-acer volumio[998]: info: CoreCommandRouter::volumioGetQueue Jan 15 16:11:35 volumio-acer volumio[998]: info: CoreStateMachine::getQueue Jan 15 16:11:35 volumio-acer volumio[998]: info: CorePlayQueue::getQueue Jan 15 16:11:35 volumio-acer volumio[998]: info: Listing playlists Jan 15 16:11:35 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 15 16:11:35 volumio-acer volumio[998]: info: Received Get System Info Jan 15 16:11:35 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 15 16:11:35 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 15 16:11:35 volumio-acer volumio[998]: info: Discovery: Getting this device information Jan 15 16:11:35 volumio-acer volumio[998]: info: CoreCommandRouter::volumioGetState Jan 15 16:11:35 volumio-acer volumio[998]: info: CorePlayQueue::getTrack 0 Jan 15 16:11:35 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 15 16:11:35 volumio-acer volumio[998]: info: CoreCommandRouter::volumioGetState Jan 15 16:11:35 volumio-acer volumio[998]: info: CorePlayQueue::getTrack 0 Jan 15 16:11:35 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 15 16:11:35 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 15 16:11:36 volumio-acer sudo[1981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Sergey Belov,password=2199,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.5/E:\музыка /mnt/NAS/Sergey Jan 15 16:11:36 volumio-acer sudo[1981]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:36 volumio-acer kernel: CIFS: Attempting to mount //192.168.1.5/E:/музыка Jan 15 16:11:36 volumio-acer sudo[1981]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:36 volumio-acer kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 15 16:11:36 volumio-acer kernel: CIFS: VFS: cifs_mount failed w/return code = -111 Jan 15 16:11:36 volumio-acer volumio[998]: info: Cannot mount NAS Sergey at system boot, trial number 3 ,retrying in 5 seconds Jan 15 16:11:36 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 15 16:11:36 volumio-acer volumio[998]: info: Received Get System Info Jan 15 16:11:36 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 15 16:11:36 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 15 16:11:36 volumio-acer volumio[998]: info: Discovery: Getting this device information Jan 15 16:11:36 volumio-acer volumio[998]: info: CoreCommandRouter::volumioGetState Jan 15 16:11:36 volumio-acer volumio[998]: info: CorePlayQueue::getTrack 0 Jan 15 16:11:36 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 15 16:11:36 volumio-acer volumio[998]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 15 16:11:38 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 15 16:11:38 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 15 16:11:38 volumio-acer volumio[998]: info: Received Get System Info Jan 15 16:11:38 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 15 16:11:38 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 15 16:11:38 volumio-acer volumio[998]: info: Discovery: Getting this device information Jan 15 16:11:38 volumio-acer volumio[998]: info: CoreCommandRouter::volumioGetState Jan 15 16:11:38 volumio-acer volumio[998]: info: CorePlayQueue::getTrack 0 Jan 15 16:11:38 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 15 16:11:39 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 15 16:11:41 volumio-acer volumio[998]: info: Adding plugin bluetooth to MyMusic Plugins Jan 15 16:11:41 volumio-acer volumio[998]: info: Adding plugin multiroom to MyMusic Plugins Jan 15 16:11:41 volumio-acer volumio[998]: info: Adding plugin metavolumio to MyMusic Plugins Jan 15 16:11:41 volumio-acer volumio[998]: info: Adding plugin cd_controller to MyMusic Plugins Jan 15 16:11:41 volumio-acer volumio[998]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 15 16:11:41 volumio-acer volumio[998]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 15 16:11:41 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 16:11:41 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 16:11:41 volumio-acer volumio[998]: info: Starting MyVolumio Remote Streaming Endpoints Jan 15 16:11:41 volumio-acer volumio[998]: info: MyVolumio login type: Token Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 15 16:11:41 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 15 16:11:42 volumio-acer volumio[998]: info: Starting Streaming Service Transparent Proxy Jan 15 16:11:42 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 15 16:11:42 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 15 16:11:42 volumio-acer volumio[998]: info: Streaming services startup Jan 15 16:11:42 volumio-acer volumio[998]: info: Starting Streaming Daemon Jan 15 16:11:42 volumio-acer sudo[2060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 15 16:11:42 volumio-acer sudo[2060]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:42 volumio-acer sudo[2060]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:42 volumio-acer volumio[998]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 15 16:11:42 volumio-acer volumio[998]: error: Cannot start Volumio Streaming Daemon Jan 15 16:11:42 volumio-acer volumio[998]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 15 16:11:42 volumio-acer volumio[998]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 15 16:11:42 volumio-acer sudo[2067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Sergey Belov,password=2199,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.5/E:\музыка /mnt/NAS/Sergey Jan 15 16:11:42 volumio-acer sudo[2067]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:42 volumio-acer kernel: CIFS: Attempting to mount //192.168.1.5/E:/музыка Jan 15 16:11:42 volumio-acer systemd[1]: systemd-hostnamed.service: Succeeded. Jan 15 16:11:42 volumio-acer sudo[2067]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:42 volumio-acer kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 15 16:11:42 volumio-acer kernel: CIFS: VFS: cifs_mount failed w/return code = -111 Jan 15 16:11:42 volumio-acer volumio[998]: info: Cannot mount NAS Sergey at system boot, trial number 4 ,retrying in 5 seconds Jan 15 16:11:42 volumio-acer volumio[998]: STREAMING PROXY: Starting server on port 3245 Jan 15 16:11:42 volumio-acer volumio[998]: Node JS runtime: 14 Jan 15 16:11:42 volumio-acer volumio[998]: error: MyVolumio Custom Token format not valid, refreshing it Jan 15 16:11:43 volumio-acer volumio[998]: info: MyVolumio login type: Token Jan 15 16:11:44 volumio-acer volumio[998]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 15 16:11:44 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 15 16:11:45 volumio-acer systemd[1]: systemd-timedated.service: Succeeded. Jan 15 16:11:45 volumio-acer volumio[998]: info: MyVolumio token set successfully Jan 15 16:11:45 volumio-acer volumio[998]: info: MYVOLUMIO: Adding device Jan 15 16:11:45 volumio-acer volumio[998]: info: MYVOLUMIO: Evaluating Server Jan 15 16:11:46 volumio-acer volumio[998]: info: MyVolumio status changed Jan 15 16:11:46 volumio-acer volumio[998]: info: Streaming services startup Jan 15 16:11:46 volumio-acer volumio[998]: info: Starting Streaming Daemon Jan 15 16:11:46 volumio-acer volumio[998]: info: Removing browser output: myVolumio user plan is not superstar Jan 15 16:11:46 volumio-acer volumio[998]: info: Removing audio output: Jan 15 16:11:46 volumio-acer volumio[998]: info: Stoppping Tunnel 1 Jan 15 16:11:46 volumio-acer sudo[2093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 15 16:11:46 volumio-acer sudo[2093]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:46 volumio-acer sudo[2093]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:46 volumio-acer volumio[998]: error: Cannot start Volumio Streaming Daemon Jan 15 16:11:46 volumio-acer sudo[2096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 15 16:11:46 volumio-acer volumio[998]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 15 16:11:46 volumio-acer volumio[998]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 15 16:11:46 volumio-acer sudo[2096]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:46 volumio-acer sudo[2096]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:46 volumio-acer volumio[998]: info: Remote SSH Stopped Jan 15 16:11:46 volumio-acer volumio[998]: info: Setting Geolocation for MyVolumio to eu4 Jan 15 16:11:46 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 16:11:46 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 16:11:46 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 16:11:47 volumio-acer volumio[998]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 15 16:11:47 volumio-acer sudo[2099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Sergey Belov,password=2199,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.5/E:\музыка /mnt/NAS/Sergey Jan 15 16:11:47 volumio-acer sudo[2099]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 16:11:47 volumio-acer kernel: CIFS: Attempting to mount //192.168.1.5/E:/музыка Jan 15 16:11:47 volumio-acer sudo[2099]: pam_unix(sudo:session): session closed for user root Jan 15 16:11:47 volumio-acer volumio[998]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jan 15 16:11:47 volumio-acer kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 15 16:11:47 volumio-acer kernel: CIFS: VFS: cifs_mount failed w/return code = -111 Jan 15 16:11:47 volumio-acer volumio[998]: info: Updating MyVolumio device info Jan 15 16:11:47 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 16:11:48 volumio-acer volumio[998]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 15 16:11:51 volumio-acer volumio[998]: info: MYVOLUMIO: Adding device Jan 15 16:11:51 volumio-acer volumio[998]: info: MYVOLUMIO: Evaluating Server Jan 15 16:11:51 volumio-acer volumio[998]: info: Setting Geolocation for MyVolumio to eu6 Jan 15 16:11:51 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 16:11:51 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 16:11:51 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 16:11:52 volumio-acer volumio[998]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 15 16:11:52 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 15 16:11:52 volumio-acer volumio[998]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 15 16:11:52 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 15 16:11:52 volumio-acer volumio[998]: info: Received Get System Version Jan 15 16:11:52 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 15 16:11:52 volumio-acer volumio[998]: info: Received Get System Info Jan 15 16:11:52 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 15 16:11:52 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 15 16:11:52 volumio-acer volumio[998]: info: Discovery: Getting this device information Jan 15 16:11:52 volumio-acer volumio[998]: info: CoreCommandRouter::volumioGetState Jan 15 16:11:52 volumio-acer volumio[998]: info: CorePlayQueue::getTrack 0 Jan 15 16:11:52 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 15 16:11:52 volumio-acer volumio[998]: info: Updating MyVolumio device info Jan 15 16:11:52 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 16:11:52 volumio-acer volumiologrotate[627]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Jan 15 16:11:53 volumio-acer volumiologrotate[627]: ls: cannot access 'ACER': No such file or directory Jan 15 16:11:54 volumio-acer volumio[998]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 15 16:11:56 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 15 16:11:56 volumio-acer volumio[998]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 15 16:11:56 volumio-acer volumio[998]: info: CoreCommandRouter::volumioGetState Jan 15 16:11:56 volumio-acer volumio[998]: info: CorePlayQueue::getTrack 0 Jan 15 16:11:57 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 15 16:11:57 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 16:11:57 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 15 16:11:57 volumio-acer volumio-remote-updater[640]: No test mode Jan 15 16:11:57 volumio-acer volumio-remote-updater[640]: No alpha test mode Jan 15 16:11:57 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 15 16:11:57 volumio-acer volumio[998]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 15 16:11:57 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 15 16:12:01 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:12:01 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 15 16:12:01 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 15 16:12:01 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 15 16:12:01 volumio-acer volumio[998]: info: CoreCommandRouter::volumioGetBrowseSources Jan 15 16:12:01 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 15 16:12:02 volumio-acer volumio[998]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 15 16:12:02 volumio-acer volumio[998]: info: Completed starting MyVolumio Plugin Jan 15 16:12:02 volumio-acer volumio[998]: [Metrics] CommandRouter: 59s 353.84ms Jan 15 16:12:02 volumio-acer volumio[998]: info: CoreCommandRouter::volumiosetStartupVolume Jan 15 16:12:02 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 15 16:12:02 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 15 16:12:02 volumio-acer volumio[998]: info: CoreCommandRouter::Close All Modals sent Jan 15 16:12:02 volumio-acer volumio[998]: info: CoreCommandRouter::Close All Modals sent Jan 15 16:12:02 volumio-acer volumio[998]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 15 16:12:02 volumio-acer volumio[998]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 15 16:12:02 volumio-acer volumio[998]: info: camilladsp stopping service pid 1770... Jan 15 16:12:02 volumio-acer volumio[998]: info: camilladsp service terminated, instance 1 Jan 15 16:12:02 volumio-acer volumio[998]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 15 16:12:02 volumio-acer volumio[998]: info: camilladsp service started and running in background, instance 1 Jan 15 16:12:02 volumio-acer volumio[998]: info: camilladsp spawned new process with pid 2207, instance 1, run: true Jan 15 16:12:03 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 15 16:12:03 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 15 16:12:03 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 15 16:12:07 volumio-acer volumio[998]: info: camilladsp spawned new process with pid 2216, instance 1, run: true Jan 15 16:12:09 volumio-acer volumio[998]: info: BOOT COMPLETED Jan 15 16:12:19 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 15 16:12:19 volumio-acer volumio[998]: info: Preload queue cleared Jan 15 16:12:21 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 15 16:12:22 volumio-acer volumio[998]: info: Preload queue cleared Jan 15 16:12:25 volumio-acer volumio[998]: info: Preload queue cleared Jan 15 16:12:25 volumio-acer volumio[998]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 15 16:12:25 volumio-acer volumio[998]: info: CoreStateMachine::ClearQueue Jan 15 16:12:25 volumio-acer volumio[998]: info: CoreStateMachine::stop Jan 15 16:12:25 volumio-acer volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 15 16:12:25 volumio-acer volumio[998]: info: CorePlayQueue::clearPlayQueue Jan 15 16:12:25 volumio-acer volumio[998]: info: CorePlayQueue::saveQueue Jan 15 16:12:25 volumio-acer volumio[998]: info: CoreCommandRouter::volumioPushQueue Jan 15 16:12:25 volumio-acer volumio[998]: info: CoreStateMachine::addQueueItems Jan 15 16:12:25 volumio-acer volumio[998]: info: CorePlayQueue::addQueueItems Jan 15 16:12:25 volumio-acer volumio[998]: info: Preload queue cleared Jan 15 16:12:25 volumio-acer volumio[998]: info: Adding Item to queue: upnp/folder/http://192.168.1.7:2869/upnphost/udhisapi.dll?control=uuid:72faeeb3-aaf1-440b-8a26-af0bb83bc568+urn:upnp-org:serviceId:ContentDirectory@1 Jan 15 16:12:25 volumio-acer volumio[998]: info: Exploding uri upnp/folder/http://192.168.1.7:2869/upnphost/udhisapi.dll?control=uuid:72faeeb3-aaf1-440b-8a26-af0bb83bc568+urn:upnp-org:serviceId:ContentDirectory@1 in service upnp_browser Jan 15 16:12:25 volumio-acer volumio[998]: info: CoreCommandRouter::volumioPushQueue Jan 15 16:12:25 volumio-acer volumio[998]: info: CorePlayQueue::saveQueue Jan 15 16:12:25 volumio-acer volumio[998]: info: CoreStateMachine::updateTrackBlock Jan 15 16:12:25 volumio-acer volumio[998]: info: CorePlayQueue::getTrackBlock Jan 15 16:12:25 volumio-acer volumio[998]: info: CoreCommandRouter::volumioPlay Jan 15 16:12:25 volumio-acer volumio[998]: info: CoreStateMachine::play index 0 Jan 15 16:12:25 volumio-acer volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 15 16:12:25 volumio-acer volumio[998]: info: CoreStateMachine::stop Jan 15 16:12:25 volumio-acer volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 15 16:12:25 volumio-acer volumio[998]: info: CoreStateMachine::play index undefined Jan 15 16:12:25 volumio-acer volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 15 16:12:25 volumio-acer volumio[998]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:27 volumio-acer volumio[998]: info: Preload queue cleared Jan 15 16:12:27 volumio-acer volumio[998]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 15 16:12:27 volumio-acer volumio[998]: info: CoreStateMachine::ClearQueue Jan 15 16:12:27 volumio-acer volumio[998]: info: CoreStateMachine::stop Jan 15 16:12:27 volumio-acer volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 15 16:12:27 volumio-acer volumio[998]: info: CorePlayQueue::clearPlayQueue Jan 15 16:12:27 volumio-acer volumio[998]: info: CorePlayQueue::saveQueue Jan 15 16:12:27 volumio-acer volumio[998]: info: CoreCommandRouter::volumioPushQueue Jan 15 16:12:27 volumio-acer volumio[998]: info: CoreStateMachine::addQueueItems Jan 15 16:12:27 volumio-acer volumio[998]: info: CorePlayQueue::addQueueItems Jan 15 16:12:27 volumio-acer volumio[998]: info: Preload queue cleared Jan 15 16:12:27 volumio-acer volumio[998]: info: Adding Item to queue: upnp/folder/http://192.168.1.7:2869/upnphost/udhisapi.dll?control=uuid:72faeeb3-aaf1-440b-8a26-af0bb83bc568+urn:upnp-org:serviceId:ContentDirectory@1 Jan 15 16:12:27 volumio-acer volumio[998]: info: Using cached record of: upnp/folder/http://192.168.1.7:2869/upnphost/udhisapi.dll?control=uuid:72faeeb3-aaf1-440b-8a26-af0bb83bc568+urn:upnp-org:serviceId:ContentDirectory@1 Jan 15 16:12:27 volumio-acer volumio[998]: info: CoreCommandRouter::volumioPushQueue Jan 15 16:12:27 volumio-acer volumio[998]: info: CorePlayQueue::saveQueue Jan 15 16:12:27 volumio-acer volumio[998]: info: CoreStateMachine::updateTrackBlock Jan 15 16:12:27 volumio-acer volumio[998]: info: CorePlayQueue::getTrackBlock Jan 15 16:12:27 volumio-acer volumio[998]: info: CoreCommandRouter::volumioPlay Jan 15 16:12:27 volumio-acer volumio[998]: info: CoreStateMachine::play index 0 Jan 15 16:12:27 volumio-acer volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 15 16:12:27 volumio-acer volumio[998]: info: CoreStateMachine::stop Jan 15 16:12:27 volumio-acer volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 15 16:12:27 volumio-acer volumio[998]: info: CoreStateMachine::play index undefined Jan 15 16:12:27 volumio-acer volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 15 16:12:27 volumio-acer volumio[998]: info: CorePlayQueue::getTrack 0 Jan 15 16:12:28 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 15 16:12:29 volumio-acer volumio[998]: info: Preload queue cleared Jan 15 16:12:36 volumio-acer volumio[998]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 15 16:12:36 volumio-acer volumio[998]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 15 16:12:36 volumio-acer volumio[998]: TypeError: Cannot read property 'length' of undefined Jan 15 16:12:36 volumio-acer volumio[998]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jan 15 16:12:36 volumio-acer volumio[998]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jan 15 16:12:36 volumio-acer volumio[998]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jan 15 16:12:36 volumio-acer volumio[998]: at Parser.emit (events.js:400:28) Jan 15 16:12:36 volumio-acer volumio[998]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jan 15 16:12:36 volumio-acer volumio[998]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jan 15 16:12:36 volumio-acer volumio[998]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jan 15 16:12:36 volumio-acer volumio[998]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jan 15 16:12:36 volumio-acer volumio[998]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jan 15 16:12:36 volumio-acer volumio[998]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jan 15 16:12:36 volumio-acer volumio[998]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jan 15 16:12:36 volumio-acer volumio[998]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jan 15 16:12:36 volumio-acer volumio[998]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jan 15 16:12:36 volumio-acer volumio[998]: at IncomingMessage.emit (events.js:412:35) Jan 15 16:12:36 volumio-acer volumio[998]: at endReadableNT (internal/streams/readable.js:1333:12) Jan 15 16:12:36 volumio-acer volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jan 15 16:12:36 volumio-acer volumio[998]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 15 16:12:36 volumio-acer sudo[2298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-15 16:11 Jan 15 16:12:36 volumio-acer sudo[2298]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"