-- Logs begin at Tue 2025-01-21 13:11:36 +07, end at Tue 2025-01-21 13:13:29 +07. -- Jan 21 13:12:01 volumio9 wireless.js[706]: trying... Jan 21 13:12:01 volumio9 sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 21 13:12:01 volumio9 sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:01 volumio9 sudo[1123]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:02 volumio9 wireless.js[706]: trying... Jan 21 13:12:02 volumio9 sudo[1126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 21 13:12:02 volumio9 sudo[1126]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:02 volumio9 sudo[1126]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:03 volumio9 wireless.js[706]: Overtime, starting plan B Jan 21 13:12:03 volumio9 systemd[1]: Started Wireless Services. Jan 21 13:12:03 volumio9 systemd[1]: Started Volumio Backend Module. Jan 21 13:12:03 volumio9 systemd[1]: Started Intel SST and HDA soundcard init service. Jan 21 13:12:03 volumio9 systemd[1]: Started Volumio Cpu Tweaker. Jan 21 13:12:03 volumio9 systemd[1]: Started Start Volumio Kiosk. Jan 21 13:12:03 volumio9 systemd[1]: Reached target Multi-User System. Jan 21 13:12:03 volumio9 systemd[1]: Reached target Graphical Interface. Jan 21 13:12:03 volumio9 systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 21 13:12:03 volumio9 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 21 13:12:03 volumio9 systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 21 13:12:03 volumio9 systemd[1]: Startup finished in 13.449s (kernel) + 25.355s (userspace) = 38.805s. Jan 21 13:12:03 volumio9 volumio-cpu-tweak[1136]: Setting RT Priority for mpd Jan 21 13:12:03 volumio9 volumio-cpu-tweak[1136]: Not enough cores to set MPD affinity Jan 21 13:12:03 volumio9 volumio-cpu-tweak[1136]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jan 21 13:12:03 volumio9 systemd[1]: volumio_cpu_tweak.service: Succeeded. Jan 21 13:12:03 volumio9 startx[1137]: X.Org X Server 1.20.4 Jan 21 13:12:03 volumio9 startx[1137]: X Protocol Version 11, Revision 0 Jan 21 13:12:03 volumio9 startx[1137]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Jan 21 13:12:03 volumio9 startx[1137]: Current Operating System: Linux volumio9 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64 Jan 21 13:12:03 volumio9 startx[1137]: Kernel command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=0e347d74-2a8e-4322-9f16-422638be666d bootpart=UUID=EFF3-BB03 datapart=UUID=75afb4e0-8c0a-469c-bc48-f3eff676f1c5 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 21 13:12:03 volumio9 startx[1137]: Build Date: 18 December 2021 09:05:36AM Jan 21 13:12:03 volumio9 startx[1137]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Jan 21 13:12:03 volumio9 startx[1137]: Current version of pixman: 0.36.0 Jan 21 13:12:03 volumio9 startx[1137]: Before reporting problems, check http://wiki.x.org Jan 21 13:12:03 volumio9 startx[1137]: to make sure that you have the latest version. Jan 21 13:12:03 volumio9 startx[1137]: Markers: (--) probed, (**) from config file, (==) default setting, Jan 21 13:12:03 volumio9 startx[1137]: (++) from command line, (!!) notice, (II) informational, Jan 21 13:12:03 volumio9 startx[1137]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jan 21 13:12:03 volumio9 startx[1137]: (==) Log file: "/var/log/Xorg.0.log", Time: Tue Jan 21 13:12:03 2025 Jan 21 13:12:03 volumio9 startx[1137]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jan 21 13:12:03 volumio9 volumio-remote-updater[704]: [2025-01-21 13:12:03] [info] asio async_connect error: system:111 (Connection refused) Jan 21 13:12:03 volumio9 volumio-remote-updater[704]: [2025-01-21 13:12:03] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 21 13:12:03 volumio9 volumio-remote-updater[704]: [2025-01-21 13:12:03] [error] handle_connect error: Underlying Transport Error Jan 21 13:12:04 volumio9 systemd[1]: soundcard-init.service: Succeeded. Jan 21 13:12:04 volumio9 startx[1137]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Jan 21 13:12:05 volumio9 volumio[1134]: info: ------------------------------------------- Jan 21 13:12:05 volumio9 volumio[1134]: info: ----- Volumio3 ---- Jan 21 13:12:05 volumio9 volumio[1134]: info: ------------------------------------------- Jan 21 13:12:05 volumio9 volumio[1134]: info: ----- System startup ---- Jan 21 13:12:05 volumio9 volumio[1134]: info: ------------------------------------------- Jan 21 13:12:05 volumio9 volumio[1134]: info: MYVOLUMIO Environment detected Jan 21 13:12:05 volumio9 volumio[1134]: info: Plugin folders cleanup Jan 21 13:12:05 volumio9 volumio[1134]: info: Scanning into folder /volumio/app/plugins/ Jan 21 13:12:05 volumio9 volumio[1134]: info: Scanning category audio_interface Jan 21 13:12:05 volumio9 volumio[1134]: info: Scanning category miscellanea Jan 21 13:12:05 volumio9 volumio[1134]: info: Scanning category music_service Jan 21 13:12:05 volumio9 volumio[1134]: info: Scanning category plugins.json Jan 21 13:12:05 volumio9 volumio[1134]: info: Scanning category system_controller Jan 21 13:12:05 volumio9 volumio[1134]: info: Scanning category user_interface Jan 21 13:12:05 volumio9 volumio[1134]: info: Scanning into folder /data/plugins/ Jan 21 13:12:05 volumio9 volumio[1134]: info: Scanning category music_service Jan 21 13:12:05 volumio9 volumio[1134]: info: Plugin folders cleanup completed Jan 21 13:12:05 volumio9 volumio[1134]: info: ------------------------------------------- Jan 21 13:12:05 volumio9 volumio[1134]: info: ----- Core plugins startup ---- Jan 21 13:12:05 volumio9 volumio[1134]: info: ------------------------------------------- Jan 21 13:12:05 volumio9 volumio[1134]: info: Loading plugins from folder /volumio/app/plugins/ Jan 21 13:12:05 volumio9 volumio[1134]: info: Adding plugin upnp to MyMusic Plugins Jan 21 13:12:05 volumio9 volumio[1134]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 21 13:12:05 volumio9 volumio[1134]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 21 13:12:05 volumio9 volumio[1134]: info: Loading plugins from folder /data/plugins/ Jan 21 13:12:05 volumio9 volumio[1134]: info: Loading plugin "system"... Jan 21 13:12:05 volumio9 volumio[1134]: info: Loading plugin "appearance"... Jan 21 13:12:07 volumio9 volumio[1134]: info: Loading plugin "network"... Jan 21 13:12:07 volumio9 volumio[1134]: info: Refreshing Cached IP Addresses Jan 21 13:12:07 volumio9 sudo[1343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 21 13:12:07 volumio9 sudo[1343]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:07 volumio9 sudo[1341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 21 13:12:07 volumio9 sudo[1341]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:07 volumio9 sudo[1343]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:07 volumio9 sudo[1341]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:07 volumio9 volumio[1134]: info: Loading plugin "services"... Jan 21 13:12:07 volumio9 volumio[1134]: info: Loading plugin "alsa_controller"... Jan 21 13:12:07 volumio9 sudo[1352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 21 13:12:07 volumio9 sudo[1352]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:07 volumio9 sudo[1352]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:07 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 13:12:07 volumio9 volumio[1134]: info: Loading plugin "wizard"... Jan 21 13:12:07 volumio9 volumio[1134]: info: Loading plugin "networkfs"... Jan 21 13:12:07 volumio9 volumio[1134]: info: Starting Udev Watcher for removable devices Jan 21 13:12:07 volumio9 sudo[1372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Santichai,password=MmXp3152,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.5/Music /mnt/NAS/Storage5 Jan 21 13:12:07 volumio9 sudo[1372]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:07 volumio9 sudo[1374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Santichai,password=MmXp3152,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.6/Music /mnt/NAS/Storage6 Jan 21 13:12:07 volumio9 volumio[1134]: info: Ignoring mount for partition: boot Jan 21 13:12:07 volumio9 sudo[1374]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:07 volumio9 volumio[1134]: info: Ignoring mount for partition: volumio Jan 21 13:12:07 volumio9 volumio[1134]: info: Ignoring mount for partition: volumio_data Jan 21 13:12:07 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 21 13:12:07 volumio9 volumio[1134]: info: Loading plugin "volumio_command_line_client"... Jan 21 13:12:07 volumio9 volumio[1134]: info: Loading plugin "upnp"... Jan 21 13:12:07 volumio9 kernel: FS-Cache: Loaded Jan 21 13:12:07 volumio9 kernel: Key type dns_resolver registered Jan 21 13:12:07 volumio9 volumio[1134]: info: [1737439927718] Starting Upmpd Daemon Jan 21 13:12:07 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 21 13:12:07 volumio9 volumio[1134]: info: Loading plugin "my_music"... Jan 21 13:12:07 volumio9 volumio[1134]: info: Loading plugin "mpd"... Jan 21 13:12:07 volumio9 kernel: Key type cifs.spnego registered Jan 21 13:12:07 volumio9 kernel: Key type cifs.idmap registered Jan 21 13:12:07 volumio9 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 21 13:12:07 volumio9 kernel: CIFS: Attempting to mount //192.168.1.5/Music Jan 21 13:12:07 volumio9 kernel: cryptd: max_cpu_qlen set to 1000 Jan 21 13:12:08 volumio9 volumio[1134]: info: Loading plugin "upnp_browser"... Jan 21 13:12:08 volumio9 kernel: CIFS: Attempting to mount //192.168.1.6/Music Jan 21 13:12:08 volumio9 sudo[1372]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:08 volumio9 volumio[1134]: info: Loading plugin "alarm-clock"... Jan 21 13:12:08 volumio9 volumio[1134]: info: Loading plugin "airplay_emulation"... Jan 21 13:12:08 volumio9 volumio[1134]: info: Starting Shairport Sync Jan 21 13:12:08 volumio9 volumio[1134]: info: Loading plugin "last_100"... Jan 21 13:12:08 volumio9 volumio[1134]: info: Loading plugin "webradio"... Jan 21 13:12:08 volumio9 sudo[1374]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:08 volumio9 volumio[1134]: info: Loading plugin "i2s_dacs"... Jan 21 13:12:08 volumio9 volumio[1134]: info: I2S DAC not set, start Auto-detection Jan 21 13:12:08 volumio9 volumio[1134]: info: Loading plugin "volumiodiscovery"... Jan 21 13:12:08 volumio9 volumio[1134]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 21 13:12:08 volumio9 volumio[1134]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 13:12:08 volumio9 volumio[1134]: *** WARNING *** For more information see Jan 21 13:12:08 volumio9 volumio[1134]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 21 13:12:08 volumio9 node[1134]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 21 13:12:08 volumio9 volumio[1134]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 13:12:08 volumio9 volumio[1134]: *** WARNING *** For more information see Jan 21 13:12:08 volumio9 node[1134]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 13:12:08 volumio9 node[1134]: *** WARNING *** For more information see Jan 21 13:12:08 volumio9 node[1134]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 21 13:12:08 volumio9 node[1134]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 13:12:08 volumio9 node[1134]: *** WARNING *** For more information see Jan 21 13:12:08 volumio9 volumio[1134]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 21 13:12:08 volumio9 volumio[1134]: info: Discovery: Started advertising with name: Volumio9 Jan 21 13:12:08 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 21 13:12:08 volumio9 volumio[1134]: info: Loading plugin "spop"... Jan 21 13:12:08 volumio9 dbus-daemon[707]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.11' (uid=0 pid=1315 comm="/usr/lib/chromium/chromium --show-component-extens") Jan 21 13:12:08 volumio9 systemd[1]: Starting Bluetooth service... Jan 21 13:12:08 volumio9 bluetoothd[1428]: Bluetooth daemon 5.50 Jan 21 13:12:08 volumio9 kernel: Bluetooth: Core ver 2.22 Jan 21 13:12:08 volumio9 kernel: NET: Registered PF_BLUETOOTH protocol family Jan 21 13:12:08 volumio9 kernel: Bluetooth: HCI device and connection manager initialized Jan 21 13:12:08 volumio9 kernel: Bluetooth: HCI socket layer initialized Jan 21 13:12:08 volumio9 kernel: Bluetooth: L2CAP socket layer initialized Jan 21 13:12:08 volumio9 kernel: Bluetooth: SCO socket layer initialized Jan 21 13:12:08 volumio9 bluetoothd[1428]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jan 21 13:12:08 volumio9 dbus-daemon[707]: [system] Successfully activated service 'org.bluez' Jan 21 13:12:08 volumio9 bluetoothd[1428]: Starting SDP server Jan 21 13:12:08 volumio9 systemd[1]: Started Bluetooth service. Jan 21 13:12:08 volumio9 bluetoothd[1428]: Excluding (cli) sap Jan 21 13:12:08 volumio9 bluetoothd[1428]: Bluetooth management interface 1.22 initialized Jan 21 13:12:08 volumio9 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 21 13:12:08 volumio9 kernel: Bluetooth: BNEP filters: protocol multicast Jan 21 13:12:08 volumio9 kernel: Bluetooth: BNEP socket layer initialized Jan 21 13:12:08 volumio9 dbus-daemon[707]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.12' (uid=0 pid=1428 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jan 21 13:12:08 volumio9 systemd[1]: Starting Hostname Service... Jan 21 13:12:09 volumio9 dbus-daemon[707]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 21 13:12:09 volumio9 systemd[1]: Started Hostname Service. Jan 21 13:12:09 volumio9 volumio[1134]: info: Loading plugin "ytmusic"... Jan 21 13:12:10 volumio9 systemd[1]: systemd-fsckd.service: Succeeded. Jan 21 13:12:11 volumio9 volumio[1134]: info: Loading plugin "outputs"... Jan 21 13:12:11 volumio9 volumio[1134]: info: Loading plugin "albumart"... Jan 21 13:12:11 volumio9 volumio[1134]: info: Plugin example_plugin is not enabled Jan 21 13:12:11 volumio9 volumio[1134]: info: Loading plugin "inputs"... Jan 21 13:12:11 volumio9 volumio[1134]: info: Loading plugin "updater_comm"... Jan 21 13:12:11 volumio9 volumio[1134]: Forking 1 albumart workers Jan 21 13:12:11 volumio9 volumio[1134]: info: Plugin mpdemulation is not enabled Jan 21 13:12:11 volumio9 volumio[1134]: info: Loading plugin "rest_api"... Jan 21 13:12:11 volumio9 volumio[1134]: info: Loading plugin "websocket"... Jan 21 13:12:11 volumio9 volumio[1134]: info: Starting Socket.io Server version 2.3.0 Jan 21 13:12:11 volumio9 volumio[1134]: info: Loading plugin "lms"... Jan 21 13:12:11 volumio9 volumio[1134]: info: Loading i18n strings for locale en Jan 21 13:12:11 volumio9 volumio[1134]: Updating browse sources language Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 13:12:11 volumio9 volumio[1134]: Starting albumart workers Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::initPlayerControls Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 13:12:11 volumio9 volumio[1134]: Express server listening on port 3000 Jan 21 13:12:11 volumio9 volumio[1134]: [Metrics] WebUI: 6s 981.88ms Jan 21 13:12:11 volumio9 volumio[1134]: info: Setting Device type: x86 Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreStateMachine::resetVolumioState Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreStateMachine::getcurrentVolume Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::volumioRetrievevolume Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 13:12:11 volumio9 volumio[1134]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 21 13:12:11 volumio9 volumio[1134]: info: Completed loading Core Plugins Jan 21 13:12:11 volumio9 volumio[1134]: info: Preparing to generate the ALSA configuration file Jan 21 13:12:11 volumio9 volumio[1134]: info: Volumio Network Manager: Network status updated: 1 Jan 21 13:12:11 volumio9 volumio[1134]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jan 21 13:12:11 volumio9 volumio[1134]: wlan0 Interface doesn't support scanning : Network is down Jan 21 13:12:11 volumio9 volumio[1134]: info: Cannot use regular scanning, forcing with ap-force Jan 21 13:12:11 volumio9 sudo[1544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jan 21 13:12:11 volumio9 sudo[1544]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:11 volumio9 sudo[1544]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:11 volumio9 volumio[1134]: command failed: Network is down (-100) Jan 21 13:12:11 volumio9 volumio[1134]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jan 21 13:12:11 volumio9 volumio[1134]: command failed: Network is down (-100) Jan 21 13:12:11 volumio9 volumio[1134]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jan 21 13:12:11 volumio9 volumio[1134]: info: Reloading queue from file Jan 21 13:12:11 volumio9 volumio[1134]: info: VolumeController:: Volume=24 Mute =false Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreStateMachine::pushState Jan 21 13:12:11 volumio9 volumio[1134]: info: CorePlayQueue::getTrack 0 Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::volumioPushState Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreStateMachine::updateTrackBlock Jan 21 13:12:11 volumio9 volumio[1134]: info: CorePlayQueue::getTrackBlock Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::volumioRetrievevolume Jan 21 13:12:11 volumio9 volumio[1134]: info: Asound.conf file unchanged, so no further update is needed Jan 21 13:12:11 volumio9 volumio[1134]: info: Output device has changed, restarting MPD Jan 21 13:12:11 volumio9 sudo[1561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 21 13:12:11 volumio9 sudo[1561]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:11 volumio9 sudo[1561]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:11 volumio9 volumio[1134]: info: Output device has changed, restarting Shairport Sync Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 13:12:11 volumio9 sudo[1564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 21 13:12:11 volumio9 sudo[1564]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:11 volumio9 systemd[1]: Stopping Music Player Daemon... Jan 21 13:12:11 volumio9 volumio[1134]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 21 13:12:11 volumio9 volumio[1134]: info: ___________ START PLUGINS ___________ Jan 21 13:12:11 volumio9 volumio[1134]: info: ControllerMpd::onStart: Initializing MPD Jan 21 13:12:11 volumio9 volumio[1134]: info: Creating MPD Configuration file Jan 21 13:12:11 volumio9 sudo[1570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 21 13:12:11 volumio9 sudo[1570]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 21 13:12:11 volumio9 volumio[1134]: info: [1737439931890] CoreMusicLibrary::Adding element Media Servers Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 13:12:11 volumio9 sudo[1570]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:11 volumio9 sudo[1572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 21 13:12:11 volumio9 sudo[1572]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:11 volumio9 systemd[1]: mpd.service: Succeeded. Jan 21 13:12:11 volumio9 systemd[1]: Stopped Music Player Daemon. Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 13:12:11 volumio9 systemd[1]: Starting Music Player Daemon... Jan 21 13:12:11 volumio9 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 21 13:12:11 volumio9 systemd[1]: mpd.service: Succeeded. Jan 21 13:12:11 volumio9 systemd[1]: Stopped Music Player Daemon. Jan 21 13:12:11 volumio9 systemd[1]: Starting Music Player Daemon... Jan 21 13:12:11 volumio9 volumio[1134]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 13:12:11 volumio9 volumio[1134]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 21 13:12:11 volumio9 volumio[1134]: info: [1737439931968] CoreMusicLibrary::Adding element Last_100 Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 21 13:12:11 volumio9 volumio[1134]: info: [1737439931970] CoreMusicLibrary::Adding element Webradio Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 13:12:11 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 13:12:11 volumio9 volumio[1134]: info: Initializing BBC Radios Jan 21 13:12:11 volumio9 sudo[1579]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 21 13:12:11 volumio9 sudo[1579]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:11 volumio9 sudo[1579]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 13:12:12 volumio9 volumio[1134]: info: Creating Spotify config file Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 21 13:12:12 volumio9 volumio[1134]: info: [1737439932068] CoreMusicLibrary::Adding element YouTube Music Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 13:12:12 volumio9 volumio[1134]: Cannot find translation for source YouTube Music Jan 21 13:12:12 volumio9 volumio[1134]: info: Volumio Calling Home Jan 21 13:12:12 volumio9 sudo[1596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Jan 21 13:12:12 volumio9 sudo[1596]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:12 volumio9 systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreStateMachine::setRepeat null single undefined Jan 21 13:12:12 volumio9 systemd[1]: Starting Logitech Media Server Daemon... Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreStateMachine::pushState Jan 21 13:12:12 volumio9 volumio[1134]: info: CorePlayQueue::getTrack 0 Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreCommandRouter::volumioPushState Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreStateMachine::setRandom null Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreStateMachine::pushState Jan 21 13:12:12 volumio9 volumio[1134]: info: CorePlayQueue::getTrack 0 Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreCommandRouter::volumioPushState Jan 21 13:12:12 volumio9 systemd[1]: Started Logitech Media Server Daemon. Jan 21 13:12:12 volumio9 systemd[1603]: logitechmediaserver.service: Failed to execute command: No such file or directory Jan 21 13:12:12 volumio9 systemd[1603]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory Jan 21 13:12:12 volumio9 sudo[1596]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:12 volumio9 systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC Jan 21 13:12:12 volumio9 systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Jan 21 13:12:12 volumio9 volumio[1134]: info: logitechmediaserver started Jan 21 13:12:12 volumio9 volumio[1134]: info: MPD Permissions set Jan 21 13:12:12 volumio9 volumio[1134]: info: Spotify config file written Jan 21 13:12:12 volumio9 sudo[1607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 21 13:12:12 volumio9 sudo[1607]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:12 volumio9 volumio[1134]: 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 21 13:12:12 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 13:12:12 volumio9 systemd[1]: Started go-librespot Daemon. Jan 21 13:12:12 volumio9 go-librespot[1609]: Librespot-go daemon starting... Jan 21 13:12:12 volumio9 sudo[1607]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 21 13:12:12 volumio9 go-librespot[1609]: time="2025-01-21T13:12:12+07:00" level=info msg="generated new device id: c7ea8108cf9bc20fef31f3f449b06e1f02ec3fd8" Jan 21 13:12:12 volumio9 go-librespot[1609]: time="2025-01-21T13:12:12+07:00" level=debug msg="stored credentials found for 31ujwh6hkh6kshabxeokjduu7j3e" Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 13:12:12 volumio9 volumio[1134]: info: No need to fix Spotify hosts Jan 21 13:12:12 volumio9 volumio[1134]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jan 21 13:12:12 volumio9 mpd[1585]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 21 13:12:12 volumio9 mpd[1585]: output: No 'audio_output' defined in config file Jan 21 13:12:12 volumio9 mpd[1585]: output: Successfully detected a sndio audio device Jan 21 13:12:12 volumio9 mpd[1585]: zeroconf: No global port, disabling zeroconf Jan 21 13:12:12 volumio9 systemd[1]: Started Music Player Daemon. Jan 21 13:12:12 volumio9 sudo[1572]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:12 volumio9 sudo[1564]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:12 volumio9 volumio[1134]: info: VolumeController:: Volume=24 Mute =false Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreStateMachine::pushState Jan 21 13:12:12 volumio9 volumio[1134]: info: CorePlayQueue::getTrack 0 Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreCommandRouter::volumioPushState Jan 21 13:12:12 volumio9 volumio[1134]: info: Completed starting Core Plugins Jan 21 13:12:12 volumio9 volumio[1134]: info: ------------------------------------------- Jan 21 13:12:12 volumio9 volumio[1134]: info: ----- MyVolumio plugins startup ---- Jan 21 13:12:12 volumio9 volumio[1134]: info: ------------------------------------------- Jan 21 13:12:12 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 21 13:12:12 volumio9 volumio[1134]: info: Volumio called home Jan 21 13:12:12 volumio9 volumio[1134]: info: Starting Shairport Sync Jan 21 13:12:12 volumio9 volumio[1134]: info: Starting Shairport Sync Jan 21 13:12:12 volumio9 volumio[1134]: info: Starting Shairport Sync Jan 21 13:12:12 volumio9 sudo[1633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 21 13:12:12 volumio9 sudo[1635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 21 13:12:12 volumio9 sudo[1633]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:12 volumio9 sudo[1635]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:12 volumio9 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 21 13:12:12 volumio9 systemd[1]: shairport-sync.service: Succeeded. Jan 21 13:12:12 volumio9 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 21 13:12:12 volumio9 go-librespot[1609]: time="2025-01-21T13:12:12+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 21 13:12:12 volumio9 go-librespot[1609]: time="2025-01-21T13:12:12+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 21 13:12:12 volumio9 go-librespot[1609]: time="2025-01-21T13:12:12+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 21 13:12:12 volumio9 go-librespot[1609]: time="2025-01-21T13:12:12+07:00" level=debug msg="zeroconf server listening on port 38099" Jan 21 13:12:12 volumio9 sudo[1639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 21 13:12:12 volumio9 sudo[1639]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:12 volumio9 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 21 13:12:12 volumio9 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 21 13:12:12 volumio9 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 21 13:12:12 volumio9 systemd[1]: shairport-sync.service: Succeeded. Jan 21 13:12:12 volumio9 sudo[1635]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:12 volumio9 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 21 13:12:12 volumio9 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 21 13:12:12 volumio9 sudo[1633]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:12 volumio9 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 21 13:12:12 volumio9 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 21 13:12:12 volumio9 systemd[1]: shairport-sync.service: Succeeded. Jan 21 13:12:12 volumio9 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 21 13:12:12 volumio9 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 21 13:12:12 volumio9 sudo[1639]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:12 volumio9 volumio[1134]: error: MPD error: The expression evaluated to a falsy value: Jan 21 13:12:12 volumio9 volumio[1134]: assert.ok(self.idling) Jan 21 13:12:12 volumio9 volumio[1134]: error: The expression evaluated to a falsy value: Jan 21 13:12:12 volumio9 volumio[1134]: assert.ok(self.idling) Jan 21 13:12:12 volumio9 volumio[1134]: info: CoreCommandRouter::volumioGetState Jan 21 13:12:12 volumio9 volumio[1134]: info: CorePlayQueue::getTrack 0 Jan 21 13:12:12 volumio9 volumio[1134]: error: updateQueue error: null Jan 21 13:12:12 volumio9 volumio[1134]: info: Shairport-Sync Started Jan 21 13:12:12 volumio9 volumio[1134]: Error adding Membership: Error: addMembership EINVAL Jan 21 13:12:12 volumio9 volumio[1134]: info: Shairport-Sync Started Jan 21 13:12:12 volumio9 volumio[1134]: info: Shairport-Sync Started Jan 21 13:12:12 volumio9 volumio[1134]: info: MPD running with PID1585 Jan 21 13:12:12 volumio9 volumio[1134]: ,establishing connection Jan 21 13:12:12 volumio9 go-librespot[1609]: time="2025-01-21T13:12:12+07:00" level=debug msg="obtained new client token: AABxMjcMK+JtaJMmnVImqwylUAxZ1emuVthQiQ7uVHKLmpghlYfjQsYDbs7/PfTyU/lCLe5+2xHD0zJN8WY7EbgtmGEVpWN/jtoRSNLc3JWaIetQhiEmeK3YF+JHnB3KBwNjT9GxhE/vUqEHKIx6M9dZcrUt4r2hacoGGsNnt+XdkJNeN63hKSylILAk58OxEVW1eK48sMy6E3SWNt4xcF51SYPsSeLeACvVfgpd64d8YZX7VgCpFDibZ/hWJg==" Jan 21 13:12:12 volumio9 volumio[1134]: error: updateQueue error: null Jan 21 13:12:12 volumio9 volumio[1134]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 21 13:12:12 volumio9 volumio[1134]: SPOTIFY: BQC-CrWtSS9S5IiwDjcBJcgMD3II5xY4e_wElIk104GvQxsqNWqr7Yh6T0uJNw-qMu-J4nafsuQgu6s9yQZ71iPWUbwkBpYIgJxih86wbsnHUXvSqOurpiqaMPbK2OgXo-XiBKJViJDeReK0JT6kgW-eJ8qeklqHTMNaViMRyvvgK2euSZ2-aBNXxPFK6ROl_tFryn6UUTiYeHOpAWIT2Hq3O-fhMszWRVvZMo2mI6ezrVH0-rbU02h7ezdaG6qyrCbbI056jitxJscVOxU Jan 21 13:12:12 volumio9 volumio[1134]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 21 13:12:12 volumio9 volumio[1134]: info: New Spotify access token = BQC-CrWtSS9S5IiwDjcBJcgMD3II5xY4e_wElIk104GvQxsqNWqr7Yh6T0uJNw-qMu-J4nafsuQgu6s9yQZ71iPWUbwkBpYIgJxih86wbsnHUXvSqOurpiqaMPbK2OgXo-XiBKJViJDeReK0JT6kgW-eJ8qeklqHTMNaViMRyvvgK2euSZ2-aBNXxPFK6ROl_tFryn6UUTiYeHOpAWIT2Hq3O-fhMszWRVvZMo2mI6ezrVH0-rbU02h7ezdaG6qyrCbbI056jitxJscVOxU Jan 21 13:12:12 volumio9 volumio[1134]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 21 13:12:13 volumio9 go-librespot[1609]: time="2025-01-21T13:12:13+07:00" level=info msg="connected to ap-gae2.spotify.com:4070" Jan 21 13:12:13 volumio9 go-librespot[1609]: time="2025-01-21T13:12:13+07:00" level=debug msg="completed keyexchange" Jan 21 13:12:13 volumio9 volumio[1134]: SPOTIFY: User informations: {"country":"TH","display_name":"ชนะ ชนะภัย","email":"schanapai@hotmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31ujwh6hkh6kshabxeokjduu7j3e"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31ujwh6hkh6kshabxeokjduu7j3e","id":"31ujwh6hkh6kshabxeokjduu7j3e","images":[{"height":300,"url":"https://scontent-ams2-1.xx.fbcdn.net/v/t39.30808-1/405560085_6916165708500864_5513590859254949333_n.jpg?stp=c427.0.1518.1518a_dst-jpg_s320x320_tt6&_nc_cat=106&ccb=1-7&_nc_sid=79bf43&_nc_ohc=F_Sr8GZY-ywQ7kNvgG7ARh0&_nc_zt=24&_nc_ht=scontent-ams2-1.xx&edm=AP4hL3IEAAAA&_nc_gid=ArBH6hxavaqLLjLjC8BVash&oh=00_AYDLMmUS0jQM7dIM4ySVo93UATFqrHocu0qJ3TO1XxoABw&oe=67950AF5","width":300},{"height":64,"url":"https://scontent-ams2-1.xx.fbcdn.net/v/t39.30808-1/405560085_6916165708500864_5513590859254949333_n.jpg?stp=c427.0.1518.1518a_cp0_dst-jpg_s50x50_tt6&_nc_cat=106&ccb=1-7&_nc_sid=fe756c&_nc_ohc=F_Sr8GZY-ywQ7kNvgG7ARh0&_nc_zt=24&_nc_ht=scontent-ams2-1.xx&edm=AP4hL3IEAAAA&_nc_gid=ArBH6hxavaqLLjLjC8BVash&oh=00_AYAF8t-CXKQsJZQdmkQPA165DG_LBAUYrsp_AZbGxmYGDw&oe=67950AF5","width":64}],"product":"premium","type":"user","uri":"spotify:user:31ujwh6hkh6kshabxeokjduu7j3e"} Jan 21 13:12:13 volumio9 volumio[1134]: info: Spotify Successfully logged in Jan 21 13:12:13 volumio9 volumio[1134]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 21 13:12:13 volumio9 volumio[1134]: info: [1737439933236] CoreMusicLibrary::Adding element Spotify Jan 21 13:12:13 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 13:12:13 volumio9 volumio[1134]: Cannot find translation for source YouTube Music Jan 21 13:12:13 volumio9 volumio[1134]: Cannot find translation for source Spotify Jan 21 13:12:13 volumio9 go-librespot[1609]: time="2025-01-21T13:12:13+07:00" level=debug msg="completed challenge" Jan 21 13:12:13 volumio9 volumio[1134]: 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: 2 Jan 21 13:12:13 volumio9 go-librespot[1609]: time="2025-01-21T13:12:13+07:00" level=debug msg="authenticated as 31ujwh6hkh6kshabxeokjduu7j3e" Jan 21 13:12:13 volumio9 volumio-remote-updater[704]: [2025-01-21 13:12:13] [connect] Successful connection Jan 21 13:12:13 volumio9 volumio-remote-updater[704]: [2025-01-21 13:12:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1737439933 101 Jan 21 13:12:13 volumio9 volumio[1134]: 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: 3 Jan 21 13:12:13 volumio9 go-librespot[1609]: time="2025-01-21T13:12:13+07:00" level=debug msg="authenticated as 31ujwh6hkh6kshabxeokjduu7j3e" Jan 21 13:12:14 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 21 13:12:14 volumio9 go-librespot[1609]: time="2025-01-21T13:12:14+07:00" level=debug msg="dealer connection opened" Jan 21 13:12:14 volumio9 go-librespot[1609]: time="2025-01-21T13:12:14+07:00" level=debug msg="initializing zeroconf session, username: 31ujwh6hkh6kshabxeokjduu7j3e" Jan 21 13:12:14 volumio9 go-librespot[1609]: time="2025-01-21T13:12:14+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 21 13:12:14 volumio9 go-librespot[1609]: time="2025-01-21T13:12:14+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 21 13:12:14 volumio9 go-librespot[1609]: time="2025-01-21T13:12:14+07:00" level=debug msg="received connection id: ZjQwNDY3OTktMTA2Yi00YjNhLWExOTktYzNmZmVlNGMyNTg2K2RlYWxlcit0Y3A6Ly8wYWM5NDkxNC5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArNzM1N0ZBMDg2OTY2NzlCMzczNzg5MDhCM0ZEQUM2ODA5MTQ5QkRGQ0VFMzA3MjRCNTdCN0QxM0M5MzdGNjlGQw==" Jan 21 13:12:14 volumio9 go-librespot[1609]: time="2025-01-21T13:12:14+07:00" level=debug msg="put connect state because NEW_DEVICE" Jan 21 13:12:14 volumio9 go-librespot[1609]: time="2025-01-21T13:12:14+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 21 13:12:14 volumio9 go-librespot[1609]: time="2025-01-21T13:12:14+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 21 13:12:14 volumio9 go-librespot[1609]: time="2025-01-21T13:12:14+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 21 13:12:14 volumio9 go-librespot[1609]: time="2025-01-21T13:12:14+07:00" level=debug msg="autoplay enabled: false" Jan 21 13:12:14 volumio9 volumio[1134]: 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: 4 Jan 21 13:12:15 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 21 13:12:15 volumio9 volumio[1134]: info: CoreCommandRouter::volumioGetVisibleSources Jan 21 13:12:15 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 13:12:15 volumio9 volumio[1134]: info: CoreCommandRouter::volumioGetState Jan 21 13:12:15 volumio9 volumio[1134]: info: CorePlayQueue::getTrack 0 Jan 21 13:12:15 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 21 13:12:15 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 21 13:12:15 volumio9 volumio[1134]: info: Received Get System Info Jan 21 13:12:15 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 13:12:15 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 13:12:15 volumio9 volumio[1134]: info: Discovery: Getting this device information Jan 21 13:12:15 volumio9 volumio[1134]: info: CoreCommandRouter::volumioGetState Jan 21 13:12:15 volumio9 volumio[1134]: info: CorePlayQueue::getTrack 0 Jan 21 13:12:15 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 13:12:15 volumio9 volumio[1134]: info: CoreCommandRouter::volumioGetState Jan 21 13:12:15 volumio9 volumio[1134]: info: CorePlayQueue::getTrack 0 Jan 21 13:12:15 volumio9 volumio[1134]: info: Listing playlists Jan 21 13:12:15 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 21 13:12:15 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 21 13:12:15 volumio9 volumio[1134]: info: go-librespot daemon successfully initialized Jan 21 13:12:15 volumio9 volumio[1134]: info: CoreCommandRouter::volumioGetState Jan 21 13:12:15 volumio9 volumio[1134]: info: CorePlayQueue::getTrack 0 Jan 21 13:12:16 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 21 13:12:16 volumio9 volumio[1134]: info: Received Get System Info Jan 21 13:12:16 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 13:12:16 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 13:12:16 volumio9 volumio[1134]: info: Discovery: Getting this device information Jan 21 13:12:16 volumio9 volumio[1134]: info: CoreCommandRouter::volumioGetState Jan 21 13:12:16 volumio9 volumio[1134]: info: CorePlayQueue::getTrack 0 Jan 21 13:12:16 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 13:12:16 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 21 13:12:17 volumio9 sudo[1666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 21 13:12:17 volumio9 sudo[1666]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:17 volumio9 sudo[1666]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:17 volumio9 sudo[1669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 21 13:12:17 volumio9 sudo[1669]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:17 volumio9 sudo[1669]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:17 volumio9 sudo[1672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 21 13:12:17 volumio9 sudo[1672]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:17 volumio9 systemd[1]: Started UPnP Renderer front-end to MPD. Jan 21 13:12:17 volumio9 sudo[1672]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:17 volumio9 volumio[1134]: info: Upmpdcli Daemon Started Jan 21 13:12:17 volumio9 mpd[1585]: exception: No database Jan 21 13:12:17 volumio9 mpd[1585]: exception: No database Jan 21 13:12:17 volumio9 mpd[1585]: exception: No database Jan 21 13:12:17 volumio9 volumio[1134]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Jan 21 13:12:17 volumio9 volumio[1674]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 21 13:12:18 volumio9 volumio[1674]: .............++++ Jan 21 13:12:18 volumio9 volumio[1134]: info: Initializing connection to go-librespot Websocket Jan 21 13:12:18 volumio9 go-librespot[1609]: time="2025-01-21T13:12:18+07:00" level=debug msg="new websocket client" Jan 21 13:12:18 volumio9 volumio[1134]: info: Connection to go-librespot Websocket established Jan 21 13:12:20 volumio9 volumio[1134]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 13:12:20 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 21 13:12:20 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 21 13:12:20 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 21 13:12:20 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 21 13:12:20 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 13:12:20 volumio9 volumio[1134]: info: CoreCommandRouter::volumioGetBrowseSources Jan 21 13:12:20 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 13:12:20 volumio9 mpd[1585]: exception: No database Jan 21 13:12:20 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 21 13:12:21 volumio9 volumio[1674]: ................................................................................................................................................................................................................................................++++ Jan 21 13:12:21 volumio9 volumio[1674]: e is 65537 (0x010001) Jan 21 13:12:21 volumio9 volumio[1674]: writing RSA key Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 21 13:12:21 volumio9 volumio[1134]: info: Adding plugin bluetooth to MyMusic Plugins Jan 21 13:12:21 volumio9 volumio[1134]: info: Adding plugin multiroom to MyMusic Plugins Jan 21 13:12:21 volumio9 volumio[1134]: info: Adding plugin metavolumio to MyMusic Plugins Jan 21 13:12:21 volumio9 volumio[1134]: info: Adding plugin cd_controller to MyMusic Plugins Jan 21 13:12:21 volumio9 volumio[1134]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 21 13:12:21 volumio9 volumio[1134]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 21 13:12:21 volumio9 systemd[1]: systemd-timedated.service: Succeeded. Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 21 13:12:21 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 13:12:21 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 13:12:21 volumio9 volumio[1134]: info: Starting MyVolumio Remote Streaming Endpoints Jan 21 13:12:21 volumio9 volumio[1134]: info: MyVolumio login type: Token Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 21 13:12:21 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 21 13:12:22 volumio9 volumio[1134]: info: Starting Streaming Service Transparent Proxy Jan 21 13:12:22 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 21 13:12:22 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 21 13:12:22 volumio9 volumio[1134]: info: Streaming services startup Jan 21 13:12:22 volumio9 volumio[1134]: info: Starting Streaming Daemon Jan 21 13:12:22 volumio9 sudo[2104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 21 13:12:22 volumio9 sudo[2104]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:22 volumio9 sudo[2104]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:22 volumio9 volumio[1134]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 21 13:12:22 volumio9 volumio[1134]: info: Getting Spotify volume Jan 21 13:12:22 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 21 13:12:22 volumio9 volumio[1134]: info: Received Get System Info Jan 21 13:12:22 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 13:12:22 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 13:12:22 volumio9 volumio[1134]: info: Discovery: Getting this device information Jan 21 13:12:22 volumio9 volumio[1134]: info: CoreCommandRouter::volumioGetState Jan 21 13:12:22 volumio9 volumio[1134]: info: CorePlayQueue::getTrack 0 Jan 21 13:12:22 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 13:12:22 volumio9 volumio[1134]: error: Cannot start Volumio Streaming Daemon Jan 21 13:12:22 volumio9 volumio[1134]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 21 13:12:22 volumio9 volumio[1134]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 21 13:12:22 volumio9 volumio[1134]: info: Spotify volume: 100 Jan 21 13:12:22 volumio9 volumio[1134]: 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 21 13:12:22 volumio9 volumio[1134]: info: CoreCommandRouter::volumioGetState Jan 21 13:12:22 volumio9 volumio[1134]: info: CorePlayQueue::getTrack 0 Jan 21 13:12:22 volumio9 volumio[1134]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jan 21 13:12:22 volumio9 volumio[1134]: SPOTIFY: SPOTIFY VOLUME 100 Jan 21 13:12:22 volumio9 volumio[1134]: SPOTIFY: VOLUMIO VOLUME 24 Jan 21 13:12:22 volumio9 volumio[1134]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 21 13:12:22 volumio9 volumio[1134]: info: Setting Spotify Volume from Volumio: 24 Jan 21 13:12:23 volumio9 volumio[1134]: STREAMING PROXY: Starting server on port 3245 Jan 21 13:12:23 volumio9 volumio[1134]: Node JS runtime: 14 Jan 21 13:12:23 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 21 13:12:23 volumio9 volumio[1134]: info: MyVolumio login type: Token Jan 21 13:12:23 volumio9 volumio[1134]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 21 13:12:24 volumio9 volumio[1134]: info: MyVolumio token set successfully Jan 21 13:12:24 volumio9 volumio[1134]: info: MYVOLUMIO: Adding device Jan 21 13:12:24 volumio9 volumio[1134]: info: MYVOLUMIO: Evaluating Server Jan 21 13:12:24 volumio9 volumio[1134]: SPOTIFY: SETTING SPOTIFY VOLUME 24 Jan 21 13:12:24 volumio9 volumio[1134]: info: Sending Spotify command with payload to local API: /player/volume Jan 21 13:12:24 volumio9 volumio[1134]: info: MyVolumio status changed Jan 21 13:12:24 volumio9 volumio[1134]: info: Streaming services startup Jan 21 13:12:24 volumio9 volumio[1134]: info: Starting Streaming Daemon Jan 21 13:12:24 volumio9 volumio[1134]: info: Removing browser output: myVolumio user plan is not superstar Jan 21 13:12:24 volumio9 volumio[1134]: info: Removing audio output: Jan 21 13:12:24 volumio9 volumio[1134]: info: Stoppping Tunnel 1 Jan 21 13:12:24 volumio9 sudo[2250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 21 13:12:24 volumio9 sudo[2250]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:24 volumio9 sudo[2250]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:24 volumio9 go-librespot[1609]: time="2025-01-21T13:12:24+07:00" level=debug msg="update volume to 15728/65535" Jan 21 13:12:24 volumio9 volumio[1134]: error: Cannot start Volumio Streaming Daemon Jan 21 13:12:24 volumio9 volumio[1134]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 21 13:12:24 volumio9 volumio[1134]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 21 13:12:24 volumio9 sudo[2258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 21 13:12:24 volumio9 sudo[2258]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 13:12:24 volumio9 sudo[2258]: pam_unix(sudo:session): session closed for user root Jan 21 13:12:24 volumio9 volumio[1134]: info: Remote SSH Stopped Jan 21 13:12:24 volumio9 go-librespot[1609]: time="2025-01-21T13:12:24+07:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 21 13:12:24 volumio9 go-librespot[1609]: time="2025-01-21T13:12:24+07:00" level=trace msg="emitting websocket event: volume" Jan 21 13:12:24 volumio9 volumio[1134]: SPOTIFY: received: {"type":"volume","data":{"value":24,"max":100}} Jan 21 13:12:24 volumio9 volumio[1134]: SPOTIFY: RECEIVED SPOTIFY VOLUME 24 Jan 21 13:12:24 volumio9 volumio[1134]: info: Setting Geolocation for MyVolumio to as1 Jan 21 13:12:24 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 13:12:24 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 13:12:24 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 13:12:24 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 21 13:12:24 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 13:12:24 volumio9 mpd[1585]: exception: No database Jan 21 13:12:25 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 21 13:12:25 volumio9 volumio[1134]: verbose: ControllerMpd::sendMpdCommand rescan Jan 21 13:12:25 volumio9 volumio[1134]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 21 13:12:25 volumio9 volumio[1134]: info: Updating MyVolumio device info Jan 21 13:12:25 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 13:12:25 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 13:12:25 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 21 13:12:25 volumio9 volumio[1134]: info: Received Get System Info Jan 21 13:12:25 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 13:12:25 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 13:12:25 volumio9 volumio[1134]: info: Discovery: Getting this device information Jan 21 13:12:25 volumio9 volumio[1134]: info: CoreCommandRouter::volumioGetState Jan 21 13:12:25 volumio9 volumio[1134]: info: CorePlayQueue::getTrack 0 Jan 21 13:12:25 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 13:12:26 volumio9 volumio[1134]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 21 13:12:28 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 13:12:28 volumio9 mpd[1585]: exception: No database Jan 21 13:12:29 volumio9 volumio[1134]: info: MYVOLUMIO: Adding device Jan 21 13:12:29 volumio9 volumio[1134]: info: MYVOLUMIO: Evaluating Server Jan 21 13:12:30 volumio9 volumio[1134]: info: Setting Geolocation for MyVolumio to as1 Jan 21 13:12:30 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 13:12:30 volumio9 volumio[1134]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 21 13:12:31 volumio9 volumio[1134]: info: Updating MyVolumio device info Jan 21 13:12:31 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 13:12:31 volumio9 volumio[1134]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 21 13:12:32 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 13:12:32 volumio9 mpd[1585]: exception: No database Jan 21 13:12:32 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 21 13:12:32 volumio9 volumio[1134]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 21 13:12:32 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 21 13:12:32 volumio9 volumio[1134]: info: Received Get System Version Jan 21 13:12:32 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 13:12:32 volumio9 volumio[1134]: info: Received Get System Info Jan 21 13:12:32 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 13:12:32 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 13:12:32 volumio9 volumio[1134]: info: Discovery: Getting this device information Jan 21 13:12:32 volumio9 volumio[1134]: info: CoreCommandRouter::volumioGetState Jan 21 13:12:32 volumio9 volumio[1134]: info: CorePlayQueue::getTrack 0 Jan 21 13:12:32 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 13:12:36 volumio9 volumio[1134]: info: CALLMETHOD: miscellanea albumart saveAlbumartOptions [object Object] Jan 21 13:12:36 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: albumart , saveAlbumartOptions Jan 21 13:12:36 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 13:12:36 volumio9 mpd[1585]: exception: No database Jan 21 13:12:36 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 21 13:12:36 volumio9 volumio[1134]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 21 13:12:36 volumio9 volumio[1134]: info: CoreCommandRouter::volumioGetState Jan 21 13:12:36 volumio9 volumio[1134]: info: CorePlayQueue::getTrack 0 Jan 21 13:12:39 volumio9 systemd[1]: systemd-hostnamed.service: Succeeded. Jan 21 13:12:40 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 13:12:40 volumio9 mpd[1585]: exception: No database Jan 21 13:12:41 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 13:12:41 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 13:12:41 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 21 13:12:41 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 21 13:12:41 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 21 13:12:41 volumio9 volumio[1134]: info: CoreCommandRouter::volumioGetBrowseSources Jan 21 13:12:41 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 13:12:42 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 13:12:42 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 13:12:42 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 21 13:12:42 volumio9 volumio-remote-updater[704]: No test mode Jan 21 13:12:42 volumio9 volumio-remote-updater[704]: No alpha test mode Jan 21 13:12:42 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 21 13:12:42 volumio9 volumio[1134]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 21 13:12:42 volumio9 volumio[1134]: info: Completed starting MyVolumio Plugin Jan 21 13:12:42 volumio9 volumio[1134]: [Metrics] CommandRouter: 37s 555.30ms Jan 21 13:12:42 volumio9 volumio[1134]: info: CoreCommandRouter::volumiosetStartupVolume Jan 21 13:12:42 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 13:12:42 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 13:12:42 volumio9 volumio[1134]: info: CoreCommandRouter::Close All Modals sent Jan 21 13:12:42 volumio9 volumio[1134]: info: CoreCommandRouter::Close All Modals sent Jan 21 13:12:42 volumio9 volumio[1134]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 21 13:12:42 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 21 13:12:43 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 21 13:12:43 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 21 13:12:43 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 21 13:12:44 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 13:12:44 volumio9 mpd[1585]: exception: No database Jan 21 13:12:45 volumio9 kernel: snd_hda_intel 0000:00:1b.0: IRQ timing workaround is activated for card #0. Suggest a bigger bdl_pos_adj. Jan 21 13:12:45 volumio9 volumio[1134]: info: CALLMETHOD: music_service mpd saveMusicLibraryOptions [object Object] Jan 21 13:12:45 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , saveMusicLibraryOptions Jan 21 13:12:48 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 13:12:48 volumio9 mpd[1585]: exception: No database Jan 21 13:12:49 volumio9 volumio[1134]: info: BOOT COMPLETED Jan 21 13:12:52 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 13:12:52 volumio9 mpd[1585]: exception: No database Jan 21 13:12:56 volumio9 volumio[1134]: info: CoreCommandRouter::volumioGetState Jan 21 13:12:56 volumio9 volumio[1134]: info: CorePlayQueue::getTrack 0 Jan 21 13:12:59 volumio9 volumio[1134]: info: CoreCommandRouter::volumioGetQueue Jan 21 13:12:59 volumio9 volumio[1134]: info: CoreStateMachine::getQueue Jan 21 13:12:59 volumio9 volumio[1134]: info: CorePlayQueue::getQueue Jan 21 13:12:59 volumio9 ntpd[1040]: Soliciting pool server 202.29.58.130 Jan 21 13:13:00 volumio9 ntpd[1040]: Soliciting pool server 124.109.2.169 Jan 21 13:13:00 volumio9 ntpd[1040]: Soliciting pool server 2406:3100:1010:c03::148 Jan 21 13:13:00 volumio9 ntpd[1040]: Soliciting pool server 202.28.117.7 Jan 21 13:13:01 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 21 13:13:01 volumio9 volumio[1134]: info: CURURI: music-library Jan 21 13:13:01 volumio9 volumio[1134]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Jan 21 13:13:01 volumio9 mpd[1585]: exception: No database Jan 21 13:13:01 volumio9 volumio[1134]: info: Preload queue cleared Jan 21 13:13:05 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 21 13:13:05 volumio9 volumio[1134]: info: Preload queue cleared Jan 21 13:13:07 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 21 13:13:07 volumio9 volumio[1134]: info: Preload queue cleared Jan 21 13:13:08 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 21 13:13:08 volumio9 volumio[1134]: info: Preload queue cleared Jan 21 13:13:10 volumio9 dhcpcd[772]: eth0: carrier lost Jan 21 13:13:10 volumio9 dhcpcd[1041]: eth0: carrier lost Jan 21 13:13:10 volumio9 kernel: e1000e 0000:00:19.0 eth0: NIC Link is Down Jan 21 13:13:10 volumio9 avahi-daemon[727]: Withdrawing address record for 192.168.1.84 on eth0. Jan 21 13:13:10 volumio9 avahi-daemon[727]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.84. Jan 21 13:13:10 volumio9 avahi-daemon[727]: Interface eth0.IPv4 no longer relevant for mDNS. Jan 21 13:13:10 volumio9 volumio[1134]: info: Discovery: A device disappeared from network Jan 21 13:13:10 volumio9 volumio[1134]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Jan 21 13:13:10 volumio9 dhcpcd[1041]: eth0: deleting route to 192.168.1.0/24 Jan 21 13:13:10 volumio9 dhcpcd[1041]: eth0: deleting default route via 192.168.1.254 Jan 21 13:13:10 volumio9 dhcpcd[772]: eth0: deleting route to 192.168.1.0/24 Jan 21 13:13:10 volumio9 dhcpcd[772]: eth0: deleting default route via 192.168.1.254 Jan 21 13:13:10 volumio9 ifplugd(eth0)[850]: Link beat lost. Jan 21 13:13:11 volumio9 ntpd[1040]: Deleting interface #3 eth0, 192.168.1.84#123, interface stats: received=56, sent=56, dropped=3, active_time=81 secs Jan 21 13:13:11 volumio9 ntpd[1040]: 202.28.117.7 local addr 192.168.1.84 -> Jan 21 13:13:11 volumio9 ntpd[1040]: 124.109.2.169 local addr 192.168.1.84 -> Jan 21 13:13:11 volumio9 ntpd[1040]: 202.29.58.130 local addr 192.168.1.84 -> Jan 21 13:13:11 volumio9 ntpd[1040]: 147.50.17.138 local addr 192.168.1.84 -> Jan 21 13:13:11 volumio9 ntpd[1040]: 202.12.97.45 local addr 192.168.1.84 -> Jan 21 13:13:11 volumio9 ntpd[1040]: 122.155.180.231 local addr 192.168.1.84 -> Jan 21 13:13:11 volumio9 ntpd[1040]: 203.159.70.33 local addr 192.168.1.84 -> Jan 21 13:13:13 volumio9 dhcpcd[772]: eth0: carrier acquired Jan 21 13:13:13 volumio9 kernel: e1000e 0000:00:19.0 eth0: NIC Link is Up 1000 Mbps Full Duplex, Flow Control: Rx/Tx Jan 21 13:13:13 volumio9 dhcpcd[1041]: eth0: carrier acquired Jan 21 13:13:13 volumio9 dhcpcd[772]: eth0: IAID 7e:d8:b8:b5 Jan 21 13:13:13 volumio9 dhcpcd[1041]: eth0: IAID 7e:d8:b8:b5 Jan 21 13:13:13 volumio9 dhcpcd[1041]: eth0: rebinding lease of 192.168.1.84 Jan 21 13:13:13 volumio9 dhcpcd[1041]: eth0: probing address 192.168.1.84/24 Jan 21 13:13:13 volumio9 dhcpcd[1041]: eth0: soliciting an IPv6 router Jan 21 13:13:13 volumio9 dhcpcd[772]: eth0: rebinding lease of 192.168.1.84 Jan 21 13:13:13 volumio9 dhcpcd[772]: eth0: probing address 192.168.1.84/24 Jan 21 13:13:14 volumio9 ifplugd(eth0)[850]: Link beat detected. Jan 21 13:13:14 volumio9 volumio[1134]: info: CoreCommandRouter::volumioGetState Jan 21 13:13:14 volumio9 volumio[1134]: info: CorePlayQueue::getTrack 0 Jan 21 13:13:14 volumio9 dhcpcd[772]: eth0: soliciting an IPv6 router Jan 21 13:13:16 volumio9 dhcpcd[772]: eth0: carrier lost Jan 21 13:13:16 volumio9 dhcpcd[1041]: eth0: carrier lost Jan 21 13:13:16 volumio9 kernel: e1000e 0000:00:19.0 eth0: NIC Link is Down Jan 21 13:13:16 volumio9 ifplugd(eth0)[850]: Link beat lost. Jan 21 13:13:17 volumio9 volumio[1134]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 13:13:17 volumio9 volumio[1134]: info: Retrieving Cloud Streaming UI Jan 21 13:13:17 volumio9 volumio[1134]: info: Getting Tidal Cloud Configuration Jan 21 13:13:17 volumio9 volumio[1134]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 13:13:17 volumio9 volumio[1134]: info: Getting Qobuz Cloud Configuration Jan 21 13:13:17 volumio9 volumio[1134]: info: Asking plugin for UI Config Jan 21 13:13:17 volumio9 volumio[1134]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 13:13:17 volumio9 volumio[1134]: info: Getting Spotify Cloud Configuration Jan 21 13:13:17 volumio9 volumio[1134]: info: Asking plugin for UI Config Jan 21 13:13:17 volumio9 volumio[1134]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 13:13:17 volumio9 volumio[1134]: info: Saving Spotify Acccount Jan 21 13:13:17 volumio9 volumio[1134]: info: Got Tidal Cloud Configuration Jan 21 13:13:17 volumio9 volumio[1134]: info: Got it Jan 21 13:13:17 volumio9 volumio[1134]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 21 13:13:17 volumio9 volumio[1134]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 13:13:17 volumio9 volumio[1134]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 21 13:13:17 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 21 13:13:17 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 21 13:13:17 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 21 13:13:17 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 21 13:13:17 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 13:13:17 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 13:13:17 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 13:13:17 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 13:13:17 volumio9 volumio[1134]: info: CoreCommandRouter::volumioGetBrowseSources Jan 21 13:13:17 volumio9 volumio[1134]: info: CoreCommandRouter::volumioGetBrowseSources Jan 21 13:13:17 volumio9 volumio[1134]: info: CoreCommandRouter::volumioGetBrowseSources Jan 21 13:13:17 volumio9 volumio[1134]: info: Volumio Network Manager: Network status updated: 0 Jan 21 13:13:17 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 13:13:17 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 21 13:13:17 volumio9 mpd[1585]: exception: No database Jan 21 13:13:18 volumio9 dhcpcd[772]: eth0: carrier acquired Jan 21 13:13:18 volumio9 dhcpcd[1041]: eth0: carrier acquired Jan 21 13:13:18 volumio9 kernel: e1000e 0000:00:19.0 eth0: NIC Link is Up 1000 Mbps Full Duplex, Flow Control: Rx/Tx Jan 21 13:13:18 volumio9 dhcpcd[1041]: eth0: IAID 7e:d8:b8:b5 Jan 21 13:13:18 volumio9 dhcpcd[772]: eth0: IAID 7e:d8:b8:b5 Jan 21 13:13:18 volumio9 dhcpcd[1041]: eth0: soliciting an IPv6 router Jan 21 13:13:18 volumio9 dhcpcd[1041]: eth0: rebinding lease of 192.168.1.84 Jan 21 13:13:18 volumio9 dhcpcd[1041]: eth0: probing address 192.168.1.84/24 Jan 21 13:13:18 volumio9 dhcpcd[772]: eth0: rebinding lease of 192.168.1.84 Jan 21 13:13:18 volumio9 dhcpcd[772]: eth0: probing address 192.168.1.84/24 Jan 21 13:13:19 volumio9 dhcpcd[772]: eth0: soliciting an IPv6 router Jan 21 13:13:19 volumio9 ifplugd(eth0)[850]: Link beat detected. Jan 21 13:13:20 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jan 21 13:13:20 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jan 21 13:13:21 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 13:13:21 volumio9 mpd[1585]: exception: No database Jan 21 13:13:23 volumio9 dhcpcd[1041]: eth0: leased 192.168.1.84 for 86400 seconds Jan 21 13:13:23 volumio9 dhcpcd[1041]: eth0: adding route to 192.168.1.0/24 Jan 21 13:13:23 volumio9 dhcpcd[1041]: eth0: adding default route via 192.168.1.254 Jan 21 13:13:23 volumio9 avahi-daemon[727]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.84. Jan 21 13:13:23 volumio9 avahi-daemon[727]: New relevant interface eth0.IPv4 for mDNS. Jan 21 13:13:23 volumio9 avahi-daemon[727]: Registering new address record for 192.168.1.84 on eth0.IPv4. Jan 21 13:13:23 volumio9 ntpd[1040]: ntpd exiting on signal 15 (Terminated) Jan 21 13:13:23 volumio9 systemd[1]: Stopping Network Time Service... Jan 21 13:13:23 volumio9 systemd[1]: ntp.service: Succeeded. Jan 21 13:13:23 volumio9 systemd[1]: Stopped Network Time Service. Jan 21 13:13:23 volumio9 systemd[1]: Starting Network Time Service... Jan 21 13:13:23 volumio9 ntpd[2667]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 21 13:13:23 volumio9 ntpd[2667]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jan 21 13:13:23 volumio9 systemd[1]: Started Network Time Service. Jan 21 13:13:23 volumio9 ntpd[2673]: proto: precision = 1.606 usec (-19) Jan 21 13:13:23 volumio9 ntpd[2673]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 21 13:13:23 volumio9 ntpd[2673]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 21 13:13:23 volumio9 ntpd[2673]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 756 days ago Jan 21 13:13:23 volumio9 ntpd[2673]: Listen and drop on 0 v6wildcard [::]:123 Jan 21 13:13:23 volumio9 ntpd[2673]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 21 13:13:23 volumio9 ntpd[2673]: Listen normally on 2 lo 127.0.0.1:123 Jan 21 13:13:23 volumio9 ntpd[2673]: Listen normally on 3 eth0 192.168.1.84:123 Jan 21 13:13:23 volumio9 ntpd[2673]: Listening on routing socket on fd #20 for interface updates Jan 21 13:13:23 volumio9 ntpd[2673]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 21 13:13:23 volumio9 ntpd[2673]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 21 13:13:24 volumio9 volumio[1134]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jan 21 13:13:24 volumio9 dhcpcd[772]: eth0: leased 192.168.1.84 for 86400 seconds Jan 21 13:13:24 volumio9 dhcpcd[772]: eth0: adding route to 192.168.1.0/24 Jan 21 13:13:24 volumio9 dhcpcd[772]: eth0: adding default route via 192.168.1.254 Jan 21 13:13:24 volumio9 systemd[1]: Stopping Network Time Service... Jan 21 13:13:24 volumio9 ntpd[2673]: ntpd exiting on signal 15 (Terminated) Jan 21 13:13:24 volumio9 systemd[1]: ntp.service: Succeeded. Jan 21 13:13:24 volumio9 systemd[1]: Stopped Network Time Service. Jan 21 13:13:24 volumio9 systemd[1]: Starting Network Time Service... Jan 21 13:13:24 volumio9 ntpd[2713]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 21 13:13:24 volumio9 systemd[1]: Started Network Time Service. Jan 21 13:13:24 volumio9 ntpd[2713]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jan 21 13:13:24 volumio9 ntpd[2719]: proto: precision = 1.397 usec (-19) Jan 21 13:13:24 volumio9 ntpd[2719]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 21 13:13:24 volumio9 ntpd[2719]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 21 13:13:24 volumio9 ntpd[2719]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 756 days ago Jan 21 13:13:24 volumio9 ntpd[2719]: Listen and drop on 0 v6wildcard [::]:123 Jan 21 13:13:24 volumio9 ntpd[2719]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 21 13:13:24 volumio9 ntpd[2719]: Listen normally on 2 lo 127.0.0.1:123 Jan 21 13:13:24 volumio9 ntpd[2719]: Listen normally on 3 eth0 192.168.1.84:123 Jan 21 13:13:24 volumio9 ntpd[2719]: Listening on routing socket on fd #20 for interface updates Jan 21 13:13:24 volumio9 ntpd[2719]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 21 13:13:24 volumio9 ntpd[2719]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 21 13:13:25 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 13:13:25 volumio9 mpd[1585]: exception: No database Jan 21 13:13:25 volumio9 ntpd[2719]: Soliciting pool server 203.159.70.33 Jan 21 13:13:25 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 21 13:13:25 volumio9 volumio[1134]: info: Received Get System Info Jan 21 13:13:25 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 13:13:25 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 13:13:25 volumio9 volumio[1134]: info: Discovery: Getting this device information Jan 21 13:13:25 volumio9 volumio[1134]: info: CoreCommandRouter::volumioGetState Jan 21 13:13:25 volumio9 volumio[1134]: info: CorePlayQueue::getTrack 0 Jan 21 13:13:25 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 13:13:26 volumio9 ntpd[2719]: Soliciting pool server 202.28.117.7 Jan 21 13:13:26 volumio9 ntpd[2719]: Soliciting pool server 147.50.17.138 Jan 21 13:13:27 volumio9 volumio[1134]: info: Volumio Network Manager: Network status updated: 1 Jan 21 13:13:27 volumio9 ntpd[2719]: Soliciting pool server 202.12.97.45 Jan 21 13:13:27 volumio9 ntpd[2719]: Soliciting pool server 202.28.116.236 Jan 21 13:13:27 volumio9 ntpd[2719]: Soliciting pool server 122.155.180.231 Jan 21 13:13:28 volumio9 ntpd[2719]: Soliciting pool server 2406:3100:1010:c03::149 Jan 21 13:13:28 volumio9 ntpd[2719]: Soliciting pool server 124.109.2.169 Jan 21 13:13:28 volumio9 ntpd[2719]: Soliciting pool server 202.80.227.130 Jan 21 13:13:29 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jan 21 13:13:29 volumio9 volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jan 21 13:13:29 volumio9 volumio[1134]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 21 13:13:29 volumio9 volumio[1134]: Error: getaddrinfo -3007 Jan 21 13:13:29 volumio9 volumio[1134]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Jan 21 13:13:29 volumio9 volumio[1134]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Jan 21 13:13:29 volumio9 volumio[1134]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Jan 21 13:13:29 volumio9 volumio[1134]: code: -3007, Jan 21 13:13:29 volumio9 volumio[1134]: errno: -3007, Jan 21 13:13:29 volumio9 volumio[1134]: syscall: 'getaddrinfo' Jan 21 13:13:29 volumio9 volumio[1134]: } Jan 21 13:13:29 volumio9 volumio[1134]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 21 13:13:29 volumio9 sudo[2747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-21 13:12 Jan 21 13:13:29 volumio9 sudo[2747]: 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"